[17:44:16.208] New invocation is queued and will start shortly
[17:44:17.505] Starting the invocation (attempt 1)
[17:44:17.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:44:17.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:44:17.553] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054704124313083008",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6259653d7a8eb12826a511f3a35b7a68499c0047\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054704124313083008",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/6259653d7a8eb12826a511f3a35b7a68499c0047",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6259653d7a8eb12826a511f3a35b7a68499c0047",
"gitiles_ref:refs/heads/master"
]
}
[17:44:18.532] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580924657857236",
"id": "8889274061920956832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6259653d7a8eb12826a511f3a35b7a68499c0047\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580924658293091",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168267",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6259653d7a8eb12826a511f3a35b7a68499c0047",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6259653d7a8eb12826a511f3a35b7a68499c0047",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054704124313083008",
"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/8889274061920956832/+/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": "1580924658293228",
"url": "https://ci.chromium.org/b/8889274061920956832",
"utcnow_ts": "1580924658525621"
}
}
[17:44:18.532] Task URL: https://ci.chromium.org/b/8889274061920956832
[17:44:18.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:2:0) after 1m0s
[17:45:18.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:2:0)
[17:45:18.649] Timer tick, asking Buildbucket for the build status
[17:45:18.838] Build 8889274061920956832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:18.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:3:0) after 1m0s
[17:46:18.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:3:0)
[17:46:18.965] Timer tick, asking Buildbucket for the build status
[17:46:19.112] Build 8889274061920956832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:19.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:4:0) after 1m0s
[17:47:19.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:4:0)
[17:47:19.189] Timer tick, asking Buildbucket for the build status
[17:47:19.366] Build 8889274061920956832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:19.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:5:0) after 1m0s
[17:48:19.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:5:0)
[17:48:19.434] Timer tick, asking Buildbucket for the build status
[17:48:19.640] Build 8889274061920956832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:19.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:6:0) after 1m0s
[17:49:04.355] Received PubSub notification, asking Buildbucket for the build status
[17:49:04.987] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:19.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:6:0)
[17:49:19.660] Timer tick, asking Buildbucket for the build status
[17:49:19.987] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:19.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:8:0) after 1m0s
[17:50:20.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:8:0)
[17:50:20.078] Timer tick, asking Buildbucket for the build status
[17:50:20.327] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:20.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:9:0) after 1m0s
[17:51:20.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:9:0)
[17:51:20.349] Timer tick, asking Buildbucket for the build status
[17:51:20.487] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:20.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:10:0) after 1m0s
[17:52:20.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:10:0)
[17:52:20.541] Timer tick, asking Buildbucket for the build status
[17:52:20.915] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:20.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:11:0) after 1m0s
[17:53:20.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:11:0)
[17:53:20.987] Timer tick, asking Buildbucket for the build status
[17:53:21.540] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:21.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:12:0) after 1m0s
[17:54:21.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:12:0)
[17:54:21.660] Timer tick, asking Buildbucket for the build status
[17:54:21.811] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:21.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:13:0) after 1m0s
[17:55:21.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:13:0)
[17:55:21.823] Timer tick, asking Buildbucket for the build status
[17:55:22.291] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:22.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:14:0) after 1m0s
[17:56:22.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:14:0)
[17:56:22.356] Timer tick, asking Buildbucket for the build status
[17:56:22.772] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:22.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:15:0) after 1m0s
[17:57:22.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:15:0)
[17:57:22.809] Timer tick, asking Buildbucket for the build status
[17:57:22.976] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:22.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:16:0) after 1m0s
[17:58:23.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:16:0)
[17:58:23.022] Timer tick, asking Buildbucket for the build status
[17:58:23.238] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:23.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:17:0) after 1m0s
[17:59:23.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:17:0)
[17:59:23.241] Timer tick, asking Buildbucket for the build status
[17:59:23.960] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:23.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:18:0) after 1m0s
[18:00:24.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:18:0)
[18:00:24.092] Timer tick, asking Buildbucket for the build status
[18:00:24.909] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:19:0) after 1m0s
[18:01:25.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:19:0)
[18:01:25.090] Timer tick, asking Buildbucket for the build status
[18:01:25.429] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:25.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:20:0) after 1m0s
[18:02:25.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:20:0)
[18:02:25.481] Timer tick, asking Buildbucket for the build status
[18:02:26.154] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:26.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:21:0) after 1m0s
[18:03:26.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:21:0)
[18:03:26.247] Timer tick, asking Buildbucket for the build status
[18:03:26.756] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:26.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:22:0) after 1m0s
[18:04:26.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:22:0)
[18:04:26.804] Timer tick, asking Buildbucket for the build status
[18:04:27.184] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:27.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:23:0) after 1m0s
[18:05:27.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:23:0)
[18:05:27.200] Timer tick, asking Buildbucket for the build status
[18:05:27.784] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:27.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:24:0) after 1m0s
[18:06:27.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:24:0)
[18:06:27.807] Timer tick, asking Buildbucket for the build status
[18:06:28.594] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:28.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:25:0) after 1m0s
[18:07:28.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:25:0)
[18:07:28.625] Timer tick, asking Buildbucket for the build status
[18:07:29.194] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:29.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:26:0) after 1m0s
[18:08:29.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:26:0)
[18:08:29.271] Timer tick, asking Buildbucket for the build status
[18:08:29.472] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:29.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:27:0) after 1m0s
[18:09:29.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:27:0)
[18:09:29.560] Timer tick, asking Buildbucket for the build status
[18:09:29.847] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:29.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:28:0) after 1m0s
[18:10:30.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:28:0)
[18:10:30.000] Timer tick, asking Buildbucket for the build status
[18:10:30.238] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:30.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:29:0) after 1m0s
[18:11:30.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:29:0)
[18:11:30.496] Timer tick, asking Buildbucket for the build status
[18:11:30.734] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:30.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:30:0) after 1m0s
[18:12:30.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:30:0)
[18:12:30.729] Timer tick, asking Buildbucket for the build status
[18:12:30.956] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:30.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:31:0) after 1m0s
[18:13:31.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:31:0)
[18:13:31.025] Timer tick, asking Buildbucket for the build status
[18:13:31.443] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:31.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:32:0) after 1m0s
[18:14:31.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:32:0)
[18:14:31.468] Timer tick, asking Buildbucket for the build status
[18:14:32.102] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:32.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:33:0) after 1m0s
[18:15:32.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:33:0)
[18:15:32.182] Timer tick, asking Buildbucket for the build status
[18:15:32.704] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:32.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:34:0) after 1m0s
[18:16:32.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:34:0)
[18:16:32.816] Timer tick, asking Buildbucket for the build status
[18:16:32.984] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:32.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:35:0) after 1m0s
[18:17:33.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:35:0)
[18:17:33.051] Timer tick, asking Buildbucket for the build status
[18:17:33.201] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:33.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:36:0) after 1m0s
[18:18:33.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:36:0)
[18:18:33.263] Timer tick, asking Buildbucket for the build status
[18:18:33.950] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:33.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:37:0) after 1m0s
[18:19:33.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:37:0)
[18:19:33.996] Timer tick, asking Buildbucket for the build status
[18:19:34.710] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:34.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:38:0) after 1m0s
[18:20:34.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:38:0)
[18:20:34.768] Timer tick, asking Buildbucket for the build status
[18:20:34.911] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:34.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:39:0) after 1m0s
[18:21:34.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:39:0)
[18:21:34.934] Timer tick, asking Buildbucket for the build status
[18:21:35.808] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:35.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:40:0) after 1m0s
[18:22:35.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:40:0)
[18:22:35.826] Timer tick, asking Buildbucket for the build status
[18:22:35.969] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:35.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:41:0) after 1m0s
[18:23:35.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:41:0)
[18:23:35.996] Timer tick, asking Buildbucket for the build status
[18:23:36.183] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:36.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:42:0) after 1m0s
[18:24:36.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:42:0)
[18:24:36.212] Timer tick, asking Buildbucket for the build status
[18:24:36.489] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:36.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:43:0) after 1m0s
[18:25:36.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:43:0)
[18:25:36.488] Timer tick, asking Buildbucket for the build status
[18:25:36.691] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:36.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:44:0) after 1m0s
[18:26:36.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:44:0)
[18:26:36.699] Timer tick, asking Buildbucket for the build status
[18:26:36.857] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:36.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:45:0) after 1m0s
[18:27:37.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:45:0)
[18:27:37.489] Timer tick, asking Buildbucket for the build status
[18:27:37.815] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:37.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:46:0) after 1m0s
[18:28:37.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:46:0)
[18:28:37.805] Timer tick, asking Buildbucket for the build status
[18:28:37.959] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:37.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:47:0) after 1m0s
[18:29:37.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:47:0)
[18:29:37.997] Timer tick, asking Buildbucket for the build status
[18:29:38.214] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:38.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:48:0) after 1m0s
[18:30:38.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:48:0)
[18:30:38.293] Timer tick, asking Buildbucket for the build status
[18:30:39.762] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:39.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:49:0) after 1m0s
[18:31:39.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:49:0)
[18:31:39.900] Timer tick, asking Buildbucket for the build status
[18:31:40.150] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:40.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:50:0) after 1m0s
[18:32:40.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:50:0)
[18:32:40.183] Timer tick, asking Buildbucket for the build status
[18:32:40.345] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:40.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:51:0) after 1m0s
[18:33:40.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:51:0)
[18:33:40.403] Timer tick, asking Buildbucket for the build status
[18:33:40.729] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:40.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:52:0) after 1m0s
[18:34:40.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:52:0)
[18:34:40.878] Timer tick, asking Buildbucket for the build status
[18:34:41.147] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:41.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:53:0) after 1m0s
[18:35:41.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:53:0)
[18:35:41.193] Timer tick, asking Buildbucket for the build status
[18:35:41.889] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:54:0) after 1m0s
[18:36:42.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:54:0)
[18:36:42.036] Timer tick, asking Buildbucket for the build status
[18:36:42.446] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:42.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:55:0) after 1m0s
[18:37:42.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:55:0)
[18:37:42.549] Timer tick, asking Buildbucket for the build status
[18:37:42.782] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:42.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:56:0) after 1m0s
[18:38:42.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:56:0)
[18:38:42.901] Timer tick, asking Buildbucket for the build status
[18:38:43.748] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:43.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:57:0) after 1m0s
[18:39:43.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:57:0)
[18:39:43.902] Timer tick, asking Buildbucket for the build status
[18:39:44.402] Build 8889274061920956832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:44.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054704124313083008:58:0) after 1m0s
[18:40:43.494] Received PubSub notification, asking Buildbucket for the build status
[18:40:43.620] Build 8889274061920956832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:40:43.620] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580928042425179",
"created_by": "project:chromium",
"created_ts": "1580924657857236",
"id": "8889274061920956832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6259653d7a8eb12826a511f3a35b7a68499c0047\"}}",
"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-91-vd6f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168267, \"got_angle_revision\": \"9c62f66c7235b9a4224f6d6cec0f8d7e0bf71f18\", \"got_dawn_revision\": \"47b15048a1fab6c360dc1594764506224655752b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"6259653d7a8eb12826a511f3a35b7a68499c0047\", \"got_revision_cp\": \"refs/heads/master@{#738589}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8e8e1d0dd7292440b803b95bc0832589520f784d\", \"got_v8_revision\": \"30fcb3ebe01452448273e5fe198141107973b5fd\", \"got_v8_revision_cp\": \"refs/heads/8.2.11@{#1}\", \"got_webrtc_revision\": \"712ebbb5b73baf30f11711efdceb6f08248fac38\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30457}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6259653d7a8eb12826a511f3a35b7a68499c0047\", \"swarm_hashes_refs/heads/master(at){#738589}_without_patch\": {\"accessibility_unittests\": \"7b16123e99aecf29fba204fb2651377ea894a6ad\", \"angle_unittests\": \"cee36e0ff7e00a9483e0b8849e2edf27095f82f1\", \"app_shell_unittests\": \"6f23c474bf2ce6c0094f09e6fcc9eeb739a04d92\", \"aura_unittests\": \"afd8b3fa1555d71c8464e299743e19be63db7103\", \"base_unittests\": \"cf01798909fc39cf8c44ba9a50b0215c2f8be422\", \"base_util_unittests\": \"8818a9773a30bd7c33cdec37a93518b2382f934c\", \"blink_common_unittests\": \"6cc089be58c6f97c32e67cc2c4ac9a8667a06a02\", \"blink_fuzzer_unittests\": \"ef23c62c8dc9f5aef64dfbe047c7288733b29dfc\", \"blink_heap_unittests\": \"7607e8e6e85da51dc16fd72e34727b5a274f624e\", \"blink_platform_unittests\": \"51bfe8724b55266261e365946948bd06b02f796b\", \"blink_python_tests\": \"fd78d0c1f64ce9e5ebe924017918cc3823a1a524\", \"blink_unittests\": \"aa26c3bdc046afff08866082e6afb6f58c055410\", \"blink_web_tests\": \"e39995b533ef60247e3921fa793242b8f70cea22\", \"boringssl_crypto_tests\": \"647acdbbf37dcb1c568c903868bcd7d855eb8d3d\", \"boringssl_ssl_tests\": \"2d20c1e4e0accd9c244ab60965347348f2ba0707\", \"browser_tests\": \"83a1fe8ff01feef1003e8a3dca2acd6362b885ab\", \"cacheinvalidation_unittests\": \"fb78258f28fc3710799ce5f9fc563eff7a407fe6\", \"capture_unittests\": \"59ffaa3780281259078959ebe66b81cf8955dd5e\", \"cast_unittests\": \"121653c5fef12d8b0cc7b9e9abe962b6b8196d29\", \"cc_unittests\": \"e924fadb8fb81622959b0be52faf3093d0f44ac1\", \"chrome_app_unittests\": \"e0a33a1a271e6756cda8c6d16c5da01e349655f8\", \"chromedriver_unittests\": \"d4c5669879291f5a678e58fdb05cf2f464a58f39\", \"components_browsertests\": \"b2641178d82cb3f97f8718aae1e0bfd24701e8f1\", \"components_unittests\": \"171abd8c2b63b43b3c3e19d55fe18f219db822db\", \"compositor_unittests\": \"875e2969adad0cbe5c257e2c9a4aa613b2c9b84d\", \"content_browsertests\": \"c65ea86a4715eba20f902f4b4d12890143edb641\", \"content_shell_crash_test\": \"8cb3fc9275966fdac7c4b7bf36c8de56736fd907\", \"content_unittests\": \"0e6f02872bbdd96b9806977c148af0e48033c236\", \"crashpad_tests\": \"0af122a680e0eff26626a05740cfdd0e49ba9701\", \"cronet_tests\": \"5f94a3e52be8aae205b8070bd9925a5251efbe6d\", \"cronet_unittests\": \"bfe8310480c79ff4866faf4bbfec1c65e457feab\", \"crypto_unittests\": \"71311b088be815360ef6cc6ea5930edb34e2864b\", \"dbus_unittests\": \"873e817e19dbdc5f49bf65aaf57c76570748dbc7\", \"device_unittests\": \"8b0a8834f8f2412df45c2d8023475b94a714e793\", \"display_unittests\": \"8b8bb2cd69cd6536a491948f7534c6183c2337e7\", \"events_unittests\": \"4b0bf4b732d0bfd7ebf0fcbd416b0b61959cdda3\", \"extensions_browsertests\": \"43b83e38c89db540053c42def0be1d3c62381960\", \"extensions_unittests\": \"a4f7772508965ba4ec6dce70c6cc743e0e9a3138\", \"filesystem_service_unittests\": \"fed2b0447f51cb38928b56d2669b7dc0727929c5\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"9856fe35b163ad0f2309de66470d53e74553752d\", \"gfx_unittests\": \"23195dc22fc4baaa72c89cdc0aa71cbc74a8864f\", \"gin_unittests\": \"fa69ee668a204048b037ee1b394dbec47e2ee492\", \"google_apis_unittests\": \"b0af3b56c1dbb63f229aba08d9f4e67de4ad5643\", \"gpu_unittests\": \"9b4daed8067ee815750eeddcdcf2bdb753224236\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"d2d57150bbed91c14f6cd4b17df0b9b078449849\", \"headless_browsertests\": \"ba9107c9d7f426fd53db0a604e421ad357003bdf\", \"headless_unittests\": \"e29594784c34700d86b69093a1fc19f634b95a43\", \"interactive_ui_tests\": \"8d299152328f4c23a243b084db76b9ff0e8f79cb\", \"ipc_tests\": \"d74c98ce51b5c43dd0d28c93c777df0c8a8f01f4\", \"jingle_unittests\": \"c4cf4dcc1a666434fa1f1659049c5d35ae4cbedc\", \"latency_unittests\": \"c12becd8654e79251e2ebc28f73afc5952f9b4f2\", \"libjingle_xmpp_unittests\": \"4b4c168b54edca11e17680601b3aa86aa8b8d60c\", \"media_blink_unittests\": \"2bd5ca8dae00a7ec94908d95639ebc33ea4605a4\", \"media_service_unittests\": \"2c76a70aa7eff06354ecfb6e5f3b44c56e366e68\", \"media_unittests\": \"dbeccba8d62e5d976a8bd904f1c741f99ddbad3d\", \"message_center_unittests\": \"72165f8d853713f1d52c29cd55f1787cb4249025\", \"metrics_python_tests\": \"bbd71bf6ace559c63119efba14af9e76913018bd\", \"midi_unittests\": \"a8f274e34aa18cca56f5608a2470f4273c986b23\", \"mojo_core_unittests\": \"cea2055bc973787f0b09972f913f38b422a730ee\", \"mojo_unittests\": \"76f02939df9ec9d1326b0ab465696318c4068f85\", \"nacl_helper_nonsfi_unittests\": \"45f7057fb6cc6d43c9d425196d4f8b8e3c47ec86\", \"nacl_loader_unittests\": \"73ae2480d98c34b46a352d249267c6bfaaa26784\", \"native_theme_unittests\": \"a7961d2a241ddce59b059eda7d45ce5f7a981bbd\", \"net_unittests\": \"0793added6553cea216001e2c6a779bf71a93950\", \"pdf_unittests\": \"a0944caf432e472dfa0183ed01f0390bdb7ad533\", \"perfetto_unittests\": \"17509b245ad4b497d3841d295e88b7901b2cec79\", \"ppapi_unittests\": \"e953fa1b915932dd0ae13266819194bc073f4c6e\", \"printing_unittests\": \"94cbd32dfcbab811a1e2558aa90391ef3adeb455\", \"remoting_unittests\": \"e3b3aecd9cbaef7b9e5692faf6ee41749a4a2fb7\", \"sandbox_linux_unittests\": \"f077ad62754d18572a2ee9ce2d3d872a65c6e5a6\", \"service_manager_unittests\": \"14c34193328d1c3db3305be09007b9a4a9f9b5cd\", \"services_unittests\": \"2479c9546ea0d4fc3423208f363196afed28c766\", \"shell_dialogs_unittests\": \"92092149a3e91052f975a8fa11a9b84eaecbbdfa\", \"skia_unittests\": \"e0e7da92f6098608f3f678eb19b3d2239b367953\", \"snapshot_unittests\": \"ebabd6fb2749bb9afc9f48788efffbe8310795ac\", \"sql_unittests\": \"a3b4ee81735b81745ef66d3f2cdb4792e51234d2\", \"storage_unittests\": \"f56f42a44edc67d75cbdf732ca4e986d3ff6bb4c\", \"sync_integration_tests\": \"a27aa2bc8fc2b88514dfb5caba1b480f6143af6d\", \"telemetry_gpu_unittests\": \"9ca589429260e550686884ea922fa39a62a6c411\", \"telemetry_perf_unittests\": \"54149f496c41fb6738a59486e6cc0792c4119e54\", \"telemetry_unittests\": \"0f6cc93f9d89a051ad8811813b8e696d1b436e80\", \"traffic_annotation_auditor_unittests\": \"a64efba7506677f55710f63ccb9d10a260be97de\", \"ui_base_unittests\": \"cb6715f0ea2cf60095563123b1fa13d7043652f9\", \"ui_touch_selection_unittests\": \"958f6ad79b8d6815eac798aeaa101a8f59e803e8\", \"unit_tests\": \"50617413840c83ce129b12206dd0ebf7c065ea43\", \"url_unittests\": \"bcb7b5f5a33edaad1503c78a2e14ba1fa100e85d\", \"views_perftests\": \"3550434a6289857c4502cb547927ca803dea1292\", \"views_unittests\": \"65f9c7cb95853155c11328233daef5068534f6b6\", \"viz_unittests\": \"70786fba6881fc72b34973273d75dfa9843ffeb2\", \"vr_common_unittests\": \"0b68e2ffc24b514b447abe9a62f9e326815e9204\", \"vr_pixeltests\": \"b9aa261532cb1e63e4dc48f102eefc96456ca0eb\", \"webdriver_wpt_tests\": \"bbd6ac5cec0b65b477d3b0b7c36ae446316ee10d\", \"weblayer_browsertests\": \"f989b2f351d417b3ab71d03c476bb798013da003\", \"wm_unittests\": \"a402ff7f39aabcce804c276b07f53fa483e54c2b\", \"wtf_unittests\": \"43966aecb6ac23de51aa7ef81baea7b6920562de\", \"xr_browser_tests\": \"524f0e448ad479d09cc10368d47ca469113345d2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0ba11647c3777752a9345c9a31488a30e4a4f8f072cd97454b4c4835355bfe20_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_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-91-vd6f\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580924943399685",
"status": "COMPLETED",
"status_changed_ts": "1580928042904638",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168267",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6259653d7a8eb12826a511f3a35b7a68499c0047",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6259653d7a8eb12826a511f3a35b7a68499c0047",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054704124313083008",
"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/8889274061920956832/+/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:4a2f456b58aa4f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580928043006988",
"url": "https://ci.chromium.org/b/8889274061920956832",
"utcnow_ts": "1580928043613337"
}
}
[18:40:43.620] Invocation finished in 56m27.428452284s with status SUCCEEDED