[21:22:40.938] New invocation is queued and will start shortly
[21:22:42.557] Starting the invocation (attempt 1)
[21:22:42.601] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:22:42.601] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:22:42.601] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054690383011885072",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a3624427f3f46301408242dcd4c08c1ac8a59c2d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054690383011885072",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"gitiles_ref:refs/heads/master"
]
}
[21:22:44.271] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580937763270628",
"id": "8889260319899663696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3624427f3f46301408242dcd4c08c1ac8a59c2d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580937764037468",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168272",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054690383011885072",
"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/8889260319899663696/+/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": "1580937764037620",
"url": "https://ci.chromium.org/b/8889260319899663696",
"utcnow_ts": "1580937764253913"
}
}
[21:22:44.271] Task URL: https://ci.chromium.org/b/8889260319899663696
[21:22:44.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:2:0) after 1m0s
[21:23:44.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:2:0)
[21:23:44.381] Timer tick, asking Buildbucket for the build status
[21:23:45.062] Build 8889260319899663696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:45.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:3:0) after 1m0s
[21:24:45.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:3:0)
[21:24:45.134] Timer tick, asking Buildbucket for the build status
[21:24:45.329] Build 8889260319899663696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:45.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:4:0) after 1m0s
[21:25:45.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:4:0)
[21:25:45.442] Timer tick, asking Buildbucket for the build status
[21:25:45.633] Build 8889260319899663696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:45.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:5:0) after 1m0s
[21:26:45.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:5:0)
[21:26:45.659] Timer tick, asking Buildbucket for the build status
[21:26:45.875] Build 8889260319899663696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:45.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:6:0) after 1m0s
[21:27:18.200] Received PubSub notification, asking Buildbucket for the build status
[21:27:18.430] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:45.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:6:0)
[21:27:45.916] Timer tick, asking Buildbucket for the build status
[21:27:46.465] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:46.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:8:0) after 1m0s
[21:28:46.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:8:0)
[21:28:46.638] Timer tick, asking Buildbucket for the build status
[21:28:46.820] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:46.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:9:0) after 1m0s
[21:29:46.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:9:0)
[21:29:46.849] Timer tick, asking Buildbucket for the build status
[21:29:47.159] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:47.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:10:0) after 1m0s
[21:30:47.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:10:0)
[21:30:47.223] Timer tick, asking Buildbucket for the build status
[21:30:47.371] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:47.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:11:0) after 1m0s
[21:31:47.495] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:11:0)
[21:31:47.495] Timer tick, asking Buildbucket for the build status
[21:31:48.047] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:48.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:12:0) after 1m0s
[21:32:48.154] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:12:0)
[21:32:48.154] Timer tick, asking Buildbucket for the build status
[21:32:48.552] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:48.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:13:0) after 1m0s
[21:33:48.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:13:0)
[21:33:48.670] Timer tick, asking Buildbucket for the build status
[21:33:48.893] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:48.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:14:0) after 1m0s
[21:34:48.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:14:0)
[21:34:48.957] Timer tick, asking Buildbucket for the build status
[21:34:49.197] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:49.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:15:0) after 1m0s
[21:35:49.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:15:0)
[21:35:49.245] Timer tick, asking Buildbucket for the build status
[21:35:49.402] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:49.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:16:0) after 1m0s
[21:36:49.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:16:0)
[21:36:49.483] Timer tick, asking Buildbucket for the build status
[21:36:49.650] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:49.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:17:0) after 1m0s
[21:37:49.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:17:0)
[21:37:49.656] Timer tick, asking Buildbucket for the build status
[21:37:49.944] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:49.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:18:0) after 1m0s
[21:38:49.980] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:18:0)
[21:38:49.981] Timer tick, asking Buildbucket for the build status
[21:38:50.137] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:50.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:19:0) after 1m0s
[21:39:50.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:19:0)
[21:39:50.291] Timer tick, asking Buildbucket for the build status
[21:39:50.466] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:50.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:20:0) after 1m0s
[21:40:50.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:20:0)
[21:40:50.616] Timer tick, asking Buildbucket for the build status
[21:40:50.802] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:50.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:21:0) after 1m0s
[21:41:50.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:21:0)
[21:41:50.785] Timer tick, asking Buildbucket for the build status
[21:41:51.038] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:51.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:22:0) after 1m0s
[21:42:51.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:22:0)
[21:42:51.016] Timer tick, asking Buildbucket for the build status
[21:42:51.194] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:51.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:23:0) after 1m0s
[21:43:51.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:23:0)
[21:43:51.274] Timer tick, asking Buildbucket for the build status
[21:43:51.487] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:51.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:24:0) after 1m0s
[21:44:51.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:24:0)
[21:44:51.479] Timer tick, asking Buildbucket for the build status
[21:44:51.645] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:51.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:25:0) after 1m0s
[21:45:51.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:25:0)
[21:45:51.804] Timer tick, asking Buildbucket for the build status
[21:45:52.249] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:52.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:26:0) after 1m0s
[21:46:52.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:26:0)
[21:46:52.343] Timer tick, asking Buildbucket for the build status
[21:46:52.896] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:52.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:27:0) after 1m0s
[21:47:53.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:27:0)
[21:47:53.296] Timer tick, asking Buildbucket for the build status
[21:47:53.790] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:28:0) after 1m0s
[21:48:53.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:28:0)
[21:48:53.925] Timer tick, asking Buildbucket for the build status
[21:48:54.172] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:29:0) after 1m0s
[21:49:54.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:29:0)
[21:49:54.358] Timer tick, asking Buildbucket for the build status
[21:49:54.764] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:30:0) after 1m0s
[21:50:55.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:30:0)
[21:50:55.055] Timer tick, asking Buildbucket for the build status
[21:50:55.268] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:55.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:31:0) after 1m0s
[21:51:55.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:31:0)
[21:51:55.468] Timer tick, asking Buildbucket for the build status
[21:51:55.627] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:55.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:32:0) after 1m0s
[21:52:55.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:32:0)
[21:52:55.767] Timer tick, asking Buildbucket for the build status
[21:52:55.962] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:55.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:33:0) after 1m0s
[21:53:56.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:33:0)
[21:53:56.011] Timer tick, asking Buildbucket for the build status
[21:53:56.189] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:56.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:34:0) after 1m0s
[21:54:56.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:34:0)
[21:54:56.437] Timer tick, asking Buildbucket for the build status
[21:54:57.075] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:57.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:35:0) after 1m0s
[21:55:57.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:35:0)
[21:55:57.168] Timer tick, asking Buildbucket for the build status
[21:55:57.362] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:57.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:36:0) after 1m0s
[21:56:57.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:36:0)
[21:56:57.410] Timer tick, asking Buildbucket for the build status
[21:56:57.562] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:57.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:37:0) after 1m0s
[21:57:57.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:37:0)
[21:57:57.589] Timer tick, asking Buildbucket for the build status
[21:57:57.761] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:57.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:38:0) after 1m0s
[21:58:57.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:38:0)
[21:58:57.808] Timer tick, asking Buildbucket for the build status
[21:58:58.113] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:58.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:39:0) after 1m0s
[21:59:58.217] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:39:0)
[21:59:58.217] Timer tick, asking Buildbucket for the build status
[22:00:00.062] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:00.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:40:0) after 1m0s
[22:01:00.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:40:0)
[22:01:00.191] Timer tick, asking Buildbucket for the build status
[22:01:01.316] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:01.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:41:0) after 1m0s
[22:02:01.396] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:41:0)
[22:02:01.396] Timer tick, asking Buildbucket for the build status
[22:02:02.015] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:02.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:42:0) after 1m0s
[22:03:02.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:42:0)
[22:03:02.082] Timer tick, asking Buildbucket for the build status
[22:03:02.329] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:02.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:43:0) after 1m0s
[22:04:02.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:43:0)
[22:04:02.428] Timer tick, asking Buildbucket for the build status
[22:04:02.614] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:02.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:44:0) after 1m0s
[22:05:02.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:44:0)
[22:05:02.714] Timer tick, asking Buildbucket for the build status
[22:05:02.893] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:02.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:45:0) after 1m0s
[22:06:02.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:45:0)
[22:06:02.872] Timer tick, asking Buildbucket for the build status
[22:06:03.332] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:03.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:46:0) after 1m0s
[22:07:03.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:46:0)
[22:07:03.360] Timer tick, asking Buildbucket for the build status
[22:07:03.553] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:03.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:47:0) after 1m0s
[22:08:03.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:47:0)
[22:08:03.653] Timer tick, asking Buildbucket for the build status
[22:08:04.062] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:48:0) after 1m0s
[22:09:04.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:48:0)
[22:09:04.659] Timer tick, asking Buildbucket for the build status
[22:09:04.815] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:04.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:49:0) after 1m0s
[22:10:04.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:49:0)
[22:10:04.842] Timer tick, asking Buildbucket for the build status
[22:10:05.228] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:05.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:50:0) after 1m0s
[22:11:05.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:50:0)
[22:11:05.349] Timer tick, asking Buildbucket for the build status
[22:11:05.967] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:05.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:51:0) after 1m0s
[22:12:06.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:51:0)
[22:12:06.001] Timer tick, asking Buildbucket for the build status
[22:12:07.109] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:07.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:52:0) after 1m0s
[22:13:07.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:52:0)
[22:13:07.145] Timer tick, asking Buildbucket for the build status
[22:13:07.619] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:07.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:53:0) after 1m0s
[22:14:07.743] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:53:0)
[22:14:07.743] Timer tick, asking Buildbucket for the build status
[22:14:07.962] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:07.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:54:0) after 1m0s
[22:15:07.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:54:0)
[22:15:07.981] Timer tick, asking Buildbucket for the build status
[22:15:08.168] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:08.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:55:0) after 1m0s
[22:16:08.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:55:0)
[22:16:08.226] Timer tick, asking Buildbucket for the build status
[22:16:09.797] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:09.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:56:0) after 1m0s
[22:17:09.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:56:0)
[22:17:09.837] Timer tick, asking Buildbucket for the build status
[22:17:10.074] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:10.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:57:0) after 1m0s
[22:18:10.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:57:0)
[22:18:10.051] Timer tick, asking Buildbucket for the build status
[22:18:10.357] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:10.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:58:0) after 1m0s
[22:19:10.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:58:0)
[22:19:10.457] Timer tick, asking Buildbucket for the build status
[22:19:10.738] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:10.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:59:0) after 1m0s
[22:20:10.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:59:0)
[22:20:10.792] Timer tick, asking Buildbucket for the build status
[22:20:10.974] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:10.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:60:0) after 1m0s
[22:21:11.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:60:0)
[22:21:11.016] Timer tick, asking Buildbucket for the build status
[22:21:11.209] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:11.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:61:0) after 1m0s
[22:22:11.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:61:0)
[22:22:11.351] Timer tick, asking Buildbucket for the build status
[22:22:11.615] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:11.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:62:0) after 1m0s
[22:23:11.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:62:0)
[22:23:11.948] Timer tick, asking Buildbucket for the build status
[22:23:12.368] Build 8889260319899663696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:12.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054690383011885072:63:0) after 1m0s
[22:23:25.693] Received PubSub notification, asking Buildbucket for the build status
[22:23:25.792] Build 8889260319899663696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:23:25.792] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580941405106838",
"created_by": "project:chromium",
"created_ts": "1580937763270628",
"id": "8889260319899663696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3624427f3f46301408242dcd4c08c1ac8a59c2d\"}}",
"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-east1-d-8-77-amnw\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168272, \"got_angle_revision\": \"a44d96dcd676fc7d446b41fb766caad9bdab9fda\", \"got_dawn_revision\": \"47b15048a1fab6c360dc1594764506224655752b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"a3624427f3f46301408242dcd4c08c1ac8a59c2d\", \"got_revision_cp\": \"refs/heads/master@{#738677}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8e8e1d0dd7292440b803b95bc0832589520f784d\", \"got_v8_revision\": \"4dc7c627a1ebc90c34cdb39c44015a83d924f4ee\", \"got_v8_revision_cp\": \"refs/heads/8.2.12@{#1}\", \"got_webrtc_revision\": \"02b17a5507ae6be7cb8775760627b79b7faba69c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30458}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3624427f3f46301408242dcd4c08c1ac8a59c2d\", \"swarm_hashes_refs/heads/master(at){#738677}_without_patch\": {\"accessibility_unittests\": \"fb364f0b4cc7431976061ff8d97dd945f5f7de00\", \"angle_unittests\": \"e6a2e07d5c12067b71ac162aef35b88cfe7d948a\", \"app_shell_unittests\": \"88fc8102c06f8e9fa3e41ecd184821f472bbb227\", \"aura_unittests\": \"2db487c868ced0267d70950d6c1486f0015e7629\", \"base_unittests\": \"d46b71b91e220be839fa06781d8dc2a749bb5ddb\", \"base_util_unittests\": \"1fb24bf247e4c9ff5abdd7750c9e5d4ba74014d0\", \"blink_common_unittests\": \"f6d05c8f115424393570729bbd494907d6f579c1\", \"blink_fuzzer_unittests\": \"d392246f3857499fc70e471464233c4be322f66e\", \"blink_heap_unittests\": \"e00e124f5c70fff72b0a63b0db7ff593799d4a8a\", \"blink_platform_unittests\": \"09fe8c140819f820b8ef3849b5ef8360e2ee47db\", \"blink_python_tests\": \"45f126c04cedc68e444171b6efbd9b742febd59f\", \"blink_unittests\": \"57c8f7193f26a972da40bad8c0f8b548cce85f8e\", \"blink_web_tests\": \"6d43306fedf73df90ebd2d658c6afea8eefd4b03\", \"boringssl_crypto_tests\": \"59ffb3dea1f049cbd1273457caef906771add2ec\", \"boringssl_ssl_tests\": \"9d93285a4117d25760a190a2546ea756631a04f4\", \"browser_tests\": \"5bfde0e643f449059a74e4399d3a9d31632b89a4\", \"cacheinvalidation_unittests\": \"6380104f37c3571d0d5b29db58ed9a95e25d4918\", \"capture_unittests\": \"ef39cbeb4e81e839dad0c7fe15d806f1aa7e1119\", \"cast_unittests\": \"5b63e6e463ef4cac0111b28f7beba87542bddf75\", \"cc_unittests\": \"1126f5de011095b9cf7e719ac77ee35c857cc6a7\", \"chrome_app_unittests\": \"8076d6022961a6951b400d97825813f62088fb03\", \"chromedriver_unittests\": \"50ed5aed524e3a9448be3c5eb0f997a384b36b86\", \"components_browsertests\": \"700ca5f18fc01806337bca567e4e838d8538c7de\", \"components_unittests\": \"f695e37b759725439c52d83fc755cbc30ecfa8f4\", \"compositor_unittests\": \"5abaa12217f40f2b579dd4429ac6cb3d74ba6dd7\", \"content_browsertests\": \"fee2c7ed0b171f889aea9b89a2ddb2ab7943f02f\", \"content_shell_crash_test\": \"2a1642c0359a10911d6ee84755b4b056d6e35713\", \"content_unittests\": \"862934799c7992991de0a20b91fe76f103e143cc\", \"crashpad_tests\": \"97dca390746ab8567b9524558b89a33e0117cc59\", \"cronet_tests\": \"43ad178ed4705e7f6c89b175ca15d6cbdb5a5187\", \"cronet_unittests\": \"d2cbd25444ae962340b98683ba6d897d3fb006d2\", \"crypto_unittests\": \"11dde5e8a63f1a3f08858116887b4741e24d9071\", \"dbus_unittests\": \"9ca9077bb30479a16583f3ad19e7871f79bd83b8\", \"device_unittests\": \"b1c198830cb92b8693eaaee5a59947df194d839a\", \"display_unittests\": \"fb772c85be42f2ed919bb9f4d1033df913513e1b\", \"events_unittests\": \"bddbeff10dd3141c4c859814c483bcd190be2904\", \"extensions_browsertests\": \"864cf81b8a0b95da60363eb5af7d313de39bfe61\", \"extensions_unittests\": \"203c2fa6fac2c035dacfb9f4c9ca3575d439a159\", \"filesystem_service_unittests\": \"0b01fcc33be9dc424593c044c839c7f7662da738\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"a6f5fa024ad6e03f5c0bacc7b1357adbd1ba2cb2\", \"gfx_unittests\": \"19f02fb8c934db078bb54b7b3c12b7f503a49e9b\", \"gin_unittests\": \"7eebd351168cd96c81b921053efa297f52b4591e\", \"google_apis_unittests\": \"6abcb844967e24c17c78d21a04897ca7428d9524\", \"gpu_unittests\": \"46426065738fcc8563667da755db1f10eecf0285\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"8fc6d56be132770b2ee265dbeddb3b544a9642a0\", \"headless_browsertests\": \"87fce26e43664c5ff92bb6f15f42844b82ca11d0\", \"headless_unittests\": \"513f68afeac22b9cba03375efc0454ba3282bed8\", \"interactive_ui_tests\": \"b13020f66087a6955674c12a1a8d442db46eaa29\", \"ipc_tests\": \"5dcd2a9028d4c558e52cdd0490fde05ad9015584\", \"jingle_unittests\": \"53bdc5e4788d8b0932a45efe355c5a6888d5820f\", \"latency_unittests\": \"b7876365663300c3d99f5cb91302763b279b2592\", \"libjingle_xmpp_unittests\": \"02ff42a8c9eba8b98bd390630bfd51bc99bb00fc\", \"media_blink_unittests\": \"56b15ef6cf7c61fd04d13768bcec66d735cc78e3\", \"media_service_unittests\": \"5b7e2fe764fb0594962a5a6c0ad4a80cf268c254\", \"media_unittests\": \"61cda8a16f81afcf216e30ea5cebadb9064735df\", \"message_center_unittests\": \"bcea467d115f35cac4556e0e9ab4eb68e0578ea4\", \"metrics_python_tests\": \"7033f65fa42ad3ebb05337f83f4d7bfdf806277f\", \"midi_unittests\": \"0300237b144968e57853e02fe1cda46eec29617f\", \"mojo_core_unittests\": \"df61682da07ad1c046f0577d98fec4c0f422bc18\", \"mojo_unittests\": \"ab9577c4251b73e646dce14c77eab40d58c9d8e4\", \"nacl_helper_nonsfi_unittests\": \"53cd259b7b1e63cfce3b0db5e6fbd19310b9abc2\", \"nacl_loader_unittests\": \"68ab559e8f763f8f2ada3758d1a9340235e7849e\", \"native_theme_unittests\": \"2faeaacba6facfe27662e71b22bdaada72e8e5d7\", \"net_unittests\": \"35939673035d6732ed9cc40df56f2afc2dbcef76\", \"pdf_unittests\": \"277e8de865c0058776e43dc8fe978f5baeb51402\", \"perfetto_unittests\": \"977903dd56561d1d863119e8fe34709a9264e30b\", \"ppapi_unittests\": \"0598437f7b4781b628a79f0d71b8310885e0b401\", \"printing_unittests\": \"6a8ce568d8ce3d592beeadeb3e1ece1df6e9932a\", \"remoting_unittests\": \"ac32ea7d7f18e991e936519a4ca5a64c987f50ec\", \"sandbox_linux_unittests\": \"8fd52c5d27e8e0be6081b7efa31224dae8cb44f6\", \"service_manager_unittests\": \"8acc0f57217d1a27206209dff19b0ac28041f61e\", \"services_unittests\": \"e882e831a4ba4a43e5c8c5898ee46687abe8d098\", \"shell_dialogs_unittests\": \"9c333703677fbb600a90d88b916965f4573e7dc2\", \"skia_unittests\": \"77267e78785200192888540a15cd445f20327c60\", \"snapshot_unittests\": \"aa711e99a538e1930b46b59ece205ffc74a483b3\", \"sql_unittests\": \"e0b85af04651f692556bb86f3d7c34732b893b77\", \"storage_unittests\": \"a2c07c5a2abe12bb518c0921e79a7a65c0485b24\", \"sync_integration_tests\": \"5b8f76f21fefb76b611ad8b9ddc65647746cc8cc\", \"telemetry_gpu_unittests\": \"143decd4b30897d6af444e670136a1330fc29ed4\", \"telemetry_perf_unittests\": \"537bb9e892c97fe03b72e878641c05f188187f50\", \"telemetry_unittests\": \"7cd2d7826e24b51c6be99e90e237e818530f862b\", \"traffic_annotation_auditor_unittests\": \"33fa11c76213c0b6c629d9a21a4555bae13eeca9\", \"ui_base_unittests\": \"8f1a4aefe018eb56baa3f34e04e6da65373d11f5\", \"ui_touch_selection_unittests\": \"2d141f80033150c063ce8ba936790414cba959e7\", \"unit_tests\": \"944cfe66e58ade719fc589a08147433ac9931539\", \"url_unittests\": \"34652ba7cca8da879ad7ab10b09f204ed0a12188\", \"views_perftests\": \"1aa07c83be2101e5e9345b2e8167cdd8e34f93e6\", \"views_unittests\": \"eaa88b882a81cb179a8807a90156a38238bbf1ad\", \"viz_unittests\": \"baaeb5ae7c6748de6c4b1476975aed76708d3b20\", \"vr_common_unittests\": \"33e117e6612c952e9c9149486053f1cd83f61668\", \"vr_pixeltests\": \"cb12e7b1dd425d989d6b770e4c57cf31fa618cfc\", \"webdriver_wpt_tests\": \"5192c0ebe321f7c3db2046acb2f54aff3a0efb6b\", \"weblayer_browsertests\": \"957b6c42265b3d1b3622efa57b5d505bba019a13\", \"wm_unittests\": \"4cca3c4ce409247a81835a81be4597d6cc48b954\", \"wtf_unittests\": \"d17ce118e225ff0acc5f9028a6c3528ef9cb6f47\", \"xr_browser_tests\": \"fd68eb624ecc93dd3f08d296d84ae1bac8b708db\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_26ee0c713798d591dc066c3b18aba10c428b81e49e5d430ca5b1f8f50ef6f654_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_a3b3a06131dfa9924971b27d0acd4e18e80a2242b6d6ae41f94b770d7c996d6f_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_ee0cba3c54f4de5afe190f5f1f18eea3c236437d0f634917582eff9316b81bdc_v2\", \"builder_f5529a61c4772e2a2b7c80424c65e6b688bd4755892e8f82a67b8d7980cd8e00_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-east1-d-8-77-amnw\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580938037383370",
"status": "COMPLETED",
"status_changed_ts": "1580941405477381",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168272",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3624427f3f46301408242dcd4c08c1ac8a59c2d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054690383011885072",
"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/8889260319899663696/+/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:4a300d6601ffdd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580941405481923",
"url": "https://ci.chromium.org/b/8889260319899663696",
"utcnow_ts": "1580941405781095"
}
}
[22:23:25.792] Invocation finished in 1h0m44.873480501s with status SUCCEEDED