[04:38:53.290] New invocation is queued and will start shortly
[04:38:54.575] Starting the invocation (attempt 1)
[04:38:54.673] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:38:54.673] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:38:54.674] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9048230554695435856",
"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\":\"9ae5ed529bb4447641897ad86487f425fd4805b5\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ae5ed529bb4447641897ad86487f425fd4805b5\",\"title\":\"9ae5ed529bb4447641897ad86487f425fd4805b5\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"9ae5ed529bb4447641897ad86487f425fd4805b5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9048230554695435856",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/9ae5ed529bb4447641897ad86487f425fd4805b5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5",
"gitiles_ref:refs/heads/master"
]
}
[04:38:55.348] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1587098334800679",
"id": "8882800492446775968",
"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\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ae5ed529bb4447641897ad86487f425fd4805b5\", \"title\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587098335178071",
"tags": [
"builder:linux",
"buildset:commit/git/9ae5ed529bb4447641897ad86487f425fd4805b5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048230554695435856",
"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/8882800492446775968/+/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": "1587098335178197",
"url": "https://ci.chromium.org/b/8882800492446775968",
"utcnow_ts": "1587098335337808"
}
}
[04:38:55.348] Task URL: https://ci.chromium.org/b/8882800492446775968
[04:38:55.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:2:0) after 1m0s
[04:39:49.640] Received PubSub notification, asking Buildbucket for the build status
[04:39:49.861] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:2:0)
[04:39:55.322] Timer tick, asking Buildbucket for the build status
[04:39:55.455] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:4:0) after 1m0s
[04:40:55.502] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:4:0)
[04:40:55.502] Timer tick, asking Buildbucket for the build status
[04:40:56.050] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:56.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:5:0) after 1m0s
[04:41:56.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:5:0)
[04:41:56.066] Timer tick, asking Buildbucket for the build status
[04:41:56.404] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:56.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:6:0) after 1m0s
[04:42:56.387] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:6:0)
[04:42:56.387] Timer tick, asking Buildbucket for the build status
[04:42:56.540] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:56.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:7:0) after 1m0s
[04:43:56.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:7:0)
[04:43:56.564] Timer tick, asking Buildbucket for the build status
[04:43:56.804] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:56.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:8:0) after 1m0s
[04:44:56.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:8:0)
[04:44:56.826] Timer tick, asking Buildbucket for the build status
[04:44:56.943] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:56.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:9:0) after 1m0s
[04:45:56.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:9:0)
[04:45:56.982] Timer tick, asking Buildbucket for the build status
[04:45:57.125] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:57.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:10:0) after 1m0s
[04:46:57.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:10:0)
[04:46:57.145] Timer tick, asking Buildbucket for the build status
[04:46:57.303] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:57.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:11:0) after 1m0s
[04:47:57.360] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:11:0)
[04:47:57.360] Timer tick, asking Buildbucket for the build status
[04:47:57.530] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:57.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:12:0) after 1m0s
[04:48:57.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:12:0)
[04:48:57.653] Timer tick, asking Buildbucket for the build status
[04:48:57.779] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:57.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:13:0) after 1m0s
[04:49:57.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:13:0)
[04:49:57.795] Timer tick, asking Buildbucket for the build status
[04:49:58.243] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:58.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:14:0) after 1m0s
[04:50:58.319] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:14:0)
[04:50:58.319] Timer tick, asking Buildbucket for the build status
[04:50:58.488] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:58.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:15:0) after 1m0s
[04:51:58.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:15:0)
[04:51:58.527] Timer tick, asking Buildbucket for the build status
[04:51:58.629] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:58.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:16:0) after 1m0s
[04:52:58.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:16:0)
[04:52:58.672] Timer tick, asking Buildbucket for the build status
[04:52:58.821] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:58.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:17:0) after 1m0s
[04:53:58.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:17:0)
[04:53:58.929] Timer tick, asking Buildbucket for the build status
[04:53:59.136] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:18:0) after 1m0s
[04:54:59.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:18:0)
[04:54:59.245] Timer tick, asking Buildbucket for the build status
[04:54:59.659] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:59.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:19:0) after 1m0s
[04:55:59.708] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:19:0)
[04:55:59.708] Timer tick, asking Buildbucket for the build status
[04:56:00.305] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:00.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:20:0) after 1m0s
[04:57:00.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:20:0)
[04:57:00.951] Timer tick, asking Buildbucket for the build status
[04:57:01.112] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:01.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:21:0) after 1m0s
[04:58:01.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:21:0)
[04:58:01.156] Timer tick, asking Buildbucket for the build status
[04:58:01.481] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:01.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:22:0) after 1m0s
[04:59:01.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:22:0)
[04:59:01.638] Timer tick, asking Buildbucket for the build status
[04:59:01.759] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:01.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:23:0) after 1m0s
[05:00:01.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:23:0)
[05:00:01.826] Timer tick, asking Buildbucket for the build status
[05:00:02.003] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:02.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:24:0) after 1m0s
[05:01:02.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:24:0)
[05:01:02.069] Timer tick, asking Buildbucket for the build status
[05:01:02.214] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:02.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:25:0) after 1m0s
[05:02:02.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:25:0)
[05:02:02.316] Timer tick, asking Buildbucket for the build status
[05:02:02.478] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:02.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:26:0) after 1m0s
[05:03:02.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:26:0)
[05:03:02.573] Timer tick, asking Buildbucket for the build status
[05:03:02.727] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:02.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:27:0) after 1m0s
[05:04:02.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:27:0)
[05:04:02.837] Timer tick, asking Buildbucket for the build status
[05:04:03.197] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:03.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:28:0) after 1m0s
[05:05:03.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:28:0)
[05:05:03.302] Timer tick, asking Buildbucket for the build status
[05:05:03.411] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:03.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:29:0) after 1m0s
[05:06:03.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:29:0)
[05:06:03.591] Timer tick, asking Buildbucket for the build status
[05:06:03.967] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:03.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:30:0) after 1m0s
[05:07:04.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:30:0)
[05:07:04.023] Timer tick, asking Buildbucket for the build status
[05:07:04.569] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:04.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:31:0) after 1m0s
[05:08:04.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:31:0)
[05:08:04.750] Timer tick, asking Buildbucket for the build status
[05:08:05.093] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:05.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:32:0) after 1m0s
[05:09:05.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:32:0)
[05:09:05.226] Timer tick, asking Buildbucket for the build status
[05:09:05.331] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:05.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:33:0) after 1m0s
[05:10:05.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:33:0)
[05:10:05.440] Timer tick, asking Buildbucket for the build status
[05:10:05.652] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:05.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:34:0) after 1m0s
[05:11:05.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:34:0)
[05:11:05.695] Timer tick, asking Buildbucket for the build status
[05:11:06.006] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:06.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:35:0) after 1m0s
[05:12:06.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:35:0)
[05:12:06.045] Timer tick, asking Buildbucket for the build status
[05:12:06.203] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:36:0) after 1m0s
[05:13:06.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:36:0)
[05:13:06.364] Timer tick, asking Buildbucket for the build status
[05:13:06.520] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:06.520] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:37:0) after 1m0s
[05:14:06.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:37:0)
[05:14:06.553] Timer tick, asking Buildbucket for the build status
[05:14:06.948] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:06.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:38:0) after 1m0s
[05:15:06.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:38:0)
[05:15:06.956] Timer tick, asking Buildbucket for the build status
[05:15:07.175] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:07.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:39:0) after 1m0s
[05:16:07.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:39:0)
[05:16:07.305] Timer tick, asking Buildbucket for the build status
[05:16:07.878] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:07.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:40:0) after 1m0s
[05:17:07.943] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:40:0)
[05:17:07.943] Timer tick, asking Buildbucket for the build status
[05:17:08.282] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:08.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:41:0) after 1m0s
[05:18:08.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:41:0)
[05:18:08.376] Timer tick, asking Buildbucket for the build status
[05:18:08.489] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:08.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:42:0) after 1m0s
[05:19:08.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:42:0)
[05:19:08.630] Timer tick, asking Buildbucket for the build status
[05:19:08.974] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:08.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:43:0) after 1m0s
[05:20:09.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:43:0)
[05:20:09.017] Timer tick, asking Buildbucket for the build status
[05:20:09.349] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:09.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:44:0) after 1m0s
[05:21:09.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:44:0)
[05:21:09.554] Timer tick, asking Buildbucket for the build status
[05:21:09.831] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:09.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:45:0) after 1m0s
[05:22:10.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:45:0)
[05:22:10.103] Timer tick, asking Buildbucket for the build status
[05:22:10.309] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:10.309] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:46:0) after 1m0s
[05:23:10.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:46:0)
[05:23:10.444] Timer tick, asking Buildbucket for the build status
[05:23:11.752] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:47:0) after 1m0s
[05:24:11.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:47:0)
[05:24:11.789] Timer tick, asking Buildbucket for the build status
[05:24:12.162] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:12.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:48:0) after 1m0s
[05:25:12.236] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:48:0)
[05:25:12.236] Timer tick, asking Buildbucket for the build status
[05:25:12.657] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:12.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:49:0) after 1m0s
[05:26:12.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:49:0)
[05:26:12.639] Timer tick, asking Buildbucket for the build status
[05:26:12.913] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:12.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:50:0) after 1m0s
[05:27:12.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:50:0)
[05:27:12.971] Timer tick, asking Buildbucket for the build status
[05:27:13.236] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:13.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:51:0) after 1m0s
[05:28:13.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:51:0)
[05:28:13.264] Timer tick, asking Buildbucket for the build status
[05:28:13.462] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:13.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:52:0) after 1m0s
[05:29:13.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:52:0)
[05:29:13.585] Timer tick, asking Buildbucket for the build status
[05:29:13.896] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:13.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:53:0) after 1m0s
[05:30:13.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:53:0)
[05:30:13.936] Timer tick, asking Buildbucket for the build status
[05:30:14.098] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:14.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:54:0) after 1m0s
[05:31:14.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:54:0)
[05:31:14.183] Timer tick, asking Buildbucket for the build status
[05:31:14.368] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:14.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:55:0) after 1m0s
[05:32:14.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:55:0)
[05:32:14.448] Timer tick, asking Buildbucket for the build status
[05:32:14.710] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:14.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:56:0) after 1m0s
[05:33:14.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:56:0)
[05:33:14.747] Timer tick, asking Buildbucket for the build status
[05:33:14.983] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:14.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:57:0) after 1m0s
[05:34:15.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:57:0)
[05:34:15.051] Timer tick, asking Buildbucket for the build status
[05:34:15.185] Build 8882800492446775968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:15.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9048230554695435856:58:0) after 1m0s
[05:34:29.326] Received PubSub notification, asking Buildbucket for the build status
[05:34:29.509] Build 8882800492446775968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:34:29.509] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1587101667335840",
"created_by": "project:emscripten-releases",
"created_ts": "1587098334800679",
"id": "8882800492446775968",
"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\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ae5ed529bb4447641897ad86487f425fd4805b5\", \"title\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}}",
"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\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ae5ed529bb4447641897ad86487f425fd4805b5\", \"title\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-2-40j4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ae5ed529bb4447641897ad86487f425fd4805b5\"}, \"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\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-2-40j4\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"4985-2a273fe\"], \"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": "1587098388938115",
"status": "COMPLETED",
"status_changed_ts": "1587101667695654",
"tags": [
"builder:linux",
"buildset:commit/git/9ae5ed529bb4447641897ad86487f425fd4805b5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ae5ed529bb4447641897ad86487f425fd4805b5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9048230554695435856",
"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/8882800492446775968/+/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:4b9f404be7d0fe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1587101667712287",
"url": "https://ci.chromium.org/b/8882800492446775968",
"utcnow_ts": "1587101669499136"
}
}
[05:34:29.509] Invocation finished in 55m36.237046816s with status SUCCEEDED