[01:09:29.041] New invocation is queued and will start shortly
[01:09:30.504] Starting the invocation (attempt 1)
[01:09:30.544] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:09:30.544] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:09:30.544] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9066634913456813456",
"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\":\"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\",\"title\":\"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9066634913456813456",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"gitiles_ref:refs/heads/master"
]
}
[01:09:34.271] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1569546571355521",
"id": "8901204850353246224",
"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\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"title\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1569546573438962",
"tags": [
"builder:linux",
"buildset:commit/git/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066634913456813456",
"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/8901204850353246224/+/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": "1569546573439077",
"url": "https://ci.chromium.org/b/8901204850353246224",
"utcnow_ts": "1569546574234696"
}
}
[01:09:34.271] Task URL: https://ci.chromium.org/b/8901204850353246224
[01:09:34.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:2:0) after 1m0s
[01:10:34.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:2:0)
[01:10:34.286] Timer tick, asking Buildbucket for the build status
[01:10:34.973] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:34.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:3:0) after 1m0s
[01:11:34.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:3:0)
[01:11:34.997] Timer tick, asking Buildbucket for the build status
[01:11:35.740] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:35.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:4:0) after 1m0s
[01:12:35.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:4:0)
[01:12:35.766] Timer tick, asking Buildbucket for the build status
[01:12:36.609] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:36.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:5:0) after 1m0s
[01:13:36.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:5:0)
[01:13:36.666] Timer tick, asking Buildbucket for the build status
[01:13:36.849] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:36.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:6:0) after 1m0s
[01:14:36.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:6:0)
[01:14:36.919] Timer tick, asking Buildbucket for the build status
[01:14:38.097] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:38.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:7:0) after 1m0s
[01:15:38.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:7:0)
[01:15:38.166] Timer tick, asking Buildbucket for the build status
[01:15:39.351] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:39.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:8:0) after 1m0s
[01:16:39.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:8:0)
[01:16:39.429] Timer tick, asking Buildbucket for the build status
[01:16:40.117] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:40.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:9:0) after 1m0s
[01:17:40.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:9:0)
[01:17:40.112] Timer tick, asking Buildbucket for the build status
[01:17:40.461] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:40.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:10:0) after 1m0s
[01:18:40.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:10:0)
[01:18:40.545] Timer tick, asking Buildbucket for the build status
[01:18:41.163] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:41.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:11:0) after 1m0s
[01:19:41.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:11:0)
[01:19:41.208] Timer tick, asking Buildbucket for the build status
[01:19:41.381] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:41.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:12:0) after 1m0s
[01:20:41.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:12:0)
[01:20:41.449] Timer tick, asking Buildbucket for the build status
[01:20:51.598] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:51.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:13:0) after 1m0s
[01:21:51.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:13:0)
[01:21:51.727] Timer tick, asking Buildbucket for the build status
[01:21:52.443] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:52.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:14:0) after 1m0s
[01:22:52.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:14:0)
[01:22:52.504] Timer tick, asking Buildbucket for the build status
[01:22:53.285] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:53.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:15:0) after 1m0s
[01:23:53.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:15:0)
[01:23:53.322] Timer tick, asking Buildbucket for the build status
[01:23:53.551] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:53.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:16:0) after 1m0s
[01:24:53.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:16:0)
[01:24:53.627] Timer tick, asking Buildbucket for the build status
[01:24:54.493] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:54.493] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:17:0) after 1m0s
[01:25:54.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:17:0)
[01:25:54.541] Timer tick, asking Buildbucket for the build status
[01:25:55.132] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:55.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:18:0) after 1m0s
[01:26:55.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:18:0)
[01:26:55.205] Timer tick, asking Buildbucket for the build status
[01:26:55.953] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:55.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:19:0) after 1m0s
[01:27:56.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:19:0)
[01:27:56.029] Timer tick, asking Buildbucket for the build status
[01:27:56.159] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:56.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:20:0) after 1m0s
[01:28:56.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:20:0)
[01:28:56.139] Timer tick, asking Buildbucket for the build status
[01:28:56.799] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:56.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:21:0) after 1m0s
[01:29:56.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:21:0)
[01:29:56.874] Timer tick, asking Buildbucket for the build status
[01:29:57.258] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:57.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:22:0) after 1m0s
[01:30:57.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:22:0)
[01:30:57.341] Timer tick, asking Buildbucket for the build status
[01:30:57.421] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:57.421] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:23:0) after 1m0s
[01:31:57.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:23:0)
[01:31:57.508] Timer tick, asking Buildbucket for the build status
[01:31:57.673] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:57.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:24:0) after 1m0s
[01:32:57.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:24:0)
[01:32:57.727] Timer tick, asking Buildbucket for the build status
[01:32:58.222] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:58.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:25:0) after 1m0s
[01:33:58.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:25:0)
[01:33:58.258] Timer tick, asking Buildbucket for the build status
[01:33:59.242] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:59.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:26:0) after 1m0s
[01:34:59.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:26:0)
[01:34:59.224] Timer tick, asking Buildbucket for the build status
[01:34:59.481] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:59.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:27:0) after 1m0s
[01:35:59.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:27:0)
[01:35:59.490] Timer tick, asking Buildbucket for the build status
[01:35:59.608] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:59.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:28:0) after 1m0s
[01:36:59.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:28:0)
[01:36:59.716] Timer tick, asking Buildbucket for the build status
[01:37:00.078] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:00.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:29:0) after 1m0s
[01:38:00.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:29:0)
[01:38:00.218] Timer tick, asking Buildbucket for the build status
[01:38:00.753] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:00.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:30:0) after 1m0s
[01:39:00.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:30:0)
[01:39:00.837] Timer tick, asking Buildbucket for the build status
[01:39:01.155] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:01.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:31:0) after 1m0s
[01:40:01.232] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:31:0)
[01:40:01.232] Timer tick, asking Buildbucket for the build status
[01:40:01.495] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:01.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:32:0) after 1m0s
[01:41:01.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:32:0)
[01:41:01.609] Timer tick, asking Buildbucket for the build status
[01:41:01.726] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:01.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:33:0) after 1m0s
[01:42:01.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:33:0)
[01:42:01.761] Timer tick, asking Buildbucket for the build status
[01:42:01.954] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:01.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:34:0) after 1m0s
[01:43:02.022] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:34:0)
[01:43:02.022] Timer tick, asking Buildbucket for the build status
[01:43:03.782] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:03.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:35:0) after 1m0s
[01:44:03.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:35:0)
[01:44:03.796] Timer tick, asking Buildbucket for the build status
[01:44:05.591] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:05.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:36:0) after 1m0s
[01:45:05.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:36:0)
[01:45:05.650] Timer tick, asking Buildbucket for the build status
[01:45:06.725] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:06.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:37:0) after 1m0s
[01:46:06.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:37:0)
[01:46:06.792] Timer tick, asking Buildbucket for the build status
[01:46:07.011] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:07.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:38:0) after 1m0s
[01:47:07.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:38:0)
[01:47:07.062] Timer tick, asking Buildbucket for the build status
[01:47:08.083] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:08.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:39:0) after 1m0s
[01:48:08.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:39:0)
[01:48:08.185] Timer tick, asking Buildbucket for the build status
[01:48:08.399] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:08.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:40:0) after 1m0s
[01:49:08.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:40:0)
[01:49:08.473] Timer tick, asking Buildbucket for the build status
[01:49:09.327] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:09.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:41:0) after 1m0s
[01:50:09.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:41:0)
[01:50:09.353] Timer tick, asking Buildbucket for the build status
[01:50:09.539] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:09.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:42:0) after 1m0s
[01:51:09.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:42:0)
[01:51:09.625] Timer tick, asking Buildbucket for the build status
[01:51:09.800] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:09.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:43:0) after 1m0s
[01:52:09.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:43:0)
[01:52:09.859] Timer tick, asking Buildbucket for the build status
[01:52:10.733] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:10.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:44:0) after 1m0s
[01:53:10.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:44:0)
[01:53:10.875] Timer tick, asking Buildbucket for the build status
[01:53:11.458] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:11.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:45:0) after 1m0s
[01:54:11.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:45:0)
[01:54:11.530] Timer tick, asking Buildbucket for the build status
[01:54:12.389] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:12.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:46:0) after 1m0s
[01:55:12.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:46:0)
[01:55:12.427] Timer tick, asking Buildbucket for the build status
[01:55:13.534] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:13.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:47:0) after 1m0s
[01:56:13.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:47:0)
[01:56:13.703] Timer tick, asking Buildbucket for the build status
[01:56:14.617] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:14.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:48:0) after 1m0s
[01:57:14.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:48:0)
[01:57:14.629] Timer tick, asking Buildbucket for the build status
[01:57:15.309] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:15.309] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:49:0) after 1m0s
[01:58:15.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:49:0)
[01:58:15.334] Timer tick, asking Buildbucket for the build status
[01:58:15.464] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:15.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:50:0) after 1m0s
[01:59:15.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:50:0)
[01:59:15.558] Timer tick, asking Buildbucket for the build status
[01:59:15.982] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:15.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:51:0) after 1m0s
[02:00:16.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:51:0)
[02:00:16.017] Timer tick, asking Buildbucket for the build status
[02:00:16.342] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:16.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:52:0) after 1m0s
[02:01:16.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:52:0)
[02:01:16.385] Timer tick, asking Buildbucket for the build status
[02:01:17.355] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:17.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:53:0) after 1m0s
[02:02:17.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:53:0)
[02:02:17.384] Timer tick, asking Buildbucket for the build status
[02:02:17.768] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:17.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:54:0) after 1m0s
[02:03:17.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:54:0)
[02:03:17.931] Timer tick, asking Buildbucket for the build status
[02:03:18.174] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:18.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:55:0) after 1m0s
[02:04:18.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:55:0)
[02:04:18.231] Timer tick, asking Buildbucket for the build status
[02:04:19.352] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:19.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:56:0) after 1m0s
[02:05:19.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:56:0)
[02:05:19.436] Timer tick, asking Buildbucket for the build status
[02:05:22.427] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:22.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:57:0) after 1m0s
[02:06:22.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:57:0)
[02:06:22.506] Timer tick, asking Buildbucket for the build status
[02:06:22.862] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:22.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:58:0) after 1m0s
[02:07:22.886] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:58:0)
[02:07:22.886] Timer tick, asking Buildbucket for the build status
[02:07:23.010] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:23.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:59:0) after 1m0s
[02:08:23.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:59:0)
[02:08:23.056] Timer tick, asking Buildbucket for the build status
[02:08:23.219] Build 8901204850353246224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:23.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:60:0) after 1m0s
[02:08:34.839] Received PubSub notification, asking Buildbucket for the build status
[02:08:35.508] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:60:0)
[02:09:23.308] Timer tick, asking Buildbucket for the build status
[02:09:23.420] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:62:0) after 1m0s
[02:10:23.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:62:0)
[02:10:23.431] Timer tick, asking Buildbucket for the build status
[02:10:23.734] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:23.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:63:0) after 1m0s
[02:11:23.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:63:0)
[02:11:23.854] Timer tick, asking Buildbucket for the build status
[02:11:24.511] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:24.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:64:0) after 1m0s
[02:12:24.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:64:0)
[02:12:24.570] Timer tick, asking Buildbucket for the build status
[02:12:25.052] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:25.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:65:0) after 1m0s
[02:13:25.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:65:0)
[02:13:25.179] Timer tick, asking Buildbucket for the build status
[02:13:25.373] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:25.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:66:0) after 1m0s
[02:14:25.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:66:0)
[02:14:25.413] Timer tick, asking Buildbucket for the build status
[02:14:27.269] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:27.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:67:0) after 1m0s
[02:15:27.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:67:0)
[02:15:27.369] Timer tick, asking Buildbucket for the build status
[02:15:27.447] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:27.447] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:68:0) after 1m0s
[02:16:27.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:68:0)
[02:16:27.471] Timer tick, asking Buildbucket for the build status
[02:16:27.605] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:27.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:69:0) after 1m0s
[02:17:27.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:69:0)
[02:17:27.678] Timer tick, asking Buildbucket for the build status
[02:17:28.930] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:28.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:70:0) after 1m0s
[02:18:29.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:70:0)
[02:18:29.014] Timer tick, asking Buildbucket for the build status
[02:18:30.269] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:30.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:71:0) after 1m0s
[02:19:30.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:71:0)
[02:19:30.442] Timer tick, asking Buildbucket for the build status
[02:19:30.609] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:30.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:72:0) after 1m0s
[02:20:30.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:72:0)
[02:20:30.743] Timer tick, asking Buildbucket for the build status
[02:20:31.993] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:31.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:73:0) after 1m0s
[02:21:32.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:73:0)
[02:21:32.057] Timer tick, asking Buildbucket for the build status
[02:21:32.354] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:32.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:74:0) after 1m0s
[02:22:32.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:74:0)
[02:22:32.382] Timer tick, asking Buildbucket for the build status
[02:22:33.049] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:33.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:75:0) after 1m0s
[02:23:33.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:75:0)
[02:23:33.067] Timer tick, asking Buildbucket for the build status
[02:23:33.316] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:33.316] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:76:0) after 1m0s
[02:24:33.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:76:0)
[02:24:33.391] Timer tick, asking Buildbucket for the build status
[02:24:33.982] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:33.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:77:0) after 1m0s
[02:25:34.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:77:0)
[02:25:34.086] Timer tick, asking Buildbucket for the build status
[02:25:34.258] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:34.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:78:0) after 1m0s
[02:26:34.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:78:0)
[02:26:34.353] Timer tick, asking Buildbucket for the build status
[02:26:34.501] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:34.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:79:0) after 1m0s
[02:27:34.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:79:0)
[02:27:34.654] Timer tick, asking Buildbucket for the build status
[02:27:35.023] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:35.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:80:0) after 1m0s
[02:28:35.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:80:0)
[02:28:35.279] Timer tick, asking Buildbucket for the build status
[02:28:35.436] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:35.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:81:0) after 1m0s
[02:29:35.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:81:0)
[02:29:35.604] Timer tick, asking Buildbucket for the build status
[02:29:35.725] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:35.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:82:0) after 1m0s
[02:30:35.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:82:0)
[02:30:35.846] Timer tick, asking Buildbucket for the build status
[02:30:36.029] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:36.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:83:0) after 1m0s
[02:31:36.173] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:83:0)
[02:31:36.173] Timer tick, asking Buildbucket for the build status
[02:31:36.660] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:36.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:84:0) after 1m0s
[02:32:36.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:84:0)
[02:32:36.724] Timer tick, asking Buildbucket for the build status
[02:32:36.890] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:36.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:85:0) after 1m0s
[02:33:36.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:85:0)
[02:33:36.937] Timer tick, asking Buildbucket for the build status
[02:33:37.222] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:37.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:86:0) after 1m0s
[02:34:37.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:86:0)
[02:34:37.284] Timer tick, asking Buildbucket for the build status
[02:34:37.531] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:37.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:87:0) after 1m0s
[02:35:37.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:87:0)
[02:35:37.594] Timer tick, asking Buildbucket for the build status
[02:35:37.711] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:37.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:88:0) after 1m0s
[02:36:37.791] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:88:0)
[02:36:37.791] Timer tick, asking Buildbucket for the build status
[02:36:38.052] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:38.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:89:0) after 1m0s
[02:37:38.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:89:0)
[02:37:38.150] Timer tick, asking Buildbucket for the build status
[02:37:38.365] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:38.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:90:0) after 1m0s
[02:38:38.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:90:0)
[02:38:38.372] Timer tick, asking Buildbucket for the build status
[02:38:38.427] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:38.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:91:0) after 1m0s
[02:39:38.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:91:0)
[02:39:38.444] Timer tick, asking Buildbucket for the build status
[02:39:38.571] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:38.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:92:0) after 1m0s
[02:40:38.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:92:0)
[02:40:38.700] Timer tick, asking Buildbucket for the build status
[02:40:39.989] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:39.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:93:0) after 1m0s
[02:41:40.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:93:0)
[02:41:40.025] Timer tick, asking Buildbucket for the build status
[02:41:40.589] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:40.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:94:0) after 1m0s
[02:42:40.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:94:0)
[02:42:40.620] Timer tick, asking Buildbucket for the build status
[02:42:40.868] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:40.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:95:0) after 1m0s
[02:43:40.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:95:0)
[02:43:40.962] Timer tick, asking Buildbucket for the build status
[02:43:41.301] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:41.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:96:0) after 1m0s
[02:44:41.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:96:0)
[02:44:41.624] Timer tick, asking Buildbucket for the build status
[02:44:41.952] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:41.952] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:97:0) after 1m0s
[02:45:42.018] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:97:0)
[02:45:42.018] Timer tick, asking Buildbucket for the build status
[02:45:42.203] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:42.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:98:0) after 1m0s
[02:46:42.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:98:0)
[02:46:42.250] Timer tick, asking Buildbucket for the build status
[02:46:42.609] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:42.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:99:0) after 1m0s
[02:47:42.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:99:0)
[02:47:42.743] Timer tick, asking Buildbucket for the build status
[02:47:42.867] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:42.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:100:0) after 1m0s
[02:48:43.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:100:0)
[02:48:43.132] Timer tick, asking Buildbucket for the build status
[02:48:43.462] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:43.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:101:0) after 1m0s
[02:49:43.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:101:0)
[02:49:43.610] Timer tick, asking Buildbucket for the build status
[02:49:43.875] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:43.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:102:0) after 1m0s
[02:50:44.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:102:0)
[02:50:44.241] Timer tick, asking Buildbucket for the build status
[02:50:44.656] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:44.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:103:0) after 1m0s
[02:51:44.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:103:0)
[02:51:44.902] Timer tick, asking Buildbucket for the build status
[02:51:46.852] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:46.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:104:0) after 1m0s
[02:52:46.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:104:0)
[02:52:46.911] Timer tick, asking Buildbucket for the build status
[02:52:47.070] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.070] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:105:0) after 1m0s
[02:53:47.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:105:0)
[02:53:47.239] Timer tick, asking Buildbucket for the build status
[02:53:47.600] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:47.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:106:0) after 1m0s
[02:54:47.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:106:0)
[02:54:47.630] Timer tick, asking Buildbucket for the build status
[02:54:48.119] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:48.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:107:0) after 1m0s
[02:55:48.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:107:0)
[02:55:48.168] Timer tick, asking Buildbucket for the build status
[02:55:48.613] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:108:0) after 1m0s
[02:56:48.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:108:0)
[02:56:48.666] Timer tick, asking Buildbucket for the build status
[02:56:48.808] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:48.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:109:0) after 1m0s
[02:57:48.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:109:0)
[02:57:48.859] Timer tick, asking Buildbucket for the build status
[02:57:49.153] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:49.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:110:0) after 1m0s
[02:58:49.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:110:0)
[02:58:49.168] Timer tick, asking Buildbucket for the build status
[02:58:49.562] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:49.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:111:0) after 1m0s
[02:59:49.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:111:0)
[02:59:49.654] Timer tick, asking Buildbucket for the build status
[02:59:49.956] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:49.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:112:0) after 1m0s
[03:00:49.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:112:0)
[03:00:49.983] Timer tick, asking Buildbucket for the build status
[03:00:50.145] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:50.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:113:0) after 1m0s
[03:01:50.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:113:0)
[03:01:50.273] Timer tick, asking Buildbucket for the build status
[03:01:51.765] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:51.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:114:0) after 1m0s
[03:02:51.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:114:0)
[03:02:51.904] Timer tick, asking Buildbucket for the build status
[03:02:52.429] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:52.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:115:0) after 1m0s
[03:03:52.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:115:0)
[03:03:52.594] Timer tick, asking Buildbucket for the build status
[03:03:52.771] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:52.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:116:0) after 1m0s
[03:04:52.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:116:0)
[03:04:52.825] Timer tick, asking Buildbucket for the build status
[03:04:53.212] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:53.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:117:0) after 1m0s
[03:05:53.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:117:0)
[03:05:53.239] Timer tick, asking Buildbucket for the build status
[03:05:53.828] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:53.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:118:0) after 1m0s
[03:06:53.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:118:0)
[03:06:53.933] Timer tick, asking Buildbucket for the build status
[03:06:54.071] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:54.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:119:0) after 1m0s
[03:07:54.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:119:0)
[03:07:54.111] Timer tick, asking Buildbucket for the build status
[03:07:54.386] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:54.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:120:0) after 1m0s
[03:08:54.644] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:120:0)
[03:08:54.644] Timer tick, asking Buildbucket for the build status
[03:08:54.802] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:54.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:121:0) after 1m0s
[03:09:54.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:121:0)
[03:09:54.807] Timer tick, asking Buildbucket for the build status
[03:09:55.260] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:55.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:122:0) after 1m0s
[03:10:55.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:122:0)
[03:10:55.349] Timer tick, asking Buildbucket for the build status
[03:10:55.538] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:55.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:123:0) after 1m0s
[03:11:55.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:123:0)
[03:11:55.626] Timer tick, asking Buildbucket for the build status
[03:11:56.096] Build 8901204850353246224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:56.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9066634913456813456:124:0) after 1m0s
[03:12:29.296] Received PubSub notification, asking Buildbucket for the build status
[03:12:29.399] Build 8901204850353246224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:12:29.399] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1569553948325374",
"created_by": "project:emscripten-releases",
"created_ts": "1569546571355521",
"id": "8901204850353246224",
"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\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"title\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}}",
"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\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"title\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-8n6t\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506\"}, \"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-8n6t\"], \"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": "1569550112221998",
"status": "COMPLETED",
"status_changed_ts": "1569553948843351",
"tags": [
"builder:linux",
"buildset:commit/git/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0dd3bfa9bcdccf69e8672a29b3d851d4b1c89506",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066634913456813456",
"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/8901204850353246224/+/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:478915ded1628810",
"user_agent:luci-scheduler"
],
"updated_ts": "1569553948854723",
"url": "https://ci.chromium.org/b/8901204850353246224",
"utcnow_ts": "1569553949390898"
}
}
[03:12:29.399] Invocation finished in 2h3m0.38689958s with status SUCCEEDED