[18:44:08.828] New invocation is queued and will start shortly
[18:44:10.175] Starting the invocation (attempt 1)
[18:44:10.262] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:44:10.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:44:10.263] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9060136175175884464",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"89ac4e448b09333ffa4b12b3c604d310702d85d1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9060136175175884464",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"gitiles_ref:refs/heads/master"
]
}
[18:44:11.429] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1575744250611884",
"id": "8894706112629864368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89ac4e448b09333ffa4b12b3c604d310702d85d1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575744251188265",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165782",
"builder:Linux Builder (dbg)",
"buildset:commit/git/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060136175175884464",
"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/8894706112629864368/+/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": "1575744251188562",
"url": "https://ci.chromium.org/b/8894706112629864368",
"utcnow_ts": "1575744251416615"
}
}
[18:44:11.429] Task URL: https://ci.chromium.org/b/8894706112629864368
[18:44:11.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:2:0) after 1m0s
[18:45:11.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:2:0)
[18:45:11.572] Timer tick, asking Buildbucket for the build status
[18:45:11.768] Build 8894706112629864368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:11.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:3:0) after 1m0s
[18:46:11.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:3:0)
[18:46:11.811] Timer tick, asking Buildbucket for the build status
[18:46:11.946] Build 8894706112629864368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:11.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:4:0) after 1m0s
[18:47:12.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:4:0)
[18:47:12.056] Timer tick, asking Buildbucket for the build status
[18:47:12.500] Build 8894706112629864368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:12.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:5:0) after 1m0s
[18:48:12.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:5:0)
[18:48:12.552] Timer tick, asking Buildbucket for the build status
[18:48:13.244] Build 8894706112629864368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:13.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:6:0) after 1m0s
[18:49:02.940] Received PubSub notification, asking Buildbucket for the build status
[18:49:03.207] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:13.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:6:0)
[18:49:13.273] Timer tick, asking Buildbucket for the build status
[18:49:13.500] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:13.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:8:0) after 1m0s
[18:50:13.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:8:0)
[18:50:13.539] Timer tick, asking Buildbucket for the build status
[18:50:13.761] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:13.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:9:0) after 1m0s
[18:51:13.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:9:0)
[18:51:13.826] Timer tick, asking Buildbucket for the build status
[18:51:14.013] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:10:0) after 1m0s
[18:52:14.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:10:0)
[18:52:14.056] Timer tick, asking Buildbucket for the build status
[18:52:16.521] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:16.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:11:0) after 1m0s
[18:53:16.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:11:0)
[18:53:16.647] Timer tick, asking Buildbucket for the build status
[18:53:16.943] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:16.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:12:0) after 1m0s
[18:54:17.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:12:0)
[18:54:17.246] Timer tick, asking Buildbucket for the build status
[18:54:17.458] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:13:0) after 1m0s
[18:55:17.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:13:0)
[18:55:17.565] Timer tick, asking Buildbucket for the build status
[18:55:18.037] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:14:0) after 1m0s
[18:56:18.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:14:0)
[18:56:18.225] Timer tick, asking Buildbucket for the build status
[18:56:18.435] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:18.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:15:0) after 1m0s
[18:57:18.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:15:0)
[18:57:18.478] Timer tick, asking Buildbucket for the build status
[18:57:18.648] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:18.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:16:0) after 1m0s
[18:58:18.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:16:0)
[18:58:18.678] Timer tick, asking Buildbucket for the build status
[18:58:18.855] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:18.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:17:0) after 1m0s
[18:59:18.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:17:0)
[18:59:18.972] Timer tick, asking Buildbucket for the build status
[18:59:19.209] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:19.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:18:0) after 1m0s
[19:00:19.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:18:0)
[19:00:19.326] Timer tick, asking Buildbucket for the build status
[19:00:19.982] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:19.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:19:0) after 1m0s
[19:01:20.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:19:0)
[19:01:20.046] Timer tick, asking Buildbucket for the build status
[19:01:20.242] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:20.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:20:0) after 1m0s
[19:02:20.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:20:0)
[19:02:20.391] Timer tick, asking Buildbucket for the build status
[19:02:21.038] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:21:0) after 1m0s
[19:03:21.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:21:0)
[19:03:21.046] Timer tick, asking Buildbucket for the build status
[19:03:21.250] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:21.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:22:0) after 1m0s
[19:04:21.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:22:0)
[19:04:21.261] Timer tick, asking Buildbucket for the build status
[19:04:21.831] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:21.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:23:0) after 1m0s
[19:05:21.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:23:0)
[19:05:21.868] Timer tick, asking Buildbucket for the build status
[19:05:22.500] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:22.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:24:0) after 1m0s
[19:06:22.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:24:0)
[19:06:22.630] Timer tick, asking Buildbucket for the build status
[19:06:22.883] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:22.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:25:0) after 1m0s
[19:07:22.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:25:0)
[19:07:22.940] Timer tick, asking Buildbucket for the build status
[19:07:23.439] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:23.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:26:0) after 1m0s
[19:08:23.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:26:0)
[19:08:23.587] Timer tick, asking Buildbucket for the build status
[19:08:23.790] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:23.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:27:0) after 1m0s
[19:09:23.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:27:0)
[19:09:23.858] Timer tick, asking Buildbucket for the build status
[19:09:24.045] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:24.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:28:0) after 1m0s
[19:10:24.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:28:0)
[19:10:24.157] Timer tick, asking Buildbucket for the build status
[19:10:24.535] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:24.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:29:0) after 1m0s
[19:11:24.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:29:0)
[19:11:24.566] Timer tick, asking Buildbucket for the build status
[19:11:25.214] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:25.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:30:0) after 1m0s
[19:12:25.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:30:0)
[19:12:25.243] Timer tick, asking Buildbucket for the build status
[19:12:25.412] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:25.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:31:0) after 1m0s
[19:13:25.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:31:0)
[19:13:25.566] Timer tick, asking Buildbucket for the build status
[19:13:26.111] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:32:0) after 1m0s
[19:14:26.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:32:0)
[19:14:26.242] Timer tick, asking Buildbucket for the build status
[19:14:26.404] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:26.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:33:0) after 1m0s
[19:15:26.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:33:0)
[19:15:26.532] Timer tick, asking Buildbucket for the build status
[19:15:27.027] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:27.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:34:0) after 1m0s
[19:16:27.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:34:0)
[19:16:27.092] Timer tick, asking Buildbucket for the build status
[19:16:28.247] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:28.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:35:0) after 1m0s
[19:17:28.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:35:0)
[19:17:28.275] Timer tick, asking Buildbucket for the build status
[19:17:28.456] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:28.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:36:0) after 1m0s
[19:18:28.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:36:0)
[19:18:28.590] Timer tick, asking Buildbucket for the build status
[19:18:29.159] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:29.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:37:0) after 1m0s
[19:19:29.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:37:0)
[19:19:29.332] Timer tick, asking Buildbucket for the build status
[19:19:29.503] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:29.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:38:0) after 1m0s
[19:20:29.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:38:0)
[19:20:29.608] Timer tick, asking Buildbucket for the build status
[19:20:30.077] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:30.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:39:0) after 1m0s
[19:21:30.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:39:0)
[19:21:30.358] Timer tick, asking Buildbucket for the build status
[19:21:30.545] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:40:0) after 1m0s
[19:22:36.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:40:0)
[19:22:36.345] Timer tick, asking Buildbucket for the build status
[19:22:36.529] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:36.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:41:0) after 1m0s
[19:23:36.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:41:0)
[19:23:36.583] Timer tick, asking Buildbucket for the build status
[19:23:37.177] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:37.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:42:0) after 1m0s
[19:24:37.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:42:0)
[19:24:37.243] Timer tick, asking Buildbucket for the build status
[19:24:37.700] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:43:0) after 1m0s
[19:25:37.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:43:0)
[19:25:37.816] Timer tick, asking Buildbucket for the build status
[19:25:38.385] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:38.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:44:0) after 1m0s
[19:26:38.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:44:0)
[19:26:38.479] Timer tick, asking Buildbucket for the build status
[19:26:38.777] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:38.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:45:0) after 1m0s
[19:27:38.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:45:0)
[19:27:38.921] Timer tick, asking Buildbucket for the build status
[19:27:39.182] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:39.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:46:0) after 1m0s
[19:28:39.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:46:0)
[19:28:39.262] Timer tick, asking Buildbucket for the build status
[19:28:39.692] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:39.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:47:0) after 1m0s
[19:29:39.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:47:0)
[19:29:39.774] Timer tick, asking Buildbucket for the build status
[19:29:40.869] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:48:0) after 1m0s
[19:30:40.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:48:0)
[19:30:40.893] Timer tick, asking Buildbucket for the build status
[19:30:41.143] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:41.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:49:0) after 1m0s
[19:31:41.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:49:0)
[19:31:41.166] Timer tick, asking Buildbucket for the build status
[19:31:41.515] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:41.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:50:0) after 1m0s
[19:32:41.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:50:0)
[19:32:41.541] Timer tick, asking Buildbucket for the build status
[19:32:42.083] Build 8894706112629864368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:42.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060136175175884464:51:0) after 1m0s
[19:32:51.562] Received PubSub notification, asking Buildbucket for the build status
[19:32:51.670] Build 8894706112629864368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:32:51.670] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1575747170538676",
"created_by": "project:chromium",
"created_ts": "1575744250611884",
"id": "8894706112629864368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89ac4e448b09333ffa4b12b3c604d310702d85d1\"}}",
"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-135-oj6g\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165782, \"got_angle_revision\": \"27c89d2b5c567dbe74cb854e77c45bd15bcdcde8\", \"got_dawn_revision\": \"dc3317da6cd5242e525d3230516cfd2bbff25fe9\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"89ac4e448b09333ffa4b12b3c604d310702d85d1\", \"got_revision_cp\": \"refs/heads/master@{#722769}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"31916f494c2eca2ee3257733812ce21aa8f2e756\", \"got_v8_revision\": \"69827db645fcece065bf16a795a4ec8d3a51057f\", \"got_v8_revision_cp\": \"refs/heads/8.0.426@{#2}\", \"got_webrtc_revision\": \"80bc1acb9c2b9d8096f356c748ec9b57e02759c9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30031}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"89ac4e448b09333ffa4b12b3c604d310702d85d1\", \"swarm_hashes_refs/heads/master(at){#722769}_without_patch\": {\"accessibility_unittests\": \"45ebe2f86b1d8cbbeb3c049bc8335ba3988442b7\", \"angle_unittests\": \"3da7babe54d47a2d48833f55a9049fce4109323c\", \"app_shell_unittests\": \"942fa10e517431c0d4f88b7fb9d248077a8f8da3\", \"aura_unittests\": \"20e323e371f9fa35d3ff1c58e7636100e24f22ee\", \"base_unittests\": \"3de819efd70cf92ae0c63dd27f7943b2cfbf76fc\", \"base_util_unittests\": \"35c78eae5e05120400fdd8dc9ba23a6a6c653c7e\", \"blink_common_unittests\": \"2c984690628247d325e4245fe878858ca9c9bae3\", \"blink_fuzzer_unittests\": \"2f4b561148236d4d1baa7e7ab47557324b8a0e9b\", \"blink_heap_unittests\": \"66f0bcd5c8decba6ea89454695323110e892443e\", \"blink_platform_unittests\": \"52a21a06ea94352fe45f7ea09bf1ce3cb6f81dfa\", \"blink_python_tests\": \"877f5b1934390172fe06df3e3258616913169a26\", \"blink_unittests\": \"390d1997750c56625276cc7ad1e619b55a1ad426\", \"blink_web_tests\": \"3aed2890d906723dab16892e5cb413a383309be1\", \"boringssl_crypto_tests\": \"33a6d1ded897811d84a22ed30535a673a11392fe\", \"boringssl_ssl_tests\": \"39779e31baf598d5ced9ecc9f1fa07f610e1e8f6\", \"browser_tests\": \"5c2ff3bdbb503ab080491be1a2c7e990d6c39be9\", \"cacheinvalidation_unittests\": \"d5d8e474d1f0b18aff8432824b2cb54200cf0d54\", \"capture_unittests\": \"821dc784c2730c3e90e3499d2e16872d0435d910\", \"cast_unittests\": \"cdb5d3869bb683bfb4ce5a8a4aa5819695f734c8\", \"cc_unittests\": \"a246bf42914e5ec23de1251a70dd4f43066440ff\", \"chrome_app_unittests\": \"31b3e84d49b8c46a83915873efc0c4b4ac23339e\", \"chromedriver_unittests\": \"84090e9c43b6ec3389c11f41b04f01856b2e4dc6\", \"components_browsertests\": \"4e0f5c4bb523d6024cd78526e2e7639f29a388a3\", \"components_unittests\": \"2b01963446a46394105025abb227bfc80c473d13\", \"compositor_unittests\": \"03c420da47f21c5e5dc9722cd111527d75386ff8\", \"content_browsertests\": \"be9088478534caa7132aea04fd941d71ef7d2270\", \"content_shell_crash_test\": \"475bd308e31575b9b004bb38e914f1a604f17c22\", \"content_unittests\": \"1050a44561b66d451ccf23ff26e7ddcfee38ff44\", \"crashpad_tests\": \"a5fc248895132ae6db634989752dc8631a622599\", \"cronet_tests\": \"a04c14198453561f997881baca1991f2fd6e5254\", \"cronet_unittests\": \"0c3c03f9545506722ff1def9b83b4009d9ed7710\", \"crypto_unittests\": \"cd9d5805cc3811b16653f4c0fad66ee80cce3d51\", \"dbus_unittests\": \"df14d4ee7fba6232e462063888e37787ab71714d\", \"device_unittests\": \"59e9469d7c9323615bfc64306cec274cddddc097\", \"devtools_lint_check\": \"d95d9a81dc71d79f9838639e0d09d604643b7d6f\", \"devtools_type_check\": \"6b9091e748a913ad4b9016062e43bfb24a06fdff\", \"display_unittests\": \"f6ec2738a7a03240a894c8862668c0edc9b18561\", \"events_unittests\": \"06edb8a4488eb8af8180f1ac79b5355942f46ffd\", \"extensions_browsertests\": \"61eff1f3f995099414ee720669fbf7398f576ace\", \"extensions_unittests\": \"a56b23a749cdae181da9443278d32a1cbf3f5db9\", \"filesystem_service_unittests\": \"c8f7f46f3178f6720a4561eed017779015baf6b1\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"0e35d6c104f6a9692d4e126cab755eeebd74d308\", \"gfx_unittests\": \"101d39c1a170b1f18f579dbcefaefa4331054fcc\", \"gin_unittests\": \"8ac099acd10db6a8778c7015cd94b2815301bc1d\", \"google_apis_unittests\": \"1915c1f19b7c8cb9f24de9c5b5b31f3ac314813b\", \"gpu_unittests\": \"f44ada929758bface630833381a0a9d4cc7c8ea2\", \"grit_python_unittests\": \"0c42ecbed689c3d1bcc102380d1896c4df8e7050\", \"gwp_asan_unittests\": \"b3fa3a32532593ec42b64720c489f8fb095b7cd5\", \"headless_browsertests\": \"d40c17b1261052aa8a40d87cb77dea0974a9b891\", \"headless_unittests\": \"93e61070fd77a0676c8d70fc32f6c05bfde38506\", \"interactive_ui_tests\": \"c6b7e39f6c5d8c384be84d8829ca40ca5057eec1\", \"ipc_tests\": \"052b336eabad05bbc841a9fb0d3c78f31b04dfd8\", \"jingle_unittests\": \"cada4549168878955ca72d3148a65accb7472681\", \"latency_unittests\": \"2ce3638445d7fb3aefe8d3f2d9837715c0d892e0\", \"libjingle_xmpp_unittests\": \"8525d80cc9ac19fdbfefafe28a49559b2c512758\", \"media_blink_unittests\": \"41d9c943ef24e692f7e813b2c15bf1e5bc738ca0\", \"media_service_unittests\": \"cc7ebddc8e7f1e915f6df088be3788f8d3266d63\", \"media_unittests\": \"6af89e6412d2c43fec0882e11bbfe6a21c534086\", \"message_center_unittests\": \"1a416ee7716e6f515f5c76fea1e49e25d15c8ca2\", \"metrics_python_tests\": \"510520f7f4a1b5934a09130f746fa9a1a9a5d3f4\", \"midi_unittests\": \"56e2056b1fc17f549d3834e1c51bac71a44e976a\", \"mojo_core_unittests\": \"89cc69cbb5f232c97400eda3139122d2009b83c3\", \"mojo_unittests\": \"8bd7b454f50d168fa0d02dd3ce3c0682fb4a4c68\", \"nacl_helper_nonsfi_unittests\": \"f95630c79260767bf2b5a756b4e06eaa4c77343c\", \"nacl_loader_unittests\": \"735a57ca3a7560bbd80a19df975777010f33db77\", \"native_theme_unittests\": \"c2a949ca4809e85ac9ce911bae6e81492aa053c8\", \"net_unittests\": \"3e42583a7310937f5ee0e03908df06c6d18589a4\", \"pdf_unittests\": \"707045efbb8054ba9e0be586dc14a22d4d6149fa\", \"perfetto_unittests\": \"f8a6bb279331716f7d051508b7bd9ceedf4b89a1\", \"ppapi_unittests\": \"7824fcddac6dd6ccd7b354650f37f262119ff055\", \"printing_unittests\": \"f988fa7736455034cb9e7dbba63eb242c70fa98a\", \"remoting_unittests\": \"f5fdaf68231ca0682aaaf320d560bfdf71dbdf41\", \"sandbox_linux_unittests\": \"340ed273643cfeb5db6b5bd9b398d37351dbe820\", \"service_manager_unittests\": \"c97b3b2a32da52ffdcb35e935893bf53dba43838\", \"services_unittests\": \"c4f9444f12ea78ca5443e28011586cc3ea4d98f3\", \"shell_dialogs_unittests\": \"63732a9338d714f265d03fb453205224e28769fc\", \"skia_unittests\": \"94f7e4f8b4df08a9201cc6cfd855db11ea219e58\", \"snapshot_unittests\": \"cf45e52b0c48418f3699d64be314bbdbf1e52537\", \"sql_unittests\": \"49c75338c5f439ec8b7dd04f5030203d61ff4eaa\", \"storage_unittests\": \"a359cb38f2b133801f2abcff405c538d5685d143\", \"sync_integration_tests\": \"3f5c2108c8d3c79b5f7e8d8f347dbae05fa761b4\", \"telemetry_gpu_unittests\": \"ed94d7a35c0d964d13dc08803c72ab91da01a661\", \"telemetry_perf_unittests\": \"f7dc3f7053681a0738b78116e107e715f4cd09e0\", \"telemetry_unittests\": \"4f3ed5a095840b423842c5d92c595cab3205fe4e\", \"traffic_annotation_auditor_unittests\": \"928afede1119f8aa060c6d7f434f7716cce4ae77\", \"ui_base_unittests\": \"6ae89d30035156e12482e6a46b8293d4e969bc9f\", \"ui_touch_selection_unittests\": \"82e298efdbb7447c227275e79eadd9ca02546958\", \"unit_tests\": \"8ed928c737ca4dd347fef15fe3d617ac29a84ae3\", \"url_unittests\": \"2f89608643bb7c16693f70f083a40b9cc9b2fc03\", \"views_perftests\": \"9988ea2415d3e1729cae3b3bd514017185b29689\", \"views_unittests\": \"c742deb84066a5fec3dae05db2d16ceee9e9d8a0\", \"viz_unittests\": \"9b95433a9e5492a9e5b2b83ba2ecabeb386d8369\", \"vr_common_unittests\": \"986a9cca4fb3ecf5f4680e7987f03c77519ba7f1\", \"vr_pixeltests\": \"395ca7e179ffbabf40c83b74c2bfdfbd4ae5852b\", \"webdriver_wpt_tests\": \"74a67b09c0ff032f762b73a723ca556654fa27c0\", \"weblayer_browsertests\": \"3e0e37f76c24e5de90c710370129c80b373ea677\", \"wm_unittests\": \"b6c82a9c5b2493e1eb6358faee02c3cb8753942c\", \"wtf_unittests\": \"107ac6e4b1187ec9df53f5a5a39424ba0992efed\", \"xr_browser_tests\": \"c504906cd6b7c5a0926ecf0b2823b642dbb05d7f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_859368e171259d521efea13da6023dc28f5117d74d565299fe323cf1e9b3ffc9_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-135-oj6g\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"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\": [\"4702-f6d98f9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1575744541659844",
"status": "COMPLETED",
"status_changed_ts": "1575747170853316",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165782",
"builder:Linux Builder (dbg)",
"buildset:commit/git/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89ac4e448b09333ffa4b12b3c604d310702d85d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060136175175884464",
"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/8894706112629864368/+/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:48fa7eae7363d310",
"user_agent:luci-scheduler"
],
"updated_ts": "1575747170934854",
"url": "https://ci.chromium.org/b/8894706112629864368",
"utcnow_ts": "1575747171661698"
}
}
[19:32:51.670] Invocation finished in 48m42.871722031s with status SUCCEEDED