[07:28:01.859] New invocation is queued and will start shortly
[07:28:02.952] Starting the invocation (attempt 1)
[07:28:03.000] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:28:03.000] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:28:03.000] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9066792291261439552",
"parameters_json": "{\"builder_name\":\"linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"aa147e336461e8843c33d4282bb90a3cd6c376b2\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@aa147e336461e8843c33d4282bb90a3cd6c376b2\",\"title\":\"aa147e336461e8843c33d4282bb90a3cd6c376b2\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9066792291261439552",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"gitiles_ref:refs/heads/master"
]
}
[07:28:03.765] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1569396483164326",
"id": "8901362229228581712",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"title\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1569396483584042",
"tags": [
"builder:linux",
"buildset:commit/git/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066792291261439552",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8901362229228581712/+/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": "1569396483584211",
"url": "https://ci.chromium.org/b/8901362229228581712",
"utcnow_ts": "1569396483727565"
}
}
[07:28:03.765] Task URL: https://ci.chromium.org/b/8901362229228581712
[07:28:03.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:2:0) after 1m0s
[07:29:03.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:2:0)
[07:29:03.735] Timer tick, asking Buildbucket for the build status
[07:29:04.966] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:04.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:3:0) after 1m0s
[07:30:05.000] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:3:0)
[07:30:05.000] Timer tick, asking Buildbucket for the build status
[07:30:05.255] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:05.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:4:0) after 1m0s
[07:31:05.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:4:0)
[07:31:05.433] Timer tick, asking Buildbucket for the build status
[07:31:05.548] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:05.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:5:0) after 1m0s
[07:32:05.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:5:0)
[07:32:05.781] Timer tick, asking Buildbucket for the build status
[07:32:05.957] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:05.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:6:0) after 1m0s
[07:33:06.127] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:6:0)
[07:33:06.127] Timer tick, asking Buildbucket for the build status
[07:33:06.314] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:06.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:7:0) after 1m0s
[07:34:06.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:7:0)
[07:34:06.344] Timer tick, asking Buildbucket for the build status
[07:34:06.624] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:06.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:8:0) after 1m0s
[07:35:06.760] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:8:0)
[07:35:06.760] Timer tick, asking Buildbucket for the build status
[07:35:06.881] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:06.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:9:0) after 1m0s
[07:36:06.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:9:0)
[07:36:06.914] Timer tick, asking Buildbucket for the build status
[07:36:07.067] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:07.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:10:0) after 1m0s
[07:37:07.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:10:0)
[07:37:07.175] Timer tick, asking Buildbucket for the build status
[07:37:08.726] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:08.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:11:0) after 1m0s
[07:38:09.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:11:0)
[07:38:09.087] Timer tick, asking Buildbucket for the build status
[07:38:10.477] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:10.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:12:0) after 1m0s
[07:39:10.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:12:0)
[07:39:10.510] Timer tick, asking Buildbucket for the build status
[07:39:11.198] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:11.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:13:0) after 1m0s
[07:40:11.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:13:0)
[07:40:11.364] Timer tick, asking Buildbucket for the build status
[07:40:11.600] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:11.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:14:0) after 1m0s
[07:41:11.754] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:14:0)
[07:41:11.754] Timer tick, asking Buildbucket for the build status
[07:41:12.073] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:12.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:15:0) after 1m0s
[07:42:12.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:15:0)
[07:42:12.165] Timer tick, asking Buildbucket for the build status
[07:42:13.200] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:13.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:16:0) after 1m0s
[07:43:13.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:16:0)
[07:43:13.288] Timer tick, asking Buildbucket for the build status
[07:43:14.084] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:14.084] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:17:0) after 1m0s
[07:44:14.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:17:0)
[07:44:14.171] Timer tick, asking Buildbucket for the build status
[07:44:14.300] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:14.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:18:0) after 1m0s
[07:45:14.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:18:0)
[07:45:14.286] Timer tick, asking Buildbucket for the build status
[07:45:14.447] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:14.447] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:19:0) after 1m0s
[07:46:14.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:19:0)
[07:46:14.540] Timer tick, asking Buildbucket for the build status
[07:46:14.891] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:14.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:20:0) after 1m0s
[07:47:14.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:20:0)
[07:47:14.985] Timer tick, asking Buildbucket for the build status
[07:47:15.272] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:15.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:21:0) after 1m0s
[07:48:15.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:21:0)
[07:48:15.342] Timer tick, asking Buildbucket for the build status
[07:48:15.501] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:15.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:22:0) after 1m0s
[07:49:15.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:22:0)
[07:49:15.532] Timer tick, asking Buildbucket for the build status
[07:49:16.554] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:16.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:23:0) after 1m0s
[07:50:16.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:23:0)
[07:50:16.589] Timer tick, asking Buildbucket for the build status
[07:50:16.860] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:16.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:24:0) after 1m0s
[07:51:16.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:24:0)
[07:51:16.947] Timer tick, asking Buildbucket for the build status
[07:51:17.079] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:17.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:25:0) after 1m0s
[07:52:17.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:25:0)
[07:52:17.196] Timer tick, asking Buildbucket for the build status
[07:52:17.340] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:17.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:26:0) after 1m0s
[07:53:17.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:26:0)
[07:53:17.367] Timer tick, asking Buildbucket for the build status
[07:53:17.505] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:17.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:27:0) after 1m0s
[07:54:17.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:27:0)
[07:54:17.529] Timer tick, asking Buildbucket for the build status
[07:54:17.944] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:17.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:28:0) after 1m0s
[07:55:17.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:28:0)
[07:55:17.917] Timer tick, asking Buildbucket for the build status
[07:55:18.129] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:18.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:29:0) after 1m0s
[07:56:18.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:29:0)
[07:56:18.135] Timer tick, asking Buildbucket for the build status
[07:56:19.103] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:19.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:30:0) after 1m0s
[07:57:19.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:30:0)
[07:57:19.155] Timer tick, asking Buildbucket for the build status
[07:57:19.463] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:19.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:31:0) after 1m0s
[07:58:19.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:31:0)
[07:58:19.526] Timer tick, asking Buildbucket for the build status
[07:58:19.747] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:19.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:32:0) after 1m0s
[07:59:19.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:32:0)
[07:59:19.773] Timer tick, asking Buildbucket for the build status
[07:59:19.864] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:19.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:33:0) after 1m0s
[08:00:19.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:33:0)
[08:00:19.961] Timer tick, asking Buildbucket for the build status
[08:00:20.144] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:20.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:34:0) after 1m0s
[08:01:20.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:34:0)
[08:01:20.157] Timer tick, asking Buildbucket for the build status
[08:01:20.508] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:20.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:35:0) after 1m0s
[08:02:20.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:35:0)
[08:02:20.533] Timer tick, asking Buildbucket for the build status
[08:02:20.602] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:20.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:36:0) after 1m0s
[08:03:20.641] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:36:0)
[08:03:20.641] Timer tick, asking Buildbucket for the build status
[08:03:21.053] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:21.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:37:0) after 1m0s
[08:04:21.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:37:0)
[08:04:21.101] Timer tick, asking Buildbucket for the build status
[08:04:22.259] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:22.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:38:0) after 1m0s
[08:05:22.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:38:0)
[08:05:22.291] Timer tick, asking Buildbucket for the build status
[08:05:22.512] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:22.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:39:0) after 1m0s
[08:06:22.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:39:0)
[08:06:22.570] Timer tick, asking Buildbucket for the build status
[08:06:22.662] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:22.662] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:40:0) after 1m0s
[08:07:22.740] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:40:0)
[08:07:22.740] Timer tick, asking Buildbucket for the build status
[08:07:23.967] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:23.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:41:0) after 1m0s
[08:08:23.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:41:0)
[08:08:23.987] Timer tick, asking Buildbucket for the build status
[08:08:24.133] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:24.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:42:0) after 1m0s
[08:09:24.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:42:0)
[08:09:24.275] Timer tick, asking Buildbucket for the build status
[08:09:24.374] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:24.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:43:0) after 1m0s
[08:10:24.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:43:0)
[08:10:24.427] Timer tick, asking Buildbucket for the build status
[08:10:24.608] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:24.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:44:0) after 1m0s
[08:11:24.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:44:0)
[08:11:24.744] Timer tick, asking Buildbucket for the build status
[08:11:24.965] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:24.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:45:0) after 1m0s
[08:12:25.163] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:45:0)
[08:12:25.163] Timer tick, asking Buildbucket for the build status
[08:12:25.338] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:25.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:46:0) after 1m0s
[08:13:25.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:46:0)
[08:13:25.397] Timer tick, asking Buildbucket for the build status
[08:13:25.660] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:25.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:47:0) after 1m0s
[08:14:25.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:47:0)
[08:14:25.825] Timer tick, asking Buildbucket for the build status
[08:14:26.564] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:26.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:48:0) after 1m0s
[08:15:26.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:48:0)
[08:15:26.680] Timer tick, asking Buildbucket for the build status
[08:15:26.833] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:26.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:49:0) after 1m0s
[08:16:27.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:49:0)
[08:16:27.132] Timer tick, asking Buildbucket for the build status
[08:16:27.274] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:27.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:50:0) after 1m0s
[08:17:27.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:50:0)
[08:17:27.431] Timer tick, asking Buildbucket for the build status
[08:17:27.539] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:27.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:51:0) after 1m0s
[08:18:27.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:51:0)
[08:18:27.565] Timer tick, asking Buildbucket for the build status
[08:18:31.409] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:31.409] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:52:0) after 1m0s
[08:19:31.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:52:0)
[08:19:31.535] Timer tick, asking Buildbucket for the build status
[08:19:31.802] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:31.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:53:0) after 1m0s
[08:20:31.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:53:0)
[08:20:31.898] Timer tick, asking Buildbucket for the build status
[08:20:32.011] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:32.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:54:0) after 1m0s
[08:21:32.074] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:54:0)
[08:21:32.074] Timer tick, asking Buildbucket for the build status
[08:21:32.445] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:32.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:55:0) after 1m0s
[08:22:32.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:55:0)
[08:22:32.471] Timer tick, asking Buildbucket for the build status
[08:22:32.657] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:32.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:56:0) after 1m0s
[08:23:32.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:56:0)
[08:23:32.668] Timer tick, asking Buildbucket for the build status
[08:23:32.764] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:32.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:57:0) after 1m0s
[08:24:32.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:57:0)
[08:24:32.813] Timer tick, asking Buildbucket for the build status
[08:24:33.007] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:33.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:58:0) after 1m0s
[08:25:33.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:58:0)
[08:25:33.057] Timer tick, asking Buildbucket for the build status
[08:25:33.198] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:33.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:59:0) after 1m0s
[08:26:33.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:59:0)
[08:26:33.305] Timer tick, asking Buildbucket for the build status
[08:26:34.856] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:34.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:60:0) after 1m0s
[08:27:34.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:60:0)
[08:27:34.857] Timer tick, asking Buildbucket for the build status
[08:27:34.974] Build 8901362229228581712: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:34.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:61:0) after 1m0s
[08:28:22.380] Received PubSub notification, asking Buildbucket for the build status
[08:28:23.229] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:35.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:61:0)
[08:28:35.083] Timer tick, asking Buildbucket for the build status
[08:28:35.292] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:35.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:63:0) after 1m0s
[08:29:35.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:63:0)
[08:29:35.512] Timer tick, asking Buildbucket for the build status
[08:29:35.671] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:35.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:64:0) after 1m0s
[08:30:35.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:64:0)
[08:30:35.796] Timer tick, asking Buildbucket for the build status
[08:30:36.048] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:36.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:65:0) after 1m0s
[08:31:36.107] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:65:0)
[08:31:36.107] Timer tick, asking Buildbucket for the build status
[08:31:36.257] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:36.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:66:0) after 1m0s
[08:32:36.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:66:0)
[08:32:36.390] Timer tick, asking Buildbucket for the build status
[08:32:36.517] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:36.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:67:0) after 1m0s
[08:33:36.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:67:0)
[08:33:36.544] Timer tick, asking Buildbucket for the build status
[08:33:36.690] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:36.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:68:0) after 1m0s
[08:34:36.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:68:0)
[08:34:36.729] Timer tick, asking Buildbucket for the build status
[08:34:36.805] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:36.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:69:0) after 1m0s
[08:35:36.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:69:0)
[08:35:36.851] Timer tick, asking Buildbucket for the build status
[08:35:36.995] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:36.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:70:0) after 1m0s
[08:36:37.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:70:0)
[08:36:37.153] Timer tick, asking Buildbucket for the build status
[08:36:37.295] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:37.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:71:0) after 1m0s
[08:37:37.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:71:0)
[08:37:37.430] Timer tick, asking Buildbucket for the build status
[08:37:37.601] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:37.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:72:0) after 1m0s
[08:38:37.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:72:0)
[08:38:37.744] Timer tick, asking Buildbucket for the build status
[08:38:37.864] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:37.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:73:0) after 1m0s
[08:39:37.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:73:0)
[08:39:37.914] Timer tick, asking Buildbucket for the build status
[08:39:38.433] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:38.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:74:0) after 1m0s
[08:40:38.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:74:0)
[08:40:38.473] Timer tick, asking Buildbucket for the build status
[08:40:38.674] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:38.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:75:0) after 1m0s
[08:41:38.754] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:75:0)
[08:41:38.754] Timer tick, asking Buildbucket for the build status
[08:41:39.102] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:39.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:76:0) after 1m0s
[08:42:39.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:76:0)
[08:42:39.196] Timer tick, asking Buildbucket for the build status
[08:42:39.442] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:39.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:77:0) after 1m0s
[08:43:39.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:77:0)
[08:43:39.498] Timer tick, asking Buildbucket for the build status
[08:43:39.808] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:39.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:78:0) after 1m0s
[08:44:39.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:78:0)
[08:44:39.830] Timer tick, asking Buildbucket for the build status
[08:44:40.023] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:40.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:79:0) after 1m0s
[08:45:40.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:79:0)
[08:45:40.092] Timer tick, asking Buildbucket for the build status
[08:45:40.436] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:40.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:80:0) after 1m0s
[08:46:40.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:80:0)
[08:46:40.564] Timer tick, asking Buildbucket for the build status
[08:46:41.703] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:41.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:81:0) after 1m0s
[08:47:41.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:81:0)
[08:47:41.764] Timer tick, asking Buildbucket for the build status
[08:47:42.055] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:42.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:82:0) after 1m0s
[08:48:42.090] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:82:0)
[08:48:42.090] Timer tick, asking Buildbucket for the build status
[08:48:42.348] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:42.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:83:0) after 1m0s
[08:49:42.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:83:0)
[08:49:42.373] Timer tick, asking Buildbucket for the build status
[08:49:43.495] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:43.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:84:0) after 1m0s
[08:50:43.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:84:0)
[08:50:43.489] Timer tick, asking Buildbucket for the build status
[08:50:43.609] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:43.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:85:0) after 1m0s
[08:51:43.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:85:0)
[08:51:43.627] Timer tick, asking Buildbucket for the build status
[08:51:43.959] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:43.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:86:0) after 1m0s
[08:52:44.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:86:0)
[08:52:44.062] Timer tick, asking Buildbucket for the build status
[08:52:44.219] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:44.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:87:0) after 1m0s
[08:53:44.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:87:0)
[08:53:44.336] Timer tick, asking Buildbucket for the build status
[08:53:44.434] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:44.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:88:0) after 1m0s
[08:54:44.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:88:0)
[08:54:44.468] Timer tick, asking Buildbucket for the build status
[08:54:44.893] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:44.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:89:0) after 1m0s
[08:55:44.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:89:0)
[08:55:44.937] Timer tick, asking Buildbucket for the build status
[08:55:45.033] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:45.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:90:0) after 1m0s
[08:56:45.080] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:90:0)
[08:56:45.080] Timer tick, asking Buildbucket for the build status
[08:56:45.524] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:45.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:91:0) after 1m0s
[08:57:45.552] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:91:0)
[08:57:45.552] Timer tick, asking Buildbucket for the build status
[08:57:45.683] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:45.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:92:0) after 1m0s
[08:58:45.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:92:0)
[08:58:45.676] Timer tick, asking Buildbucket for the build status
[08:58:46.353] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:46.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:93:0) after 1m0s
[08:59:46.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:93:0)
[08:59:46.409] Timer tick, asking Buildbucket for the build status
[08:59:46.562] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:46.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:94:0) after 1m0s
[09:00:46.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:94:0)
[09:00:46.599] Timer tick, asking Buildbucket for the build status
[09:00:47.918] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:47.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:95:0) after 1m0s
[09:01:47.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:95:0)
[09:01:47.987] Timer tick, asking Buildbucket for the build status
[09:01:48.242] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:48.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:96:0) after 1m0s
[09:02:48.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:96:0)
[09:02:48.329] Timer tick, asking Buildbucket for the build status
[09:02:48.463] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:48.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:97:0) after 1m0s
[09:03:48.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:97:0)
[09:03:48.581] Timer tick, asking Buildbucket for the build status
[09:03:49.009] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:49.009] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:98:0) after 1m0s
[09:04:49.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:98:0)
[09:04:49.215] Timer tick, asking Buildbucket for the build status
[09:04:49.411] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:49.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:99:0) after 1m0s
[09:05:49.492] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:99:0)
[09:05:49.492] Timer tick, asking Buildbucket for the build status
[09:05:49.647] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:49.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:100:0) after 1m0s
[09:06:49.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:100:0)
[09:06:49.669] Timer tick, asking Buildbucket for the build status
[09:06:49.852] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:49.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:101:0) after 1m0s
[09:07:49.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:101:0)
[09:07:49.894] Timer tick, asking Buildbucket for the build status
[09:07:50.461] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:50.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:102:0) after 1m0s
[09:08:50.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:102:0)
[09:08:50.486] Timer tick, asking Buildbucket for the build status
[09:08:50.740] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:50.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:103:0) after 1m0s
[09:09:50.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:103:0)
[09:09:50.766] Timer tick, asking Buildbucket for the build status
[09:10:03.304] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:03.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:104:0) after 1m0s
[09:11:03.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:104:0)
[09:11:03.393] Timer tick, asking Buildbucket for the build status
[09:11:03.486] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:03.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:105:0) after 1m0s
[09:12:03.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:105:0)
[09:12:03.516] Timer tick, asking Buildbucket for the build status
[09:12:03.922] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:03.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:106:0) after 1m0s
[09:13:04.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:106:0)
[09:13:04.024] Timer tick, asking Buildbucket for the build status
[09:13:05.971] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:05.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:107:0) after 1m0s
[09:14:06.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:107:0)
[09:14:06.043] Timer tick, asking Buildbucket for the build status
[09:14:06.771] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:06.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:108:0) after 1m0s
[09:15:06.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:108:0)
[09:15:06.889] Timer tick, asking Buildbucket for the build status
[09:15:06.996] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:06.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:109:0) after 1m0s
[09:16:06.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:109:0)
[09:16:06.982] Timer tick, asking Buildbucket for the build status
[09:16:07.508] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:07.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:110:0) after 1m0s
[09:17:07.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:110:0)
[09:17:07.550] Timer tick, asking Buildbucket for the build status
[09:17:07.724] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:07.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:111:0) after 1m0s
[09:18:07.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:111:0)
[09:18:07.706] Timer tick, asking Buildbucket for the build status
[09:18:08.577] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:08.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:112:0) after 1m0s
[09:19:08.708] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:112:0)
[09:19:08.708] Timer tick, asking Buildbucket for the build status
[09:19:09.093] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:09.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:113:0) after 1m0s
[09:20:09.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:113:0)
[09:20:09.137] Timer tick, asking Buildbucket for the build status
[09:20:09.216] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:09.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:114:0) after 1m0s
[09:21:09.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:114:0)
[09:21:09.333] Timer tick, asking Buildbucket for the build status
[09:21:09.657] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:09.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:115:0) after 1m0s
[09:22:09.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:115:0)
[09:22:09.739] Timer tick, asking Buildbucket for the build status
[09:22:10.067] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:10.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:116:0) after 1m0s
[09:23:10.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:116:0)
[09:23:10.135] Timer tick, asking Buildbucket for the build status
[09:23:10.358] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:10.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:117:0) after 1m0s
[09:24:10.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:117:0)
[09:24:10.383] Timer tick, asking Buildbucket for the build status
[09:24:12.062] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:12.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:118:0) after 1m0s
[09:25:12.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:118:0)
[09:25:12.156] Timer tick, asking Buildbucket for the build status
[09:25:12.650] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:12.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:119:0) after 1m0s
[09:26:12.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:119:0)
[09:26:12.803] Timer tick, asking Buildbucket for the build status
[09:26:14.335] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:14.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:120:0) after 1m0s
[09:27:14.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:120:0)
[09:27:14.399] Timer tick, asking Buildbucket for the build status
[09:27:14.808] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:14.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:121:0) after 1m0s
[09:28:14.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:121:0)
[09:28:14.938] Timer tick, asking Buildbucket for the build status
[09:28:15.048] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:15.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:122:0) after 1m0s
[09:29:15.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:122:0)
[09:29:15.230] Timer tick, asking Buildbucket for the build status
[09:29:15.327] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:15.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:123:0) after 1m0s
[09:30:19.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:123:0)
[09:30:19.087] Timer tick, asking Buildbucket for the build status
[09:30:19.215] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:19.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:124:0) after 1m0s
[09:31:19.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:124:0)
[09:31:19.247] Timer tick, asking Buildbucket for the build status
[09:31:19.375] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:19.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:125:0) after 1m0s
[09:32:19.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:125:0)
[09:32:19.400] Timer tick, asking Buildbucket for the build status
[09:32:20.446] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:20.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:126:0) after 1m0s
[09:33:20.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:126:0)
[09:33:20.480] Timer tick, asking Buildbucket for the build status
[09:33:21.302] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:21.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:127:0) after 1m0s
[09:34:30.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:127:0)
[09:34:30.203] Timer tick, asking Buildbucket for the build status
[09:34:30.628] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:30.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:128:0) after 1m0s
[09:35:30.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:128:0)
[09:35:30.719] Timer tick, asking Buildbucket for the build status
[09:35:30.831] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:30.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:129:0) after 1m0s
[09:36:30.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:129:0)
[09:36:30.878] Timer tick, asking Buildbucket for the build status
[09:36:30.972] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:30.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:130:0) after 1m0s
[09:37:31.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:130:0)
[09:37:31.020] Timer tick, asking Buildbucket for the build status
[09:37:31.160] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:31.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:131:0) after 1m0s
[09:38:31.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:131:0)
[09:38:31.239] Timer tick, asking Buildbucket for the build status
[09:38:31.439] Build 8901362229228581712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:31.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066792291261439552:132:0) after 1m0s
[09:38:47.162] Received PubSub notification, asking Buildbucket for the build status
[09:38:47.306] Build 8901362229228581712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:38:47.306] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1569404326059077",
"created_by": "project:emscripten-releases",
"created_ts": "1569396483164326",
"id": "8901362229228581712",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"title\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/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\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"title\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-pait\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"aa147e336461e8843c33d4282bb90a3cd6c376b2\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_461cb3affc6f270af3ac9b28cfd8af8c60e3aebc1d1ae3ebd8f2aa2c00b55fae_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-0-pait\"], \"image\": [\"chrome-xenial-19090600-ed917d8233f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4443-3a52fcb\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1569400101075639",
"status": "COMPLETED",
"status_changed_ts": "1569404326532012",
"tags": [
"builder:linux",
"buildset:commit/git/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/aa147e336461e8843c33d4282bb90a3cd6c376b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066792291261439552",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8901362229228581712/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:478024890cd75f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1569404326543144",
"url": "https://ci.chromium.org/b/8901362229228581712",
"utcnow_ts": "1569404327280454"
}
}
[09:38:47.306] Invocation finished in 2h10m45.465010331s with status SUCCEEDED