[19:51:44.186] New invocation is queued and will start shortly
[19:51:45.487] Starting the invocation (attempt 1)
[19:51:45.538] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:51:45.538] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:51:45.539] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021537615123791040",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a99961d35ffcc2e08f822615f6dc3bb403e97931\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021537615123791040",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"gitiles_ref:refs/heads/master"
]
}
[19:51:46.975] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612554705720475",
"id": "8856107552853702528",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a99961d35ffcc2e08f822615f6dc3bb403e97931\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612554706869253",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22497",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021537615123791040",
"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/8856107552853702528/+/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": "1612554706869426",
"url": "https://ci.chromium.org/b/8856107552853702528",
"utcnow_ts": "1612554706968390"
}
}
[19:51:46.975] Task URL: https://ci.chromium.org/b/8856107552853702528
[19:51:46.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:2:0) after 1m0s
[19:51:50.239] Received PubSub notification, asking Buildbucket for the build status
[19:51:50.536] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:47.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:2:0)
[19:52:47.023] Timer tick, asking Buildbucket for the build status
[19:52:47.618] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:47.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:4:0) after 1m0s
[19:53:47.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:4:0)
[19:53:47.655] Timer tick, asking Buildbucket for the build status
[19:53:47.733] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:47.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:5:0) after 1m0s
[19:54:47.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:5:0)
[19:54:47.756] Timer tick, asking Buildbucket for the build status
[19:54:47.887] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:47.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:6:0) after 1m0s
[19:55:47.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:6:0)
[19:55:47.887] Timer tick, asking Buildbucket for the build status
[19:55:48.192] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:48.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:7:0) after 1m0s
[19:56:48.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:7:0)
[19:56:48.252] Timer tick, asking Buildbucket for the build status
[19:56:48.415] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:48.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:8:0) after 1m0s
[19:57:48.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:8:0)
[19:57:48.433] Timer tick, asking Buildbucket for the build status
[19:57:48.524] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:48.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:9:0) after 1m0s
[19:58:48.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:9:0)
[19:58:48.564] Timer tick, asking Buildbucket for the build status
[19:58:48.722] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:48.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:10:0) after 1m0s
[19:59:48.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:10:0)
[19:59:48.694] Timer tick, asking Buildbucket for the build status
[19:59:48.767] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:48.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:11:0) after 1m0s
[20:00:48.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:11:0)
[20:00:48.843] Timer tick, asking Buildbucket for the build status
[20:00:49.070] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:12:0) after 1m0s
[20:01:49.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:12:0)
[20:01:49.126] Timer tick, asking Buildbucket for the build status
[20:01:49.249] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:13:0) after 1m0s
[20:02:49.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:13:0)
[20:02:49.323] Timer tick, asking Buildbucket for the build status
[20:02:49.384] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:14:0) after 1m0s
[20:03:49.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:14:0)
[20:03:49.395] Timer tick, asking Buildbucket for the build status
[20:03:49.473] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:49.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:15:0) after 1m0s
[20:04:49.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:15:0)
[20:04:49.498] Timer tick, asking Buildbucket for the build status
[20:04:49.577] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:49.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:16:0) after 1m0s
[20:05:49.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:16:0)
[20:05:49.632] Timer tick, asking Buildbucket for the build status
[20:05:49.721] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:49.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:17:0) after 1m0s
[20:06:49.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:17:0)
[20:06:49.767] Timer tick, asking Buildbucket for the build status
[20:06:49.888] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:49.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:18:0) after 1m0s
[20:07:50.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:18:0)
[20:07:50.045] Timer tick, asking Buildbucket for the build status
[20:07:50.388] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:50.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:19:0) after 1m0s
[20:08:50.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:19:0)
[20:08:50.453] Timer tick, asking Buildbucket for the build status
[20:08:50.528] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:50.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:20:0) after 1m0s
[20:09:50.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:20:0)
[20:09:50.583] Timer tick, asking Buildbucket for the build status
[20:09:50.656] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:50.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:21:0) after 1m0s
[20:10:50.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:21:0)
[20:10:50.705] Timer tick, asking Buildbucket for the build status
[20:10:51.082] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:51.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:22:0) after 1m0s
[20:11:51.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:22:0)
[20:11:51.101] Timer tick, asking Buildbucket for the build status
[20:11:51.915] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:51.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:23:0) after 1m0s
[20:12:52.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:23:0)
[20:12:52.008] Timer tick, asking Buildbucket for the build status
[20:12:52.075] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:52.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:24:0) after 1m0s
[20:13:52.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:24:0)
[20:13:52.125] Timer tick, asking Buildbucket for the build status
[20:13:52.205] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:52.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:25:0) after 1m0s
[20:14:52.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:25:0)
[20:14:52.298] Timer tick, asking Buildbucket for the build status
[20:14:52.429] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:52.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:26:0) after 1m0s
[20:15:52.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:26:0)
[20:15:52.448] Timer tick, asking Buildbucket for the build status
[20:15:52.540] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:27:0) after 1m0s
[20:16:52.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:27:0)
[20:16:52.586] Timer tick, asking Buildbucket for the build status
[20:16:52.679] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:52.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:28:0) after 1m0s
[20:17:52.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:28:0)
[20:17:52.683] Timer tick, asking Buildbucket for the build status
[20:17:52.747] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:52.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:29:0) after 1m0s
[20:18:52.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:29:0)
[20:18:52.765] Timer tick, asking Buildbucket for the build status
[20:18:52.832] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:52.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:30:0) after 1m0s
[20:19:52.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:30:0)
[20:19:52.891] Timer tick, asking Buildbucket for the build status
[20:19:52.964] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:52.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:31:0) after 1m0s
[20:20:52.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:31:0)
[20:20:52.985] Timer tick, asking Buildbucket for the build status
[20:20:53.053] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:53.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:32:0) after 1m0s
[20:21:53.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:32:0)
[20:21:53.107] Timer tick, asking Buildbucket for the build status
[20:21:53.446] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:53.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:33:0) after 1m0s
[20:22:53.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:33:0)
[20:22:53.502] Timer tick, asking Buildbucket for the build status
[20:22:53.697] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:53.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:34:0) after 1m0s
[20:23:53.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:34:0)
[20:23:53.864] Timer tick, asking Buildbucket for the build status
[20:23:53.929] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:53.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:35:0) after 1m0s
[20:24:54.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:35:0)
[20:24:54.036] Timer tick, asking Buildbucket for the build status
[20:24:54.088] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:54.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:36:0) after 1m0s
[20:25:54.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:36:0)
[20:25:54.156] Timer tick, asking Buildbucket for the build status
[20:25:54.244] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:37:0) after 1m0s
[20:26:54.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:37:0)
[20:26:54.319] Timer tick, asking Buildbucket for the build status
[20:26:54.437] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:38:0) after 1m0s
[20:27:54.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:38:0)
[20:27:54.475] Timer tick, asking Buildbucket for the build status
[20:27:54.650] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:54.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:39:0) after 1m0s
[20:28:54.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:39:0)
[20:28:54.691] Timer tick, asking Buildbucket for the build status
[20:28:54.767] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:54.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:40:0) after 1m0s
[20:29:54.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:40:0)
[20:29:54.788] Timer tick, asking Buildbucket for the build status
[20:29:54.921] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:54.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:41:0) after 1m0s
[20:30:54.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:41:0)
[20:30:54.941] Timer tick, asking Buildbucket for the build status
[20:30:55.535] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:55.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:42:0) after 1m0s
[20:31:55.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:42:0)
[20:31:55.566] Timer tick, asking Buildbucket for the build status
[20:31:55.679] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:55.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:43:0) after 1m0s
[20:32:55.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:43:0)
[20:32:55.708] Timer tick, asking Buildbucket for the build status
[20:32:55.829] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:55.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:44:0) after 1m0s
[20:33:55.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:44:0)
[20:33:55.860] Timer tick, asking Buildbucket for the build status
[20:33:56.047] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:56.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:45:0) after 1m0s
[20:34:56.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:45:0)
[20:34:56.078] Timer tick, asking Buildbucket for the build status
[20:34:56.614] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:56.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:46:0) after 1m0s
[20:35:56.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:46:0)
[20:35:56.592] Timer tick, asking Buildbucket for the build status
[20:35:56.707] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:56.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:47:0) after 1m0s
[20:36:56.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:47:0)
[20:36:56.740] Timer tick, asking Buildbucket for the build status
[20:36:57.294] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:57.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:48:0) after 1m0s
[20:37:57.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:48:0)
[20:37:57.291] Timer tick, asking Buildbucket for the build status
[20:37:57.735] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:49:0) after 1m0s
[20:38:57.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:49:0)
[20:38:57.792] Timer tick, asking Buildbucket for the build status
[20:38:58.132] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:58.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:50:0) after 1m0s
[20:39:58.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:50:0)
[20:39:58.259] Timer tick, asking Buildbucket for the build status
[20:39:58.400] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:58.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:51:0) after 1m0s
[20:40:58.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:51:0)
[20:40:58.471] Timer tick, asking Buildbucket for the build status
[20:40:58.728] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:52:0) after 1m0s
[20:41:58.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:52:0)
[20:41:58.816] Timer tick, asking Buildbucket for the build status
[20:41:59.051] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:59.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:53:0) after 1m0s
[20:42:59.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:53:0)
[20:42:59.136] Timer tick, asking Buildbucket for the build status
[20:42:59.275] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:59.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:54:0) after 1m0s
[20:43:59.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:54:0)
[20:43:59.391] Timer tick, asking Buildbucket for the build status
[20:43:59.494] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:59.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:55:0) after 1m0s
[20:44:59.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:55:0)
[20:44:59.563] Timer tick, asking Buildbucket for the build status
[20:44:59.647] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:56:0) after 1m0s
[20:45:59.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:56:0)
[20:45:59.736] Timer tick, asking Buildbucket for the build status
[20:46:00.339] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:00.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:57:0) after 1m0s
[20:47:00.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:57:0)
[20:47:00.733] Timer tick, asking Buildbucket for the build status
[20:47:00.866] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:00.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:58:0) after 1m0s
[20:48:01.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:58:0)
[20:48:01.591] Timer tick, asking Buildbucket for the build status
[20:48:01.668] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:01.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:59:0) after 1m0s
[20:49:02.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:59:0)
[20:49:02.326] Timer tick, asking Buildbucket for the build status
[20:49:02.629] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:02.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:60:0) after 1m0s
[20:50:02.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:60:0)
[20:50:02.640] Timer tick, asking Buildbucket for the build status
[20:50:03.040] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:03.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:61:0) after 1m0s
[20:51:03.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:61:0)
[20:51:03.128] Timer tick, asking Buildbucket for the build status
[20:51:03.209] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:03.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:62:0) after 1m0s
[20:52:03.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:62:0)
[20:52:03.274] Timer tick, asking Buildbucket for the build status
[20:52:03.724] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:03.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:63:0) after 1m0s
[20:53:03.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:63:0)
[20:53:03.776] Timer tick, asking Buildbucket for the build status
[20:53:03.946] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:03.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:64:0) after 1m0s
[20:54:04.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:64:0)
[20:54:04.028] Timer tick, asking Buildbucket for the build status
[20:54:04.218] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:04.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:65:0) after 1m0s
[20:55:04.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:65:0)
[20:55:04.277] Timer tick, asking Buildbucket for the build status
[20:55:04.696] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:04.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:66:0) after 1m0s
[20:56:04.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:66:0)
[20:56:04.718] Timer tick, asking Buildbucket for the build status
[20:56:07.313] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:07.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:67:0) after 1m0s
[20:57:07.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:67:0)
[20:57:07.312] Timer tick, asking Buildbucket for the build status
[20:57:07.738] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:07.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:68:0) after 1m0s
[20:58:07.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:68:0)
[20:58:07.757] Timer tick, asking Buildbucket for the build status
[20:58:07.861] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:07.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:69:0) after 1m0s
[20:59:07.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:69:0)
[20:59:07.914] Timer tick, asking Buildbucket for the build status
[20:59:08.022] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:08.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:70:0) after 1m0s
[21:00:08.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:70:0)
[21:00:08.154] Timer tick, asking Buildbucket for the build status
[21:00:08.735] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:08.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:71:0) after 1m0s
[21:01:08.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:71:0)
[21:01:08.802] Timer tick, asking Buildbucket for the build status
[21:01:08.921] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:08.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:72:0) after 1m0s
[21:02:08.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:72:0)
[21:02:08.941] Timer tick, asking Buildbucket for the build status
[21:02:09.360] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:09.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:73:0) after 1m0s
[21:03:09.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:73:0)
[21:03:09.506] Timer tick, asking Buildbucket for the build status
[21:03:09.665] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:09.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:74:0) after 1m0s
[21:04:09.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:74:0)
[21:04:09.735] Timer tick, asking Buildbucket for the build status
[21:04:09.813] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:09.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:75:0) after 1m0s
[21:05:09.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:75:0)
[21:05:09.975] Timer tick, asking Buildbucket for the build status
[21:05:10.079] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:10.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:76:0) after 1m0s
[21:06:10.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:76:0)
[21:06:10.264] Timer tick, asking Buildbucket for the build status
[21:06:10.336] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:10.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:77:0) after 1m0s
[21:07:10.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:77:0)
[21:07:10.485] Timer tick, asking Buildbucket for the build status
[21:07:11.033] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:11.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:78:0) after 1m0s
[21:08:11.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:78:0)
[21:08:11.161] Timer tick, asking Buildbucket for the build status
[21:08:11.234] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:79:0) after 1m0s
[21:09:11.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:79:0)
[21:09:11.372] Timer tick, asking Buildbucket for the build status
[21:09:11.947] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:11.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:80:0) after 1m0s
[21:10:11.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:80:0)
[21:10:11.981] Timer tick, asking Buildbucket for the build status
[21:10:12.131] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:12.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:81:0) after 1m0s
[21:11:12.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:81:0)
[21:11:12.151] Timer tick, asking Buildbucket for the build status
[21:11:12.297] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:12.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:82:0) after 1m0s
[21:12:12.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:82:0)
[21:12:12.435] Timer tick, asking Buildbucket for the build status
[21:12:12.539] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:12.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:83:0) after 1m0s
[21:13:12.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:83:0)
[21:13:12.578] Timer tick, asking Buildbucket for the build status
[21:13:12.692] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:12.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:84:0) after 1m0s
[21:14:12.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:84:0)
[21:14:12.773] Timer tick, asking Buildbucket for the build status
[21:14:13.152] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:13.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:85:0) after 1m0s
[21:15:13.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:85:0)
[21:15:13.305] Timer tick, asking Buildbucket for the build status
[21:15:13.375] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:13.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:86:0) after 1m0s
[21:16:13.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:86:0)
[21:16:13.598] Timer tick, asking Buildbucket for the build status
[21:16:13.669] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:13.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:87:0) after 1m0s
[21:17:14.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:87:0)
[21:17:14.130] Timer tick, asking Buildbucket for the build status
[21:17:14.303] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:14.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:88:0) after 1m0s
[21:18:14.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:88:0)
[21:18:14.717] Timer tick, asking Buildbucket for the build status
[21:18:14.856] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:14.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:89:0) after 1m0s
[21:19:15.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:89:0)
[21:19:15.101] Timer tick, asking Buildbucket for the build status
[21:19:15.604] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:15.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:90:0) after 1m0s
[21:20:15.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:90:0)
[21:20:15.843] Timer tick, asking Buildbucket for the build status
[21:20:16.122] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:16.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:91:0) after 1m0s
[21:21:16.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:91:0)
[21:21:16.221] Timer tick, asking Buildbucket for the build status
[21:21:16.493] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:92:0) after 1m0s
[21:22:16.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:92:0)
[21:22:16.901] Timer tick, asking Buildbucket for the build status
[21:22:17.311] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:17.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:93:0) after 1m0s
[21:23:17.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:93:0)
[21:23:17.350] Timer tick, asking Buildbucket for the build status
[21:23:18.127] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:18.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:94:0) after 1m0s
[21:24:18.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:94:0)
[21:24:18.182] Timer tick, asking Buildbucket for the build status
[21:24:18.311] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:18.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:95:0) after 1m0s
[21:25:18.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:95:0)
[21:25:18.389] Timer tick, asking Buildbucket for the build status
[21:25:18.460] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:18.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:96:0) after 1m0s
[21:26:18.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:96:0)
[21:26:18.488] Timer tick, asking Buildbucket for the build status
[21:26:18.561] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:18.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:97:0) after 1m0s
[21:27:18.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:97:0)
[21:27:18.643] Timer tick, asking Buildbucket for the build status
[21:27:18.760] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:18.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:98:0) after 1m0s
[21:28:18.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:98:0)
[21:28:18.824] Timer tick, asking Buildbucket for the build status
[21:28:18.901] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:18.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:99:0) after 1m0s
[21:29:18.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:99:0)
[21:29:18.944] Timer tick, asking Buildbucket for the build status
[21:29:19.064] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:19.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:100:0) after 1m0s
[21:30:19.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:100:0)
[21:30:19.126] Timer tick, asking Buildbucket for the build status
[21:30:19.208] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:19.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:101:0) after 1m0s
[21:31:19.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:101:0)
[21:31:19.281] Timer tick, asking Buildbucket for the build status
[21:31:19.440] Build 8856107552853702528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:19.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021537615123791040:102:0) after 1m0s
[21:31:32.843] Received PubSub notification, asking Buildbucket for the build status
[21:31:32.917] Build 8856107552853702528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:31:32.917] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612560691838292",
"created_by": "project:chromium",
"created_ts": "1612554705720475",
"id": "8856107552853702528",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a99961d35ffcc2e08f822615f6dc3bb403e97931\"}}",
"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\": \"a99961d35ffcc2e08f822615f6dc3bb403e97931\", \"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\": 22497, \"got_angle_revision\": \"3bb5f1ff26bccc4f409cedd196e9749f76bd6869\", \"got_dawn_revision\": \"185c6a5b0f358c115e3d3ef43afbb157d4c4b591\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"a99961d35ffcc2e08f822615f6dc3bb403e97931\", \"got_revision_cp\": \"refs/heads/master@{#851244}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a8da847d13385bb1c4322ae3c456e4896976c93b\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"a99961d35ffcc2e08f822615f6dc3bb403e97931\", \"swarm_hashes_refs/heads/master(at){#851244}_without_patch\": {\"absl_hardening_tests\": \"56be1824a6a7deb4ad2d9d78e4540914b003e94f\", \"accessibility_unittests\": \"6f0b8ee66ad0be57449f8c3d2e31c7ab68edf4d2\", \"angle_unittests\": \"94b578ab17a6f5694dc65e24473b7fdf1c77770c\", \"app_list_unittests\": \"0783fb3f315e54ccf7f2e498879228923477e55d\", \"app_shell_unittests\": \"7f27b3c42f722308058f9281c130a40654096517\", \"ash_unittests\": \"8c28ecdd79db6b1623fe8b1dabf227511871b19b\", \"aura_unittests\": \"16e8d9c069accac174508a6849877cf2347dbcaf\", \"base_unittests\": \"0be00f93a3bd2c2978d9a2894f3b06100150624e\", \"base_util_unittests\": \"790b97a0bf61f958249f659b2f4a67f6f724095f\", \"blink_common_unittests\": \"d593fd0b3bb03dd85e68191feded3b7ae203abc5\", \"blink_fuzzer_unittests\": \"db398950c88174adfbfe455ff6e4da652279995e\", \"blink_heap_unittests\": \"7f412f4975ab9ee41b4be3b8c2edac92c87920d8\", \"blink_platform_unittests\": \"2f76b20bd6c4325fd5071b713b6f39348a4e9566\", \"blink_unittests\": \"ae2f603fda18b801cd2fc6b2e2d3515cb570daab\", \"boringssl_crypto_tests\": \"15d3cd50a8367db933bb40f39035f8f2918ec128\", \"boringssl_ssl_tests\": \"0c718129fad0ccb0fb08c2765d48142f17bbd408\", \"browser_tests\": \"47a4d81ee6ec6b364ec228e21aa759a6dd3adf5f\", \"capture_unittests\": \"e3099628457871aa17e9522e7dbc28a02c904c07\", \"cast_unittests\": \"677dc59f45567b27889c5da725595e5901273283\", \"cc_unittests\": \"fd8eaaef0c73cbc96a4a8e8cd30f58f3859a57e1\", \"chrome_app_unittests\": \"96e3f760048cbbb5ab9a824716f21438936b6b00\", \"chromedriver_unittests\": \"d99c4bd9cb677d7f480746d94f61e058ebb3bdfd\", \"chromeos_components_unittests\": \"efc8eeae63eab051a3dfb5886475d2a17ebc0f76\", \"chromeos_unittests\": \"d4693ecb4ad509ea053310a7e235eca15a6b72a7\", \"components_browsertests\": \"f213af5054d91015869beee8dc540d1eb42fc134\", \"components_unittests\": \"75ea83f5f9639a2c1fc9c23f144d3c79526a8234\", \"compositor_unittests\": \"3ff67c7ba8256e6e1be4012b3bfde3679252f43e\", \"content_browsertests\": \"c26b4da9e0bb71c3c189d2b85193e9ca76609faa\", \"content_unittests\": \"9c78d577c194fb91afbf47264431026cc028eb53\", \"crashpad_tests\": \"85e38804d115d98b559a49e41b7618b3bafae41c\", \"crypto_unittests\": \"eb7ce9ae9071767bc59280fd4d203354d675f9a8\", \"dbus_unittests\": \"90ac20d59a05b5e4b2fa22e36e8ef81669c4e1c8\", \"device_unittests\": \"b791a53fb26c6697557ef2dbe34b6c3447e37515\", \"display_unittests\": \"9dfb341df6f82ab3dc40fc1fb055c5b6d127cc3b\", \"events_unittests\": \"9ba532ed938f79757bc2d9a97041d0ec68b783b7\", \"exo_unittests\": \"aa5885d5b272baea48c1328089f8c289526f8392\", \"extensions_browsertests\": \"285c5214b569e81e858aa3cd774f4dfd42765265\", \"extensions_unittests\": \"a6091768fecc50e83154f5a39e1f589f7df367ab\", \"filesystem_service_unittests\": \"fb6fb9b09d9ee68c21ce1c40b7447e82b556d859\", \"gcm_unit_tests\": \"f7829f4c22ed8f0863bf4a2896a6f8b1cc6e8060\", \"gfx_unittests\": \"6664b47494747ada3c044434b29bd1518c5a9cb5\", \"gin_unittests\": \"9e207c88d6c4e0a5bbfcf54fe5d8f0764c103ea2\", \"gl_unittests_ozone\": \"2859bd644941cbaabe4fe3319ff404c16ceb8a10\", \"google_apis_unittests\": \"455a3355ed2b0b79b8cd482fb58b86d0984e96be\", \"gpu_unittests\": \"2c5978e7158fb03bcf3482396d27385678bb5ef4\", \"gwp_asan_unittests\": \"db0288e7c6d2eec7f7f04d466bd93a452c8890a3\", \"interactive_ui_tests\": \"d4a2f2dabea3610fbcc26e160f68cda2f551e2de\", \"ipc_tests\": \"fd2f61e9cea23d6fda598d267769d7c0b0cbc9bc\", \"jingle_unittests\": \"c33da76f50afc4660025fa45b33e24c08b016e57\", \"keyboard_unittests\": \"49fa42b2c21e193e3e99b6ef7fb2166a8432a111\", \"latency_unittests\": \"8878e571360de81c27b3500a3fa3ab05f79f5a14\", \"libjingle_xmpp_unittests\": \"50fd348b90b25b724b8309bab16bafc57b587136\", \"liburlpattern_unittests\": \"c4d854b52ac0407fe5ec1e5075b268762ebc670d\", \"media_blink_unittests\": \"2358c5c912ebe3ee8b36317a71007360d357f8f0\", \"media_unittests\": \"b594af4cd5100d459a46cfde7d27b3487e46ad49\", \"message_center_unittests\": \"c183e4d5435e757459c344a6875042237ba5aab9\", \"midi_unittests\": \"2bfc9015db2e0268e635e95a2f82ffc5201ca712\", \"mojo_core_unittests\": \"290767c72e79f3dea061c97a3ded381181eb0b28\", \"mojo_unittests\": \"89f5f5933b382f5dfc0c07b30f3a2b0ca9ec522d\", \"nacl_helper_nonsfi_unittests\": \"19f6c92e1d144ac65f91e2cf22ecbdc0b40d455c\", \"nacl_loader_unittests\": \"c2fd8155b4b57f17e19e1ba4c5a9a44dad7f6c54\", \"native_theme_unittests\": \"69d5c6b519e250b7f82f61d478b6ffcd327cf74a\", \"net_unittests\": \"a16a5e118140e98faee4d5f203dd53fbfb934fa9\", \"ozone_gl_unittests\": \"6ca98a536eab2826d99b11a2d0b22bdc839a9aa1\", \"ozone_unittests\": \"623f69911546d26ea7d90eb5d095255e7247973b\", \"ozone_x11_unittests\": \"251ee321c44decf43676c4d3370c34a3889b1eec\", \"pdf_unittests\": \"43d015339b4fea4c1e497420d06d5617a5448dbd\", \"perfetto_unittests\": \"33f3509ee85932e604c40295b9cc491ccc39f9b8\", \"ppapi_unittests\": \"1e405757101b3e4c56dbdf149726b7825d036746\", \"printing_unittests\": \"050a4aa4949655a607f7a0240478147095625269\", \"remoting_unittests\": \"242364065eb5c46fa5e32ef4b45510e3a0484580\", \"sandbox_linux_unittests\": \"047dd081d9619220ebbe9e4e03d2cfc422d163fe\", \"service_manager_unittests\": \"c3689d7decd73a6aa1fbce7e6a4ef7d8020c190c\", \"services_unittests\": \"24019467954d98dcb96abdd7859a31641048c542\", \"shell_dialogs_unittests\": \"d0fa8c42a6cf9b6d803587970b9133ac2fb5565d\", \"shell_encryption_unittests\": \"2da790155db46593582e0ebce5d3ebff95462344\", \"skia_unittests\": \"8866036a9359eb7de807622a67289d9085e8ba43\", \"snapshot_unittests\": \"76c2d0c71c4f0173bc984d03c2f2b6f761aa0c6c\", \"sql_unittests\": \"8d30fe9e22ea6cffb19cb6b3e77aeffc9b5c772b\", \"storage_unittests\": \"8332ec0094eb79ac3cc8c88c5f5db2a59f2cd260\", \"sync_integration_tests\": \"aba95aa277156503fdecc8a691fd2b9d2391e05e\", \"traffic_annotation_auditor_unittests\": \"6765f72eace46808dca3a7a71eb935f1c9981dc0\", \"ui_base_unittests\": \"057cdd4c18b0f592859b2b4f45e2ba01b5228715\", \"ui_chromeos_unittests\": \"6ae0c4bface9f707abe9346b0136d17aa7cb06bd\", \"ui_touch_selection_unittests\": \"8d8aba5596956a0f18e64978d7ccb7553f42cd3a\", \"unit_tests\": \"4b85860d52cad0fd44f58199936942d67d0f098a\", \"url_unittests\": \"0154e8ff4bf40aa8e036c62dcb611a314caf9c4f\", \"usage_time_limit_unittests\": \"fb573be0027ed5521cdf2aaff5b15192a6e5acf0\", \"views_unittests\": \"b56ea643a5145832659ef2e56f55ea0be82faee2\", \"viz_unittests\": \"b0de95ef67ca88bb3d5d7add81b0e2e7ac926fae\", \"wm_unittests\": \"a5b77c5afb79d9fbca7370e7d18952cf017c2d1a\", \"wtf_unittests\": \"efb228f208229cb8000cb7286bc96bbac6481891\", \"zlib_unittests\": \"4da6cd96930e69915fb19ff5ca0d07ac904af20a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_17616f83fd632132c65ea3dd60ce01b78f71f01fa76c3e3446f88a64858a3864_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_573c606f18492d6cde6d25a02b12203725732038b1c2909b0b80126d2afb25c2_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": "1612554709684609",
"status": "COMPLETED",
"status_changed_ts": "1612560692382410",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22497",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a99961d35ffcc2e08f822615f6dc3bb403e97931",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021537615123791040",
"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/8856107552853702528/+/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:518c91a89f104010",
"user_agent:luci-scheduler"
],
"updated_ts": "1612560692387688",
"url": "https://ci.chromium.org/b/8856107552853702528",
"utcnow_ts": "1612560692905966"
}
}
[21:31:32.917] Invocation finished in 1h39m48.746247294s with status SUCCEEDED