[19:30:42.426] New invocation is queued and will start shortly
[19:30:43.914] Starting the invocation (attempt 1)
[19:30:43.958] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:30:43.958] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:30:43.958] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9040383107191526688",
"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\":\"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3870591f2bca7a3a134f318c4a5bf6ecb597847e\",\"title\":\"3870591f2bca7a3a134f318c4a5bf6ecb597847e\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9040383107191526688",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"gitiles_ref:refs/heads/master"
]
}
[19:30:45.292] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1594582244107083",
"id": "8874953044761643008",
"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\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"title\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594582244940656",
"tags": [
"builder:win",
"buildset:commit/git/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040383107191526688",
"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/8874953044761643008/+/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": "1594582244940781",
"url": "https://ci.chromium.org/b/8874953044761643008",
"utcnow_ts": "1594582245271772"
}
}
[19:30:45.292] Task URL: https://ci.chromium.org/b/8874953044761643008
[19:30:45.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:2:0) after 1m0s
[19:31:27.966] Received PubSub notification, asking Buildbucket for the build status
[19:31:28.280] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:45.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:2:0)
[19:31:45.323] Timer tick, asking Buildbucket for the build status
[19:31:45.670] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:45.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:4:0) after 1m0s
[19:32:47.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:4:0)
[19:32:47.088] Timer tick, asking Buildbucket for the build status
[19:32:47.846] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:47.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:5:0) after 1m0s
[19:33:47.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:5:0)
[19:33:47.868] Timer tick, asking Buildbucket for the build status
[19:33:48.281] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:48.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:6:0) after 1m0s
[19:34:48.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:6:0)
[19:34:48.300] Timer tick, asking Buildbucket for the build status
[19:34:48.492] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:48.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:7:0) after 1m0s
[19:35:48.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:7:0)
[19:35:48.585] Timer tick, asking Buildbucket for the build status
[19:35:49.048] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:49.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:8:0) after 1m0s
[19:36:49.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:8:0)
[19:36:49.131] Timer tick, asking Buildbucket for the build status
[19:36:49.464] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:49.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:9:0) after 1m0s
[19:37:49.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:9:0)
[19:37:49.480] Timer tick, asking Buildbucket for the build status
[19:37:49.765] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:49.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:10:0) after 1m0s
[19:38:49.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:10:0)
[19:38:49.865] Timer tick, asking Buildbucket for the build status
[19:38:50.470] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:50.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:11:0) after 1m0s
[19:39:50.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:11:0)
[19:39:50.479] Timer tick, asking Buildbucket for the build status
[19:39:50.849] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:50.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:12:0) after 1m0s
[19:40:50.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:12:0)
[19:40:50.921] Timer tick, asking Buildbucket for the build status
[19:40:51.098] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:51.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:13:0) after 1m0s
[19:41:51.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:13:0)
[19:41:51.146] Timer tick, asking Buildbucket for the build status
[19:41:51.307] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:51.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:14:0) after 1m0s
[19:42:51.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:14:0)
[19:42:51.401] Timer tick, asking Buildbucket for the build status
[19:42:51.688] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:51.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:15:0) after 1m0s
[19:43:51.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:15:0)
[19:43:51.703] Timer tick, asking Buildbucket for the build status
[19:43:51.873] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:51.873] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:16:0) after 1m0s
[19:44:51.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:16:0)
[19:44:51.965] Timer tick, asking Buildbucket for the build status
[19:44:52.212] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:52.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:17:0) after 1m0s
[19:45:52.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:17:0)
[19:45:52.272] Timer tick, asking Buildbucket for the build status
[19:45:52.446] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:52.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:18:0) after 1m0s
[19:46:52.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:18:0)
[19:46:52.461] Timer tick, asking Buildbucket for the build status
[19:46:52.618] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:52.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:19:0) after 1m0s
[19:47:52.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:19:0)
[19:47:52.770] Timer tick, asking Buildbucket for the build status
[19:47:52.899] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:52.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:20:0) after 1m0s
[19:48:53.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:20:0)
[19:48:53.033] Timer tick, asking Buildbucket for the build status
[19:48:53.162] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:53.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:21:0) after 1m0s
[19:49:53.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:21:0)
[19:49:53.238] Timer tick, asking Buildbucket for the build status
[19:49:53.461] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:53.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:22:0) after 1m0s
[19:50:53.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:22:0)
[19:50:53.619] Timer tick, asking Buildbucket for the build status
[19:50:53.918] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:53.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:23:0) after 1m0s
[19:51:53.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:23:0)
[19:51:53.980] Timer tick, asking Buildbucket for the build status
[19:51:54.222] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:54.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:24:0) after 1m0s
[19:52:54.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:24:0)
[19:52:54.992] Timer tick, asking Buildbucket for the build status
[19:52:55.357] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:55.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:25:0) after 1m0s
[19:53:55.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:25:0)
[19:53:55.672] Timer tick, asking Buildbucket for the build status
[19:53:55.808] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:55.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:26:0) after 1m0s
[19:54:55.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:26:0)
[19:54:55.939] Timer tick, asking Buildbucket for the build status
[19:54:56.428] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:56.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:27:0) after 1m0s
[19:55:56.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:27:0)
[19:55:56.595] Timer tick, asking Buildbucket for the build status
[19:55:56.838] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:56.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:28:0) after 1m0s
[19:56:56.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:28:0)
[19:56:56.992] Timer tick, asking Buildbucket for the build status
[19:56:57.555] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:57.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:29:0) after 1m0s
[19:57:57.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:29:0)
[19:57:57.629] Timer tick, asking Buildbucket for the build status
[19:57:58.117] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:58.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:30:0) after 1m0s
[19:58:58.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:30:0)
[19:58:58.259] Timer tick, asking Buildbucket for the build status
[19:58:58.408] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:58.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:31:0) after 1m0s
[19:59:58.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:31:0)
[19:59:58.592] Timer tick, asking Buildbucket for the build status
[19:59:58.711] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:58.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:32:0) after 1m0s
[20:00:59.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:32:0)
[20:00:59.010] Timer tick, asking Buildbucket for the build status
[20:00:59.246] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:59.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:33:0) after 1m0s
[20:01:59.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:33:0)
[20:01:59.316] Timer tick, asking Buildbucket for the build status
[20:01:59.514] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:59.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:34:0) after 1m0s
[20:02:59.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:34:0)
[20:02:59.865] Timer tick, asking Buildbucket for the build status
[20:03:00.257] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:00.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:35:0) after 1m0s
[20:04:00.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:35:0)
[20:04:00.334] Timer tick, asking Buildbucket for the build status
[20:04:00.494] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:00.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:36:0) after 1m0s
[20:05:00.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:36:0)
[20:05:00.897] Timer tick, asking Buildbucket for the build status
[20:05:01.042] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:01.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:37:0) after 1m0s
[20:06:01.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:37:0)
[20:06:01.126] Timer tick, asking Buildbucket for the build status
[20:06:01.237] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:01.237] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:38:0) after 1m0s
[20:07:01.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:38:0)
[20:07:01.262] Timer tick, asking Buildbucket for the build status
[20:07:01.691] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:01.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:39:0) after 1m0s
[20:08:02.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:39:0)
[20:08:02.055] Timer tick, asking Buildbucket for the build status
[20:08:02.191] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:02.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:40:0) after 1m0s
[20:09:02.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:40:0)
[20:09:02.276] Timer tick, asking Buildbucket for the build status
[20:09:02.393] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:02.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:41:0) after 1m0s
[20:10:02.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:41:0)
[20:10:02.506] Timer tick, asking Buildbucket for the build status
[20:10:02.968] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:02.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:42:0) after 1m0s
[20:11:03.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:42:0)
[20:11:03.015] Timer tick, asking Buildbucket for the build status
[20:11:03.368] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:03.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:43:0) after 1m0s
[20:12:03.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:43:0)
[20:12:03.436] Timer tick, asking Buildbucket for the build status
[20:12:03.860] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:03.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:44:0) after 1m0s
[20:13:03.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:44:0)
[20:13:03.914] Timer tick, asking Buildbucket for the build status
[20:13:04.327] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:04.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:45:0) after 1m0s
[20:14:04.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:45:0)
[20:14:04.366] Timer tick, asking Buildbucket for the build status
[20:14:04.515] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:04.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:46:0) after 1m0s
[20:15:05.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:46:0)
[20:15:05.385] Timer tick, asking Buildbucket for the build status
[20:15:05.754] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:05.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:47:0) after 1m0s
[20:16:06.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:47:0)
[20:16:06.013] Timer tick, asking Buildbucket for the build status
[20:16:06.377] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:06.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:48:0) after 1m0s
[20:17:06.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:48:0)
[20:17:06.514] Timer tick, asking Buildbucket for the build status
[20:17:06.716] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:06.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:49:0) after 1m0s
[20:18:06.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:49:0)
[20:18:06.892] Timer tick, asking Buildbucket for the build status
[20:18:07.349] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:07.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:50:0) after 1m0s
[20:19:07.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:50:0)
[20:19:07.507] Timer tick, asking Buildbucket for the build status
[20:19:07.665] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:07.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:51:0) after 1m0s
[20:20:07.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:51:0)
[20:20:07.770] Timer tick, asking Buildbucket for the build status
[20:20:07.945] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:07.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:52:0) after 1m0s
[20:21:08.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:52:0)
[20:21:08.162] Timer tick, asking Buildbucket for the build status
[20:21:08.325] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:53:0) after 1m0s
[20:22:08.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:53:0)
[20:22:08.397] Timer tick, asking Buildbucket for the build status
[20:22:08.730] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:08.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:54:0) after 1m0s
[20:23:08.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:54:0)
[20:23:08.773] Timer tick, asking Buildbucket for the build status
[20:23:08.930] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:08.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:55:0) after 1m0s
[20:24:09.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:55:0)
[20:24:09.016] Timer tick, asking Buildbucket for the build status
[20:24:09.185] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:09.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:56:0) after 1m0s
[20:25:09.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:56:0)
[20:25:09.362] Timer tick, asking Buildbucket for the build status
[20:25:09.586] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:09.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:57:0) after 1m0s
[20:26:09.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:57:0)
[20:26:09.660] Timer tick, asking Buildbucket for the build status
[20:26:09.989] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:09.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:58:0) after 1m0s
[20:27:10.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:58:0)
[20:27:10.020] Timer tick, asking Buildbucket for the build status
[20:27:10.146] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:10.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:59:0) after 1m0s
[20:28:10.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:59:0)
[20:28:10.183] Timer tick, asking Buildbucket for the build status
[20:28:10.617] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:10.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:60:0) after 1m0s
[20:29:10.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:60:0)
[20:29:10.732] Timer tick, asking Buildbucket for the build status
[20:29:10.841] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:10.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:61:0) after 1m0s
[20:30:10.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:61:0)
[20:30:10.916] Timer tick, asking Buildbucket for the build status
[20:30:11.050] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:11.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:62:0) after 1m0s
[20:31:11.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:62:0)
[20:31:11.089] Timer tick, asking Buildbucket for the build status
[20:31:11.270] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:11.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:63:0) after 1m0s
[20:32:11.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:63:0)
[20:32:11.380] Timer tick, asking Buildbucket for the build status
[20:32:11.591] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:11.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:64:0) after 1m0s
[20:33:11.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:64:0)
[20:33:11.662] Timer tick, asking Buildbucket for the build status
[20:33:11.969] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:11.969] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:65:0) after 1m0s
[20:34:12.000] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:65:0)
[20:34:12.000] Timer tick, asking Buildbucket for the build status
[20:34:12.486] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:12.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:66:0) after 1m0s
[20:35:12.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:66:0)
[20:35:12.618] Timer tick, asking Buildbucket for the build status
[20:35:12.758] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:12.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:67:0) after 1m0s
[20:36:12.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:67:0)
[20:36:12.865] Timer tick, asking Buildbucket for the build status
[20:36:12.985] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:12.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:68:0) after 1m0s
[20:37:13.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:68:0)
[20:37:13.065] Timer tick, asking Buildbucket for the build status
[20:37:13.193] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:13.193] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:69:0) after 1m0s
[20:38:13.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:69:0)
[20:38:13.264] Timer tick, asking Buildbucket for the build status
[20:38:13.442] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:13.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:70:0) after 1m0s
[20:39:13.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:70:0)
[20:39:13.469] Timer tick, asking Buildbucket for the build status
[20:39:13.879] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:13.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:71:0) after 1m0s
[20:40:13.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:71:0)
[20:40:13.975] Timer tick, asking Buildbucket for the build status
[20:40:14.200] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:14.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:72:0) after 1m0s
[20:41:14.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:72:0)
[20:41:14.327] Timer tick, asking Buildbucket for the build status
[20:41:14.481] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:14.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:73:0) after 1m0s
[20:42:14.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:73:0)
[20:42:14.514] Timer tick, asking Buildbucket for the build status
[20:42:14.708] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:14.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:74:0) after 1m0s
[20:43:14.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:74:0)
[20:43:14.751] Timer tick, asking Buildbucket for the build status
[20:43:14.922] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:14.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:75:0) after 1m0s
[20:44:14.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:75:0)
[20:44:14.944] Timer tick, asking Buildbucket for the build status
[20:44:15.207] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:15.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:76:0) after 1m0s
[20:45:15.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:76:0)
[20:45:15.285] Timer tick, asking Buildbucket for the build status
[20:45:15.516] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:15.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:77:0) after 1m0s
[20:46:15.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:77:0)
[20:46:15.553] Timer tick, asking Buildbucket for the build status
[20:46:15.693] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:15.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:78:0) after 1m0s
[20:47:15.721] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:78:0)
[20:47:15.721] Timer tick, asking Buildbucket for the build status
[20:47:15.910] Build 8874953044761643008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:15.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9040383107191526688:79:0) after 1m0s
[20:47:35.867] Received PubSub notification, asking Buildbucket for the build status
[20:47:36.075] Build 8874953044761643008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:47:36.075] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1594586854552538",
"created_by": "project:emscripten-releases",
"created_ts": "1594582244107083",
"id": "8874953044761643008",
"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\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"title\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}}",
"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\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"title\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-h0oy\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3870591f2bca7a3a134f318c4a5bf6ecb597847e\"}, \"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-h0oy\"], \"image\": [\"chrome-win10-1703-20070700-8a9e7990cc1\"], \"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\": [\"5378-e275056\"], \"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": "1594582286569730",
"status": "COMPLETED",
"status_changed_ts": "1594586855509416",
"tags": [
"builder:win",
"buildset:commit/git/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3870591f2bca7a3a134f318c4a5bf6ecb597847e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040383107191526688",
"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/8874953044761643008/+/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:4d5d53a9535ff310",
"user_agent:luci-scheduler"
],
"updated_ts": "1594586855523363",
"url": "https://ci.chromium.org/b/8874953044761643008",
"utcnow_ts": "1594586856031029"
}
}
[20:47:36.075] Invocation finished in 1h16m53.668136718s with status SUCCEEDED