[14:05:52.962] New invocation is queued and will start shortly
[14:05:54.510] Starting the invocation (attempt 1)
[14:05:54.598] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:05:54.598] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:05:54.598] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052090552083243472",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a755f09183daae4c20b3e166d0326c7b86751f77\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052090552083243472",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a755f09183daae4c20b3e166d0326c7b86751f77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a755f09183daae4c20b3e166d0326c7b86751f77",
"gitiles_ref:refs/heads/master"
]
}
[14:05:55.390] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1583417154738025",
"id": "8886660489512062576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a755f09183daae4c20b3e166d0326c7b86751f77\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583417155269638",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169370",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a755f09183daae4c20b3e166d0326c7b86751f77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a755f09183daae4c20b3e166d0326c7b86751f77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052090552083243472",
"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/8886660489512062576/+/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": "1583417155269767",
"url": "https://ci.chromium.org/b/8886660489512062576",
"utcnow_ts": "1583417155380408"
}
}
[14:05:55.390] Task URL: https://ci.chromium.org/b/8886660489512062576
[14:05:55.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:2:0) after 1m0s
[14:06:55.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:2:0)
[14:06:55.416] Timer tick, asking Buildbucket for the build status
[14:06:55.572] Build 8886660489512062576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:55.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:3:0) after 1m0s
[14:07:55.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:3:0)
[14:07:55.629] Timer tick, asking Buildbucket for the build status
[14:07:55.745] Build 8886660489512062576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:07:55.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:4:0) after 1m0s
[14:08:55.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:4:0)
[14:08:55.775] Timer tick, asking Buildbucket for the build status
[14:08:55.867] Build 8886660489512062576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:55.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:5:0) after 1m0s
[14:09:55.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:5:0)
[14:09:55.908] Timer tick, asking Buildbucket for the build status
[14:09:56.122] Build 8886660489512062576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:56.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:6:0) after 1m0s
[14:10:08.993] Received PubSub notification, asking Buildbucket for the build status
[14:10:09.251] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:56.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:6:0)
[14:10:56.186] Timer tick, asking Buildbucket for the build status
[14:10:56.308] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:56.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:8:0) after 1m0s
[14:11:56.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:8:0)
[14:11:56.332] Timer tick, asking Buildbucket for the build status
[14:11:56.422] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:56.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:9:0) after 1m0s
[14:12:56.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:9:0)
[14:12:56.454] Timer tick, asking Buildbucket for the build status
[14:12:56.794] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:56.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:10:0) after 1m0s
[14:13:56.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:10:0)
[14:13:56.889] Timer tick, asking Buildbucket for the build status
[14:13:56.972] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:56.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:11:0) after 1m0s
[14:14:57.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:11:0)
[14:14:57.009] Timer tick, asking Buildbucket for the build status
[14:14:57.304] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:12:0) after 1m0s
[14:15:57.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:12:0)
[14:15:57.339] Timer tick, asking Buildbucket for the build status
[14:15:57.429] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:57.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:13:0) after 1m0s
[14:16:57.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:13:0)
[14:16:57.452] Timer tick, asking Buildbucket for the build status
[14:16:57.550] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:57.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:14:0) after 1m0s
[14:17:57.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:14:0)
[14:17:57.796] Timer tick, asking Buildbucket for the build status
[14:17:57.929] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:57.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:15:0) after 1m0s
[14:18:57.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:15:0)
[14:18:57.998] Timer tick, asking Buildbucket for the build status
[14:18:58.144] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:58.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:16:0) after 1m0s
[14:19:58.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:16:0)
[14:19:58.211] Timer tick, asking Buildbucket for the build status
[14:19:58.285] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:58.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:17:0) after 1m0s
[14:20:58.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:17:0)
[14:20:58.383] Timer tick, asking Buildbucket for the build status
[14:20:58.532] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:58.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:18:0) after 1m0s
[14:21:58.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:18:0)
[14:21:58.749] Timer tick, asking Buildbucket for the build status
[14:21:58.849] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:58.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:19:0) after 1m0s
[14:22:59.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:19:0)
[14:22:59.122] Timer tick, asking Buildbucket for the build status
[14:22:59.445] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:59.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:20:0) after 1m0s
[14:23:59.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:20:0)
[14:23:59.502] Timer tick, asking Buildbucket for the build status
[14:23:59.642] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:59.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:21:0) after 1m0s
[14:24:59.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:21:0)
[14:24:59.759] Timer tick, asking Buildbucket for the build status
[14:24:59.868] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:59.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:22:0) after 1m0s
[14:25:59.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:22:0)
[14:25:59.947] Timer tick, asking Buildbucket for the build status
[14:26:00.142] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:00.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:23:0) after 1m0s
[14:27:00.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:23:0)
[14:27:00.283] Timer tick, asking Buildbucket for the build status
[14:27:00.501] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:00.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:24:0) after 1m0s
[14:28:00.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:24:0)
[14:28:00.618] Timer tick, asking Buildbucket for the build status
[14:28:00.781] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:00.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:25:0) after 1m0s
[14:29:01.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:25:0)
[14:29:01.045] Timer tick, asking Buildbucket for the build status
[14:29:01.110] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:01.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:26:0) after 1m0s
[14:30:01.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:26:0)
[14:30:01.180] Timer tick, asking Buildbucket for the build status
[14:30:01.298] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:01.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:27:0) after 1m0s
[14:31:01.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:27:0)
[14:31:01.285] Timer tick, asking Buildbucket for the build status
[14:31:01.416] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:01.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:28:0) after 1m0s
[14:32:01.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:28:0)
[14:32:01.493] Timer tick, asking Buildbucket for the build status
[14:32:01.616] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:01.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:29:0) after 1m0s
[14:33:01.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:29:0)
[14:33:01.865] Timer tick, asking Buildbucket for the build status
[14:33:01.929] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:01.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:30:0) after 1m0s
[14:34:02.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:30:0)
[14:34:02.036] Timer tick, asking Buildbucket for the build status
[14:34:02.143] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:02.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:31:0) after 1m0s
[14:35:02.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:31:0)
[14:35:02.442] Timer tick, asking Buildbucket for the build status
[14:35:02.690] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:02.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:32:0) after 1m0s
[14:36:02.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:32:0)
[14:36:02.713] Timer tick, asking Buildbucket for the build status
[14:36:02.970] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:02.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:33:0) after 1m0s
[14:37:03.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:33:0)
[14:37:03.141] Timer tick, asking Buildbucket for the build status
[14:37:03.352] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:03.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:34:0) after 1m0s
[14:38:03.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:34:0)
[14:38:03.399] Timer tick, asking Buildbucket for the build status
[14:38:03.474] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:03.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:35:0) after 1m0s
[14:39:03.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:35:0)
[14:39:03.639] Timer tick, asking Buildbucket for the build status
[14:39:03.787] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:03.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:36:0) after 1m0s
[14:40:03.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:36:0)
[14:40:03.773] Timer tick, asking Buildbucket for the build status
[14:40:03.910] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:03.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:37:0) after 1m0s
[14:41:03.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:37:0)
[14:41:03.933] Timer tick, asking Buildbucket for the build status
[14:41:04.279] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:04.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:38:0) after 1m0s
[14:42:04.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:38:0)
[14:42:04.341] Timer tick, asking Buildbucket for the build status
[14:42:04.552] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:04.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:39:0) after 1m0s
[14:43:04.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:39:0)
[14:43:04.574] Timer tick, asking Buildbucket for the build status
[14:43:04.672] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:04.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:40:0) after 1m0s
[14:44:04.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:40:0)
[14:44:04.732] Timer tick, asking Buildbucket for the build status
[14:44:04.822] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:04.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:41:0) after 1m0s
[14:45:04.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:41:0)
[14:45:04.859] Timer tick, asking Buildbucket for the build status
[14:45:05.027] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:05.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:42:0) after 1m0s
[14:46:05.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:42:0)
[14:46:05.109] Timer tick, asking Buildbucket for the build status
[14:46:05.211] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:05.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:43:0) after 1m0s
[14:47:05.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:43:0)
[14:47:05.327] Timer tick, asking Buildbucket for the build status
[14:47:05.571] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:05.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:44:0) after 1m0s
[14:48:05.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:44:0)
[14:48:05.618] Timer tick, asking Buildbucket for the build status
[14:48:05.774] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:05.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:45:0) after 1m0s
[14:49:05.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:45:0)
[14:49:05.804] Timer tick, asking Buildbucket for the build status
[14:49:05.995] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:05.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:46:0) after 1m0s
[14:50:06.023] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:46:0)
[14:50:06.023] Timer tick, asking Buildbucket for the build status
[14:50:06.208] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:06.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:47:0) after 1m0s
[14:51:06.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:47:0)
[14:51:06.318] Timer tick, asking Buildbucket for the build status
[14:51:06.850] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:06.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:48:0) after 1m0s
[14:52:06.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:48:0)
[14:52:06.924] Timer tick, asking Buildbucket for the build status
[14:52:07.488] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:07.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:49:0) after 1m0s
[14:53:07.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:49:0)
[14:53:07.499] Timer tick, asking Buildbucket for the build status
[14:53:07.618] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:07.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:50:0) after 1m0s
[14:54:07.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:50:0)
[14:54:07.715] Timer tick, asking Buildbucket for the build status
[14:54:07.991] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:07.991] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:51:0) after 1m0s
[14:55:08.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:51:0)
[14:55:08.004] Timer tick, asking Buildbucket for the build status
[14:55:08.467] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:08.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:52:0) after 1m0s
[14:56:08.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:52:0)
[14:56:08.595] Timer tick, asking Buildbucket for the build status
[14:56:08.836] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:08.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:53:0) after 1m0s
[14:57:08.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:53:0)
[14:57:08.851] Timer tick, asking Buildbucket for the build status
[14:57:08.955] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:08.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:54:0) after 1m0s
[14:58:08.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:54:0)
[14:58:08.981] Timer tick, asking Buildbucket for the build status
[14:58:09.283] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:55:0) after 1m0s
[14:59:09.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:55:0)
[14:59:09.335] Timer tick, asking Buildbucket for the build status
[14:59:09.498] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:09.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:56:0) after 1m0s
[15:00:09.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:56:0)
[15:00:09.499] Timer tick, asking Buildbucket for the build status
[15:00:09.975] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:09.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:57:0) after 1m0s
[15:01:10.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:57:0)
[15:01:10.049] Timer tick, asking Buildbucket for the build status
[15:01:10.368] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:10.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:58:0) after 1m0s
[15:02:10.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:58:0)
[15:02:10.400] Timer tick, asking Buildbucket for the build status
[15:02:10.528] Build 8886660489512062576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:10.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052090552083243472:59:0) after 1m0s
[15:02:33.760] Received PubSub notification, asking Buildbucket for the build status
[15:02:35.171] Build 8886660489512062576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:02:35.171] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1583420552696885",
"created_by": "project:chromium",
"created_ts": "1583417154738025",
"id": "8886660489512062576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a755f09183daae4c20b3e166d0326c7b86751f77\"}}",
"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\"}, \"$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-130-k7u5\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169370, \"got_angle_revision\": \"852653954526386c86f120f6120b70fb74904b94\", \"got_dawn_revision\": \"2de34c1791f301f8ec647afd9011dd3afd502465\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"a755f09183daae4c20b3e166d0326c7b86751f77\", \"got_revision_cp\": \"refs/heads/master@{#747250}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"a095711c5d771437d7f9bfcbd78a82062e19c55e\", \"got_v8_revision\": \"d4ed59c4ed609eacc9062bd22ebca409ef7c6d1c\", \"got_v8_revision_cp\": \"refs/heads/8.2.263@{#1}\", \"got_webrtc_revision\": \"16ddae924efbdead817e78e4d23eea9d4032b365\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30688}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a755f09183daae4c20b3e166d0326c7b86751f77\", \"swarm_hashes_refs/heads/master(at){#747250}_without_patch\": {\"accessibility_unittests\": \"82c0c03e3f2208d29abd32e38303cb8dacf6f1ee\", \"angle_unittests\": \"23efa4a47e8cc4091a982ac99a454da16f8cc86b\", \"app_shell_unittests\": \"ae17ebec9c61d3ff0111733a7c9bf6e1530e108a\", \"aura_unittests\": \"3b5da9cee9dd9647f1b6b641f07e911db639684e\", \"base_unittests\": \"3b3a61e7a8fb4f7ba3782c408d95023c680d25e3\", \"base_util_unittests\": \"8e20b72a24c05b6836d022481d279cbe023df36e\", \"blink_common_unittests\": \"a50eddc9c2708b7e1a20dcbaf2b387e05cc8254f\", \"blink_fuzzer_unittests\": \"ace4f9b536062b49998d476810e965aa60be8fc4\", \"blink_heap_unittests\": \"487f7eed4fbc67b60fad3279a075bdd4073d1f3c\", \"blink_platform_unittests\": \"7fcfa0c966c5660b2712b518f6fa8dcee303e55e\", \"blink_python_tests\": \"54382006559bdafa934c64d7c7ee77a98a6e4408\", \"blink_unittests\": \"153bd722437c25ac8e7776c09254125a9f0d6b9a\", \"blink_web_tests\": \"2041da0512bda881ec5fc791608da2570dc86f42\", \"boringssl_crypto_tests\": \"bf3bb5f3ca3c334f343aaac0f788ff22067ee4b0\", \"boringssl_ssl_tests\": \"f3ec9c58c7584a5680a64ac3d8d9cc96f98cc958\", \"browser_tests\": \"f6c60adbaec6b9ffbb17e087980e505eaff30005\", \"cacheinvalidation_unittests\": \"c2e23ce9b89246f05410f8fecfffb7cadfb92334\", \"capture_unittests\": \"e55e226050933309b7b8d98964b5c4a6c3212ccd\", \"cast_unittests\": \"552ab2714ce7b129ffcd01dd143a43e37a32cb8f\", \"cc_unittests\": \"ef20c50b8a4bf35664c36c02b3212016d591b7d7\", \"chrome_app_unittests\": \"1954b0e68c3f9d4db90e4fef2431f261ffd55ccb\", \"chromedriver_unittests\": \"417a64d7f008c6f20db93ff0ad67c79ca26ad236\", \"components_browsertests\": \"871ca956d38beac85a845c5c6d7f8c74b5b5bb8c\", \"components_unittests\": \"49ef9bae5c5070f62a35ec40e6eddc5427c7a626\", \"compositor_unittests\": \"29a18b82f3d760af30d0377f5a614bca4faa65f0\", \"content_browsertests\": \"4f316a40a3fa2ec61936c5ab9db8fc2b95190001\", \"content_shell_crash_test\": \"1289f59f5f1f8de6c3e87ad62e9d789f6281bc52\", \"content_unittests\": \"f3baa4d8cbcce294290515cda73facd3530b006a\", \"crashpad_tests\": \"a4f13819c229e67771587007a603121b80e23b0c\", \"cronet_tests\": \"856062e4735924390d47ae1c02368700d96d6051\", \"cronet_unittests\": \"5a66bf81fe6178140ecfad560793b19312441185\", \"crypto_unittests\": \"f5a1cd119af18bccf8cd33adba31a13153f1b582\", \"dbus_unittests\": \"1978065da6660ea75cf533ccc601cdcf81c92f07\", \"device_unittests\": \"707fbc5615f6d1400a6ffaaae16adb71c51f609c\", \"display_unittests\": \"4839ba2f111017416d1f2b6ce3ba2f5d0060375c\", \"events_unittests\": \"9241c5bcc6db7809d231746bb1e258c4408334d4\", \"extensions_browsertests\": \"d08c2f3b8546aa45adbfe07a85eeb484b1cdf874\", \"extensions_unittests\": \"c637ee2298f87e2a32b13d39d6dc44f3eab87ade\", \"filesystem_service_unittests\": \"43275ff6521319799a1d9a3d4c8c41415e5513a2\", \"flatbuffers_unittests\": \"501dfa51280eae93fccecaec30d9257cff6dbcc8\", \"gcm_unit_tests\": \"ab49fe2b6c053f6bd8e658c2ae5724437ba6d889\", \"gfx_unittests\": \"5a81fc12082471741ed90a03bb44fd0956f5c5ad\", \"gin_unittests\": \"d872ce46d473be9fb6f7789d1901dff4c3782851\", \"google_apis_unittests\": \"12a8acf009e2f49eb5748f4359b8bea9da67fe74\", \"gpu_unittests\": \"e8ab6dceb051282c7cee34b778e7e2900b1c6fe8\", \"grit_python_unittests\": \"300ea8455288eab00cd459e887bd99a4f6468e8a\", \"gwp_asan_unittests\": \"5fa05c765af435a726e40cca2541e8b5500a88c6\", \"headless_browsertests\": \"b2aecbeaeb6d62c221840d158cef22f2d90299e5\", \"headless_unittests\": \"cbd267563204c6efcaffede6755723fd33e6ae9d\", \"interactive_ui_tests\": \"9ad57de24096dc6d17a878b38392b1e56746652a\", \"ipc_tests\": \"2f936ea3252e2763d3357c2e84b88290a400be47\", \"jingle_unittests\": \"d773dc35f1d88af8568f7c58d46b19f6678162ba\", \"latency_unittests\": \"02770a7d5ff314cb5b4729b37d2a519ec2f37681\", \"libjingle_xmpp_unittests\": \"43abb9a8a66123209afdf884cb58f870658814d6\", \"media_blink_unittests\": \"f4d55dfcdebbe32afe98d58c18e485ded3b4c89f\", \"media_service_unittests\": \"6aecd095ea6953db9c803335c92202fb955075c3\", \"media_unittests\": \"96117d5fece53cfc632dca39d3114e78b51fd609\", \"message_center_unittests\": \"2807baede223207a756431743408c55e6ba833b8\", \"metrics_python_tests\": \"f391b3e8d9ad56e25b3e13c33ee0c31230d5d334\", \"midi_unittests\": \"044b47f8e2881841a7a939193e024fff86734e1c\", \"mojo_core_unittests\": \"d9130ac3b012995cbe17b140762a8ea82205bb89\", \"mojo_unittests\": \"6d4eb680ab7360d944c78644a6823c83ef31fe44\", \"nacl_helper_nonsfi_unittests\": \"6f8b78263215c23e995dd3efdc949a90d5df8552\", \"nacl_loader_unittests\": \"0ed754dfc0bc38976e5b271883b937dcf89ef1df\", \"native_theme_unittests\": \"b3d5e6eb3251a6f426b19c55c17bb46c89f6f594\", \"net_unittests\": \"961337c1782ac8e454150e29d2efc20453148f32\", \"pdf_unittests\": \"ab2dce237e17e66e9c257c42c9217432f38e9d43\", \"perfetto_unittests\": \"4e5ffaaeb5649346795ac4fc22c9d06ec254236c\", \"ppapi_unittests\": \"7ca244adb1d4b1db7815f18cb13d03f5bb31093b\", \"printing_unittests\": \"68c72e650c02231f68b1fbc8a455b0eed5954f59\", \"remoting_unittests\": \"5de0f7ff63864789d8f60a84a4fd6d7e2d344a58\", \"sandbox_linux_unittests\": \"60283633c0a5503e56b5bd5d390b076271e83316\", \"service_manager_unittests\": \"1661352ae1a4dc24e03ff1e227f28af5a96aaa8b\", \"services_unittests\": \"23a7db7aaa90fa1249b3193a3a5aa0df745471b1\", \"shell_dialogs_unittests\": \"afc6863f991e37fec8e1c57f71fb3539f2cd622f\", \"skia_unittests\": \"bac0563cf01c4601de446d7b8b23b83053d83bc5\", \"snapshot_unittests\": \"abe24fafe7383e8d4e9ba4763e19dc2202570a95\", \"sql_unittests\": \"22cfc836748410e09f1840ce015d5afe954b1204\", \"storage_unittests\": \"64001eb02df1d96372960dcdd01ca0ca9deb68f2\", \"sync_integration_tests\": \"3f99cf3ec7edd9156721424fe1bad1fb5be01a42\", \"telemetry_gpu_unittests\": \"3d611427235b7b825d750d9aa037f33f6e35fd02\", \"telemetry_perf_unittests\": \"f6a7fb25d6f92b8a98402cf8ebbf0724d088fa3a\", \"telemetry_unittests\": \"e653aae4aae2b38b3e90418f261c72e23452be06\", \"traffic_annotation_auditor_unittests\": \"f2f823e37770e0130e4e5396c026b120e1559999\", \"ui_base_unittests\": \"7c48bb9c4cc52f91e91b7ed972f84e83b8039629\", \"ui_touch_selection_unittests\": \"e10c8552362e3863ab5ecf9a2a8ea264cb61b4cb\", \"unit_tests\": \"c01957eaf3f0dcd52d8a1afd60938967d8d4cef7\", \"url_unittests\": \"2c0e20d89abaff674dc411b2bf163c063d962e48\", \"views_perftests\": \"f1fe4b9d884bbfce9495c58d1533b26be94f76bf\", \"views_unittests\": \"49adf41d2b735eea96b6a6c7e064bc8d72ca7c2a\", \"viz_unittests\": \"aa8c375ad464459367f9967248b5896cbe6a79ae\", \"vr_common_unittests\": \"5c76f2080412e32ed4cadd1a9ba36a0ca64008d1\", \"vr_pixeltests\": \"4e365acab13432f066cf6e67ea70a5ade6cd35da\", \"webdriver_wpt_tests\": \"3f206142e01db78566458352e552cadd5b7a2387\", \"weblayer_browsertests\": \"7b21a9fec970e35ac974e636187667d39a73638e\", \"wm_unittests\": \"e6045e70a98f98c535730acf0f56c4c27f650067\", \"wtf_unittests\": \"6993afa1426a2e081596fdf6264746790459875e\", \"xr_browser_tests\": \"029d82207b1612bf7eb625721ffd9d283a4326f0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4735eb974b804f74efeb4253a3690e62eb4b060f1d311c87e54f3b58924ad1a2_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-130-k7u5\"], \"image\": [\"chrome-xenial-20022300-4c0e34b5b5e\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583417406898654",
"status": "COMPLETED",
"status_changed_ts": "1583420553323268",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169370",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a755f09183daae4c20b3e166d0326c7b86751f77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a755f09183daae4c20b3e166d0326c7b86751f77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052090552083243472",
"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/8886660489512062576/+/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:4ac3d5e78477bd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583420553326875",
"url": "https://ci.chromium.org/b/8886660489512062576",
"utcnow_ts": "1583420555120951"
}
}
[15:02:35.171] Invocation finished in 56m42.269798868s with status SUCCEEDED