[03:04:58.932] New invocation is queued and will start shortly
[03:05:01.207] Starting the invocation (attempt 1)
[03:05:01.302] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:05:01.302] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:05:01.302] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9048327059719211408",
"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\":\"154922b178c90ad0e5496992da9c590dcc8179d5\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@154922b178c90ad0e5496992da9c590dcc8179d5\",\"title\":\"154922b178c90ad0e5496992da9c590dcc8179d5\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"154922b178c90ad0e5496992da9c590dcc8179d5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9048327059719211408",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/154922b178c90ad0e5496992da9c590dcc8179d5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5",
"gitiles_ref:refs/heads/master"
]
}
[03:05:01.911] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1587006301419599",
"id": "8882896996441660032",
"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\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@154922b178c90ad0e5496992da9c590dcc8179d5\", \"title\": \"154922b178c90ad0e5496992da9c590dcc8179d5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587006301782093",
"tags": [
"builder:win",
"buildset:commit/git/154922b178c90ad0e5496992da9c590dcc8179d5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048327059719211408",
"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/8882896996441660032/+/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": "1587006301782243",
"url": "https://ci.chromium.org/b/8882896996441660032",
"utcnow_ts": "1587006301900762"
}
}
[03:05:01.911] Task URL: https://ci.chromium.org/b/8882896996441660032
[03:05:01.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:2:0) after 1m0s
[03:05:16.428] Received PubSub notification, asking Buildbucket for the build status
[03:05:16.526] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:02.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:2:0)
[03:06:02.001] Timer tick, asking Buildbucket for the build status
[03:06:02.156] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:02.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:4:0) after 1m0s
[03:07:02.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:4:0)
[03:07:02.362] Timer tick, asking Buildbucket for the build status
[03:07:02.783] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:02.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:5:0) after 1m0s
[03:08:02.890] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:5:0)
[03:08:02.890] Timer tick, asking Buildbucket for the build status
[03:08:03.138] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:03.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:6:0) after 1m0s
[03:09:03.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:6:0)
[03:09:03.314] Timer tick, asking Buildbucket for the build status
[03:09:03.513] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:03.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:7:0) after 1m0s
[03:10:03.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:7:0)
[03:10:03.551] Timer tick, asking Buildbucket for the build status
[03:10:03.760] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:03.760] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:8:0) after 1m0s
[03:11:03.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:8:0)
[03:11:03.789] Timer tick, asking Buildbucket for the build status
[03:11:03.923] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:03.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:9:0) after 1m0s
[03:12:03.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:9:0)
[03:12:03.901] Timer tick, asking Buildbucket for the build status
[03:12:04.285] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:04.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:10:0) after 1m0s
[03:13:04.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:10:0)
[03:13:04.336] Timer tick, asking Buildbucket for the build status
[03:13:04.515] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:04.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:11:0) after 1m0s
[03:14:04.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:11:0)
[03:14:04.596] Timer tick, asking Buildbucket for the build status
[03:14:04.824] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:04.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:12:0) after 1m0s
[03:15:04.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:12:0)
[03:15:04.826] Timer tick, asking Buildbucket for the build status
[03:15:05.323] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:05.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:13:0) after 1m0s
[03:16:05.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:13:0)
[03:16:05.343] Timer tick, asking Buildbucket for the build status
[03:16:05.478] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:05.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:14:0) after 1m0s
[03:17:05.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:14:0)
[03:17:05.504] Timer tick, asking Buildbucket for the build status
[03:17:05.667] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:05.667] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:15:0) after 1m0s
[03:18:05.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:15:0)
[03:18:05.744] Timer tick, asking Buildbucket for the build status
[03:18:06.072] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:06.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:16:0) after 1m0s
[03:19:06.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:16:0)
[03:19:06.157] Timer tick, asking Buildbucket for the build status
[03:19:06.748] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:06.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:17:0) after 1m0s
[03:20:06.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:17:0)
[03:20:06.765] Timer tick, asking Buildbucket for the build status
[03:20:07.280] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:07.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:18:0) after 1m0s
[03:21:07.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:18:0)
[03:21:07.310] Timer tick, asking Buildbucket for the build status
[03:21:07.535] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:07.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:19:0) after 1m0s
[03:22:07.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:19:0)
[03:22:07.563] Timer tick, asking Buildbucket for the build status
[03:22:07.717] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:07.717] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:20:0) after 1m0s
[03:23:07.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:20:0)
[03:23:07.750] Timer tick, asking Buildbucket for the build status
[03:23:07.952] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:07.952] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:21:0) after 1m0s
[03:24:07.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:21:0)
[03:24:07.976] Timer tick, asking Buildbucket for the build status
[03:24:08.422] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:08.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:22:0) after 1m0s
[03:25:08.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:22:0)
[03:25:08.487] Timer tick, asking Buildbucket for the build status
[03:25:08.759] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:08.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:23:0) after 1m0s
[03:26:08.786] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:23:0)
[03:26:08.786] Timer tick, asking Buildbucket for the build status
[03:26:08.989] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:08.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:24:0) after 1m0s
[03:27:08.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:24:0)
[03:27:08.991] Timer tick, asking Buildbucket for the build status
[03:27:09.483] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:09.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:25:0) after 1m0s
[03:28:09.584] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:25:0)
[03:28:09.584] Timer tick, asking Buildbucket for the build status
[03:28:10.015] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:10.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:26:0) after 1m0s
[03:29:10.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:26:0)
[03:29:10.039] Timer tick, asking Buildbucket for the build status
[03:29:10.350] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:10.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:27:0) after 1m0s
[03:30:10.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:27:0)
[03:30:10.398] Timer tick, asking Buildbucket for the build status
[03:30:10.540] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:10.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:28:0) after 1m0s
[03:31:10.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:28:0)
[03:31:10.596] Timer tick, asking Buildbucket for the build status
[03:31:10.781] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:10.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:29:0) after 1m0s
[03:32:10.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:29:0)
[03:32:10.799] Timer tick, asking Buildbucket for the build status
[03:32:10.935] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:10.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:30:0) after 1m0s
[03:33:11.012] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:30:0)
[03:33:11.012] Timer tick, asking Buildbucket for the build status
[03:33:11.601] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:11.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:31:0) after 1m0s
[03:34:11.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:31:0)
[03:34:11.692] Timer tick, asking Buildbucket for the build status
[03:34:11.826] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:32:0) after 1m0s
[03:35:11.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:32:0)
[03:35:11.885] Timer tick, asking Buildbucket for the build status
[03:35:12.119] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:12.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:33:0) after 1m0s
[03:36:12.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:33:0)
[03:36:12.235] Timer tick, asking Buildbucket for the build status
[03:36:12.395] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:12.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:34:0) after 1m0s
[03:37:12.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:34:0)
[03:37:12.478] Timer tick, asking Buildbucket for the build status
[03:37:12.693] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:12.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:35:0) after 1m0s
[03:38:12.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:35:0)
[03:38:12.748] Timer tick, asking Buildbucket for the build status
[03:38:12.964] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:12.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:36:0) after 1m0s
[03:39:12.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:36:0)
[03:39:12.955] Timer tick, asking Buildbucket for the build status
[03:39:13.353] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:13.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:37:0) after 1m0s
[03:40:13.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:37:0)
[03:40:13.379] Timer tick, asking Buildbucket for the build status
[03:40:13.554] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:13.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:38:0) after 1m0s
[03:41:13.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:38:0)
[03:41:13.586] Timer tick, asking Buildbucket for the build status
[03:41:13.981] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:13.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:39:0) after 1m0s
[03:42:13.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:39:0)
[03:42:13.998] Timer tick, asking Buildbucket for the build status
[03:42:14.135] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:14.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:40:0) after 1m0s
[03:43:14.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:40:0)
[03:43:14.197] Timer tick, asking Buildbucket for the build status
[03:43:14.326] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:14.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:41:0) after 1m0s
[03:44:14.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:41:0)
[03:44:14.438] Timer tick, asking Buildbucket for the build status
[03:44:14.662] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:14.662] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:42:0) after 1m0s
[03:45:14.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:42:0)
[03:45:14.741] Timer tick, asking Buildbucket for the build status
[03:45:14.974] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:14.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:43:0) after 1m0s
[03:46:14.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:43:0)
[03:46:14.999] Timer tick, asking Buildbucket for the build status
[03:46:15.163] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:15.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:44:0) after 1m0s
[03:47:15.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:44:0)
[03:47:15.191] Timer tick, asking Buildbucket for the build status
[03:47:15.357] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:15.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:45:0) after 1m0s
[03:48:15.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:45:0)
[03:48:15.397] Timer tick, asking Buildbucket for the build status
[03:48:15.760] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:15.760] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:46:0) after 1m0s
[03:49:15.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:46:0)
[03:49:15.798] Timer tick, asking Buildbucket for the build status
[03:49:15.967] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:15.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:47:0) after 1m0s
[03:50:16.000] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:47:0)
[03:50:16.000] Timer tick, asking Buildbucket for the build status
[03:50:16.409] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:16.409] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:48:0) after 1m0s
[03:51:16.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:48:0)
[03:51:16.529] Timer tick, asking Buildbucket for the build status
[03:51:17.420] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:17.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:49:0) after 1m0s
[03:52:17.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:49:0)
[03:52:17.483] Timer tick, asking Buildbucket for the build status
[03:52:17.624] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:17.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:50:0) after 1m0s
[03:53:17.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:50:0)
[03:53:17.743] Timer tick, asking Buildbucket for the build status
[03:53:17.901] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:17.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:51:0) after 1m0s
[03:54:18.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:51:0)
[03:54:18.035] Timer tick, asking Buildbucket for the build status
[03:54:18.503] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:18.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:52:0) after 1m0s
[03:55:18.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:52:0)
[03:55:18.569] Timer tick, asking Buildbucket for the build status
[03:55:20.666] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:20.666] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:53:0) after 1m0s
[03:56:20.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:53:0)
[03:56:20.707] Timer tick, asking Buildbucket for the build status
[03:56:21.011] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:21.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:54:0) after 1m0s
[03:57:21.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:54:0)
[03:57:21.101] Timer tick, asking Buildbucket for the build status
[03:57:21.582] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:21.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:55:0) after 1m0s
[03:58:21.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:55:0)
[03:58:21.601] Timer tick, asking Buildbucket for the build status
[03:58:21.867] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:21.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:56:0) after 1m0s
[03:59:21.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:56:0)
[03:59:21.912] Timer tick, asking Buildbucket for the build status
[03:59:22.530] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:22.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:57:0) after 1m0s
[04:00:22.584] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:57:0)
[04:00:22.584] Timer tick, asking Buildbucket for the build status
[04:00:22.752] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:22.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:58:0) after 1m0s
[04:01:22.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:58:0)
[04:01:22.970] Timer tick, asking Buildbucket for the build status
[04:01:23.249] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:23.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:59:0) after 1m0s
[04:02:23.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:59:0)
[04:02:23.284] Timer tick, asking Buildbucket for the build status
[04:02:23.547] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:23.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:60:0) after 1m0s
[04:03:23.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:60:0)
[04:03:23.688] Timer tick, asking Buildbucket for the build status
[04:03:24.232] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:24.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:61:0) after 1m0s
[04:04:24.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:61:0)
[04:04:24.257] Timer tick, asking Buildbucket for the build status
[04:04:24.460] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:24.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:62:0) after 1m0s
[04:05:24.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:62:0)
[04:05:24.516] Timer tick, asking Buildbucket for the build status
[04:05:26.022] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:26.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:63:0) after 1m0s
[04:06:26.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:63:0)
[04:06:26.044] Timer tick, asking Buildbucket for the build status
[04:06:26.223] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:26.223] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:64:0) after 1m0s
[04:07:26.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:64:0)
[04:07:26.243] Timer tick, asking Buildbucket for the build status
[04:07:26.442] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:26.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:65:0) after 1m0s
[04:08:26.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:65:0)
[04:08:26.479] Timer tick, asking Buildbucket for the build status
[04:08:26.810] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:26.810] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:66:0) after 1m0s
[04:09:26.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:66:0)
[04:09:26.837] Timer tick, asking Buildbucket for the build status
[04:09:26.951] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:26.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:67:0) after 1m0s
[04:10:27.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:67:0)
[04:10:27.065] Timer tick, asking Buildbucket for the build status
[04:10:27.198] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:27.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:68:0) after 1m0s
[04:11:27.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:68:0)
[04:11:27.248] Timer tick, asking Buildbucket for the build status
[04:11:28.140] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:28.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:69:0) after 1m0s
[04:12:28.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:69:0)
[04:12:28.326] Timer tick, asking Buildbucket for the build status
[04:12:28.846] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:28.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:70:0) after 1m0s
[04:13:28.913] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:70:0)
[04:13:28.913] Timer tick, asking Buildbucket for the build status
[04:13:29.247] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:29.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:71:0) after 1m0s
[04:14:29.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:71:0)
[04:14:29.327] Timer tick, asking Buildbucket for the build status
[04:14:29.558] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:72:0) after 1m0s
[04:15:29.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:72:0)
[04:15:29.690] Timer tick, asking Buildbucket for the build status
[04:15:29.815] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:73:0) after 1m0s
[04:16:29.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:73:0)
[04:16:29.839] Timer tick, asking Buildbucket for the build status
[04:16:30.208] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:74:0) after 1m0s
[04:17:30.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:74:0)
[04:17:30.306] Timer tick, asking Buildbucket for the build status
[04:17:30.656] Build 8882896996441660032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9048327059719211408:75:0) after 1m0s
[04:18:29.186] Received PubSub notification, asking Buildbucket for the build status
[04:18:29.361] Build 8882896996441660032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:18:29.361] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1587010708427514",
"created_by": "project:emscripten-releases",
"created_ts": "1587006301419599",
"id": "8882896996441660032",
"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\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@154922b178c90ad0e5496992da9c590dcc8179d5\", \"title\": \"154922b178c90ad0e5496992da9c590dcc8179d5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@154922b178c90ad0e5496992da9c590dcc8179d5\", \"title\": \"154922b178c90ad0e5496992da9c590dcc8179d5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-5m6g\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"154922b178c90ad0e5496992da9c590dcc8179d5\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"154922b178c90ad0e5496992da9c590dcc8179d5\"}, \"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-5m6g\"], \"image\": [\"chrome-win10-1703-20041000-c83e5f50d12\"], \"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\": [\"4967-6d1ba8b\"], \"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": "1587006315663499",
"status": "COMPLETED",
"status_changed_ts": "1587010708894759",
"tags": [
"builder:win",
"buildset:commit/git/154922b178c90ad0e5496992da9c590dcc8179d5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/154922b178c90ad0e5496992da9c590dcc8179d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048327059719211408",
"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/8882896996441660032/+/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:4b99c3f080fe6610",
"user_agent:luci-scheduler"
],
"updated_ts": "1587010708914071",
"url": "https://ci.chromium.org/b/8882896996441660032",
"utcnow_ts": "1587010709344997"
}
}
[04:18:29.361] Invocation finished in 1h13m30.450625341s with status SUCCEEDED