[19:58:40.956] New invocation is queued and will start shortly
[19:58:42.599] Starting the invocation (attempt 1)
[19:58:42.695] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:58:42.695] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:58:42.695] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059587904000905536",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6b5cadab28e01f6604aeae13dbc140ce2297266c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059587904000905536",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"gitiles_ref:refs/heads/master"
]
}
[19:58:46.475] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576267124678538",
"id": "8894157839431471136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b5cadab28e01f6604aeae13dbc140ce2297266c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576267126097505",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166056",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059587904000905536",
"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/8894157839431471136/+/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": "1576267126097632",
"url": "https://ci.chromium.org/b/8894157839431471136",
"utcnow_ts": "1576267126445075"
}
}
[19:58:46.475] Task URL: https://ci.chromium.org/b/8894157839431471136
[19:58:46.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:2:0) after 1m0s
[19:59:46.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:2:0)
[19:59:46.466] Timer tick, asking Buildbucket for the build status
[19:59:46.689] Build 8894157839431471136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:46.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:3:0) after 1m0s
[20:00:46.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:3:0)
[20:00:46.724] Timer tick, asking Buildbucket for the build status
[20:00:46.970] Build 8894157839431471136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:46.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:4:0) after 1m0s
[20:01:47.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:4:0)
[20:01:47.207] Timer tick, asking Buildbucket for the build status
[20:01:47.417] Build 8894157839431471136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:47.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:5:0) after 1m0s
[20:02:47.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:5:0)
[20:02:47.693] Timer tick, asking Buildbucket for the build status
[20:02:47.840] Build 8894157839431471136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:47.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:6:0) after 1m0s
[20:03:05.677] Received PubSub notification, asking Buildbucket for the build status
[20:03:05.856] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:48.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:6:0)
[20:03:48.172] Timer tick, asking Buildbucket for the build status
[20:03:48.536] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:48.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:8:0) after 1m0s
[20:04:48.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:8:0)
[20:04:48.891] Timer tick, asking Buildbucket for the build status
[20:04:49.264] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:49.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:9:0) after 1m0s
[20:05:49.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:9:0)
[20:05:49.351] Timer tick, asking Buildbucket for the build status
[20:05:50.023] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:50.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:10:0) after 1m0s
[20:06:50.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:10:0)
[20:06:50.094] Timer tick, asking Buildbucket for the build status
[20:06:50.276] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:50.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:11:0) after 1m0s
[20:07:50.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:11:0)
[20:07:50.872] Timer tick, asking Buildbucket for the build status
[20:07:50.997] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:12:0) after 1m0s
[20:08:51.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:12:0)
[20:08:51.230] Timer tick, asking Buildbucket for the build status
[20:08:51.427] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:51.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:13:0) after 1m0s
[20:09:51.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:13:0)
[20:09:51.562] Timer tick, asking Buildbucket for the build status
[20:09:51.806] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:51.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:14:0) after 1m0s
[20:10:51.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:14:0)
[20:10:51.886] Timer tick, asking Buildbucket for the build status
[20:10:52.304] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:52.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:15:0) after 1m0s
[20:11:52.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:15:0)
[20:11:52.637] Timer tick, asking Buildbucket for the build status
[20:11:54.186] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:54.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:16:0) after 1m0s
[20:12:54.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:16:0)
[20:12:54.234] Timer tick, asking Buildbucket for the build status
[20:12:54.512] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:54.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:17:0) after 1m0s
[20:13:54.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:17:0)
[20:13:54.547] Timer tick, asking Buildbucket for the build status
[20:13:56.318] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:18:0) after 1m0s
[20:14:56.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:18:0)
[20:14:56.406] Timer tick, asking Buildbucket for the build status
[20:14:56.822] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:56.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:19:0) after 1m0s
[20:15:56.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:19:0)
[20:15:56.914] Timer tick, asking Buildbucket for the build status
[20:15:57.323] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:57.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:20:0) after 1m0s
[20:16:57.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:20:0)
[20:16:57.408] Timer tick, asking Buildbucket for the build status
[20:16:57.922] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:57.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:21:0) after 1m0s
[20:17:57.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:21:0)
[20:17:57.943] Timer tick, asking Buildbucket for the build status
[20:17:58.306] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:58.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:22:0) after 1m0s
[20:18:58.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:22:0)
[20:18:58.441] Timer tick, asking Buildbucket for the build status
[20:18:58.819] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:58.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:23:0) after 1m0s
[20:19:58.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:23:0)
[20:19:58.938] Timer tick, asking Buildbucket for the build status
[20:19:59.184] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:59.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:24:0) after 1m0s
[20:20:59.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:24:0)
[20:20:59.266] Timer tick, asking Buildbucket for the build status
[20:20:59.427] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:59.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:25:0) after 1m0s
[20:21:59.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:25:0)
[20:21:59.531] Timer tick, asking Buildbucket for the build status
[20:22:00.190] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:00.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:26:0) after 1m0s
[20:23:00.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:26:0)
[20:23:00.216] Timer tick, asking Buildbucket for the build status
[20:23:00.416] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:27:0) after 1m0s
[20:24:00.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:27:0)
[20:24:00.475] Timer tick, asking Buildbucket for the build status
[20:24:01.037] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:01.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:28:0) after 1m0s
[20:25:01.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:28:0)
[20:25:01.130] Timer tick, asking Buildbucket for the build status
[20:25:01.930] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:01.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:29:0) after 1m0s
[20:26:01.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:29:0)
[20:26:01.953] Timer tick, asking Buildbucket for the build status
[20:26:02.396] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:02.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:30:0) after 1m0s
[20:27:02.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:30:0)
[20:27:02.450] Timer tick, asking Buildbucket for the build status
[20:27:02.979] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:02.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:31:0) after 1m0s
[20:28:03.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:31:0)
[20:28:03.084] Timer tick, asking Buildbucket for the build status
[20:28:04.398] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:04.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:32:0) after 1m0s
[20:29:04.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:32:0)
[20:29:04.648] Timer tick, asking Buildbucket for the build status
[20:29:04.853] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:04.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:33:0) after 1m0s
[20:30:04.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:33:0)
[20:30:04.949] Timer tick, asking Buildbucket for the build status
[20:30:05.528] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:05.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:34:0) after 1m0s
[20:31:05.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:34:0)
[20:31:05.742] Timer tick, asking Buildbucket for the build status
[20:31:05.940] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:05.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:35:0) after 1m0s
[20:32:06.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:35:0)
[20:32:06.235] Timer tick, asking Buildbucket for the build status
[20:32:06.445] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:06.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:36:0) after 1m0s
[20:33:06.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:36:0)
[20:33:06.512] Timer tick, asking Buildbucket for the build status
[20:33:06.812] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:06.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:37:0) after 1m0s
[20:34:06.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:37:0)
[20:34:06.871] Timer tick, asking Buildbucket for the build status
[20:34:07.373] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:07.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:38:0) after 1m0s
[20:35:07.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:38:0)
[20:35:07.463] Timer tick, asking Buildbucket for the build status
[20:35:07.608] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:07.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:39:0) after 1m0s
[20:36:07.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:39:0)
[20:36:07.843] Timer tick, asking Buildbucket for the build status
[20:36:08.276] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:08.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:40:0) after 1m0s
[20:37:08.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:40:0)
[20:37:08.312] Timer tick, asking Buildbucket for the build status
[20:37:08.528] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:08.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:41:0) after 1m0s
[20:38:08.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:41:0)
[20:38:08.607] Timer tick, asking Buildbucket for the build status
[20:38:09.572] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:09.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:42:0) after 1m0s
[20:39:09.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:42:0)
[20:39:09.664] Timer tick, asking Buildbucket for the build status
[20:39:10.167] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:10.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:43:0) after 1m0s
[20:40:10.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:43:0)
[20:40:10.189] Timer tick, asking Buildbucket for the build status
[20:40:10.423] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:10.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:44:0) after 1m0s
[20:41:10.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:44:0)
[20:41:10.471] Timer tick, asking Buildbucket for the build status
[20:41:10.765] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:10.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:45:0) after 1m0s
[20:42:10.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:45:0)
[20:42:10.852] Timer tick, asking Buildbucket for the build status
[20:42:11.304] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:11.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:46:0) after 1m0s
[20:43:11.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:46:0)
[20:43:11.357] Timer tick, asking Buildbucket for the build status
[20:43:11.561] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:11.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:47:0) after 1m0s
[20:44:11.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:47:0)
[20:44:11.667] Timer tick, asking Buildbucket for the build status
[20:44:12.007] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:12.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:48:0) after 1m0s
[20:45:12.040] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:48:0)
[20:45:12.040] Timer tick, asking Buildbucket for the build status
[20:45:12.238] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:12.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:49:0) after 1m0s
[20:46:12.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:49:0)
[20:46:12.275] Timer tick, asking Buildbucket for the build status
[20:46:12.456] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:12.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:50:0) after 1m0s
[20:47:12.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:50:0)
[20:47:12.549] Timer tick, asking Buildbucket for the build status
[20:47:12.913] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:12.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:51:0) after 1m0s
[20:48:13.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:51:0)
[20:48:13.018] Timer tick, asking Buildbucket for the build status
[20:48:13.247] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:13.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:52:0) after 1m0s
[20:49:13.334] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:52:0)
[20:49:13.334] Timer tick, asking Buildbucket for the build status
[20:49:13.710] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:13.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:53:0) after 1m0s
[20:50:13.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:53:0)
[20:50:13.790] Timer tick, asking Buildbucket for the build status
[20:50:13.925] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:13.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:54:0) after 1m0s
[20:51:13.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:54:0)
[20:51:13.981] Timer tick, asking Buildbucket for the build status
[20:51:14.255] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:14.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:55:0) after 1m0s
[20:52:14.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:55:0)
[20:52:14.421] Timer tick, asking Buildbucket for the build status
[20:52:15.102] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:15.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:56:0) after 1m0s
[20:53:15.133] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:56:0)
[20:53:15.133] Timer tick, asking Buildbucket for the build status
[20:53:15.775] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:15.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:57:0) after 1m0s
[20:54:15.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:57:0)
[20:54:15.890] Timer tick, asking Buildbucket for the build status
[20:54:16.154] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:16.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:58:0) after 1m0s
[20:55:16.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:58:0)
[20:55:16.259] Timer tick, asking Buildbucket for the build status
[20:55:16.698] Build 8894157839431471136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:16.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059587904000905536:59:0) after 1m0s
[20:56:15.344] Received PubSub notification, asking Buildbucket for the build status
[20:56:15.590] Build 8894157839431471136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:56:15.590] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576270574435991",
"created_by": "project:chromium",
"created_ts": "1576267124678538",
"id": "8894157839431471136",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b5cadab28e01f6604aeae13dbc140ce2297266c\"}}",
"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-50-yave\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166056, \"got_angle_revision\": \"e1822727560b6eb2e65ae9d41f6032923a7a6663\", \"got_dawn_revision\": \"77fd4750ae5ad3ee9f281089fddfeaca56ed049c\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"6b5cadab28e01f6604aeae13dbc140ce2297266c\", \"got_revision_cp\": \"refs/heads/master@{#724737}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"468ee1576da76a2d6b6e4861d9a874944ab7b959\", \"got_v8_revision\": \"785b7f0be4c8a228eb4072fdbb0105cbf05620de\", \"got_v8_revision_cp\": \"refs/heads/8.1.32@{#1}\", \"got_webrtc_revision\": \"41466b7bef5aa0927e3724f53c556bc4019f80d4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30087}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b5cadab28e01f6604aeae13dbc140ce2297266c\", \"swarm_hashes_refs/heads/master(at){#724737}_without_patch\": {\"accessibility_unittests\": \"c2a3ce23ec133e0a83f9823cb3dc7603a15d6c6a\", \"angle_unittests\": \"2996c4360fe1564daba92b2bc31c95eaaa5022d2\", \"app_shell_unittests\": \"8f77d2dc544cbf7b3096448acec1fed3599ea933\", \"aura_unittests\": \"d76be12f2dd8fe17056a5e856c70d63a45508a3e\", \"base_unittests\": \"62a37f9c90564e0f9a1c02cb60f1fd2fd779295a\", \"base_util_unittests\": \"5c5a4a37861a68c5368d60f5c39fb839480c4640\", \"blink_common_unittests\": \"eb9b2c83daadb292cb06316a80a6fc44fb17b5b0\", \"blink_fuzzer_unittests\": \"1458cbc613a0d99a52ce2b90197fb33c5383cab3\", \"blink_heap_unittests\": \"c8dde2c115d2593596fba54fe0a24f95c485b726\", \"blink_platform_unittests\": \"0bd0e6edf39547840b0e783ac935c49fbe586ec1\", \"blink_python_tests\": \"d82713f5b3a98a41ecd62cc3a2e1013ccf1bca85\", \"blink_unittests\": \"73a28f1749f0a335537aa0d9f46e7258b2628b46\", \"blink_web_tests\": \"77db596e5f252cf5dbe64980ebd1081c9d90a3f0\", \"boringssl_crypto_tests\": \"e67155d51c588bbd3aa75a0d7f3529a6f1eb6871\", \"boringssl_ssl_tests\": \"8a0b0f3bbcfdb04c0f6e77105dc5b4bb115f3135\", \"browser_tests\": \"97b20ef8fb27cfc8e4c5eed8dbb4e878222bd7be\", \"cacheinvalidation_unittests\": \"828a105ad04d719f56e47e1d2a60a476281ccb0b\", \"capture_unittests\": \"561ea5004a8d5ab2c4e7c98866cc278932c2653c\", \"cast_unittests\": \"8bd29fd93fce38490cbd04fdf72208971851ffab\", \"cc_unittests\": \"98830b288f1830b2f09e714695fc0837fdc7e430\", \"chrome_app_unittests\": \"5f18fce361271182dc783d0f117ee3b3b1f002c6\", \"chromedriver_unittests\": \"d036289f7cb198c5be6af43f30e1ce140b97cd49\", \"components_browsertests\": \"713142a48ba9a1297508eabae3742d1fa2404b14\", \"components_unittests\": \"9f7bbbe9dd77bb76d5f30f6c216add8768745148\", \"compositor_unittests\": \"6216b03d6dc71020f8bcbe332a2e436fc16ced13\", \"content_browsertests\": \"65d15380f7c4468d01c051d76341698fdc3c64d2\", \"content_shell_crash_test\": \"c4f37b155006a5d8777a9df1c6dc9a06488774ea\", \"content_unittests\": \"2ed8474c272aa7f89799e3a8e45a7cb79d661d54\", \"crashpad_tests\": \"23d7e1d1a6c0718f1920ef87c09d5f7e017727c3\", \"cronet_tests\": \"23618e043dddf76283f9edfe930b2a1ff4887b0f\", \"cronet_unittests\": \"5f7a18913d5c421dc9e4975dbf0084d422728def\", \"crypto_unittests\": \"0633404b02b62f61cfaa06967f4b62c11c1b3a7a\", \"dbus_unittests\": \"d1fd1a7855a6ef9aa3222ccf9786124dcdeb4a36\", \"device_unittests\": \"369e24a6c31146fa88e9b8afddaa9b6a6761d455\", \"devtools_lint_check\": \"1bf2f108f8410c21e2a04749bd05c0eb49e79b0e\", \"devtools_type_check\": \"d1c26d88e2a8ccfac08e83823baf80cce4317e5c\", \"display_unittests\": \"4b201c578a2ba107c417a6ea41e877e04b4c9445\", \"events_unittests\": \"6a3d3c8d8183235cf5d8752bf743c29125db0a13\", \"extensions_browsertests\": \"d33586979d277eaa4f5082a13fd2c7aafea6ff58\", \"extensions_unittests\": \"e24161fb448136bd8866f7ff72fdcf80ac7a8464\", \"filesystem_service_unittests\": \"55d740db9ffda515258e656a262a03c7f48e5869\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"470436b7884a744f589454d92a4b668aa032cc06\", \"gfx_unittests\": \"ff87084470a76ea3ec89c94ba68293d3c88dc3b7\", \"gin_unittests\": \"4d01cd875543a8956d52ba51836cc36a578cf50f\", \"google_apis_unittests\": \"802a3dce3014adb0dfdd7dc662cba1c0244692ce\", \"gpu_unittests\": \"0d00dea961a165928edcaaec5ae0e917dd4fde83\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"0ad3a6779628575ac9aa2d706758510878814fa3\", \"headless_browsertests\": \"17770918036197a7a8cadcca937c6ed436792125\", \"headless_unittests\": \"657f9511e4ff42ac6aa77de59f8d44cbac47618f\", \"interactive_ui_tests\": \"fd7472174de48c24edf750450864cf05c5a4359f\", \"ipc_tests\": \"1494995cba926ae22e98c764916ad431e3972237\", \"jingle_unittests\": \"8845f56c3a99c3ac45d506e742943aa273e3d68f\", \"latency_unittests\": \"c0306ac45a91406fba886ef7c964180dcc6d8933\", \"libjingle_xmpp_unittests\": \"c711d5b3bd8b7d83680c50e35f7d6461a4f83519\", \"media_blink_unittests\": \"91ff7e685ddeece690a27c8fc9a8658d1b1d60d6\", \"media_service_unittests\": \"1638058d35261c58925d203e840de21232473635\", \"media_unittests\": \"f9748c1c87f44ddc71646627bc51e13cd416d397\", \"message_center_unittests\": \"257f9a9e461724ab733f649ea21620d0f89726fc\", \"metrics_python_tests\": \"c6055f8df56a764dc8b7cfe832ce4832192efdb8\", \"midi_unittests\": \"9c562f8734d0576cd3c1dcf2f72134634329ab1e\", \"mojo_core_unittests\": \"e31c83ec714b35637aaff43d6c1e9f816a536486\", \"mojo_unittests\": \"13880141d87ca0179e6e6504f6684d7ae3f6477b\", \"nacl_helper_nonsfi_unittests\": \"a6a55acb2d8bb44db90ae483beb729483da87550\", \"nacl_loader_unittests\": \"94ec8f8ee4ef0de012644b392a2d435898f24759\", \"native_theme_unittests\": \"1b53b9cb858d3b82370bc753a389b87d792396a8\", \"net_unittests\": \"8da38ea902941cd4d005c3be1440ccd373164531\", \"pdf_unittests\": \"360737e03eac213e4c7c30395c3ea62a69515050\", \"perfetto_unittests\": \"85a1d632bce9352aab386e88c73ce8098d3897e9\", \"ppapi_unittests\": \"6ceba32a075223b23fcb33a13aa85073082ca48a\", \"printing_unittests\": \"20afddfb5718b69de628899fbced92f7c163be4a\", \"remoting_unittests\": \"85735ff354ce91d9ee3e139c09dccc9feb4cc40e\", \"sandbox_linux_unittests\": \"e256da7aa667c2534642fa52b91f00cfc7eb0400\", \"service_manager_unittests\": \"50f9353eac25e693ebca787f9b8bf29eae60d1e9\", \"services_unittests\": \"75c1ebd9b6be90fc716f605cc35191a824d07a8a\", \"shell_dialogs_unittests\": \"ea4a5a66b50b38e08dfe3a52c5547846ff881ff7\", \"skia_unittests\": \"4935c6296f6712422523911c0da9f127c6ab03c6\", \"snapshot_unittests\": \"0f5efe6204172936c4987f6cbd0aec1c601ded3a\", \"sql_unittests\": \"e8b55a5acda6b0786d9160ed00ad8c8df669b92f\", \"storage_unittests\": \"4a7345378ec85174658424137fd74464956d53fd\", \"sync_integration_tests\": \"67c04f5aba00a385f32e2f22de08818f4e6dbfa1\", \"telemetry_gpu_unittests\": \"4005810fc60db156d26912b4f294e99baf5d871e\", \"telemetry_perf_unittests\": \"71b224355fa02f1414631066bdc5638ec7f8f88d\", \"telemetry_unittests\": \"54251113d347fd9878d60582230af2c18463c49f\", \"traffic_annotation_auditor_unittests\": \"aebc834f6e6965a8e78429b042bcc658d10d6b61\", \"ui_base_unittests\": \"b6e0a95d12f2675b6c1f388215f57f05c9a4651a\", \"ui_touch_selection_unittests\": \"e698e0600be0a99c8bcd55925df88806acfda806\", \"unit_tests\": \"2b9df51a222ae0bd0c6de2dafd302b07ce8fb589\", \"url_unittests\": \"8ad11f448a82e8fd6fabbcfea550d58f241d1a92\", \"views_perftests\": \"d136acfcf8b0e7d8626923e665e02a6dec58c5fe\", \"views_unittests\": \"5515ce440d82249fca4cfe64fd534121854ad65b\", \"viz_unittests\": \"b45498df832e513716f3db4765d2927d2198d79e\", \"vr_common_unittests\": \"8b7f635dc51b08ec9f3c1c0bcdd02a6fffb578e0\", \"vr_pixeltests\": \"99dc4134d4e62a0801d28ad58d709d622dc591cc\", \"webdriver_wpt_tests\": \"0aa1180a2ac199e2b8440f79327a1f8d2cabbd22\", \"weblayer_browsertests\": \"0c28434a3c7f6d7ab2991828cdc05aad195f3771\", \"wm_unittests\": \"79350e65ab9d2531836162033b8710a9e68ce1f6\", \"wtf_unittests\": \"0c7364d5563a86fa3324f2bbf654152bd2996b8f\", \"xr_browser_tests\": \"641fe57f7a328843e973b9c71ef463703e18efe7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0095fe006eafdabf54002a791fb8c836a288874203ece8347c24b74b8d090ce3_v2\", \"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_c3721323df3e8b30ca31e9fd94d37af10db531e4b5840116d1e48fbce174f68f_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-50-yave\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4724-8894a57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576267384343657",
"status": "COMPLETED",
"status_changed_ts": "1576270574984118",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166056",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b5cadab28e01f6604aeae13dbc140ce2297266c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059587904000905536",
"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/8894157839431471136/+/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:4919a94d13487f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576270574989241",
"url": "https://ci.chromium.org/b/8894157839431471136",
"utcnow_ts": "1576270575577457"
}
}
[20:56:15.590] Invocation finished in 57m34.65323293s with status SUCCEEDED