[01:50:54.726] New invocation is queued and will start shortly
[01:50:56.013] Starting the invocation (attempt 1)
[01:50:56.064] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:50:56.064] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:50:56.064] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055851268023310080",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9720ec499f1acd5319025f10bb1cabba0af8293b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055851268023310080",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/9720ec499f1acd5319025f10bb1cabba0af8293b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9720ec499f1acd5319025f10bb1cabba0af8293b",
"gitiles_ref:refs/heads/master"
]
}
[01:50:58.189] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579830656505104",
"id": "8890421205483353136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9720ec499f1acd5319025f10bb1cabba0af8293b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579830657827722",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167759",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9720ec499f1acd5319025f10bb1cabba0af8293b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9720ec499f1acd5319025f10bb1cabba0af8293b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055851268023310080",
"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/8890421205483353136/+/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": "1579830657830495",
"url": "https://ci.chromium.org/b/8890421205483353136",
"utcnow_ts": "1579830658181793"
}
}
[01:50:58.189] Task URL: https://ci.chromium.org/b/8890421205483353136
[01:50:58.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:2:0) after 1m0s
[01:51:58.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:2:0)
[01:51:58.231] Timer tick, asking Buildbucket for the build status
[01:51:58.658] Build 8890421205483353136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:58.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:3:0) after 1m0s
[01:52:58.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:3:0)
[01:52:58.639] Timer tick, asking Buildbucket for the build status
[01:52:58.813] Build 8890421205483353136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:58.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:4:0) after 1m0s
[01:53:58.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:4:0)
[01:53:58.915] Timer tick, asking Buildbucket for the build status
[01:53:59.313] Build 8890421205483353136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:59.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:5:0) after 1m0s
[01:54:59.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:5:0)
[01:54:59.359] Timer tick, asking Buildbucket for the build status
[01:55:00.133] Build 8890421205483353136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:00.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:6:0) after 1m0s
[01:55:11.810] Received PubSub notification, asking Buildbucket for the build status
[01:55:12.268] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:00.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:6:0)
[01:56:00.180] Timer tick, asking Buildbucket for the build status
[01:56:00.698] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:00.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:8:0) after 1m0s
[01:57:00.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:8:0)
[01:57:00.767] Timer tick, asking Buildbucket for the build status
[01:57:01.363] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:01.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:9:0) after 1m0s
[01:58:01.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:9:0)
[01:58:01.376] Timer tick, asking Buildbucket for the build status
[01:58:02.108] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:02.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:10:0) after 1m0s
[01:59:02.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:10:0)
[01:59:02.124] Timer tick, asking Buildbucket for the build status
[01:59:02.596] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:02.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:11:0) after 1m0s
[02:00:02.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:11:0)
[02:00:02.671] Timer tick, asking Buildbucket for the build status
[02:00:03.777] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:03.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:12:0) after 1m0s
[02:01:03.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:12:0)
[02:01:03.794] Timer tick, asking Buildbucket for the build status
[02:01:04.243] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:04.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:13:0) after 1m0s
[02:02:04.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:13:0)
[02:02:04.261] Timer tick, asking Buildbucket for the build status
[02:02:04.414] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:04.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:14:0) after 1m0s
[02:03:04.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:14:0)
[02:03:04.529] Timer tick, asking Buildbucket for the build status
[02:03:05.017] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:05.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:15:0) after 1m0s
[02:04:05.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:15:0)
[02:04:05.113] Timer tick, asking Buildbucket for the build status
[02:04:05.960] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:05.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:16:0) after 1m0s
[02:05:06.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:16:0)
[02:05:06.009] Timer tick, asking Buildbucket for the build status
[02:05:06.187] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:06.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:17:0) after 1m0s
[02:06:06.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:17:0)
[02:06:06.229] Timer tick, asking Buildbucket for the build status
[02:06:06.615] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:06.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:18:0) after 1m0s
[02:07:06.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:18:0)
[02:07:06.654] Timer tick, asking Buildbucket for the build status
[02:07:07.684] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:19:0) after 1m0s
[02:08:07.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:19:0)
[02:08:07.721] Timer tick, asking Buildbucket for the build status
[02:08:07.890] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:07.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:20:0) after 1m0s
[02:09:07.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:20:0)
[02:09:07.910] Timer tick, asking Buildbucket for the build status
[02:09:08.145] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:08.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:21:0) after 1m0s
[02:10:08.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:21:0)
[02:10:08.178] Timer tick, asking Buildbucket for the build status
[02:10:11.524] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:11.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:22:0) after 1m0s
[02:11:11.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:22:0)
[02:11:11.647] Timer tick, asking Buildbucket for the build status
[02:11:11.928] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:11.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:23:0) after 1m0s
[02:12:11.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:23:0)
[02:12:11.986] Timer tick, asking Buildbucket for the build status
[02:12:12.779] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:12.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:24:0) after 1m0s
[02:13:12.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:24:0)
[02:13:12.895] Timer tick, asking Buildbucket for the build status
[02:13:13.251] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:25:0) after 1m0s
[02:14:13.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:25:0)
[02:14:13.450] Timer tick, asking Buildbucket for the build status
[02:14:13.694] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:26:0) after 1m0s
[02:15:13.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:26:0)
[02:15:13.790] Timer tick, asking Buildbucket for the build status
[02:15:14.217] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:27:0) after 1m0s
[02:16:14.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:27:0)
[02:16:14.378] Timer tick, asking Buildbucket for the build status
[02:16:14.990] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.991] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:28:0) after 1m0s
[02:17:15.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:28:0)
[02:17:15.064] Timer tick, asking Buildbucket for the build status
[02:17:15.675] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:15.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:29:0) after 1m0s
[02:18:15.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:29:0)
[02:18:15.722] Timer tick, asking Buildbucket for the build status
[02:18:15.869] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:15.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:30:0) after 1m0s
[02:19:16.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:30:0)
[02:19:16.010] Timer tick, asking Buildbucket for the build status
[02:19:16.449] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:16.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:31:0) after 1m0s
[02:20:16.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:31:0)
[02:20:16.621] Timer tick, asking Buildbucket for the build status
[02:20:16.972] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:16.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:32:0) after 1m0s
[02:21:17.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:32:0)
[02:21:17.044] Timer tick, asking Buildbucket for the build status
[02:21:17.302] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:17.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:33:0) after 1m0s
[02:22:17.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:33:0)
[02:22:17.330] Timer tick, asking Buildbucket for the build status
[02:22:17.732] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:17.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:34:0) after 1m0s
[02:23:17.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:34:0)
[02:23:17.792] Timer tick, asking Buildbucket for the build status
[02:23:18.298] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:18.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:35:0) after 1m0s
[02:24:18.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:35:0)
[02:24:18.321] Timer tick, asking Buildbucket for the build status
[02:24:18.505] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:18.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:36:0) after 1m0s
[02:25:18.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:36:0)
[02:25:18.660] Timer tick, asking Buildbucket for the build status
[02:25:18.892] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:37:0) after 1m0s
[02:26:18.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:37:0)
[02:26:18.938] Timer tick, asking Buildbucket for the build status
[02:26:19.119] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:19.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:38:0) after 1m0s
[02:27:19.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:38:0)
[02:27:19.400] Timer tick, asking Buildbucket for the build status
[02:27:20.574] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:20.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:39:0) after 1m0s
[02:28:20.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:39:0)
[02:28:20.617] Timer tick, asking Buildbucket for the build status
[02:28:20.805] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:20.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:40:0) after 1m0s
[02:29:20.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:40:0)
[02:29:20.875] Timer tick, asking Buildbucket for the build status
[02:29:21.092] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:21.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:41:0) after 1m0s
[02:30:21.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:41:0)
[02:30:21.272] Timer tick, asking Buildbucket for the build status
[02:30:22.434] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:22.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:42:0) after 1m0s
[02:31:22.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:42:0)
[02:31:22.615] Timer tick, asking Buildbucket for the build status
[02:31:23.131] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:23.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:43:0) after 1m0s
[02:32:23.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:43:0)
[02:32:23.215] Timer tick, asking Buildbucket for the build status
[02:32:23.772] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:23.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:44:0) after 1m0s
[02:33:23.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:44:0)
[02:33:23.901] Timer tick, asking Buildbucket for the build status
[02:33:24.258] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:24.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:45:0) after 1m0s
[02:34:24.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:45:0)
[02:34:24.420] Timer tick, asking Buildbucket for the build status
[02:34:24.721] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:24.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:46:0) after 1m0s
[02:35:24.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:46:0)
[02:35:24.858] Timer tick, asking Buildbucket for the build status
[02:35:25.169] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:25.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:47:0) after 1m0s
[02:36:25.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:47:0)
[02:36:25.372] Timer tick, asking Buildbucket for the build status
[02:36:25.521] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:25.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:48:0) after 1m0s
[02:37:25.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:48:0)
[02:37:25.552] Timer tick, asking Buildbucket for the build status
[02:37:26.238] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:26.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:49:0) after 1m0s
[02:38:26.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:49:0)
[02:38:26.289] Timer tick, asking Buildbucket for the build status
[02:38:26.713] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:26.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:50:0) after 1m0s
[02:39:26.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:50:0)
[02:39:26.766] Timer tick, asking Buildbucket for the build status
[02:39:27.104] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:27.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:51:0) after 1m0s
[02:40:27.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:51:0)
[02:40:27.178] Timer tick, asking Buildbucket for the build status
[02:40:27.449] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:27.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:52:0) after 1m0s
[02:41:27.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:52:0)
[02:41:27.539] Timer tick, asking Buildbucket for the build status
[02:41:28.859] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:28.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:53:0) after 1m0s
[02:42:28.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:53:0)
[02:42:28.953] Timer tick, asking Buildbucket for the build status
[02:42:31.270] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:31.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:54:0) after 1m0s
[02:43:31.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:54:0)
[02:43:31.318] Timer tick, asking Buildbucket for the build status
[02:43:31.496] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:31.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:55:0) after 1m0s
[02:44:31.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:55:0)
[02:44:31.596] Timer tick, asking Buildbucket for the build status
[02:44:31.746] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:31.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:56:0) after 1m0s
[02:45:31.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:56:0)
[02:45:31.843] Timer tick, asking Buildbucket for the build status
[02:45:32.800] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:32.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:57:0) after 1m0s
[02:46:32.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:57:0)
[02:46:32.931] Timer tick, asking Buildbucket for the build status
[02:46:33.105] Build 8890421205483353136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:33.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055851268023310080:58:0) after 1m0s
[02:46:58.320] Received PubSub notification, asking Buildbucket for the build status
[02:46:58.478] Build 8890421205483353136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:46:58.479] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579834016657598",
"created_by": "project:chromium",
"created_ts": "1579830656505104",
"id": "8890421205483353136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9720ec499f1acd5319025f10bb1cabba0af8293b\"}}",
"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-30-bqj5\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167759, \"got_angle_revision\": \"813b027723cb04c6bd11911174d055ca46d20723\", \"got_dawn_revision\": \"d87e67684558fd46e15c382b78558fb13a091920\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"9720ec499f1acd5319025f10bb1cabba0af8293b\", \"got_revision_cp\": \"refs/heads/master@{#734774}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"5ef91b82cbedb4e262af2cb4fddaee7006de8818\", \"got_v8_revision\": \"6c8330035f2d351464f433133ba92c471b7b04ea\", \"got_v8_revision_cp\": \"refs/heads/8.1.268@{#1}\", \"got_webrtc_revision\": \"760fd5249488a7a8698679a026b247423caa7cce\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30366}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9720ec499f1acd5319025f10bb1cabba0af8293b\", \"swarm_hashes_refs/heads/master(at){#734774}_without_patch\": {\"accessibility_unittests\": \"cf7ab2630ac88c50dd004346f6fbbc615ab14110\", \"angle_unittests\": \"78b26e0d1a0a9897df0e0293c29a19b6bae87d6a\", \"app_shell_unittests\": \"a25e1d6d424ba4ae24a7cd37eeee48b900e086b7\", \"aura_unittests\": \"b7ac23cae2026d65b64d113a202aa2d23581600c\", \"base_unittests\": \"abcd915a1cea2b12b891fdba9fbeba00d8c11175\", \"base_util_unittests\": \"20ec693eb396e67eb399f64c836eba85c4579f7f\", \"blink_common_unittests\": \"39c7eb9fa3bc20504b8eb267859360bdf39fb4db\", \"blink_fuzzer_unittests\": \"5741c0e11ea795b2a8ee3a8f4aded8e082dffb2d\", \"blink_heap_unittests\": \"080266b2965503d05453947c52f4e113bced277e\", \"blink_platform_unittests\": \"be90938dacb5ba63f86eb0b72b60334f26b32d9c\", \"blink_python_tests\": \"d038cc6dc91229ebdcd59f7e5ae505528da14034\", \"blink_unittests\": \"8d072c91cffb79eeee157f4ed14d97a943635046\", \"blink_web_tests\": \"2bfcc9bb18b31eac05b63f0f0aa06e89fbbc0560\", \"boringssl_crypto_tests\": \"104106235ea7c379202077e89fcc57b923e0dd6e\", \"boringssl_ssl_tests\": \"3cd56afc162753c7ae12429b6a43f45dfd6554d3\", \"browser_tests\": \"45a88e9677063495a5346faf9f5d94057b5ea514\", \"cacheinvalidation_unittests\": \"7c73b0780c1f251116ff08164e6fe1ae6bf439b1\", \"capture_unittests\": \"6ff0f76462fd97bc9716fa96ec7dd233b691c5c9\", \"cast_unittests\": \"633404714e760c0be152cbfdc91b0e23ddbd72fd\", \"cc_unittests\": \"845c5c8b5823b9157547b29f6c6b43345f9a1d46\", \"chrome_app_unittests\": \"1d054f5e6f934b988ab8d9e6710de09021f8bd66\", \"chromedriver_unittests\": \"b36dbc28c1bde62b0fcb835fddaf4f9f280f7bfc\", \"components_browsertests\": \"eed7755f4334f19bec13d6ce69b6e03e614f362a\", \"components_unittests\": \"c1f3bd3d6e61c7d419c42cad31b8af18fd3f63c2\", \"compositor_unittests\": \"ac0332c4fa07a58567c8869e49065f383aaff7b4\", \"content_browsertests\": \"767c2cf3ef04ed33d084389653dc6124ba029766\", \"content_shell_crash_test\": \"dc942053f7ef0eb44f32e492159f985162bde7ae\", \"content_unittests\": \"cac4fff9f1fd7d867cbf5b8df70941c2fcb88e1e\", \"crashpad_tests\": \"9a99cbd1f85ffe841b2af29c6efab659cd9701c7\", \"cronet_tests\": \"ec9973ce6e4adccef0f9fb23b6fe25a5b9969dbf\", \"cronet_unittests\": \"deae5b96ffd3729ee1257c6090aed64589c307a3\", \"crypto_unittests\": \"8c157897fff23200b4cb2afb4ba3280aa10a1dac\", \"dbus_unittests\": \"7365d61f7035f383f98a580ae95110ff5290428d\", \"device_unittests\": \"fa84c368a7c2f5d2b8fb990349006579c7592989\", \"display_unittests\": \"b605d956f31374a6faa0a273715a49b37a344486\", \"events_unittests\": \"9a6e992ebf050c7725edbf0902ea74afa7c4e20f\", \"extensions_browsertests\": \"78aa5d084d7bd089091403e3dc21662c19fbbe23\", \"extensions_unittests\": \"486b9f8abf99ef665e03294d7cf204fa731cd0f8\", \"filesystem_service_unittests\": \"b1c71b0015757ff5ee7dbc25957ebd2df4f57a4d\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"a4cc0d278e0b546ab85d14f5303224e251c432ac\", \"gfx_unittests\": \"e5fea47ad7df7d3188bf1e73283ad2c2592eabb4\", \"gin_unittests\": \"a60e9fa04da8a132695fd098b24d9a08019de353\", \"google_apis_unittests\": \"b79eb199dab394dc2bf82c2021f487e78283ec81\", \"gpu_unittests\": \"74fec4a94d57b7b23b76e94163a216f9b6a3efe5\", \"grit_python_unittests\": \"d715786376a2cd01028269e386a2464ac7cd5a43\", \"gwp_asan_unittests\": \"9bd65cc559fe28c7f62b99480254bc43501eb9e3\", \"headless_browsertests\": \"128be34c0b758aa538b742582186e3f3ebbbb897\", \"headless_unittests\": \"34ef1613ddffe6b5b1ef16924f450c3b55bdecfd\", \"interactive_ui_tests\": \"b56fc576a9ba45967cc865ebf2af5185f15f910a\", \"ipc_tests\": \"13ebd94e0dc84ea9659b1634a72dd1e725fd88ae\", \"jingle_unittests\": \"94cbe4bc2dee1510553455a67a2de7d14ea53b2c\", \"latency_unittests\": \"d45ad3d7aa40936764f2def392c2d593d1c46918\", \"libjingle_xmpp_unittests\": \"397b7cdc0e7af4f342946896f47bbec6bbe67072\", \"media_blink_unittests\": \"99683a686473dc016e78db41ff71e31171de6839\", \"media_service_unittests\": \"5b4c05c3bc210daf7dbfb0ccfb9ce5397e53802b\", \"media_unittests\": \"2f01802ae3466b52f9cec9b8bf084e2e156052b0\", \"message_center_unittests\": \"f1db35def6821533f2c8b3dcef57e31d078de342\", \"metrics_python_tests\": \"0e099c7cf1bef75eefaa8dfa16dd57a7f6e262f6\", \"midi_unittests\": \"11a00406ba84a227d922a5942a5038755dba0685\", \"mojo_core_unittests\": \"2bb8fb342c21ca4c8fe271657edce54db5ce2489\", \"mojo_unittests\": \"fb4c0f475ba0a01dfe06f0783f1b48a958fd15ea\", \"nacl_helper_nonsfi_unittests\": \"0cc29dd7b0de461896a077f975cc67ce2fe0384f\", \"nacl_loader_unittests\": \"b7bc25b2f0ae9e74cc544ff015a4d854946b48f9\", \"native_theme_unittests\": \"9ca8b87f636b515cad419e51a184e77b249b99f0\", \"net_unittests\": \"f5a59780efcb11ebbe36c67f269ac3cd8b009078\", \"pdf_unittests\": \"43c8c3435836fa1595ee80352364c3540fa6b6fe\", \"perfetto_unittests\": \"93a3b187d76c0b1e8cd96882859683a7ab1100ac\", \"ppapi_unittests\": \"8b54c6b2f8c6ddb2b28045b5b557968fe1bd0717\", \"printing_unittests\": \"14c5fe990198206c3752da27a64a9f76c277b71f\", \"remoting_unittests\": \"8d7add1bf3460b2c629a79ed4276972beaa46ee2\", \"sandbox_linux_unittests\": \"f018fda7ade64c944f18ad3958d4786e85b8bc30\", \"service_manager_unittests\": \"a7e4592047c88ed6b6fc521ba4e4b79371d0ec04\", \"services_unittests\": \"84cf0d3ef22307f15448b6b13cd3a985cd63b430\", \"shell_dialogs_unittests\": \"a8648b79eefde210dea916d2b45b6a5b27f7630d\", \"skia_unittests\": \"7cf501a109cf2fbb0178d27f22eccb2461e2a18c\", \"snapshot_unittests\": \"7112751f4d9a620c9e53ab4affb97e10fdfe4145\", \"sql_unittests\": \"b1aef898e6ae0b08e8c440131b727dedbec8f851\", \"storage_unittests\": \"a5309610e075396b8a04a0dd6d756cec1d82dcfe\", \"sync_integration_tests\": \"1daef0525996447b4b8de29e65cc6c226ae68054\", \"telemetry_gpu_unittests\": \"44abea67deb1c94b2b3dfe34afd200a277959bf0\", \"telemetry_perf_unittests\": \"b87b88b43c257a6ccf965971378c1b0971b30a7d\", \"telemetry_unittests\": \"b24b958a308e939132be3b197410f9513c4e290f\", \"traffic_annotation_auditor_unittests\": \"cf44c558bbc498af3644c1b4770f3cc5368c6a06\", \"ui_base_unittests\": \"178b6447f26d184e141a8f6584832ee6c7532392\", \"ui_touch_selection_unittests\": \"9f82fe990c719c19e23f33e7d39b0017616da0d5\", \"unit_tests\": \"37d628648ecce00f7715f2092a6b9f43a1986365\", \"url_unittests\": \"74b6aa1e6fd0f3daadf1eb86ba21ce57b3fec98e\", \"views_perftests\": \"cbeeed66697f8ba875fa1fc15d94a89a15bc6ea6\", \"views_unittests\": \"a93bc24c755edca0f19862970d46e798a933d643\", \"viz_unittests\": \"9ca83c82db721cfd3bf47a938f4e3475da9f9476\", \"vr_common_unittests\": \"04e1a9134942061c649bf2cf4573cafe971689fd\", \"vr_pixeltests\": \"d68ae965d83ecfda9b62bc3c92cc69a584c1c274\", \"webdriver_wpt_tests\": \"bcda9be6a0645577586453820bcf794a29ba9615\", \"weblayer_browsertests\": \"89248a4fd02cefbbf7503dd0ba01369e6b4cac02\", \"wm_unittests\": \"c199ae0f02f379b8bac38ea3349962e0d8d6cee9\", \"wtf_unittests\": \"491602b82c54708aaa29055e9467a01bd98884f6\", \"xr_browser_tests\": \"75c29fd1e26d8d10a4ffae5ea944d611b6d515cc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5031bc3d586fdedb2b02f62dae64b9ebfd5c405012b7a28b41092bb1d66cfccb_v2\", \"builder_5c21095a1a129131be723eb555273fa4f1dc89f7e6d0ed0ea2c09e97b0cf5fcb_v2\", \"builder_874b300c24913e8fd09d4e55502c2323a0d822e7e1e6984eec8382ff1b789e99_v2\", \"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"builder_fbb3d50a702ddd064319c35d96f4b89f6e7a29ba4c11145a49be75e11fea82c5_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-30-bqj5\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"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\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579830910933270",
"status": "COMPLETED",
"status_changed_ts": "1579834017783245",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167759",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9720ec499f1acd5319025f10bb1cabba0af8293b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9720ec499f1acd5319025f10bb1cabba0af8293b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055851268023310080",
"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/8890421205483353136/+/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:49ee104d1229ba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579834017787115",
"url": "https://ci.chromium.org/b/8890421205483353136",
"utcnow_ts": "1579834018470110"
}
}
[02:46:58.479] Invocation finished in 56m3.779182163s with status SUCCEEDED