[11:25:32.516] New invocation is queued and will start shortly
[11:25:33.732] Starting the invocation (attempt 1)
[11:25:33.774] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:25:33.774] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:25:33.774] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027095877079975024",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4c0934b7b95a5d22e0a0211146318fa71a2b8559\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9027095877079975024",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"gitiles_ref:refs/heads/master"
]
}
[11:25:34.814] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607253933933304",
"id": "8861665814930076992",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c0934b7b95a5d22e0a0211146318fa71a2b8559\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607253934656838",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21627",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027095877079975024",
"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/8861665814930076992/+/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": "1607253934657035",
"url": "https://ci.chromium.org/b/8861665814930076992",
"utcnow_ts": "1607253934807416"
}
}
[11:25:34.814] Task URL: https://ci.chromium.org/b/8861665814930076992
[11:25:34.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:2:0) after 1m0s
[11:26:34.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:2:0)
[11:26:34.835] Timer tick, asking Buildbucket for the build status
[11:26:34.972] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:34.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:3:0) after 1m0s
[11:27:35.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:3:0)
[11:27:35.029] Timer tick, asking Buildbucket for the build status
[11:27:35.202] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:35.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:4:0) after 1m0s
[11:28:35.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:4:0)
[11:28:35.223] Timer tick, asking Buildbucket for the build status
[11:28:35.348] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:35.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:5:0) after 1m0s
[11:29:35.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:5:0)
[11:29:35.365] Timer tick, asking Buildbucket for the build status
[11:29:36.324] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:36.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:6:0) after 1m0s
[11:30:36.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:6:0)
[11:30:36.351] Timer tick, asking Buildbucket for the build status
[11:30:36.500] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:36.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:7:0) after 1m0s
[11:31:36.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:7:0)
[11:31:36.536] Timer tick, asking Buildbucket for the build status
[11:31:36.663] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:36.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:8:0) after 1m0s
[11:32:36.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:8:0)
[11:32:36.652] Timer tick, asking Buildbucket for the build status
[11:32:36.845] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:36.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:9:0) after 1m0s
[11:33:36.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:9:0)
[11:33:36.868] Timer tick, asking Buildbucket for the build status
[11:33:36.997] Build 8861665814930076992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:36.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:10:0) after 1m0s
[11:34:35.442] Received PubSub notification, asking Buildbucket for the build status
[11:34:35.680] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:37.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:10:0)
[11:34:37.022] Timer tick, asking Buildbucket for the build status
[11:34:37.109] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:37.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:12:0) after 1m0s
[11:35:37.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:12:0)
[11:35:37.129] Timer tick, asking Buildbucket for the build status
[11:35:37.237] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:37.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:13:0) after 1m0s
[11:36:37.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:13:0)
[11:36:37.346] Timer tick, asking Buildbucket for the build status
[11:36:37.622] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:37.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:14:0) after 1m0s
[11:37:37.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:14:0)
[11:37:37.645] Timer tick, asking Buildbucket for the build status
[11:37:37.737] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:37.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:15:0) after 1m0s
[11:38:37.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:15:0)
[11:38:37.779] Timer tick, asking Buildbucket for the build status
[11:38:37.878] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:37.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:16:0) after 1m0s
[11:39:37.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:16:0)
[11:39:37.909] Timer tick, asking Buildbucket for the build status
[11:39:41.008] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:41.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:17:0) after 1m0s
[11:40:41.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:17:0)
[11:40:41.231] Timer tick, asking Buildbucket for the build status
[11:40:41.405] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:41.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:18:0) after 1m0s
[11:41:41.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:18:0)
[11:41:41.588] Timer tick, asking Buildbucket for the build status
[11:41:41.738] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:41.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:19:0) after 1m0s
[11:42:41.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:19:0)
[11:42:41.761] Timer tick, asking Buildbucket for the build status
[11:42:42.183] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:42.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:20:0) after 1m0s
[11:43:42.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:20:0)
[11:43:42.232] Timer tick, asking Buildbucket for the build status
[11:43:42.383] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:42.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:21:0) after 1m0s
[11:44:42.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:21:0)
[11:44:42.434] Timer tick, asking Buildbucket for the build status
[11:44:42.948] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:42.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:22:0) after 1m0s
[11:45:42.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:22:0)
[11:45:42.967] Timer tick, asking Buildbucket for the build status
[11:45:43.153] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:43.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:23:0) after 1m0s
[11:46:43.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:23:0)
[11:46:43.174] Timer tick, asking Buildbucket for the build status
[11:46:43.248] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:43.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:24:0) after 1m0s
[11:47:43.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:24:0)
[11:47:43.264] Timer tick, asking Buildbucket for the build status
[11:47:43.470] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:43.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:25:0) after 1m0s
[11:48:43.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:25:0)
[11:48:43.466] Timer tick, asking Buildbucket for the build status
[11:48:43.601] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:43.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:26:0) after 1m0s
[11:49:43.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:26:0)
[11:49:43.622] Timer tick, asking Buildbucket for the build status
[11:49:43.754] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:43.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:27:0) after 1m0s
[11:50:43.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:27:0)
[11:50:43.732] Timer tick, asking Buildbucket for the build status
[11:50:44.133] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:44.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:28:0) after 1m0s
[11:51:44.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:28:0)
[11:51:44.170] Timer tick, asking Buildbucket for the build status
[11:51:44.268] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:44.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:29:0) after 1m0s
[11:52:44.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:29:0)
[11:52:44.337] Timer tick, asking Buildbucket for the build status
[11:52:44.407] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:30:0) after 1m0s
[11:53:44.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:30:0)
[11:53:44.481] Timer tick, asking Buildbucket for the build status
[11:53:44.568] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:44.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:31:0) after 1m0s
[11:54:44.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:31:0)
[11:54:44.750] Timer tick, asking Buildbucket for the build status
[11:54:45.153] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:32:0) after 1m0s
[11:55:45.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:32:0)
[11:55:45.252] Timer tick, asking Buildbucket for the build status
[11:55:45.423] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:45.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:33:0) after 1m0s
[11:56:45.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:33:0)
[11:56:45.513] Timer tick, asking Buildbucket for the build status
[11:56:45.584] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:45.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:34:0) after 1m0s
[11:57:45.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:34:0)
[11:57:45.788] Timer tick, asking Buildbucket for the build status
[11:57:45.888] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:45.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:35:0) after 1m0s
[11:58:45.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:35:0)
[11:58:45.984] Timer tick, asking Buildbucket for the build status
[11:58:46.701] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:46.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:36:0) after 1m0s
[11:59:46.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:36:0)
[11:59:46.818] Timer tick, asking Buildbucket for the build status
[11:59:46.891] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:46.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:37:0) after 1m0s
[12:00:46.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:37:0)
[12:00:46.971] Timer tick, asking Buildbucket for the build status
[12:00:47.180] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:47.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:38:0) after 1m0s
[12:01:47.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:38:0)
[12:01:47.233] Timer tick, asking Buildbucket for the build status
[12:01:47.346] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:47.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:39:0) after 1m0s
[12:02:47.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:39:0)
[12:02:47.369] Timer tick, asking Buildbucket for the build status
[12:02:47.510] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:47.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:40:0) after 1m0s
[12:03:47.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:40:0)
[12:03:47.594] Timer tick, asking Buildbucket for the build status
[12:03:47.668] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:47.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:41:0) after 1m0s
[12:04:47.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:41:0)
[12:04:47.994] Timer tick, asking Buildbucket for the build status
[12:04:48.137] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:48.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:42:0) after 1m0s
[12:05:48.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:42:0)
[12:05:48.310] Timer tick, asking Buildbucket for the build status
[12:05:48.431] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:48.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:43:0) after 1m0s
[12:06:48.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:43:0)
[12:06:48.647] Timer tick, asking Buildbucket for the build status
[12:06:48.766] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:48.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:44:0) after 1m0s
[12:07:48.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:44:0)
[12:07:48.917] Timer tick, asking Buildbucket for the build status
[12:07:48.977] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:48.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:45:0) after 1m0s
[12:08:49.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:45:0)
[12:08:49.168] Timer tick, asking Buildbucket for the build status
[12:08:49.265] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:49.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:46:0) after 1m0s
[12:09:49.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:46:0)
[12:09:49.289] Timer tick, asking Buildbucket for the build status
[12:09:51.258] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:51.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:47:0) after 1m0s
[12:10:51.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:47:0)
[12:10:51.421] Timer tick, asking Buildbucket for the build status
[12:10:51.560] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:51.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:48:0) after 1m0s
[12:11:51.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:48:0)
[12:11:51.778] Timer tick, asking Buildbucket for the build status
[12:11:51.852] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:51.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:49:0) after 1m0s
[12:12:51.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:49:0)
[12:12:51.941] Timer tick, asking Buildbucket for the build status
[12:12:52.014] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:52.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:50:0) after 1m0s
[12:13:52.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:50:0)
[12:13:52.116] Timer tick, asking Buildbucket for the build status
[12:13:52.253] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:52.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:51:0) after 1m0s
[12:14:52.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:51:0)
[12:14:52.272] Timer tick, asking Buildbucket for the build status
[12:14:52.416] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:52.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:52:0) after 1m0s
[12:15:52.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:52:0)
[12:15:52.449] Timer tick, asking Buildbucket for the build status
[12:15:52.576] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:52.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:53:0) after 1m0s
[12:16:52.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:53:0)
[12:16:52.605] Timer tick, asking Buildbucket for the build status
[12:16:52.679] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:52.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:54:0) after 1m0s
[12:17:52.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:54:0)
[12:17:52.789] Timer tick, asking Buildbucket for the build status
[12:17:52.857] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:52.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:55:0) after 1m0s
[12:18:52.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:55:0)
[12:18:52.936] Timer tick, asking Buildbucket for the build status
[12:18:52.998] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:52.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:56:0) after 1m0s
[12:19:53.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:56:0)
[12:19:53.184] Timer tick, asking Buildbucket for the build status
[12:19:53.295] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:53.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:57:0) after 1m0s
[12:20:53.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:57:0)
[12:20:53.316] Timer tick, asking Buildbucket for the build status
[12:20:53.514] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:53.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:58:0) after 1m0s
[12:21:53.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:58:0)
[12:21:53.570] Timer tick, asking Buildbucket for the build status
[12:21:53.646] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:53.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:59:0) after 1m0s
[12:22:53.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:59:0)
[12:22:53.800] Timer tick, asking Buildbucket for the build status
[12:22:54.242] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:54.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:60:0) after 1m0s
[12:23:54.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:60:0)
[12:23:54.320] Timer tick, asking Buildbucket for the build status
[12:23:54.503] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:54.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:61:0) after 1m0s
[12:24:54.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:61:0)
[12:24:54.561] Timer tick, asking Buildbucket for the build status
[12:24:54.784] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:62:0) after 1m0s
[12:25:54.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:62:0)
[12:25:54.824] Timer tick, asking Buildbucket for the build status
[12:25:55.173] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:55.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:63:0) after 1m0s
[12:26:55.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:63:0)
[12:26:55.250] Timer tick, asking Buildbucket for the build status
[12:26:55.864] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:55.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:64:0) after 1m0s
[12:27:55.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:64:0)
[12:27:55.882] Timer tick, asking Buildbucket for the build status
[12:27:56.016] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:56.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:65:0) after 1m0s
[12:28:56.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:65:0)
[12:28:56.055] Timer tick, asking Buildbucket for the build status
[12:28:56.162] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:56.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:66:0) after 1m0s
[12:29:56.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:66:0)
[12:29:56.137] Timer tick, asking Buildbucket for the build status
[12:29:56.416] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:56.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:67:0) after 1m0s
[12:30:56.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:67:0)
[12:30:56.413] Timer tick, asking Buildbucket for the build status
[12:30:56.577] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:56.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:68:0) after 1m0s
[12:31:56.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:68:0)
[12:31:56.646] Timer tick, asking Buildbucket for the build status
[12:31:56.807] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:56.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:69:0) after 1m0s
[12:32:56.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:69:0)
[12:32:56.834] Timer tick, asking Buildbucket for the build status
[12:32:56.954] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:56.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:70:0) after 1m0s
[12:33:56.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:70:0)
[12:33:56.977] Timer tick, asking Buildbucket for the build status
[12:33:57.363] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:57.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:71:0) after 1m0s
[12:34:57.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:71:0)
[12:34:57.437] Timer tick, asking Buildbucket for the build status
[12:34:57.615] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:57.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:72:0) after 1m0s
[12:35:57.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:72:0)
[12:35:57.641] Timer tick, asking Buildbucket for the build status
[12:35:58.370] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:58.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:73:0) after 1m0s
[12:36:58.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:73:0)
[12:36:58.558] Timer tick, asking Buildbucket for the build status
[12:36:58.630] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:58.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:74:0) after 1m0s
[12:37:58.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:74:0)
[12:37:58.666] Timer tick, asking Buildbucket for the build status
[12:37:58.760] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:58.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:75:0) after 1m0s
[12:38:58.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:75:0)
[12:38:58.785] Timer tick, asking Buildbucket for the build status
[12:38:58.888] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:58.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:76:0) after 1m0s
[12:39:59.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:76:0)
[12:39:59.240] Timer tick, asking Buildbucket for the build status
[12:39:59.412] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:59.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:77:0) after 1m0s
[12:40:59.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:77:0)
[12:40:59.571] Timer tick, asking Buildbucket for the build status
[12:40:59.793] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:59.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:78:0) after 1m0s
[12:42:00.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:78:0)
[12:42:00.125] Timer tick, asking Buildbucket for the build status
[12:42:00.333] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:00.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:79:0) after 1m0s
[12:43:00.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:79:0)
[12:43:00.510] Timer tick, asking Buildbucket for the build status
[12:43:00.603] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:00.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:80:0) after 1m0s
[12:44:00.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:80:0)
[12:44:00.742] Timer tick, asking Buildbucket for the build status
[12:44:00.807] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:00.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:81:0) after 1m0s
[12:45:01.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:81:0)
[12:45:01.636] Timer tick, asking Buildbucket for the build status
[12:45:01.873] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:01.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:82:0) after 1m0s
[12:46:01.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:82:0)
[12:46:01.927] Timer tick, asking Buildbucket for the build status
[12:46:02.369] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:02.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:83:0) after 1m0s
[12:47:02.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:83:0)
[12:47:02.408] Timer tick, asking Buildbucket for the build status
[12:47:02.774] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:02.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:84:0) after 1m0s
[12:48:02.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:84:0)
[12:48:02.812] Timer tick, asking Buildbucket for the build status
[12:48:02.878] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:02.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:85:0) after 1m0s
[12:49:02.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:85:0)
[12:49:02.957] Timer tick, asking Buildbucket for the build status
[12:49:03.135] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:03.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:86:0) after 1m0s
[12:50:03.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:86:0)
[12:50:03.278] Timer tick, asking Buildbucket for the build status
[12:50:03.364] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:03.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:87:0) after 1m0s
[12:51:03.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:87:0)
[12:51:03.439] Timer tick, asking Buildbucket for the build status
[12:51:03.678] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:03.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:88:0) after 1m0s
[12:52:03.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:88:0)
[12:52:03.748] Timer tick, asking Buildbucket for the build status
[12:52:03.920] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:03.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:89:0) after 1m0s
[12:53:05.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:89:0)
[12:53:05.993] Timer tick, asking Buildbucket for the build status
[12:53:06.109] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:06.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:90:0) after 1m0s
[12:54:06.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:90:0)
[12:54:06.472] Timer tick, asking Buildbucket for the build status
[12:54:06.568] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:06.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:91:0) after 1m0s
[12:55:07.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:91:0)
[12:55:07.025] Timer tick, asking Buildbucket for the build status
[12:55:07.102] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:07.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:92:0) after 1m0s
[12:56:07.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:92:0)
[12:56:07.436] Timer tick, asking Buildbucket for the build status
[12:56:08.054] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:08.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:93:0) after 1m0s
[12:57:08.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:93:0)
[12:57:08.139] Timer tick, asking Buildbucket for the build status
[12:57:08.222] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:08.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:94:0) after 1m0s
[12:58:08.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:94:0)
[12:58:08.522] Timer tick, asking Buildbucket for the build status
[12:58:08.783] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:08.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:95:0) after 1m0s
[12:59:08.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:95:0)
[12:59:08.909] Timer tick, asking Buildbucket for the build status
[12:59:09.260] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:09.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:96:0) after 1m0s
[13:00:09.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:96:0)
[13:00:09.385] Timer tick, asking Buildbucket for the build status
[13:00:09.546] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:09.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:97:0) after 1m0s
[13:01:10.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:97:0)
[13:01:10.516] Timer tick, asking Buildbucket for the build status
[13:01:11.234] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:98:0) after 1m0s
[13:02:11.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:98:0)
[13:02:11.248] Timer tick, asking Buildbucket for the build status
[13:02:11.339] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:11.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:99:0) after 1m0s
[13:03:11.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:99:0)
[13:03:11.363] Timer tick, asking Buildbucket for the build status
[13:03:11.690] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:11.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:100:0) after 1m0s
[13:04:11.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:100:0)
[13:04:11.844] Timer tick, asking Buildbucket for the build status
[13:04:12.194] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:12.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:101:0) after 1m0s
[13:05:12.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:101:0)
[13:05:12.219] Timer tick, asking Buildbucket for the build status
[13:05:12.314] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:102:0) after 1m0s
[13:06:12.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:102:0)
[13:06:12.377] Timer tick, asking Buildbucket for the build status
[13:06:12.547] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:12.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:103:0) after 1m0s
[13:07:12.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:103:0)
[13:07:12.575] Timer tick, asking Buildbucket for the build status
[13:07:12.729] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:12.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:104:0) after 1m0s
[13:08:12.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:104:0)
[13:08:12.751] Timer tick, asking Buildbucket for the build status
[13:08:13.049] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:105:0) after 1m0s
[13:09:13.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:105:0)
[13:09:13.108] Timer tick, asking Buildbucket for the build status
[13:09:13.344] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:13.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:106:0) after 1m0s
[13:10:13.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:106:0)
[13:10:13.466] Timer tick, asking Buildbucket for the build status
[13:10:14.387] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:14.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:107:0) after 1m0s
[13:11:14.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:107:0)
[13:11:14.409] Timer tick, asking Buildbucket for the build status
[13:11:14.578] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:14.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:108:0) after 1m0s
[13:12:14.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:108:0)
[13:12:14.651] Timer tick, asking Buildbucket for the build status
[13:12:14.721] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:14.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:109:0) after 1m0s
[13:13:14.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:109:0)
[13:13:14.753] Timer tick, asking Buildbucket for the build status
[13:13:14.871] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:14.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:110:0) after 1m0s
[13:14:14.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:110:0)
[13:14:14.890] Timer tick, asking Buildbucket for the build status
[13:14:15.661] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:15.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:111:0) after 1m0s
[13:15:15.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:111:0)
[13:15:15.656] Timer tick, asking Buildbucket for the build status
[13:15:15.771] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:15.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:112:0) after 1m0s
[13:16:15.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:112:0)
[13:16:15.843] Timer tick, asking Buildbucket for the build status
[13:16:16.020] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:16.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:113:0) after 1m0s
[13:17:16.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:113:0)
[13:17:16.018] Timer tick, asking Buildbucket for the build status
[13:17:16.113] Build 8861665814930076992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:16.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027095877079975024:114:0) after 1m0s
[13:17:59.013] Received PubSub notification, asking Buildbucket for the build status
[13:17:59.119] Build 8861665814930076992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:17:59.119] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607260677929118",
"created_by": "project:chromium",
"created_ts": "1607253933933304",
"id": "8861665814930076992",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c0934b7b95a5d22e0a0211146318fa71a2b8559\"}}",
"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\": \"4c0934b7b95a5d22e0a0211146318fa71a2b8559\", \"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-east1-d-8-2-lpos\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21627, \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"4c0934b7b95a5d22e0a0211146318fa71a2b8559\", \"got_revision_cp\": \"refs/heads/master@{#834069}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7c84426d2abe231f3087a2942fd831137eb3b0aa\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"4c0934b7b95a5d22e0a0211146318fa71a2b8559\", \"swarm_hashes_refs/heads/master(at){#834069}_without_patch\": {\"absl_hardening_tests\": \"296d23ba38017d8881c8c90b78a7eb5372b6b3f7\", \"accessibility_unittests\": \"69bc1d48622d1b8f39ac7c1b2c4fcd8474f48039\", \"angle_unittests\": \"3ca418952ea83c7775e7a49047c560805974d52b\", \"app_list_unittests\": \"0826f843146f383150a2b7c15df9baef32d4d413\", \"app_shell_unittests\": \"fab449ec5315936bd05fccb17dd043eb06246c30\", \"ash_unittests\": \"fadb5d4cdf0c93c472d04164bbbbb61e6d36a144\", \"aura_unittests\": \"039834624036f731b675d6f8cc3dc54b2a1d09bb\", \"base_unittests\": \"d3b67809d4c9963b693222151e639b39e2874e23\", \"base_util_unittests\": \"458fff88e62541a3acf77a6800b819bcde1d5288\", \"blink_common_unittests\": \"dd699a8c6e8b9de10bacb26ae1bbc61581d5ac1d\", \"blink_fuzzer_unittests\": \"60ba49750e22105570a2b0a3be40c73eb41636bd\", \"blink_heap_unittests\": \"c2758a93c217185941530c75d197b1f480db0b6f\", \"blink_platform_unittests\": \"594bc4b3dbfdcc1f7baeab641b383d1b1937b1ca\", \"blink_unittests\": \"2c5f9d5b7afd2e08a5627a398c263e5b83d06797\", \"boringssl_crypto_tests\": \"4a2c2f9f3029b6604fcb9f6f08419cba72cff18d\", \"boringssl_ssl_tests\": \"ac9a5d8bcc6d3113ef57526a8c42a7a6f7b15169\", \"browser_tests\": \"8a18ed85fa5929fee72401be09980233c38b7841\", \"capture_unittests\": \"2f4e707588e13ba885ad84d6f22b59d4c2c0ca7c\", \"cast_unittests\": \"c7044ba25579e0d4cb3c9f04cba3e8d8c4c64025\", \"cc_unittests\": \"af88a0bbd996caa08a62a3470c4faeb4d545f085\", \"chrome_app_unittests\": \"cbf4527e1dd676cf664544e85cc8e348dfad31c0\", \"chromedriver_unittests\": \"4563646b6c25768d34f5efda56b85d7800647c3f\", \"chromeos_components_unittests\": \"2c8fedb26b6a42ec4af278bbac2e5a99da96b750\", \"chromeos_unittests\": \"87f36bb2401f83508f26168474ee1bb01324cedd\", \"components_browsertests\": \"f271e10ba35de2e07176921078f5d008897aa9ff\", \"components_unittests\": \"a9cbc66297beac86c8f9dee740739aefd44a73f9\", \"compositor_unittests\": \"5e9e74feaf833db159ff3564d40febde6258de45\", \"content_browsertests\": \"d74c99b150bd14a81f7c95cedb14d78e3e52813e\", \"content_unittests\": \"ebb1b6f140e746b4c6c670ed9a14debd81310962\", \"crashpad_tests\": \"8900ebb5fbb4abf604694a6d4b4d4ffd2716e6f4\", \"crypto_unittests\": \"02e8cf2d8e7b9fec7524670f351ec9b3bf496aa3\", \"dbus_unittests\": \"2fb22af4e6e92d8568d3751ad3e1870ed8aefeb3\", \"device_unittests\": \"bbe51bf60bc17cf044bce63066caf2d9416aa686\", \"display_unittests\": \"120e82c2fd599c9397798a1992e5c9c0f09a8a3d\", \"events_unittests\": \"8e08cdeefc075868b5dea65a09fb38d1fbced43d\", \"exo_unittests\": \"9b8d8da25d1c5043ee5cd062b489678b321c8897\", \"extensions_browsertests\": \"e92be414f58806d423329678be496a0ffe325c62\", \"extensions_unittests\": \"afade2fdb8edadc49c59130e96c9fcc093793c42\", \"filesystem_service_unittests\": \"35290f57a04902c2c5e2ca71fd811c9dc8554bfb\", \"gcm_unit_tests\": \"1f8538704a9f5d044a4df5f17da0d0ed85db6744\", \"gfx_unittests\": \"314e325970cf4672f94ed525b7368934642e9c37\", \"gin_unittests\": \"bea8957aafe87e2b457c1e0c903a2f2aa867754f\", \"gl_unittests_ozone\": \"4aa4f223b367c9b759b053b2aac10f883f541c0d\", \"google_apis_unittests\": \"d80c62fe9f602c17fb64460ed82e096a2a3d37f1\", \"gpu_unittests\": \"a226c8871213e8b0bbcc8a17b4ef88d491c6d6eb\", \"gwp_asan_unittests\": \"2133bf3c2bcc8a87a8070f1fec2dbd9729d17e77\", \"interactive_ui_tests\": \"dd8707c0ed1e964ceaf4bf9f4dcca9b057f11471\", \"ipc_tests\": \"6d8ba7c2596add5eebd6aee3c92bd98e1d4c30c6\", \"jingle_unittests\": \"b267b5f6c34597045f09929c28545112d09c77fc\", \"keyboard_unittests\": \"91fb87d3ace3067a29b3176b503a68428064229e\", \"latency_unittests\": \"7e49f308217294472a6cbfb4b69c5f6154269c78\", \"libjingle_xmpp_unittests\": \"63478aeccab2e7820f68d71828def39b0e64a377\", \"liburlpattern_unittests\": \"0faeb91a7c4dd5d001c5ce4422217d9aedff470a\", \"media_blink_unittests\": \"1da1e4423a390d42e025b8c56a1524c28684adf3\", \"media_unittests\": \"18dc7f5d2f59e5eec33d62af407fdf8b801824f2\", \"message_center_unittests\": \"1da3ff4f08a588590bc50e767217ca7d5dd92f50\", \"midi_unittests\": \"b545fa012c47cef81b59064f991edf13aa03cfbd\", \"mojo_core_unittests\": \"f4318df6768b483cc7a29b596880eebb106572c8\", \"mojo_unittests\": \"1fc87ee350ca65b71957d3a2e46e55bd697ae35a\", \"nacl_helper_nonsfi_unittests\": \"28a40e57a9f14127cba03c86e8f670c091d8f55f\", \"nacl_loader_unittests\": \"9ff14ac818de164f2770f0388446d086ab4bbc81\", \"native_theme_unittests\": \"a5ef1f93608154cb0edefc96fd8d4ac7985a330d\", \"net_unittests\": \"52d7fd83304a4a2622e38b9fecb74e2eb01d124b\", \"ozone_gl_unittests\": \"2a22af385fdf923575037aa8c8fd42260aa6f958\", \"ozone_unittests\": \"6a2e1bbab5619aa9c408221a6f6ae8201bd6cc97\", \"ozone_x11_unittests\": \"4079da649d43b60bb763d2af33084384a455878d\", \"pdf_unittests\": \"1205a6152aec9348b13d7719b36a90000be0f926\", \"perfetto_unittests\": \"f3f61a00f00a59278ec14196d67f2565ccdd51b8\", \"ppapi_unittests\": \"2aa93863fe739c28706a362a7ee166a0bc226abb\", \"printing_unittests\": \"819a0a2a887b4f25cd6a847a7879a15fdb03a5ff\", \"remoting_unittests\": \"9ad4f13750214df23afc38569d2806dfdd1557f1\", \"sandbox_linux_unittests\": \"2e3bc2d7525fc67f7d7f1d32fa3d2022ba39b22c\", \"service_manager_unittests\": \"3c52bf99d616be8c5638930ab16cd0847754585f\", \"services_unittests\": \"aaae6835e3ae949e68cc6b6e31964b931e815521\", \"shell_dialogs_unittests\": \"cfca0ca73595d1568d160e8b6e18f51b3b1a20aa\", \"shell_encryption_unittests\": \"e170421dcea30f1111e71590559f4b2d72488306\", \"skia_unittests\": \"3808733c958c136bc1d1e7da04cdf6cabf4083a6\", \"snapshot_unittests\": \"e35bdf758d20f5bc91077e04db51b2e48db6ddde\", \"sql_unittests\": \"51d3365d29cf9537632e5ee17760832d887034f9\", \"storage_unittests\": \"9ecc57c5315dd37ff9069c62bc8690248f1e4100\", \"sync_integration_tests\": \"28c1afc2d9b07e8b1392a17629c7c4a38aff07f8\", \"traffic_annotation_auditor_unittests\": \"31a29e5df33cb08638dfc153ca9316c676d19087\", \"ui_base_unittests\": \"91077480f4c137de17eee68d65b59dbf0e468635\", \"ui_chromeos_unittests\": \"1ea6d7e9e4f79e74dc435a576cff0a5aec9a53a5\", \"ui_touch_selection_unittests\": \"98a32a6c17a737fbdd610b483c3a35d1beaf054b\", \"unit_tests\": \"bfc2589ca0f697e67a0aa2644bc1dc513335dbac\", \"url_unittests\": \"53213ad65491b179590e814c97f204d0a120ccd2\", \"usage_time_limit_unittests\": \"2f8b9dc0273c99d6614e135a0fde022e3fb41a83\", \"views_unittests\": \"b77004884c805da2b6726d6239e0d1e96d048837\", \"viz_unittests\": \"a1921867c1a45c55162a966910f429a1e2d56a3a\", \"wm_unittests\": \"392acdaf56506c3d2791d8fbdf31fa14e46588c5\", \"wtf_unittests\": \"0c24d9c1d7d52455b07e9b961561679eef216b4e\", \"zlib_unittests\": \"4b1e3fda89f59c8582c46cf6bdd054ac7428bdc7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_349e20fa0346c22afd9d85eafc166958d65f8d3eb174d2113a3f89c2e822dead_v2\", \"builder_47c3ec4238d3b3d0448e41826f5b2c1435e77a4255b8c93aa27077a59a7a5a67_v2\", \"builder_4ee5460861b9ef33d156aeaeac2391df3413ba8f3171f779b8382484348a79c5_v2\", \"builder_51fbd57b92446336655ba5d71606939b5bf22f086edf809f01e3703848d966de_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_81975214af25fe3c157931ccf4182e1c4ae2635ce20b925a941b30b87e2fcb1d_v2\", \"builder_9f02bd2de6eac19c457931accbc0b1e66d92a355209524fd30dfc8de3911a1f0_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-east1-d-8-2-lpos\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607254471746748",
"status": "COMPLETED",
"status_changed_ts": "1607260678485686",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21627",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c0934b7b95a5d22e0a0211146318fa71a2b8559",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027095877079975024",
"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/8861665814930076992/+/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:50509e506e31d710",
"user_agent:luci-scheduler"
],
"updated_ts": "1607260678502970",
"url": "https://ci.chromium.org/b/8861665814930076992",
"utcnow_ts": "1607260679109570"
}
}
[13:17:59.119] Invocation finished in 1h52m26.619577478s with status SUCCEEDED