[23:30:10.122] New invocation is queued and will start shortly
[23:30:13.658] Starting the invocation (attempt 1)
[23:30:13.790] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:30:13.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:30:13.790] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029405808634626880",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9029405808634626880",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"gitiles_ref:refs/heads/master"
]
}
[23:30:14.738] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605051014001857",
"id": "8863975743901516736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605051014579295",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21244",
"builder:linux-chromeos-dbg",
"buildset:commit/git/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029405808634626880",
"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/8863975743901516736/+/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": "1605051014579436",
"url": "https://ci.chromium.org/b/8863975743901516736",
"utcnow_ts": "1605051014724182"
}
}
[23:30:14.738] Task URL: https://ci.chromium.org/b/8863975743901516736
[23:30:14.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:2:0) after 1m0s
[23:30:33.296] Received PubSub notification, asking Buildbucket for the build status
[23:30:34.149] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:15.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:2:0)
[23:31:15.034] Timer tick, asking Buildbucket for the build status
[23:31:15.218] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:15.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:4:0) after 1m0s
[23:32:15.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:4:0)
[23:32:15.271] Timer tick, asking Buildbucket for the build status
[23:32:15.406] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:5:0) after 1m0s
[23:33:15.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:5:0)
[23:33:15.466] Timer tick, asking Buildbucket for the build status
[23:33:15.587] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:15.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:6:0) after 1m0s
[23:34:15.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:6:0)
[23:34:15.794] Timer tick, asking Buildbucket for the build status
[23:34:15.886] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:15.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:7:0) after 1m0s
[23:35:16.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:7:0)
[23:35:16.031] Timer tick, asking Buildbucket for the build status
[23:35:16.461] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:16.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:8:0) after 1m0s
[23:36:16.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:8:0)
[23:36:16.709] Timer tick, asking Buildbucket for the build status
[23:36:17.752] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:17.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:9:0) after 1m0s
[23:37:17.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:9:0)
[23:37:17.957] Timer tick, asking Buildbucket for the build status
[23:37:18.253] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:18.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:10:0) after 1m0s
[23:38:18.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:10:0)
[23:38:18.336] Timer tick, asking Buildbucket for the build status
[23:38:19.989] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:19.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:11:0) after 1m0s
[23:39:20.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:11:0)
[23:39:20.166] Timer tick, asking Buildbucket for the build status
[23:39:20.239] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:20.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:12:0) after 1m0s
[23:40:20.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:12:0)
[23:40:20.247] Timer tick, asking Buildbucket for the build status
[23:40:20.414] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:20.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:13:0) after 1m0s
[23:41:20.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:13:0)
[23:41:20.493] Timer tick, asking Buildbucket for the build status
[23:41:20.613] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:20.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:14:0) after 1m0s
[23:42:20.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:14:0)
[23:42:20.834] Timer tick, asking Buildbucket for the build status
[23:42:20.901] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:20.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:15:0) after 1m0s
[23:43:21.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:15:0)
[23:43:21.073] Timer tick, asking Buildbucket for the build status
[23:43:21.156] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:21.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:16:0) after 1m0s
[23:44:21.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:16:0)
[23:44:21.209] Timer tick, asking Buildbucket for the build status
[23:44:21.275] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:21.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:17:0) after 1m0s
[23:45:21.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:17:0)
[23:45:21.455] Timer tick, asking Buildbucket for the build status
[23:45:21.688] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:21.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:18:0) after 1m0s
[23:46:21.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:18:0)
[23:46:21.817] Timer tick, asking Buildbucket for the build status
[23:46:22.186] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:22.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:19:0) after 1m0s
[23:47:22.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:19:0)
[23:47:22.382] Timer tick, asking Buildbucket for the build status
[23:47:22.848] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:22.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:20:0) after 1m0s
[23:48:22.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:20:0)
[23:48:22.932] Timer tick, asking Buildbucket for the build status
[23:48:23.193] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:23.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:21:0) after 1m0s
[23:49:23.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:21:0)
[23:49:23.216] Timer tick, asking Buildbucket for the build status
[23:49:23.287] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:23.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:22:0) after 1m0s
[23:50:23.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:22:0)
[23:50:23.353] Timer tick, asking Buildbucket for the build status
[23:50:23.446] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:23.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:23:0) after 1m0s
[23:51:23.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:23:0)
[23:51:23.473] Timer tick, asking Buildbucket for the build status
[23:51:23.913] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:23.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:24:0) after 1m0s
[23:52:23.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:24:0)
[23:52:23.908] Timer tick, asking Buildbucket for the build status
[23:52:24.806] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:24.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:25:0) after 1m0s
[23:53:25.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:25:0)
[23:53:25.381] Timer tick, asking Buildbucket for the build status
[23:53:25.894] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:26:0) after 1m0s
[23:54:25.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:26:0)
[23:54:25.941] Timer tick, asking Buildbucket for the build status
[23:54:26.026] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:26.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:27:0) after 1m0s
[23:55:26.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:27:0)
[23:55:26.112] Timer tick, asking Buildbucket for the build status
[23:55:27.081] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:27.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:28:0) after 1m0s
[23:56:27.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:28:0)
[23:56:27.170] Timer tick, asking Buildbucket for the build status
[23:56:27.311] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:27.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:29:0) after 1m0s
[23:57:27.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:29:0)
[23:57:27.542] Timer tick, asking Buildbucket for the build status
[23:57:27.710] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:27.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:30:0) after 1m0s
[23:58:27.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:30:0)
[23:58:27.786] Timer tick, asking Buildbucket for the build status
[23:58:27.871] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:27.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:31:0) after 1m0s
[23:59:27.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:31:0)
[23:59:27.935] Timer tick, asking Buildbucket for the build status
[23:59:28.040] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:28.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:32:0) after 1m0s
[00:00:28.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:32:0)
[00:00:28.068] Timer tick, asking Buildbucket for the build status
[00:00:28.578] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:28.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:33:0) after 1m0s
[00:01:28.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:33:0)
[00:01:28.730] Timer tick, asking Buildbucket for the build status
[00:01:28.888] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:28.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:34:0) after 1m0s
[00:02:28.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:34:0)
[00:02:28.937] Timer tick, asking Buildbucket for the build status
[00:02:29.438] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:29.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:35:0) after 1m0s
[00:03:29.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:35:0)
[00:03:29.490] Timer tick, asking Buildbucket for the build status
[00:03:29.670] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:29.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:36:0) after 1m0s
[00:04:29.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:36:0)
[00:04:29.688] Timer tick, asking Buildbucket for the build status
[00:04:29.917] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:29.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:37:0) after 1m0s
[00:05:29.915] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:37:0)
[00:05:29.915] Timer tick, asking Buildbucket for the build status
[00:05:30.141] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:30.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:38:0) after 1m0s
[00:06:30.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:38:0)
[00:06:30.181] Timer tick, asking Buildbucket for the build status
[00:06:30.389] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:30.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:39:0) after 1m0s
[00:07:30.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:39:0)
[00:07:30.453] Timer tick, asking Buildbucket for the build status
[00:07:30.509] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:30.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:40:0) after 1m0s
[00:08:30.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:40:0)
[00:08:30.561] Timer tick, asking Buildbucket for the build status
[00:08:30.721] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:30.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:41:0) after 1m0s
[00:09:30.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:41:0)
[00:09:30.743] Timer tick, asking Buildbucket for the build status
[00:09:30.897] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:30.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:42:0) after 1m0s
[00:10:31.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:42:0)
[00:10:31.057] Timer tick, asking Buildbucket for the build status
[00:10:32.142] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:32.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:43:0) after 1m0s
[00:11:32.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:43:0)
[00:11:32.169] Timer tick, asking Buildbucket for the build status
[00:11:32.277] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:32.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:44:0) after 1m0s
[00:12:32.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:44:0)
[00:12:32.365] Timer tick, asking Buildbucket for the build status
[00:12:32.452] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:32.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:45:0) after 1m0s
[00:13:32.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:45:0)
[00:13:32.498] Timer tick, asking Buildbucket for the build status
[00:13:32.583] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:32.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:46:0) after 1m0s
[00:14:32.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:46:0)
[00:14:32.743] Timer tick, asking Buildbucket for the build status
[00:14:32.915] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:32.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:47:0) after 1m0s
[00:15:32.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:47:0)
[00:15:32.945] Timer tick, asking Buildbucket for the build status
[00:15:33.033] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:33.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:48:0) after 1m0s
[00:16:33.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:48:0)
[00:16:33.146] Timer tick, asking Buildbucket for the build status
[00:16:33.600] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:33.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:49:0) after 1m0s
[00:17:33.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:49:0)
[00:17:33.655] Timer tick, asking Buildbucket for the build status
[00:17:34.128] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:34.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:50:0) after 1m0s
[00:18:34.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:50:0)
[00:18:34.167] Timer tick, asking Buildbucket for the build status
[00:18:34.241] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:34.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:51:0) after 1m0s
[00:19:34.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:51:0)
[00:19:34.326] Timer tick, asking Buildbucket for the build status
[00:19:34.760] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:34.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:52:0) after 1m0s
[00:20:34.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:52:0)
[00:20:34.893] Timer tick, asking Buildbucket for the build status
[00:20:35.094] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:35.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:53:0) after 1m0s
[00:21:35.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:53:0)
[00:21:35.156] Timer tick, asking Buildbucket for the build status
[00:21:35.366] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:35.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:54:0) after 1m0s
[00:22:35.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:54:0)
[00:22:35.430] Timer tick, asking Buildbucket for the build status
[00:22:35.630] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:35.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:55:0) after 1m0s
[00:23:35.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:55:0)
[00:23:35.688] Timer tick, asking Buildbucket for the build status
[00:23:35.852] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:35.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:56:0) after 1m0s
[00:24:35.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:56:0)
[00:24:35.897] Timer tick, asking Buildbucket for the build status
[00:24:36.124] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:36.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:57:0) after 1m0s
[00:25:36.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:57:0)
[00:25:36.217] Timer tick, asking Buildbucket for the build status
[00:25:36.288] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:36.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:58:0) after 1m0s
[00:26:36.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:58:0)
[00:26:36.371] Timer tick, asking Buildbucket for the build status
[00:26:36.880] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:36.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:59:0) after 1m0s
[00:27:36.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:59:0)
[00:27:36.974] Timer tick, asking Buildbucket for the build status
[00:27:37.102] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:37.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:60:0) after 1m0s
[00:28:37.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:60:0)
[00:28:37.169] Timer tick, asking Buildbucket for the build status
[00:28:37.385] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:37.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:61:0) after 1m0s
[00:29:37.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:61:0)
[00:29:37.550] Timer tick, asking Buildbucket for the build status
[00:29:38.020] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:38.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:62:0) after 1m0s
[00:30:38.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:62:0)
[00:30:38.201] Timer tick, asking Buildbucket for the build status
[00:30:38.339] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:38.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:63:0) after 1m0s
[00:31:38.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:63:0)
[00:31:38.712] Timer tick, asking Buildbucket for the build status
[00:31:39.104] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:39.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:64:0) after 1m0s
[00:32:39.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:64:0)
[00:32:39.125] Timer tick, asking Buildbucket for the build status
[00:32:39.247] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:39.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:65:0) after 1m0s
[00:33:39.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:65:0)
[00:33:39.344] Timer tick, asking Buildbucket for the build status
[00:33:39.458] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:39.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:66:0) after 1m0s
[00:34:39.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:66:0)
[00:34:39.467] Timer tick, asking Buildbucket for the build status
[00:34:40.348] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:40.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:67:0) after 1m0s
[00:35:40.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:67:0)
[00:35:40.412] Timer tick, asking Buildbucket for the build status
[00:35:40.599] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:40.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:68:0) after 1m0s
[00:36:40.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:68:0)
[00:36:40.628] Timer tick, asking Buildbucket for the build status
[00:36:41.312] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:41.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:69:0) after 1m0s
[00:37:41.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:69:0)
[00:37:41.341] Timer tick, asking Buildbucket for the build status
[00:37:41.410] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:41.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:70:0) after 1m0s
[00:38:41.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:70:0)
[00:38:41.459] Timer tick, asking Buildbucket for the build status
[00:38:41.534] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:41.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:71:0) after 1m0s
[00:39:41.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:71:0)
[00:39:41.567] Timer tick, asking Buildbucket for the build status
[00:39:42.073] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:42.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:72:0) after 1m0s
[00:40:42.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:72:0)
[00:40:42.175] Timer tick, asking Buildbucket for the build status
[00:40:42.494] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:42.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:73:0) after 1m0s
[00:41:42.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:73:0)
[00:41:42.549] Timer tick, asking Buildbucket for the build status
[00:41:42.672] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:42.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:74:0) after 1m0s
[00:42:42.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:74:0)
[00:42:42.841] Timer tick, asking Buildbucket for the build status
[00:42:42.917] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:42.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:75:0) after 1m0s
[00:43:42.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:75:0)
[00:43:42.963] Timer tick, asking Buildbucket for the build status
[00:43:43.213] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:43.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:76:0) after 1m0s
[00:44:43.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:76:0)
[00:44:43.252] Timer tick, asking Buildbucket for the build status
[00:44:43.445] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:43.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:77:0) after 1m0s
[00:45:43.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:77:0)
[00:45:43.501] Timer tick, asking Buildbucket for the build status
[00:45:43.710] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:43.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:78:0) after 1m0s
[00:46:43.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:78:0)
[00:46:43.734] Timer tick, asking Buildbucket for the build status
[00:46:43.941] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:43.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:79:0) after 1m0s
[00:47:43.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:79:0)
[00:47:43.943] Timer tick, asking Buildbucket for the build status
[00:47:44.364] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:44.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:80:0) after 1m0s
[00:48:44.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:80:0)
[00:48:44.456] Timer tick, asking Buildbucket for the build status
[00:48:44.699] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:44.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:81:0) after 1m0s
[00:49:44.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:81:0)
[00:49:44.727] Timer tick, asking Buildbucket for the build status
[00:49:44.800] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:44.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:82:0) after 1m0s
[00:50:44.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:82:0)
[00:50:44.950] Timer tick, asking Buildbucket for the build status
[00:50:45.048] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:83:0) after 1m0s
[00:51:45.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:83:0)
[00:51:45.330] Timer tick, asking Buildbucket for the build status
[00:51:45.742] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:45.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:84:0) after 1m0s
[00:52:45.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:84:0)
[00:52:45.862] Timer tick, asking Buildbucket for the build status
[00:52:45.978] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:45.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:85:0) after 1m0s
[00:53:46.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:85:0)
[00:53:46.029] Timer tick, asking Buildbucket for the build status
[00:53:46.137] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:46.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:86:0) after 1m0s
[00:54:46.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:86:0)
[00:54:46.448] Timer tick, asking Buildbucket for the build status
[00:54:46.720] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:46.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:87:0) after 1m0s
[00:55:46.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:87:0)
[00:55:46.749] Timer tick, asking Buildbucket for the build status
[00:55:46.876] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:46.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:88:0) after 1m0s
[00:56:46.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:88:0)
[00:56:46.872] Timer tick, asking Buildbucket for the build status
[00:56:46.983] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:46.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:89:0) after 1m0s
[00:57:47.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:89:0)
[00:57:47.034] Timer tick, asking Buildbucket for the build status
[00:57:47.124] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:47.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:90:0) after 1m0s
[00:58:47.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:90:0)
[00:58:47.150] Timer tick, asking Buildbucket for the build status
[00:58:47.896] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:47.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:91:0) after 1m0s
[00:59:47.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:91:0)
[00:59:47.963] Timer tick, asking Buildbucket for the build status
[00:59:48.110] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:48.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:92:0) after 1m0s
[01:00:48.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:92:0)
[01:00:48.174] Timer tick, asking Buildbucket for the build status
[01:00:48.366] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:48.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:93:0) after 1m0s
[01:01:48.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:93:0)
[01:01:48.596] Timer tick, asking Buildbucket for the build status
[01:01:49.954] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:49.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:94:0) after 1m0s
[01:02:50.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:94:0)
[01:02:50.102] Timer tick, asking Buildbucket for the build status
[01:02:50.442] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:50.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:95:0) after 1m0s
[01:03:50.470] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:95:0)
[01:03:50.470] Timer tick, asking Buildbucket for the build status
[01:03:50.842] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:50.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:96:0) after 1m0s
[01:04:50.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:96:0)
[01:04:50.944] Timer tick, asking Buildbucket for the build status
[01:04:51.028] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:51.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:97:0) after 1m0s
[01:05:51.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:97:0)
[01:05:51.068] Timer tick, asking Buildbucket for the build status
[01:05:51.668] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:51.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:98:0) after 1m0s
[01:06:51.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:98:0)
[01:06:51.643] Timer tick, asking Buildbucket for the build status
[01:06:52.017] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:99:0) after 1m0s
[01:07:52.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:99:0)
[01:07:52.010] Timer tick, asking Buildbucket for the build status
[01:07:52.719] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:52.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:100:0) after 1m0s
[01:08:52.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:100:0)
[01:08:52.782] Timer tick, asking Buildbucket for the build status
[01:08:52.859] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:52.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:101:0) after 1m0s
[01:09:53.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:101:0)
[01:09:53.017] Timer tick, asking Buildbucket for the build status
[01:09:53.107] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:53.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:102:0) after 1m0s
[01:10:53.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:102:0)
[01:10:53.405] Timer tick, asking Buildbucket for the build status
[01:10:53.561] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:53.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:103:0) after 1m0s
[01:11:53.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:103:0)
[01:11:53.865] Timer tick, asking Buildbucket for the build status
[01:11:53.944] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:53.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:104:0) after 1m0s
[01:12:54.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:104:0)
[01:12:54.118] Timer tick, asking Buildbucket for the build status
[01:12:54.202] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:105:0) after 1m0s
[01:13:54.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:105:0)
[01:13:54.252] Timer tick, asking Buildbucket for the build status
[01:13:54.444] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:54.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:106:0) after 1m0s
[01:14:54.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:106:0)
[01:14:54.480] Timer tick, asking Buildbucket for the build status
[01:14:54.811] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:54.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:107:0) after 1m0s
[01:15:54.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:107:0)
[01:15:54.837] Timer tick, asking Buildbucket for the build status
[01:15:55.202] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:55.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:108:0) after 1m0s
[01:16:55.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:108:0)
[01:16:55.333] Timer tick, asking Buildbucket for the build status
[01:16:55.457] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:55.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:109:0) after 1m0s
[01:17:55.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:109:0)
[01:17:55.469] Timer tick, asking Buildbucket for the build status
[01:17:55.813] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:55.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:110:0) after 1m0s
[01:18:55.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:110:0)
[01:18:55.842] Timer tick, asking Buildbucket for the build status
[01:18:55.956] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:55.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:111:0) after 1m0s
[01:19:55.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:111:0)
[01:19:55.986] Timer tick, asking Buildbucket for the build status
[01:19:56.111] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:56.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:112:0) after 1m0s
[01:20:56.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:112:0)
[01:20:56.137] Timer tick, asking Buildbucket for the build status
[01:20:56.411] Build 8863975743901516736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:56.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029405808634626880:113:0) after 1m0s
[01:21:49.624] Received PubSub notification, asking Buildbucket for the build status
[01:21:49.774] Build 8863975743901516736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:21:49.774] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605057707267898",
"created_by": "project:chromium",
"created_ts": "1605051014001857",
"id": "8863975743901516736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\"}}",
"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\": \"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\", \"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-102-p9wv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21244, \"got_angle_revision\": \"98035e11d907c2dadd8d5aaa2309944c7a2d0bd3\", \"got_dawn_revision\": \"c04a0132be6c384c90bd33b06929a9b7fc5d4726\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\", \"got_revision_cp\": \"refs/heads/master@{#826009}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536\", \"swarm_hashes_refs/heads/master(at){#826009}_without_patch\": {\"absl_hardening_tests\": \"c59733428a0104ce1071318942f55ca6172a246d\", \"accessibility_unittests\": \"dcd7e5a7999580370d3665c060d0b574fe4eed7e\", \"angle_unittests\": \"2806842f8e07f5276f6efca136032ab53f63029c\", \"app_list_unittests\": \"dabc7e137340b8d1391b06352acb46fb2d1af85b\", \"app_shell_unittests\": \"66b227334f891fa42dc8c705401b7b04a3ade1e6\", \"ash_unittests\": \"a5acb04d9f238f8636134ffa5b798c7091768389\", \"aura_unittests\": \"69142d843c6665a119dd6fca88c2bc628cd0d0b9\", \"base_unittests\": \"8bbe3e5252070f57c10c806129384ed1cdde3480\", \"base_util_unittests\": \"c6cb10360626d7544efe279a8166bd91eda6e8c9\", \"blink_common_unittests\": \"82f2e25d5a8c4b9392d9a280c0fc57c838bc1f9c\", \"blink_fuzzer_unittests\": \"d85640d42f37baa09ff535c97ad0895f18e67494\", \"blink_heap_unittests\": \"74dc81f3c1688c99aeccc28c335e72f24d531ab9\", \"blink_platform_unittests\": \"1984d3b0530762af9fd1a1c92101522f465488c0\", \"blink_unittests\": \"3ce136250e53517bdba34e1b9f47c1b5d843e314\", \"boringssl_crypto_tests\": \"e391415d002b3bad2566a400d460cd0fc43b0377\", \"boringssl_ssl_tests\": \"ec71918d72101b1098e15c54906217c9ffff1b02\", \"browser_tests\": \"a80475319271eea9ba76bbe21ceb3e649910bf24\", \"capture_unittests\": \"510c3b56396995e38ebcbe0f1f2217264f57d1db\", \"cast_unittests\": \"3edf16e450b5168861c0847f81798155bd7a4768\", \"cc_unittests\": \"5aa36085ee0bb967016fea800ce05d02b9da1420\", \"chrome_app_unittests\": \"89cb09c3f7c014efcbc7f56f98f097a3f2f7e2e1\", \"chromedriver_unittests\": \"185ae15139316f3b003041648ae3a8697d858257\", \"chromeos_components_unittests\": \"30a148a814a94480f3f327275fb5f7360139d540\", \"chromeos_unittests\": \"9fd9ca8da9f8f5a2bbcd47992d5b013e28464af6\", \"components_browsertests\": \"0e2c9ee8ccc96a3997a1befeb01cd18958bc1a4d\", \"components_unittests\": \"a230155516ba6d44c0ddec28dd1175140472e174\", \"compositor_unittests\": \"1748b4ae5ebc1eda64a386f81aa19137cac95f50\", \"content_browsertests\": \"c4afd218a55b848ad040606ab87adaadff398a48\", \"content_unittests\": \"8ad513edb6aa52c32f972ba441465f7a6565555d\", \"crypto_unittests\": \"ed875f894356314fd0526600a605da2f9d48a956\", \"dbus_unittests\": \"f664ec2d6672dfde85ef901734380c0fea7f1c4f\", \"device_unittests\": \"a3becf1d511be3a24706f871ee6944695d5550c3\", \"display_unittests\": \"40b63cafbaa1802174f4ed81c32223314edc21db\", \"events_unittests\": \"1e2e3dcb38e403e1aa9e5e67df0008974993aa77\", \"exo_unittests\": \"5ad7f5298948e2277990eb02dbef6784ff8ff197\", \"extensions_browsertests\": \"62c2bc76e1262d6193374a0e0db6cdff103f2a7e\", \"extensions_unittests\": \"5c9df7824abf394afbef990bbe231e396b27b4ad\", \"filesystem_service_unittests\": \"3fd54c6afe0dae7abe300144054a5ed29e3b1675\", \"gcm_unit_tests\": \"7c3bb7e65be037b1e4389fc1eea40d59753a70d7\", \"gfx_unittests\": \"ca125fed6ca2378677c207c7f93470b99cb6879f\", \"gin_unittests\": \"aa84dc4099460e58ab4c46f22aac6d2398d47bbb\", \"gl_unittests_ozone\": \"1fa3df138bb771744e02c061090d91a859a3a205\", \"google_apis_unittests\": \"4cca7643435ed553469abe1e9b657d1d2373e204\", \"gpu_unittests\": \"9bad22253c55217790e0ec5f09d5e0404601d34e\", \"gwp_asan_unittests\": \"6d8f4244858279450506cd684c3c327078c3f7bb\", \"interactive_ui_tests\": \"7273472d46a57f76c052fae3b9660ff013607558\", \"ipc_tests\": \"968a4de9814f904e63fc93a97e21b19ea7a8e0a9\", \"jingle_unittests\": \"8ed5ff3ea1c12ad1594ff5e6763bd066c56a649c\", \"keyboard_unittests\": \"fdf7659b5cbb58311044ed505839874ce48d4e96\", \"latency_unittests\": \"ee895111fa34710904fad494e79e886099a134e7\", \"libjingle_xmpp_unittests\": \"412a7c2123c3cd8281de2e71b1d197b525ea32ed\", \"liburlpattern_unittests\": \"8aa3d5466d7422566310d641d204c3fa1e04b87a\", \"media_blink_unittests\": \"12b02f248350b33605807d510d75599919d20506\", \"media_unittests\": \"c9b90e6ccc9b654e2c7e26746ac4887445b59943\", \"message_center_unittests\": \"70a9c7196b4f476e6323e78ac7dc9f22a113afc8\", \"midi_unittests\": \"aec83a3c1b8731b482f265172a64fd2fc284fcc5\", \"mojo_core_unittests\": \"857c3d4df171be3f29065b29aa14b32c202dc712\", \"mojo_unittests\": \"7fad2bc360dba402679d2234e67a8effb5185c7b\", \"nacl_helper_nonsfi_unittests\": \"c9ea64b65ff06621a1354a40d26c95491fbab5b8\", \"nacl_loader_unittests\": \"f6edbffd51b226a69db1e935e2343c00aaff5b3e\", \"native_theme_unittests\": \"d048fffb61a82d886838abee3a487d87c1b07f52\", \"net_unittests\": \"aa66ee66238f8529478a1dc8516e678d9eb1f796\", \"ozone_gl_unittests\": \"f82d47cc7515eec8a7a5f56a70702011e87469e0\", \"ozone_unittests\": \"6438af292372911c3299f8d4770d76de4f085a9c\", \"ozone_x11_unittests\": \"7b0fb8e69a1b710d5f7b2c21a0e724eaed7d1602\", \"pdf_unittests\": \"7933dd756bf3365742dea62aa9d7e4b5946d50e9\", \"perfetto_unittests\": \"9fb61c45cc2d8f89c47b941c3d27e23cb73e2a90\", \"ppapi_unittests\": \"c3518642ffcdb8bc18f507e9b973dec3b66118d7\", \"printing_unittests\": \"ceafe264d5347f45fa5b2312edb2f063698524a5\", \"remoting_unittests\": \"59993f0b0314e7cb43638b36a8cdb2ed0e7c1ac4\", \"sandbox_linux_unittests\": \"84ea6b27135f093144a1f0a8213b76f63f72ef30\", \"service_manager_unittests\": \"1cd6f33b226b8f1f572e003f6e82b51a0334204d\", \"services_unittests\": \"c203a9ac79a1190dcc7d9cba3159dab28808752a\", \"shell_dialogs_unittests\": \"9fda9f52bad2d73d2d533dafc27452be2de30186\", \"skia_unittests\": \"7bfa192c64b6f284ac70be31b6794ae18ad96c95\", \"snapshot_unittests\": \"b07074eefb032adbf1516dd915bd12ac6732b2a6\", \"sql_unittests\": \"03d9e3e4d0276884c7b61d6c5f53e98ea942b80f\", \"storage_unittests\": \"f6f6bd9809055c559cf2b242799536d49ab3c595\", \"sync_integration_tests\": \"8857cae14438b3dabec658e0f54ee7b7a5e8b03a\", \"traffic_annotation_auditor_unittests\": \"68f2f6d7415683ef9fe5d7b0372c85aff0abdae4\", \"ui_base_unittests\": \"21528b2915045dc3cd2bc36e63a2eb8c959bbc16\", \"ui_chromeos_unittests\": \"8c90c9fb08aa003755d1ceb111285d1912ff1169\", \"ui_touch_selection_unittests\": \"5c35d3bcd512e06ebdd7920a8c121730f952f4de\", \"unit_tests\": \"8700d58b45caa8994b8baab911ff856b5bc1d7b4\", \"url_unittests\": \"4be156a9c0e343a176c024f3b3cfa539a7f666d0\", \"usage_time_limit_unittests\": \"3164e4cfe1fe1785ad1fdf9659fbc8987997f20a\", \"views_unittests\": \"7bcb1ddc2c82fcfb09dfa69fa5695fcfe4e93840\", \"viz_unittests\": \"5556e43bda3ac8da0af3379356b7b44cc6dfb779\", \"wm_unittests\": \"b7290ad136ad55cdbb837c40dbb5f62418567c36\", \"wtf_unittests\": \"0dfd62f04e5337b92e279a923843128342000cfb\", \"zlib_unittests\": \"e5d7a817f97450fd7a280e1193eea6ff26a6da0d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_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-102-p9wv\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605051030151345",
"status": "COMPLETED",
"status_changed_ts": "1605057707846678",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21244",
"builder:linux-chromeos-dbg",
"buildset:commit/git/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/016a2d8f0fc59dadd1e0830f7b3fc6f1b6289536",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029405808634626880",
"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/8863975743901516736/+/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:4fcd50794fc34a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605057707855916",
"url": "https://ci.chromium.org/b/8863975743901516736",
"utcnow_ts": "1605057709755420"
}
}
[01:21:49.774] Invocation finished in 1h51m39.67143393s with status SUCCEEDED