[09:37:22.560] New invocation is queued and will start shortly
[09:37:23.887] Starting the invocation (attempt 1)
[09:37:23.939] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:37:23.940] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:37:23.940] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9039152078828379184",
"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\":\"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d64ad849b7e9933910fb0b53f47efea4b63e06c4\",\"title\":\"d64ad849b7e9933910fb0b53f47efea4b63e06c4\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9039152078828379184",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"gitiles_ref:refs/heads/master"
]
}
[09:37:24.844] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1595756244148026",
"id": "8873722016494915168",
"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\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"title\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595756244655660",
"tags": [
"builder:win",
"buildset:commit/git/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039152078828379184",
"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/8873722016494915168/+/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": "1595756244655863",
"url": "https://ci.chromium.org/b/8873722016494915168",
"utcnow_ts": "1595756244835566"
}
}
[09:37:24.844] Task URL: https://ci.chromium.org/b/8873722016494915168
[09:37:24.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:2:0) after 1m0s
[09:38:23.378] Received PubSub notification, asking Buildbucket for the build status
[09:38:23.541] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:24.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:2:0)
[09:38:24.904] Timer tick, asking Buildbucket for the build status
[09:38:25.004] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:25.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:4:0) after 1m0s
[09:39:25.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:4:0)
[09:39:25.053] Timer tick, asking Buildbucket for the build status
[09:39:25.265] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:25.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:5:0) after 1m0s
[09:40:25.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:5:0)
[09:40:25.247] Timer tick, asking Buildbucket for the build status
[09:40:25.627] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:25.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:6:0) after 1m0s
[09:41:25.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:6:0)
[09:41:25.653] Timer tick, asking Buildbucket for the build status
[09:41:25.888] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:7:0) after 1m0s
[09:42:25.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:7:0)
[09:42:25.917] Timer tick, asking Buildbucket for the build status
[09:42:26.418] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:26.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:8:0) after 1m0s
[09:43:26.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:8:0)
[09:43:26.447] Timer tick, asking Buildbucket for the build status
[09:43:26.632] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:26.632] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:9:0) after 1m0s
[09:44:26.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:9:0)
[09:44:26.692] Timer tick, asking Buildbucket for the build status
[09:44:27.168] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:27.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:10:0) after 1m0s
[09:45:27.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:10:0)
[09:45:27.191] Timer tick, asking Buildbucket for the build status
[09:45:27.291] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:27.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:11:0) after 1m0s
[09:46:27.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:11:0)
[09:46:27.427] Timer tick, asking Buildbucket for the build status
[09:46:27.613] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:27.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:12:0) after 1m0s
[09:47:27.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:12:0)
[09:47:27.649] Timer tick, asking Buildbucket for the build status
[09:47:27.787] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:27.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:13:0) after 1m0s
[09:48:27.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:13:0)
[09:48:27.867] Timer tick, asking Buildbucket for the build status
[09:48:27.960] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:27.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:14:0) after 1m0s
[09:49:27.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:14:0)
[09:49:27.995] Timer tick, asking Buildbucket for the build status
[09:49:28.206] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:28.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:15:0) after 1m0s
[09:50:28.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:15:0)
[09:50:28.237] Timer tick, asking Buildbucket for the build status
[09:50:28.320] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:28.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:16:0) after 1m0s
[09:51:28.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:16:0)
[09:51:28.436] Timer tick, asking Buildbucket for the build status
[09:51:28.547] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:28.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:17:0) after 1m0s
[09:52:28.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:17:0)
[09:52:28.593] Timer tick, asking Buildbucket for the build status
[09:52:28.701] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:28.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:18:0) after 1m0s
[09:53:28.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:18:0)
[09:53:28.729] Timer tick, asking Buildbucket for the build status
[09:53:28.848] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:28.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:19:0) after 1m0s
[09:54:28.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:19:0)
[09:54:28.872] Timer tick, asking Buildbucket for the build status
[09:54:28.962] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:28.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:20:0) after 1m0s
[09:55:29.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:20:0)
[09:55:29.033] Timer tick, asking Buildbucket for the build status
[09:55:29.276] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:29.276] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:21:0) after 1m0s
[09:56:29.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:21:0)
[09:56:29.255] Timer tick, asking Buildbucket for the build status
[09:56:29.452] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:22:0) after 1m0s
[09:57:29.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:22:0)
[09:57:29.519] Timer tick, asking Buildbucket for the build status
[09:57:29.945] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:29.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:23:0) after 1m0s
[09:58:30.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:23:0)
[09:58:30.023] Timer tick, asking Buildbucket for the build status
[09:58:30.185] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:30.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:24:0) after 1m0s
[09:59:30.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:24:0)
[09:59:30.226] Timer tick, asking Buildbucket for the build status
[09:59:30.568] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:30.568] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:25:0) after 1m0s
[10:00:30.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:25:0)
[10:00:30.630] Timer tick, asking Buildbucket for the build status
[10:00:30.710] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:30.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:26:0) after 1m0s
[10:01:30.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:26:0)
[10:01:30.802] Timer tick, asking Buildbucket for the build status
[10:01:31.047] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:31.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:27:0) after 1m0s
[10:02:31.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:27:0)
[10:02:31.200] Timer tick, asking Buildbucket for the build status
[10:02:31.482] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:31.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:28:0) after 1m0s
[10:03:31.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:28:0)
[10:03:31.559] Timer tick, asking Buildbucket for the build status
[10:03:31.635] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:31.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:29:0) after 1m0s
[10:04:31.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:29:0)
[10:04:31.725] Timer tick, asking Buildbucket for the build status
[10:04:31.800] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:31.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:30:0) after 1m0s
[10:05:31.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:30:0)
[10:05:31.862] Timer tick, asking Buildbucket for the build status
[10:05:32.162] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:32.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:31:0) after 1m0s
[10:06:32.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:31:0)
[10:06:32.187] Timer tick, asking Buildbucket for the build status
[10:06:32.266] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:32.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:32:0) after 1m0s
[10:07:32.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:32:0)
[10:07:32.396] Timer tick, asking Buildbucket for the build status
[10:07:32.663] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:32.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:33:0) after 1m0s
[10:08:32.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:33:0)
[10:08:32.752] Timer tick, asking Buildbucket for the build status
[10:08:32.890] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:32.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:34:0) after 1m0s
[10:09:32.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:34:0)
[10:09:32.963] Timer tick, asking Buildbucket for the build status
[10:09:33.051] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:33.051] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:35:0) after 1m0s
[10:10:33.059] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:35:0)
[10:10:33.060] Timer tick, asking Buildbucket for the build status
[10:10:33.142] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:33.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:36:0) after 1m0s
[10:11:33.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:36:0)
[10:11:33.342] Timer tick, asking Buildbucket for the build status
[10:11:33.476] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:33.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:37:0) after 1m0s
[10:12:33.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:37:0)
[10:12:33.500] Timer tick, asking Buildbucket for the build status
[10:12:33.687] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:33.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:38:0) after 1m0s
[10:13:33.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:38:0)
[10:13:33.766] Timer tick, asking Buildbucket for the build status
[10:13:33.859] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:33.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:39:0) after 1m0s
[10:14:34.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:39:0)
[10:14:34.120] Timer tick, asking Buildbucket for the build status
[10:14:34.217] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:34.217] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:40:0) after 1m0s
[10:15:34.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:40:0)
[10:15:34.318] Timer tick, asking Buildbucket for the build status
[10:15:34.429] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:34.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:41:0) after 1m0s
[10:16:34.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:41:0)
[10:16:34.503] Timer tick, asking Buildbucket for the build status
[10:16:34.748] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:34.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:42:0) after 1m0s
[10:17:34.850] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:42:0)
[10:17:34.850] Timer tick, asking Buildbucket for the build status
[10:17:34.982] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:34.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:43:0) after 1m0s
[10:18:35.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:43:0)
[10:18:35.123] Timer tick, asking Buildbucket for the build status
[10:18:35.305] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:35.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:44:0) after 1m0s
[10:19:35.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:44:0)
[10:19:35.351] Timer tick, asking Buildbucket for the build status
[10:19:35.569] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:35.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:45:0) after 1m0s
[10:20:35.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:45:0)
[10:20:35.629] Timer tick, asking Buildbucket for the build status
[10:20:35.796] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:35.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:46:0) after 1m0s
[10:21:35.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:46:0)
[10:21:35.848] Timer tick, asking Buildbucket for the build status
[10:21:35.994] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:35.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:47:0) after 1m0s
[10:22:36.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:47:0)
[10:22:36.055] Timer tick, asking Buildbucket for the build status
[10:22:36.367] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:36.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:48:0) after 1m0s
[10:23:36.450] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:48:0)
[10:23:36.450] Timer tick, asking Buildbucket for the build status
[10:23:36.659] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:36.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:49:0) after 1m0s
[10:24:36.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:49:0)
[10:24:36.700] Timer tick, asking Buildbucket for the build status
[10:24:36.833] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:36.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:50:0) after 1m0s
[10:25:36.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:50:0)
[10:25:36.962] Timer tick, asking Buildbucket for the build status
[10:25:37.038] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:37.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:51:0) after 1m0s
[10:26:37.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:51:0)
[10:26:37.121] Timer tick, asking Buildbucket for the build status
[10:26:37.235] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:37.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:52:0) after 1m0s
[10:27:37.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:52:0)
[10:27:37.325] Timer tick, asking Buildbucket for the build status
[10:27:37.464] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:37.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:53:0) after 1m0s
[10:28:37.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:53:0)
[10:28:37.612] Timer tick, asking Buildbucket for the build status
[10:28:37.688] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:37.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:54:0) after 1m0s
[10:29:37.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:54:0)
[10:29:37.773] Timer tick, asking Buildbucket for the build status
[10:29:37.855] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:37.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:55:0) after 1m0s
[10:30:37.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:55:0)
[10:30:37.942] Timer tick, asking Buildbucket for the build status
[10:30:38.057] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:38.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:56:0) after 1m0s
[10:31:38.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:56:0)
[10:31:38.101] Timer tick, asking Buildbucket for the build status
[10:31:38.244] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:38.244] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:57:0) after 1m0s
[10:32:38.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:57:0)
[10:32:38.314] Timer tick, asking Buildbucket for the build status
[10:32:38.462] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:38.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:58:0) after 1m0s
[10:33:38.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:58:0)
[10:33:38.542] Timer tick, asking Buildbucket for the build status
[10:33:38.606] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:38.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:59:0) after 1m0s
[10:34:38.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:59:0)
[10:34:38.698] Timer tick, asking Buildbucket for the build status
[10:34:38.754] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:38.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:60:0) after 1m0s
[10:35:38.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:60:0)
[10:35:38.887] Timer tick, asking Buildbucket for the build status
[10:35:38.965] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:38.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:61:0) after 1m0s
[10:36:39.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:61:0)
[10:36:39.089] Timer tick, asking Buildbucket for the build status
[10:36:39.231] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:39.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:62:0) after 1m0s
[10:37:39.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:62:0)
[10:37:39.257] Timer tick, asking Buildbucket for the build status
[10:37:39.614] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:39.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:63:0) after 1m0s
[10:38:39.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:63:0)
[10:38:39.639] Timer tick, asking Buildbucket for the build status
[10:38:39.882] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:39.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:64:0) after 1m0s
[10:39:39.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:64:0)
[10:39:39.909] Timer tick, asking Buildbucket for the build status
[10:39:39.982] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:39.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:65:0) after 1m0s
[10:40:40.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:65:0)
[10:40:40.039] Timer tick, asking Buildbucket for the build status
[10:40:41.804] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:41.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:66:0) after 1m0s
[10:41:41.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:66:0)
[10:41:41.920] Timer tick, asking Buildbucket for the build status
[10:41:41.991] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:41.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:67:0) after 1m0s
[10:42:42.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:67:0)
[10:42:42.015] Timer tick, asking Buildbucket for the build status
[10:42:42.162] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:42.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:68:0) after 1m0s
[10:43:42.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:68:0)
[10:43:42.205] Timer tick, asking Buildbucket for the build status
[10:43:42.307] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:42.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:69:0) after 1m0s
[10:44:42.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:69:0)
[10:44:42.341] Timer tick, asking Buildbucket for the build status
[10:44:42.856] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:42.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:70:0) after 1m0s
[10:45:43.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:70:0)
[10:45:43.046] Timer tick, asking Buildbucket for the build status
[10:45:43.121] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:43.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:71:0) after 1m0s
[10:46:43.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:71:0)
[10:46:43.179] Timer tick, asking Buildbucket for the build status
[10:46:43.306] Build 8873722016494915168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:43.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039152078828379184:72:0) after 1m0s
[10:47:01.645] Received PubSub notification, asking Buildbucket for the build status
[10:47:02.004] Build 8873722016494915168: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:47:02.005] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1595760420596123",
"created_by": "project:emscripten-releases",
"created_ts": "1595756244148026",
"id": "8873722016494915168",
"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\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"title\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}}",
"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\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"title\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-olvj\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d64ad849b7e9933910fb0b53f47efea4b63e06c4\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-olvj\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5425-e4e3059\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1595756302339147",
"status": "COMPLETED",
"status_changed_ts": "1595760421070634",
"tags": [
"builder:win",
"buildset:commit/git/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d64ad849b7e9933910fb0b53f47efea4b63e06c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039152078828379184",
"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/8873722016494915168/+/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:4da34d77eb723d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1595760421085395",
"url": "https://ci.chromium.org/b/8873722016494915168",
"utcnow_ts": "1595760421983571"
}
}
[10:47:02.005] Invocation finished in 1h9m39.465997441s with status SUCCEEDED