[06:42:00.335] New invocation is queued and will start shortly
[06:42:01.556] Starting the invocation (attempt 1)
[06:42:01.655] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:42:01.655] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:42:01.655] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056648326693104976",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5253663345658eca461058207071a19d205f0de2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056648326693104976",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5253663345658eca461058207071a19d205f0de2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5253663345658eca461058207071a19d205f0de2",
"gitiles_ref:refs/heads/master"
]
}
[06:42:03.217] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579070522252687",
"id": "8891218264017287456",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5253663345658eca461058207071a19d205f0de2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579070522946618",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167391",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5253663345658eca461058207071a19d205f0de2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5253663345658eca461058207071a19d205f0de2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056648326693104976",
"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/8891218264017287456/+/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": "1579070522946772",
"url": "https://ci.chromium.org/b/8891218264017287456",
"utcnow_ts": "1579070523175001"
}
}
[06:42:03.217] Task URL: https://ci.chromium.org/b/8891218264017287456
[06:42:03.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:2:0) after 1m0s
[06:43:03.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:2:0)
[06:43:03.202] Timer tick, asking Buildbucket for the build status
[06:43:03.331] Build 8891218264017287456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:03.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:3:0) after 1m0s
[06:44:03.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:3:0)
[06:44:03.415] Timer tick, asking Buildbucket for the build status
[06:44:03.891] Build 8891218264017287456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:03.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:4:0) after 1m0s
[06:45:04.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:4:0)
[06:45:04.028] Timer tick, asking Buildbucket for the build status
[06:45:04.494] Build 8891218264017287456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:04.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:5:0) after 1m0s
[06:46:04.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:5:0)
[06:46:04.616] Timer tick, asking Buildbucket for the build status
[06:46:05.691] Build 8891218264017287456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:05.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:6:0) after 1m0s
[06:47:05.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:6:0)
[06:47:05.763] Timer tick, asking Buildbucket for the build status
[06:47:06.061] Build 8891218264017287456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:06.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:7:0) after 1m0s
[06:47:08.589] Received PubSub notification, asking Buildbucket for the build status
[06:47:08.715] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:7:0)
[06:48:06.131] Timer tick, asking Buildbucket for the build status
[06:48:06.751] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:9:0) after 1m0s
[06:49:06.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:9:0)
[06:49:06.832] Timer tick, asking Buildbucket for the build status
[06:49:07.232] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:07.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:10:0) after 1m0s
[06:50:07.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:10:0)
[06:50:07.304] Timer tick, asking Buildbucket for the build status
[06:50:07.508] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:07.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:11:0) after 1m0s
[06:51:07.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:11:0)
[06:51:07.568] Timer tick, asking Buildbucket for the build status
[06:51:08.203] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:08.203] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:12:0) after 1m0s
[06:52:08.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:12:0)
[06:52:08.236] Timer tick, asking Buildbucket for the build status
[06:52:08.537] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:08.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:13:0) after 1m0s
[06:53:08.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:13:0)
[06:53:08.630] Timer tick, asking Buildbucket for the build status
[06:53:08.783] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:14:0) after 1m0s
[06:54:08.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:14:0)
[06:54:08.915] Timer tick, asking Buildbucket for the build status
[06:54:09.202] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:09.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:15:0) after 1m0s
[06:55:09.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:15:0)
[06:55:09.222] Timer tick, asking Buildbucket for the build status
[06:55:10.286] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:10.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:16:0) after 1m0s
[06:56:10.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:16:0)
[06:56:10.427] Timer tick, asking Buildbucket for the build status
[06:56:10.560] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:10.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:17:0) after 1m0s
[06:57:10.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:17:0)
[06:57:10.721] Timer tick, asking Buildbucket for the build status
[06:57:10.817] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:10.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:18:0) after 1m0s
[06:58:11.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:18:0)
[06:58:11.068] Timer tick, asking Buildbucket for the build status
[06:58:11.307] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:11.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:19:0) after 1m0s
[06:59:11.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:19:0)
[06:59:11.352] Timer tick, asking Buildbucket for the build status
[06:59:11.891] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:20:0) after 1m0s
[07:00:11.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:20:0)
[07:00:11.920] Timer tick, asking Buildbucket for the build status
[07:00:12.578] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:12.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:21:0) after 1m0s
[07:01:12.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:21:0)
[07:01:12.638] Timer tick, asking Buildbucket for the build status
[07:01:13.362] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:13.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:22:0) after 1m0s
[07:02:13.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:22:0)
[07:02:13.550] Timer tick, asking Buildbucket for the build status
[07:02:13.719] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:13.720] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:23:0) after 1m0s
[07:03:13.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:23:0)
[07:03:13.767] Timer tick, asking Buildbucket for the build status
[07:03:14.469] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:14.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:24:0) after 1m0s
[07:04:14.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:24:0)
[07:04:14.542] Timer tick, asking Buildbucket for the build status
[07:04:14.879] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:14.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:25:0) after 1m0s
[07:05:14.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:25:0)
[07:05:14.898] Timer tick, asking Buildbucket for the build status
[07:05:15.614] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:15.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:26:0) after 1m0s
[07:06:15.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:26:0)
[07:06:15.661] Timer tick, asking Buildbucket for the build status
[07:06:15.878] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:15.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:27:0) after 1m0s
[07:07:16.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:27:0)
[07:07:16.128] Timer tick, asking Buildbucket for the build status
[07:07:16.485] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:16.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:28:0) after 1m0s
[07:08:16.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:28:0)
[07:08:16.607] Timer tick, asking Buildbucket for the build status
[07:08:17.472] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:17.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:29:0) after 1m0s
[07:09:17.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:29:0)
[07:09:17.812] Timer tick, asking Buildbucket for the build status
[07:09:18.735] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:18.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:30:0) after 1m0s
[07:10:18.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:30:0)
[07:10:18.794] Timer tick, asking Buildbucket for the build status
[07:10:19.654] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:19.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:31:0) after 1m0s
[07:11:19.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:31:0)
[07:11:19.648] Timer tick, asking Buildbucket for the build status
[07:11:20.263] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:20.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:32:0) after 1m0s
[07:12:20.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:32:0)
[07:12:20.446] Timer tick, asking Buildbucket for the build status
[07:12:20.631] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:20.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:33:0) after 1m0s
[07:13:20.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:33:0)
[07:13:20.722] Timer tick, asking Buildbucket for the build status
[07:13:20.820] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:20.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:34:0) after 1m0s
[07:14:21.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:34:0)
[07:14:21.089] Timer tick, asking Buildbucket for the build status
[07:14:23.432] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:23.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:35:0) after 1m0s
[07:15:23.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:35:0)
[07:15:23.527] Timer tick, asking Buildbucket for the build status
[07:15:23.731] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:23.731] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:36:0) after 1m0s
[07:16:23.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:36:0)
[07:16:23.732] Timer tick, asking Buildbucket for the build status
[07:16:24.212] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:24.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:37:0) after 1m0s
[07:17:24.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:37:0)
[07:17:24.321] Timer tick, asking Buildbucket for the build status
[07:17:24.489] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:24.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:38:0) after 1m0s
[07:18:24.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:38:0)
[07:18:24.646] Timer tick, asking Buildbucket for the build status
[07:18:25.378] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:25.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:39:0) after 1m0s
[07:19:25.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:39:0)
[07:19:25.599] Timer tick, asking Buildbucket for the build status
[07:19:27.273] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:27.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:40:0) after 1m0s
[07:20:27.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:40:0)
[07:20:27.394] Timer tick, asking Buildbucket for the build status
[07:20:27.561] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:27.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:41:0) after 1m0s
[07:21:27.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:41:0)
[07:21:27.577] Timer tick, asking Buildbucket for the build status
[07:21:28.161] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:28.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:42:0) after 1m0s
[07:22:28.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:42:0)
[07:22:28.216] Timer tick, asking Buildbucket for the build status
[07:22:31.877] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:31.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:43:0) after 1m0s
[07:23:31.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:43:0)
[07:23:31.938] Timer tick, asking Buildbucket for the build status
[07:23:32.915] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:32.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:44:0) after 1m0s
[07:24:33.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:44:0)
[07:24:33.062] Timer tick, asking Buildbucket for the build status
[07:24:33.202] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:33.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:45:0) after 1m0s
[07:25:33.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:45:0)
[07:25:33.471] Timer tick, asking Buildbucket for the build status
[07:25:33.699] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:33.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:46:0) after 1m0s
[07:26:33.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:46:0)
[07:26:33.889] Timer tick, asking Buildbucket for the build status
[07:26:34.214] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:34.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:47:0) after 1m0s
[07:27:34.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:47:0)
[07:27:34.325] Timer tick, asking Buildbucket for the build status
[07:27:34.556] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:34.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:48:0) after 1m0s
[07:28:34.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:48:0)
[07:28:34.660] Timer tick, asking Buildbucket for the build status
[07:28:34.809] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:34.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:49:0) after 1m0s
[07:29:34.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:49:0)
[07:29:34.916] Timer tick, asking Buildbucket for the build status
[07:29:35.075] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:35.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:50:0) after 1m0s
[07:30:35.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:50:0)
[07:30:35.164] Timer tick, asking Buildbucket for the build status
[07:30:35.663] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:35.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:51:0) after 1m0s
[07:31:35.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:51:0)
[07:31:35.796] Timer tick, asking Buildbucket for the build status
[07:31:35.946] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:35.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:52:0) after 1m0s
[07:32:35.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:52:0)
[07:32:35.943] Timer tick, asking Buildbucket for the build status
[07:32:37.557] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:37.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:53:0) after 1m0s
[07:33:37.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:53:0)
[07:33:37.642] Timer tick, asking Buildbucket for the build status
[07:33:39.789] Build 8891218264017287456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:39.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056648326693104976:54:0) after 1m0s
[07:34:17.690] Received PubSub notification, asking Buildbucket for the build status
[07:34:18.047] Build 8891218264017287456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:34:18.047] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579073656573973",
"created_by": "project:chromium",
"created_ts": "1579070522252687",
"id": "8891218264017287456",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5253663345658eca461058207071a19d205f0de2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-0-l410\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167391, \"got_angle_revision\": \"86f730971751eb18460f2e13e3be1b97a68abacc\", \"got_dawn_revision\": \"7f078e7ebe3f1046b57f4fb3cc21805b4348dbf9\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"5253663345658eca461058207071a19d205f0de2\", \"got_revision_cp\": \"refs/heads/master@{#731858}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"146e16f68fdc4678600031ab3256cccf7b32d5e2\", \"got_v8_revision\": \"fab248d2ccddc45eae7806b23a1992b304e1abb2\", \"got_v8_revision_cp\": \"refs/heads/8.1.185@{#1}\", \"got_webrtc_revision\": \"b42aeaa3fb21d78e59c47d2a9916acb380494496\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30212}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5253663345658eca461058207071a19d205f0de2\", \"swarm_hashes_refs/heads/master(at){#731858}_without_patch\": {\"accessibility_unittests\": \"72c723fd0f7b040670f49cb3d5084d660bc1df4e\", \"angle_unittests\": \"4677daaf12ee4f1266ef6aadf7264b8a00c9e6da\", \"app_shell_unittests\": \"dcd3551d3128e4d4e48c3df9d6f3403785ef3b76\", \"aura_unittests\": \"22840abed8d7ccf4dcf2406c33a778f18e10b878\", \"base_unittests\": \"7cfff3e63393fa316e657c110ea0b679ba153c06\", \"base_util_unittests\": \"ea81722d0875148c8445957335ce0054448a2c61\", \"blink_common_unittests\": \"bfe01b84956796e0cb5f792a34b865c9503ead86\", \"blink_fuzzer_unittests\": \"c200f6d1559230d831bbf2ad1e420c4868e09042\", \"blink_heap_unittests\": \"1e5320d9ece265c54d6d89b6db05fc4345dd2903\", \"blink_platform_unittests\": \"ed97979e08e6801779d171d1919a5b8c74a564c3\", \"blink_python_tests\": \"2226d0192cf6be7bba088ee949134ccc2e97a3f8\", \"blink_unittests\": \"e5da49facc27dc2d966a4c4c2cb2657ad7c41c2d\", \"blink_web_tests\": \"ad7f46e13b628ca65c65b88269b25dc856ee62ca\", \"boringssl_crypto_tests\": \"c037cd18bf29691228b148bdfb0e6221ed41a124\", \"boringssl_ssl_tests\": \"68d909fc69d4b3c0977b575297e4f3ff020fc999\", \"browser_tests\": \"a51a1c844e2e0c03312ac0d9d7301c9a46bc438b\", \"cacheinvalidation_unittests\": \"e49a6759d7f456202925c83e5d20128d9c3c4599\", \"capture_unittests\": \"28f5e6011b188f0d17c95e7f3956435ce6344d4e\", \"cast_unittests\": \"617474e26e4411096c61144c32e362bda3bfc380\", \"cc_unittests\": \"6d865bba53c85e2f5cbf7c9197b916aa6d636ab5\", \"chrome_app_unittests\": \"2269092e5c9c342983b6791c934a4e421328675a\", \"chromedriver_unittests\": \"956071b906fc6957dfed0e4226b3bb6e87385733\", \"components_browsertests\": \"369c322810f6f725cfd9cf7920b7b68126b5e1a3\", \"components_unittests\": \"6a6d02dd88a9652757ebbb55a70477125b71fc05\", \"compositor_unittests\": \"f7db71aaab3147ca3c679d9de3dc1de0e2618675\", \"content_browsertests\": \"3129a583ba5a30f65b93ae7c972ec22c0027f97c\", \"content_shell_crash_test\": \"a062c6d84b9210ad3ea488f8cf76ab005c884ce9\", \"content_unittests\": \"d81546b39a304d8ba4617c5f08c1115a4797df7b\", \"crashpad_tests\": \"25c703272c663e00e1ab7c7317a69a3d33518d89\", \"cronet_tests\": \"2c3f72c516191abddebffb141a34fef15ce6b478\", \"cronet_unittests\": \"b59430f6b259569a94f7955ceb5c252818ec9acb\", \"crypto_unittests\": \"f50d7e658b9a100c238d43b7986d9b07f7fb5a4d\", \"dbus_unittests\": \"c34bb9cc6ed940e1d3f02c3fff059b56c684061f\", \"device_unittests\": \"f4b4148aaeb63853cea88aacff91a6d9e654653e\", \"devtools_lint_check\": \"98568e9b24f9b2315d3895fba4707fb1295bde9f\", \"devtools_type_check\": \"e61609a099c0ec622c83a6f55d787a765dac4ee1\", \"display_unittests\": \"5cd060957be8ec9b6f9db39ac9f38090e899de62\", \"events_unittests\": \"e01366deaab0a757cda9cb3790c3b82196e0e136\", \"extensions_browsertests\": \"0bcce3ac19e966a5b067540ade40ad9d5fd04c11\", \"extensions_unittests\": \"d618de04565a794d8aeec883771da766f2add5af\", \"filesystem_service_unittests\": \"0f8c3f5cb9b5da00a70a79cf3990c69d8dc7cf6e\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"a020b95add03557608bd5c560e39b0349ff336cb\", \"gfx_unittests\": \"64c644abdcdbe3465de28feee2aa4c20a24b57d2\", \"gin_unittests\": \"e4d525a52740a41fbab4e4f30cbedd31f349d316\", \"google_apis_unittests\": \"ceca718ade969e75b7802e21d5daf08daf82ad4c\", \"gpu_unittests\": \"e06098ffd07509534675d95d347108950a19aebd\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"41ea4cc60f9a0c7454a0653371021680b2646a01\", \"headless_browsertests\": \"2871495ab962e9de8b7417e14270d101d8d76dda\", \"headless_unittests\": \"953564d360bc5724f31cb7e09f955ce89890cac1\", \"interactive_ui_tests\": \"5a5bdad26a4dc0bc043ce2c91353c4082b7cf810\", \"ipc_tests\": \"1b6f72b32c3e562aa78069915df3fa245c1026fe\", \"jingle_unittests\": \"1e5a453a320a91bed3ea9185759d2f9538b6b6c4\", \"latency_unittests\": \"66c159ef1ed08a7469045fc3874e24dc8bbd4c87\", \"libjingle_xmpp_unittests\": \"ca9acf032b1916c4386c961364f4e7bc57693ba8\", \"media_blink_unittests\": \"19a1880fc762b412b8564ee8856e4e757017edf3\", \"media_service_unittests\": \"250d339575007e246b2c7c9c5b726e398a1f7856\", \"media_unittests\": \"052de67d13168a6a63d49dfff424c180d1864fad\", \"message_center_unittests\": \"dc9b64a75648940a625505db41e01ed92b35a152\", \"metrics_python_tests\": \"2c3895afe1b956075e65e70f1309abf79cb2caae\", \"midi_unittests\": \"92d583f139e751b799ed8ded18fdcb6c49ffe8ae\", \"mojo_core_unittests\": \"c5f6ffe78761c4af9b3459a16251a9c86f1401dd\", \"mojo_unittests\": \"d139114743a74ac68d4e7956312fab90292048dd\", \"nacl_helper_nonsfi_unittests\": \"e2b7f2b3f35e6f52844b7203b847865e84fec957\", \"nacl_loader_unittests\": \"47000b30a4b7ee299c317f6e82681f44226d2d14\", \"native_theme_unittests\": \"321738640a787152f96a8f4b46b1fc50034a1288\", \"net_unittests\": \"f1e3449621c6d2563a86b4f4269724b39a73c251\", \"pdf_unittests\": \"cdc36e606a7434e6c9295940285756c5e2746b51\", \"perfetto_unittests\": \"c72e8a307d3e00b3eb4725edd19b934d70b33ec4\", \"ppapi_unittests\": \"ee58eb17e2b0e39fa0328c34765e28d3d24a7083\", \"printing_unittests\": \"5e5fd508daaea90aebde841c1d530e4f48460a67\", \"remoting_unittests\": \"c9da9daa77b41b0656114d4564b6b6fdad9dc147\", \"sandbox_linux_unittests\": \"03e54aa7e028ae77443e6fcff2bf017e9a680cd1\", \"service_manager_unittests\": \"7ee457ee13e028e6fe951cdfca8726fa04723602\", \"services_unittests\": \"84cde168dd9db909e525b29bccd60ca6b74239f7\", \"shell_dialogs_unittests\": \"28beb16bdd70bea7ab4ddc3ef902dbcd833ea92c\", \"skia_unittests\": \"5414e0a2124b2d232967549e392a9c6aa99e3f3d\", \"snapshot_unittests\": \"b172cba5e65835dadf452041c42afb7b9bd92c41\", \"sql_unittests\": \"653458f12f5a10d67dfa36b7654310ca936007d7\", \"storage_unittests\": \"51ec67e77082fbd112c844b918726e482e87c952\", \"sync_integration_tests\": \"d0741659303f1a018674b1497d63af17d2412c48\", \"telemetry_gpu_unittests\": \"a1186410d8141e3e661c36ce7532d74772b0ca6f\", \"telemetry_perf_unittests\": \"65a65381c36c5ffc2c69dbec32babe452401773b\", \"telemetry_unittests\": \"711c8097d10fff9beea7977558865ffd8ed57594\", \"traffic_annotation_auditor_unittests\": \"0340d7c634cc55f51e6ca077d5685967c0049477\", \"ui_base_unittests\": \"e6ba2da814e7162bbb36e25b9c32a8d994216cfb\", \"ui_touch_selection_unittests\": \"a508e4b9701f146e9dc6acad6fe75dad05f07256\", \"unit_tests\": \"af95745fa981e5b8f5144e3e31201c1d9e722784\", \"url_unittests\": \"aa26a7f60394d29c518eb7a79ad09207243e1923\", \"views_perftests\": \"289af5f9f8af2ce22919dfba8ec5999cde3830e2\", \"views_unittests\": \"17cc650492b3d49091e645d1eedf0fa8eba2300c\", \"viz_unittests\": \"fd726851ce0707a9b0f08d7c57215d6d6b98414e\", \"vr_common_unittests\": \"ae59f8584d0ba852f15646f278d22f3b8b1f8193\", \"vr_pixeltests\": \"ac7793246060849e29da95da62fb3ef7165b4d00\", \"webdriver_wpt_tests\": \"829576aca0a1146dfab96ae2255ccfff885403ee\", \"weblayer_browsertests\": \"cc917fc1918be021585f49ca22c6966429565b19\", \"wm_unittests\": \"e41d10a34289ae54eec967091b9f906b8e7377ef\", \"wtf_unittests\": \"123624500e53004c79e64a95875b44650ebe2bf3\", \"xr_browser_tests\": \"cdebf3d08f05708d25d2c107c3fa54e1724e2d69\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_8116c80c2882afcd67a910417bbd6fba6f94de5911d922ccd599199b9d07f827_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-central1-b-8-0-l410\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4760-d9a0e6d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579070827083655",
"status": "COMPLETED",
"status_changed_ts": "1579073657051819",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167391",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5253663345658eca461058207071a19d205f0de2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5253663345658eca461058207071a19d205f0de2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056648326693104976",
"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/8891218264017287456/+/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:49c0c223529a6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579073657061587",
"url": "https://ci.chromium.org/b/8891218264017287456",
"utcnow_ts": "1579073658038012"
}
}
[07:34:18.047] Invocation finished in 52m17.729429294s with status SUCCEEDED