[02:18:58.575] New invocation is queued and will start shortly
[02:18:59.826] Starting the invocation (attempt 1)
[02:18:59.862] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:18:59.862] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:18:59.862] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9059020396193656592",
"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\":\"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\",\"title\":\"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9059020396193656592",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"gitiles_ref:refs/heads/master"
]
}
[02:19:01.899] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1576808340117500",
"id": "8893590333911227472",
"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\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"title\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576808341350790",
"tags": [
"builder:linux",
"buildset:commit/git/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059020396193656592",
"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/8893590333911227472/+/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": "1576808341351720",
"url": "https://ci.chromium.org/b/8893590333911227472",
"utcnow_ts": "1576808341870332"
}
}
[02:19:01.899] Task URL: https://ci.chromium.org/b/8893590333911227472
[02:19:01.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:2:0) after 1m0s
[02:20:01.824] Received PubSub notification, asking Buildbucket for the build status
[02:20:01.958] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:02.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:2:0)
[02:20:02.180] Timer tick, asking Buildbucket for the build status
[02:20:02.407] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:02.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:4:0) after 1m0s
[02:21:02.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:4:0)
[02:21:02.481] Timer tick, asking Buildbucket for the build status
[02:21:02.897] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:02.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:5:0) after 1m0s
[02:22:03.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:5:0)
[02:22:03.040] Timer tick, asking Buildbucket for the build status
[02:22:03.348] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:03.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:6:0) after 1m0s
[02:23:03.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:6:0)
[02:23:03.648] Timer tick, asking Buildbucket for the build status
[02:23:03.882] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:03.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:7:0) after 1m0s
[02:24:04.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:7:0)
[02:24:04.138] Timer tick, asking Buildbucket for the build status
[02:24:04.505] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:04.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:8:0) after 1m0s
[02:25:04.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:8:0)
[02:25:04.583] Timer tick, asking Buildbucket for the build status
[02:25:04.740] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:04.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:9:0) after 1m0s
[02:26:05.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:9:0)
[02:26:05.006] Timer tick, asking Buildbucket for the build status
[02:26:05.434] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:05.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:10:0) after 1m0s
[02:27:05.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:10:0)
[02:27:05.527] Timer tick, asking Buildbucket for the build status
[02:27:05.625] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:05.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:11:0) after 1m0s
[02:28:06.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:11:0)
[02:28:06.089] Timer tick, asking Buildbucket for the build status
[02:28:06.632] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:06.632] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:12:0) after 1m0s
[02:29:06.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:12:0)
[02:29:06.662] Timer tick, asking Buildbucket for the build status
[02:29:07.418] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:07.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:13:0) after 1m0s
[02:30:07.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:13:0)
[02:30:07.688] Timer tick, asking Buildbucket for the build status
[02:30:08.617] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:08.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:14:0) after 1m0s
[02:31:08.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:14:0)
[02:31:08.658] Timer tick, asking Buildbucket for the build status
[02:31:08.949] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:08.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:15:0) after 1m0s
[02:32:09.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:15:0)
[02:32:09.154] Timer tick, asking Buildbucket for the build status
[02:32:09.303] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:09.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:16:0) after 1m0s
[02:33:09.502] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:16:0)
[02:33:09.502] Timer tick, asking Buildbucket for the build status
[02:33:10.554] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:10.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:17:0) after 1m0s
[02:34:10.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:17:0)
[02:34:10.765] Timer tick, asking Buildbucket for the build status
[02:34:11.054] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:11.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:18:0) after 1m0s
[02:35:11.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:18:0)
[02:35:11.242] Timer tick, asking Buildbucket for the build status
[02:35:11.682] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:11.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:19:0) after 1m0s
[02:36:11.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:19:0)
[02:36:11.712] Timer tick, asking Buildbucket for the build status
[02:36:11.854] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:11.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:20:0) after 1m0s
[02:37:12.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:20:0)
[02:37:12.001] Timer tick, asking Buildbucket for the build status
[02:37:12.576] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:12.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:21:0) after 1m0s
[02:38:12.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:21:0)
[02:38:12.663] Timer tick, asking Buildbucket for the build status
[02:38:13.248] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:13.248] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:22:0) after 1m0s
[02:39:13.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:22:0)
[02:39:13.247] Timer tick, asking Buildbucket for the build status
[02:39:13.903] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:13.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:23:0) after 1m0s
[02:40:13.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:23:0)
[02:40:13.985] Timer tick, asking Buildbucket for the build status
[02:40:14.232] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:14.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:24:0) after 1m0s
[02:41:14.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:24:0)
[02:41:14.289] Timer tick, asking Buildbucket for the build status
[02:41:14.479] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:14.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:25:0) after 1m0s
[02:42:14.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:25:0)
[02:42:14.556] Timer tick, asking Buildbucket for the build status
[02:42:14.902] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:14.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:26:0) after 1m0s
[02:43:14.989] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:26:0)
[02:43:14.989] Timer tick, asking Buildbucket for the build status
[02:43:15.185] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:15.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:27:0) after 1m0s
[02:44:15.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:27:0)
[02:44:15.279] Timer tick, asking Buildbucket for the build status
[02:44:15.693] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:15.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:28:0) after 1m0s
[02:45:15.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:28:0)
[02:45:15.747] Timer tick, asking Buildbucket for the build status
[02:45:16.345] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:16.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:29:0) after 1m0s
[02:46:16.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:29:0)
[02:46:16.401] Timer tick, asking Buildbucket for the build status
[02:46:17.001] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:17.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:30:0) after 1m0s
[02:47:17.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:30:0)
[02:47:17.192] Timer tick, asking Buildbucket for the build status
[02:47:17.557] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:17.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:31:0) after 1m0s
[02:48:17.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:31:0)
[02:48:17.603] Timer tick, asking Buildbucket for the build status
[02:48:17.853] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:17.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:32:0) after 1m0s
[02:49:17.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:32:0)
[02:49:17.973] Timer tick, asking Buildbucket for the build status
[02:49:18.679] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:18.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:33:0) after 1m0s
[02:50:18.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:33:0)
[02:50:18.744] Timer tick, asking Buildbucket for the build status
[02:50:18.934] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:18.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:34:0) after 1m0s
[02:51:18.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:34:0)
[02:51:18.982] Timer tick, asking Buildbucket for the build status
[02:51:21.800] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:21.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:35:0) after 1m0s
[02:52:21.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:35:0)
[02:52:21.894] Timer tick, asking Buildbucket for the build status
[02:52:22.294] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:22.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:36:0) after 1m0s
[02:53:22.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:36:0)
[02:53:22.440] Timer tick, asking Buildbucket for the build status
[02:53:22.570] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:22.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:37:0) after 1m0s
[02:54:22.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:37:0)
[02:54:22.601] Timer tick, asking Buildbucket for the build status
[02:54:22.805] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:22.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:38:0) after 1m0s
[02:55:22.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:38:0)
[02:55:22.787] Timer tick, asking Buildbucket for the build status
[02:55:22.916] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:22.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:39:0) after 1m0s
[02:56:23.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:39:0)
[02:56:23.014] Timer tick, asking Buildbucket for the build status
[02:56:23.527] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:23.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:40:0) after 1m0s
[02:57:23.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:40:0)
[02:57:23.553] Timer tick, asking Buildbucket for the build status
[02:57:23.970] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:23.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:41:0) after 1m0s
[02:58:24.080] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:41:0)
[02:58:24.080] Timer tick, asking Buildbucket for the build status
[02:58:24.342] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:24.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:42:0) after 1m0s
[02:59:24.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:42:0)
[02:59:24.429] Timer tick, asking Buildbucket for the build status
[02:59:24.612] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:24.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:43:0) after 1m0s
[03:00:24.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:43:0)
[03:00:24.671] Timer tick, asking Buildbucket for the build status
[03:00:26.871] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:26.871] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:44:0) after 1m0s
[03:01:26.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:44:0)
[03:01:26.856] Timer tick, asking Buildbucket for the build status
[03:01:27.023] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:27.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:45:0) after 1m0s
[03:02:27.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:45:0)
[03:02:27.132] Timer tick, asking Buildbucket for the build status
[03:02:27.415] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:27.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:46:0) after 1m0s
[03:03:27.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:46:0)
[03:03:27.438] Timer tick, asking Buildbucket for the build status
[03:03:27.580] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:27.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:47:0) after 1m0s
[03:04:27.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:47:0)
[03:04:27.594] Timer tick, asking Buildbucket for the build status
[03:04:27.876] Build 8893590333911227472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:27.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9059020396193656592:48:0) after 1m0s
[03:05:11.464] Received PubSub notification, asking Buildbucket for the build status
[03:05:11.661] Build 8893590333911227472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:05:11.662] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1576811110529294",
"created_by": "project:emscripten-releases",
"created_ts": "1576808340117500",
"id": "8893590333911227472",
"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\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"title\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}}",
"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\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"title\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-2-q8fx\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f\"}, \"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-2-q8fx\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"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": "1576808400542578",
"status": "COMPLETED",
"status_changed_ts": "1576811111082947",
"tags": [
"builder:linux",
"buildset:commit/git/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ecf9864b2db71d3d432d1c1dd2f1fa6b1bbec00f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059020396193656592",
"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/8893590333911227472/+/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:4939eb6c5a5e2010",
"user_agent:luci-scheduler"
],
"updated_ts": "1576811111118223",
"url": "https://ci.chromium.org/b/8893590333911227472",
"utcnow_ts": "1576811111642049"
}
}
[03:05:11.665] Invocation finished in 46m13.107186393s with status SUCCEEDED