[21:32:59.430] New invocation is queued and will start shortly
[21:33:00.902] Starting the invocation (attempt 1)
[21:33:00.934] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:33:00.935] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:33:00.935] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9021078259937762416",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"27097ee351cfc40b31f6c1de0d083f7723a75c20\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@27097ee351cfc40b31f6c1de0d083f7723a75c20\",\"title\":\"27097ee351cfc40b31f6c1de0d083f7723a75c20\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9021078259937762416",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"gitiles_ref:refs/heads/master"
]
}
[21:33:01.472] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1612992781010069",
"id": "8855648197617584944",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"title\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612992781301471",
"tags": [
"builder:win",
"buildset:commit/git/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021078259937762416",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8855648197617584944/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612992781301665",
"url": "https://ci.chromium.org/b/8855648197617584944",
"utcnow_ts": "1612992781466177"
}
}
[21:33:01.472] Task URL: https://ci.chromium.org/b/8855648197617584944
[21:33:01.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:2:0) after 1m0s
[21:33:12.915] Received PubSub notification, asking Buildbucket for the build status
[21:33:13.122] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:01.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:2:0)
[21:34:01.504] Timer tick, asking Buildbucket for the build status
[21:34:01.674] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:01.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:4:0) after 1m0s
[21:35:01.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:4:0)
[21:35:01.717] Timer tick, asking Buildbucket for the build status
[21:35:01.988] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:01.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:5:0) after 1m0s
[21:36:02.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:5:0)
[21:36:02.013] Timer tick, asking Buildbucket for the build status
[21:36:02.136] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:02.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:6:0) after 1m0s
[21:37:02.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:6:0)
[21:37:02.154] Timer tick, asking Buildbucket for the build status
[21:37:02.295] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:02.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:7:0) after 1m0s
[21:38:02.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:7:0)
[21:38:02.344] Timer tick, asking Buildbucket for the build status
[21:38:04.200] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:04.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:8:0) after 1m0s
[21:39:04.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:8:0)
[21:39:04.316] Timer tick, asking Buildbucket for the build status
[21:39:04.443] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:04.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:9:0) after 1m0s
[21:40:04.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:9:0)
[21:40:04.512] Timer tick, asking Buildbucket for the build status
[21:40:05.055] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:05.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:10:0) after 1m0s
[21:41:05.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:10:0)
[21:41:05.174] Timer tick, asking Buildbucket for the build status
[21:41:05.269] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:05.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:11:0) after 1m0s
[21:42:05.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:11:0)
[21:42:05.328] Timer tick, asking Buildbucket for the build status
[21:42:05.619] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:05.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:12:0) after 1m0s
[21:43:05.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:12:0)
[21:43:05.787] Timer tick, asking Buildbucket for the build status
[21:43:06.279] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:06.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:13:0) after 1m0s
[21:44:06.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:13:0)
[21:44:06.335] Timer tick, asking Buildbucket for the build status
[21:44:06.428] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:06.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:14:0) after 1m0s
[21:45:06.476] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:14:0)
[21:45:06.476] Timer tick, asking Buildbucket for the build status
[21:45:06.550] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:06.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:15:0) after 1m0s
[21:46:06.621] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:15:0)
[21:46:06.621] Timer tick, asking Buildbucket for the build status
[21:46:07.009] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:07.009] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:16:0) after 1m0s
[21:47:07.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:16:0)
[21:47:07.072] Timer tick, asking Buildbucket for the build status
[21:47:07.167] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:07.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:17:0) after 1m0s
[21:48:07.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:17:0)
[21:48:07.220] Timer tick, asking Buildbucket for the build status
[21:48:07.527] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:07.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:18:0) after 1m0s
[21:49:07.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:18:0)
[21:49:07.612] Timer tick, asking Buildbucket for the build status
[21:49:07.685] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:07.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:19:0) after 1m0s
[21:50:07.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:19:0)
[21:50:07.734] Timer tick, asking Buildbucket for the build status
[21:50:07.941] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:07.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:20:0) after 1m0s
[21:51:07.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:20:0)
[21:51:07.962] Timer tick, asking Buildbucket for the build status
[21:51:08.105] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:08.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:21:0) after 1m0s
[21:52:08.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:21:0)
[21:52:08.146] Timer tick, asking Buildbucket for the build status
[21:52:08.237] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:08.237] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:22:0) after 1m0s
[21:53:08.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:22:0)
[21:53:08.308] Timer tick, asking Buildbucket for the build status
[21:53:08.418] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:23:0) after 1m0s
[21:54:08.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:23:0)
[21:54:08.471] Timer tick, asking Buildbucket for the build status
[21:54:08.569] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:08.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:24:0) after 1m0s
[21:55:08.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:24:0)
[21:55:08.588] Timer tick, asking Buildbucket for the build status
[21:55:08.674] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:08.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:25:0) after 1m0s
[21:56:08.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:25:0)
[21:56:08.697] Timer tick, asking Buildbucket for the build status
[21:56:08.767] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:08.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:26:0) after 1m0s
[21:57:08.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:26:0)
[21:57:08.841] Timer tick, asking Buildbucket for the build status
[21:57:08.958] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:08.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:27:0) after 1m0s
[21:58:09.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:27:0)
[21:58:09.014] Timer tick, asking Buildbucket for the build status
[21:58:09.161] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:09.161] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:28:0) after 1m0s
[21:59:09.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:28:0)
[21:59:09.256] Timer tick, asking Buildbucket for the build status
[21:59:09.426] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:09.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:29:0) after 1m0s
[22:00:09.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:29:0)
[22:00:09.565] Timer tick, asking Buildbucket for the build status
[22:00:09.808] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:09.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:30:0) after 1m0s
[22:01:10.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:30:0)
[22:01:10.214] Timer tick, asking Buildbucket for the build status
[22:01:10.655] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:10.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:31:0) after 1m0s
[22:02:10.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:31:0)
[22:02:10.804] Timer tick, asking Buildbucket for the build status
[22:02:11.141] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:11.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:32:0) after 1m0s
[22:03:11.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:32:0)
[22:03:11.340] Timer tick, asking Buildbucket for the build status
[22:03:11.621] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:11.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:33:0) after 1m0s
[22:04:11.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:33:0)
[22:04:11.800] Timer tick, asking Buildbucket for the build status
[22:04:11.870] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:11.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:34:0) after 1m0s
[22:05:11.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:34:0)
[22:05:11.925] Timer tick, asking Buildbucket for the build status
[22:05:12.059] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:12.059] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:35:0) after 1m0s
[22:06:12.210] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:35:0)
[22:06:12.210] Timer tick, asking Buildbucket for the build status
[22:06:13.196] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:13.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:36:0) after 1m0s
[22:07:13.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:36:0)
[22:07:13.281] Timer tick, asking Buildbucket for the build status
[22:07:13.521] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:13.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:37:0) after 1m0s
[22:08:13.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:37:0)
[22:08:13.545] Timer tick, asking Buildbucket for the build status
[22:08:13.769] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:13.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:38:0) after 1m0s
[22:09:13.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:38:0)
[22:09:13.774] Timer tick, asking Buildbucket for the build status
[22:09:13.943] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:13.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:39:0) after 1m0s
[22:10:14.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:39:0)
[22:10:14.177] Timer tick, asking Buildbucket for the build status
[22:10:14.556] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:14.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:40:0) after 1m0s
[22:11:14.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:40:0)
[22:11:14.577] Timer tick, asking Buildbucket for the build status
[22:11:14.648] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:14.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:41:0) after 1m0s
[22:12:14.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:41:0)
[22:12:14.658] Timer tick, asking Buildbucket for the build status
[22:12:14.920] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:14.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:42:0) after 1m0s
[22:13:15.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:42:0)
[22:13:15.026] Timer tick, asking Buildbucket for the build status
[22:13:15.119] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:15.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:43:0) after 1m0s
[22:14:15.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:43:0)
[22:14:15.149] Timer tick, asking Buildbucket for the build status
[22:14:15.239] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:15.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:44:0) after 1m0s
[22:15:15.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:44:0)
[22:15:15.427] Timer tick, asking Buildbucket for the build status
[22:15:15.870] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:15.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:45:0) after 1m0s
[22:16:15.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:45:0)
[22:16:15.889] Timer tick, asking Buildbucket for the build status
[22:16:15.985] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:15.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:46:0) after 1m0s
[22:17:16.063] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:46:0)
[22:17:16.063] Timer tick, asking Buildbucket for the build status
[22:17:16.159] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:16.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:47:0) after 1m0s
[22:18:16.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:47:0)
[22:18:16.144] Timer tick, asking Buildbucket for the build status
[22:18:16.555] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:16.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:48:0) after 1m0s
[22:19:16.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:48:0)
[22:19:16.600] Timer tick, asking Buildbucket for the build status
[22:19:16.676] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:16.676] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:49:0) after 1m0s
[22:20:16.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:49:0)
[22:20:16.730] Timer tick, asking Buildbucket for the build status
[22:20:16.804] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:16.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:50:0) after 1m0s
[22:21:16.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:50:0)
[22:21:16.888] Timer tick, asking Buildbucket for the build status
[22:21:16.957] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:16.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:51:0) after 1m0s
[22:22:17.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:51:0)
[22:22:17.002] Timer tick, asking Buildbucket for the build status
[22:22:17.098] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:17.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:52:0) after 1m0s
[22:23:17.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:52:0)
[22:23:17.212] Timer tick, asking Buildbucket for the build status
[22:23:17.294] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:17.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:53:0) after 1m0s
[22:24:17.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:53:0)
[22:24:17.342] Timer tick, asking Buildbucket for the build status
[22:24:17.425] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:17.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:54:0) after 1m0s
[22:25:17.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:54:0)
[22:25:17.482] Timer tick, asking Buildbucket for the build status
[22:25:17.565] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:17.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:55:0) after 1m0s
[22:26:17.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:55:0)
[22:26:17.597] Timer tick, asking Buildbucket for the build status
[22:26:17.692] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:17.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:56:0) after 1m0s
[22:27:17.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:56:0)
[22:27:17.727] Timer tick, asking Buildbucket for the build status
[22:27:17.983] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:17.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:57:0) after 1m0s
[22:28:17.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:57:0)
[22:28:17.994] Timer tick, asking Buildbucket for the build status
[22:28:18.098] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:18.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:58:0) after 1m0s
[22:29:18.144] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:58:0)
[22:29:18.144] Timer tick, asking Buildbucket for the build status
[22:29:18.301] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:18.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:59:0) after 1m0s
[22:30:18.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:59:0)
[22:30:18.326] Timer tick, asking Buildbucket for the build status
[22:30:18.442] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:18.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:60:0) after 1m0s
[22:31:18.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:60:0)
[22:31:18.503] Timer tick, asking Buildbucket for the build status
[22:31:18.829] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:18.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:61:0) after 1m0s
[22:32:18.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:61:0)
[22:32:18.878] Timer tick, asking Buildbucket for the build status
[22:32:19.047] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:19.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:62:0) after 1m0s
[22:33:19.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:62:0)
[22:33:19.116] Timer tick, asking Buildbucket for the build status
[22:33:19.291] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:19.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:63:0) after 1m0s
[22:34:19.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:63:0)
[22:34:19.308] Timer tick, asking Buildbucket for the build status
[22:34:19.403] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:19.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:64:0) after 1m0s
[22:35:19.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:64:0)
[22:35:19.447] Timer tick, asking Buildbucket for the build status
[22:35:19.521] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:19.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:65:0) after 1m0s
[22:36:19.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:65:0)
[22:36:19.617] Timer tick, asking Buildbucket for the build status
[22:36:20.024] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:20.024] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:66:0) after 1m0s
[22:37:20.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:66:0)
[22:37:20.141] Timer tick, asking Buildbucket for the build status
[22:37:20.536] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:20.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:67:0) after 1m0s
[22:38:20.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:67:0)
[22:38:20.585] Timer tick, asking Buildbucket for the build status
[22:38:20.729] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:20.729] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:68:0) after 1m0s
[22:39:20.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:68:0)
[22:39:20.747] Timer tick, asking Buildbucket for the build status
[22:39:20.835] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:20.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:69:0) after 1m0s
[22:40:20.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:69:0)
[22:40:20.934] Timer tick, asking Buildbucket for the build status
[22:40:21.017] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:21.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:70:0) after 1m0s
[22:41:21.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:70:0)
[22:41:21.073] Timer tick, asking Buildbucket for the build status
[22:41:21.458] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:21.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:71:0) after 1m0s
[22:42:21.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:71:0)
[22:42:21.511] Timer tick, asking Buildbucket for the build status
[22:42:21.778] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:21.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:72:0) after 1m0s
[22:43:21.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:72:0)
[22:43:21.906] Timer tick, asking Buildbucket for the build status
[22:43:22.129] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:22.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:73:0) after 1m0s
[22:44:22.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:73:0)
[22:44:22.159] Timer tick, asking Buildbucket for the build status
[22:44:22.271] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:22.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:74:0) after 1m0s
[22:45:22.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:74:0)
[22:45:22.287] Timer tick, asking Buildbucket for the build status
[22:45:22.463] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:22.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:75:0) after 1m0s
[22:46:22.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:75:0)
[22:46:22.493] Timer tick, asking Buildbucket for the build status
[22:46:22.586] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:22.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:76:0) after 1m0s
[22:47:22.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:76:0)
[22:47:22.643] Timer tick, asking Buildbucket for the build status
[22:47:22.788] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:22.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:77:0) after 1m0s
[22:48:22.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:77:0)
[22:48:22.891] Timer tick, asking Buildbucket for the build status
[22:48:23.202] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:23.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:78:0) after 1m0s
[22:49:23.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:78:0)
[22:49:23.437] Timer tick, asking Buildbucket for the build status
[22:49:23.542] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:23.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:79:0) after 1m0s
[22:50:23.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:79:0)
[22:50:23.599] Timer tick, asking Buildbucket for the build status
[22:50:23.685] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:23.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:80:0) after 1m0s
[22:51:23.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:80:0)
[22:51:23.829] Timer tick, asking Buildbucket for the build status
[22:51:23.986] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:23.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:81:0) after 1m0s
[22:52:24.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:81:0)
[22:52:24.136] Timer tick, asking Buildbucket for the build status
[22:52:24.233] Build 8855648197617584944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:24.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9021078259937762416:82:0) after 1m0s
[22:52:41.647] Received PubSub notification, asking Buildbucket for the build status
[22:52:41.786] Build 8855648197617584944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:52:41.786] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1612997559713897",
"created_by": "project:emscripten-releases",
"created_ts": "1612992781010069",
"id": "8855648197617584944",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"title\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"title\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-plry\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"27097ee351cfc40b31f6c1de0d083f7723a75c20\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-plry\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612992792096869",
"status": "COMPLETED",
"status_changed_ts": "1612997560941459",
"tags": [
"builder:win",
"buildset:commit/git/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/27097ee351cfc40b31f6c1de0d083f7723a75c20",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021078259937762416",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8855648197617584944/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51a6ae250d815110",
"user_agent:luci-scheduler"
],
"updated_ts": "1612997561105683",
"url": "https://ci.chromium.org/b/8855648197617584944",
"utcnow_ts": "1612997561777352"
}
}
[22:52:41.786] Invocation finished in 1h19m42.372134659s with status SUCCEEDED