[16:34:06.418] New invocation is queued and will start shortly
[16:34:07.521] Starting the invocation (attempt 1)
[16:34:07.556] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d0e637fe48197587d981f79e8114757731d0c2a9"
[16:34:07.557] Popped gitiles commit info from properties and tags
[16:34:07.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:34:07.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:34:07.557] Buildbucket request:
{
"requestId": "8985854844135719952",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "d0e637fe48197587d981f79e8114757731d0c2a9"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@d0e637fe48197587d981f79e8114757731d0c2a9",
"title": "d0e637fe48197587d981f79e8114757731d0c2a9",
"url": "https://chromium.googlesource.com/emscripten-releases/+/d0e637fe48197587d981f79e8114757731d0c2a9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "d0e637fe48197587d981f79e8114757731d0c2a9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8985854844135719952"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:34:08.013] Scheduled build:
{
"id": "8820424782190423953",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-03-06T16:34:07.639583740Z",
"updateTime": "2022-03-06T16:34:07.639583740Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "d0e637fe48197587d981f79e8114757731d0c2a9",
"ref": "refs/heads/main"
}
}
}
[16:34:08.013] Task URL: https://cr-buildbucket.appspot.com/build/8820424782190423953
[16:34:08.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:2:0) after 9m20s
[16:43:28.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:2:0)
[16:43:28.060] Build status: SCHEDULED
[16:43:28.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:3:0) after 7m42s
[16:51:10.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:3:0)
[16:51:10.107] Build status: SCHEDULED
[16:51:10.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:4:0) after 8m24s
[16:59:34.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:4:0)
[16:59:34.155] Build status: SCHEDULED
[16:59:34.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:5:0) after 7m4s
[17:06:38.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:5:0)
[17:06:38.206] Build status: SCHEDULED
[17:06:38.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:6:0) after 2m9s
[17:08:47.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:6:0)
[17:08:47.512] Build status: SCHEDULED
[17:08:47.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:7:0) after 3m40s
[17:12:27.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:7:0)
[17:12:27.578] Build status: SCHEDULED
[17:12:27.578] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:8:0) after 2m32s
[17:14:59.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:8:0)
[17:14:59.628] Build status: SCHEDULED
[17:14:59.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:9:0) after 6m14s
[17:21:13.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:9:0)
[17:21:13.677] Build status: SCHEDULED
[17:21:13.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:10:0) after 6m36s
[17:27:49.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:10:0)
[17:27:49.727] Build status: SCHEDULED
[17:27:49.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:11:0) after 1m58s
[17:29:47.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:11:0)
[17:29:47.861] Build status: SCHEDULED
[17:29:47.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:12:0) after 4m48s
[17:34:35.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:12:0)
[17:34:35.965] Build status: SCHEDULED
[17:34:35.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:13:0) after 3m29s
[17:38:05.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:13:0)
[17:38:05.083] Build status: SCHEDULED
[17:38:05.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:14:0) after 7m29s
[17:45:34.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:14:0)
[17:45:34.131] Build status: SCHEDULED
[17:45:34.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:15:0) after 5m53s
[17:51:27.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:15:0)
[17:51:27.174] Build status: SCHEDULED
[17:51:27.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:16:0) after 3m58s
[17:55:25.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:16:0)
[17:55:25.280] Build status: SCHEDULED
[17:55:25.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:17:0) after 9m12s
[18:04:37.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:17:0)
[18:04:37.545] Build status: SCHEDULED
[18:04:37.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:18:0) after 6m17s
[18:10:55.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:18:0)
[18:10:55.222] Build status: SCHEDULED
[18:10:55.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:19:0) after 9m24s
[18:20:19.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:19:0)
[18:20:19.368] Build status: SCHEDULED
[18:20:19.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:20:0) after 4m25s
[18:24:44.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:20:0)
[18:24:44.414] Build status: SCHEDULED
[18:24:44.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:21:0) after 3m19s
[18:28:03.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:21:0)
[18:28:03.454] Build status: SCHEDULED
[18:28:03.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:22:0) after 4m13s
[18:32:16.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:22:0)
[18:32:16.551] Build status: SCHEDULED
[18:32:16.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:23:0) after 8m14s
[18:34:25.321] Received PubSub notification, asking Buildbucket for the build status
[18:34:25.350] Build status: STARTED
[18:40:30.823] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:23:0)
[18:40:30.850] Build status: STARTED
[18:40:30.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:25:0) after 6m40s
[18:47:12.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:25:0)
[18:47:12.740] Build status: STARTED
[18:47:12.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:26:0) after 3m14s
[18:50:26.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:26:0)
[18:50:26.804] Build status: STARTED
[18:50:26.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:27:0) after 4m42s
[18:55:08.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:27:0)
[18:55:08.862] Build status: STARTED
[18:55:08.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:28:0) after 3m9s
[18:58:17.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:28:0)
[18:58:17.910] Build status: STARTED
[18:58:17.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:29:0) after 5m40s
[19:03:57.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:29:0)
[19:03:57.971] Build status: STARTED
[19:03:57.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:30:0) after 7m6s
[19:11:03.996] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:30:0)
[19:11:04.024] Build status: STARTED
[19:11:04.024] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:31:0) after 3m1s
[19:14:05.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:31:0)
[19:14:05.124] Build status: STARTED
[19:14:05.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:32:0) after 4m29s
[19:18:34.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:32:0)
[19:18:34.165] Build status: STARTED
[19:18:34.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:33:0) after 8m10s
[19:26:44.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:33:0)
[19:26:44.438] Build status: STARTED
[19:26:44.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:34:0) after 1m48s
[19:28:32.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:34:0)
[19:28:32.601] Build status: STARTED
[19:28:32.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:35:0) after 6m28s
[19:35:00.644] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:35:0)
[19:35:00.681] Build status: STARTED
[19:35:00.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:36:0) after 4m2s
[19:39:02.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:36:0)
[19:39:02.846] Build status: STARTED
[19:39:02.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:37:0) after 8m43s
[19:47:45.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:37:0)
[19:47:45.904] Build status: STARTED
[19:47:45.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:38:0) after 5m32s
[19:53:17.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:38:0)
[19:53:17.957] Build status: STARTED
[19:53:17.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:39:0) after 5m22s
[19:58:40.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:39:0)
[19:58:40.121] Build status: STARTED
[19:58:40.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8985854844135719952:40:0) after 6m7s
[20:02:52.476] Received PubSub notification, asking Buildbucket for the build status
[20:02:52.507] Build:
{
"id": "8820424782190423953",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-03-06T16:34:07.639583740Z",
"startTime": "2022-03-06T18:34:24.801228Z",
"endTime": "2022-03-06T20:02:51.906058389Z",
"updateTime": "2022-03-06T20:02:51.906058389Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "d0e637fe48197587d981f79e8114757731d0c2a9",
"ref": "refs/heads/main"
}
}
}
[20:02:52.507] Invocation finished in 3h28m46.106145365s with status SUCCEEDED