[01:04:55.714] New invocation is queued and will start shortly
[01:04:56.791] Starting the invocation (attempt 1)
[01:04:56.892] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:04:56.892] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:04:56.893] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9030124622587672352",
"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\":\"2cf546c7e132505652d76b5a2fb86a72642a1d76\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2cf546c7e132505652d76b5a2fb86a72642a1d76\",\"title\":\"2cf546c7e132505652d76b5a2fb86a72642a1d76\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9030124622587672352",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"gitiles_ref:refs/heads/master"
]
}
[01:04:57.841] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1604365497052737",
"id": "8864694560521190608",
"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\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"title\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604365497575885",
"tags": [
"builder:linux",
"buildset:commit/git/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030124622587672352",
"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/8864694560521190608/+/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": "1604365497576322",
"url": "https://ci.chromium.org/b/8864694560521190608",
"utcnow_ts": "1604365497830743"
}
}
[01:04:57.841] Task URL: https://ci.chromium.org/b/8864694560521190608
[01:04:57.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:2:0) after 1m0s
[01:05:57.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:2:0)
[01:05:57.861] Timer tick, asking Buildbucket for the build status
[01:05:57.958] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:57.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:3:0) after 1m0s
[01:06:57.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:3:0)
[01:06:57.971] Timer tick, asking Buildbucket for the build status
[01:06:58.544] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:58.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:4:0) after 1m0s
[01:07:58.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:4:0)
[01:07:58.564] Timer tick, asking Buildbucket for the build status
[01:07:58.644] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:58.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:5:0) after 1m0s
[01:08:58.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:5:0)
[01:08:58.696] Timer tick, asking Buildbucket for the build status
[01:08:58.822] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:58.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:6:0) after 1m0s
[01:09:58.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:6:0)
[01:09:58.856] Timer tick, asking Buildbucket for the build status
[01:09:58.974] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:58.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:7:0) after 1m0s
[01:10:59.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:7:0)
[01:10:59.021] Timer tick, asking Buildbucket for the build status
[01:10:59.136] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:59.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:8:0) after 1m0s
[01:11:59.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:8:0)
[01:11:59.206] Timer tick, asking Buildbucket for the build status
[01:11:59.301] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:59.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:9:0) after 1m0s
[01:12:59.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:9:0)
[01:12:59.324] Timer tick, asking Buildbucket for the build status
[01:12:59.410] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:59.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:10:0) after 1m0s
[01:13:59.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:10:0)
[01:13:59.393] Timer tick, asking Buildbucket for the build status
[01:13:59.543] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:59.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:11:0) after 1m0s
[01:14:59.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:11:0)
[01:14:59.561] Timer tick, asking Buildbucket for the build status
[01:14:59.759] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:59.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:12:0) after 1m0s
[01:15:59.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:12:0)
[01:15:59.803] Timer tick, asking Buildbucket for the build status
[01:15:59.904] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:59.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:13:0) after 1m0s
[01:16:59.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:13:0)
[01:16:59.926] Timer tick, asking Buildbucket for the build status
[01:17:00.160] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:00.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:14:0) after 1m0s
[01:18:00.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:14:0)
[01:18:00.401] Timer tick, asking Buildbucket for the build status
[01:18:00.494] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:00.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:15:0) after 1m0s
[01:19:01.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:15:0)
[01:19:01.645] Timer tick, asking Buildbucket for the build status
[01:19:01.755] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:01.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:16:0) after 1m0s
[01:20:02.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:16:0)
[01:20:02.347] Timer tick, asking Buildbucket for the build status
[01:20:02.484] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:02.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:17:0) after 1m0s
[01:21:02.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:17:0)
[01:21:02.548] Timer tick, asking Buildbucket for the build status
[01:21:02.651] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:02.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:18:0) after 1m0s
[01:22:03.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:18:0)
[01:22:03.055] Timer tick, asking Buildbucket for the build status
[01:22:03.129] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:03.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:19:0) after 1m0s
[01:23:03.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:19:0)
[01:23:03.504] Timer tick, asking Buildbucket for the build status
[01:23:03.874] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:03.874] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:20:0) after 1m0s
[01:24:03.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:20:0)
[01:24:03.930] Timer tick, asking Buildbucket for the build status
[01:24:04.155] Build 8864694560521190608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:04.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:21:0) after 1m0s
[01:24:58.912] Received PubSub notification, asking Buildbucket for the build status
[01:24:58.982] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:04.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:21:0)
[01:25:04.272] Timer tick, asking Buildbucket for the build status
[01:25:04.564] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:04.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:23:0) after 1m0s
[01:26:04.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:23:0)
[01:26:04.744] Timer tick, asking Buildbucket for the build status
[01:26:05.035] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:05.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:24:0) after 1m0s
[01:27:05.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:24:0)
[01:27:05.133] Timer tick, asking Buildbucket for the build status
[01:27:05.314] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:05.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:25:0) after 1m0s
[01:28:05.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:25:0)
[01:28:05.443] Timer tick, asking Buildbucket for the build status
[01:28:05.557] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:05.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:26:0) after 1m0s
[01:29:05.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:26:0)
[01:29:05.661] Timer tick, asking Buildbucket for the build status
[01:29:05.731] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:05.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:27:0) after 1m0s
[01:30:05.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:27:0)
[01:30:05.742] Timer tick, asking Buildbucket for the build status
[01:30:06.346] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:06.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:28:0) after 1m0s
[01:31:06.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:28:0)
[01:31:06.533] Timer tick, asking Buildbucket for the build status
[01:31:06.635] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:06.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:29:0) after 1m0s
[01:32:06.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:29:0)
[01:32:06.715] Timer tick, asking Buildbucket for the build status
[01:32:06.781] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:06.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:30:0) after 1m0s
[01:33:06.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:30:0)
[01:33:06.848] Timer tick, asking Buildbucket for the build status
[01:33:06.943] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:06.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:31:0) after 1m0s
[01:34:06.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:31:0)
[01:34:06.987] Timer tick, asking Buildbucket for the build status
[01:34:07.067] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:07.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:32:0) after 1m0s
[01:35:07.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:32:0)
[01:35:07.165] Timer tick, asking Buildbucket for the build status
[01:35:07.769] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:07.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:33:0) after 1m0s
[01:36:07.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:33:0)
[01:36:07.807] Timer tick, asking Buildbucket for the build status
[01:36:07.895] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:07.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:34:0) after 1m0s
[01:37:07.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:34:0)
[01:37:07.966] Timer tick, asking Buildbucket for the build status
[01:37:08.036] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:08.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:35:0) after 1m0s
[01:38:08.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:35:0)
[01:38:08.143] Timer tick, asking Buildbucket for the build status
[01:38:08.256] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:08.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:36:0) after 1m0s
[01:39:08.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:36:0)
[01:39:08.374] Timer tick, asking Buildbucket for the build status
[01:39:08.485] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:08.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:37:0) after 1m0s
[01:40:08.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:37:0)
[01:40:08.701] Timer tick, asking Buildbucket for the build status
[01:40:08.820] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:08.820] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:38:0) after 1m0s
[01:41:08.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:38:0)
[01:41:08.914] Timer tick, asking Buildbucket for the build status
[01:41:09.010] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:09.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:39:0) after 1m0s
[01:42:09.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:39:0)
[01:42:09.058] Timer tick, asking Buildbucket for the build status
[01:42:09.199] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:09.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:40:0) after 1m0s
[01:43:09.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:40:0)
[01:43:09.456] Timer tick, asking Buildbucket for the build status
[01:43:09.537] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:09.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:41:0) after 1m0s
[01:44:09.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:41:0)
[01:44:09.714] Timer tick, asking Buildbucket for the build status
[01:44:09.782] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:09.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:42:0) after 1m0s
[01:45:09.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:42:0)
[01:45:09.799] Timer tick, asking Buildbucket for the build status
[01:45:09.938] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:09.938] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:43:0) after 1m0s
[01:46:10.034] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:43:0)
[01:46:10.034] Timer tick, asking Buildbucket for the build status
[01:46:10.141] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:10.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:44:0) after 1m0s
[01:47:10.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:44:0)
[01:47:10.258] Timer tick, asking Buildbucket for the build status
[01:47:10.533] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:10.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:45:0) after 1m0s
[01:48:10.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:45:0)
[01:48:10.593] Timer tick, asking Buildbucket for the build status
[01:48:10.832] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:10.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:46:0) after 1m0s
[01:49:10.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:46:0)
[01:49:10.906] Timer tick, asking Buildbucket for the build status
[01:49:10.988] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:10.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:47:0) after 1m0s
[01:50:11.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:47:0)
[01:50:11.099] Timer tick, asking Buildbucket for the build status
[01:50:11.235] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:11.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:48:0) after 1m0s
[01:51:11.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:48:0)
[01:51:11.407] Timer tick, asking Buildbucket for the build status
[01:51:11.572] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:11.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:49:0) after 1m0s
[01:52:11.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:49:0)
[01:52:11.628] Timer tick, asking Buildbucket for the build status
[01:52:11.701] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:11.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:50:0) after 1m0s
[01:53:11.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:50:0)
[01:53:11.772] Timer tick, asking Buildbucket for the build status
[01:53:11.833] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:11.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:51:0) after 1m0s
[01:54:11.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:51:0)
[01:54:11.939] Timer tick, asking Buildbucket for the build status
[01:54:11.999] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:11.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:52:0) after 1m0s
[01:55:12.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:52:0)
[01:55:12.275] Timer tick, asking Buildbucket for the build status
[01:55:12.401] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:12.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:53:0) after 1m0s
[01:56:12.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:53:0)
[01:56:12.452] Timer tick, asking Buildbucket for the build status
[01:56:12.517] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:12.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:54:0) after 1m0s
[01:57:12.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:54:0)
[01:57:12.794] Timer tick, asking Buildbucket for the build status
[01:57:13.144] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:13.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:55:0) after 1m0s
[01:58:13.331] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:55:0)
[01:58:13.331] Timer tick, asking Buildbucket for the build status
[01:58:13.512] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:13.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:56:0) after 1m0s
[01:59:13.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:56:0)
[01:59:13.559] Timer tick, asking Buildbucket for the build status
[01:59:13.664] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:13.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:57:0) after 1m0s
[02:00:13.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:57:0)
[02:00:13.731] Timer tick, asking Buildbucket for the build status
[02:00:13.877] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:13.877] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:58:0) after 1m0s
[02:01:13.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:58:0)
[02:01:13.941] Timer tick, asking Buildbucket for the build status
[02:01:14.035] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:14.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:59:0) after 1m0s
[02:02:14.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:59:0)
[02:02:14.064] Timer tick, asking Buildbucket for the build status
[02:02:14.142] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:14.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:60:0) after 1m0s
[02:03:14.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:60:0)
[02:03:14.339] Timer tick, asking Buildbucket for the build status
[02:03:14.590] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:14.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:61:0) after 1m0s
[02:04:14.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:61:0)
[02:04:14.920] Timer tick, asking Buildbucket for the build status
[02:04:15.224] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:15.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:62:0) after 1m0s
[02:05:15.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:62:0)
[02:05:15.501] Timer tick, asking Buildbucket for the build status
[02:05:15.621] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:15.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:63:0) after 1m0s
[02:06:15.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:63:0)
[02:06:15.735] Timer tick, asking Buildbucket for the build status
[02:06:15.837] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:15.837] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:64:0) after 1m0s
[02:07:15.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:64:0)
[02:07:15.905] Timer tick, asking Buildbucket for the build status
[02:07:15.980] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:15.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:65:0) after 1m0s
[02:08:16.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:65:0)
[02:08:16.141] Timer tick, asking Buildbucket for the build status
[02:08:16.299] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:16.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:66:0) after 1m0s
[02:09:16.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:66:0)
[02:09:16.318] Timer tick, asking Buildbucket for the build status
[02:09:16.399] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:16.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:67:0) after 1m0s
[02:10:16.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:67:0)
[02:10:16.419] Timer tick, asking Buildbucket for the build status
[02:10:16.801] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:16.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:68:0) after 1m0s
[02:11:16.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:68:0)
[02:11:16.803] Timer tick, asking Buildbucket for the build status
[02:11:16.876] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:16.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:69:0) after 1m0s
[02:12:16.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:69:0)
[02:12:16.925] Timer tick, asking Buildbucket for the build status
[02:12:17.010] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:17.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:70:0) after 1m0s
[02:13:17.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:70:0)
[02:13:17.242] Timer tick, asking Buildbucket for the build status
[02:13:17.357] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:17.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:71:0) after 1m0s
[02:14:17.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:71:0)
[02:14:17.620] Timer tick, asking Buildbucket for the build status
[02:14:17.757] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:17.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:72:0) after 1m0s
[02:15:17.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:72:0)
[02:15:17.892] Timer tick, asking Buildbucket for the build status
[02:15:17.996] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:17.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:73:0) after 1m0s
[02:16:18.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:73:0)
[02:16:18.196] Timer tick, asking Buildbucket for the build status
[02:16:18.258] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:18.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:74:0) after 1m0s
[02:17:18.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:74:0)
[02:17:18.419] Timer tick, asking Buildbucket for the build status
[02:17:18.487] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:18.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:75:0) after 1m0s
[02:18:18.607] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:75:0)
[02:18:18.607] Timer tick, asking Buildbucket for the build status
[02:18:18.999] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:18.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:76:0) after 1m0s
[02:19:19.060] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:76:0)
[02:19:19.060] Timer tick, asking Buildbucket for the build status
[02:19:19.134] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:19.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:77:0) after 1m0s
[02:20:19.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:77:0)
[02:20:19.257] Timer tick, asking Buildbucket for the build status
[02:20:19.693] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:19.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:78:0) after 1m0s
[02:21:19.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:78:0)
[02:21:19.705] Timer tick, asking Buildbucket for the build status
[02:21:19.789] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:19.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:79:0) after 1m0s
[02:22:19.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:79:0)
[02:22:19.894] Timer tick, asking Buildbucket for the build status
[02:22:20.183] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:20.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:80:0) after 1m0s
[02:23:20.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:80:0)
[02:23:20.294] Timer tick, asking Buildbucket for the build status
[02:23:20.370] Build 8864694560521190608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:20.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9030124622587672352:81:0) after 1m0s
[02:23:29.375] Received PubSub notification, asking Buildbucket for the build status
[02:23:29.470] Build 8864694560521190608: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:23:29.470] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1604370208134287",
"created_by": "project:emscripten-releases",
"created_ts": "1604365497052737",
"id": "8864694560521190608",
"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\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"title\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}}",
"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\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"title\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-2-6n3t\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2cf546c7e132505652d76b5a2fb86a72642a1d76\"}, \"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-6n3t\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5614-d862eaa\"], \"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": "1604366697686704",
"status": "COMPLETED",
"status_changed_ts": "1604370208790671",
"tags": [
"builder:linux",
"buildset:commit/git/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2cf546c7e132505652d76b5a2fb86a72642a1d76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030124622587672352",
"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/8864694560521190608/+/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:4fa474400edd8210",
"user_agent:luci-scheduler"
],
"updated_ts": "1604370208890024",
"url": "https://ci.chromium.org/b/8864694560521190608",
"utcnow_ts": "1604370209445260"
}
}
[02:23:29.470] Invocation finished in 1h18m33.772585739s with status SUCCEEDED