[16:24:02.788] New invocation is queued and will start shortly
[16:24:04.312] Starting the invocation (attempt 1)
[16:24:04.359] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:24:04.359] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:24:04.359] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054799768520840992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4c4ac5495adda4cb36706ad08f6792ebc793817a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054799768520840992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"gitiles_ref:refs/heads/master"
]
}
[16:24:06.791] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580833445395725",
"id": "8889369705119476528",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c4ac5495adda4cb36706ad08f6792ebc793817a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580833446474171",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168224",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054799768520840992",
"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/8889369705119476528/+/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": "1580833446474366",
"url": "https://ci.chromium.org/b/8889369705119476528",
"utcnow_ts": "1580833446776309"
}
}
[16:24:06.791] Task URL: https://ci.chromium.org/b/8889369705119476528
[16:24:06.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:2:0) after 1m0s
[16:25:06.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:2:0)
[16:25:06.811] Timer tick, asking Buildbucket for the build status
[16:25:07.569] Build 8889369705119476528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:07.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:3:0) after 1m0s
[16:26:07.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:3:0)
[16:26:07.624] Timer tick, asking Buildbucket for the build status
[16:26:07.793] Build 8889369705119476528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:07.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:4:0) after 1m0s
[16:27:07.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:4:0)
[16:27:07.785] Timer tick, asking Buildbucket for the build status
[16:27:11.080] Build 8889369705119476528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:11.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:5:0) after 1m0s
[16:28:11.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:5:0)
[16:28:11.136] Timer tick, asking Buildbucket for the build status
[16:28:11.606] Build 8889369705119476528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:11.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:6:0) after 1m0s
[16:29:10.043] Received PubSub notification, asking Buildbucket for the build status
[16:29:10.165] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:11.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:6:0)
[16:29:11.627] Timer tick, asking Buildbucket for the build status
[16:29:11.980] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:11.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:8:0) after 1m0s
[16:30:12.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:8:0)
[16:30:12.006] Timer tick, asking Buildbucket for the build status
[16:30:12.342] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:12.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:9:0) after 1m0s
[16:31:12.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:9:0)
[16:31:12.393] Timer tick, asking Buildbucket for the build status
[16:31:12.809] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:12.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:10:0) after 1m0s
[16:32:12.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:10:0)
[16:32:12.828] Timer tick, asking Buildbucket for the build status
[16:32:13.003] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:13.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:11:0) after 1m0s
[16:33:12.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:11:0)
[16:33:12.983] Timer tick, asking Buildbucket for the build status
[16:33:13.529] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:13.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:12:0) after 1m0s
[16:34:13.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:12:0)
[16:34:13.618] Timer tick, asking Buildbucket for the build status
[16:34:14.003] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:13:0) after 1m0s
[16:35:13.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:13:0)
[16:35:13.995] Timer tick, asking Buildbucket for the build status
[16:35:14.334] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:14.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:14:0) after 1m0s
[16:36:14.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:14:0)
[16:36:14.356] Timer tick, asking Buildbucket for the build status
[16:36:14.995] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:14.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:15:0) after 1m0s
[16:37:15.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:15:0)
[16:37:15.057] Timer tick, asking Buildbucket for the build status
[16:37:15.748] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:15.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:16:0) after 1m0s
[16:38:15.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:16:0)
[16:38:15.827] Timer tick, asking Buildbucket for the build status
[16:38:16.009] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:16.010] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:17:0) after 1m0s
[16:39:16.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:17:0)
[16:39:16.060] Timer tick, asking Buildbucket for the build status
[16:39:17.031] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:17.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:18:0) after 1m0s
[16:40:17.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:18:0)
[16:40:17.012] Timer tick, asking Buildbucket for the build status
[16:40:17.821] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:19:0) after 1m0s
[16:41:18.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:19:0)
[16:41:18.015] Timer tick, asking Buildbucket for the build status
[16:41:18.669] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:18.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:20:0) after 1m0s
[16:42:18.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:20:0)
[16:42:18.928] Timer tick, asking Buildbucket for the build status
[16:42:19.847] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:19.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:21:0) after 1m0s
[16:43:20.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:21:0)
[16:43:20.145] Timer tick, asking Buildbucket for the build status
[16:43:20.397] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:20.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:22:0) after 1m0s
[16:44:20.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:22:0)
[16:44:20.436] Timer tick, asking Buildbucket for the build status
[16:44:20.580] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:20.580] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:23:0) after 1m0s
[16:45:20.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:23:0)
[16:45:20.688] Timer tick, asking Buildbucket for the build status
[16:45:21.061] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:21.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:24:0) after 1m0s
[16:46:21.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:24:0)
[16:46:21.164] Timer tick, asking Buildbucket for the build status
[16:46:21.347] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:21.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:25:0) after 1m0s
[16:47:22.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:25:0)
[16:47:22.019] Timer tick, asking Buildbucket for the build status
[16:47:22.304] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:26:0) after 1m0s
[16:48:22.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:26:0)
[16:48:22.377] Timer tick, asking Buildbucket for the build status
[16:48:22.842] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:22.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:27:0) after 1m0s
[16:49:22.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:27:0)
[16:49:22.965] Timer tick, asking Buildbucket for the build status
[16:49:23.112] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:23.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:28:0) after 1m0s
[16:50:23.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:28:0)
[16:50:23.130] Timer tick, asking Buildbucket for the build status
[16:50:23.376] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:23.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:29:0) after 1m0s
[16:51:23.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:29:0)
[16:51:23.433] Timer tick, asking Buildbucket for the build status
[16:51:24.413] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:24.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:30:0) after 1m0s
[16:52:24.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:30:0)
[16:52:24.454] Timer tick, asking Buildbucket for the build status
[16:52:25.951] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:25.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:31:0) after 1m0s
[16:53:25.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:31:0)
[16:53:25.975] Timer tick, asking Buildbucket for the build status
[16:53:26.868] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:26.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:32:0) after 1m0s
[16:54:26.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:32:0)
[16:54:26.862] Timer tick, asking Buildbucket for the build status
[16:54:27.038] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:27.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:33:0) after 1m0s
[16:55:27.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:33:0)
[16:55:27.064] Timer tick, asking Buildbucket for the build status
[16:55:27.314] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:34:0) after 1m0s
[16:56:27.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:34:0)
[16:56:27.333] Timer tick, asking Buildbucket for the build status
[16:56:28.784] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:28.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:35:0) after 1m0s
[16:57:28.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:35:0)
[16:57:28.916] Timer tick, asking Buildbucket for the build status
[16:57:29.528] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:29.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:36:0) after 1m0s
[16:58:29.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:36:0)
[16:58:29.782] Timer tick, asking Buildbucket for the build status
[16:58:29.947] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:29.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:37:0) after 1m0s
[16:59:30.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:37:0)
[16:59:30.001] Timer tick, asking Buildbucket for the build status
[16:59:30.435] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:30.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:38:0) after 1m0s
[17:00:30.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:38:0)
[17:00:30.688] Timer tick, asking Buildbucket for the build status
[17:00:30.910] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:30.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:39:0) after 1m0s
[17:01:31.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:39:0)
[17:01:31.089] Timer tick, asking Buildbucket for the build status
[17:01:31.212] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:31.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:40:0) after 1m0s
[17:02:31.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:40:0)
[17:02:31.275] Timer tick, asking Buildbucket for the build status
[17:02:31.638] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:31.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:41:0) after 1m0s
[17:03:31.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:41:0)
[17:03:31.846] Timer tick, asking Buildbucket for the build status
[17:03:32.967] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:32.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:42:0) after 1m0s
[17:04:33.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:42:0)
[17:04:33.251] Timer tick, asking Buildbucket for the build status
[17:04:33.610] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:33.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:43:0) after 1m0s
[17:05:33.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:43:0)
[17:05:33.967] Timer tick, asking Buildbucket for the build status
[17:05:34.131] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:34.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:44:0) after 1m0s
[17:06:34.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:44:0)
[17:06:34.307] Timer tick, asking Buildbucket for the build status
[17:06:35.184] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:35.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:45:0) after 1m0s
[17:07:35.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:45:0)
[17:07:35.337] Timer tick, asking Buildbucket for the build status
[17:07:35.625] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:35.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:46:0) after 1m0s
[17:08:35.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:46:0)
[17:08:35.735] Timer tick, asking Buildbucket for the build status
[17:08:36.296] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:36.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:47:0) after 1m0s
[17:09:36.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:47:0)
[17:09:36.510] Timer tick, asking Buildbucket for the build status
[17:09:36.953] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:36.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:48:0) after 1m0s
[17:10:37.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:48:0)
[17:10:37.021] Timer tick, asking Buildbucket for the build status
[17:10:37.205] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:37.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:49:0) after 1m0s
[17:11:37.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:49:0)
[17:11:37.252] Timer tick, asking Buildbucket for the build status
[17:11:38.231] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:38.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:50:0) after 1m0s
[17:12:38.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:50:0)
[17:12:38.437] Timer tick, asking Buildbucket for the build status
[17:12:41.312] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:41.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:51:0) after 1m0s
[17:13:41.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:51:0)
[17:13:41.339] Timer tick, asking Buildbucket for the build status
[17:13:41.702] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:41.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:52:0) after 1m0s
[17:14:41.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:52:0)
[17:14:41.722] Timer tick, asking Buildbucket for the build status
[17:14:42.037] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:42.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:53:0) after 1m0s
[17:15:42.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:53:0)
[17:15:42.054] Timer tick, asking Buildbucket for the build status
[17:15:42.274] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:42.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:54:0) after 1m0s
[17:16:42.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:54:0)
[17:16:42.289] Timer tick, asking Buildbucket for the build status
[17:16:42.861] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:42.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:55:0) after 1m0s
[17:17:42.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:55:0)
[17:17:42.927] Timer tick, asking Buildbucket for the build status
[17:17:43.137] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:43.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:56:0) after 1m0s
[17:18:43.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:56:0)
[17:18:43.226] Timer tick, asking Buildbucket for the build status
[17:18:43.327] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:43.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:57:0) after 1m0s
[17:19:43.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:57:0)
[17:19:43.394] Timer tick, asking Buildbucket for the build status
[17:19:44.132] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:44.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:58:0) after 1m0s
[17:20:44.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:58:0)
[17:20:44.191] Timer tick, asking Buildbucket for the build status
[17:20:44.337] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:44.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:59:0) after 1m0s
[17:21:44.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:59:0)
[17:21:44.355] Timer tick, asking Buildbucket for the build status
[17:21:44.613] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:44.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:60:0) after 1m0s
[17:22:44.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:60:0)
[17:22:44.628] Timer tick, asking Buildbucket for the build status
[17:22:44.789] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:44.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:61:0) after 1m0s
[17:23:44.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:61:0)
[17:23:44.817] Timer tick, asking Buildbucket for the build status
[17:23:45.650] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:45.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:62:0) after 1m0s
[17:24:45.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:62:0)
[17:24:45.817] Timer tick, asking Buildbucket for the build status
[17:24:46.025] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.025] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:63:0) after 1m0s
[17:25:46.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:63:0)
[17:25:46.108] Timer tick, asking Buildbucket for the build status
[17:25:46.421] Build 8889369705119476528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054799768520840992:64:0) after 1m0s
[17:26:26.536] Received PubSub notification, asking Buildbucket for the build status
[17:26:26.925] Build 8889369705119476528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:26:26.925] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580837185454688",
"created_by": "project:chromium",
"created_ts": "1580833445395725",
"id": "8889369705119476528",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c4ac5495adda4cb36706ad08f6792ebc793817a\"}}",
"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-83-v9lm\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168224, \"got_angle_revision\": \"4546c5cefa0eb11d423b60ac98802e671510a562\", \"got_dawn_revision\": \"43a0a815b8146d7ce596e281267ccd6565e9619e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"4c4ac5495adda4cb36706ad08f6792ebc793817a\", \"got_revision_cp\": \"refs/heads/master@{#738193}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"41a7302e9c62c207e5ac5bcce40f16a1e418c42b\", \"got_v8_revision\": \"842c4bc23d4ebf75589ed964ba1c2af057137d4c\", \"got_v8_revision_cp\": \"refs/heads/8.2.7@{#1}\", \"got_webrtc_revision\": \"f5d877847faebad151be2e8824a01204351de5e1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30450}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c4ac5495adda4cb36706ad08f6792ebc793817a\", \"swarm_hashes_refs/heads/master(at){#738193}_without_patch\": {\"accessibility_unittests\": \"f6659752d7dca6f92a57b8bbe38f4664e9653830\", \"angle_unittests\": \"27f957a79a8ea6482bf88008eb8c0a6a593b5e55\", \"app_shell_unittests\": \"ac5683dfc8e94227a602c36923a98504ebc39b71\", \"aura_unittests\": \"769320da6b8c5e26bb826936c16d904bb73b0b31\", \"base_unittests\": \"3e3e9b6067523ac84a26214a1ba72db3d2b19f94\", \"base_util_unittests\": \"fc9db37de4a85c1339326f88b829e265ee0dbd46\", \"blink_common_unittests\": \"53410b2448a808fd9268c22047635c9e14a21d94\", \"blink_fuzzer_unittests\": \"2fe5ff4b6b2d293bf4e3ed1ea9540db0256cb05c\", \"blink_heap_unittests\": \"195a2889f8536d341c2525f676a285b53e25e8bc\", \"blink_platform_unittests\": \"fcffbdb68f047b2b688055fd99064af9a2ed5ddf\", \"blink_python_tests\": \"6b9761fe75c48529f57b866e576c9a9177049e2b\", \"blink_unittests\": \"822ab514505bb4cfc178c0a713b41d445f9a123f\", \"blink_web_tests\": \"d1051529533b9ca85ff901e2ff45d18709380569\", \"boringssl_crypto_tests\": \"8d11744e898aaaa0de4f3a891f519dcc1363c358\", \"boringssl_ssl_tests\": \"a3f9377aa1c6daee17bb770b04c73491f1b39f3b\", \"browser_tests\": \"6e9c54c92ba02916e63f05a0c25e2631df81588d\", \"cacheinvalidation_unittests\": \"3915207468567d0fc827e85d11514203fc189bc8\", \"capture_unittests\": \"78ffc9a5787c2ad8bd987f6b50663ac96c14aab5\", \"cast_unittests\": \"21da3c9bfd272746b527815d5afc0349186e4201\", \"cc_unittests\": \"80b5505681398bf68da7f9263933d6cd5daf0e32\", \"chrome_app_unittests\": \"916c06d54878ae7515596552453381650e09ca00\", \"chromedriver_unittests\": \"6b62cac1ea9f8e8b88d5680f58eac2515cd5a31d\", \"components_browsertests\": \"a2a59a76a77e5c0739c7262bde97bda399711a42\", \"components_unittests\": \"04ad619288a4009bf5520dd9709c23ea1bcf97bc\", \"compositor_unittests\": \"418645f355033639a1bb1340e3ca07edfd31172d\", \"content_browsertests\": \"42ba04517aa5b2e9012a38a077576640ee94cde7\", \"content_shell_crash_test\": \"2174f188bc651199a79826aa79f1ef58d779495c\", \"content_unittests\": \"cef297f2929c8fe0ff8e251352e1d53e59900b85\", \"crashpad_tests\": \"033adeb2bdf950da2e2a9ad3247944afa7288380\", \"cronet_tests\": \"0219e1b12bfd123001e5f8b3aaec68af6de1f4a2\", \"cronet_unittests\": \"5671ea2331d8831582b99e370c7abc97d07c8dd3\", \"crypto_unittests\": \"ee4b2ed39ab6423f3efbe8fe175c7abb2e333800\", \"dbus_unittests\": \"5821f0434a26159ea90312bb2afcfa481610335d\", \"device_unittests\": \"93ac6580ca88d168170e6a50672c60a1021fd0a1\", \"display_unittests\": \"d10b912b0a51937bf8e1e92bb08e21e53d1110b9\", \"events_unittests\": \"d776de0faa740c4676375225df9e747b71e6b9c1\", \"extensions_browsertests\": \"2b50b96dabd774f012299ce9e919bf02c7c0ec72\", \"extensions_unittests\": \"f7ca9cd79cb1ba184da41eb34f781534fedf32b2\", \"filesystem_service_unittests\": \"3eaf3cda007ec38e7c204b7e190cc971da8ec9b0\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"b7bce692614e01f8e5bd4d466ec510604a1de668\", \"gfx_unittests\": \"00faa123340214cda723c6280b8e185ecaee34f2\", \"gin_unittests\": \"ca30aaa6326fcfe4308fe85addf679f7559a766d\", \"google_apis_unittests\": \"34ad55e0e70dcbd9ba1c4f3308ec19b361766397\", \"gpu_unittests\": \"05b2a6b54ec7dbbba1087476d4d125f79e501744\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"4f63affe42245801f27dd67b7878233469c5501c\", \"headless_browsertests\": \"01639176500c5e8a1963dd29defd1330c0178bb5\", \"headless_unittests\": \"4b26e8ed10b44d30812133b6aa62bb72d834e6ea\", \"interactive_ui_tests\": \"24ca8f7a8618f2da601ce4092c7d4567ec9acd42\", \"ipc_tests\": \"f291e8b4d7d619481c73e9a7ac817ce421fea499\", \"jingle_unittests\": \"d64a5224b04706305f2f8e16abe8f4ed77148b44\", \"latency_unittests\": \"771021badd2a4e718d14a9a50e21116430a939f0\", \"libjingle_xmpp_unittests\": \"77c351cb81a78360e72fd912caea23812a9fa966\", \"media_blink_unittests\": \"f4d7b85dcd5f4a4d9af6cbe65a27a2f56d2d118a\", \"media_service_unittests\": \"ca7080d86f6265c80ec54cf17575becf74e6b0ae\", \"media_unittests\": \"e0c94e67c9091eecfa0494b377022771ccf33244\", \"message_center_unittests\": \"e78030d217b0aaeb50a8f201b0274833a84013d9\", \"metrics_python_tests\": \"523358cf1a8fd89d380dabaf55da1bc9acd53194\", \"midi_unittests\": \"b235bf93449be5d18aa38598a52bdb356103a13c\", \"mojo_core_unittests\": \"c16a191c0ed2aa71c9ba2316ffdeef193c9b5c08\", \"mojo_unittests\": \"a83cc20f575e9d09a81e280f9fb4bc0e3c9dac4e\", \"nacl_helper_nonsfi_unittests\": \"b278489c95049dbc9bd71877869ceb613df86f05\", \"nacl_loader_unittests\": \"4afa075765b20822c898f3a6672a7d9572f2c7ba\", \"native_theme_unittests\": \"f40b0c57d1d37920ec9563074f87e173de7a0990\", \"net_unittests\": \"fd90cb99b618d7a3e8d0d2a504a5c856906fe0ab\", \"pdf_unittests\": \"883e2e72a6a7e440b53f1b23cc14d0f998c6ebcc\", \"perfetto_unittests\": \"801587af8b48ca67ffb927964c12a836cf3bd408\", \"ppapi_unittests\": \"2258a7cffc21e84f41e49655434a1dd83fb712f3\", \"printing_unittests\": \"0a9bd99fc25a08190bec716b9f14c15b62021e7a\", \"remoting_unittests\": \"3b4ee88aad308a29b0faa77292c04d94135fc7c9\", \"sandbox_linux_unittests\": \"7876ff25c19764dbb801ab37fa1da7eaa914dc6b\", \"service_manager_unittests\": \"9aec411347a98b2b2c809dadfc1ecdc89f25380b\", \"services_unittests\": \"c78f62e214515f91f4e88afc410b2841e5681b84\", \"shell_dialogs_unittests\": \"4ffbb25ebd088f77819e1c36b9488061a2779278\", \"skia_unittests\": \"1d27737eeca4e686d89d815e87fcc9730bc9d8a6\", \"snapshot_unittests\": \"047bd67c4a6b0128273b9b41b0121b1f4c1699a6\", \"sql_unittests\": \"42f5589ed2ca13933b76c8fc94632efa859ec471\", \"storage_unittests\": \"37ccdf588b4d11cab2d7970443fb403806ee495a\", \"sync_integration_tests\": \"df1d8a44272e1807f0fb2e37eb0231a86a9d3c0f\", \"telemetry_gpu_unittests\": \"0366a2197ae85d1b82823b619370a0646951972f\", \"telemetry_perf_unittests\": \"3a5c3ff6aa2227c1221ad2a49d2c4f75d1177ec4\", \"telemetry_unittests\": \"909b6322f4ed30f4c6c231b63b463ee66c2cc0ae\", \"traffic_annotation_auditor_unittests\": \"253a78c9a94ab6eddf27b0c5fcb281b30ba5b69d\", \"ui_base_unittests\": \"32edaa4a4ff6a1a73a30e1fb109b49421d847f34\", \"ui_touch_selection_unittests\": \"32b063b221ac1b864e43fd445100ff330d9e54bd\", \"unit_tests\": \"43252dc52dc6ecfbd3b0f4565edcd6df7bd5be5e\", \"url_unittests\": \"fbf6ac31efb3e85628d5edde14c829589f31ac07\", \"views_perftests\": \"161dc2b908a536a871b4d0aa46aa6711cdc27567\", \"views_unittests\": \"68181731f81f5a165ad0b96c4ec35e4adb773c50\", \"viz_unittests\": \"13dc1340fc146e5e804aa622413ca60855bf808c\", \"vr_common_unittests\": \"8e457b8ffaab55fa99d079204ad8da8c3476213e\", \"vr_pixeltests\": \"826558f8983ef88b2f2086d0b65cb1d84cff10d5\", \"webdriver_wpt_tests\": \"f02ee609e5b9d3bcc2a7507b5b7b30ed16a9e518\", \"weblayer_browsertests\": \"f3d1f312d5a7ed6da2a95b90146de1a8be76df9d\", \"wm_unittests\": \"5cb6e5ea07eff185b9c63fc13b96f4865d801201\", \"wtf_unittests\": \"93e10734a6e2bda65dbfce120d0e6d042cc6f18d\", \"xr_browser_tests\": \"96bc91c5a89eaf945526bbafb28e70692139ef1e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_4735eb974b804f74efeb4253a3690e62eb4b060f1d311c87e54f3b58924ad1a2_v2\", \"builder_7ed5e98f023cd9e0abec02df990f9772adc652ea96b625245e768b064914efaf_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-83-v9lm\"], \"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": "1580833749094204",
"status": "COMPLETED",
"status_changed_ts": "1580837186104672",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168224",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c4ac5495adda4cb36706ad08f6792ebc793817a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054799768520840992",
"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/8889369705119476528/+/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:4a29d5b06ab8d010",
"user_agent:luci-scheduler"
],
"updated_ts": "1580837186174129",
"url": "https://ci.chromium.org/b/8889369705119476528",
"utcnow_ts": "1580837186908386"
}
}
[17:26:26.925] Invocation finished in 1h2m24.157162477s with status SUCCEEDED