[16:37:00.978] New invocation is queued and will start shortly
[16:37:02.122] Starting the invocation (attempt 1)
[16:37:02.170] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:37:02.170] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:37:02.170] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056701488825186496",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"49f2d51539fa97f4a4ab60f2382d5d9c17903391\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056701488825186496",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"gitiles_ref:refs/heads/master"
]
}
[16:37:03.401] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579019822698650",
"id": "8891271426352533760",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49f2d51539fa97f4a4ab60f2382d5d9c17903391\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579019823119777",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167369",
"builder:Linux Builder (dbg)",
"buildset:commit/git/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056701488825186496",
"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/8891271426352533760/+/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": "1579019823119884",
"url": "https://ci.chromium.org/b/8891271426352533760",
"utcnow_ts": "1579019823285527"
}
}
[16:37:03.401] Task URL: https://ci.chromium.org/b/8891271426352533760
[16:37:03.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:2:0) after 1m0s
[16:38:03.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:2:0)
[16:38:03.434] Timer tick, asking Buildbucket for the build status
[16:38:03.689] Build 8891271426352533760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:03.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:3:0) after 1m0s
[16:39:04.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:3:0)
[16:39:04.016] Timer tick, asking Buildbucket for the build status
[16:39:04.423] Build 8891271426352533760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:04.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:4:0) after 1m0s
[16:40:04.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:4:0)
[16:40:04.716] Timer tick, asking Buildbucket for the build status
[16:40:05.850] Build 8891271426352533760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:05.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:5:0) after 1m0s
[16:41:05.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:5:0)
[16:41:05.863] Timer tick, asking Buildbucket for the build status
[16:41:06.112] Build 8891271426352533760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:06.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:6:0) after 1m0s
[16:42:03.332] Received PubSub notification, asking Buildbucket for the build status
[16:42:03.685] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:06.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:6:0)
[16:42:06.167] Timer tick, asking Buildbucket for the build status
[16:42:06.678] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:06.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:8:0) after 1m0s
[16:43:06.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:8:0)
[16:43:06.715] Timer tick, asking Buildbucket for the build status
[16:43:06.876] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:06.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:9:0) after 1m0s
[16:44:06.919] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:9:0)
[16:44:06.919] Timer tick, asking Buildbucket for the build status
[16:44:07.702] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:07.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:10:0) after 1m0s
[16:45:07.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:10:0)
[16:45:07.733] Timer tick, asking Buildbucket for the build status
[16:45:08.103] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:08.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:11:0) after 1m0s
[16:46:08.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:11:0)
[16:46:08.187] Timer tick, asking Buildbucket for the build status
[16:46:08.647] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:08.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:12:0) after 1m0s
[16:47:08.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:12:0)
[16:47:08.760] Timer tick, asking Buildbucket for the build status
[16:47:09.241] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:09.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:13:0) after 1m0s
[16:48:09.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:13:0)
[16:48:09.339] Timer tick, asking Buildbucket for the build status
[16:48:09.691] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:09.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:14:0) after 1m0s
[16:49:10.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:14:0)
[16:49:10.122] Timer tick, asking Buildbucket for the build status
[16:49:10.740] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:10.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:15:0) after 1m0s
[16:50:10.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:15:0)
[16:50:10.863] Timer tick, asking Buildbucket for the build status
[16:50:11.058] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:11.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:16:0) after 1m0s
[16:51:11.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:16:0)
[16:51:11.148] Timer tick, asking Buildbucket for the build status
[16:51:11.528] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:11.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:17:0) after 1m0s
[16:52:11.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:17:0)
[16:52:11.649] Timer tick, asking Buildbucket for the build status
[16:52:11.867] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:11.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:18:0) after 1m0s
[16:53:12.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:18:0)
[16:53:12.431] Timer tick, asking Buildbucket for the build status
[16:53:13.073] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:13.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:19:0) after 1m0s
[16:54:13.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:19:0)
[16:54:13.653] Timer tick, asking Buildbucket for the build status
[16:54:14.103] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:14.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:20:0) after 1m0s
[16:55:14.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:20:0)
[16:55:14.805] Timer tick, asking Buildbucket for the build status
[16:55:14.921] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:21:0) after 1m0s
[16:56:15.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:21:0)
[16:56:15.186] Timer tick, asking Buildbucket for the build status
[16:56:16.330] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:16.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:22:0) after 1m0s
[16:57:16.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:22:0)
[16:57:16.358] Timer tick, asking Buildbucket for the build status
[16:57:16.515] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:16.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:23:0) after 1m0s
[16:58:16.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:23:0)
[16:58:16.597] Timer tick, asking Buildbucket for the build status
[16:58:16.921] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:24:0) after 1m0s
[16:59:16.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:24:0)
[16:59:16.999] Timer tick, asking Buildbucket for the build status
[16:59:17.711] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:17.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:25:0) after 1m0s
[17:00:17.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:25:0)
[17:00:17.748] Timer tick, asking Buildbucket for the build status
[17:00:18.507] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:18.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:26:0) after 1m0s
[17:01:18.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:26:0)
[17:01:18.574] Timer tick, asking Buildbucket for the build status
[17:01:19.698] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:19.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:27:0) after 1m0s
[17:02:19.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:27:0)
[17:02:19.736] Timer tick, asking Buildbucket for the build status
[17:02:20.146] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:20.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:28:0) after 1m0s
[17:03:20.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:28:0)
[17:03:20.285] Timer tick, asking Buildbucket for the build status
[17:03:20.742] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:20.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:29:0) after 1m0s
[17:04:21.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:29:0)
[17:04:21.058] Timer tick, asking Buildbucket for the build status
[17:04:22.414] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:22.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:30:0) after 1m0s
[17:05:22.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:30:0)
[17:05:22.681] Timer tick, asking Buildbucket for the build status
[17:05:22.877] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:22.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:31:0) after 1m0s
[17:06:22.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:31:0)
[17:06:22.934] Timer tick, asking Buildbucket for the build status
[17:06:23.218] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:23.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:32:0) after 1m0s
[17:07:23.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:32:0)
[17:07:23.327] Timer tick, asking Buildbucket for the build status
[17:07:23.571] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:23.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:33:0) after 1m0s
[17:08:23.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:33:0)
[17:08:23.690] Timer tick, asking Buildbucket for the build status
[17:08:25.225] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:25.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:34:0) after 1m0s
[17:09:25.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:34:0)
[17:09:25.346] Timer tick, asking Buildbucket for the build status
[17:09:25.615] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:25.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:35:0) after 1m0s
[17:10:25.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:35:0)
[17:10:25.842] Timer tick, asking Buildbucket for the build status
[17:10:27.866] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:27.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:36:0) after 1m0s
[17:11:28.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:36:0)
[17:11:28.198] Timer tick, asking Buildbucket for the build status
[17:11:28.441] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:28.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:37:0) after 1m0s
[17:12:28.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:37:0)
[17:12:28.480] Timer tick, asking Buildbucket for the build status
[17:12:28.713] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:28.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:38:0) after 1m0s
[17:13:28.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:38:0)
[17:13:28.763] Timer tick, asking Buildbucket for the build status
[17:13:28.931] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:28.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:39:0) after 1m0s
[17:14:29.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:39:0)
[17:14:29.004] Timer tick, asking Buildbucket for the build status
[17:14:29.283] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:29.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:40:0) after 1m0s
[17:15:29.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:40:0)
[17:15:29.404] Timer tick, asking Buildbucket for the build status
[17:15:29.871] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:29.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:41:0) after 1m0s
[17:16:29.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:41:0)
[17:16:29.935] Timer tick, asking Buildbucket for the build status
[17:16:30.654] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:30.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:42:0) after 1m0s
[17:17:30.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:42:0)
[17:17:30.716] Timer tick, asking Buildbucket for the build status
[17:17:30.904] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:30.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:43:0) after 1m0s
[17:18:30.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:43:0)
[17:18:30.971] Timer tick, asking Buildbucket for the build status
[17:18:33.710] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:44:0) after 1m0s
[17:19:33.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:44:0)
[17:19:33.936] Timer tick, asking Buildbucket for the build status
[17:19:34.269] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:34.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:45:0) after 1m0s
[17:20:34.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:45:0)
[17:20:34.501] Timer tick, asking Buildbucket for the build status
[17:20:34.727] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:34.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:46:0) after 1m0s
[17:21:35.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:46:0)
[17:21:35.638] Timer tick, asking Buildbucket for the build status
[17:21:37.126] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:37.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:47:0) after 1m0s
[17:22:37.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:47:0)
[17:22:37.259] Timer tick, asking Buildbucket for the build status
[17:22:38.653] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:38.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:48:0) after 1m0s
[17:23:38.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:48:0)
[17:23:38.737] Timer tick, asking Buildbucket for the build status
[17:23:39.521] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:49:0) after 1m0s
[17:24:39.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:49:0)
[17:24:39.614] Timer tick, asking Buildbucket for the build status
[17:24:40.139] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:40.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:50:0) after 1m0s
[17:25:40.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:50:0)
[17:25:40.259] Timer tick, asking Buildbucket for the build status
[17:25:40.447] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:40.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:51:0) after 1m0s
[17:26:40.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:51:0)
[17:26:40.523] Timer tick, asking Buildbucket for the build status
[17:26:41.076] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:41.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:52:0) after 1m0s
[17:27:41.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:52:0)
[17:27:41.378] Timer tick, asking Buildbucket for the build status
[17:27:41.930] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:41.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:53:0) after 1m0s
[17:28:42.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:53:0)
[17:28:42.033] Timer tick, asking Buildbucket for the build status
[17:28:42.271] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:42.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:54:0) after 1m0s
[17:29:42.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:54:0)
[17:29:42.302] Timer tick, asking Buildbucket for the build status
[17:29:42.712] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:42.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:55:0) after 1m0s
[17:30:42.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:55:0)
[17:30:42.797] Timer tick, asking Buildbucket for the build status
[17:30:43.065] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:43.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:56:0) after 1m0s
[17:31:43.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:56:0)
[17:31:43.127] Timer tick, asking Buildbucket for the build status
[17:31:43.590] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:43.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:57:0) after 1m0s
[17:32:43.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:57:0)
[17:32:43.649] Timer tick, asking Buildbucket for the build status
[17:32:45.187] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:45.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:58:0) after 1m0s
[17:33:45.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:58:0)
[17:33:45.213] Timer tick, asking Buildbucket for the build status
[17:33:45.406] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:45.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:59:0) after 1m0s
[17:34:45.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:59:0)
[17:34:45.499] Timer tick, asking Buildbucket for the build status
[17:34:45.705] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:45.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:60:0) after 1m0s
[17:35:45.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:60:0)
[17:35:45.780] Timer tick, asking Buildbucket for the build status
[17:35:46.026] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:46.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:61:0) after 1m0s
[17:36:46.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:61:0)
[17:36:46.102] Timer tick, asking Buildbucket for the build status
[17:36:46.633] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:46.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:62:0) after 1m0s
[17:37:46.740] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:62:0)
[17:37:46.740] Timer tick, asking Buildbucket for the build status
[17:37:46.981] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:46.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:63:0) after 1m0s
[17:38:47.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:63:0)
[17:38:47.140] Timer tick, asking Buildbucket for the build status
[17:38:47.425] Build 8891271426352533760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:47.425] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056701488825186496:64:0) after 1m0s
[17:39:03.494] Received PubSub notification, asking Buildbucket for the build status
[17:39:04.217] Build 8891271426352533760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:39:04.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579023539757298",
"created_by": "project:chromium",
"created_ts": "1579019822698650",
"id": "8891271426352533760",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49f2d51539fa97f4a4ab60f2382d5d9c17903391\"}}",
"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-east1-d-8-105-dbgc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167369, \"got_angle_revision\": \"54d0d539593317eee954eaec1b4aca003032a360\", \"got_dawn_revision\": \"e5cb8f2eff7e2b0f672c0d20fb97ca17fe28e94a\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"49f2d51539fa97f4a4ab60f2382d5d9c17903391\", \"got_revision_cp\": \"refs/heads/master@{#731444}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"488d35ac0b1c66c79a6a8511111ad5b6296a2ffb\", \"got_v8_revision\": \"8f7dc4f43c237cb2ce02dbefb0b3bd31559c7edd\", \"got_v8_revision_cp\": \"refs/heads/8.1.178@{#1}\", \"got_webrtc_revision\": \"b42aeaa3fb21d78e59c47d2a9916acb380494496\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30212}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"49f2d51539fa97f4a4ab60f2382d5d9c17903391\", \"swarm_hashes_refs/heads/master(at){#731444}_without_patch\": {\"accessibility_unittests\": \"3f2cc9a527370bd151fed18fd2a9140130ac7272\", \"angle_unittests\": \"9aea862a895f7dc4abd3b86aacef610eb37737b4\", \"app_shell_unittests\": \"4dfd9f3ae306355e73bfe9c2ee0f30fbee9a9211\", \"aura_unittests\": \"a9c5d5f318b638dcf1ac746ce6af9b90d6329efe\", \"base_unittests\": \"57f49de22e0b1dc0283ba70bfe4fb03d25d72792\", \"base_util_unittests\": \"d65ce6c4c4c7aef9470570a87a83a877a5d5966f\", \"blink_common_unittests\": \"d3ce367e675fd88729928bbf7ee4f1b67283e51d\", \"blink_fuzzer_unittests\": \"8287972aada424971836b92ad6954d56b1945a87\", \"blink_heap_unittests\": \"5a2e1da15833815f536bfb53d37421f1983e6074\", \"blink_platform_unittests\": \"139351d9c63bfa6579755f4b592aec50014cd853\", \"blink_python_tests\": \"e049a5249faf91a274f3bd41436cde91cd57fadb\", \"blink_unittests\": \"3ad5413a21f131afebb1b1082c0ffd96cbe16bb8\", \"blink_web_tests\": \"50a7bfa722868bb7ccc4d6c2b4a57f0a890a36dd\", \"boringssl_crypto_tests\": \"ed3a125c8d1a29599b36f9584d6204f91be2d691\", \"boringssl_ssl_tests\": \"beec5142bd105cd429a871012f5379f6f82b9823\", \"browser_tests\": \"39b017d3d3e20032a56b48fb7b3a52df7f37e28d\", \"cacheinvalidation_unittests\": \"5139f7b244d817449561051629885be80e0a0fef\", \"capture_unittests\": \"6b9781cbc6bafb8357569b65c93c4c352bfd62fc\", \"cast_unittests\": \"1cefb8e01b13fb3572b003840545e7344e3d5c7a\", \"cc_unittests\": \"4a8e6456c6e75ce4f5fd8f334c868e56c8fbf93a\", \"chrome_app_unittests\": \"f9d91015a17763f09bb5b1d1fe7307b12085a077\", \"chromedriver_unittests\": \"a5f18d25a476c2de28b58c1a4bc9f1189f2c6ca7\", \"components_browsertests\": \"bc001c89c73d7cc1a10329a8c3b50f2d464f33da\", \"components_unittests\": \"1f63ebde6d53b36492732029023c7216d9ebbe44\", \"compositor_unittests\": \"f54fb0b8ea61fa053d96780fedf53193b5960276\", \"content_browsertests\": \"4187c6238ba293b7764e4ac33d78456d5a002064\", \"content_shell_crash_test\": \"495c119eed08b7d14c448b2888ca785fa73196aa\", \"content_unittests\": \"ec13fbbafa8d804521bc7730a9f1a28529365682\", \"crashpad_tests\": \"94dafbf0178fdbe514868ed3d62609de926013ee\", \"cronet_tests\": \"fcc525d5e893849e93a66262bdbea1c77054bd20\", \"cronet_unittests\": \"fde38b935c168d54da7c08e1d78a87c7cb2ff370\", \"crypto_unittests\": \"9eb80994fc7d3ea96d5e0529ecda32a1006b10e5\", \"dbus_unittests\": \"eac6a2669e14c4d7d414220d29017b72e5423d46\", \"device_unittests\": \"509bcf3bdbec99e384ad2a895305bfdb164c897a\", \"devtools_lint_check\": \"5dbecfeac3ce9652046f5a7516093b99bbaa4b2a\", \"devtools_type_check\": \"7148e7cebdc480b4d34be6b1d59c60199be58d95\", \"display_unittests\": \"bfaa722620e82239a8d2b0aeca4684635bffbaf7\", \"events_unittests\": \"b0602a5140aca594a28b2db4ae80d813f6022313\", \"extensions_browsertests\": \"f2cc451a4eef1e46e044c4fd7e18b5130ad85729\", \"extensions_unittests\": \"96896258991cf25ce1e246d755aefc4607093112\", \"filesystem_service_unittests\": \"cc01100ec44820a02d2a253241d66593bca1c589\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"f289ea31bdee6887ee12c642fe8208b72b3db9ba\", \"gfx_unittests\": \"f80a09618c1ad595b64724cccbe29923eb3c3a87\", \"gin_unittests\": \"b19459500b3f4c2f3b823697e0ac1cd581767a4f\", \"google_apis_unittests\": \"ee57d45b27fe61536ba57b6c34c575af8a8ef08f\", \"gpu_unittests\": \"066863e75d2a42fa288e09338c4f72fb1b35647f\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"67044d5d3504ec65adc12ef4e5533b329d500475\", \"headless_browsertests\": \"c30e61e960e1d25969dd6cfa08df80edb40eb5be\", \"headless_unittests\": \"1ab52999bcaf558df51d48fccbfa5b7354318f87\", \"interactive_ui_tests\": \"7323b87fb5ed43363d88322dc13ec6a05536c13c\", \"ipc_tests\": \"5dfaf1d6cc9023e9e4a5c895664a9692759ff08d\", \"jingle_unittests\": \"8fe499b7a23a04ee85c24f9f537e8ec08bfa1ebe\", \"latency_unittests\": \"5007584ed05247b1937434c0092fd5f63fe18f9c\", \"libjingle_xmpp_unittests\": \"2e536f0a7560071798021be51c266975b8688929\", \"media_blink_unittests\": \"5e9f66eedf16a04d5fa0bf08ed15e7ff5f2721da\", \"media_service_unittests\": \"d8185ecd83af7003e143cbe3add2b8ce953d30d9\", \"media_unittests\": \"0f662b2e076547c61c9ce1b853faa63c52e2d533\", \"message_center_unittests\": \"2332f9bfefe83f2bb78a1fc8a024ac8b6e486d38\", \"metrics_python_tests\": \"7cbac2df1d04a4f520539e474fc07804caf3a3d5\", \"midi_unittests\": \"83b401968dfe5d2933b10dc67cd0e0022610520c\", \"mojo_core_unittests\": \"f09d92ef3b0f8d9a96e409f10ab05fabab984bce\", \"mojo_unittests\": \"d6f413855bd0c1ead647b3f1a368b523c0620c30\", \"nacl_helper_nonsfi_unittests\": \"4b810458dce67cd7bce0ec3f9ae6fe4c408f2e24\", \"nacl_loader_unittests\": \"04a8448c52ede095629254a006b3ad2e5b2e3630\", \"native_theme_unittests\": \"fae946b6c44929f144e709cc34b502469b03b718\", \"net_unittests\": \"1b0eef1ba103985997d4182d4b6aaa8c0eb67f61\", \"pdf_unittests\": \"4fa292a408549c6dd412506805a0b45997441874\", \"perfetto_unittests\": \"f7b15bd3b6ce00bd32177c78a67a97fddc509450\", \"ppapi_unittests\": \"ac7219788918155be43d94311de2aba0e5d6bf10\", \"printing_unittests\": \"65ccddc0bc4edf50f846cc44938841af83cc4e30\", \"remoting_unittests\": \"6f051e3f0394ac6d6ee4589ac793c1b7d7c1b031\", \"sandbox_linux_unittests\": \"df8e90d4d585408b329a2ceeff3161f244907bcf\", \"service_manager_unittests\": \"614217065f63ca16ce15ab02d7ca56e69391656f\", \"services_unittests\": \"0b70c59b864d6c85a6679ea04a26ad28a4652df0\", \"shell_dialogs_unittests\": \"8eaf7b792a7c9b6f9273632b2461e0bbce1a1177\", \"skia_unittests\": \"ba309bc042f8c1d46bc89422f34022aad348c61a\", \"snapshot_unittests\": \"35732c274279db6a9bac2df23e8e4b93af84e075\", \"sql_unittests\": \"03d616c5a611e2420ac2f0d1eb6c02230a18a6cc\", \"storage_unittests\": \"8412039b875e4905163eedf42cc0da8e824e903c\", \"sync_integration_tests\": \"4103df819dd8f622b569b6621de5602923b898bb\", \"telemetry_gpu_unittests\": \"8d97576d53532c814e345d81706ba644f2ff1d90\", \"telemetry_perf_unittests\": \"fe34b4c6f331294b86532996e20d198b86d48251\", \"telemetry_unittests\": \"48c87258750d92ce3665535f983ab71bdda7c5b1\", \"traffic_annotation_auditor_unittests\": \"b0de793c138a115feb0e7a69a0f8dadde0788ae9\", \"ui_base_unittests\": \"076503f89300c75887c01db8a817a12938dde4ae\", \"ui_touch_selection_unittests\": \"19db0406cb62d3c5f9042ab283dba0e87d1e9741\", \"unit_tests\": \"3c03773f80b46a5a4492028746cfb9bb8b63f08f\", \"url_unittests\": \"28d0ab67f568d4cb55fa163becbe90b4dd54dda4\", \"views_perftests\": \"dd143a1252e99a90cffc78f88ca9036229bb8b7e\", \"views_unittests\": \"a9ae76230d044cc8e3dcd2afa0ed0b8ca26955d0\", \"viz_unittests\": \"0ce56edd078eede86c1686f7406da908e24c5f8e\", \"vr_common_unittests\": \"aea82855c0df36200db391c63773ed5d23bee29b\", \"vr_pixeltests\": \"a4a94ecbe512dc9bef6f671945542aac48e22a39\", \"webdriver_wpt_tests\": \"a1910369456f95001fe5c404ed72d2f58e505408\", \"weblayer_browsertests\": \"55e09143e453a99d7e521aeb908a3e4ff31acebe\", \"wm_unittests\": \"78dca9d3aac56852e57ecdbdc253afdd00570f45\", \"wtf_unittests\": \"68e5ac9b178884a824132f1c54709be39e24ab27\", \"xr_browser_tests\": \"73269881c63c0cb7e35bb046342a7c167002d17a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_016577e073ce9abf0fa10d80f88bc2caf8f81662b409bd6e59fa52089e44a3df_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_dd987fcd94b516d81fcf1552cd73a9eed370c3dc02cc1a52ddee828f1ab8817d_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_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-105-dbgc\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4760-d9a0e6d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579020122726708",
"status": "COMPLETED",
"status_changed_ts": "1579023540195638",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167369",
"builder:Linux Builder (dbg)",
"buildset:commit/git/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49f2d51539fa97f4a4ab60f2382d5d9c17903391",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056701488825186496",
"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/8891271426352533760/+/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:49bdbc7d39c4d610",
"user_agent:luci-scheduler"
],
"updated_ts": "1579023540237317",
"url": "https://ci.chromium.org/b/8891271426352533760",
"utcnow_ts": "1579023544186553"
}
}
[17:39:04.217] Invocation finished in 1h2m3.260102194s with status SUCCEEDED