[02:09:55.413] New invocation is queued and will start shortly
[02:09:56.526] Starting the invocation (attempt 1)
[02:09:56.599] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:09:56.599] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:09:56.599] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053494550794741552",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"41abf0c4d560d7bcee55415422fc722caacd36c2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053494550794741552",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/41abf0c4d560d7bcee55415422fc722caacd36c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41abf0c4d560d7bcee55415422fc722caacd36c2",
"gitiles_ref:refs/heads/master"
]
}
[02:09:57.867] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582078196726947",
"id": "8888064488748570992",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41abf0c4d560d7bcee55415422fc722caacd36c2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582078197591867",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168773",
"builder:Linux Builder (dbg)",
"buildset:commit/git/41abf0c4d560d7bcee55415422fc722caacd36c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41abf0c4d560d7bcee55415422fc722caacd36c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053494550794741552",
"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/8888064488748570992/+/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": "1582078197592048",
"url": "https://ci.chromium.org/b/8888064488748570992",
"utcnow_ts": "1582078197853422"
}
}
[02:09:57.867] Task URL: https://ci.chromium.org/b/8888064488748570992
[02:09:57.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:2:0) after 1m0s
[02:10:57.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:2:0)
[02:10:57.889] Timer tick, asking Buildbucket for the build status
[02:10:58.900] Build 8888064488748570992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:3:0) after 1m0s
[02:11:58.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:3:0)
[02:11:58.956] Timer tick, asking Buildbucket for the build status
[02:11:59.072] Build 8888064488748570992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:59.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:4:0) after 1m0s
[02:12:59.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:4:0)
[02:12:59.123] Timer tick, asking Buildbucket for the build status
[02:12:59.291] Build 8888064488748570992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:59.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:5:0) after 1m0s
[02:13:59.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:5:0)
[02:13:59.418] Timer tick, asking Buildbucket for the build status
[02:13:59.556] Build 8888064488748570992: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:59.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:6:0) after 1m0s
[02:14:57.303] Received PubSub notification, asking Buildbucket for the build status
[02:14:57.404] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:59.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:6:0)
[02:14:59.642] Timer tick, asking Buildbucket for the build status
[02:14:59.777] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:59.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:8:0) after 1m0s
[02:15:59.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:8:0)
[02:15:59.911] Timer tick, asking Buildbucket for the build status
[02:16:00.083] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:00.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:9:0) after 1m0s
[02:17:00.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:9:0)
[02:17:00.111] Timer tick, asking Buildbucket for the build status
[02:17:00.231] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:00.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:10:0) after 1m0s
[02:18:00.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:10:0)
[02:18:00.331] Timer tick, asking Buildbucket for the build status
[02:18:00.698] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:00.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:11:0) after 1m0s
[02:19:00.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:11:0)
[02:19:00.809] Timer tick, asking Buildbucket for the build status
[02:19:00.930] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:00.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:12:0) after 1m0s
[02:20:00.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:12:0)
[02:20:00.988] Timer tick, asking Buildbucket for the build status
[02:20:01.215] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:01.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:13:0) after 1m0s
[02:21:01.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:13:0)
[02:21:01.371] Timer tick, asking Buildbucket for the build status
[02:21:01.484] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:01.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:14:0) after 1m0s
[02:22:01.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:14:0)
[02:22:01.503] Timer tick, asking Buildbucket for the build status
[02:22:01.599] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:01.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:15:0) after 1m0s
[02:23:01.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:15:0)
[02:23:01.632] Timer tick, asking Buildbucket for the build status
[02:23:01.738] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:01.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:16:0) after 1m0s
[02:24:01.861] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:16:0)
[02:24:01.861] Timer tick, asking Buildbucket for the build status
[02:24:02.017] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:02.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:17:0) after 1m0s
[02:25:02.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:17:0)
[02:25:02.125] Timer tick, asking Buildbucket for the build status
[02:25:02.264] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:02.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:18:0) after 1m0s
[02:26:02.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:18:0)
[02:26:02.380] Timer tick, asking Buildbucket for the build status
[02:26:02.554] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:02.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:19:0) after 1m0s
[02:27:02.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:19:0)
[02:27:02.627] Timer tick, asking Buildbucket for the build status
[02:27:02.837] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:20:0) after 1m0s
[02:28:02.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:20:0)
[02:28:02.916] Timer tick, asking Buildbucket for the build status
[02:28:03.015] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:03.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:21:0) after 1m0s
[02:29:03.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:21:0)
[02:29:03.246] Timer tick, asking Buildbucket for the build status
[02:29:03.359] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:03.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:22:0) after 1m0s
[02:30:03.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:22:0)
[02:30:03.376] Timer tick, asking Buildbucket for the build status
[02:30:03.590] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:03.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:23:0) after 1m0s
[02:31:03.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:23:0)
[02:31:03.671] Timer tick, asking Buildbucket for the build status
[02:31:03.867] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:03.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:24:0) after 1m0s
[02:32:03.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:24:0)
[02:32:03.871] Timer tick, asking Buildbucket for the build status
[02:32:04.067] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:04.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:25:0) after 1m0s
[02:33:04.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:25:0)
[02:33:04.209] Timer tick, asking Buildbucket for the build status
[02:33:04.313] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:04.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:26:0) after 1m0s
[02:34:04.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:26:0)
[02:34:04.346] Timer tick, asking Buildbucket for the build status
[02:34:04.600] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:04.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:27:0) after 1m0s
[02:35:04.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:27:0)
[02:35:04.596] Timer tick, asking Buildbucket for the build status
[02:35:04.729] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:04.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:28:0) after 1m0s
[02:36:04.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:28:0)
[02:36:04.791] Timer tick, asking Buildbucket for the build status
[02:36:05.006] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:05.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:29:0) after 1m0s
[02:37:05.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:29:0)
[02:37:05.012] Timer tick, asking Buildbucket for the build status
[02:37:05.292] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:05.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:30:0) after 1m0s
[02:38:05.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:30:0)
[02:38:05.435] Timer tick, asking Buildbucket for the build status
[02:38:05.743] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:05.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:31:0) after 1m0s
[02:39:05.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:31:0)
[02:39:05.892] Timer tick, asking Buildbucket for the build status
[02:39:06.049] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:06.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:32:0) after 1m0s
[02:40:06.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:32:0)
[02:40:06.086] Timer tick, asking Buildbucket for the build status
[02:40:06.269] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:06.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:33:0) after 1m0s
[02:41:06.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:33:0)
[02:41:06.350] Timer tick, asking Buildbucket for the build status
[02:41:06.520] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:06.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:34:0) after 1m0s
[02:42:06.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:34:0)
[02:42:06.557] Timer tick, asking Buildbucket for the build status
[02:42:06.841] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:06.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:35:0) after 1m0s
[02:43:06.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:35:0)
[02:43:06.966] Timer tick, asking Buildbucket for the build status
[02:43:07.187] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:07.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:36:0) after 1m0s
[02:44:07.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:36:0)
[02:44:07.343] Timer tick, asking Buildbucket for the build status
[02:44:07.506] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:07.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:37:0) after 1m0s
[02:45:07.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:37:0)
[02:45:07.562] Timer tick, asking Buildbucket for the build status
[02:45:07.669] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:07.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:38:0) after 1m0s
[02:46:07.712] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:38:0)
[02:46:07.712] Timer tick, asking Buildbucket for the build status
[02:46:07.861] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:07.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:39:0) after 1m0s
[02:47:07.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:39:0)
[02:47:07.902] Timer tick, asking Buildbucket for the build status
[02:47:08.034] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:08.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:40:0) after 1m0s
[02:48:08.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:40:0)
[02:48:08.029] Timer tick, asking Buildbucket for the build status
[02:48:08.182] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:08.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:41:0) after 1m0s
[02:49:08.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:41:0)
[02:49:08.224] Timer tick, asking Buildbucket for the build status
[02:49:08.465] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:42:0) after 1m0s
[02:50:08.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:42:0)
[02:50:08.570] Timer tick, asking Buildbucket for the build status
[02:50:08.675] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:08.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:43:0) after 1m0s
[02:51:08.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:43:0)
[02:51:08.822] Timer tick, asking Buildbucket for the build status
[02:51:09.164] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:09.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:44:0) after 1m0s
[02:52:09.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:44:0)
[02:52:09.255] Timer tick, asking Buildbucket for the build status
[02:52:09.385] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:09.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:45:0) after 1m0s
[02:53:09.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:45:0)
[02:53:09.407] Timer tick, asking Buildbucket for the build status
[02:53:09.638] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:09.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:46:0) after 1m0s
[02:54:09.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:46:0)
[02:54:09.737] Timer tick, asking Buildbucket for the build status
[02:54:09.832] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:09.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:47:0) after 1m0s
[02:55:09.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:47:0)
[02:55:09.972] Timer tick, asking Buildbucket for the build status
[02:55:10.091] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:10.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:48:0) after 1m0s
[02:56:10.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:48:0)
[02:56:10.237] Timer tick, asking Buildbucket for the build status
[02:56:10.420] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:10.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:49:0) after 1m0s
[02:57:10.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:49:0)
[02:57:10.522] Timer tick, asking Buildbucket for the build status
[02:57:10.673] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:10.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:50:0) after 1m0s
[02:58:10.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:50:0)
[02:58:10.796] Timer tick, asking Buildbucket for the build status
[02:58:10.910] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:10.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:51:0) after 1m0s
[02:59:10.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:51:0)
[02:59:10.949] Timer tick, asking Buildbucket for the build status
[02:59:11.296] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:11.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:52:0) after 1m0s
[03:00:11.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:52:0)
[03:00:11.333] Timer tick, asking Buildbucket for the build status
[03:00:11.469] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:11.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:53:0) after 1m0s
[03:01:11.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:53:0)
[03:01:11.600] Timer tick, asking Buildbucket for the build status
[03:01:11.996] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:11.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:54:0) after 1m0s
[03:02:12.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:54:0)
[03:02:12.020] Timer tick, asking Buildbucket for the build status
[03:02:12.159] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:12.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:55:0) after 1m0s
[03:03:12.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:55:0)
[03:03:12.216] Timer tick, asking Buildbucket for the build status
[03:03:12.513] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:12.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:56:0) after 1m0s
[03:04:12.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:56:0)
[03:04:12.613] Timer tick, asking Buildbucket for the build status
[03:04:12.849] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:12.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:57:0) after 1m0s
[03:05:12.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:57:0)
[03:05:12.946] Timer tick, asking Buildbucket for the build status
[03:05:13.073] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:13.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:58:0) after 1m0s
[03:06:13.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:58:0)
[03:06:13.141] Timer tick, asking Buildbucket for the build status
[03:06:13.362] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:13.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:59:0) after 1m0s
[03:07:13.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:59:0)
[03:07:13.511] Timer tick, asking Buildbucket for the build status
[03:07:13.627] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:13.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:60:0) after 1m0s
[03:08:13.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:60:0)
[03:08:13.647] Timer tick, asking Buildbucket for the build status
[03:08:13.855] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:13.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:61:0) after 1m0s
[03:09:13.970] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:61:0)
[03:09:13.970] Timer tick, asking Buildbucket for the build status
[03:09:14.381] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:14.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:62:0) after 1m0s
[03:10:14.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:62:0)
[03:10:14.364] Timer tick, asking Buildbucket for the build status
[03:10:14.462] Build 8888064488748570992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:14.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053494550794741552:63:0) after 1m0s
[03:10:52.074] Received PubSub notification, asking Buildbucket for the build status
[03:10:52.262] Build 8888064488748570992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:10:52.262] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582081850686439",
"created_by": "project:chromium",
"created_ts": "1582078196726947",
"id": "8888064488748570992",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41abf0c4d560d7bcee55415422fc722caacd36c2\"}}",
"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-73-h8ju\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168773, \"got_angle_revision\": \"123fd97000bb9fd912159bb9ccc62308b763aead\", \"got_dawn_revision\": \"0aa86a7ae8315f93f9501323dc082af0ac869efc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"41abf0c4d560d7bcee55415422fc722caacd36c2\", \"got_revision_cp\": \"refs/heads/master@{#742445}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8def9063c4fdefa8e644198d2254ef279952c88b\", \"got_v8_revision\": \"0b2347be5cfc3a2fd5f2a3592016a5c37682b632\", \"got_v8_revision_cp\": \"refs/heads/8.2.112@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41abf0c4d560d7bcee55415422fc722caacd36c2\", \"swarm_hashes_refs/heads/master(at){#742445}_without_patch\": {\"accessibility_unittests\": \"350ccd10c56769cba7c1a1b2a1df882f213f8dc1\", \"angle_unittests\": \"abb8f29ccd855adad0bc6cddc4d19adb07be7b16\", \"app_shell_unittests\": \"221621c897b249692ff1a7655ec7f78b85b19ee6\", \"aura_unittests\": \"ce700b8bbcb0b8f58d667b15e1baf4b2162a5316\", \"base_unittests\": \"d89380744a36e0de940023eae2aaf800da772cd6\", \"base_util_unittests\": \"468deb0cf39f9fef5e3d18baa5a8609b86f47b57\", \"blink_common_unittests\": \"bdde1fbfcef1663b98a7d9c86ee833570d1d3087\", \"blink_fuzzer_unittests\": \"dd52f0a3d7a91153e66372cfa2457e441e8d4336\", \"blink_heap_unittests\": \"26ee69ce1c2e0bc05e3b5fda685d6cd060835037\", \"blink_platform_unittests\": \"adf1740738baa6767a7d1c6ce39dfcb9d88f0c71\", \"blink_python_tests\": \"30b36557f41f6a84014cff07164e8505d1bccefb\", \"blink_unittests\": \"296fda335e4663fbe4fb2d4323a63c6bd68f62ba\", \"blink_web_tests\": \"818fe0659729a10fcaa8b5fae849cfc7544afb8f\", \"boringssl_crypto_tests\": \"9ca2279dbeb2756495ac34cf237e59358588fb48\", \"boringssl_ssl_tests\": \"53725b9d83fa01fe5ec43a0332dfbd8f52c4fb60\", \"browser_tests\": \"d2efd61bd8186538b733d7149283b44c5099a38b\", \"cacheinvalidation_unittests\": \"323e1e46995659bd764637a2e9c566a75979b25a\", \"capture_unittests\": \"126e2b11b40ea87cb65b907687392f735f91b267\", \"cast_unittests\": \"465e2de4a3cab3728ba23c7cfa76a028482742ff\", \"cc_unittests\": \"1d71294a9ff509f5f32372143a8fb65b64adb8d5\", \"chrome_app_unittests\": \"03174bd61871b7a96262c1965ad3eae290485255\", \"chromedriver_unittests\": \"dc0b8845d8f2bfdc3549244b8583f894f4898240\", \"components_browsertests\": \"f1fce638d0eade122dc7823001e0ad1ebc47d008\", \"components_unittests\": \"398be07158cf589ca026331cc3f4b0e61adf9bd0\", \"compositor_unittests\": \"9f895c0dd2868caf4f5f0a0f8c030aa009fe554d\", \"content_browsertests\": \"39069af88d332037f0c430b530d2d397594b1401\", \"content_shell_crash_test\": \"3279db0a5346983d55eedb7e1be85b302cc018f4\", \"content_unittests\": \"3c2ea2027347ae0139265a466561d370716acc9c\", \"crashpad_tests\": \"cac984c945cbf6f7dc7781448e6b591e2483edc7\", \"cronet_tests\": \"2936b48947cc68b5ebcc221f1a522e83eafd864f\", \"cronet_unittests\": \"26405eec6fdf81492cd5e621941b0b352f1701e9\", \"crypto_unittests\": \"ab56a15fa4e09b3c0b965c33675a2577453d78bf\", \"dbus_unittests\": \"84c4dff3925f67cd30fa0a97555595f347a67ca8\", \"device_unittests\": \"39e3d763ed5e42947a7687c72d3d5505d0217530\", \"display_unittests\": \"9a91120501e655267549f4814b8401c1ae7f3747\", \"events_unittests\": \"3d699a58c010c3a00b130cb428908d53ab5494be\", \"extensions_browsertests\": \"44dd3fca7d6e20ef49e8582200fcd62f48080c80\", \"extensions_unittests\": \"e0029de09c8c9271d50e75cb12837567225dda8c\", \"filesystem_service_unittests\": \"99c29e2b337972e9390d0d6efbc0770d0d8467ed\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"b85ba7b36190c27fe3f9d0c39c76d270301abb30\", \"gfx_unittests\": \"7df78548457d49dc1fffcf5700caf694b454760a\", \"gin_unittests\": \"816105929753ac06542cdf9337b015833d43daa7\", \"google_apis_unittests\": \"2029ee19f3a65abfc0283f1788ca5fdac3d74819\", \"gpu_unittests\": \"0d0321600e08c2002eacee1c70094b02fab2b770\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"07223e118d9ad92e1d3a8c532b700b0a720fed09\", \"headless_browsertests\": \"d192e08a120beea0d7e88c882f0db0dd7719afb6\", \"headless_unittests\": \"d3327d5e80b9bdef2fdbbca869eb450a49e29aac\", \"interactive_ui_tests\": \"51e603f8a37d70068ebd7f41b5d994a7a924a3e1\", \"ipc_tests\": \"7b7407d06fe23bf54b024974b2077c2958103121\", \"jingle_unittests\": \"7437a47d69123eb5d3c12ee74ba07547b100638c\", \"latency_unittests\": \"ec030e6504ab97229e8119b9bf1d52fedbad0629\", \"libjingle_xmpp_unittests\": \"64b3ad16211594845e3aa5a0f7cb1ca7c9a27c67\", \"media_blink_unittests\": \"b56a133541acab6ac62b5d78177bc1303eff37fa\", \"media_service_unittests\": \"b4351c76ba5f135e8316458e91860ba0de207a57\", \"media_unittests\": \"6bc293815b9a2957273267c1b9e92fe0da309ed8\", \"message_center_unittests\": \"8be79752748625c9a8f3c8afdec6dfbe8e393797\", \"metrics_python_tests\": \"667cbd518ed7c12ef808c074b552da5889790914\", \"midi_unittests\": \"b5897008b66758e6e7c3bbec99d73437a30c6a98\", \"mojo_core_unittests\": \"d2afda697258ebc3c22b885bf6c18188593f39e1\", \"mojo_unittests\": \"3af08aaa8336c30af095e6f4495e77746657ffc0\", \"nacl_helper_nonsfi_unittests\": \"9dad0cdf1df497baf07ecef7be5eb9bb122cbbc4\", \"nacl_loader_unittests\": \"07e4320e2d917a76ede6e0cc838faf3405e8447f\", \"native_theme_unittests\": \"1898fb32497486533a760196499a1d0869856d0c\", \"net_unittests\": \"21da8ac7ffb3e668ed1caea7b2f88f54a9da1a4a\", \"pdf_unittests\": \"6828764adcf3f356b83a106a559f497ce51cd086\", \"perfetto_unittests\": \"16a1aa16394f7d0420398f8c436b16de89b9bb66\", \"ppapi_unittests\": \"293ccca909b80660e500bd6424814ba6d6cb339a\", \"printing_unittests\": \"6c9da75c7e92affa5a41fd4e294702ce86784e1a\", \"remoting_unittests\": \"bad424ce997287343c090e9101189a11a6ffcd9d\", \"sandbox_linux_unittests\": \"49333a7bacfab78ba5f30b5b83a585b4aba61dcc\", \"service_manager_unittests\": \"7f4a81aace07672a8afa4f6c8ec5baa8ff523e6f\", \"services_unittests\": \"80a0e9cd00bab1d1b0a02377ac055d9360d7f60c\", \"shell_dialogs_unittests\": \"88d7665027c96781c183e9a4a43df67fc5fc3e8e\", \"skia_unittests\": \"ec824f6df8451b69823b0a1683845df598c4cc5b\", \"snapshot_unittests\": \"6a05a419df2448c9d8f4293b820e01f1d4c9bc50\", \"sql_unittests\": \"17a985241baacb73b9c0b748806301ff4e7e3f64\", \"storage_unittests\": \"4c875959b0f04c4aef79dc95ee75ad13b13c132c\", \"sync_integration_tests\": \"19d5ba60b193751b825de6d906ad57d5360882a4\", \"telemetry_gpu_unittests\": \"bface9027f4697b43d8b77be2f7334889fd2b1d3\", \"telemetry_perf_unittests\": \"7914319d37b0e4f867b8ee2320ca5f640f75509c\", \"telemetry_unittests\": \"130034434cec61ea9da1cb41dc31d76eda286ff6\", \"traffic_annotation_auditor_unittests\": \"4af659be5115c836d5e56cf2a1936014f59db766\", \"ui_base_unittests\": \"b124dc89e800bc4b759fd6272291aff89f1ec30f\", \"ui_touch_selection_unittests\": \"4a15ec7b876b9ee881b0ee8c23add20ecee7ecf5\", \"unit_tests\": \"bf3cac7bc10005c1f6d99904fb0a7e4f4bba59ee\", \"url_unittests\": \"879045635c6421053835295cc7efed35ca6298ca\", \"views_perftests\": \"412f3b80268d9b41c544cab5e7adea77c3e31d93\", \"views_unittests\": \"ee5ed4b0394524b5216443a04c27a853543cd27c\", \"viz_unittests\": \"879344dc3be1b9aa6db13b680a654baf4c9fe5e9\", \"vr_common_unittests\": \"e7857875331d77998e5aae873c97011d51178974\", \"vr_pixeltests\": \"0a8e3acd813934648c4e3e69a897049793a95321\", \"webdriver_wpt_tests\": \"943440bb7b3036002872867d815384612c7d200c\", \"weblayer_browsertests\": \"552b76e862e647a8ea27b04657d792d0806a45b8\", \"wm_unittests\": \"6ad7684de66e5395fc8107779added177dc9d014\", \"wtf_unittests\": \"951e5c551367a72d7c4f2c6d724169aca1bf1678\", \"xr_browser_tests\": \"1aca24ad77a0e91004d8390676ac95e6d88e850d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_33498e86c0181efb6d75c3f73059d9522f6d5d2b3b1c7a2dc2a1c6b75cd205cd_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_a4e8234367f6e9970c36d6e793971f7a1ed9e2532234459a292e8560f005826d_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-73-h8ju\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582078496292226",
"status": "COMPLETED",
"status_changed_ts": "1582081851179692",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168773",
"builder:Linux Builder (dbg)",
"buildset:commit/git/41abf0c4d560d7bcee55415422fc722caacd36c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41abf0c4d560d7bcee55415422fc722caacd36c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053494550794741552",
"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/8888064488748570992/+/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:4a740708e4fb1810",
"user_agent:luci-scheduler"
],
"updated_ts": "1582081851211744",
"url": "https://ci.chromium.org/b/8888064488748570992",
"utcnow_ts": "1582081852237362"
}
}
[03:10:52.262] Invocation finished in 1h0m56.870904104s with status SUCCEEDED