[02:50:53.309] New invocation is queued and will start shortly
[02:50:54.534] Starting the invocation (attempt 1)
[02:50:54.571] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:50:54.573] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:50:54.573] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9042076755732832608",
"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\":\"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a9a3d8b41e200ac1f1bed42083da5e28f9686898\",\"title\":\"a9a3d8b41e200ac1f1bed42083da5e28f9686898\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9042076755732832608",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"gitiles_ref:refs/heads/master"
]
}
[02:50:55.977] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1592967054749854",
"id": "8876646693557961040",
"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\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"title\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1592967055616339",
"tags": [
"builder:linux",
"buildset:commit/git/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042076755732832608",
"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/8876646693557961040/+/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": "1592967055616565",
"url": "https://ci.chromium.org/b/8876646693557961040",
"utcnow_ts": "1592967055955041"
}
}
[02:50:55.977] Task URL: https://ci.chromium.org/b/8876646693557961040
[02:50:55.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:2:0) after 1m0s
[02:51:56.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:2:0)
[02:51:56.225] Timer tick, asking Buildbucket for the build status
[02:51:56.369] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:56.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:3:0) after 1m0s
[02:52:56.665] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:3:0)
[02:52:56.665] Timer tick, asking Buildbucket for the build status
[02:52:56.806] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:56.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:4:0) after 1m0s
[02:53:56.993] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:4:0)
[02:53:56.993] Timer tick, asking Buildbucket for the build status
[02:53:58.594] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:58.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:5:0) after 1m0s
[02:54:58.636] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:5:0)
[02:54:58.636] Timer tick, asking Buildbucket for the build status
[02:54:58.764] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:58.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:6:0) after 1m0s
[02:55:58.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:6:0)
[02:55:58.782] Timer tick, asking Buildbucket for the build status
[02:55:59.016] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:59.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:7:0) after 1m0s
[02:56:59.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:7:0)
[02:56:59.052] Timer tick, asking Buildbucket for the build status
[02:56:59.236] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:59.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:8:0) after 1m0s
[02:57:59.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:8:0)
[02:57:59.293] Timer tick, asking Buildbucket for the build status
[02:57:59.755] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:59.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:9:0) after 1m0s
[02:58:59.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:9:0)
[02:58:59.778] Timer tick, asking Buildbucket for the build status
[02:59:00.502] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:00.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:10:0) after 1m0s
[03:00:01.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:10:0)
[03:00:01.836] Timer tick, asking Buildbucket for the build status
[03:00:01.952] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:01.952] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:11:0) after 1m0s
[03:01:02.124] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:11:0)
[03:01:02.124] Timer tick, asking Buildbucket for the build status
[03:01:02.234] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:02.234] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:12:0) after 1m0s
[03:02:02.737] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:12:0)
[03:02:02.737] Timer tick, asking Buildbucket for the build status
[03:02:03.044] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:03.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:13:0) after 1m0s
[03:03:03.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:13:0)
[03:03:03.112] Timer tick, asking Buildbucket for the build status
[03:03:03.849] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:03.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:14:0) after 1m0s
[03:04:03.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:14:0)
[03:04:03.875] Timer tick, asking Buildbucket for the build status
[03:04:04.047] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:04.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:15:0) after 1m0s
[03:05:04.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:15:0)
[03:05:04.316] Timer tick, asking Buildbucket for the build status
[03:05:04.446] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:04.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:16:0) after 1m0s
[03:06:04.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:16:0)
[03:06:04.685] Timer tick, asking Buildbucket for the build status
[03:06:04.818] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:04.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:17:0) after 1m0s
[03:07:04.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:17:0)
[03:07:04.998] Timer tick, asking Buildbucket for the build status
[03:07:05.133] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:05.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:18:0) after 1m0s
[03:08:05.244] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:18:0)
[03:08:05.244] Timer tick, asking Buildbucket for the build status
[03:08:05.602] Build 8876646693557961040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:05.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:19:0) after 1m0s
[03:08:30.743] Received PubSub notification, asking Buildbucket for the build status
[03:08:33.472] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:06.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:19:0)
[03:09:06.712] Timer tick, asking Buildbucket for the build status
[03:09:07.551] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:21:0) after 1m0s
[03:10:08.163] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:21:0)
[03:10:08.163] Timer tick, asking Buildbucket for the build status
[03:10:08.301] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:08.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:22:0) after 1m0s
[03:11:08.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:22:0)
[03:11:08.761] Timer tick, asking Buildbucket for the build status
[03:11:09.213] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:09.213] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:23:0) after 1m0s
[03:12:09.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:23:0)
[03:12:09.512] Timer tick, asking Buildbucket for the build status
[03:12:09.670] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:09.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:24:0) after 1m0s
[03:13:09.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:24:0)
[03:13:09.671] Timer tick, asking Buildbucket for the build status
[03:13:09.894] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:09.894] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:25:0) after 1m0s
[03:14:10.081] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:25:0)
[03:14:10.081] Timer tick, asking Buildbucket for the build status
[03:14:10.239] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:10.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:26:0) after 1m0s
[03:15:10.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:26:0)
[03:15:10.293] Timer tick, asking Buildbucket for the build status
[03:15:10.459] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:10.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:27:0) after 1m0s
[03:16:10.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:27:0)
[03:16:10.592] Timer tick, asking Buildbucket for the build status
[03:16:10.829] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:28:0) after 1m0s
[03:17:11.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:28:0)
[03:17:11.026] Timer tick, asking Buildbucket for the build status
[03:17:12.372] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:12.372] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:29:0) after 1m0s
[03:18:12.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:29:0)
[03:18:12.452] Timer tick, asking Buildbucket for the build status
[03:18:12.595] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:12.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:30:0) after 1m0s
[03:19:13.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:30:0)
[03:19:13.172] Timer tick, asking Buildbucket for the build status
[03:19:13.366] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:13.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:31:0) after 1m0s
[03:20:14.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:31:0)
[03:20:14.417] Timer tick, asking Buildbucket for the build status
[03:20:15.072] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:15.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:32:0) after 1m0s
[03:21:15.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:32:0)
[03:21:15.222] Timer tick, asking Buildbucket for the build status
[03:21:15.508] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:15.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:33:0) after 1m0s
[03:22:15.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:33:0)
[03:22:15.716] Timer tick, asking Buildbucket for the build status
[03:22:15.861] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:15.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:34:0) after 1m0s
[03:23:15.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:34:0)
[03:23:15.904] Timer tick, asking Buildbucket for the build status
[03:23:15.993] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:35:0) after 1m0s
[03:24:16.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:35:0)
[03:24:16.006] Timer tick, asking Buildbucket for the build status
[03:24:16.153] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:16.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:36:0) after 1m0s
[03:25:16.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:36:0)
[03:25:16.258] Timer tick, asking Buildbucket for the build status
[03:25:16.537] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:16.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:37:0) after 1m0s
[03:26:16.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:37:0)
[03:26:16.531] Timer tick, asking Buildbucket for the build status
[03:26:17.034] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:17.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:38:0) after 1m0s
[03:27:17.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:38:0)
[03:27:17.154] Timer tick, asking Buildbucket for the build status
[03:27:17.242] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:17.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:39:0) after 1m0s
[03:28:17.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:39:0)
[03:28:17.292] Timer tick, asking Buildbucket for the build status
[03:28:18.058] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:18.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:40:0) after 1m0s
[03:29:18.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:40:0)
[03:29:18.136] Timer tick, asking Buildbucket for the build status
[03:29:18.325] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:18.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:41:0) after 1m0s
[03:30:18.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:41:0)
[03:30:18.313] Timer tick, asking Buildbucket for the build status
[03:30:18.942] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:18.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:42:0) after 1m0s
[03:31:19.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:42:0)
[03:31:19.221] Timer tick, asking Buildbucket for the build status
[03:31:19.359] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:19.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:43:0) after 1m0s
[03:32:19.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:43:0)
[03:32:19.547] Timer tick, asking Buildbucket for the build status
[03:32:19.619] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:19.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:44:0) after 1m0s
[03:33:19.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:44:0)
[03:33:19.855] Timer tick, asking Buildbucket for the build status
[03:33:20.004] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:20.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:45:0) after 1m0s
[03:34:20.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:45:0)
[03:34:20.105] Timer tick, asking Buildbucket for the build status
[03:34:20.199] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:20.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:46:0) after 1m0s
[03:35:21.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:46:0)
[03:35:21.178] Timer tick, asking Buildbucket for the build status
[03:35:21.452] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:21.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:47:0) after 1m0s
[03:36:21.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:47:0)
[03:36:21.678] Timer tick, asking Buildbucket for the build status
[03:36:21.767] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:21.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:48:0) after 1m0s
[03:37:21.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:48:0)
[03:37:21.969] Timer tick, asking Buildbucket for the build status
[03:37:22.076] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:49:0) after 1m0s
[03:38:22.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:49:0)
[03:38:22.563] Timer tick, asking Buildbucket for the build status
[03:38:22.661] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:22.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:50:0) after 1m0s
[03:39:22.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:50:0)
[03:39:22.887] Timer tick, asking Buildbucket for the build status
[03:39:23.062] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:23.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:51:0) after 1m0s
[03:40:23.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:51:0)
[03:40:23.417] Timer tick, asking Buildbucket for the build status
[03:40:23.588] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:23.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:52:0) after 1m0s
[03:41:23.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:52:0)
[03:41:23.757] Timer tick, asking Buildbucket for the build status
[03:41:23.988] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:23.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:53:0) after 1m0s
[03:42:24.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:53:0)
[03:42:24.304] Timer tick, asking Buildbucket for the build status
[03:42:24.368] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:24.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:54:0) after 1m0s
[03:43:24.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:54:0)
[03:43:24.448] Timer tick, asking Buildbucket for the build status
[03:43:24.931] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:24.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:55:0) after 1m0s
[03:44:24.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:55:0)
[03:44:24.979] Timer tick, asking Buildbucket for the build status
[03:44:25.119] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:25.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:56:0) after 1m0s
[03:45:25.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:56:0)
[03:45:25.415] Timer tick, asking Buildbucket for the build status
[03:45:25.488] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:25.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:57:0) after 1m0s
[03:46:25.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:57:0)
[03:46:25.768] Timer tick, asking Buildbucket for the build status
[03:46:25.900] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:58:0) after 1m0s
[03:47:26.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:58:0)
[03:47:26.035] Timer tick, asking Buildbucket for the build status
[03:47:26.107] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:26.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:59:0) after 1m0s
[03:48:26.232] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:59:0)
[03:48:26.232] Timer tick, asking Buildbucket for the build status
[03:48:26.453] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:26.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:60:0) after 1m0s
[03:49:26.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:60:0)
[03:49:26.511] Timer tick, asking Buildbucket for the build status
[03:49:26.693] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:26.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:61:0) after 1m0s
[03:50:26.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:61:0)
[03:50:26.833] Timer tick, asking Buildbucket for the build status
[03:50:27.124] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:62:0) after 1m0s
[03:51:27.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:62:0)
[03:51:27.335] Timer tick, asking Buildbucket for the build status
[03:51:27.448] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:27.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:63:0) after 1m0s
[03:52:27.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:63:0)
[03:52:27.627] Timer tick, asking Buildbucket for the build status
[03:52:27.748] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:27.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:64:0) after 1m0s
[03:53:28.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:64:0)
[03:53:28.029] Timer tick, asking Buildbucket for the build status
[03:53:28.430] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:28.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:65:0) after 1m0s
[03:54:28.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:65:0)
[03:54:28.649] Timer tick, asking Buildbucket for the build status
[03:54:28.837] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:28.837] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:66:0) after 1m0s
[03:55:29.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:66:0)
[03:55:29.125] Timer tick, asking Buildbucket for the build status
[03:55:29.325] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:29.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:67:0) after 1m0s
[03:56:29.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:67:0)
[03:56:29.380] Timer tick, asking Buildbucket for the build status
[03:56:29.569] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:29.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:68:0) after 1m0s
[03:57:29.718] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:68:0)
[03:57:29.718] Timer tick, asking Buildbucket for the build status
[03:57:29.845] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:29.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:69:0) after 1m0s
[03:58:29.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:69:0)
[03:58:29.896] Timer tick, asking Buildbucket for the build status
[03:58:29.973] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:29.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:70:0) after 1m0s
[03:59:30.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:70:0)
[03:59:30.139] Timer tick, asking Buildbucket for the build status
[03:59:30.552] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:30.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:71:0) after 1m0s
[04:00:30.780] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:71:0)
[04:00:30.780] Timer tick, asking Buildbucket for the build status
[04:00:31.057] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:31.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:72:0) after 1m0s
[04:01:31.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:72:0)
[04:01:31.133] Timer tick, asking Buildbucket for the build status
[04:01:31.469] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:31.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:73:0) after 1m0s
[04:02:31.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:73:0)
[04:02:31.554] Timer tick, asking Buildbucket for the build status
[04:02:31.827] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:31.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:74:0) after 1m0s
[04:03:32.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:74:0)
[04:03:32.014] Timer tick, asking Buildbucket for the build status
[04:03:32.611] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:32.611] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:75:0) after 1m0s
[04:04:32.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:75:0)
[04:04:32.739] Timer tick, asking Buildbucket for the build status
[04:04:32.809] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:32.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:76:0) after 1m0s
[04:05:32.877] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:76:0)
[04:05:32.877] Timer tick, asking Buildbucket for the build status
[04:05:33.044] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:33.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:77:0) after 1m0s
[04:06:33.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:77:0)
[04:06:33.118] Timer tick, asking Buildbucket for the build status
[04:06:33.279] Build 8876646693557961040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:33.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9042076755732832608:78:0) after 1m0s
[04:06:34.622] Received PubSub notification, asking Buildbucket for the build status
[04:06:34.726] Build 8876646693557961040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:06:34.726] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1592971593859849",
"created_by": "project:emscripten-releases",
"created_ts": "1592967054749854",
"id": "8876646693557961040",
"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\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"title\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"title\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-2-gdmo\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a9a3d8b41e200ac1f1bed42083da5e28f9686898\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_e437bc7da551d62da6a64b09ca05a66344db3b0846e125cb83052108c473fa36_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-gdmo\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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\": [\"5218-123b763\"], \"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": "1592968109719582",
"status": "COMPLETED",
"status_changed_ts": "1592971594243971",
"tags": [
"builder:linux",
"buildset:commit/git/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a9a3d8b41e200ac1f1bed42083da5e28f9686898",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042076755732832608",
"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/8876646693557961040/+/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:4cfd0f52776f7b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1592971594274634",
"url": "https://ci.chromium.org/b/8876646693557961040",
"utcnow_ts": "1592971594715104"
}
}
[04:06:34.726] Invocation finished in 1h15m41.435015675s with status SUCCEEDED