[22:02:48.269] New invocation is queued and will start shortly
[22:02:50.579] Starting the invocation (attempt 1)
[22:02:50.714] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:02:50.715] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:02:50.715] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027327574891565376",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d034cb9684581c1db6a026e13dfc3995687ec179\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9027327574891565376",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/d034cb9684581c1db6a026e13dfc3995687ec179",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d034cb9684581c1db6a026e13dfc3995687ec179",
"gitiles_ref:refs/heads/master"
]
}
[22:02:52.584] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607032970950532",
"id": "8861897511411601920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d034cb9684581c1db6a026e13dfc3995687ec179\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607032971372306",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21592",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d034cb9684581c1db6a026e13dfc3995687ec179",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d034cb9684581c1db6a026e13dfc3995687ec179",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027327574891565376",
"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/8861897511411601920/+/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": "1607032971372483",
"url": "https://ci.chromium.org/b/8861897511411601920",
"utcnow_ts": "1607032972571918"
}
}
[22:02:52.584] Task URL: https://ci.chromium.org/b/8861897511411601920
[22:02:52.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:2:0) after 1m0s
[22:03:52.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:2:0)
[22:03:52.689] Timer tick, asking Buildbucket for the build status
[22:03:54.184] Build 8861897511411601920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:54.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:3:0) after 1m0s
[22:04:54.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:3:0)
[22:04:54.257] Timer tick, asking Buildbucket for the build status
[22:04:54.499] Build 8861897511411601920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:54.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:4:0) after 1m0s
[22:05:17.365] Received PubSub notification, asking Buildbucket for the build status
[22:05:18.418] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:54.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:4:0)
[22:05:54.600] Timer tick, asking Buildbucket for the build status
[22:05:54.912] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:54.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:6:0) after 1m0s
[22:06:54.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:6:0)
[22:06:54.991] Timer tick, asking Buildbucket for the build status
[22:06:55.167] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:55.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:7:0) after 1m0s
[22:07:55.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:7:0)
[22:07:55.259] Timer tick, asking Buildbucket for the build status
[22:07:55.356] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:55.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:8:0) after 1m0s
[22:08:55.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:8:0)
[22:08:55.419] Timer tick, asking Buildbucket for the build status
[22:08:55.597] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:55.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:9:0) after 1m0s
[22:09:55.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:9:0)
[22:09:55.617] Timer tick, asking Buildbucket for the build status
[22:09:55.740] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:55.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:10:0) after 1m0s
[22:10:55.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:10:0)
[22:10:55.791] Timer tick, asking Buildbucket for the build status
[22:10:55.996] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:55.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:11:0) after 1m0s
[22:11:56.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:11:0)
[22:11:56.057] Timer tick, asking Buildbucket for the build status
[22:11:56.807] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:56.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:12:0) after 1m0s
[22:12:56.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:12:0)
[22:12:56.891] Timer tick, asking Buildbucket for the build status
[22:12:56.998] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:56.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:13:0) after 1m0s
[22:13:57.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:13:0)
[22:13:57.151] Timer tick, asking Buildbucket for the build status
[22:13:57.778] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:57.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:14:0) after 1m0s
[22:14:57.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:14:0)
[22:14:57.911] Timer tick, asking Buildbucket for the build status
[22:14:58.815] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:58.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:15:0) after 1m0s
[22:15:58.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:15:0)
[22:15:58.847] Timer tick, asking Buildbucket for the build status
[22:15:58.992] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:58.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:16:0) after 1m0s
[22:16:59.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:16:0)
[22:16:59.066] Timer tick, asking Buildbucket for the build status
[22:16:59.438] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:59.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:17:0) after 1m0s
[22:17:59.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:17:0)
[22:17:59.479] Timer tick, asking Buildbucket for the build status
[22:17:59.551] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:59.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:18:0) after 1m0s
[22:18:59.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:18:0)
[22:18:59.599] Timer tick, asking Buildbucket for the build status
[22:18:59.898] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:59.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:19:0) after 1m0s
[22:19:59.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:19:0)
[22:19:59.944] Timer tick, asking Buildbucket for the build status
[22:20:00.163] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:00.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:20:0) after 1m0s
[22:21:00.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:20:0)
[22:21:00.183] Timer tick, asking Buildbucket for the build status
[22:21:00.439] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:00.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:21:0) after 1m0s
[22:22:00.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:21:0)
[22:22:00.465] Timer tick, asking Buildbucket for the build status
[22:22:00.530] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:00.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:22:0) after 1m0s
[22:23:00.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:22:0)
[22:23:00.576] Timer tick, asking Buildbucket for the build status
[22:23:00.639] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:00.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:23:0) after 1m0s
[22:24:00.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:23:0)
[22:24:00.659] Timer tick, asking Buildbucket for the build status
[22:24:00.784] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:00.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:24:0) after 1m0s
[22:25:00.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:24:0)
[22:25:00.819] Timer tick, asking Buildbucket for the build status
[22:25:00.908] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:00.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:25:0) after 1m0s
[22:26:02.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:25:0)
[22:26:02.594] Timer tick, asking Buildbucket for the build status
[22:26:02.935] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:02.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:26:0) after 1m0s
[22:27:02.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:26:0)
[22:27:02.979] Timer tick, asking Buildbucket for the build status
[22:27:03.756] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:03.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:27:0) after 1m0s
[22:28:03.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:27:0)
[22:28:03.846] Timer tick, asking Buildbucket for the build status
[22:28:04.004] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:04.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:28:0) after 1m0s
[22:29:04.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:28:0)
[22:29:04.059] Timer tick, asking Buildbucket for the build status
[22:29:04.233] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:04.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:29:0) after 1m0s
[22:30:04.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:29:0)
[22:30:04.255] Timer tick, asking Buildbucket for the build status
[22:30:05.444] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:05.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:30:0) after 1m0s
[22:31:05.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:30:0)
[22:31:05.658] Timer tick, asking Buildbucket for the build status
[22:31:05.727] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:05.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:31:0) after 1m0s
[22:32:05.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:31:0)
[22:32:05.814] Timer tick, asking Buildbucket for the build status
[22:32:05.908] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:05.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:32:0) after 1m0s
[22:33:05.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:32:0)
[22:33:05.996] Timer tick, asking Buildbucket for the build status
[22:33:06.318] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:06.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:33:0) after 1m0s
[22:34:06.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:33:0)
[22:34:06.318] Timer tick, asking Buildbucket for the build status
[22:34:06.601] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:06.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:34:0) after 1m0s
[22:35:06.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:34:0)
[22:35:06.830] Timer tick, asking Buildbucket for the build status
[22:35:06.946] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:06.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:35:0) after 1m0s
[22:36:06.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:35:0)
[22:36:06.942] Timer tick, asking Buildbucket for the build status
[22:36:07.270] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:07.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:36:0) after 1m0s
[22:37:07.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:36:0)
[22:37:07.404] Timer tick, asking Buildbucket for the build status
[22:37:07.494] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:37:0) after 1m0s
[22:38:07.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:37:0)
[22:38:07.557] Timer tick, asking Buildbucket for the build status
[22:38:07.718] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:07.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:38:0) after 1m0s
[22:39:07.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:38:0)
[22:39:07.747] Timer tick, asking Buildbucket for the build status
[22:39:07.830] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:07.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:39:0) after 1m0s
[22:40:07.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:39:0)
[22:40:07.862] Timer tick, asking Buildbucket for the build status
[22:40:07.981] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:07.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:40:0) after 1m0s
[22:41:07.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:40:0)
[22:41:07.987] Timer tick, asking Buildbucket for the build status
[22:41:08.157] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:08.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:41:0) after 1m0s
[22:42:08.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:41:0)
[22:42:08.234] Timer tick, asking Buildbucket for the build status
[22:42:08.491] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:08.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:42:0) after 1m0s
[22:43:08.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:42:0)
[22:43:08.548] Timer tick, asking Buildbucket for the build status
[22:43:08.723] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:43:0) after 1m0s
[22:44:08.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:43:0)
[22:44:08.781] Timer tick, asking Buildbucket for the build status
[22:44:08.863] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:08.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:44:0) after 1m0s
[22:45:08.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:44:0)
[22:45:08.894] Timer tick, asking Buildbucket for the build status
[22:45:08.963] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:08.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:45:0) after 1m0s
[22:46:09.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:45:0)
[22:46:09.048] Timer tick, asking Buildbucket for the build status
[22:46:09.118] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:09.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:46:0) after 1m0s
[22:47:09.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:46:0)
[22:47:09.188] Timer tick, asking Buildbucket for the build status
[22:47:09.247] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:09.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:47:0) after 1m0s
[22:48:09.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:47:0)
[22:48:09.487] Timer tick, asking Buildbucket for the build status
[22:48:09.589] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:09.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:48:0) after 1m0s
[22:49:10.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:48:0)
[22:49:10.057] Timer tick, asking Buildbucket for the build status
[22:49:10.197] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:10.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:49:0) after 1m0s
[22:50:12.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:49:0)
[22:50:12.233] Timer tick, asking Buildbucket for the build status
[22:50:12.502] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:12.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:50:0) after 1m0s
[22:51:12.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:50:0)
[22:51:12.901] Timer tick, asking Buildbucket for the build status
[22:51:13.010] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:13.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:51:0) after 1m0s
[22:52:13.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:51:0)
[22:52:13.511] Timer tick, asking Buildbucket for the build status
[22:52:13.737] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:13.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:52:0) after 1m0s
[22:53:14.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:52:0)
[22:53:14.048] Timer tick, asking Buildbucket for the build status
[22:53:14.259] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:14.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:53:0) after 1m0s
[22:54:14.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:53:0)
[22:54:14.485] Timer tick, asking Buildbucket for the build status
[22:54:15.494] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:15.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:54:0) after 1m0s
[22:55:16.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:54:0)
[22:55:16.489] Timer tick, asking Buildbucket for the build status
[22:55:16.777] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:16.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:55:0) after 1m0s
[22:56:16.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:55:0)
[22:56:16.834] Timer tick, asking Buildbucket for the build status
[22:56:17.192] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:56:0) after 1m0s
[22:57:17.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:56:0)
[22:57:17.200] Timer tick, asking Buildbucket for the build status
[22:57:17.316] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:17.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:57:0) after 1m0s
[22:58:17.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:57:0)
[22:58:17.757] Timer tick, asking Buildbucket for the build status
[22:58:18.303] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:18.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:58:0) after 1m0s
[22:59:18.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:58:0)
[22:59:18.536] Timer tick, asking Buildbucket for the build status
[22:59:18.770] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:18.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:59:0) after 1m0s
[23:00:18.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:59:0)
[23:00:18.867] Timer tick, asking Buildbucket for the build status
[23:00:19.571] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:19.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:60:0) after 1m0s
[23:01:19.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:60:0)
[23:01:19.867] Timer tick, asking Buildbucket for the build status
[23:01:20.034] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:20.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:61:0) after 1m0s
[23:02:20.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:61:0)
[23:02:20.415] Timer tick, asking Buildbucket for the build status
[23:02:20.545] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:20.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:62:0) after 1m0s
[23:03:20.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:62:0)
[23:03:20.949] Timer tick, asking Buildbucket for the build status
[23:03:21.220] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:21.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:63:0) after 1m0s
[23:04:21.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:63:0)
[23:04:21.389] Timer tick, asking Buildbucket for the build status
[23:04:23.003] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:23.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:64:0) after 1m0s
[23:05:23.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:64:0)
[23:05:23.629] Timer tick, asking Buildbucket for the build status
[23:05:23.759] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:23.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:65:0) after 1m0s
[23:06:24.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:65:0)
[23:06:24.001] Timer tick, asking Buildbucket for the build status
[23:06:24.084] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:24.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:66:0) after 1m0s
[23:07:24.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:66:0)
[23:07:24.086] Timer tick, asking Buildbucket for the build status
[23:07:24.612] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:24.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:67:0) after 1m0s
[23:08:24.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:67:0)
[23:08:24.940] Timer tick, asking Buildbucket for the build status
[23:08:25.085] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:25.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:68:0) after 1m0s
[23:09:25.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:68:0)
[23:09:25.270] Timer tick, asking Buildbucket for the build status
[23:09:25.397] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:25.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:69:0) after 1m0s
[23:10:25.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:69:0)
[23:10:25.525] Timer tick, asking Buildbucket for the build status
[23:10:28.820] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:28.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:70:0) after 1m0s
[23:11:29.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:70:0)
[23:11:29.447] Timer tick, asking Buildbucket for the build status
[23:11:29.593] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:71:0) after 1m0s
[23:12:30.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:71:0)
[23:12:30.046] Timer tick, asking Buildbucket for the build status
[23:12:30.260] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:30.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:72:0) after 1m0s
[23:13:30.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:72:0)
[23:13:30.455] Timer tick, asking Buildbucket for the build status
[23:13:30.572] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:30.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:73:0) after 1m0s
[23:14:30.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:73:0)
[23:14:30.702] Timer tick, asking Buildbucket for the build status
[23:14:30.911] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:30.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:74:0) after 1m0s
[23:15:31.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:74:0)
[23:15:31.024] Timer tick, asking Buildbucket for the build status
[23:15:31.233] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:31.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:75:0) after 1m0s
[23:16:31.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:75:0)
[23:16:31.260] Timer tick, asking Buildbucket for the build status
[23:16:31.394] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:31.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:76:0) after 1m0s
[23:17:31.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:76:0)
[23:17:31.426] Timer tick, asking Buildbucket for the build status
[23:17:31.555] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:31.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:77:0) after 1m0s
[23:18:31.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:77:0)
[23:18:31.594] Timer tick, asking Buildbucket for the build status
[23:18:31.887] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:31.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:78:0) after 1m0s
[23:19:31.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:78:0)
[23:19:31.920] Timer tick, asking Buildbucket for the build status
[23:19:32.054] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:32.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:79:0) after 1m0s
[23:20:32.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:79:0)
[23:20:32.074] Timer tick, asking Buildbucket for the build status
[23:20:32.935] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:32.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:80:0) after 1m0s
[23:21:33.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:80:0)
[23:21:33.045] Timer tick, asking Buildbucket for the build status
[23:21:33.170] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:33.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:81:0) after 1m0s
[23:22:33.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:81:0)
[23:22:33.162] Timer tick, asking Buildbucket for the build status
[23:22:33.410] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:33.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:82:0) after 1m0s
[23:23:33.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:82:0)
[23:23:33.604] Timer tick, asking Buildbucket for the build status
[23:23:34.965] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:34.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:83:0) after 1m0s
[23:24:35.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:83:0)
[23:24:35.019] Timer tick, asking Buildbucket for the build status
[23:24:35.746] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:35.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:84:0) after 1m0s
[23:25:35.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:84:0)
[23:25:35.806] Timer tick, asking Buildbucket for the build status
[23:25:36.137] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:36.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:85:0) after 1m0s
[23:26:36.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:85:0)
[23:26:36.241] Timer tick, asking Buildbucket for the build status
[23:26:36.383] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:36.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:86:0) after 1m0s
[23:27:36.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:86:0)
[23:27:36.479] Timer tick, asking Buildbucket for the build status
[23:27:36.633] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:36.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:87:0) after 1m0s
[23:28:36.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:87:0)
[23:28:36.696] Timer tick, asking Buildbucket for the build status
[23:28:36.921] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:36.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:88:0) after 1m0s
[23:29:36.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:88:0)
[23:29:36.981] Timer tick, asking Buildbucket for the build status
[23:29:37.325] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:37.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:89:0) after 1m0s
[23:30:37.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:89:0)
[23:30:37.399] Timer tick, asking Buildbucket for the build status
[23:30:37.511] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:90:0) after 1m0s
[23:31:37.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:90:0)
[23:31:37.540] Timer tick, asking Buildbucket for the build status
[23:31:37.630] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:37.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:91:0) after 1m0s
[23:32:37.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:91:0)
[23:32:37.661] Timer tick, asking Buildbucket for the build status
[23:32:38.023] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:92:0) after 1m0s
[23:33:38.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:92:0)
[23:33:38.048] Timer tick, asking Buildbucket for the build status
[23:33:38.195] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:93:0) after 1m0s
[23:34:38.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:93:0)
[23:34:38.471] Timer tick, asking Buildbucket for the build status
[23:34:38.602] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:38.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:94:0) after 1m0s
[23:35:38.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:94:0)
[23:35:38.710] Timer tick, asking Buildbucket for the build status
[23:35:38.784] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:38.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:95:0) after 1m0s
[23:36:39.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:95:0)
[23:36:39.239] Timer tick, asking Buildbucket for the build status
[23:36:39.316] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:39.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:96:0) after 1m0s
[23:37:40.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:96:0)
[23:37:40.027] Timer tick, asking Buildbucket for the build status
[23:37:40.263] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:40.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:97:0) after 1m0s
[23:38:40.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:97:0)
[23:38:40.370] Timer tick, asking Buildbucket for the build status
[23:38:40.489] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:98:0) after 1m0s
[23:39:40.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:98:0)
[23:39:40.621] Timer tick, asking Buildbucket for the build status
[23:39:40.698] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:40.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:99:0) after 1m0s
[23:40:40.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:99:0)
[23:40:40.793] Timer tick, asking Buildbucket for the build status
[23:40:40.863] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:40.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:100:0) after 1m0s
[23:41:41.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:100:0)
[23:41:41.029] Timer tick, asking Buildbucket for the build status
[23:41:41.097] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:101:0) after 1m0s
[23:42:41.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:101:0)
[23:42:41.272] Timer tick, asking Buildbucket for the build status
[23:42:41.439] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:41.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:102:0) after 1m0s
[23:43:41.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:102:0)
[23:43:41.506] Timer tick, asking Buildbucket for the build status
[23:43:43.277] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:43.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:103:0) after 1m0s
[23:44:43.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:103:0)
[23:44:43.373] Timer tick, asking Buildbucket for the build status
[23:44:43.456] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:43.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:104:0) after 1m0s
[23:45:43.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:104:0)
[23:45:43.568] Timer tick, asking Buildbucket for the build status
[23:45:45.451] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:45.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:105:0) after 1m0s
[23:46:45.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:105:0)
[23:46:45.556] Timer tick, asking Buildbucket for the build status
[23:46:45.703] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:45.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:106:0) after 1m0s
[23:47:45.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:106:0)
[23:47:45.904] Timer tick, asking Buildbucket for the build status
[23:47:46.029] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:46.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:107:0) after 1m0s
[23:48:46.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:107:0)
[23:48:46.086] Timer tick, asking Buildbucket for the build status
[23:48:46.224] Build 8861897511411601920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:46.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9027327574891565376:108:0) after 1m0s
[23:49:35.348] Received PubSub notification, asking Buildbucket for the build status
[23:49:35.443] Build 8861897511411601920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:49:35.443] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607039372836338",
"created_by": "project:chromium",
"created_ts": "1607032970950532",
"id": "8861897511411601920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d034cb9684581c1db6a026e13dfc3995687ec179\"}}",
"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\": \"d034cb9684581c1db6a026e13dfc3995687ec179\", \"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-0-b447\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21592, \"got_angle_revision\": \"19a302fca592e3825998b78b7d7d075c51f3c4f6\", \"got_dawn_revision\": \"10cb17e079e14f4f9a1bdaea9b46b343c82c4679\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d034cb9684581c1db6a026e13dfc3995687ec179\", \"got_revision_cp\": \"refs/heads/master@{#833471}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"8c8f17af29f1a4073630ba7ca924ddb4ca45abd6\", \"got_v8_revision_cp\": \"refs/heads/8.9.94@{#1}\", \"got_webrtc_revision\": \"10f76ac226f2b569924a5060d4dd2ca1231aa30a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32760}\", \"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\": \"d034cb9684581c1db6a026e13dfc3995687ec179\", \"swarm_hashes_refs/heads/master(at){#833471}_without_patch\": {\"absl_hardening_tests\": \"f7f50b3d605dcf121b4670fb1c429f6cfa2a0bb7\", \"accessibility_unittests\": \"0c7a96be541d78bb19c5542f2bc24e66aa1e6b9b\", \"angle_unittests\": \"bce75d55f1048472c744f7690c7ae01267771b8b\", \"app_list_unittests\": \"a185f839a6dc8d0f75289d64dc6aa9d5bd445c5a\", \"app_shell_unittests\": \"7ea2cb9afc0d0458148b0a6890cfd2dce1b412fa\", \"ash_unittests\": \"748d61cfec98320437486256d9eef2ce5bd3338c\", \"aura_unittests\": \"ca0e9f1ec413c9a9f4f2eddcadc3ddf56a2d17ff\", \"base_unittests\": \"635bb1db92c44bae823e9fda98e92b79acd9b8b5\", \"base_util_unittests\": \"ea17fc02a416b672e6b1fb5e89a8fead83044524\", \"blink_common_unittests\": \"d32cf6827efd604300121f1bc570cdb9121feba3\", \"blink_fuzzer_unittests\": \"17dfb2b74b931d39e87f618cbfcfc5917795b3c1\", \"blink_heap_unittests\": \"a4a9595607a4469315cd4c80d4349a0c7ada612d\", \"blink_platform_unittests\": \"7415b9248b5652098e55afaa1f2b6b43056b24f3\", \"blink_unittests\": \"0f4f8e807cfc4b8dfa143810a9043efdd3361f4e\", \"boringssl_crypto_tests\": \"20827dfe477ca340b4ea2263a17d8f3d892fade7\", \"boringssl_ssl_tests\": \"da2fe3f518aeef4c1cb9bf2d6a73b3cb659baea0\", \"browser_tests\": \"6c74d0ff848e43e7d2bba3e8a2525497f451fd7d\", \"capture_unittests\": \"aa35317d73a134179845b792f8a95ae0f52dc5c2\", \"cast_unittests\": \"7d39af5d03073d5301262f5304bc7be3b02d8397\", \"cc_unittests\": \"3e329e1d109e0bcd8dc4b8f83e4400226f98d115\", \"chrome_app_unittests\": \"a3e401ba8c7383e4a973afaa93aadd1f88475c04\", \"chromedriver_unittests\": \"6fa4e8134139d7ca31075c318b7de8b102ff9257\", \"chromeos_components_unittests\": \"01e582687568dbee35c3db1522a5f393467b4540\", \"chromeos_unittests\": \"81980b146554c242d9701bb9feb6f0c839c64afd\", \"components_browsertests\": \"bbec1d2e610068a4b27cf1fea6234a1ee8c29a8f\", \"components_unittests\": \"fb3a9437a7e248e3f880acad2ac717325fee02e2\", \"compositor_unittests\": \"e7345e7444373650d3a3a153afa72d75ad506281\", \"content_browsertests\": \"da472ce72d266858cd8d1cfbf4149bc63249b7cb\", \"content_unittests\": \"42a364394e884460d09a12d969f6752a262bb466\", \"crashpad_tests\": \"4adba419312f492ce2c71301a405aea9fc02984d\", \"crypto_unittests\": \"b6df7fa400ff4f56f2ab4be26e4c03caeb524455\", \"dbus_unittests\": \"71ab6129646c1cc5be2f0c880a348eaa87d2780d\", \"device_unittests\": \"1a5d01b6a590d77cc79131da610e20a34ad01f99\", \"display_unittests\": \"07e2c92485a171a355316c7ec1f935513ed554b1\", \"events_unittests\": \"fb29ede94fea71f93a5b106f36bde7b8d5e8d5b6\", \"exo_unittests\": \"1798abcfc315109b73b2ea1912d36dcabb439400\", \"extensions_browsertests\": \"836e4cf451d372c521e1257ce8f51a5de8f5c90c\", \"extensions_unittests\": \"1860ff33df8c56548f3235abbb0872683fb238bd\", \"filesystem_service_unittests\": \"b39dedb27fd8fba88dbd487c929464238d5ea699\", \"gcm_unit_tests\": \"1ca15afa673c841f2d6791ba99d1d92cf8e1b21e\", \"gfx_unittests\": \"64a7f2e33dbbbefcd3036ffbfb33c61719233096\", \"gin_unittests\": \"dc7de6bb71e0130cda7b445e64d7c4c624052d94\", \"gl_unittests_ozone\": \"1955ece346281cc090163b431f142e478a0ea52f\", \"google_apis_unittests\": \"b4e222bc4e4f79daf8945da6aa0e5df01a201f86\", \"gpu_unittests\": \"455108dd56fb912ad668ebc84d85f8982be6536d\", \"gwp_asan_unittests\": \"c41b9e2047d30e2d814f295a39b597313e61743f\", \"interactive_ui_tests\": \"20a877ed652cf32b76e78f7639782e7c6c856dab\", \"ipc_tests\": \"cabb0e22026097cda7fea8a98e13194ae825b1c0\", \"jingle_unittests\": \"dea5829becab766df5c265b1aae374d536340243\", \"keyboard_unittests\": \"8e4b5ba87823b833cda79d52f9579a111dc2775d\", \"latency_unittests\": \"11dd633729e478db2b91ca29392409e2bf33199c\", \"libjingle_xmpp_unittests\": \"65d74a215ccb355c284a524d5dbcd5cc0af7b7a8\", \"liburlpattern_unittests\": \"c898d60b3cbdeab956fa9e815559b9117789fa4f\", \"media_blink_unittests\": \"8c79f3abfcac1bd93cdff3708cac7bef377d421d\", \"media_unittests\": \"dea049817e7fbf8a894719dc66948c0db755e825\", \"message_center_unittests\": \"9433f74ac75da2ee6d917f99d72556f57c113f56\", \"midi_unittests\": \"299c68023ec03b01651b930665a547075f09761b\", \"mojo_core_unittests\": \"c5f7d866dd5339d830d6764ef14224ff5be53ac7\", \"mojo_unittests\": \"c63ee69eb3747be659bad83f8aa6b7f4f41349eb\", \"nacl_helper_nonsfi_unittests\": \"8c75502c9b83f10b3093d3e55f83fb56c81471d8\", \"nacl_loader_unittests\": \"ae9b43b587e1b26e6e32db37065168d0b2ecded3\", \"native_theme_unittests\": \"05ad097f43ce4a098549229ca2e0b1727284872b\", \"net_unittests\": \"bffe689f14505a54b8c93f714b6b201c9cb94c3c\", \"ozone_gl_unittests\": \"3eb8caa76e42bb0f98bced404478000670467aeb\", \"ozone_unittests\": \"4cbc1c66851e14d2c190e4700a0ab1ac24c53e17\", \"ozone_x11_unittests\": \"f3dc6a4108e90a0a3e3b421ac2b66d415fc9f4f5\", \"pdf_unittests\": \"d863ab1e5e23197b1bf047d7fec035dba35b8b9a\", \"perfetto_unittests\": \"67b9fbf717f8d1c3085e3db3cd80b175bd22035c\", \"ppapi_unittests\": \"7e738218455eef1ce366e5fa9c4b3643ebb81c78\", \"printing_unittests\": \"e264311254743d005ff0c23b8ab7c62138f0baa6\", \"remoting_unittests\": \"2b59bbea5d761f790c2e3f5a2ef0da46b808b204\", \"sandbox_linux_unittests\": \"80f3359aa55a7e76d43e6b1d709e826ac7fe81ab\", \"service_manager_unittests\": \"823a7bb3acec582a2218bfc62010bb9a78b209ae\", \"services_unittests\": \"4b09bd4f4bfca1770e2777ad28e970b1dd8afa64\", \"shell_dialogs_unittests\": \"6b6cabe7bf2f251073b7e6bf8c967db7334cac0e\", \"shell_encryption_unittests\": \"b41e4c793f0af099598049dc0562f5594abc22c8\", \"skia_unittests\": \"6d7ef0dcb7236d4a26177111f86e8b7214a435bf\", \"snapshot_unittests\": \"9bc86b40cccaaeea7aadc6387e7fd5c271fb7019\", \"sql_unittests\": \"f1453b8c67a7fdf769b7e49fd0ef5244dd20a877\", \"storage_unittests\": \"f37c6f30f0f20a7570df523358c9f5724d5a420c\", \"sync_integration_tests\": \"9eb4c4b6e9274ddfee3db2e72debb742b489f526\", \"traffic_annotation_auditor_unittests\": \"a044ec0c22f4ab554cb320ac38c643e3cce429ec\", \"ui_base_unittests\": \"a229623a6763e7fdb55fa731d17c870f55b8bb8b\", \"ui_chromeos_unittests\": \"5680649e497e35e17d9cda6df882281dd22f9a51\", \"ui_touch_selection_unittests\": \"e88e6de3bea0964ec145b02c58f928c1dcea0970\", \"unit_tests\": \"db75833a2a7858cd8bc1f77b48b9dc0ee7a10fa6\", \"url_unittests\": \"d27e02e0ffef09ed1568753cfb83df18f032a9b9\", \"usage_time_limit_unittests\": \"65ae44797266db611c91ca8b6aca28fc3aed68c6\", \"views_unittests\": \"aad3eaaf1585abb0451a914af8c6a87ade0f849a\", \"viz_unittests\": \"0ff61f07b692be907201d8b69bdcf0e59e8c0187\", \"wm_unittests\": \"45d9fadd2a8e97546fce520e14e552fbe041852a\", \"wtf_unittests\": \"ca7bb0cb2396b8a5b71312270bea0b110c2eb454\", \"zlib_unittests\": \"13f55ad659f25a4c0c219980acf423b6cd9c41a8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-east1-d-8-0-b447\"], \"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": "1607033115666293",
"status": "COMPLETED",
"status_changed_ts": "1607039374663184",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21592",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d034cb9684581c1db6a026e13dfc3995687ec179",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d034cb9684581c1db6a026e13dfc3995687ec179",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027327574891565376",
"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/8861897511411601920/+/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:504372c886ace910",
"user_agent:luci-scheduler"
],
"updated_ts": "1607039374668120",
"url": "https://ci.chromium.org/b/8861897511411601920",
"utcnow_ts": "1607039375432853"
}
}
[23:49:35.443] Invocation finished in 1h46m47.196194288s with status SUCCEEDED