[21:02:55.745] New invocation is queued and will start shortly
[21:02:56.780] Starting the invocation (attempt 1)
[21:02:56.849] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:02:56.850] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:02:56.850] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020717763372519200",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"27ef08841e6e1231901c2e2770f4d847cb5e15a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9020717763372519200",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"gitiles_ref:refs/heads/master"
]
}
[21:02:57.455] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613336576921805",
"id": "8855287701477016800",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"27ef08841e6e1231901c2e2770f4d847cb5e15a2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613336577330255",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180572",
"builder:Linux Builder (dbg)",
"buildset:commit/git/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020717763372519200",
"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/8855287701477016800/+/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": "1613336577330382",
"url": "https://ci.chromium.org/b/8855287701477016800",
"utcnow_ts": "1613336577433189"
}
}
[21:02:57.455] Task URL: https://ci.chromium.org/b/8855287701477016800
[21:02:57.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:2:0) after 1m0s
[21:03:57.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:2:0)
[21:03:57.506] Timer tick, asking Buildbucket for the build status
[21:03:57.587] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:57.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:3:0) after 1m0s
[21:04:57.636] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:3:0)
[21:04:57.636] Timer tick, asking Buildbucket for the build status
[21:04:57.702] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:57.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:4:0) after 1m0s
[21:05:57.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:4:0)
[21:05:57.741] Timer tick, asking Buildbucket for the build status
[21:05:57.833] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:57.834] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:5:0) after 1m0s
[21:06:57.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:5:0)
[21:06:57.851] Timer tick, asking Buildbucket for the build status
[21:06:58.396] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:58.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:6:0) after 1m0s
[21:07:58.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:6:0)
[21:07:58.455] Timer tick, asking Buildbucket for the build status
[21:07:58.542] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:58.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:7:0) after 1m0s
[21:08:58.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:7:0)
[21:08:58.559] Timer tick, asking Buildbucket for the build status
[21:08:59.611] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:59.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:8:0) after 1m0s
[21:09:59.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:8:0)
[21:09:59.626] Timer tick, asking Buildbucket for the build status
[21:09:59.741] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:59.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:9:0) after 1m0s
[21:10:59.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:9:0)
[21:10:59.757] Timer tick, asking Buildbucket for the build status
[21:10:59.828] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:59.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:10:0) after 1m0s
[21:11:59.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:10:0)
[21:11:59.890] Timer tick, asking Buildbucket for the build status
[21:12:00.208] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:11:0) after 1m0s
[21:13:00.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:11:0)
[21:13:00.275] Timer tick, asking Buildbucket for the build status
[21:13:00.424] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:00.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:12:0) after 1m0s
[21:14:00.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:12:0)
[21:14:00.457] Timer tick, asking Buildbucket for the build status
[21:14:00.529] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:00.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:13:0) after 1m0s
[21:15:00.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:13:0)
[21:15:00.551] Timer tick, asking Buildbucket for the build status
[21:15:00.625] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:00.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:14:0) after 1m0s
[21:16:00.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:14:0)
[21:16:00.640] Timer tick, asking Buildbucket for the build status
[21:16:00.720] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:00.720] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:15:0) after 1m0s
[21:17:00.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:15:0)
[21:17:00.769] Timer tick, asking Buildbucket for the build status
[21:17:00.855] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:00.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:16:0) after 1m0s
[21:18:00.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:16:0)
[21:18:00.868] Timer tick, asking Buildbucket for the build status
[21:18:00.955] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:00.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:17:0) after 1m0s
[21:19:01.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:17:0)
[21:19:01.484] Timer tick, asking Buildbucket for the build status
[21:19:01.569] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:01.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:18:0) after 1m0s
[21:20:01.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:18:0)
[21:20:01.787] Timer tick, asking Buildbucket for the build status
[21:20:02.026] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:02.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:19:0) after 1m0s
[21:21:02.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:19:0)
[21:21:02.125] Timer tick, asking Buildbucket for the build status
[21:21:02.199] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:02.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:20:0) after 1m0s
[21:22:02.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:20:0)
[21:22:02.197] Timer tick, asking Buildbucket for the build status
[21:22:02.305] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:02.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:21:0) after 1m0s
[21:23:02.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:21:0)
[21:23:02.419] Timer tick, asking Buildbucket for the build status
[21:23:02.617] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:02.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:22:0) after 1m0s
[21:24:02.657] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:22:0)
[21:24:02.657] Timer tick, asking Buildbucket for the build status
[21:24:02.780] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:02.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:23:0) after 1m0s
[21:25:02.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:23:0)
[21:25:02.987] Timer tick, asking Buildbucket for the build status
[21:25:03.085] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:03.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:24:0) after 1m0s
[21:26:03.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:24:0)
[21:26:03.199] Timer tick, asking Buildbucket for the build status
[21:26:03.422] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:03.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:25:0) after 1m0s
[21:27:03.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:25:0)
[21:27:03.464] Timer tick, asking Buildbucket for the build status
[21:27:03.563] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:26:0) after 1m0s
[21:28:03.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:26:0)
[21:28:03.577] Timer tick, asking Buildbucket for the build status
[21:28:03.825] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:03.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:27:0) after 1m0s
[21:29:03.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:27:0)
[21:29:03.894] Timer tick, asking Buildbucket for the build status
[21:29:03.956] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:03.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:28:0) after 1m0s
[21:30:03.985] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:28:0)
[21:30:03.985] Timer tick, asking Buildbucket for the build status
[21:30:04.051] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:04.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:29:0) after 1m0s
[21:31:04.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:29:0)
[21:31:04.084] Timer tick, asking Buildbucket for the build status
[21:31:04.222] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:04.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:30:0) after 1m0s
[21:32:04.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:30:0)
[21:32:04.674] Timer tick, asking Buildbucket for the build status
[21:32:04.737] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:04.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:31:0) after 1m0s
[21:33:04.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:31:0)
[21:33:04.810] Timer tick, asking Buildbucket for the build status
[21:33:04.900] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:04.900] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:32:0) after 1m0s
[21:34:04.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:32:0)
[21:34:04.932] Timer tick, asking Buildbucket for the build status
[21:34:05.074] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:05.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:33:0) after 1m0s
[21:35:05.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:33:0)
[21:35:05.115] Timer tick, asking Buildbucket for the build status
[21:35:05.303] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:05.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:34:0) after 1m0s
[21:36:05.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:34:0)
[21:36:05.328] Timer tick, asking Buildbucket for the build status
[21:36:05.399] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:05.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:35:0) after 1m0s
[21:37:05.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:35:0)
[21:37:05.421] Timer tick, asking Buildbucket for the build status
[21:37:05.495] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:05.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:36:0) after 1m0s
[21:38:05.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:36:0)
[21:38:05.559] Timer tick, asking Buildbucket for the build status
[21:38:05.692] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:05.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:37:0) after 1m0s
[21:39:05.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:37:0)
[21:39:05.723] Timer tick, asking Buildbucket for the build status
[21:39:05.785] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:05.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:38:0) after 1m0s
[21:40:05.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:38:0)
[21:40:05.823] Timer tick, asking Buildbucket for the build status
[21:40:06.191] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:06.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:39:0) after 1m0s
[21:41:06.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:39:0)
[21:41:06.170] Timer tick, asking Buildbucket for the build status
[21:41:06.237] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:06.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:40:0) after 1m0s
[21:42:06.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:40:0)
[21:42:06.255] Timer tick, asking Buildbucket for the build status
[21:42:06.322] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:06.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:41:0) after 1m0s
[21:43:06.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:41:0)
[21:43:06.384] Timer tick, asking Buildbucket for the build status
[21:43:06.592] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:06.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:42:0) after 1m0s
[21:44:06.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:42:0)
[21:44:06.619] Timer tick, asking Buildbucket for the build status
[21:44:06.783] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:06.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:43:0) after 1m0s
[21:45:06.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:43:0)
[21:45:06.811] Timer tick, asking Buildbucket for the build status
[21:45:06.891] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:06.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:44:0) after 1m0s
[21:46:06.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:44:0)
[21:46:06.913] Timer tick, asking Buildbucket for the build status
[21:46:06.983] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:06.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:45:0) after 1m0s
[21:47:07.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:45:0)
[21:47:07.001] Timer tick, asking Buildbucket for the build status
[21:47:07.109] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:07.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:46:0) after 1m0s
[21:48:07.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:46:0)
[21:48:07.127] Timer tick, asking Buildbucket for the build status
[21:48:07.281] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:07.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:47:0) after 1m0s
[21:49:07.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:47:0)
[21:49:07.377] Timer tick, asking Buildbucket for the build status
[21:49:07.447] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:48:0) after 1m0s
[21:50:07.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:48:0)
[21:50:07.467] Timer tick, asking Buildbucket for the build status
[21:50:07.609] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:07.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:49:0) after 1m0s
[21:51:07.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:49:0)
[21:51:07.628] Timer tick, asking Buildbucket for the build status
[21:51:07.840] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:50:0) after 1m0s
[21:52:07.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:50:0)
[21:52:07.864] Timer tick, asking Buildbucket for the build status
[21:52:08.216] Build 8855287701477016800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:08.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:51:0) after 1m0s
[21:52:25.525] Received PubSub notification, asking Buildbucket for the build status
[21:52:25.590] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:51:0)
[21:53:08.228] Timer tick, asking Buildbucket for the build status
[21:53:08.597] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:08.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:53:0) after 1m0s
[21:54:08.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:53:0)
[21:54:08.613] Timer tick, asking Buildbucket for the build status
[21:54:08.707] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:08.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:54:0) after 1m0s
[21:55:08.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:54:0)
[21:55:08.778] Timer tick, asking Buildbucket for the build status
[21:55:08.921] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:08.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:55:0) after 1m0s
[21:56:08.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:55:0)
[21:56:08.931] Timer tick, asking Buildbucket for the build status
[21:56:09.009] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:09.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:56:0) after 1m0s
[21:57:09.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:56:0)
[21:57:09.028] Timer tick, asking Buildbucket for the build status
[21:57:09.184] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:09.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:57:0) after 1m0s
[21:58:09.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:57:0)
[21:58:09.206] Timer tick, asking Buildbucket for the build status
[21:58:09.277] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:09.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:58:0) after 1m0s
[21:59:10.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:58:0)
[21:59:10.719] Timer tick, asking Buildbucket for the build status
[21:59:10.824] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:10.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:59:0) after 1m0s
[22:00:10.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:59:0)
[22:00:10.809] Timer tick, asking Buildbucket for the build status
[22:00:10.883] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:10.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:60:0) after 1m0s
[22:01:10.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:60:0)
[22:01:10.935] Timer tick, asking Buildbucket for the build status
[22:01:11.011] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:11.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:61:0) after 1m0s
[22:02:11.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:61:0)
[22:02:11.042] Timer tick, asking Buildbucket for the build status
[22:02:11.201] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:11.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:62:0) after 1m0s
[22:03:11.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:62:0)
[22:03:11.256] Timer tick, asking Buildbucket for the build status
[22:03:11.424] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:11.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:63:0) after 1m0s
[22:04:11.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:63:0)
[22:04:11.451] Timer tick, asking Buildbucket for the build status
[22:04:11.535] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:11.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:64:0) after 1m0s
[22:05:11.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:64:0)
[22:05:11.588] Timer tick, asking Buildbucket for the build status
[22:05:11.664] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:11.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:65:0) after 1m0s
[22:06:11.710] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:65:0)
[22:06:11.710] Timer tick, asking Buildbucket for the build status
[22:06:11.817] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:11.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:66:0) after 1m0s
[22:07:11.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:66:0)
[22:07:11.833] Timer tick, asking Buildbucket for the build status
[22:07:11.992] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:11.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:67:0) after 1m0s
[22:08:12.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:67:0)
[22:08:12.052] Timer tick, asking Buildbucket for the build status
[22:08:12.144] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:12.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:68:0) after 1m0s
[22:09:12.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:68:0)
[22:09:12.151] Timer tick, asking Buildbucket for the build status
[22:09:12.225] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:12.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:69:0) after 1m0s
[22:10:12.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:69:0)
[22:10:12.263] Timer tick, asking Buildbucket for the build status
[22:10:12.348] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:12.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:70:0) after 1m0s
[22:11:12.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:70:0)
[22:11:12.423] Timer tick, asking Buildbucket for the build status
[22:11:12.506] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:12.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:71:0) after 1m0s
[22:12:12.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:71:0)
[22:12:12.585] Timer tick, asking Buildbucket for the build status
[22:12:12.694] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:12.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:72:0) after 1m0s
[22:13:12.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:72:0)
[22:13:12.717] Timer tick, asking Buildbucket for the build status
[22:13:12.801] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:12.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:73:0) after 1m0s
[22:14:12.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:73:0)
[22:14:12.884] Timer tick, asking Buildbucket for the build status
[22:14:12.971] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:74:0) after 1m0s
[22:15:12.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:74:0)
[22:15:12.994] Timer tick, asking Buildbucket for the build status
[22:15:13.082] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:13.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:75:0) after 1m0s
[22:16:13.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:75:0)
[22:16:13.094] Timer tick, asking Buildbucket for the build status
[22:16:13.160] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:13.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:76:0) after 1m0s
[22:17:13.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:76:0)
[22:17:13.184] Timer tick, asking Buildbucket for the build status
[22:17:13.260] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:13.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:77:0) after 1m0s
[22:18:13.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:77:0)
[22:18:13.328] Timer tick, asking Buildbucket for the build status
[22:18:13.423] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:13.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:78:0) after 1m0s
[22:19:13.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:78:0)
[22:19:13.447] Timer tick, asking Buildbucket for the build status
[22:19:13.534] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:13.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:79:0) after 1m0s
[22:20:13.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:79:0)
[22:20:13.556] Timer tick, asking Buildbucket for the build status
[22:20:13.635] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:13.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:80:0) after 1m0s
[22:21:13.734] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:80:0)
[22:21:13.734] Timer tick, asking Buildbucket for the build status
[22:21:13.905] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:13.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:81:0) after 1m0s
[22:22:14.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:81:0)
[22:22:14.004] Timer tick, asking Buildbucket for the build status
[22:22:14.095] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:14.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:82:0) after 1m0s
[22:23:14.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:82:0)
[22:23:14.195] Timer tick, asking Buildbucket for the build status
[22:23:14.293] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:14.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:83:0) after 1m0s
[22:24:14.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:83:0)
[22:24:14.329] Timer tick, asking Buildbucket for the build status
[22:24:14.572] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:14.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:84:0) after 1m0s
[22:25:14.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:84:0)
[22:25:14.674] Timer tick, asking Buildbucket for the build status
[22:25:14.745] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:14.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:85:0) after 1m0s
[22:26:15.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:85:0)
[22:26:15.586] Timer tick, asking Buildbucket for the build status
[22:26:15.693] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:15.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:86:0) after 1m0s
[22:27:15.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:86:0)
[22:27:15.715] Timer tick, asking Buildbucket for the build status
[22:27:16.032] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:87:0) after 1m0s
[22:28:16.030] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:87:0)
[22:28:16.030] Timer tick, asking Buildbucket for the build status
[22:28:16.428] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:16.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:88:0) after 1m0s
[22:29:16.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:88:0)
[22:29:16.487] Timer tick, asking Buildbucket for the build status
[22:29:16.801] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:16.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:89:0) after 1m0s
[22:30:16.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:89:0)
[22:30:16.911] Timer tick, asking Buildbucket for the build status
[22:30:16.987] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:16.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:90:0) after 1m0s
[22:31:17.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:90:0)
[22:31:17.102] Timer tick, asking Buildbucket for the build status
[22:31:17.190] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:17.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:91:0) after 1m0s
[22:32:17.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:91:0)
[22:32:17.291] Timer tick, asking Buildbucket for the build status
[22:32:17.374] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:17.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:92:0) after 1m0s
[22:33:17.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:92:0)
[22:33:17.458] Timer tick, asking Buildbucket for the build status
[22:33:17.569] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:17.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:93:0) after 1m0s
[22:34:17.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:93:0)
[22:34:17.623] Timer tick, asking Buildbucket for the build status
[22:34:18.007] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:18.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:94:0) after 1m0s
[22:35:18.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:94:0)
[22:35:18.105] Timer tick, asking Buildbucket for the build status
[22:35:18.201] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:18.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:95:0) after 1m0s
[22:36:18.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:95:0)
[22:36:18.248] Timer tick, asking Buildbucket for the build status
[22:36:18.484] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:18.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:96:0) after 1m0s
[22:37:18.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:96:0)
[22:37:18.507] Timer tick, asking Buildbucket for the build status
[22:37:18.638] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:18.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:97:0) after 1m0s
[22:38:18.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:97:0)
[22:38:18.699] Timer tick, asking Buildbucket for the build status
[22:38:19.028] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:98:0) after 1m0s
[22:39:19.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:98:0)
[22:39:19.068] Timer tick, asking Buildbucket for the build status
[22:39:19.226] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:19.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:99:0) after 1m0s
[22:40:19.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:99:0)
[22:40:19.257] Timer tick, asking Buildbucket for the build status
[22:40:19.610] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:19.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:100:0) after 1m0s
[22:41:19.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:100:0)
[22:41:19.662] Timer tick, asking Buildbucket for the build status
[22:41:19.747] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:19.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:101:0) after 1m0s
[22:42:19.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:101:0)
[22:42:19.816] Timer tick, asking Buildbucket for the build status
[22:42:19.896] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:19.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:102:0) after 1m0s
[22:43:19.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:102:0)
[22:43:19.945] Timer tick, asking Buildbucket for the build status
[22:43:20.245] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:20.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:103:0) after 1m0s
[22:44:20.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:103:0)
[22:44:20.391] Timer tick, asking Buildbucket for the build status
[22:44:20.456] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:20.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:104:0) after 1m0s
[22:45:20.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:104:0)
[22:45:20.653] Timer tick, asking Buildbucket for the build status
[22:45:20.833] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:20.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:105:0) after 1m0s
[22:46:20.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:105:0)
[22:46:20.910] Timer tick, asking Buildbucket for the build status
[22:46:20.976] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:20.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:106:0) after 1m0s
[22:47:21.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:106:0)
[22:47:21.029] Timer tick, asking Buildbucket for the build status
[22:47:21.117] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:21.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:107:0) after 1m0s
[22:48:21.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:107:0)
[22:48:21.259] Timer tick, asking Buildbucket for the build status
[22:48:21.506] Build 8855287701477016800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9020717763372519200:108:0) after 1m0s
[22:48:53.182] Received PubSub notification, asking Buildbucket for the build status
[22:48:53.417] Build 8855287701477016800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:48:53.417] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613342932144713",
"created_by": "project:chromium",
"created_ts": "1613336576921805",
"id": "8855287701477016800",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"27ef08841e6e1231901c2e2770f4d847cb5e15a2\"}}",
"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\": \"27ef08841e6e1231901c2e2770f4d847cb5e15a2\", \"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-55-pdoj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180572, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"27ef08841e6e1231901c2e2770f4d847cb5e15a2\", \"got_revision_cp\": \"refs/heads/master@{#853922}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"554b7ee535b3eca3f041dc45d00de5cbd490729c\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"27ef08841e6e1231901c2e2770f4d847cb5e15a2\", \"swarm_hashes_refs/heads/master(at){#853922}_without_patch\": {\"absl_hardening_tests\": \"a93815522230df3e3aadff6e38cb3675b53c9d72\", \"accessibility_unittests\": \"170706b4a7c058615d91f242657fc33a97f71a17\", \"angle_unittests\": \"ba7fde709c2afd74af4b19cc54124a335177dba7\", \"app_shell_unittests\": \"01f2a96053fa790cecf69464b8061ef0d05c6d94\", \"aura_unittests\": \"f6bb0edc03cdf62b381dbd32503d05291726b117\", \"base_unittests\": \"8ccf96159336d96488f4b5f4601bd0b6e10519ff\", \"base_util_unittests\": \"db19e68e8b4849a64b4d946c2bdaeeec147da396\", \"blink_common_unittests\": \"3b64268e851c479b43dd90c5f03c2f8d74d50ea1\", \"blink_fuzzer_unittests\": \"332529e61b3a7a124dc4f48d5b6be09c4d587f8d\", \"blink_heap_unittests\": \"ea6b5e863ecc3564f73d7abc23cbebdd338961b4\", \"blink_platform_unittests\": \"85764773ad3334abbb0665003a184ba6aee650b3\", \"blink_python_tests\": \"821e12ed1cf5e1860536d8197a0939c2061c61b1\", \"blink_unittests\": \"5f573462240f13741528c63c05342bc0766815f6\", \"blink_web_tests\": \"d0ddbb1c721ec2b139183a8f6b4caf2ba9fb5609\", \"boringssl_crypto_tests\": \"02a18bce2b2db418f99b5f451fcec2cc8817a088\", \"boringssl_ssl_tests\": \"e1daad13fb890707a3da7fd1e63ddb68ee75fc47\", \"browser_tests\": \"61e4c599185611c5d72d26efb2c055659c3ad11f\", \"capture_unittests\": \"9978a8cf43c602187e76caa57cc26f57a2243e75\", \"cast_unittests\": \"7afa0cc01d0c25aed7a570dd92a070a9d0e1ef9b\", \"cc_unittests\": \"359144b9396de3a5c786d2f5c7894628a0c5141d\", \"chrome_app_unittests\": \"0110bfffb497ba4cbea5aacc85ebfc696be24787\", \"chromedriver_unittests\": \"d8b6af9b08222cc64d0c09c41135f4f5b92be44b\", \"components_browsertests\": \"2f606858004858447b9fb4c4911a58ebaa87c7d9\", \"components_unittests\": \"f8a8abd86952745f74e636b2fe7070d20279adf6\", \"compositor_unittests\": \"17aca455842e95fae9bee17e2a4e33a899852cb9\", \"content_browsertests\": \"6a91d9eb136785d6a32cc8b89a5ae0f9623922a7\", \"content_shell_crash_test\": \"e94df1b1ab81c40adf73b2570aada320e5451f94\", \"content_unittests\": \"5f2e8c7d278e3b1b4b24cd1d6e7ae8225d830029\", \"crashpad_tests\": \"86db55d3966d4dd1aff66001182b53589eae9175\", \"cronet_tests\": \"ae06de9fc645e12d2475c3a78f0450746f161291\", \"cronet_unittests\": \"21439f4753893dccb72572e6876ce0000f2a66f2\", \"crypto_unittests\": \"c2adf199a6365231eef45556cc59fdca30d6cc8e\", \"dbus_unittests\": \"fbe2fad91133c09662abf290eab1eb62d2a4f5ee\", \"device_unittests\": \"33793ec6ee1e740398e3f60368fcab24338b1a52\", \"display_unittests\": \"42fa8c01786697b5584b6e6713ddbdaf80a3b5b6\", \"events_unittests\": \"4e8db7cfd3ae953bdf791448708ec14c0bc6cc4a\", \"extensions_browsertests\": \"10d2da0ab43ae308b72ae49bfbd46941ca6fac1d\", \"extensions_unittests\": \"4db8fbf95c4d47aa53f7ca2acfcc60e1e7d60ce4\", \"filesystem_service_unittests\": \"9996fa2ef42db38d1dcbbc197101f98d7de942b3\", \"flatbuffers_unittests\": \"075150972de9869140291a211d23ae9aa4ff1469\", \"gcm_unit_tests\": \"6372da39469e606cea7a3fc8555ff9f98f2e75d1\", \"gfx_unittests\": \"e123b79bf7461a818833ace597cd2bce01563400\", \"gin_unittests\": \"f63492e89401f3e0c370c137c57261ba503580f7\", \"google_apis_unittests\": \"809c3c28e3fb96310a9438cab77b05c48fcd6708\", \"gpu_unittests\": \"54b33bdb3282679e23369a579509bfb78d5f5390\", \"grit_python_unittests\": \"6eb7a1f1dc57239cce2e1f3e8d13a9b7cd73171c\", \"gwp_asan_unittests\": \"1d5241d6573f064c64153d0c652e87fe8acb33bb\", \"headless_browsertests\": \"f64f6dd2e5fedff3eb973081ec7e0e4f27aa8843\", \"headless_unittests\": \"7fd68ecfeb652c8428c89b0b174a472e1e1db2a3\", \"interactive_ui_tests\": \"1b0e39e1a2bc8e3436ddfb84fe2f8fec4fe4b527\", \"ipc_tests\": \"182deb1e22a9d60ac9614ee86e59a3fc49e243df\", \"jingle_unittests\": \"1ea272f63b18fac334aad72a7222362d7909dc52\", \"latency_unittests\": \"0e7c2160bf28109ba445a71114196ee03e96f32c\", \"libjingle_xmpp_unittests\": \"11667e289dc5c6c27f26d77310302e19e4cc7392\", \"liburlpattern_unittests\": \"10cf42779a6b393951c37678902166bd407f65e6\", \"media_blink_unittests\": \"e1fc22c53e6dde4a9c18315b3bbd63058794c979\", \"media_unittests\": \"797cec1e6dcf20f6b0275f810497bd32410fc028\", \"message_center_unittests\": \"b7ce91765d7ac962775f7293d530de654ffa4988\", \"metrics_python_tests\": \"54610491f8b45d3bd91588a5b37ce264a660ae2f\", \"midi_unittests\": \"0397e2124cef8cf9a45e208f0c8416105c75fbbe\", \"mojo_core_unittests\": \"29dee78eb039d2ee7808c7853eac76758b2544b1\", \"mojo_unittests\": \"126bd692400094c20eeb3888780b0da62e564643\", \"nacl_helper_nonsfi_unittests\": \"24fe6938e1fb6c3845183562c4e2cf7af09155bb\", \"nacl_loader_unittests\": \"af77a99acd713ee00f972d861081d0184475494e\", \"native_theme_unittests\": \"6bcdd1fa80649d2d4f22dc2b48368b336c18c2eb\", \"net_unittests\": \"01f0e5d99c182962759db92e8137f71934df0804\", \"openscreen_unittests\": \"54e40c6ccf0827802d966e0eaec350685bcdb260\", \"pdf_unittests\": \"0912885be5cc8a07cd6777657e2a8f051fdcf647\", \"perfetto_unittests\": \"212fd825bd766afcb0c270fc3fe34b9530742d57\", \"ppapi_unittests\": \"67d53e960a4370c5003c11aa71a12d5b076f6f49\", \"printing_unittests\": \"ea7b2fd9c253b2b333f8ffc64b8afaaf0b97705a\", \"remoting_unittests\": \"928c32e3efff60b816d1f7797bd5f827bb310ec8\", \"sandbox_linux_unittests\": \"787d7667c4e363a8058b03a224f6446f86df2131\", \"service_manager_unittests\": \"96b5ff5c57f5029c132ec83b6ff8e0f7cb7a1368\", \"services_unittests\": \"c6af698659150766874476bcf4149dcbbc49a010\", \"shell_dialogs_unittests\": \"4b18e76ad78fd76aafae8dafec2345522569c631\", \"skia_unittests\": \"ca32407793aa79d15d057a0fef76fbbb2bb9d09d\", \"snapshot_unittests\": \"e84e1b6f9c920e28082564ff084c55d796c5d968\", \"sql_unittests\": \"823b62ed56b02dbb9f12ee207293a2fab31b2717\", \"storage_unittests\": \"d45565373db4214beeda4f04e2967a716ce5b9d1\", \"sync_integration_tests\": \"79072bd24f2b0096deb0caf260436bac2bb64850\", \"telemetry_gpu_unittests\": \"1842f6d8fa7bc4877fce36ef14d07a0bae7be5e1\", \"telemetry_perf_unittests\": \"e2d71efc83b23b487dd0f53f389bd91c64d3104c\", \"telemetry_unittests\": \"41dd6e19a94f795350ec2b0552ebb3942bc0f4b2\", \"traffic_annotation_auditor_unittests\": \"e41f28ec30fb9aedd4650ad91fa9073d4b6cf37f\", \"ui_base_unittests\": \"24597f257c8039f5da06d7052ea73034db331b37\", \"ui_touch_selection_unittests\": \"dd6bdf9d66b5f45c0df9c80eb69fb67cb4df11c2\", \"unit_tests\": \"550653ed1c8f526692e8bd69872d0071c6c1d9c3\", \"url_unittests\": \"aa03031674ddbde580bb3c1aa59a76772a5f4e6f\", \"views_perftests\": \"08bc5e4f796d112d1f64d6db241be4cd3d5d4a27\", \"views_unittests\": \"d41a8393844811c24d6c651f69a7dd7b42567190\", \"viz_unittests\": \"f8cb598e2efa1fdca62907eba9e42b455063c86c\", \"vr_common_unittests\": \"08111a81e79db7111ceb5c487e673d72587acc9a\", \"vr_pixeltests\": \"bf39ee6facf4886995e43efb967577f07f52a270\", \"webdriver_wpt_tests\": \"03b4c6d7d750781f66c91341c53e3d3b70d8567e\", \"weblayer_browsertests\": \"719481543a3f3b44523816c5b7c7838c457bf06c\", \"weblayer_unittests\": \"da5382eb6085f13f0eeb913b4bf703fd17613e11\", \"wm_unittests\": \"2cdeab61260c569678af4c9476a564413c2f5ee2\", \"wtf_unittests\": \"1c8e07483c85c340eba25933e1044188ee3360f2\", \"x11_unittests\": \"98d925fc0a2d64ae9d3ef25b7cf41efd3ce4528e\", \"xr_browser_tests\": \"7fcd072ae2094a412a8da96fec02fad7e984e518\", \"zlib_unittests\": \"c41f4f4ff796bbfe06463909468cbd1719c1bfa0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_6cd5ded23cefc640ae511b7a529237cb2561e39c1afb96e401c63016ddcf35a6_v2\", \"builder_a6d905fb3e765cf980c6458181cc20495ddb667b30aa758edddbed3b46166dd6_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_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-55-pdoj\"], \"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\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613339545014848",
"status": "COMPLETED",
"status_changed_ts": "1613342932518096",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180572",
"builder:Linux Builder (dbg)",
"buildset:commit/git/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/27ef08841e6e1231901c2e2770f4d847cb5e15a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020717763372519200",
"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/8855287701477016800/+/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:51bb2c0e8c6be210",
"user_agent:luci-scheduler"
],
"updated_ts": "1613342932602354",
"url": "https://ci.chromium.org/b/8855287701477016800",
"utcnow_ts": "1613342933399949"
}
}
[22:48:53.417] Invocation finished in 1h45m57.687877997s with status SUCCEEDED