[11:29:03.117] New invocation is queued and will start shortly
[11:29:04.261] Starting the invocation (attempt 1)
[11:29:04.325] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:29:04.325] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:29:04.325] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053368776529121824",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ea9b4325a7c4f473d98d6b24c68b93eee4364d53\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053368776529121824",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"gitiles_ref:refs/heads/master"
]
}
[11:29:05.282] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582198144531322",
"id": "8887938714358659216",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ea9b4325a7c4f473d98d6b24c68b93eee4364d53\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582198145119994",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168821",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053368776529121824",
"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/8887938714358659216/+/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": "1582198145120093",
"url": "https://ci.chromium.org/b/8887938714358659216",
"utcnow_ts": "1582198145267976"
}
}
[11:29:05.282] Task URL: https://ci.chromium.org/b/8887938714358659216
[11:29:05.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:2:0) after 1m0s
[11:30:05.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:2:0)
[11:30:05.359] Timer tick, asking Buildbucket for the build status
[11:30:05.467] Build 8887938714358659216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:05.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:3:0) after 1m0s
[11:31:05.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:3:0)
[11:31:05.670] Timer tick, asking Buildbucket for the build status
[11:31:05.803] Build 8887938714358659216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:05.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:4:0) after 1m0s
[11:32:05.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:4:0)
[11:32:05.850] Timer tick, asking Buildbucket for the build status
[11:32:06.036] Build 8887938714358659216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:06.036] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:5:0) after 1m0s
[11:33:06.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:5:0)
[11:33:06.157] Timer tick, asking Buildbucket for the build status
[11:33:06.305] Build 8887938714358659216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:06.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:6:0) after 1m0s
[11:34:02.932] Received PubSub notification, asking Buildbucket for the build status
[11:34:03.021] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:06.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:6:0)
[11:34:06.381] Timer tick, asking Buildbucket for the build status
[11:34:06.584] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:06.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:8:0) after 1m0s
[11:35:06.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:8:0)
[11:35:06.624] Timer tick, asking Buildbucket for the build status
[11:35:06.939] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:06.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:9:0) after 1m0s
[11:36:07.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:9:0)
[11:36:07.067] Timer tick, asking Buildbucket for the build status
[11:36:07.165] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:07.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:10:0) after 1m0s
[11:37:07.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:10:0)
[11:37:07.196] Timer tick, asking Buildbucket for the build status
[11:37:07.319] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:07.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:11:0) after 1m0s
[11:38:07.336] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:11:0)
[11:38:07.336] Timer tick, asking Buildbucket for the build status
[11:38:07.512] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:12:0) after 1m0s
[11:39:07.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:12:0)
[11:39:07.638] Timer tick, asking Buildbucket for the build status
[11:39:07.846] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:07.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:13:0) after 1m0s
[11:40:07.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:13:0)
[11:40:07.968] Timer tick, asking Buildbucket for the build status
[11:40:08.178] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:08.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:14:0) after 1m0s
[11:41:08.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:14:0)
[11:41:08.355] Timer tick, asking Buildbucket for the build status
[11:41:08.435] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:08.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:15:0) after 1m0s
[11:42:08.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:15:0)
[11:42:08.622] Timer tick, asking Buildbucket for the build status
[11:42:08.730] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:08.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:16:0) after 1m0s
[11:43:08.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:16:0)
[11:43:08.957] Timer tick, asking Buildbucket for the build status
[11:43:09.338] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:09.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:17:0) after 1m0s
[11:44:09.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:17:0)
[11:44:09.395] Timer tick, asking Buildbucket for the build status
[11:44:09.663] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:09.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:18:0) after 1m0s
[11:45:09.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:18:0)
[11:45:09.681] Timer tick, asking Buildbucket for the build status
[11:45:09.735] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:09.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:19:0) after 1m0s
[11:46:09.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:19:0)
[11:46:09.797] Timer tick, asking Buildbucket for the build status
[11:46:09.888] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:09.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:20:0) after 1m0s
[11:47:09.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:20:0)
[11:47:09.928] Timer tick, asking Buildbucket for the build status
[11:47:10.106] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:10.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:21:0) after 1m0s
[11:48:10.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:21:0)
[11:48:10.142] Timer tick, asking Buildbucket for the build status
[11:48:10.261] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:10.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:22:0) after 1m0s
[11:49:10.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:22:0)
[11:49:10.433] Timer tick, asking Buildbucket for the build status
[11:49:10.668] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:10.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:23:0) after 1m0s
[11:50:10.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:23:0)
[11:50:10.777] Timer tick, asking Buildbucket for the build status
[11:50:10.932] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:10.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:24:0) after 1m0s
[11:51:11.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:24:0)
[11:51:11.057] Timer tick, asking Buildbucket for the build status
[11:51:11.205] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:11.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:25:0) after 1m0s
[11:52:11.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:25:0)
[11:52:11.290] Timer tick, asking Buildbucket for the build status
[11:52:11.374] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:11.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:26:0) after 1m0s
[11:53:11.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:26:0)
[11:53:11.553] Timer tick, asking Buildbucket for the build status
[11:53:11.697] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:11.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:27:0) after 1m0s
[11:54:11.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:27:0)
[11:54:11.721] Timer tick, asking Buildbucket for the build status
[11:54:11.856] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:11.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:28:0) after 1m0s
[11:55:11.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:28:0)
[11:55:11.959] Timer tick, asking Buildbucket for the build status
[11:55:12.149] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:12.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:29:0) after 1m0s
[11:56:12.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:29:0)
[11:56:12.146] Timer tick, asking Buildbucket for the build status
[11:56:12.292] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:12.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:30:0) after 1m0s
[11:57:12.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:30:0)
[11:57:12.465] Timer tick, asking Buildbucket for the build status
[11:57:12.582] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:12.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:31:0) after 1m0s
[11:58:12.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:31:0)
[11:58:12.640] Timer tick, asking Buildbucket for the build status
[11:58:12.727] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:12.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:32:0) after 1m0s
[11:59:12.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:32:0)
[11:59:12.804] Timer tick, asking Buildbucket for the build status
[11:59:12.913] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:12.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:33:0) after 1m0s
[12:00:12.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:33:0)
[12:00:12.973] Timer tick, asking Buildbucket for the build status
[12:00:13.069] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:13.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:34:0) after 1m0s
[12:01:13.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:34:0)
[12:01:13.149] Timer tick, asking Buildbucket for the build status
[12:01:13.396] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:35:0) after 1m0s
[12:02:13.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:35:0)
[12:02:13.494] Timer tick, asking Buildbucket for the build status
[12:02:13.635] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:13.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:36:0) after 1m0s
[12:03:13.740] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:36:0)
[12:03:13.740] Timer tick, asking Buildbucket for the build status
[12:03:13.845] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:13.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:37:0) after 1m0s
[12:04:13.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:37:0)
[12:04:13.873] Timer tick, asking Buildbucket for the build status
[12:04:14.003] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:38:0) after 1m0s
[12:05:14.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:38:0)
[12:05:14.065] Timer tick, asking Buildbucket for the build status
[12:05:14.218] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:14.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:39:0) after 1m0s
[12:06:14.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:39:0)
[12:06:14.220] Timer tick, asking Buildbucket for the build status
[12:06:14.465] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:14.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:40:0) after 1m0s
[12:07:14.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:40:0)
[12:07:14.563] Timer tick, asking Buildbucket for the build status
[12:07:14.921] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:41:0) after 1m0s
[12:08:14.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:41:0)
[12:08:14.999] Timer tick, asking Buildbucket for the build status
[12:08:15.375] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:15.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:42:0) after 1m0s
[12:09:15.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:42:0)
[12:09:15.465] Timer tick, asking Buildbucket for the build status
[12:09:15.699] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:15.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:43:0) after 1m0s
[12:10:15.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:43:0)
[12:10:15.726] Timer tick, asking Buildbucket for the build status
[12:10:15.911] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:15.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:44:0) after 1m0s
[12:11:15.980] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:44:0)
[12:11:15.980] Timer tick, asking Buildbucket for the build status
[12:11:16.137] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:16.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:45:0) after 1m0s
[12:12:16.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:45:0)
[12:12:16.215] Timer tick, asking Buildbucket for the build status
[12:12:16.338] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:16.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:46:0) after 1m0s
[12:13:16.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:46:0)
[12:13:16.394] Timer tick, asking Buildbucket for the build status
[12:13:16.583] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:16.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:47:0) after 1m0s
[12:14:16.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:47:0)
[12:14:16.638] Timer tick, asking Buildbucket for the build status
[12:14:17.155] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:17.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:48:0) after 1m0s
[12:15:17.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:48:0)
[12:15:17.212] Timer tick, asking Buildbucket for the build status
[12:15:17.318] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:17.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:49:0) after 1m0s
[12:16:17.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:49:0)
[12:16:17.446] Timer tick, asking Buildbucket for the build status
[12:16:17.564] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:17.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:50:0) after 1m0s
[12:17:17.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:50:0)
[12:17:17.604] Timer tick, asking Buildbucket for the build status
[12:17:17.696] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:51:0) after 1m0s
[12:18:17.857] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:51:0)
[12:18:17.857] Timer tick, asking Buildbucket for the build status
[12:18:17.982] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:17.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:52:0) after 1m0s
[12:19:18.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:52:0)
[12:19:18.067] Timer tick, asking Buildbucket for the build status
[12:19:18.193] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:18.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:53:0) after 1m0s
[12:20:18.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:53:0)
[12:20:18.222] Timer tick, asking Buildbucket for the build status
[12:20:18.381] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:18.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:54:0) after 1m0s
[12:21:18.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:54:0)
[12:21:18.481] Timer tick, asking Buildbucket for the build status
[12:21:18.574] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:18.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:55:0) after 1m0s
[12:22:18.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:55:0)
[12:22:18.640] Timer tick, asking Buildbucket for the build status
[12:22:18.824] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:18.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:56:0) after 1m0s
[12:23:18.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:56:0)
[12:23:18.971] Timer tick, asking Buildbucket for the build status
[12:23:19.063] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:19.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:57:0) after 1m0s
[12:24:19.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:57:0)
[12:24:19.214] Timer tick, asking Buildbucket for the build status
[12:24:19.663] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:19.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:58:0) after 1m0s
[12:25:19.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:58:0)
[12:25:19.805] Timer tick, asking Buildbucket for the build status
[12:25:19.907] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:19.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:59:0) after 1m0s
[12:26:20.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:59:0)
[12:26:20.034] Timer tick, asking Buildbucket for the build status
[12:26:20.169] Build 8887938714358659216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:20.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053368776529121824:60:0) after 1m0s
[12:26:55.072] Received PubSub notification, asking Buildbucket for the build status
[12:26:55.181] Build 8887938714358659216: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:26:55.181] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582201607829138",
"created_by": "project:chromium",
"created_ts": "1582198144531322",
"id": "8887938714358659216",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ea9b4325a7c4f473d98d6b24c68b93eee4364d53\"}}",
"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-17-vizy\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168821, \"got_angle_revision\": \"2801bf43716e8642efe6132c50d2a1708756bba8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"ea9b4325a7c4f473d98d6b24c68b93eee4364d53\", \"got_revision_cp\": \"refs/heads/master@{#743052}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"74d3f371f2015deeedb985a8b24ba242ea65caef\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#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\": \"ea9b4325a7c4f473d98d6b24c68b93eee4364d53\", \"swarm_hashes_refs/heads/master(at){#743052}_without_patch\": {\"accessibility_unittests\": \"d57be66d968129c1e2d715bbcc72360bdf5677b0\", \"angle_unittests\": \"373996974b2cdcdeac0bd8ba515e83aac644c008\", \"app_shell_unittests\": \"fc4cf6a67ff917b5195b66910bbfaff244368a00\", \"aura_unittests\": \"34bd51a8419bc86b9e58a161eb6bc7b1b14492a9\", \"base_unittests\": \"c1ba544a9adc85c150a9ecbdc03a79f77890d206\", \"base_util_unittests\": \"0044debf3dfd0cdcbe54697c17639913d3a59d7d\", \"blink_common_unittests\": \"090e6da0970c072c2cc66567948631b130e57fa8\", \"blink_fuzzer_unittests\": \"0f480d6d8b2de9d49468a9392c504b0d6d0ad7f6\", \"blink_heap_unittests\": \"39d88b5de4a9ad89b5eccd15e3711d8c5ed52cb8\", \"blink_platform_unittests\": \"2f676bf78fc3992f3b0ff8ce17c7c6cc8c7ef74e\", \"blink_python_tests\": \"6bfd588bde349a0719e0329021b509f47e9fac13\", \"blink_unittests\": \"427bac000755c2417e0420884cf640f89d22d107\", \"blink_web_tests\": \"3758c63cad74c1a0cdff69a166f803a953836530\", \"boringssl_crypto_tests\": \"662f28c77da22e313dd21fff2f9c9e72530ddcbc\", \"boringssl_ssl_tests\": \"12dab4e0d0514d8722e1e0c45a333ed33f631eb5\", \"browser_tests\": \"e096d15f753f0871da4ff4b5474acdc9707597b7\", \"cacheinvalidation_unittests\": \"ea925d820e993206cc14b83da3a5deed1403a514\", \"capture_unittests\": \"04c90c3c29fffaa50ba8ae1b949ff394b8ece577\", \"cast_unittests\": \"b3da69b01eca921978acd3981b9baff629456223\", \"cc_unittests\": \"1a4e5918b62f2762153ad37a2ef6accbee1a185e\", \"chrome_app_unittests\": \"304512b20e6e3a645a82a06f9e266a76ab8795d1\", \"chromedriver_unittests\": \"0bb4b1e11cdedb0c79ee5104a788753a86771bc2\", \"components_browsertests\": \"285de7a52a6dc8395f745ea41929313c6babf1cb\", \"components_unittests\": \"a1a6d3d41e9625f2779717de5343392e74d0fb22\", \"compositor_unittests\": \"fabef53b56505ac8c1731f277508422c4af8b9e1\", \"content_browsertests\": \"e1b6bab9ffe1618e08a1d1fa2e077920d83490e9\", \"content_shell_crash_test\": \"8c9c53bbfc944d44c3ec99705fc22c2779bad964\", \"content_unittests\": \"250c5a38ee054714d05f83891c1325ace265d13c\", \"crashpad_tests\": \"8b93159af0f865657b0cf56c3fe999af48fab48c\", \"cronet_tests\": \"45254b1fc4f98f7baeb9a04e8def64c7e3d7fe55\", \"cronet_unittests\": \"b9a322550f25536e48df78390743fac6ecfec855\", \"crypto_unittests\": \"a21b71445bd287108995fca793f041e788d15512\", \"dbus_unittests\": \"3ebfe474c3a5d5ae8b2bd37e1debeaf0381c4c19\", \"device_unittests\": \"7c2e329b7907e2f67ae5df746756136e4acac120\", \"display_unittests\": \"8d22cfba15f176661cb4f1a44b99a259aba5f136\", \"events_unittests\": \"f118aa2b1d6198cf9d3385890df9e5fbcf730f58\", \"extensions_browsertests\": \"80b5978aad13cd6d5a748851cf75a80f87df5aea\", \"extensions_unittests\": \"d6001a83ad68b16134616e0f543ee538b5cf54c1\", \"filesystem_service_unittests\": \"41298c77f0e2adcb6c541c5e41c06fe30c6ac4a0\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"47aa463b7397e8df78c6ff2384a9fe01cdc2bdf9\", \"gfx_unittests\": \"26541d6c80248523744abcc3d6758449f715bccb\", \"gin_unittests\": \"6ef396757b2aa47365b75a36372cd50f6625c657\", \"google_apis_unittests\": \"4e30b7838cc308713165c35717d738676fcae256\", \"gpu_unittests\": \"0f4e532fd35155179443e976b8b2294297458324\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"901f0c2458d9fe991f496e3cbf9b23458b4ced9e\", \"headless_browsertests\": \"6fa3fcbda0575a5474a5486ce1c3270da2fc39fd\", \"headless_unittests\": \"a08e3ca77ac0b99e9041f383006db50844e52f65\", \"interactive_ui_tests\": \"bbf5d02e4a904b69ebd1c3192455917c7d093162\", \"ipc_tests\": \"d323c4aaaa18fca4afbda6eb7551b41fde82999c\", \"jingle_unittests\": \"678b1b0e5845b46337ad4963987f37f019a9ff77\", \"latency_unittests\": \"b539dd284a7118c1c591614f57130a5e46be98c8\", \"libjingle_xmpp_unittests\": \"c551e74d2f8543c7b8771500f39920b81aee6843\", \"media_blink_unittests\": \"ddb5b64538e3c5e6a19582e50dfcf92d5ae49cfb\", \"media_service_unittests\": \"e2c759ae2497d88325bc5c54beb762dbfd358627\", \"media_unittests\": \"2d7c60123319b7d94da13e8a313b3cacdd943a4e\", \"message_center_unittests\": \"d79988b2c2159e617a6df18901fe281b30187790\", \"metrics_python_tests\": \"aafeab90b89836221e933cfe392cc711716d4012\", \"midi_unittests\": \"5b4383887e530a7f19e7a8380db9656f0be79a70\", \"mojo_core_unittests\": \"5255db33211bc73caa94d7e68165c84eaacafdf4\", \"mojo_unittests\": \"453d8b909f935ec863a469bb566600d7ce5961b1\", \"nacl_helper_nonsfi_unittests\": \"caecb15e17102e25d6ca3108a5e4d9890469bfde\", \"nacl_loader_unittests\": \"639545d05add028588ff800a7595b712d8c14c4d\", \"native_theme_unittests\": \"b8d0ed6b2fd99912d9890415fafc9042223a48e5\", \"net_unittests\": \"6648bdd1248bfa5fadc9a1128569b3fb338d1d11\", \"pdf_unittests\": \"66ae2d73b6271911502a02eca363249e45285788\", \"perfetto_unittests\": \"f8d47249a27ed07286fbdca57690c902df98bdaa\", \"ppapi_unittests\": \"a222d36fa55952114174a5752a2708c33371aa89\", \"printing_unittests\": \"613e9c39bbefff74b701f38d4e34c327cc355f57\", \"remoting_unittests\": \"e22627ec18a2b509637f0ad97db881fc44ae852a\", \"sandbox_linux_unittests\": \"ea39847a57442fbc0fba39e0ec10301229d41f74\", \"service_manager_unittests\": \"08242f27c265cb46516227a83fc88494425de2d8\", \"services_unittests\": \"41bed9e33785f2963cadae91d4fa4a11d9b68f06\", \"shell_dialogs_unittests\": \"2db1d08823a6549839592b494da49fad3d83b262\", \"skia_unittests\": \"be48cf3a4794ea9158e400e2705b4aaf2bc4179c\", \"snapshot_unittests\": \"2bc9b78962cae892bab43f899d85e3ffc86d77c4\", \"sql_unittests\": \"e0e35ab6140ad2257bfa0be56dc86f801424a0fe\", \"storage_unittests\": \"cd7288286d04efb9b8d40c1f6098c3707931e759\", \"sync_integration_tests\": \"aa63edb7907ab5f90780071de547bc8f582a7010\", \"telemetry_gpu_unittests\": \"7ca87e5ba28854c958fa9c5477194bcc32926ac0\", \"telemetry_perf_unittests\": \"a61d16db2060da3e24822433adaa365b9fc4ce42\", \"telemetry_unittests\": \"0db34409a5ba425be6cbf35069c25a1046da2815\", \"traffic_annotation_auditor_unittests\": \"6f4a598e35d97726348c300ca931e80ec7815dbc\", \"ui_base_unittests\": \"2bdb3eda1c2b411b7a76e165d23da2c236eeabe7\", \"ui_touch_selection_unittests\": \"e099e878d2c1e16dfa619b17930c4c8f4814ce32\", \"unit_tests\": \"15c1d38e8d7300481170692cad93eb17080dcd0b\", \"url_unittests\": \"3700f29786801439be7cdf86966fb678ad0957bf\", \"views_perftests\": \"26adc413ffb73571e7ddda810c735a5f441181c6\", \"views_unittests\": \"146670d21bb503fbd94dbeaf77f352ba3b85f12b\", \"viz_unittests\": \"592f4be609dbc2b537957f0c3ca64b539377fa9a\", \"vr_common_unittests\": \"dcdc62759aebdd3aaa57486919e73016a1534fe9\", \"vr_pixeltests\": \"d4cdeca2ef4298558c6f771ab209820b01aa9275\", \"webdriver_wpt_tests\": \"09dee9169be9fd7902eb64a0eed4c42c5ab9c4e2\", \"weblayer_browsertests\": \"1d20552ef79429161ccf5cf0fa6a3401e1f638b8\", \"wm_unittests\": \"30fcdbfc5375a98445e9104640c01e972cf70661\", \"wtf_unittests\": \"6af03931ca7ff498de6eb63a2cb91409e0c0558c\", \"xr_browser_tests\": \"834105650afd563f802734a47038b28c82ef0c30\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_94dc7d486ab1bafc5bfb11010ef18f0cf20e8e07ae86b58c09f57ba294c280ac_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_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-17-vizy\"], \"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": "1582198442268685",
"status": "COMPLETED",
"status_changed_ts": "1582201608292904",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168821",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9b4325a7c4f473d98d6b24c68b93eee4364d53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053368776529121824",
"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/8887938714358659216/+/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:4a7b2d49bcf07610",
"user_agent:luci-scheduler"
],
"updated_ts": "1582201608327950",
"url": "https://ci.chromium.org/b/8887938714358659216",
"utcnow_ts": "1582201615172479"
}
}
[12:26:55.181] Invocation finished in 57m52.103624445s with status SUCCEEDED