[22:36:56.661] New invocation is queued and will start shortly
[22:36:57.740] Starting the invocation (attempt 1)
[22:36:57.773] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:36:57.773] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:36:57.773] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021436624174249536",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"77816c25d47f226e5688c7e81628083e45958efe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021436624174249536",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/77816c25d47f226e5688c7e81628083e45958efe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77816c25d47f226e5688c7e81628083e45958efe",
"gitiles_ref:refs/heads/master"
]
}
[22:36:59.415] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612651017930529",
"id": "8856006562181366848",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77816c25d47f226e5688c7e81628083e45958efe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612651019109839",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180341",
"builder:Linux Builder (dbg)",
"buildset:commit/git/77816c25d47f226e5688c7e81628083e45958efe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77816c25d47f226e5688c7e81628083e45958efe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021436624174249536",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856006562181366848/+/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": "1612651019110079",
"url": "https://ci.chromium.org/b/8856006562181366848",
"utcnow_ts": "1612651019396655"
}
}
[22:36:59.415] Task URL: https://ci.chromium.org/b/8856006562181366848
[22:36:59.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:2:0) after 1m0s
[22:37:59.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:2:0)
[22:37:59.404] Timer tick, asking Buildbucket for the build status
[22:37:59.521] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:59.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:3:0) after 1m0s
[22:38:59.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:3:0)
[22:38:59.538] Timer tick, asking Buildbucket for the build status
[22:38:59.610] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:59.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:4:0) after 1m0s
[22:39:59.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:4:0)
[22:39:59.661] Timer tick, asking Buildbucket for the build status
[22:39:59.721] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:59.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:5:0) after 1m0s
[22:40:59.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:5:0)
[22:40:59.765] Timer tick, asking Buildbucket for the build status
[22:40:59.924] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:59.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:6:0) after 1m0s
[22:41:59.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:6:0)
[22:41:59.973] Timer tick, asking Buildbucket for the build status
[22:42:00.175] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:00.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:7:0) after 1m0s
[22:43:00.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:7:0)
[22:43:00.249] Timer tick, asking Buildbucket for the build status
[22:43:00.511] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:00.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:8:0) after 1m0s
[22:44:00.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:8:0)
[22:44:00.551] Timer tick, asking Buildbucket for the build status
[22:44:00.667] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:00.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:9:0) after 1m0s
[22:45:00.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:9:0)
[22:45:00.774] Timer tick, asking Buildbucket for the build status
[22:45:00.903] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:00.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:10:0) after 1m0s
[22:46:00.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:10:0)
[22:46:00.944] Timer tick, asking Buildbucket for the build status
[22:46:01.266] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:01.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:11:0) after 1m0s
[22:47:01.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:11:0)
[22:47:01.311] Timer tick, asking Buildbucket for the build status
[22:47:01.455] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:01.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:12:0) after 1m0s
[22:48:01.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:12:0)
[22:48:01.563] Timer tick, asking Buildbucket for the build status
[22:48:01.898] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:01.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:13:0) after 1m0s
[22:49:01.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:13:0)
[22:49:01.952] Timer tick, asking Buildbucket for the build status
[22:49:02.364] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:02.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:14:0) after 1m0s
[22:50:02.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:14:0)
[22:50:02.430] Timer tick, asking Buildbucket for the build status
[22:50:02.526] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:02.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:15:0) after 1m0s
[22:51:02.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:15:0)
[22:51:02.603] Timer tick, asking Buildbucket for the build status
[22:51:02.673] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:02.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:16:0) after 1m0s
[22:52:02.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:16:0)
[22:52:02.690] Timer tick, asking Buildbucket for the build status
[22:52:03.004] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:03.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:17:0) after 1m0s
[22:53:03.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:17:0)
[22:53:03.016] Timer tick, asking Buildbucket for the build status
[22:53:03.383] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:03.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:18:0) after 1m0s
[22:54:03.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:18:0)
[22:54:03.440] Timer tick, asking Buildbucket for the build status
[22:54:03.504] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:03.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:19:0) after 1m0s
[22:55:03.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:19:0)
[22:55:03.534] Timer tick, asking Buildbucket for the build status
[22:55:03.708] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:03.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:20:0) after 1m0s
[22:56:03.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:20:0)
[22:56:03.754] Timer tick, asking Buildbucket for the build status
[22:56:03.829] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:03.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:21:0) after 1m0s
[22:57:03.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:21:0)
[22:57:03.847] Timer tick, asking Buildbucket for the build status
[22:57:04.242] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:04.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:22:0) after 1m0s
[22:58:04.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:22:0)
[22:58:04.323] Timer tick, asking Buildbucket for the build status
[22:58:04.500] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:04.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:23:0) after 1m0s
[22:59:04.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:23:0)
[22:59:04.496] Timer tick, asking Buildbucket for the build status
[22:59:04.591] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:04.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:24:0) after 1m0s
[23:00:04.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:24:0)
[23:00:04.619] Timer tick, asking Buildbucket for the build status
[23:00:05.006] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:05.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:25:0) after 1m0s
[23:01:05.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:25:0)
[23:01:05.220] Timer tick, asking Buildbucket for the build status
[23:01:06.028] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:06.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:26:0) after 1m0s
[23:02:06.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:26:0)
[23:02:06.144] Timer tick, asking Buildbucket for the build status
[23:02:06.249] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:06.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:27:0) after 1m0s
[23:03:06.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:27:0)
[23:03:06.289] Timer tick, asking Buildbucket for the build status
[23:03:06.362] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:06.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:28:0) after 1m0s
[23:04:06.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:28:0)
[23:04:06.418] Timer tick, asking Buildbucket for the build status
[23:04:06.895] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:06.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:29:0) after 1m0s
[23:05:06.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:29:0)
[23:05:06.973] Timer tick, asking Buildbucket for the build status
[23:05:07.040] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:07.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:30:0) after 1m0s
[23:06:07.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:30:0)
[23:06:07.064] Timer tick, asking Buildbucket for the build status
[23:06:07.147] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:07.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:31:0) after 1m0s
[23:07:07.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:31:0)
[23:07:07.172] Timer tick, asking Buildbucket for the build status
[23:07:07.556] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:07.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:32:0) after 1m0s
[23:08:07.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:32:0)
[23:08:07.662] Timer tick, asking Buildbucket for the build status
[23:08:08.330] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:08.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:33:0) after 1m0s
[23:09:08.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:33:0)
[23:09:08.351] Timer tick, asking Buildbucket for the build status
[23:09:08.583] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:08.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:34:0) after 1m0s
[23:10:08.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:34:0)
[23:10:08.609] Timer tick, asking Buildbucket for the build status
[23:10:08.699] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:08.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:35:0) after 1m0s
[23:11:08.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:35:0)
[23:11:08.722] Timer tick, asking Buildbucket for the build status
[23:11:08.786] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:08.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:36:0) after 1m0s
[23:12:08.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:36:0)
[23:12:08.808] Timer tick, asking Buildbucket for the build status
[23:12:08.919] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:08.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:37:0) after 1m0s
[23:13:08.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:37:0)
[23:13:08.936] Timer tick, asking Buildbucket for the build status
[23:13:09.022] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:09.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:38:0) after 1m0s
[23:14:09.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:38:0)
[23:14:09.113] Timer tick, asking Buildbucket for the build status
[23:14:09.214] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:09.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:39:0) after 1m0s
[23:15:09.348] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:39:0)
[23:15:09.348] Timer tick, asking Buildbucket for the build status
[23:15:09.865] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:09.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:40:0) after 1m0s
[23:16:09.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:40:0)
[23:16:09.931] Timer tick, asking Buildbucket for the build status
[23:16:10.048] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:10.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:41:0) after 1m0s
[23:17:10.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:41:0)
[23:17:10.184] Timer tick, asking Buildbucket for the build status
[23:17:10.427] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:10.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:42:0) after 1m0s
[23:18:10.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:42:0)
[23:18:10.552] Timer tick, asking Buildbucket for the build status
[23:18:10.639] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:10.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:43:0) after 1m0s
[23:19:10.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:43:0)
[23:19:10.713] Timer tick, asking Buildbucket for the build status
[23:19:10.790] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:10.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:44:0) after 1m0s
[23:20:10.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:44:0)
[23:20:10.894] Timer tick, asking Buildbucket for the build status
[23:20:10.976] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:10.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:45:0) after 1m0s
[23:21:11.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:45:0)
[23:21:11.014] Timer tick, asking Buildbucket for the build status
[23:21:11.228] Build 8856006562181366848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:11.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:46:0) after 1m0s
[23:22:06.486] Received PubSub notification, asking Buildbucket for the build status
[23:22:06.562] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:11.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:46:0)
[23:22:11.419] Timer tick, asking Buildbucket for the build status
[23:22:11.642] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:11.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:48:0) after 1m0s
[23:23:11.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:48:0)
[23:23:11.756] Timer tick, asking Buildbucket for the build status
[23:23:12.014] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:12.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:49:0) after 1m0s
[23:24:12.133] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:49:0)
[23:24:12.133] Timer tick, asking Buildbucket for the build status
[23:24:12.235] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:12.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:50:0) after 1m0s
[23:25:12.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:50:0)
[23:25:12.338] Timer tick, asking Buildbucket for the build status
[23:25:12.463] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:12.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:51:0) after 1m0s
[23:26:12.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:51:0)
[23:26:12.542] Timer tick, asking Buildbucket for the build status
[23:26:12.910] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:12.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:52:0) after 1m0s
[23:27:13.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:52:0)
[23:27:13.018] Timer tick, asking Buildbucket for the build status
[23:27:13.331] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:13.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:53:0) after 1m0s
[23:28:13.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:53:0)
[23:28:13.399] Timer tick, asking Buildbucket for the build status
[23:28:13.455] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:13.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:54:0) after 1m0s
[23:29:13.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:54:0)
[23:29:13.521] Timer tick, asking Buildbucket for the build status
[23:29:13.591] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:13.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:55:0) after 1m0s
[23:30:13.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:55:0)
[23:30:13.880] Timer tick, asking Buildbucket for the build status
[23:30:14.043] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:14.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:56:0) after 1m0s
[23:31:14.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:56:0)
[23:31:14.264] Timer tick, asking Buildbucket for the build status
[23:31:14.440] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:14.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:57:0) after 1m0s
[23:32:14.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:57:0)
[23:32:14.580] Timer tick, asking Buildbucket for the build status
[23:32:14.721] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:14.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:58:0) after 1m0s
[23:33:14.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:58:0)
[23:33:14.825] Timer tick, asking Buildbucket for the build status
[23:33:14.957] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:14.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:59:0) after 1m0s
[23:34:15.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:59:0)
[23:34:15.178] Timer tick, asking Buildbucket for the build status
[23:34:15.239] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:15.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:60:0) after 1m0s
[23:35:15.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:60:0)
[23:35:15.398] Timer tick, asking Buildbucket for the build status
[23:35:15.455] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:15.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:61:0) after 1m0s
[23:36:15.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:61:0)
[23:36:15.583] Timer tick, asking Buildbucket for the build status
[23:36:15.836] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:15.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:62:0) after 1m0s
[23:37:16.076] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:62:0)
[23:37:16.076] Timer tick, asking Buildbucket for the build status
[23:37:16.177] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:16.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:63:0) after 1m0s
[23:38:16.203] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:63:0)
[23:38:16.203] Timer tick, asking Buildbucket for the build status
[23:38:16.267] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:64:0) after 1m0s
[23:39:16.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:64:0)
[23:39:16.323] Timer tick, asking Buildbucket for the build status
[23:39:16.376] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:16.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:65:0) after 1m0s
[23:40:16.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:65:0)
[23:40:16.498] Timer tick, asking Buildbucket for the build status
[23:40:16.570] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:16.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:66:0) after 1m0s
[23:41:16.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:66:0)
[23:41:16.624] Timer tick, asking Buildbucket for the build status
[23:41:16.690] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:16.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:67:0) after 1m0s
[23:42:16.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:67:0)
[23:42:16.714] Timer tick, asking Buildbucket for the build status
[23:42:16.823] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:16.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:68:0) after 1m0s
[23:43:16.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:68:0)
[23:43:16.846] Timer tick, asking Buildbucket for the build status
[23:43:17.027] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:17.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:69:0) after 1m0s
[23:44:17.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:69:0)
[23:44:17.081] Timer tick, asking Buildbucket for the build status
[23:44:17.306] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:17.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:70:0) after 1m0s
[23:45:17.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:70:0)
[23:45:17.330] Timer tick, asking Buildbucket for the build status
[23:45:17.405] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:17.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:71:0) after 1m0s
[23:46:17.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:71:0)
[23:46:17.426] Timer tick, asking Buildbucket for the build status
[23:46:17.488] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:17.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:72:0) after 1m0s
[23:47:17.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:72:0)
[23:47:17.508] Timer tick, asking Buildbucket for the build status
[23:47:17.614] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:17.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:73:0) after 1m0s
[23:48:17.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:73:0)
[23:48:17.630] Timer tick, asking Buildbucket for the build status
[23:48:17.716] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:17.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:74:0) after 1m0s
[23:49:17.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:74:0)
[23:49:17.771] Timer tick, asking Buildbucket for the build status
[23:49:17.831] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:17.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:75:0) after 1m0s
[23:50:17.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:75:0)
[23:50:17.851] Timer tick, asking Buildbucket for the build status
[23:50:18.017] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:18.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:76:0) after 1m0s
[23:51:18.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:76:0)
[23:51:18.087] Timer tick, asking Buildbucket for the build status
[23:51:18.252] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:18.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:77:0) after 1m0s
[23:52:18.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:77:0)
[23:52:18.286] Timer tick, asking Buildbucket for the build status
[23:52:18.565] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:18.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:78:0) after 1m0s
[23:53:18.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:78:0)
[23:53:18.586] Timer tick, asking Buildbucket for the build status
[23:53:18.706] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:18.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:79:0) after 1m0s
[23:54:18.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:79:0)
[23:54:18.745] Timer tick, asking Buildbucket for the build status
[23:54:18.805] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:18.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:80:0) after 1m0s
[23:55:18.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:80:0)
[23:55:18.841] Timer tick, asking Buildbucket for the build status
[23:55:18.897] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:18.897] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:81:0) after 1m0s
[23:56:18.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:81:0)
[23:56:18.944] Timer tick, asking Buildbucket for the build status
[23:56:19.145] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:19.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:82:0) after 1m0s
[23:57:19.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:82:0)
[23:57:19.181] Timer tick, asking Buildbucket for the build status
[23:57:19.271] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:19.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:83:0) after 1m0s
[23:58:19.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:83:0)
[23:58:19.322] Timer tick, asking Buildbucket for the build status
[23:58:19.540] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:19.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:84:0) after 1m0s
[23:59:19.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:84:0)
[23:59:19.597] Timer tick, asking Buildbucket for the build status
[23:59:19.662] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:19.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:85:0) after 1m0s
[00:00:19.821] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:85:0)
[00:00:19.821] Timer tick, asking Buildbucket for the build status
[00:00:19.884] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:19.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:86:0) after 1m0s
[00:01:20.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:86:0)
[00:01:20.026] Timer tick, asking Buildbucket for the build status
[00:01:20.398] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:20.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:87:0) after 1m0s
[00:02:20.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:87:0)
[00:02:20.431] Timer tick, asking Buildbucket for the build status
[00:02:20.931] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:20.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:88:0) after 1m0s
[00:03:21.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:88:0)
[00:03:21.088] Timer tick, asking Buildbucket for the build status
[00:03:21.434] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:21.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:89:0) after 1m0s
[00:04:21.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:89:0)
[00:04:21.618] Timer tick, asking Buildbucket for the build status
[00:04:21.684] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:21.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:90:0) after 1m0s
[00:05:21.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:90:0)
[00:05:21.797] Timer tick, asking Buildbucket for the build status
[00:05:22.122] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:22.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:91:0) after 1m0s
[00:06:22.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:91:0)
[00:06:22.166] Timer tick, asking Buildbucket for the build status
[00:06:22.343] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:22.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:92:0) after 1m0s
[00:07:22.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:92:0)
[00:07:22.330] Timer tick, asking Buildbucket for the build status
[00:07:22.507] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:22.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:93:0) after 1m0s
[00:08:22.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:93:0)
[00:08:22.563] Timer tick, asking Buildbucket for the build status
[00:08:22.652] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:22.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:94:0) after 1m0s
[00:09:22.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:94:0)
[00:09:22.904] Timer tick, asking Buildbucket for the build status
[00:09:22.967] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:22.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:95:0) after 1m0s
[00:10:23.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:95:0)
[00:10:23.100] Timer tick, asking Buildbucket for the build status
[00:10:23.209] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:23.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:96:0) after 1m0s
[00:11:23.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:96:0)
[00:11:23.327] Timer tick, asking Buildbucket for the build status
[00:11:23.390] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:23.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:97:0) after 1m0s
[00:12:23.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:97:0)
[00:12:23.586] Timer tick, asking Buildbucket for the build status
[00:12:24.000] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:24.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:98:0) after 1m0s
[00:13:24.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:98:0)
[00:13:24.112] Timer tick, asking Buildbucket for the build status
[00:13:24.173] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:24.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:99:0) after 1m0s
[00:14:24.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:99:0)
[00:14:24.207] Timer tick, asking Buildbucket for the build status
[00:14:24.424] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:24.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:100:0) after 1m0s
[00:15:24.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:100:0)
[00:15:24.487] Timer tick, asking Buildbucket for the build status
[00:15:25.051] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:25.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:101:0) after 1m0s
[00:16:25.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:101:0)
[00:16:25.210] Timer tick, asking Buildbucket for the build status
[00:16:25.498] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:25.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:102:0) after 1m0s
[00:17:25.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:102:0)
[00:17:25.529] Timer tick, asking Buildbucket for the build status
[00:17:25.660] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:25.660] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:103:0) after 1m0s
[00:18:25.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:103:0)
[00:18:25.684] Timer tick, asking Buildbucket for the build status
[00:18:25.777] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:25.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:104:0) after 1m0s
[00:19:25.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:104:0)
[00:19:25.851] Timer tick, asking Buildbucket for the build status
[00:19:25.952] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:25.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:105:0) after 1m0s
[00:20:26.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:105:0)
[00:20:26.033] Timer tick, asking Buildbucket for the build status
[00:20:26.111] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:26.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:106:0) after 1m0s
[00:21:26.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:106:0)
[00:21:26.211] Timer tick, asking Buildbucket for the build status
[00:21:26.274] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:26.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:107:0) after 1m0s
[00:22:26.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:107:0)
[00:22:26.425] Timer tick, asking Buildbucket for the build status
[00:22:26.531] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:26.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:108:0) after 1m0s
[00:23:26.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:108:0)
[00:23:26.787] Timer tick, asking Buildbucket for the build status
[00:23:26.851] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:26.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:109:0) after 1m0s
[00:24:26.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:109:0)
[00:24:26.990] Timer tick, asking Buildbucket for the build status
[00:24:27.100] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:27.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:110:0) after 1m0s
[00:25:27.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:110:0)
[00:25:27.142] Timer tick, asking Buildbucket for the build status
[00:25:27.205] Build 8856006562181366848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:27.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021436624174249536:111:0) after 1m0s
[00:25:54.060] Received PubSub notification, asking Buildbucket for the build status
[00:25:54.660] Build 8856006562181366848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:25:54.660] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612657552935386",
"created_by": "project:chromium",
"created_ts": "1612651017930529",
"id": "8856006562181366848",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77816c25d47f226e5688c7e81628083e45958efe\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"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\": \"77816c25d47f226e5688c7e81628083e45958efe\", \"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-8-eora\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180341, \"got_angle_revision\": \"d820ec7b3e5f9412d1ceb55d31b3f7dc09e3d16d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"77816c25d47f226e5688c7e81628083e45958efe\", \"got_revision_cp\": \"refs/heads/master@{#851505}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77816c25d47f226e5688c7e81628083e45958efe\", \"swarm_hashes_refs/heads/master(at){#851505}_without_patch\": {\"absl_hardening_tests\": \"64778e6cf144fe3d48d21b54d50d816037546329\", \"accessibility_unittests\": \"c7f8b90e9634705099236ed1505348c09e5b7817\", \"angle_unittests\": \"7bf1e437c5e082b4df2b40607246e295d994ae78\", \"app_shell_unittests\": \"18579374ca49d5e6384a8e11675853aeefeabbd6\", \"aura_unittests\": \"1af7420be707751e24e39df3e6db9cb3212118a8\", \"base_unittests\": \"3ddbc1a95da50aeea326e2c67dab45bbebe560d4\", \"base_util_unittests\": \"6cbe72d9fc9c0cb5c67ad8032fcfe4ce98aae651\", \"blink_common_unittests\": \"fa65f9fd9954f6e43e0f860f93e04f1fdd179dfc\", \"blink_fuzzer_unittests\": \"026932eb66a6cb2207a7fc526220b4d842470ae9\", \"blink_heap_unittests\": \"31eb78c45ecf7592397222f970785a9d9e2946bf\", \"blink_platform_unittests\": \"3149289fdbc77f83ce91d645dbf4a960356802bb\", \"blink_python_tests\": \"3fee940019a74843b7b678382a7a94dcff347e7b\", \"blink_unittests\": \"2036f7d43ef4a3212f99532afdadd9c18bc559c7\", \"blink_web_tests\": \"06a555639d81212654954cfec9ef4f12e86e434d\", \"boringssl_crypto_tests\": \"a424bd46f5e0cb93bfe4b0086d92335aab64a2be\", \"boringssl_ssl_tests\": \"6de8a3da857594aae666b0a9bc8df0b0c90319dd\", \"browser_tests\": \"503d98d674a0b69f4ee809763bfba74f512b33a5\", \"capture_unittests\": \"d0be6c7498070be48cbea476191e61be3934b5e8\", \"cast_unittests\": \"dc231939c5e5113333ffed46634941cdc4f72222\", \"cc_unittests\": \"e9306f6a2a2f67a11a0c0bfe511b45acdf33ea80\", \"chrome_app_unittests\": \"11aca159c59b19ff167f3332487c772fd0adf286\", \"chromedriver_unittests\": \"d63fec1c37aaaee1a75b5374a7730a088bb528c5\", \"components_browsertests\": \"2d572da8b616f0a2e4712d13b9a67f6fca7ca85f\", \"components_unittests\": \"b896629577a1a04d0a84066bd55160a4e9be579e\", \"compositor_unittests\": \"00315f4f7debb3f18afeb4b947a3716ae0612d1f\", \"content_browsertests\": \"a878e9fbfe98918fa2826b841e85ef06150d9dd3\", \"content_shell_crash_test\": \"df648b8888f7c5a0d45c4709da006f3e96cd6e65\", \"content_unittests\": \"090cb318f4cdfd990e26aaadd4ee92485a7bb2af\", \"crashpad_tests\": \"7138511a3168abe7e77e2abb84c7daa2789a2bd8\", \"cronet_tests\": \"66956a24fa581863b7d5eab02c5ffbdb57c9b12a\", \"cronet_unittests\": \"be3cce6290684cc52ec22752cc2c3eb4932a95bc\", \"crypto_unittests\": \"f935dc19a1bcf6c82098e4c41ec4a4b92029c9ae\", \"dbus_unittests\": \"4737b77a777c13e925ee08f9ac34290c3ce9b4ec\", \"device_unittests\": \"1da811910754b706cc28e8919cb1aa0b3cd029d9\", \"display_unittests\": \"2759599edd82db72467fe9577e3dcd750e609760\", \"events_unittests\": \"9655d16bc3909b51afd35828fd28444b2ed2d4bf\", \"extensions_browsertests\": \"322652e9f0a48aec7a7430e36acd3f361a963a93\", \"extensions_unittests\": \"374f45f81870470a37bb886b6cfe389732209dfb\", \"filesystem_service_unittests\": \"9e411975211d7c8c2356b23de7192e8438a2d804\", \"flatbuffers_unittests\": \"adcf587971a832b1be93c24a83cd1ec975e593d8\", \"gcm_unit_tests\": \"fc4b1e0863cd697695fd99f37cb3ca74c3cce253\", \"gfx_unittests\": \"85e24e103d6a3067503efe0bf816b4024e207c08\", \"gin_unittests\": \"b90cec6ed2e56e0b49b855bf90b902668713c901\", \"google_apis_unittests\": \"9eb93d26d1400492a3b30308fe5f88212be8d007\", \"gpu_unittests\": \"38631ea18b897d8327ebf108d87df055d0f8c30a\", \"grit_python_unittests\": \"09d11eaebf65aefde13fcafc2a12fe42d6476e6b\", \"gwp_asan_unittests\": \"7dfe728c41fd835db8f14a894babf49ef30649ec\", \"headless_browsertests\": \"b48808b3e3de58a9d6b51bef0bc307134be0045a\", \"headless_unittests\": \"105b0295f5f963daf36c6f97a2131f51e4e64655\", \"interactive_ui_tests\": \"90434be7b6a01b7c37ea796fb594112cd0436cb5\", \"ipc_tests\": \"54f9f9f5407bbdaa60c714e3744cb90e2b24dc9c\", \"jingle_unittests\": \"b4b43a3f584284abd1ccaabeef232637c753af3e\", \"latency_unittests\": \"fc27d457c0967967541012cb995cb7218a9ac8db\", \"libjingle_xmpp_unittests\": \"988c29af95ad53ccb3cd5644e176c17a0aa55f18\", \"liburlpattern_unittests\": \"215aac7876731a2562355499b348b66c74218d1c\", \"media_blink_unittests\": \"a7b822d2a7e51ab2602dd7fc5679165431f13b94\", \"media_unittests\": \"7e5c3d3462bc4c50b5ddcc9bfc7323a646077757\", \"message_center_unittests\": \"f3fdfdae0554bcbe62fccdd0b05c6fff7c23c4df\", \"metrics_python_tests\": \"3af7da07ea324fe9bd3ce36ef6f07fe4928b4065\", \"midi_unittests\": \"3fbf0d629d30f805814534b523db6fc2b61aa843\", \"mojo_core_unittests\": \"995c0cfd53ecd51b939834ec986ab81873b81ef7\", \"mojo_unittests\": \"a10b34747be4b54b79e21bd6e130eb7c402fc424\", \"nacl_helper_nonsfi_unittests\": \"db7d2023af78ab9674c4ae353a274a4f09d04d85\", \"nacl_loader_unittests\": \"dd7dafd673da591c8b9476d53061992781ad25b8\", \"native_theme_unittests\": \"5d5b7dbd56ecf43f492f2e6a47097f8660a45d7f\", \"net_unittests\": \"e2f49c147c84fa8b423c3a2df9a03f1db71e8899\", \"openscreen_unittests\": \"93b3b014ba69a05e6a37b20707959f14dc91124b\", \"pdf_unittests\": \"9e22f001f0a35bc80c83f15dc743fd84f6ec3ae6\", \"perfetto_unittests\": \"547c1e815a8f72a98dfeb5de2e2a5990239743c7\", \"ppapi_unittests\": \"4a3ebd8b2237f2d8b9ce9da6689353335ff23bc2\", \"printing_unittests\": \"a2b924a6dbd60a0fa6fa56df56ec843da70897f1\", \"remoting_unittests\": \"dd9459aab28abd5f4c09e4ff962757015046ce72\", \"sandbox_linux_unittests\": \"121534da5e51fffc06146b5bdf6761784f1c1d6c\", \"service_manager_unittests\": \"a4b19acc743c64bec0d0b0da1864bdaee9925169\", \"services_unittests\": \"9f2fed66b165f3a5016306476cfbf55d5111643f\", \"shell_dialogs_unittests\": \"7b358b7fa7a4d1c39fd54dbd69f61230d7f101b7\", \"skia_unittests\": \"27547f259aa7fb5450261ada5f99297bc4ad0407\", \"snapshot_unittests\": \"9e5ed59a0cbdfeb4dbda75b8d95282a65188368d\", \"sql_unittests\": \"b2616fcf279dfd08b95b40eed60a673f555e2ae6\", \"storage_unittests\": \"e739776d73b9f6b633ab45feb17ccbcbc8e355b7\", \"sync_integration_tests\": \"1028a6fc54a6b7c061a4a3b375571669c253d047\", \"telemetry_gpu_unittests\": \"f2526a829b45b7bd16f7afb098ded8a182d729a3\", \"telemetry_perf_unittests\": \"29cccb600690e29657eec42ccbd8fe36ef8dbabc\", \"telemetry_unittests\": \"8a22972a40f7927bdc1f348e986ec899befcdd81\", \"traffic_annotation_auditor_unittests\": \"d6aa416cd83f5edd5924ed0c8b5ac9c972dc4eec\", \"ui_base_unittests\": \"ecec8f97eb93f358dc78ec89db9a10793dc8ceeb\", \"ui_touch_selection_unittests\": \"4aa4d2969e5ce0faa3c936ca6dab0f022c1833f8\", \"unit_tests\": \"3dcff527c151450f8506eb0fff8f48302e7cdc5f\", \"url_unittests\": \"e6e87f2bc320fb2872f90c7178d4c9407e46e3d1\", \"views_perftests\": \"a6b322c65103a5a181643133fd0a767c3ce18ceb\", \"views_unittests\": \"dad4a0faa497b7c3d26c36eeec8e8782cf1e8b58\", \"viz_unittests\": \"cf228477fd9244de2a5dfc723de58313f111a775\", \"vr_common_unittests\": \"d9a901b5d8e32186d9e72658b99c3f58c3da782c\", \"vr_pixeltests\": \"b6091c0037c0e54bc2dac2352ddc25453d2367b2\", \"webdriver_wpt_tests\": \"11e17a20d06c26479565b1881b95e1852dc7afbb\", \"weblayer_browsertests\": \"98cbfae2f927b5acba8eac167240dbd57b806d93\", \"weblayer_unittests\": \"850a23ec9140919523cacca5e545b0bd702822d5\", \"wm_unittests\": \"0af054fbf1c73dc1c96ee718936ea651bc81a571\", \"wtf_unittests\": \"181765d7295b4ece108a42c96e22c04394504a8a\", \"x11_unittests\": \"66e6d6fe99df5dd9c1e667be469ceefe4f08881a\", \"xr_browser_tests\": \"35a06fa41d4973e926743e932e0c111e1dc426da\", \"zlib_unittests\": \"b879485fda9fb74f3b79ef46caca44a329fe61c0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_197e8aed8afdb4f2db5daf8954e5f1e8e76b914aa8de2eb6ac17c11267e25f98_v2\", \"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_c49efc2b6fcfe67b72a35f6b436282fc6c4393b61b532dfc8ddacf832f201b2b_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-8-eora\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612653725516523",
"status": "COMPLETED",
"status_changed_ts": "1612657553239600",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180341",
"builder:Linux Builder (dbg)",
"buildset:commit/git/77816c25d47f226e5688c7e81628083e45958efe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77816c25d47f226e5688c7e81628083e45958efe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021436624174249536",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856006562181366848/+/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:51924f44fbc63910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612657553317182",
"url": "https://ci.chromium.org/b/8856006562181366848",
"utcnow_ts": "1612657554581711"
}
}
[00:25:54.660] Invocation finished in 1h48m58.01431927s with status SUCCEEDED