[09:11:14.122] New invocation is queued and will start shortly
[09:11:15.261] Starting the invocation (attempt 1)
[09:11:15.299] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/6da3dc6e3c2a3fcb738733da40dff2ef794d5705"
[09:11:15.299] Popped gitiles commit info from properties and tags
[09:11:15.299] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[09:11:15.299] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[09:11:15.300] Buildbucket request:
{
"requestId": "9011521648700408640",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2243a8632b05861240e404bb0731cf662d4d3878"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@2243a8632b05861240e404bb0731cf662d4d3878",
"title": "2243a8632b05861240e404bb0731cf662d4d3878",
"url": "https://chromium.googlesource.com/v8/v8/+/2243a8632b05861240e404bb0731cf662d4d3878"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "dfdc8f68793c420113d53e1e877d535ed9f31612"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@dfdc8f68793c420113d53e1e877d535ed9f31612",
"title": "dfdc8f68793c420113d53e1e877d535ed9f31612",
"url": "https://chromium.googlesource.com/v8/v8/+/dfdc8f68793c420113d53e1e877d535ed9f31612"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7ff6609a53854f859b12ba4b76606f397e074a70"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@7ff6609a53854f859b12ba4b76606f397e074a70",
"title": "7ff6609a53854f859b12ba4b76606f397e074a70",
"url": "https://chromium.googlesource.com/v8/v8/+/7ff6609a53854f859b12ba4b76606f397e074a70"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f5ac0c1f40853c22c96d2a82b2582822ad15b344"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@f5ac0c1f40853c22c96d2a82b2582822ad15b344",
"title": "f5ac0c1f40853c22c96d2a82b2582822ad15b344",
"url": "https://chromium.googlesource.com/v8/v8/+/f5ac0c1f40853c22c96d2a82b2582822ad15b344"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "6da3dc6e3c2a3fcb738733da40dff2ef794d5705"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/master@6da3dc6e3c2a3fcb738733da40dff2ef794d5705",
"title": "6da3dc6e3c2a3fcb738733da40dff2ef794d5705",
"url": "https://chromium.googlesource.com/v8/v8/+/6da3dc6e3c2a3fcb738733da40dff2ef794d5705"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "6da3dc6e3c2a3fcb738733da40dff2ef794d5705",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9011521648700408640"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Win64 - dev image"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[09:11:16.338] Scheduled build:
{
"id": "8846091586446769345",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1586,
"createdBy": "project:v8",
"createTime": "2021-05-27T09:11:15.636783030Z",
"updateTime": "2021-05-27T09:11:15.984034793Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "6da3dc6e3c2a3fcb738733da40dff2ef794d5705",
"ref": "refs/heads/master"
}
},
"canary": true
}
[09:11:16.338] Task URL: https://cr-buildbucket-dev.appspot.com/build/8846091586446769345
[09:11:16.338] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:2:0) after 8m37s
[09:19:53.359] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:2:0)
[09:19:53.425] Build status: SCHEDULED
[09:19:53.425] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:3:0) after 5m28s
[09:25:21.458] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:3:0)
[09:25:21.583] Build status: SCHEDULED
[09:25:21.583] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:4:0) after 3m56s
[09:29:17.657] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:4:0)
[09:29:17.781] Build status: SCHEDULED
[09:29:17.781] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:5:0) after 6m0s
[09:35:17.857] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:5:0)
[09:35:17.902] Build status: SCHEDULED
[09:35:17.902] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:6:0) after 6m25s
[09:41:43.377] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:6:0)
[09:41:43.425] Build status: SCHEDULED
[09:41:43.425] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:7:0) after 2m58s
[09:44:41.557] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:7:0)
[09:44:41.650] Build status: SCHEDULED
[09:44:41.650] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:8:0) after 7m19s
[09:52:00.699] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:8:0)
[09:52:00.761] Build status: SCHEDULED
[09:52:00.761] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:9:0) after 6m42s
[09:58:42.782] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:9:0)
[09:58:42.838] Build status: SCHEDULED
[09:58:42.839] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:10:0) after 4m21s
[10:03:03.861] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:10:0)
[10:03:03.925] Build status: SCHEDULED
[10:03:03.925] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:11:0) after 2m6s
[10:05:09.945] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:11:0)
[10:05:10.155] Build status: SCHEDULED
[10:05:10.155] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:12:0) after 4m50s
[10:10:00.193] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:12:0)
[10:10:00.276] Build status: SCHEDULED
[10:10:00.276] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:13:0) after 3m5s
[10:13:05.368] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:13:0)
[10:13:05.427] Build status: SCHEDULED
[10:13:05.427] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:14:0) after 8m36s
[10:21:41.461] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:14:0)
[10:21:41.537] Build status: SCHEDULED
[10:21:41.537] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:15:0) after 2m42s
[10:24:23.560] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:15:0)
[10:24:23.685] Build status: SCHEDULED
[10:24:23.685] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:16:0) after 5m5s
[10:29:28.781] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:16:0)
[10:29:28.880] Build status: SCHEDULED
[10:29:28.880] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:17:0) after 5m12s
[10:34:40.898] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:17:0)
[10:34:40.981] Build status: SCHEDULED
[10:34:40.981] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:18:0) after 9m40s
[10:44:20.999] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:18:0)
[10:44:21.088] Build status: SCHEDULED
[10:44:21.088] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:19:0) after 2m37s
[10:46:58.110] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:19:0)
[10:46:58.157] Build status: SCHEDULED
[10:46:58.157] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:20:0) after 3m36s
[10:50:34.177] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:20:0)
[10:50:34.217] Build status: SCHEDULED
[10:50:34.217] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:21:0) after 8m51s
[10:59:25.248] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:21:0)
[10:59:25.289] Build status: SCHEDULED
[10:59:25.289] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:22:0) after 9m15s
[11:08:40.311] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:22:0)
[11:08:40.413] Build status: SCHEDULED
[11:08:40.413] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:23:0) after 7m38s
[11:16:18.440] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:23:0)
[11:16:18.565] Build status: SCHEDULED
[11:16:18.565] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:24:0) after 6m38s
[11:22:56.594] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:24:0)
[11:22:56.677] Build status: SCHEDULED
[11:22:56.677] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:25:0) after 9m17s
[11:32:13.764] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:25:0)
[11:32:13.850] Build status: SCHEDULED
[11:32:13.850] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:26:0) after 1m22s
[11:33:35.871] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:26:0)
[11:33:35.926] Build status: SCHEDULED
[11:33:35.926] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:27:0) after 9m9s
[11:42:44.949] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:27:0)
[11:42:45.012] Build status: SCHEDULED
[11:42:45.012] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:28:0) after 8m45s
[11:51:30.033] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:28:0)
[11:51:30.105] Build status: SCHEDULED
[11:51:30.105] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:29:0) after 3m15s
[11:54:45.135] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:29:0)
[11:54:45.191] Build status: SCHEDULED
[11:54:45.191] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:30:0) after 7m50s
[12:02:35.213] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:30:0)
[12:02:35.290] Build status: SCHEDULED
[12:02:35.290] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:31:0) after 2m17s
[12:04:52.334] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:31:0)
[12:04:52.399] Build status: SCHEDULED
[12:04:52.399] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:32:0) after 1m39s
[12:06:31.442] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:32:0)
[12:06:31.669] Build status: SCHEDULED
[12:06:31.669] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:33:0) after 9m27s
[12:15:58.694] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:33:0)
[12:15:58.767] Build status: SCHEDULED
[12:15:58.767] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:34:0) after 3m38s
[12:19:36.789] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:34:0)
[12:19:36.883] Build status: SCHEDULED
[12:19:36.883] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:35:0) after 8m14s
[12:27:50.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:35:0)
[12:27:50.958] Build status: SCHEDULED
[12:27:50.958] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:36:0) after 8m53s
[12:36:43.981] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:36:0)
[12:36:44.056] Build status: SCHEDULED
[12:36:44.056] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:37:0) after 7m8s
[12:43:52.161] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:37:0)
[12:43:52.208] Build status: SCHEDULED
[12:43:52.208] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:38:0) after 1m33s
[12:45:25.259] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:38:0)
[12:45:25.336] Build status: SCHEDULED
[12:45:25.336] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:39:0) after 1m46s
[12:47:11.368] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:39:0)
[12:47:11.417] Build status: SCHEDULED
[12:47:11.417] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:40:0) after 2m14s
[12:49:25.441] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:40:0)
[12:49:25.501] Build status: SCHEDULED
[12:49:25.501] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:41:0) after 9m59s
[12:59:24.570] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:9011521648700408640:41:0)
[12:59:24.660] Build:
{
"id": "8846091586446769345",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 1586,
"createdBy": "project:v8",
"createTime": "2021-05-27T09:11:15.636783030Z",
"startTime": "2021-05-27T12:51:13.873524955Z",
"endTime": "2021-05-27T12:54:38.631276764Z",
"updateTime": "2021-05-27T12:54:38.632468783Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "6da3dc6e3c2a3fcb738733da40dff2ef794d5705",
"ref": "refs/heads/master"
}
},
"canary": true
}
[12:59:24.660] Invocation finished in 3h48m10.557569546s with status SUCCEEDED