[22:41:40.223] New invocation is queued and will start shortly
[22:41:41.286] Starting the invocation (attempt 1)
[22:41:41.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:41.333] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:41.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053326459011865616",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"44e94a9338a494bfc775859d8db17b439f5329f0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053326459011865616",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/44e94a9338a494bfc775859d8db17b439f5329f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44e94a9338a494bfc775859d8db17b439f5329f0",
"gitiles_ref:refs/heads/master"
]
}
[22:41:42.007] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582238501433589",
"id": "8887896397080113456",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44e94a9338a494bfc775859d8db17b439f5329f0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582238501803684",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168837",
"builder:Linux Builder (dbg)",
"buildset:commit/git/44e94a9338a494bfc775859d8db17b439f5329f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44e94a9338a494bfc775859d8db17b439f5329f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053326459011865616",
"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/8887896397080113456/+/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": "1582238501803782",
"url": "https://ci.chromium.org/b/8887896397080113456",
"utcnow_ts": "1582238501998062"
}
}
[22:41:42.007] Task URL: https://ci.chromium.org/b/8887896397080113456
[22:41:42.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:2:0) after 1m0s
[22:42:42.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:2:0)
[22:42:42.085] Timer tick, asking Buildbucket for the build status
[22:42:44.575] Build 8887896397080113456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:44.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:3:0) after 1m0s
[22:43:44.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:3:0)
[22:43:44.736] Timer tick, asking Buildbucket for the build status
[22:43:46.114] Build 8887896397080113456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:46.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:4:0) after 1m0s
[22:44:46.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:4:0)
[22:44:46.213] Timer tick, asking Buildbucket for the build status
[22:44:46.326] Build 8887896397080113456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:46.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:5:0) after 1m0s
[22:45:46.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:5:0)
[22:45:46.385] Timer tick, asking Buildbucket for the build status
[22:45:46.656] Build 8887896397080113456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:46.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:6:0) after 1m0s
[22:46:46.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:6:0)
[22:46:46.764] Timer tick, asking Buildbucket for the build status
[22:46:46.977] Build 8887896397080113456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:46.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:7:0) after 1m0s
[22:46:48.380] Received PubSub notification, asking Buildbucket for the build status
[22:46:48.696] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:7:0)
[22:47:47.049] Timer tick, asking Buildbucket for the build status
[22:47:47.219] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:9:0) after 1m0s
[22:48:47.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:9:0)
[22:48:47.320] Timer tick, asking Buildbucket for the build status
[22:48:47.585] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:10:0) after 1m0s
[22:49:47.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:10:0)
[22:49:47.621] Timer tick, asking Buildbucket for the build status
[22:49:48.086] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:48.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:11:0) after 1m0s
[22:50:48.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:11:0)
[22:50:48.105] Timer tick, asking Buildbucket for the build status
[22:50:48.803] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:48.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:12:0) after 1m0s
[22:51:48.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:12:0)
[22:51:48.840] Timer tick, asking Buildbucket for the build status
[22:51:48.940] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:48.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:13:0) after 1m0s
[22:52:48.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:13:0)
[22:52:48.968] Timer tick, asking Buildbucket for the build status
[22:52:49.344] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:49.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:14:0) after 1m0s
[22:53:49.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:14:0)
[22:53:49.372] Timer tick, asking Buildbucket for the build status
[22:53:49.632] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:49.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:15:0) after 1m0s
[22:54:49.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:15:0)
[22:54:49.680] Timer tick, asking Buildbucket for the build status
[22:54:49.790] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:49.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:16:0) after 1m0s
[22:55:49.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:16:0)
[22:55:49.815] Timer tick, asking Buildbucket for the build status
[22:55:50.190] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:50.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:17:0) after 1m0s
[22:56:50.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:17:0)
[22:56:50.312] Timer tick, asking Buildbucket for the build status
[22:56:50.814] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:50.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:18:0) after 1m0s
[22:57:50.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:18:0)
[22:57:50.900] Timer tick, asking Buildbucket for the build status
[22:57:51.365] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:51.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:19:0) after 1m0s
[22:58:51.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:19:0)
[22:58:51.415] Timer tick, asking Buildbucket for the build status
[22:58:56.367] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:56.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:20:0) after 1m0s
[22:59:56.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:20:0)
[22:59:56.431] Timer tick, asking Buildbucket for the build status
[22:59:56.781] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:56.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:21:0) after 1m0s
[23:00:56.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:21:0)
[23:00:56.831] Timer tick, asking Buildbucket for the build status
[23:01:00.469] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:00.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:22:0) after 1m0s
[23:02:00.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:22:0)
[23:02:00.975] Timer tick, asking Buildbucket for the build status
[23:02:02.506] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:02.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:23:0) after 1m0s
[23:03:02.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:23:0)
[23:03:02.570] Timer tick, asking Buildbucket for the build status
[23:03:02.859] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:02.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:24:0) after 1m0s
[23:04:02.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:24:0)
[23:04:02.890] Timer tick, asking Buildbucket for the build status
[23:04:03.057] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:03.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:25:0) after 1m0s
[23:05:03.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:25:0)
[23:05:03.127] Timer tick, asking Buildbucket for the build status
[23:05:04.347] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:04.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:26:0) after 1m0s
[23:06:04.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:26:0)
[23:06:04.500] Timer tick, asking Buildbucket for the build status
[23:06:05.195] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:05.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:27:0) after 1m0s
[23:07:05.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:27:0)
[23:07:05.283] Timer tick, asking Buildbucket for the build status
[23:07:05.530] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:05.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:28:0) after 1m0s
[23:08:05.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:28:0)
[23:08:05.503] Timer tick, asking Buildbucket for the build status
[23:08:05.619] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:05.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:29:0) after 1m0s
[23:09:06.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:29:0)
[23:09:06.159] Timer tick, asking Buildbucket for the build status
[23:09:06.287] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:06.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:30:0) after 1m0s
[23:10:06.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:30:0)
[23:10:06.500] Timer tick, asking Buildbucket for the build status
[23:10:06.606] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:06.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:31:0) after 1m0s
[23:11:06.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:31:0)
[23:11:06.868] Timer tick, asking Buildbucket for the build status
[23:11:07.168] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:07.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:32:0) after 1m0s
[23:12:07.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:32:0)
[23:12:07.684] Timer tick, asking Buildbucket for the build status
[23:12:08.156] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:08.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:33:0) after 1m0s
[23:13:08.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:33:0)
[23:13:08.254] Timer tick, asking Buildbucket for the build status
[23:13:08.378] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:08.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:34:0) after 1m0s
[23:14:08.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:34:0)
[23:14:08.653] Timer tick, asking Buildbucket for the build status
[23:14:08.989] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:08.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:35:0) after 1m0s
[23:15:09.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:35:0)
[23:15:09.057] Timer tick, asking Buildbucket for the build status
[23:15:09.226] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:09.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:36:0) after 1m0s
[23:16:09.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:36:0)
[23:16:09.699] Timer tick, asking Buildbucket for the build status
[23:16:10.133] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:10.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:37:0) after 1m0s
[23:17:10.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:37:0)
[23:17:10.309] Timer tick, asking Buildbucket for the build status
[23:17:11.126] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:11.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:38:0) after 1m0s
[23:18:11.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:38:0)
[23:18:11.279] Timer tick, asking Buildbucket for the build status
[23:18:11.393] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:11.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:39:0) after 1m0s
[23:19:11.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:39:0)
[23:19:11.530] Timer tick, asking Buildbucket for the build status
[23:19:11.773] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:11.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:40:0) after 1m0s
[23:20:11.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:40:0)
[23:20:11.949] Timer tick, asking Buildbucket for the build status
[23:20:12.255] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:12.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:41:0) after 1m0s
[23:21:12.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:41:0)
[23:21:12.771] Timer tick, asking Buildbucket for the build status
[23:21:13.788] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:13.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:42:0) after 1m0s
[23:22:13.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:42:0)
[23:22:13.887] Timer tick, asking Buildbucket for the build status
[23:22:14.449] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:43:0) after 1m0s
[23:23:14.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:43:0)
[23:23:14.519] Timer tick, asking Buildbucket for the build status
[23:23:14.758] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:14.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:44:0) after 1m0s
[23:24:14.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:44:0)
[23:24:14.855] Timer tick, asking Buildbucket for the build status
[23:24:15.032] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:15.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:45:0) after 1m0s
[23:25:15.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:45:0)
[23:25:15.094] Timer tick, asking Buildbucket for the build status
[23:25:15.302] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:15.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:46:0) after 1m0s
[23:26:15.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:46:0)
[23:26:15.293] Timer tick, asking Buildbucket for the build status
[23:26:15.588] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:15.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:47:0) after 1m0s
[23:27:15.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:47:0)
[23:27:15.656] Timer tick, asking Buildbucket for the build status
[23:27:15.847] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:15.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:48:0) after 1m0s
[23:28:15.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:48:0)
[23:28:15.877] Timer tick, asking Buildbucket for the build status
[23:28:16.325] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:49:0) after 1m0s
[23:29:16.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:49:0)
[23:29:16.416] Timer tick, asking Buildbucket for the build status
[23:29:16.881] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:16.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:50:0) after 1m0s
[23:30:16.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:50:0)
[23:30:16.982] Timer tick, asking Buildbucket for the build status
[23:30:17.915] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:17.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:51:0) after 1m0s
[23:31:17.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:51:0)
[23:31:17.971] Timer tick, asking Buildbucket for the build status
[23:31:18.359] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:18.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:52:0) after 1m0s
[23:32:18.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:52:0)
[23:32:18.465] Timer tick, asking Buildbucket for the build status
[23:32:19.395] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:19.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:53:0) after 1m0s
[23:33:19.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:53:0)
[23:33:19.617] Timer tick, asking Buildbucket for the build status
[23:33:20.380] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:54:0) after 1m0s
[23:34:20.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:54:0)
[23:34:20.427] Timer tick, asking Buildbucket for the build status
[23:34:21.194] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:21.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:55:0) after 1m0s
[23:35:21.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:55:0)
[23:35:21.224] Timer tick, asking Buildbucket for the build status
[23:35:22.880] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:22.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:56:0) after 1m0s
[23:36:23.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:56:0)
[23:36:23.008] Timer tick, asking Buildbucket for the build status
[23:36:23.136] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:23.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:57:0) after 1m0s
[23:37:23.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:57:0)
[23:37:23.225] Timer tick, asking Buildbucket for the build status
[23:37:23.373] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:23.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:58:0) after 1m0s
[23:38:23.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:58:0)
[23:38:23.405] Timer tick, asking Buildbucket for the build status
[23:38:23.606] Build 8887896397080113456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:23.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053326459011865616:59:0) after 1m0s
[23:39:08.138] Received PubSub notification, asking Buildbucket for the build status
[23:39:08.259] Build 8887896397080113456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:39:08.259] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582241947401618",
"created_by": "project:chromium",
"created_ts": "1582238501433589",
"id": "8887896397080113456",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44e94a9338a494bfc775859d8db17b439f5329f0\"}}",
"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-9-4c37\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168837, \"got_angle_revision\": \"b4357d46c6c64cc1e98f90fe2051af25ed8f74f6\", \"got_dawn_revision\": \"f949a10e516bd730fb5a20cc31aa38a4e3909f16\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"44e94a9338a494bfc775859d8db17b439f5329f0\", \"got_revision_cp\": \"refs/heads/master@{#743291}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"1ee36c9fa7e5a4d2d3a4d7cd8561c37e4d0a03aa\", \"got_v8_revision\": \"c9de129a2763794ba38c79591852a761f2a8e912\", \"got_v8_revision_cp\": \"refs/heads/8.2.135@{#1}\", \"got_webrtc_revision\": \"d881983a38b40619970710a03ad346663ad5d9db\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30572}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44e94a9338a494bfc775859d8db17b439f5329f0\", \"swarm_hashes_refs/heads/master(at){#743291}_without_patch\": {\"accessibility_unittests\": \"4569028e9331df3cff43c93f8d6185860253b4d8\", \"angle_unittests\": \"d176bfdb05ef2a60112fa057c621757e3ff91dc3\", \"app_shell_unittests\": \"174ee7d58b81bb8883457431e4eae57036754147\", \"aura_unittests\": \"f4efe27bd13c69fe814b2ac2c1f0b79ef820f8ae\", \"base_unittests\": \"d185ffd684be481bc05f0a8c589df4b7b5547dff\", \"base_util_unittests\": \"59d6e0fcbe139a46087e3b5a9877410ac6aaf278\", \"blink_common_unittests\": \"59caf2dad566206e9665a2f4a0f37a1af5339ec0\", \"blink_fuzzer_unittests\": \"d835b12e0861910c3e5bee911a8948096d3f0253\", \"blink_heap_unittests\": \"8ddb2e13dffffd16336a8566b2c4f649a1c22b05\", \"blink_platform_unittests\": \"34f7bfd949bd690ac76ffcc41c4737e1a6135ca0\", \"blink_python_tests\": \"c1aa20e8093582f080c46413db5c2fbc53a7cabb\", \"blink_unittests\": \"6d978d67c161382d91476259cba6ad49f02e032a\", \"blink_web_tests\": \"1f28d387f72ffb5cd9e60a1514da47d3dbd2914a\", \"boringssl_crypto_tests\": \"20d15eb4a72f54a7954cba37e7ec02c007ff2268\", \"boringssl_ssl_tests\": \"d45150df5070cffcc0fb0afdf3355d03c33fb813\", \"browser_tests\": \"51914138aa3a73da0239729929cf1abad3308204\", \"cacheinvalidation_unittests\": \"df131cf381a27e0de34332f94d043e22d44e135c\", \"capture_unittests\": \"c16832f3e5c1da8e383ebd5487f98e24de5dfaa6\", \"cast_unittests\": \"9fc535d3c1d2b226c69c30c2b121fa217c11d903\", \"cc_unittests\": \"3e73d5fafdddcabc25b76ab44b1cdd178ff19bf7\", \"chrome_app_unittests\": \"6d84e162df85d4c2db82126a992d245f7a4a4b2e\", \"chromedriver_unittests\": \"ec1a6f5bffe6fdebdbffeb2394c83a983f9889cd\", \"components_browsertests\": \"ebac918433580f7cce58821d9ef6939958b077a2\", \"components_unittests\": \"9357f786b3e4c6fff51d871d02ad1f9c7f11bbf1\", \"compositor_unittests\": \"89c5457c4e398cf8db3629b00451b2f4c3bfdad2\", \"content_browsertests\": \"830fb19381a5862a2788e6fa59c2e40a746c318d\", \"content_shell_crash_test\": \"ea0fe3ea7b6e7e2c17385ab0e33b350133ed0fa2\", \"content_unittests\": \"5055d8976ecad8a41efd19c201f3ebe6d23e5c3d\", \"crashpad_tests\": \"314a5e1645e1c3448726e09bad3b633586e419be\", \"cronet_tests\": \"b91acc5fbc8a896e33271b1c66d339b4b58b0757\", \"cronet_unittests\": \"00f2a1563c65b8a47a8908cb97165c0f8c0d33f5\", \"crypto_unittests\": \"d07eb0afbf3d25a9a7774a04b27a609420561003\", \"dbus_unittests\": \"5af4ecf28c3ebbd8a87105b0b8f3af043e6c8669\", \"device_unittests\": \"3bdbe2b92a25eaf3f450cda84778170a34f4fa0e\", \"display_unittests\": \"ce80ab5d06586e26223d0f4262e58711e9b307e2\", \"events_unittests\": \"ee3071c8755d3eb72017c7543c3fd9d5b3de93ff\", \"extensions_browsertests\": \"625a2da8bf924dd0389dc8d7a7cceef1720ca02f\", \"extensions_unittests\": \"7d59ba80cfe2c51616ff1bbe2d8fac837cf46944\", \"filesystem_service_unittests\": \"c8804df7a6820c8dce569bc332f8a4f8e0efc49c\", \"flatbuffers_unittests\": \"c3db37cb8981f7422026864ae3bd0765e7fa7de4\", \"gcm_unit_tests\": \"acf9dd4f41776e0572bfd874038be8f7ebd5505a\", \"gfx_unittests\": \"61dc0524126e9ef4f512f2d10edea3e697352aff\", \"gin_unittests\": \"d78a2a9f01316402b46a3dd7f9abbebb3bb8bb7d\", \"google_apis_unittests\": \"e6e775c261c3261c6510d7d6e039d88fd7c2c857\", \"gpu_unittests\": \"cbc08c26edbeb67aa31ae3a825ee68f5523856b5\", \"grit_python_unittests\": \"1c302e58d59fa35730ee775859dfbdd4b9b71210\", \"gwp_asan_unittests\": \"6868c8fd31724091faa0bcaca3855007176a54d6\", \"headless_browsertests\": \"a569e225cb25ea8b4983102c61c36fe7ac773142\", \"headless_unittests\": \"3162d76d237ac2a8f22df3900d4dceee70298dc1\", \"interactive_ui_tests\": \"38e8b35501f0e55a85fefd86e7d8d8d2012d14be\", \"ipc_tests\": \"bdda91d7449369105b75a3a6de66c6c557e1a60c\", \"jingle_unittests\": \"0ee7bd463e52189015a8807b4ef672d3a044b5ec\", \"latency_unittests\": \"d82752325023e74af8d4dd7cc0ffd25897b9247a\", \"libjingle_xmpp_unittests\": \"164c4fae1c0d29ab93634c5ac1d2009bdd204401\", \"media_blink_unittests\": \"a2bfafa865d3a5ca1649eea0abcfb15fae4b4668\", \"media_service_unittests\": \"7927dbbac509d40e7bda12b61da952d314c5a4ff\", \"media_unittests\": \"fc368aa2f6b812f172a7c2b852f5e1e932b710c9\", \"message_center_unittests\": \"d0beb2d8afbac4723c307232bc8a3e545616fca6\", \"metrics_python_tests\": \"36932e376d5b01475114efd30ff9f128a0c10543\", \"midi_unittests\": \"913e13a3c69513b55ee69fc8961830e4e7b4fd0b\", \"mojo_core_unittests\": \"34d4edd2d1647e1e4143265a7afb095d56fc1ba1\", \"mojo_unittests\": \"b708305289b9fb87b4052be52c00114d14b77c30\", \"nacl_helper_nonsfi_unittests\": \"0c6ff5e634a79f2ae14893f8dd25a6df34ae45d4\", \"nacl_loader_unittests\": \"848f0968a20351d3d39ae97e6093404eef038d6c\", \"native_theme_unittests\": \"69473e4c17ca2a04fb89a6e9236384ace5dfc194\", \"net_unittests\": \"18ed70f3bbd4456d47d9b8cfa879f3c4b5b30a78\", \"pdf_unittests\": \"d2a8e20c06e6aafbf4a62e4ce9336d78badd2afd\", \"perfetto_unittests\": \"5b7a96ffe47ead0ea27bbbfb5e16f0d0dfea6212\", \"ppapi_unittests\": \"64012bf0b3acb3363356607df94a5cb4d81b0ca8\", \"printing_unittests\": \"4cb4b06fedeb572509632ad4ae96d58bbe32177e\", \"remoting_unittests\": \"3453fc4c5c10816b5d349a6cf5618b9eb0be9e4c\", \"sandbox_linux_unittests\": \"e0b9a479f300f1590fcc43ac90b77399db37d86c\", \"service_manager_unittests\": \"f5dd810d0d9f577d9b708f651f78d36df1d2a467\", \"services_unittests\": \"0db695055bbf385089d23d5a0fa5322d29ccc20b\", \"shell_dialogs_unittests\": \"e217714c843c0a6da7ad55017b27731e16ba8de7\", \"skia_unittests\": \"fcba2e4b444682347d900265e9bf83c4cc3d5c23\", \"snapshot_unittests\": \"07e54e222d21055cd4812daabe98a4ee8989320c\", \"sql_unittests\": \"76fe3ddacbbb0914ba045ab9aa22eb47f7d87a34\", \"storage_unittests\": \"57a3fe8a845defd08cd5af68c076ad330f7c96e3\", \"sync_integration_tests\": \"b1c4cea268df647835424d2ff12d44e9ef20766f\", \"telemetry_gpu_unittests\": \"c5460d41cc383e251dfadca89f32fdb409c377a2\", \"telemetry_perf_unittests\": \"38b719096f3cf2995863a8f27e92abf327e6c85d\", \"telemetry_unittests\": \"5d37f2cc66cadb201a83cd276e18b217a5a8399b\", \"traffic_annotation_auditor_unittests\": \"e3f512d24240e72c5faf23735bfe12d002667ecb\", \"ui_base_unittests\": \"dc93781bdbd04a70523b2abcdbf6d907d4aba6bb\", \"ui_touch_selection_unittests\": \"1d2e68ad978092bd83352700b1415fe8da76a932\", \"unit_tests\": \"4284893d3f949e1b827a94a0b5b8935dd98256b8\", \"url_unittests\": \"986395a441fde7cd2b317049ea1f0dbe29b03c93\", \"views_perftests\": \"9525335330b21d12fe81782dd8d0dc858b74fabf\", \"views_unittests\": \"efa9457c3ecec60c09a0a2ec79ff0048d2c5d252\", \"viz_unittests\": \"6347b34ad0880dd50526d721137241761628a6c4\", \"vr_common_unittests\": \"0652321994801cbf7d5ebfdd623834681ec14077\", \"vr_pixeltests\": \"01f0ea0d5770a014fc2645ad9df3138a72014fb7\", \"webdriver_wpt_tests\": \"48b490a9ed6367bf179f73cdf06a428a58bee170\", \"weblayer_browsertests\": \"a8a3ea00878e405c9a4086e94425709170664cad\", \"wm_unittests\": \"20619aa58e6a1d4aec750c0454deb1830c128ade\", \"wtf_unittests\": \"dcddc12f654f23315ba979cfb779347166f2e01a\", \"xr_browser_tests\": \"080ab00b805fbe2a843572c803b45507ceac1251\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_b91573fd5c97bdd1870d9c2b7f3dee6525147840030fe1e0975f137bcc8950b1_v2\", \"builder_c38832248eaa59248ebe0e5d5ae62a6bc955f53f6a99a9c0e5cded93d679f9bc_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_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-9-4c37\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582238807689197",
"status": "COMPLETED",
"status_changed_ts": "1582241947693526",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168837",
"builder:Linux Builder (dbg)",
"buildset:commit/git/44e94a9338a494bfc775859d8db17b439f5329f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44e94a9338a494bfc775859d8db17b439f5329f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053326459011865616",
"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/8887896397080113456/+/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:4a7d95150e807510",
"user_agent:luci-scheduler"
],
"updated_ts": "1582241947741179",
"url": "https://ci.chromium.org/b/8887896397080113456",
"utcnow_ts": "1582241948204576"
}
}
[23:39:08.259] Invocation finished in 57m28.051648032s with status SUCCEEDED