[11:58:14.594] New invocation is queued and will start shortly
[11:58:15.728] Starting the invocation (attempt 1)
[11:58:15.796] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:58:15.796] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:58:15.796] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021748598675434800",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021748598675434800",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master"
]
}
[11:58:27.922] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612353496518928",
"id": "8856318535993846016",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612353505592177",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22460",
"builder:linux-chromeos-dbg",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021748598675434800",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856318535993846016/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612353505592402",
"url": "https://ci.chromium.org/b/8856318535993846016",
"utcnow_ts": "1612353507762121"
}
}
[11:58:27.922] Task URL: https://ci.chromium.org/b/8856318535993846016
[11:58:27.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:2:0) after 1m0s
[11:59:27.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:2:0)
[11:59:27.964] Timer tick, asking Buildbucket for the build status
[11:59:28.350] Build 8856318535993846016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:28.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:3:0) after 1m0s
[11:59:40.835] Received PubSub notification, asking Buildbucket for the build status
[11:59:41.162] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:28.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:3:0)
[12:00:28.474] Timer tick, asking Buildbucket for the build status
[12:00:30.664] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:30.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:5:0) after 1m0s
[12:01:30.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:5:0)
[12:01:30.779] Timer tick, asking Buildbucket for the build status
[12:01:34.324] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:34.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:6:0) after 1m0s
[12:02:34.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:6:0)
[12:02:34.434] Timer tick, asking Buildbucket for the build status
[12:02:34.629] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:34.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:7:0) after 1m0s
[12:03:34.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:7:0)
[12:03:34.819] Timer tick, asking Buildbucket for the build status
[12:03:36.641] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:36.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:8:0) after 1m0s
[12:04:36.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:8:0)
[12:04:36.736] Timer tick, asking Buildbucket for the build status
[12:04:38.894] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:38.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:9:0) after 1m0s
[12:05:38.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:9:0)
[12:05:38.953] Timer tick, asking Buildbucket for the build status
[12:05:45.135] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:45.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:10:0) after 1m0s
[12:06:45.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:10:0)
[12:06:45.316] Timer tick, asking Buildbucket for the build status
[12:06:45.475] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:45.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:11:0) after 1m0s
[12:07:45.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:11:0)
[12:07:45.518] Timer tick, asking Buildbucket for the build status
[12:07:46.342] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:46.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:12:0) after 1m0s
[12:08:46.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:12:0)
[12:08:46.468] Timer tick, asking Buildbucket for the build status
[12:08:48.422] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:48.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:13:0) after 1m0s
[12:09:48.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:13:0)
[12:09:48.485] Timer tick, asking Buildbucket for the build status
[12:09:50.962] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:50.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:14:0) after 1m0s
[12:10:51.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:14:0)
[12:10:51.038] Timer tick, asking Buildbucket for the build status
[12:10:55.387] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:55.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:15:0) after 1m0s
[12:11:55.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:15:0)
[12:11:55.455] Timer tick, asking Buildbucket for the build status
[12:11:55.588] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:55.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:16:0) after 1m0s
[12:12:55.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:16:0)
[12:12:55.639] Timer tick, asking Buildbucket for the build status
[12:12:56.338] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:17:0) after 1m0s
[12:13:56.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:17:0)
[12:13:56.491] Timer tick, asking Buildbucket for the build status
[12:13:56.856] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:56.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:18:0) after 1m0s
[12:14:56.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:18:0)
[12:14:56.926] Timer tick, asking Buildbucket for the build status
[12:14:58.980] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:58.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:19:0) after 1m0s
[12:15:59.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:19:0)
[12:15:59.049] Timer tick, asking Buildbucket for the build status
[12:15:59.391] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:59.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:20:0) after 1m0s
[12:16:59.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:20:0)
[12:16:59.468] Timer tick, asking Buildbucket for the build status
[12:16:59.807] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:59.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:21:0) after 1m0s
[12:17:59.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:21:0)
[12:17:59.836] Timer tick, asking Buildbucket for the build status
[12:18:00.084] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:00.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:22:0) after 1m0s
[12:19:00.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:22:0)
[12:19:00.140] Timer tick, asking Buildbucket for the build status
[12:19:02.539] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:02.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:23:0) after 1m0s
[12:20:02.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:23:0)
[12:20:02.716] Timer tick, asking Buildbucket for the build status
[12:20:03.714] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:24:0) after 1m0s
[12:21:03.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:24:0)
[12:21:03.729] Timer tick, asking Buildbucket for the build status
[12:21:04.760] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:04.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:25:0) after 1m0s
[12:22:04.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:25:0)
[12:22:04.797] Timer tick, asking Buildbucket for the build status
[12:22:05.087] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:05.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:26:0) after 1m0s
[12:23:05.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:26:0)
[12:23:05.177] Timer tick, asking Buildbucket for the build status
[12:23:06.742] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:06.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:27:0) after 1m0s
[12:24:06.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:27:0)
[12:24:06.764] Timer tick, asking Buildbucket for the build status
[12:24:07.851] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:07.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:28:0) after 1m0s
[12:25:07.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:28:0)
[12:25:07.873] Timer tick, asking Buildbucket for the build status
[12:25:09.460] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:09.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:29:0) after 1m0s
[12:26:09.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:29:0)
[12:26:09.459] Timer tick, asking Buildbucket for the build status
[12:26:09.704] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:09.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:30:0) after 1m0s
[12:27:09.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:30:0)
[12:27:09.731] Timer tick, asking Buildbucket for the build status
[12:27:10.453] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:10.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:31:0) after 1m0s
[12:28:10.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:31:0)
[12:28:10.499] Timer tick, asking Buildbucket for the build status
[12:28:11.931] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:11.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:32:0) after 1m0s
[12:29:11.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:32:0)
[12:29:11.951] Timer tick, asking Buildbucket for the build status
[12:29:12.906] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:12.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:33:0) after 1m0s
[12:30:12.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:33:0)
[12:30:12.984] Timer tick, asking Buildbucket for the build status
[12:30:13.194] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:13.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:34:0) after 1m0s
[12:31:13.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:34:0)
[12:31:13.274] Timer tick, asking Buildbucket for the build status
[12:31:15.833] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:15.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:35:0) after 1m0s
[12:32:15.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:35:0)
[12:32:15.873] Timer tick, asking Buildbucket for the build status
[12:32:16.530] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:16.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:36:0) after 1m0s
[12:33:16.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:36:0)
[12:33:16.628] Timer tick, asking Buildbucket for the build status
[12:33:22.078] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:22.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:37:0) after 1m0s
[12:34:22.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:37:0)
[12:34:22.135] Timer tick, asking Buildbucket for the build status
[12:34:27.015] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:27.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:38:0) after 1m0s
[12:35:27.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:38:0)
[12:35:27.072] Timer tick, asking Buildbucket for the build status
[12:35:28.733] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:28.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:39:0) after 1m0s
[12:36:28.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:39:0)
[12:36:28.756] Timer tick, asking Buildbucket for the build status
[12:36:29.239] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:40:0) after 1m0s
[12:37:29.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:40:0)
[12:37:29.301] Timer tick, asking Buildbucket for the build status
[12:37:29.781] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:29.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:41:0) after 1m0s
[12:38:29.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:41:0)
[12:38:29.802] Timer tick, asking Buildbucket for the build status
[12:38:29.881] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:29.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:42:0) after 1m0s
[12:39:29.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:42:0)
[12:39:29.930] Timer tick, asking Buildbucket for the build status
[12:39:31.060] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:31.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:43:0) after 1m0s
[12:40:31.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:43:0)
[12:40:31.150] Timer tick, asking Buildbucket for the build status
[12:40:31.953] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:44:0) after 1m0s
[12:41:32.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:44:0)
[12:41:32.052] Timer tick, asking Buildbucket for the build status
[12:41:33.920] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:45:0) after 1m0s
[12:42:33.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:45:0)
[12:42:33.978] Timer tick, asking Buildbucket for the build status
[12:42:38.014] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:38.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:46:0) after 1m0s
[12:43:38.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:46:0)
[12:43:38.125] Timer tick, asking Buildbucket for the build status
[12:43:38.552] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:38.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:47:0) after 1m0s
[12:44:38.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:47:0)
[12:44:38.584] Timer tick, asking Buildbucket for the build status
[12:44:38.767] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:38.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:48:0) after 1m0s
[12:45:38.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:48:0)
[12:45:38.829] Timer tick, asking Buildbucket for the build status
[12:45:39.320] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:39.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:49:0) after 1m0s
[12:46:39.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:49:0)
[12:46:39.323] Timer tick, asking Buildbucket for the build status
[12:46:40.765] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:40.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:50:0) after 1m0s
[12:47:40.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:50:0)
[12:47:40.824] Timer tick, asking Buildbucket for the build status
[12:47:41.284] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:41.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:51:0) after 1m0s
[12:48:41.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:51:0)
[12:48:41.374] Timer tick, asking Buildbucket for the build status
[12:48:44.426] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:44.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:52:0) after 1m0s
[12:49:44.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:52:0)
[12:49:44.455] Timer tick, asking Buildbucket for the build status
[12:49:44.615] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:53:0) after 1m0s
[12:50:44.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:53:0)
[12:50:44.671] Timer tick, asking Buildbucket for the build status
[12:50:44.782] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:44.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:54:0) after 1m0s
[12:51:44.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:54:0)
[12:51:44.828] Timer tick, asking Buildbucket for the build status
[12:51:45.207] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:45.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:55:0) after 1m0s
[12:52:45.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:55:0)
[12:52:45.271] Timer tick, asking Buildbucket for the build status
[12:52:45.703] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:45.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:56:0) after 1m0s
[12:53:45.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:56:0)
[12:53:45.761] Timer tick, asking Buildbucket for the build status
[12:53:45.871] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:45.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:57:0) after 1m0s
[12:54:45.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:57:0)
[12:54:45.937] Timer tick, asking Buildbucket for the build status
[12:54:46.359] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:46.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:58:0) after 1m0s
[12:55:47.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:58:0)
[12:55:47.887] Timer tick, asking Buildbucket for the build status
[12:55:48.424] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:48.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:59:0) after 1m0s
[12:56:48.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:59:0)
[12:56:48.527] Timer tick, asking Buildbucket for the build status
[12:56:48.807] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:48.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:60:0) after 1m0s
[12:57:48.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:60:0)
[12:57:48.876] Timer tick, asking Buildbucket for the build status
[12:57:49.444] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:49.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:61:0) after 1m0s
[12:58:49.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:61:0)
[12:58:49.663] Timer tick, asking Buildbucket for the build status
[12:58:50.037] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:50.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:62:0) after 1m0s
[12:59:50.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:62:0)
[12:59:50.068] Timer tick, asking Buildbucket for the build status
[12:59:52.886] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:52.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:63:0) after 1m0s
[13:00:52.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:63:0)
[13:00:52.940] Timer tick, asking Buildbucket for the build status
[13:00:53.076] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:53.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:64:0) after 1m0s
[13:01:53.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:64:0)
[13:01:53.184] Timer tick, asking Buildbucket for the build status
[13:01:53.268] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:53.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:65:0) after 1m0s
[13:02:53.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:65:0)
[13:02:53.307] Timer tick, asking Buildbucket for the build status
[13:02:54.736] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:54.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:66:0) after 1m0s
[13:03:54.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:66:0)
[13:03:54.844] Timer tick, asking Buildbucket for the build status
[13:03:54.959] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:54.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:67:0) after 1m0s
[13:04:55.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:67:0)
[13:04:55.062] Timer tick, asking Buildbucket for the build status
[13:04:58.996] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:58.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:68:0) after 1m0s
[13:05:59.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:68:0)
[13:05:59.128] Timer tick, asking Buildbucket for the build status
[13:06:00.647] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:00.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:69:0) after 1m0s
[13:07:00.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:69:0)
[13:07:00.674] Timer tick, asking Buildbucket for the build status
[13:07:00.878] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:00.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:70:0) after 1m0s
[13:08:00.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:70:0)
[13:08:00.956] Timer tick, asking Buildbucket for the build status
[13:08:01.183] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:71:0) after 1m0s
[13:09:01.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:71:0)
[13:09:01.278] Timer tick, asking Buildbucket for the build status
[13:09:04.277] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:04.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:72:0) after 1m0s
[13:10:04.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:72:0)
[13:10:04.297] Timer tick, asking Buildbucket for the build status
[13:10:04.430] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:04.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:73:0) after 1m0s
[13:11:04.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:73:0)
[13:11:04.456] Timer tick, asking Buildbucket for the build status
[13:11:05.535] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:05.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:74:0) after 1m0s
[13:12:05.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:74:0)
[13:12:05.565] Timer tick, asking Buildbucket for the build status
[13:12:06.222] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:75:0) after 1m0s
[13:13:06.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:75:0)
[13:13:06.218] Timer tick, asking Buildbucket for the build status
[13:13:07.439] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:07.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:76:0) after 1m0s
[13:14:07.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:76:0)
[13:14:07.512] Timer tick, asking Buildbucket for the build status
[13:14:07.607] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:07.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:77:0) after 1m0s
[13:15:07.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:77:0)
[13:15:07.703] Timer tick, asking Buildbucket for the build status
[13:15:07.894] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:07.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:78:0) after 1m0s
[13:16:07.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:78:0)
[13:16:07.958] Timer tick, asking Buildbucket for the build status
[13:16:09.073] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:09.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:79:0) after 1m0s
[13:17:09.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:79:0)
[13:17:09.101] Timer tick, asking Buildbucket for the build status
[13:17:10.810] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:10.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:80:0) after 1m0s
[13:18:10.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:80:0)
[13:18:10.844] Timer tick, asking Buildbucket for the build status
[13:18:11.748] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:11.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:81:0) after 1m0s
[13:19:11.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:81:0)
[13:19:11.888] Timer tick, asking Buildbucket for the build status
[13:19:12.132] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:12.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:82:0) after 1m0s
[13:20:12.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:82:0)
[13:20:12.167] Timer tick, asking Buildbucket for the build status
[13:20:12.997] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:12.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:83:0) after 1m0s
[13:21:13.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:83:0)
[13:21:13.180] Timer tick, asking Buildbucket for the build status
[13:21:14.191] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:14.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:84:0) after 1m0s
[13:22:14.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:84:0)
[13:22:14.307] Timer tick, asking Buildbucket for the build status
[13:22:14.601] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:14.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:85:0) after 1m0s
[13:23:14.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:85:0)
[13:23:14.691] Timer tick, asking Buildbucket for the build status
[13:23:15.771] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:15.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:86:0) after 1m0s
[13:24:15.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:86:0)
[13:24:15.872] Timer tick, asking Buildbucket for the build status
[13:24:15.974] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:15.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:87:0) after 1m0s
[13:25:16.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:87:0)
[13:25:16.042] Timer tick, asking Buildbucket for the build status
[13:25:16.137] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:88:0) after 1m0s
[13:26:16.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:88:0)
[13:26:16.209] Timer tick, asking Buildbucket for the build status
[13:26:16.310] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:89:0) after 1m0s
[13:27:16.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:89:0)
[13:27:16.313] Timer tick, asking Buildbucket for the build status
[13:27:16.405] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:16.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:90:0) after 1m0s
[13:28:16.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:90:0)
[13:28:16.435] Timer tick, asking Buildbucket for the build status
[13:28:16.815] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:16.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:91:0) after 1m0s
[13:29:16.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:91:0)
[13:29:16.869] Timer tick, asking Buildbucket for the build status
[13:29:17.021] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:92:0) after 1m0s
[13:30:17.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:92:0)
[13:30:17.113] Timer tick, asking Buildbucket for the build status
[13:30:17.210] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:93:0) after 1m0s
[13:31:17.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:93:0)
[13:31:17.225] Timer tick, asking Buildbucket for the build status
[13:31:18.868] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:18.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:94:0) after 1m0s
[13:32:18.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:94:0)
[13:32:18.894] Timer tick, asking Buildbucket for the build status
[13:32:18.960] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:95:0) after 1m0s
[13:33:18.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:95:0)
[13:33:18.981] Timer tick, asking Buildbucket for the build status
[13:33:19.103] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:19.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:96:0) after 1m0s
[13:34:19.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:96:0)
[13:34:19.179] Timer tick, asking Buildbucket for the build status
[13:34:19.373] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:19.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:97:0) after 1m0s
[13:35:19.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:97:0)
[13:35:19.446] Timer tick, asking Buildbucket for the build status
[13:35:19.546] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:19.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:98:0) after 1m0s
[13:36:19.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:98:0)
[13:36:19.570] Timer tick, asking Buildbucket for the build status
[13:36:19.648] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:19.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:99:0) after 1m0s
[13:37:19.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:99:0)
[13:37:19.705] Timer tick, asking Buildbucket for the build status
[13:37:19.772] Build 8856318535993846016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:19.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021748598675434800:100:0) after 1m0s
[13:37:21.080] Received PubSub notification, asking Buildbucket for the build status
[13:37:21.618] Build 8856318535993846016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:37:21.618] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612359439299918",
"created_by": "project:chromium",
"created_ts": "1612353496518928",
"id": "8856318535993846016",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"project\": \"chromium/src\"}], \"$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}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-93-pui4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22460, \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"got_revision_cp\": \"refs/heads/master@{#850068}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"swarm_hashes_refs/heads/master(at){#850068}_without_patch\": {\"absl_hardening_tests\": \"d3021100caf2a877a82c2b041ec91467495c66e7\", \"accessibility_unittests\": \"2d288f0a737ff545bf80e08ab6be13ca8b2cecbc\", \"angle_unittests\": \"438663fe31a739c403a46edf50d418b56200ab29\", \"app_list_unittests\": \"b0ca71591dd29cbf84184394e40d0cc5f4bcb939\", \"app_shell_unittests\": \"d280e9afeaeb3964945eedc2e0334f201ac8e34a\", \"ash_unittests\": \"22d3efebf06755e176fb85d9a8743bd0c623a892\", \"aura_unittests\": \"d06c58f24c9d94d2143ae004eb635da67c856a61\", \"base_unittests\": \"c3affbaf2291ec77487c8a568d34a5f1e40edd4d\", \"base_util_unittests\": \"3db93b33a38523691968ce3c4ccebe5ea26bfab7\", \"blink_common_unittests\": \"7f8ef2ba306ca3c8fc8cc788398f1f5aa9e2931b\", \"blink_fuzzer_unittests\": \"aa11a155d69a4bd7ed60901967660046d2a859c9\", \"blink_heap_unittests\": \"3560374664f3b093946de8e643d12c46041821da\", \"blink_platform_unittests\": \"e3902d0e418300b1a35a482046082e565fd3109b\", \"blink_unittests\": \"b2649eaf393169ffc2f4e4c9d2838831b1fbb0dd\", \"boringssl_crypto_tests\": \"4e2bf69d464404337ff4f7083debf898653b6dc3\", \"boringssl_ssl_tests\": \"d8dbe939986cc9376a13a72391903c71b1318332\", \"browser_tests\": \"ebaabbfde19b0d7d6d97a02e4da1ceebdda0a570\", \"capture_unittests\": \"c41501a9b078b7913d3ffc294cab2c286df60ce3\", \"cast_unittests\": \"cae2b351fe116b4885d0c0e5ec107475ea95468c\", \"cc_unittests\": \"d0d69d9696da8350ea0b6baaae84fe06723c9353\", \"chrome_app_unittests\": \"2de40ff93383f97946f016c99ea7838514a46184\", \"chromedriver_unittests\": \"79a576e5c8c50f1da8527bc3e16b95cfca64c846\", \"chromeos_components_unittests\": \"f48a1711122bee46c733838a8d3450ca7bd3f461\", \"chromeos_unittests\": \"d5509eea45ddc12bdd79afe10b0036c25adfb032\", \"components_browsertests\": \"7d9ad88655bf43c8c66e00da7d57b7798b93d310\", \"components_unittests\": \"9f59ab314c576fe0df76a84df7f55afa1b411134\", \"compositor_unittests\": \"b6ab55ae10c11a1a078a8c67a4ab42b77b488917\", \"content_browsertests\": \"e5326ac14c84510929d2469112aea16a1682cc27\", \"content_unittests\": \"409554180948e85b001f488b266e2c854b71b35d\", \"crashpad_tests\": \"ef522761bf7266d844ce3e3b7034c4a8c94c5f7a\", \"crypto_unittests\": \"f2576357c40db4e01b08d4787eb4042f86e24697\", \"dbus_unittests\": \"e7859d36f50704d533add9a17428c9c7ba031f4e\", \"device_unittests\": \"1f410dcce73cc3d018ec50eef11203b7fa59bd4a\", \"display_unittests\": \"52ddc37c0d86e92a9a696f08ba42aba7d208973e\", \"events_unittests\": \"f813bd14958b7c4b9f723a1ea74d506f002bf31f\", \"exo_unittests\": \"8fb5f889c122fc5d8d2ae5e28c223b863d17b05a\", \"extensions_browsertests\": \"c0389a304266d5812d5257729978e2d57062d8d1\", \"extensions_unittests\": \"8c162e06e3ba740ec50e9f678d0df0f69030d970\", \"filesystem_service_unittests\": \"a4ffa5afcece95ba5a8da096d0b1c6b98808bd79\", \"gcm_unit_tests\": \"2f3027d5556713d22a283a20044936697e46af9a\", \"gfx_unittests\": \"75f416542714a2e0b4991236bba8edb46076001e\", \"gin_unittests\": \"d023ec7a16c0d5e716e3505a8332f511847d90ea\", \"gl_unittests_ozone\": \"45c23176840641d1da9732cca11c5a4f138e651d\", \"google_apis_unittests\": \"4cf66c03ff8ea6b48c296c75899b53e6c52b9803\", \"gpu_unittests\": \"7a6d06c63a3733d1f87bc03250a167f7fe8a50af\", \"gwp_asan_unittests\": \"6c71f8fe2c2d6e0afc04a4787f0ea59652efe6cb\", \"interactive_ui_tests\": \"e60e102d9e7423a6120e2b2628bf97a5aa8099f5\", \"ipc_tests\": \"c0de1c2847074eae969908cb623b006422e30452\", \"jingle_unittests\": \"d0d365e6676d94f2b8b07f6c8e8290f5e61018bb\", \"keyboard_unittests\": \"f421d518a4559bf268491697de53cec50255f932\", \"latency_unittests\": \"3acbe52c54e4ef76f532600070d9962bf9a90395\", \"libjingle_xmpp_unittests\": \"d5e42fd3848ce51923a271b88dfccf309af9b01b\", \"liburlpattern_unittests\": \"e894def8697acd6403f1dab40f4956653b98188c\", \"media_blink_unittests\": \"989be55854ae66cc74252d4773f91fc02767f343\", \"media_unittests\": \"9dcec824afffd8f30fc34e7368441241dd730d2a\", \"message_center_unittests\": \"6102b9cf64277c72b41eddeb646db0b5f1d4c8d5\", \"midi_unittests\": \"895de23df018dd2c10bf32423cd7b57461a98a9a\", \"mojo_core_unittests\": \"a4241002ff3a00048cb03b25897968daff252b5f\", \"mojo_unittests\": \"c654a551a28278aa21a45aed3764227c34dac78a\", \"nacl_helper_nonsfi_unittests\": \"1331259e404fad3dd99fd7c4e057a014e7677c41\", \"nacl_loader_unittests\": \"b69db046bf0fdd7721501011489ac0282d0cf3af\", \"native_theme_unittests\": \"172182c91c9e49a0b5f26a23117c8af5d1dfcee5\", \"net_unittests\": \"7e37e1594017d440abcdc8411009fb5b5202e940\", \"ozone_gl_unittests\": \"bdbdff395dc098214ccd2bc6ef7de26d5f032868\", \"ozone_unittests\": \"2c527c85c4597c34d790276edc07c60f20c2efb8\", \"ozone_x11_unittests\": \"5611bb0d4d5028fb65702e82f21b475fcaf0ecf5\", \"pdf_unittests\": \"7b00746e0fcb9d4483bf846edfed693c8b80049a\", \"perfetto_unittests\": \"680b03835b4bc7243181ae1ff794199749dad2ec\", \"ppapi_unittests\": \"adac21ccd7b022c58da117c5be087f19ff234290\", \"printing_unittests\": \"8d646a124854e7faaf91a6fe59f015d9813adb06\", \"remoting_unittests\": \"f1c61b3eb8288f9d26a75e01c3197eba7cdb744a\", \"sandbox_linux_unittests\": \"53ca44981d730d6aa1d7097225fe4c5f5a6589ce\", \"service_manager_unittests\": \"2fc600f43ae757321d6883573e007b6efde8260b\", \"services_unittests\": \"5fc6a84fac219a218f151243d68e8a04ca53d56a\", \"shell_dialogs_unittests\": \"a205f273b31b43528b04e8089b2060453172bed7\", \"shell_encryption_unittests\": \"b583f93bf2d36defebc3e52bbdfa298b968123ec\", \"skia_unittests\": \"9937e8647864c9d75d5b33cab5d29c37dd254bf4\", \"snapshot_unittests\": \"e45847a9d919a76cefa792a2e0f446ac6617ed0c\", \"sql_unittests\": \"c6f4759247ec7dd028be0731b46d29ef61f35aa3\", \"storage_unittests\": \"22793054c33a1b6c024890fc8fd611a88aefbb02\", \"sync_integration_tests\": \"01bc36306c48b5bc2ec54d809b0dcddf0df8ba12\", \"traffic_annotation_auditor_unittests\": \"12fce9863837e34a67da3b385dbf184045d1e98a\", \"ui_base_unittests\": \"c3db94e9cb241e0d4f94b8d16af843fce70fa4b0\", \"ui_chromeos_unittests\": \"727aaec651c34bfb56a6127f963847c0c68914cc\", \"ui_touch_selection_unittests\": \"d23acee601e929c7ab9dd3fdc85384c49c16c564\", \"unit_tests\": \"297f3bb39f7b74c30035f00959d32e3791c5a669\", \"url_unittests\": \"081c25879d8072ee79c9729692671c56c6f42d98\", \"usage_time_limit_unittests\": \"f47ec1717c483b65bb65a70bf0de01668882aba5\", \"views_unittests\": \"a16fd723e3f8a16a8aa19cb4defefbda7200b554\", \"viz_unittests\": \"3603fb8ad112b4cafb1f0c56fb6c1f21e6b360ce\", \"wm_unittests\": \"01082e021bec07deede88fedd46d6a80042e30cb\", \"wtf_unittests\": \"f6de301e55eea16f8145d1224adee8993174bd9e\", \"zlib_unittests\": \"5032e180672738a3ec54ba51807703b46a68930a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_9f02bd2de6eac19c457931accbc0b1e66d92a355209524fd30dfc8de3911a1f0_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-93-pui4\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612353570870875",
"status": "COMPLETED",
"status_changed_ts": "1612359440177540",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22460",
"builder:linux-chromeos-dbg",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021748598675434800",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856318535993846016/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5180939c3d897310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612359440186638",
"url": "https://ci.chromium.org/b/8856318535993846016",
"utcnow_ts": "1612359441596899"
}
}
[13:37:21.619] Invocation finished in 1h39m7.042338218s with status SUCCEEDED