[08:34:04.180] New invocation is queued and will start shortly
[08:34:06.136] Starting the invocation (attempt 1)
[08:34:06.206] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:34:06.206] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:34:06.206] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9065519778942857616",
"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\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\",\"title\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9065519778942857616",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master"
]
}
[08:34:07.072] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1570610046365993",
"id": "8900089715980650192",
"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\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570610046809644",
"tags": [
"builder:linux",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065519778942857616",
"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/8900089715980650192/+/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": "1570610046809773",
"url": "https://ci.chromium.org/b/8900089715980650192",
"utcnow_ts": "1570610047046612"
}
}
[08:34:07.072] Task URL: https://ci.chromium.org/b/8900089715980650192
[08:34:07.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:2:0) after 1m0s
[08:34:29.413] Received PubSub notification, asking Buildbucket for the build status
[08:34:29.702] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:07.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:2:0)
[08:35:07.199] Timer tick, asking Buildbucket for the build status
[08:35:07.311] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:07.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:4:0) after 1m0s
[08:36:07.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:4:0)
[08:36:07.337] Timer tick, asking Buildbucket for the build status
[08:36:08.379] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:08.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:5:0) after 1m0s
[08:37:08.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:5:0)
[08:37:08.456] Timer tick, asking Buildbucket for the build status
[08:37:08.734] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:08.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:6:0) after 1m0s
[08:38:08.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:6:0)
[08:38:08.825] Timer tick, asking Buildbucket for the build status
[08:38:08.988] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:08.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:7:0) after 1m0s
[08:39:09.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:7:0)
[08:39:09.076] Timer tick, asking Buildbucket for the build status
[08:39:09.218] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:09.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:8:0) after 1m0s
[08:40:09.315] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:8:0)
[08:40:09.315] Timer tick, asking Buildbucket for the build status
[08:40:09.505] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:09.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:9:0) after 1m0s
[08:41:09.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:9:0)
[08:41:09.592] Timer tick, asking Buildbucket for the build status
[08:41:09.893] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:09.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:10:0) after 1m0s
[08:42:09.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:10:0)
[08:42:09.928] Timer tick, asking Buildbucket for the build status
[08:42:10.197] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:10.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:11:0) after 1m0s
[08:43:10.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:11:0)
[08:43:10.250] Timer tick, asking Buildbucket for the build status
[08:43:10.417] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:10.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:12:0) after 1m0s
[08:44:10.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:12:0)
[08:44:10.447] Timer tick, asking Buildbucket for the build status
[08:44:10.690] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:10.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:13:0) after 1m0s
[08:45:10.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:13:0)
[08:45:10.794] Timer tick, asking Buildbucket for the build status
[08:45:11.135] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:11.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:14:0) after 1m0s
[08:46:11.161] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:14:0)
[08:46:11.161] Timer tick, asking Buildbucket for the build status
[08:46:11.635] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:11.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:15:0) after 1m0s
[08:47:11.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:15:0)
[08:47:11.669] Timer tick, asking Buildbucket for the build status
[08:47:12.042] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:12.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:16:0) after 1m0s
[08:48:12.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:16:0)
[08:48:12.114] Timer tick, asking Buildbucket for the build status
[08:48:13.216] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:13.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:17:0) after 1m0s
[08:49:13.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:17:0)
[08:49:13.273] Timer tick, asking Buildbucket for the build status
[08:49:13.425] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:13.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:18:0) after 1m0s
[08:50:13.496] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:18:0)
[08:50:13.496] Timer tick, asking Buildbucket for the build status
[08:50:14.123] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:19:0) after 1m0s
[08:51:14.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:19:0)
[08:51:14.126] Timer tick, asking Buildbucket for the build status
[08:51:14.304] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:20:0) after 1m0s
[08:52:14.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:20:0)
[08:52:14.355] Timer tick, asking Buildbucket for the build status
[08:52:14.497] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:14.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:21:0) after 1m0s
[08:53:14.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:21:0)
[08:53:14.524] Timer tick, asking Buildbucket for the build status
[08:53:14.667] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:14.667] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:22:0) after 1m0s
[08:54:14.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:22:0)
[08:54:14.692] Timer tick, asking Buildbucket for the build status
[08:54:15.076] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:23:0) after 1m0s
[08:55:15.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:23:0)
[08:55:15.115] Timer tick, asking Buildbucket for the build status
[08:55:15.226] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:15.226] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:24:0) after 1m0s
[08:56:15.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:24:0)
[08:56:15.300] Timer tick, asking Buildbucket for the build status
[08:56:15.487] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:25:0) after 1m0s
[08:57:15.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:25:0)
[08:57:15.508] Timer tick, asking Buildbucket for the build status
[08:57:15.712] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:15.712] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:26:0) after 1m0s
[08:58:15.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:26:0)
[08:58:15.779] Timer tick, asking Buildbucket for the build status
[08:58:15.934] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:15.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:27:0) after 1m0s
[08:59:15.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:27:0)
[08:59:15.947] Timer tick, asking Buildbucket for the build status
[08:59:16.081] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:28:0) after 1m0s
[09:00:16.252] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:28:0)
[09:00:16.252] Timer tick, asking Buildbucket for the build status
[09:00:17.130] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:17.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:29:0) after 1m0s
[09:01:17.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:29:0)
[09:01:17.129] Timer tick, asking Buildbucket for the build status
[09:01:18.438] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:30:0) after 1m0s
[09:02:18.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:30:0)
[09:02:18.529] Timer tick, asking Buildbucket for the build status
[09:02:18.824] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:31:0) after 1m0s
[09:03:18.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:31:0)
[09:03:18.925] Timer tick, asking Buildbucket for the build status
[09:03:18.997] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:32:0) after 1m0s
[09:04:18.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:32:0)
[09:04:18.977] Timer tick, asking Buildbucket for the build status
[09:04:19.499] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:19.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:33:0) after 1m0s
[09:05:19.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:33:0)
[09:05:19.619] Timer tick, asking Buildbucket for the build status
[09:05:19.747] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:19.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:34:0) after 1m0s
[09:06:19.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:34:0)
[09:06:19.871] Timer tick, asking Buildbucket for the build status
[09:06:20.058] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:20.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:35:0) after 1m0s
[09:07:20.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:35:0)
[09:07:20.200] Timer tick, asking Buildbucket for the build status
[09:07:20.618] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:20.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:36:0) after 1m0s
[09:08:20.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:36:0)
[09:08:20.699] Timer tick, asking Buildbucket for the build status
[09:08:20.880] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:20.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:37:0) after 1m0s
[09:09:20.993] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:37:0)
[09:09:20.993] Timer tick, asking Buildbucket for the build status
[09:09:21.260] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:21.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:38:0) after 1m0s
[09:10:21.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:38:0)
[09:10:21.249] Timer tick, asking Buildbucket for the build status
[09:10:21.458] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:21.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:39:0) after 1m0s
[09:11:21.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:39:0)
[09:11:21.482] Timer tick, asking Buildbucket for the build status
[09:11:21.875] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:21.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:40:0) after 1m0s
[09:12:21.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:40:0)
[09:12:21.897] Timer tick, asking Buildbucket for the build status
[09:12:22.310] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:22.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:41:0) after 1m0s
[09:13:22.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:41:0)
[09:13:22.392] Timer tick, asking Buildbucket for the build status
[09:13:23.366] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:23.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:42:0) after 1m0s
[09:14:23.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:42:0)
[09:14:23.442] Timer tick, asking Buildbucket for the build status
[09:14:23.533] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:23.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:43:0) after 1m0s
[09:15:23.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:43:0)
[09:15:23.604] Timer tick, asking Buildbucket for the build status
[09:15:23.832] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:23.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:44:0) after 1m0s
[09:16:23.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:44:0)
[09:16:23.960] Timer tick, asking Buildbucket for the build status
[09:16:24.062] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:24.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:45:0) after 1m0s
[09:17:24.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:45:0)
[09:17:24.211] Timer tick, asking Buildbucket for the build status
[09:17:24.458] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:24.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:46:0) after 1m0s
[09:18:24.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:46:0)
[09:18:24.568] Timer tick, asking Buildbucket for the build status
[09:18:25.469] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:25.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:47:0) after 1m0s
[09:19:25.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:47:0)
[09:19:25.573] Timer tick, asking Buildbucket for the build status
[09:19:25.883] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:25.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:48:0) after 1m0s
[09:20:26.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:48:0)
[09:20:26.076] Timer tick, asking Buildbucket for the build status
[09:20:26.423] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:26.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:49:0) after 1m0s
[09:21:26.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:49:0)
[09:21:26.518] Timer tick, asking Buildbucket for the build status
[09:21:26.672] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:26.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:50:0) after 1m0s
[09:22:26.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:50:0)
[09:22:26.731] Timer tick, asking Buildbucket for the build status
[09:22:26.893] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:26.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:51:0) after 1m0s
[09:23:26.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:51:0)
[09:23:26.994] Timer tick, asking Buildbucket for the build status
[09:23:27.154] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:27.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:52:0) after 1m0s
[09:24:27.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:52:0)
[09:24:27.273] Timer tick, asking Buildbucket for the build status
[09:24:27.414] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:27.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:53:0) after 1m0s
[09:25:27.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:53:0)
[09:25:27.437] Timer tick, asking Buildbucket for the build status
[09:25:27.510] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:27.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:54:0) after 1m0s
[09:26:27.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:54:0)
[09:26:27.573] Timer tick, asking Buildbucket for the build status
[09:26:29.040] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:29.040] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:55:0) after 1m0s
[09:27:29.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:55:0)
[09:27:29.390] Timer tick, asking Buildbucket for the build status
[09:27:29.618] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:29.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:56:0) after 1m0s
[09:28:29.641] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:56:0)
[09:28:29.641] Timer tick, asking Buildbucket for the build status
[09:28:29.777] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:29.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:57:0) after 1m0s
[09:29:29.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:57:0)
[09:29:29.828] Timer tick, asking Buildbucket for the build status
[09:29:30.183] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:30.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:58:0) after 1m0s
[09:30:30.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:58:0)
[09:30:30.286] Timer tick, asking Buildbucket for the build status
[09:30:30.477] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:30.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:59:0) after 1m0s
[09:31:30.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:59:0)
[09:31:30.521] Timer tick, asking Buildbucket for the build status
[09:31:30.775] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:30.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:60:0) after 1m0s
[09:32:30.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:60:0)
[09:32:30.960] Timer tick, asking Buildbucket for the build status
[09:32:32.024] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:32.024] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:61:0) after 1m0s
[09:33:32.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:61:0)
[09:33:32.051] Timer tick, asking Buildbucket for the build status
[09:33:32.366] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:32.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:62:0) after 1m0s
[09:34:32.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:62:0)
[09:34:32.553] Timer tick, asking Buildbucket for the build status
[09:34:32.840] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:32.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:63:0) after 1m0s
[09:35:33.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:63:0)
[09:35:33.065] Timer tick, asking Buildbucket for the build status
[09:35:33.200] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:33.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:64:0) after 1m0s
[09:36:33.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:64:0)
[09:36:33.245] Timer tick, asking Buildbucket for the build status
[09:36:33.362] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:33.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:65:0) after 1m0s
[09:37:33.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:65:0)
[09:37:33.427] Timer tick, asking Buildbucket for the build status
[09:37:33.637] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:33.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:66:0) after 1m0s
[09:38:33.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:66:0)
[09:38:33.748] Timer tick, asking Buildbucket for the build status
[09:38:33.914] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:33.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:67:0) after 1m0s
[09:39:33.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:67:0)
[09:39:33.945] Timer tick, asking Buildbucket for the build status
[09:39:34.792] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:34.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:68:0) after 1m0s
[09:40:34.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:68:0)
[09:40:34.888] Timer tick, asking Buildbucket for the build status
[09:40:34.961] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:34.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:69:0) after 1m0s
[09:41:35.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:69:0)
[09:41:35.035] Timer tick, asking Buildbucket for the build status
[09:41:35.352] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:35.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:70:0) after 1m0s
[09:42:35.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:70:0)
[09:42:35.487] Timer tick, asking Buildbucket for the build status
[09:42:35.984] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:35.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:71:0) after 1m0s
[09:43:36.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:71:0)
[09:43:36.176] Timer tick, asking Buildbucket for the build status
[09:43:36.285] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:36.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:72:0) after 1m0s
[09:44:36.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:72:0)
[09:44:36.427] Timer tick, asking Buildbucket for the build status
[09:44:36.712] Build 8900089715980650192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:36.712] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9065519778942857616:73:0) after 1m0s
[09:45:24.239] Received PubSub notification, asking Buildbucket for the build status
[09:45:24.327] Build 8900089715980650192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:45:24.328] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1570614323220889",
"created_by": "project:emscripten-releases",
"created_ts": "1570610046365993",
"id": "8900089715980650192",
"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\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"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\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-wsry\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"swarming\": {\"bot_dimensions\": {\"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-wsry\"], \"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\": [\"4494-2b7cf0c\"], \"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": "1570610067486661",
"status": "COMPLETED",
"status_changed_ts": "1570614323750856",
"tags": [
"builder:linux",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065519778942857616",
"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/8900089715980650192/+/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:47c87930db1ed210",
"user_agent:luci-scheduler"
],
"updated_ts": "1570614323810768",
"url": "https://ci.chromium.org/b/8900089715980650192",
"utcnow_ts": "1570614324319527"
}
}
[09:45:24.328] Invocation finished in 1h11m20.170110067s with status SUCCEEDED