[11:48:48.261] New invocation is queued and will start shortly
[11:48:49.354] Starting the invocation (attempt 1)
[11:48:49.391] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:48:49.391] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:48:49.391] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054182906487901520",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b11bf903e29531a4dc0285f07441286262ce9ba4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054182906487901520",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b11bf903e29531a4dc0285f07441286262ce9ba4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b11bf903e29531a4dc0285f07441286262ce9ba4",
"gitiles_ref:refs/heads/master"
]
}
[11:48:52.224] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581421731711173",
"id": "8888752842207302512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b11bf903e29531a4dc0285f07441286262ce9ba4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581421732095350",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168480",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b11bf903e29531a4dc0285f07441286262ce9ba4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b11bf903e29531a4dc0285f07441286262ce9ba4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054182906487901520",
"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/8888752842207302512/+/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": "1581421732095538",
"url": "https://ci.chromium.org/b/8888752842207302512",
"utcnow_ts": "1581421732215727"
}
}
[11:48:52.224] Task URL: https://ci.chromium.org/b/8888752842207302512
[11:48:52.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:2:0) after 1m0s
[11:49:52.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:2:0)
[11:49:52.207] Timer tick, asking Buildbucket for the build status
[11:49:52.403] Build 8888752842207302512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:52.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:3:0) after 1m0s
[11:50:52.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:3:0)
[11:50:52.390] Timer tick, asking Buildbucket for the build status
[11:50:53.881] Build 8888752842207302512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:53.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:4:0) after 1m0s
[11:51:53.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:4:0)
[11:51:53.872] Timer tick, asking Buildbucket for the build status
[11:51:54.027] Build 8888752842207302512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:51:54.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:5:0) after 1m0s
[11:52:54.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:5:0)
[11:52:54.058] Timer tick, asking Buildbucket for the build status
[11:52:54.262] Build 8888752842207302512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:54.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:6:0) after 1m0s
[11:53:04.877] Received PubSub notification, asking Buildbucket for the build status
[11:53:05.802] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:54.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:6:0)
[11:53:54.314] Timer tick, asking Buildbucket for the build status
[11:53:54.449] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:54.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:8:0) after 1m0s
[11:54:54.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:8:0)
[11:54:54.614] Timer tick, asking Buildbucket for the build status
[11:54:55.842] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:55.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:9:0) after 1m0s
[11:55:55.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:9:0)
[11:55:55.868] Timer tick, asking Buildbucket for the build status
[11:55:56.178] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:56.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:10:0) after 1m0s
[11:56:56.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:10:0)
[11:56:56.193] Timer tick, asking Buildbucket for the build status
[11:56:56.771] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:56.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:11:0) after 1m0s
[11:57:56.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:11:0)
[11:57:56.792] Timer tick, asking Buildbucket for the build status
[11:57:57.143] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:57.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:12:0) after 1m0s
[11:58:57.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:12:0)
[11:58:57.210] Timer tick, asking Buildbucket for the build status
[11:58:57.363] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:57.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:13:0) after 1m0s
[11:59:57.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:13:0)
[11:59:57.417] Timer tick, asking Buildbucket for the build status
[11:59:57.659] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:57.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:14:0) after 1m0s
[12:00:57.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:14:0)
[12:00:57.680] Timer tick, asking Buildbucket for the build status
[12:00:58.155] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:58.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:15:0) after 1m0s
[12:01:58.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:15:0)
[12:01:58.249] Timer tick, asking Buildbucket for the build status
[12:01:58.881] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:58.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:16:0) after 1m0s
[12:02:58.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:16:0)
[12:02:58.949] Timer tick, asking Buildbucket for the build status
[12:02:59.083] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:59.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:17:0) after 1m0s
[12:03:59.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:17:0)
[12:03:59.135] Timer tick, asking Buildbucket for the build status
[12:03:59.378] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:59.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:18:0) after 1m0s
[12:04:59.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:18:0)
[12:04:59.427] Timer tick, asking Buildbucket for the build status
[12:04:59.602] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:19:0) after 1m0s
[12:05:59.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:19:0)
[12:05:59.618] Timer tick, asking Buildbucket for the build status
[12:05:59.769] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:59.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:20:0) after 1m0s
[12:06:59.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:20:0)
[12:06:59.823] Timer tick, asking Buildbucket for the build status
[12:07:01.354] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:01.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:21:0) after 1m0s
[12:08:01.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:21:0)
[12:08:01.384] Timer tick, asking Buildbucket for the build status
[12:08:02.084] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:02.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:22:0) after 1m0s
[12:09:02.218] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:22:0)
[12:09:02.218] Timer tick, asking Buildbucket for the build status
[12:09:02.974] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:02.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:23:0) after 1m0s
[12:10:03.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:23:0)
[12:10:03.059] Timer tick, asking Buildbucket for the build status
[12:10:03.213] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:03.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:24:0) after 1m0s
[12:11:03.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:24:0)
[12:11:03.365] Timer tick, asking Buildbucket for the build status
[12:11:03.578] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:03.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:25:0) after 1m0s
[12:12:03.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:25:0)
[12:12:03.727] Timer tick, asking Buildbucket for the build status
[12:12:05.356] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:05.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:26:0) after 1m0s
[12:13:05.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:26:0)
[12:13:05.464] Timer tick, asking Buildbucket for the build status
[12:13:05.678] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:05.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:27:0) after 1m0s
[12:14:05.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:27:0)
[12:14:05.719] Timer tick, asking Buildbucket for the build status
[12:14:06.433] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:06.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:28:0) after 1m0s
[12:15:06.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:28:0)
[12:15:06.533] Timer tick, asking Buildbucket for the build status
[12:15:06.752] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:06.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:29:0) after 1m0s
[12:16:06.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:29:0)
[12:16:06.887] Timer tick, asking Buildbucket for the build status
[12:16:07.017] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:07.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:30:0) after 1m0s
[12:17:07.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:30:0)
[12:17:07.028] Timer tick, asking Buildbucket for the build status
[12:17:07.472] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:07.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:31:0) after 1m0s
[12:18:07.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:31:0)
[12:18:07.543] Timer tick, asking Buildbucket for the build status
[12:18:07.760] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:07.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:32:0) after 1m0s
[12:19:07.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:32:0)
[12:19:07.777] Timer tick, asking Buildbucket for the build status
[12:19:08.062] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:08.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:33:0) after 1m0s
[12:20:08.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:33:0)
[12:20:08.169] Timer tick, asking Buildbucket for the build status
[12:20:08.910] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:08.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:34:0) after 1m0s
[12:21:08.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:34:0)
[12:21:08.940] Timer tick, asking Buildbucket for the build status
[12:21:09.111] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:09.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:35:0) after 1m0s
[12:22:09.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:35:0)
[12:22:09.165] Timer tick, asking Buildbucket for the build status
[12:22:09.321] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:09.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:36:0) after 1m0s
[12:23:09.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:36:0)
[12:23:09.391] Timer tick, asking Buildbucket for the build status
[12:23:09.561] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:09.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:37:0) after 1m0s
[12:24:09.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:37:0)
[12:24:09.701] Timer tick, asking Buildbucket for the build status
[12:24:10.412] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:10.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:38:0) after 1m0s
[12:25:10.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:38:0)
[12:25:10.484] Timer tick, asking Buildbucket for the build status
[12:25:12.096] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:12.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:39:0) after 1m0s
[12:26:12.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:39:0)
[12:26:12.166] Timer tick, asking Buildbucket for the build status
[12:26:12.306] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:12.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:40:0) after 1m0s
[12:27:12.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:40:0)
[12:27:12.321] Timer tick, asking Buildbucket for the build status
[12:27:12.637] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:12.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:41:0) after 1m0s
[12:28:12.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:41:0)
[12:28:12.681] Timer tick, asking Buildbucket for the build status
[12:28:12.815] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:12.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:42:0) after 1m0s
[12:29:12.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:42:0)
[12:29:12.850] Timer tick, asking Buildbucket for the build status
[12:29:13.539] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:13.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:43:0) after 1m0s
[12:30:13.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:43:0)
[12:30:13.586] Timer tick, asking Buildbucket for the build status
[12:30:14.078] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:14.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:44:0) after 1m0s
[12:31:14.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:44:0)
[12:31:14.109] Timer tick, asking Buildbucket for the build status
[12:31:14.271] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:14.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:45:0) after 1m0s
[12:32:14.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:45:0)
[12:32:14.297] Timer tick, asking Buildbucket for the build status
[12:32:14.861] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:14.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:46:0) after 1m0s
[12:33:14.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:46:0)
[12:33:14.903] Timer tick, asking Buildbucket for the build status
[12:33:15.875] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:15.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:47:0) after 1m0s
[12:34:15.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:47:0)
[12:34:15.916] Timer tick, asking Buildbucket for the build status
[12:34:16.105] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:16.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:48:0) after 1m0s
[12:35:16.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:48:0)
[12:35:16.176] Timer tick, asking Buildbucket for the build status
[12:35:16.398] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:16.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:49:0) after 1m0s
[12:36:16.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:49:0)
[12:36:16.507] Timer tick, asking Buildbucket for the build status
[12:36:17.175] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:17.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:50:0) after 1m0s
[12:37:17.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:50:0)
[12:37:17.235] Timer tick, asking Buildbucket for the build status
[12:37:17.366] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:17.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:51:0) after 1m0s
[12:38:17.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:51:0)
[12:38:17.448] Timer tick, asking Buildbucket for the build status
[12:38:22.065] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:22.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:52:0) after 1m0s
[12:39:22.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:52:0)
[12:39:22.357] Timer tick, asking Buildbucket for the build status
[12:39:22.709] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:53:0) after 1m0s
[12:40:22.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:53:0)
[12:40:22.854] Timer tick, asking Buildbucket for the build status
[12:40:22.997] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:22.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:54:0) after 1m0s
[12:41:23.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:54:0)
[12:41:23.085] Timer tick, asking Buildbucket for the build status
[12:41:23.446] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:23.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:55:0) after 1m0s
[12:42:23.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:55:0)
[12:42:23.540] Timer tick, asking Buildbucket for the build status
[12:42:23.690] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:23.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:56:0) after 1m0s
[12:43:23.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:56:0)
[12:43:23.678] Timer tick, asking Buildbucket for the build status
[12:43:23.894] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:23.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:57:0) after 1m0s
[12:44:23.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:57:0)
[12:44:23.925] Timer tick, asking Buildbucket for the build status
[12:44:24.522] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:24.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:58:0) after 1m0s
[12:45:24.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:58:0)
[12:45:24.704] Timer tick, asking Buildbucket for the build status
[12:45:25.095] Build 8888752842207302512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:25.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054182906487901520:59:0) after 1m0s
[12:46:15.797] Received PubSub notification, asking Buildbucket for the build status
[12:46:16.760] Build 8888752842207302512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:46:16.760] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581425175132866",
"created_by": "project:chromium",
"created_ts": "1581421731711173",
"id": "8888752842207302512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b11bf903e29531a4dc0285f07441286262ce9ba4\"}}",
"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-16-8m6f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168480, \"got_angle_revision\": \"b40d48a67c932625f349c6e58036c2d96b62489b\", \"got_dawn_revision\": \"713d9cbf939e525e025a69a17bda0bf51b449c47\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b11bf903e29531a4dc0285f07441286262ce9ba4\", \"got_revision_cp\": \"refs/heads/master@{#740266}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"196d81774ef86d1c6c2d028dd5407b47eaa51a99\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b11bf903e29531a4dc0285f07441286262ce9ba4\", \"swarm_hashes_refs/heads/master(at){#740266}_without_patch\": {\"accessibility_unittests\": \"ee54ba2ecb295d7a35fe672184440c1077566a5c\", \"angle_unittests\": \"45d49712fb055a6351f209eeb3aeb298900ed9ac\", \"app_shell_unittests\": \"8fd1f580e9b77165bd73aab703e78624f440dca1\", \"aura_unittests\": \"9d14860d434552177107df458bc6cce081fbf39d\", \"base_unittests\": \"e28d273839e49b2c52e3bc7bc645da3c702b9c4d\", \"base_util_unittests\": \"c05025d6be5cb337a3dbd3063e68ed2a1628689d\", \"blink_common_unittests\": \"148c292c0f240efa039a641b0dc121251cb97c4a\", \"blink_fuzzer_unittests\": \"ec5dcf9fe7914a0f271966994c7630e2b7718a32\", \"blink_heap_unittests\": \"3b8ce04e85ea6efdb4cf0841b1b997b2c0a80ad7\", \"blink_platform_unittests\": \"04c8d4cd1f84d79b7030463a0cadebbb704dea54\", \"blink_python_tests\": \"690de573e8c8d838b52f642e5e0188bd0975d31c\", \"blink_unittests\": \"3be3662496b0b76872ad3b4555771fbb63c46434\", \"blink_web_tests\": \"d998e4b869f870a441033ceaf83d9c57fc3b3c2f\", \"boringssl_crypto_tests\": \"95e11b3ad1485ef5ca655ac0b86d8ff3dba78047\", \"boringssl_ssl_tests\": \"8866b6c4ad6d38ade44b9b37bae3ea235aadd8cc\", \"browser_tests\": \"1829b89ac61f3fa0ce2fbe7e5a9268bbcfb00cf4\", \"cacheinvalidation_unittests\": \"1d10521a9370a4731b63a49784b78475af69f943\", \"capture_unittests\": \"98c444735f2c94fd1dec330bc4647d48713ccf5c\", \"cast_unittests\": \"6a10be8097580ca3539df8f50d9e0a7a18b022a8\", \"cc_unittests\": \"3f6d9fdea639e6bb66cd55732c3e19b246266cfe\", \"chrome_app_unittests\": \"9834d8559f5cd8acce0626307125edc8503d138d\", \"chromedriver_unittests\": \"cc3c28fc079d2da2d36740e1291af1f4f3dfe4be\", \"components_browsertests\": \"574f4cc127d44918d892ab9beaec7ba437a53b42\", \"components_unittests\": \"9330cbb6fbe995b0a5aa03c58c2df9afdc6f32a0\", \"compositor_unittests\": \"b530092ddf16baf538e6bedd672eb5b358f0aad3\", \"content_browsertests\": \"1db2f1f3c5912c94f763e6f30c86fcb4339227ac\", \"content_shell_crash_test\": \"390607299c7d1da708a240458ff79c0813af6c73\", \"content_unittests\": \"663e79cb61663750a2af8ea36ad476f496711baa\", \"crashpad_tests\": \"023803b06a5c1d9219866baadc639dd403479e66\", \"cronet_tests\": \"afb8d372033c3310d8645806fbb59e3891e9d745\", \"cronet_unittests\": \"e60d6dd46ec7f77542bdeb7da2245e53abe6d039\", \"crypto_unittests\": \"d22b6be1d474b603123318a330a4f75492406c0b\", \"dbus_unittests\": \"c6c5b33b5c4c1bd03bc012e23724f7a0b2e043ce\", \"device_unittests\": \"f30aeca4e6bc2f02273a8a33b6a75e25ce4a855d\", \"display_unittests\": \"8d3ebf7a1cde523135d73e94b772689708f8a1c9\", \"events_unittests\": \"223751b48e59d90bb853b6689d41158a65424fd5\", \"extensions_browsertests\": \"805bdafa8600187950b190b2ad5a35257b8888cc\", \"extensions_unittests\": \"eda581b9edebf2cd3fc3c343a4fdf6686bf7af5a\", \"filesystem_service_unittests\": \"3d6a8fd75ea261b00a21ca9b3fae8c996ef395ee\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"eede9718318d6937f3679a7ec6b143d3b52bbf87\", \"gfx_unittests\": \"a3b7a69d1e90ab4795f988a1f08a831917fa885b\", \"gin_unittests\": \"660e6221e90e5b24fdfd4797224b2ccce057d423\", \"google_apis_unittests\": \"db4e435ba41d1cf1215c505641ad29095ef10cfb\", \"gpu_unittests\": \"7a029c933a55c10074c9b05823f04ba07c4f6053\", \"grit_python_unittests\": \"2c197a1bd065e31b00ed1c7636679f90a901aabe\", \"gwp_asan_unittests\": \"6fba2dccc1827b6afc3e6376d168a769b8d93843\", \"headless_browsertests\": \"4892dd9b7d4189a0a7c3b4d8ea1c2ca0ea96951f\", \"headless_unittests\": \"b818528a341caed8726bdd6c7213a6af5c8f8255\", \"interactive_ui_tests\": \"802e3b5ec1fd923b58c4804e09967a4ead7e6658\", \"ipc_tests\": \"eaed91cb9e85f21f9ba771c2aa46928d8ca3d3d4\", \"jingle_unittests\": \"66ac8c67cc48e88d7e90cce4f75b7dd6bf5cc299\", \"latency_unittests\": \"3cdb4b1f14cb91077ccc08a1574df499c243fa08\", \"libjingle_xmpp_unittests\": \"e9a128a14f8c998fe9e82a43f7418dafac36e1a3\", \"media_blink_unittests\": \"d3dca2d8b71732e83d45d367dd5d9fb4383b9957\", \"media_service_unittests\": \"f16c66af9d687e1274fe09f39583ab78e81db653\", \"media_unittests\": \"8b4400a15d4801dd3ae4cf7365f318adfaa96d31\", \"message_center_unittests\": \"d34b21c370600a8eec09f6467fe824328b6da342\", \"metrics_python_tests\": \"2f25d822974b19ea672bfa643b9d48584a07defb\", \"midi_unittests\": \"6a2efd20b2ef6d361bd28f3bd66f0e0d587cd7f3\", \"mojo_core_unittests\": \"f88b9a3addfffc8a12d94a8ed58ea0f43f9a433a\", \"mojo_unittests\": \"b0c0bae9fb64cf494bdea7f605bd794d6790ac8f\", \"nacl_helper_nonsfi_unittests\": \"34bfb4402e9d109cce9f85ddab7c2e0cb200480a\", \"nacl_loader_unittests\": \"f5590714431ad7ae92ac30ec08693e8c788c1a11\", \"native_theme_unittests\": \"a9d5a9c21d5e503c7c6a0e26ef84aeaf207f24b4\", \"net_unittests\": \"0537664f2b48befebe7a35920534cb55241a83e8\", \"pdf_unittests\": \"c471f212e15fbc4f06615e418516c61f0b7f7528\", \"perfetto_unittests\": \"a0abf6c6ff861323f27a0e76f608e7e4c047cd3b\", \"ppapi_unittests\": \"815d2590f66b4d67f5d9c17f8d4600a51a2f5319\", \"printing_unittests\": \"0a2a32cc88694474cc2c2407197353f971c7222d\", \"remoting_unittests\": \"ea8ba6c2d7194b486210784075038002cd79369e\", \"sandbox_linux_unittests\": \"e8d4ed764c6c7c4768ad6000eb2e73ddb502bc6a\", \"service_manager_unittests\": \"082101532c55459f8b3548c07148d5270f8f4b66\", \"services_unittests\": \"2e23a1bfc1582c630b8d6bf843844fb6e2b45692\", \"shell_dialogs_unittests\": \"0b8213fde1dde68d5dc2235568263a9d6416bd1f\", \"skia_unittests\": \"8d151d27ad45c5a2633eda63c17ee489e291835a\", \"snapshot_unittests\": \"d17b1ea60573d2ecafbed9cd148571f11619ed5d\", \"sql_unittests\": \"1d3e703df90a5bbda98999360d9009e9aefeebd2\", \"storage_unittests\": \"f1a41b296115020484c3323cc2b43ad40f9323a7\", \"sync_integration_tests\": \"b397acbc18f0a1e63302b2ce8599ddf0e5a57a2b\", \"telemetry_gpu_unittests\": \"4ecf7f07dd276a921b92009e0e3eeef35814a761\", \"telemetry_perf_unittests\": \"9e30fc5e71d2c21d63d0f528d9f88769fe352080\", \"telemetry_unittests\": \"7fd415f6b78f54ba6902e3878518588781cf6038\", \"traffic_annotation_auditor_unittests\": \"e5d92524611892f7593cc41a33435f7b10b6b859\", \"ui_base_unittests\": \"016c6b273cb120794a47c7fdbedd0f02be8cf7f4\", \"ui_touch_selection_unittests\": \"9fa1e7436d1c2d44ed503d88a785598db4695238\", \"unit_tests\": \"f4d68891b03a22762a0484c675ca90592963cea7\", \"url_unittests\": \"49f2783dcb6b3311e0355455f39271753b48522a\", \"views_perftests\": \"03aa4c554987422632fc3ea0df2b062014348e8f\", \"views_unittests\": \"666bab337c9add0deefba8ba8e02e05ee50dbc19\", \"viz_unittests\": \"bc235967a9418a8275dbef265a2e55c4c6bafa3f\", \"vr_common_unittests\": \"225c6167b49c30cfbac43d36219001d813223b55\", \"vr_pixeltests\": \"843a33d995ffa4dffdea3f9b2f80f9f2775b581a\", \"webdriver_wpt_tests\": \"137e4a5698b6122f8b40f956c5a2fdaba1bdd8c5\", \"weblayer_browsertests\": \"ea805cffc0f49e337ede28cbc95dd631c76a4745\", \"wm_unittests\": \"267f23171943f5e34cdea4c5132c780effa98f70\", \"wtf_unittests\": \"eed3af1f427d81ecff52316ddecd73bd215f39d9\", \"xr_browser_tests\": \"bd9110cc7d2f9a661d78c53a854b28dd8af12318\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_8116c80c2882afcd67a910417bbd6fba6f94de5911d922ccd599199b9d07f827_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_a3249012ab2e105b4f1a10f0dc352b422f692fd701d889292e5c6170d198db4b_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_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-central1-b-8-16-8m6f\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581421984057564",
"status": "COMPLETED",
"status_changed_ts": "1581425175500649",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168480",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b11bf903e29531a4dc0285f07441286262ce9ba4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b11bf903e29531a4dc0285f07441286262ce9ba4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054182906487901520",
"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/8888752842207302512/+/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:4a4ce62a59627610",
"user_agent:luci-scheduler"
],
"updated_ts": "1581425175508341",
"url": "https://ci.chromium.org/b/8888752842207302512",
"utcnow_ts": "1581425176702432"
}
}
[12:46:16.760] Invocation finished in 57m28.515442225s with status SUCCEEDED