[17:39:07.303] New invocation is queued and will start shortly
[17:39:10.395] Starting the invocation (attempt 1)
[17:39:10.483] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:39:10.483] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:39:10.483] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056697581486238224",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8d31e0b58831697e922a607f87a6d42e0aef418c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056697581486238224",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8d31e0b58831697e922a607f87a6d42e0aef418c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d31e0b58831697e922a607f87a6d42e0aef418c",
"gitiles_ref:refs/heads/master"
]
}
[17:39:11.520] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579023550847350",
"id": "8891267517105425024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d31e0b58831697e922a607f87a6d42e0aef418c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579023551361300",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167370",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8d31e0b58831697e922a607f87a6d42e0aef418c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d31e0b58831697e922a607f87a6d42e0aef418c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056697581486238224",
"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/8891267517105425024/+/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": "1579023551361455",
"url": "https://ci.chromium.org/b/8891267517105425024",
"utcnow_ts": "1579023551490429"
}
}
[17:39:11.520] Task URL: https://ci.chromium.org/b/8891267517105425024
[17:39:11.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:2:0) after 1m0s
[17:40:11.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:2:0)
[17:40:11.685] Timer tick, asking Buildbucket for the build status
[17:40:11.994] Build 8891267517105425024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:11.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:3:0) after 1m0s
[17:41:12.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:3:0)
[17:41:12.049] Timer tick, asking Buildbucket for the build status
[17:41:12.256] Build 8891267517105425024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:12.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:4:0) after 1m0s
[17:42:12.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:4:0)
[17:42:12.521] Timer tick, asking Buildbucket for the build status
[17:42:13.281] Build 8891267517105425024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:13.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:5:0) after 1m0s
[17:43:13.361] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:5:0)
[17:43:13.361] Timer tick, asking Buildbucket for the build status
[17:43:13.589] Build 8891267517105425024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:13.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:6:0) after 1m0s
[17:44:09.618] Received PubSub notification, asking Buildbucket for the build status
[17:44:09.935] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:13.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:6:0)
[17:44:13.658] Timer tick, asking Buildbucket for the build status
[17:44:14.587] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:14.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:8:0) after 1m0s
[17:45:14.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:8:0)
[17:45:14.845] Timer tick, asking Buildbucket for the build status
[17:45:16.144] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:16.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:9:0) after 1m0s
[17:46:16.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:9:0)
[17:46:16.459] Timer tick, asking Buildbucket for the build status
[17:46:16.684] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:16.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:10:0) after 1m0s
[17:47:16.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:10:0)
[17:47:16.825] Timer tick, asking Buildbucket for the build status
[17:47:18.111] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:18.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:11:0) after 1m0s
[17:48:18.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:11:0)
[17:48:18.200] Timer tick, asking Buildbucket for the build status
[17:48:18.866] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:18.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:12:0) after 1m0s
[17:49:18.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:12:0)
[17:49:18.963] Timer tick, asking Buildbucket for the build status
[17:49:20.069] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:13:0) after 1m0s
[17:50:20.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:13:0)
[17:50:20.092] Timer tick, asking Buildbucket for the build status
[17:50:20.673] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:20.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:14:0) after 1m0s
[17:51:20.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:14:0)
[17:51:20.760] Timer tick, asking Buildbucket for the build status
[17:51:22.093] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:22.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:15:0) after 1m0s
[17:52:22.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:15:0)
[17:52:22.131] Timer tick, asking Buildbucket for the build status
[17:52:23.034] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:23.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:16:0) after 1m0s
[17:53:23.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:16:0)
[17:53:23.275] Timer tick, asking Buildbucket for the build status
[17:53:23.467] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:23.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:17:0) after 1m0s
[17:54:23.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:17:0)
[17:54:23.497] Timer tick, asking Buildbucket for the build status
[17:54:23.669] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:23.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:18:0) after 1m0s
[17:55:23.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:18:0)
[17:55:23.809] Timer tick, asking Buildbucket for the build status
[17:55:24.228] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:24.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:19:0) after 1m0s
[17:56:24.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:19:0)
[17:56:24.362] Timer tick, asking Buildbucket for the build status
[17:56:24.700] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:24.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:20:0) after 1m0s
[17:57:24.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:20:0)
[17:57:24.794] Timer tick, asking Buildbucket for the build status
[17:57:25.001] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:25.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:21:0) after 1m0s
[17:58:25.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:21:0)
[17:58:25.327] Timer tick, asking Buildbucket for the build status
[17:58:25.858] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:25.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:22:0) after 1m0s
[17:59:26.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:22:0)
[17:59:26.082] Timer tick, asking Buildbucket for the build status
[17:59:26.315] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:26.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:23:0) after 1m0s
[18:00:26.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:23:0)
[18:00:26.457] Timer tick, asking Buildbucket for the build status
[18:00:26.658] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:26.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:24:0) after 1m0s
[18:01:26.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:24:0)
[18:01:26.650] Timer tick, asking Buildbucket for the build status
[18:01:27.715] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:27.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:25:0) after 1m0s
[18:02:27.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:25:0)
[18:02:27.771] Timer tick, asking Buildbucket for the build status
[18:02:27.970] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:27.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:26:0) after 1m0s
[18:03:28.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:26:0)
[18:03:28.149] Timer tick, asking Buildbucket for the build status
[18:03:28.393] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:28.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:27:0) after 1m0s
[18:04:28.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:27:0)
[18:04:28.756] Timer tick, asking Buildbucket for the build status
[18:04:29.538] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:29.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:28:0) after 1m0s
[18:05:29.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:28:0)
[18:05:29.677] Timer tick, asking Buildbucket for the build status
[18:05:29.877] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:29.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:29:0) after 1m0s
[18:06:30.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:29:0)
[18:06:30.145] Timer tick, asking Buildbucket for the build status
[18:06:30.314] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:30.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:30:0) after 1m0s
[18:07:31.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:30:0)
[18:07:31.255] Timer tick, asking Buildbucket for the build status
[18:07:31.508] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:31.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:31:0) after 1m0s
[18:08:31.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:31:0)
[18:08:31.835] Timer tick, asking Buildbucket for the build status
[18:08:32.935] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:32.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:32:0) after 1m0s
[18:09:33.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:32:0)
[18:09:33.168] Timer tick, asking Buildbucket for the build status
[18:09:33.558] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:33.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:33:0) after 1m0s
[18:10:33.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:33:0)
[18:10:33.681] Timer tick, asking Buildbucket for the build status
[18:10:33.903] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:33.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:34:0) after 1m0s
[18:11:34.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:34:0)
[18:11:34.234] Timer tick, asking Buildbucket for the build status
[18:11:34.632] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:34.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:35:0) after 1m0s
[18:12:34.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:35:0)
[18:12:34.914] Timer tick, asking Buildbucket for the build status
[18:12:35.666] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:35.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:36:0) after 1m0s
[18:13:35.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:36:0)
[18:13:35.676] Timer tick, asking Buildbucket for the build status
[18:13:35.844] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:35.844] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:37:0) after 1m0s
[18:14:35.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:37:0)
[18:14:35.962] Timer tick, asking Buildbucket for the build status
[18:14:37.088] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:37.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:38:0) after 1m0s
[18:15:37.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:38:0)
[18:15:37.300] Timer tick, asking Buildbucket for the build status
[18:15:38.011] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:39:0) after 1m0s
[18:16:38.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:39:0)
[18:16:38.584] Timer tick, asking Buildbucket for the build status
[18:16:38.770] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:40:0) after 1m0s
[18:17:39.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:40:0)
[18:17:39.138] Timer tick, asking Buildbucket for the build status
[18:17:39.704] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:39.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:41:0) after 1m0s
[18:18:39.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:41:0)
[18:18:39.786] Timer tick, asking Buildbucket for the build status
[18:18:40.001] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:40.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:42:0) after 1m0s
[18:19:40.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:42:0)
[18:19:40.373] Timer tick, asking Buildbucket for the build status
[18:19:40.549] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:40.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:43:0) after 1m0s
[18:20:40.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:43:0)
[18:20:40.732] Timer tick, asking Buildbucket for the build status
[18:20:41.471] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:41.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:44:0) after 1m0s
[18:21:41.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:44:0)
[18:21:41.666] Timer tick, asking Buildbucket for the build status
[18:21:41.965] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:41.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:45:0) after 1m0s
[18:22:42.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:45:0)
[18:22:42.301] Timer tick, asking Buildbucket for the build status
[18:22:43.271] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:43.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:46:0) after 1m0s
[18:23:43.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:46:0)
[18:23:43.335] Timer tick, asking Buildbucket for the build status
[18:23:43.714] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:43.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:47:0) after 1m0s
[18:24:43.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:47:0)
[18:24:43.798] Timer tick, asking Buildbucket for the build status
[18:24:44.012] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:44.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:48:0) after 1m0s
[18:25:44.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:48:0)
[18:25:44.093] Timer tick, asking Buildbucket for the build status
[18:25:44.299] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:44.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:49:0) after 1m0s
[18:26:44.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:49:0)
[18:26:44.525] Timer tick, asking Buildbucket for the build status
[18:26:45.789] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:45.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:50:0) after 1m0s
[18:27:45.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:50:0)
[18:27:45.810] Timer tick, asking Buildbucket for the build status
[18:27:46.662] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:46.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:51:0) after 1m0s
[18:28:46.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:51:0)
[18:28:46.754] Timer tick, asking Buildbucket for the build status
[18:28:47.635] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:47.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:52:0) after 1m0s
[18:29:47.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:52:0)
[18:29:47.880] Timer tick, asking Buildbucket for the build status
[18:29:49.073] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:49.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:53:0) after 1m0s
[18:30:49.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:53:0)
[18:30:49.355] Timer tick, asking Buildbucket for the build status
[18:30:49.709] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:49.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:54:0) after 1m0s
[18:31:49.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:54:0)
[18:31:49.767] Timer tick, asking Buildbucket for the build status
[18:31:50.144] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:50.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:55:0) after 1m0s
[18:32:50.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:55:0)
[18:32:50.198] Timer tick, asking Buildbucket for the build status
[18:32:50.831] Build 8891267517105425024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:50.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056697581486238224:56:0) after 1m0s
[18:33:20.658] Received PubSub notification, asking Buildbucket for the build status
[18:33:23.497] Build 8891267517105425024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:33:23.497] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579026799508859",
"created_by": "project:chromium",
"created_ts": "1579023550847350",
"id": "8891267517105425024",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d31e0b58831697e922a607f87a6d42e0aef418c\"}}",
"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-76-lkmh\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167370, \"got_angle_revision\": \"683dd1d6a8631e6969918bfca777af7c8f0d74c4\", \"got_dawn_revision\": \"e5cb8f2eff7e2b0f672c0d20fb97ca17fe28e94a\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"8d31e0b58831697e922a607f87a6d42e0aef418c\", \"got_revision_cp\": \"refs/heads/master@{#731510}\", \"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\": \"8d31e0b58831697e922a607f87a6d42e0aef418c\", \"swarm_hashes_refs/heads/master(at){#731510}_without_patch\": {\"accessibility_unittests\": \"81232ca408c40bc035094d6d3de189a64494ec82\", \"angle_unittests\": \"c52725cc079a5c8737d922bca8c2f58e869b2ca6\", \"app_shell_unittests\": \"2ea3962bd57f6fb822ffc30367e18c7b54c9cdb2\", \"aura_unittests\": \"be1151cf79414ba60789585b3f5310804b3b353f\", \"base_unittests\": \"4111eba7e69314fd69c0ce08c349c9197071c4db\", \"base_util_unittests\": \"2f050164f7eebd1fea0df00bdc619d665cabb735\", \"blink_common_unittests\": \"4e0742088a1f0bf51721436398c80ba89b1bd5b3\", \"blink_fuzzer_unittests\": \"88ee43a1ed4adecccd30cbfeba3bccb6e140ed65\", \"blink_heap_unittests\": \"950d7c310da58bc5a56d529cf922d6d98636d4b2\", \"blink_platform_unittests\": \"7e50e7fa6d2f48075111cb4187b3ce8e259d290c\", \"blink_python_tests\": \"e049a5249faf91a274f3bd41436cde91cd57fadb\", \"blink_unittests\": \"bebbad95382e5b658a2ab6fe37bdfc64bf374e54\", \"blink_web_tests\": \"4c44e823011bfc85642922c7e2ecb6e3926270a0\", \"boringssl_crypto_tests\": \"8ab333c4339856692cdfd96c344eaa7b34111900\", \"boringssl_ssl_tests\": \"e0497491bf37cae312792d217750c5eddb9ac296\", \"browser_tests\": \"a0cdbe1ed54e3c3e88d2956c2e573cf26178f580\", \"cacheinvalidation_unittests\": \"50163bc51efa70db843c53e4065f72d4403156da\", \"capture_unittests\": \"b2b0615ef59a73737b46a297d965e7caf1c51c66\", \"cast_unittests\": \"c306270ca8e78e1cac4b3b72587725b43b823145\", \"cc_unittests\": \"e79c925cf01db5beb0955b9d9e532b5f38140f62\", \"chrome_app_unittests\": \"df4340c46624ff49eba0a95d41618e48509009e3\", \"chromedriver_unittests\": \"8d8a974309e9c720c68b5d118afa3fdaa906c9ee\", \"components_browsertests\": \"f0cc8ec6b4d372d34d85a765ff1a466938bb7d07\", \"components_unittests\": \"2770720efc062c4e3efddf7efd5b4e6d9b0c8ec9\", \"compositor_unittests\": \"76daedd18754bdb311c5ad29cf53656d0d5aea26\", \"content_browsertests\": \"f90db70744b4097e4c50765cd1a21a93ea19ffe4\", \"content_shell_crash_test\": \"676b536a37c1ab35e6214a02be705f31a4fa2f1a\", \"content_unittests\": \"86f2bb337b165629543cbd51239ce4d9322bad86\", \"crashpad_tests\": \"8430b93c4b0301271c190550e475af199eb6905c\", \"cronet_tests\": \"e939e89fffeed7624bd1ea2e59c99c1b2e423e65\", \"cronet_unittests\": \"51fbb48a3e10806ae4602a87d253bd6a90ba7e86\", \"crypto_unittests\": \"48cacea84ef1654cc3d61572f7386ccef9ffdbca\", \"dbus_unittests\": \"f84aed58192050bd2d4e04ff30539fdd4e855b3c\", \"device_unittests\": \"4e3f515a2dc7c035f1ad1ecd895942db0bb29aa0\", \"devtools_lint_check\": \"5dbecfeac3ce9652046f5a7516093b99bbaa4b2a\", \"devtools_type_check\": \"7148e7cebdc480b4d34be6b1d59c60199be58d95\", \"display_unittests\": \"22c9eb8c0c9a2996b2065832746b6821dd2df4bd\", \"events_unittests\": \"4228e6d5262ac723ef42e1ca35e702d3ab9a355a\", \"extensions_browsertests\": \"607bb3371306750c303ce023e7ecafb9fa7dcd42\", \"extensions_unittests\": \"acaf69f0badbdc3b712be4b773fc734698943d2b\", \"filesystem_service_unittests\": \"3ed46dff546df37383f235bea7e3a2274fd86e0d\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"ab2f717e27a98a9e3e047f908da8382e3e46a864\", \"gfx_unittests\": \"5b26ebe9c6e2054283692c73874dece7d10ff0be\", \"gin_unittests\": \"970d61fcddc72927196b056ff0e93ac29b72e3d6\", \"google_apis_unittests\": \"4a5859459f9f511300e527d9713e2849fddc4bf0\", \"gpu_unittests\": \"83c93eadbeb8b4a64955448b6b7b496a62eaf0ed\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"369a855079539a84584e65fe29c4224c4389376e\", \"headless_browsertests\": \"8d4257b88947afabc9a59e6789436aadca1b7497\", \"headless_unittests\": \"2db63b76a7a1c2a76bf4d5957b8dc797dec42d8f\", \"interactive_ui_tests\": \"cf7b926efc26ace5a24c698efc98b9f20acbc39f\", \"ipc_tests\": \"3ea4d45fb0f2a76803cec9840f5605b7a7c9430c\", \"jingle_unittests\": \"a7b7efaa043091b32c23a2b90b7c67470b087533\", \"latency_unittests\": \"6fddda1ce6a65429a68d0cb93deab1e179fb1c73\", \"libjingle_xmpp_unittests\": \"031d59d421cb731a2e5cb92d8260d17a6d026433\", \"media_blink_unittests\": \"344392c794c8763ce067d88bb1c08b6f3cd19f26\", \"media_service_unittests\": \"12039aa9be044faa947b63352ea716549771af2f\", \"media_unittests\": \"1bef51b531d904a6cd1ccfadd13186617a332751\", \"message_center_unittests\": \"63b9631f8bb84beccc084d1ba0509d2e139b30f3\", \"metrics_python_tests\": \"027a5da4af37ceb727941a4da778c4c27062f4b2\", \"midi_unittests\": \"074262af74903cf1af0e88dada21a776818065c2\", \"mojo_core_unittests\": \"442fac2b595dc9775b4f55174d16e0e617c7b357\", \"mojo_unittests\": \"4bd30150c4487c8a708eaaba2e3a3e963bf58a66\", \"nacl_helper_nonsfi_unittests\": \"32db1dd0c6fb6b418ac7edda470821fcec9b6450\", \"nacl_loader_unittests\": \"7ae90fa4049dc673e28534883bba0e328b7888fb\", \"native_theme_unittests\": \"a3efb511efb614c490a90e4fa6700acddd25aa4c\", \"net_unittests\": \"c0489d5036b89c7854ae16ceb1d5c58f00d22be3\", \"pdf_unittests\": \"70bbb41a461ff54b6ac319f918431a18c5929c81\", \"perfetto_unittests\": \"2995095c70690f760384b4fee3b9866b6a9f2e1b\", \"ppapi_unittests\": \"68255898bc9fe979977b3996e03aba025a138329\", \"printing_unittests\": \"4cec38bbd203463da793e345feaf964df79de1c4\", \"remoting_unittests\": \"db3c2d18f1b1b8384d080baa6a1ea04b455bc615\", \"sandbox_linux_unittests\": \"f94e08381bcacda92c0e8a3406e02749810dae03\", \"service_manager_unittests\": \"bd63084abc434cccd11ca70faa38b50c2354d11f\", \"services_unittests\": \"5a6f54deab3d35b8ef4cf3d4ce75068b744bddce\", \"shell_dialogs_unittests\": \"5e873ee00074c71e8d5d2a5684199be0b066568d\", \"skia_unittests\": \"d3be5fe078ae1901b4cc353c372852e1abeb02bb\", \"snapshot_unittests\": \"54965dedf2f0e25f4cac729eaf92a16fb105f225\", \"sql_unittests\": \"1db1249d73042922803b734256ba0849c3f84791\", \"storage_unittests\": \"15e90808f766146bdef1eb8997c862112892a0b6\", \"sync_integration_tests\": \"087f2ffd76a09b4522931f9e94e959abfbf4bb48\", \"telemetry_gpu_unittests\": \"26ec2bd4415996b730f998d0e2aaa0b5d548958b\", \"telemetry_perf_unittests\": \"c675d5b9278d3212a0be57b820a8cfbb50551e40\", \"telemetry_unittests\": \"10631f36257549e3e8c792410fba2b210ec9acc9\", \"traffic_annotation_auditor_unittests\": \"59b3e45ab3b2dedbbc76a80c8fb53bd0c19f8395\", \"ui_base_unittests\": \"0f5a94fa362e3333e55f5945f67d29d8842d7714\", \"ui_touch_selection_unittests\": \"c6da1a1877b5283226eb118589a9fd0bead13c09\", \"unit_tests\": \"e1b698aabb2c7714660e6805f2df7b57192e505e\", \"url_unittests\": \"ac1b860da340ac9a42d0f64a901996afcec41fe6\", \"views_perftests\": \"035055e5d43e6e95ae6fc81215271ef5e9b0d09a\", \"views_unittests\": \"9ab6c9782bf7a86797154e906f0b97346b1e6a38\", \"viz_unittests\": \"82ee0bf967397eab85b2ce1e017b9b8a0c121900\", \"vr_common_unittests\": \"a349c5969603a285064529300031fde924d0df52\", \"vr_pixeltests\": \"ef97bc294d4e9bd9bb74d3e8698abc321bdaff53\", \"webdriver_wpt_tests\": \"ca19e80941e52afaede2b7ef6840ab0a93bb4319\", \"weblayer_browsertests\": \"d15e600ab9f010098e36cf7da086097fe6de032e\", \"wm_unittests\": \"ae4e48581a7685878cfa07ac1ae9d713699749e1\", \"wtf_unittests\": \"c63864e8fdb65895fe4c8c17be6e6beca359aa22\", \"xr_browser_tests\": \"951178fbd324fab5082d853bbd2c3cd3fab36270\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e7d7e672723aea6cc752b70abb9e3aec1540865d07a8c2471e48d2b0cb9abe3_v2\", \"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_a050691f36f3646c8cc368108f8edcf844a0d1683dc4a3731c64b1c7527ccb94_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_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-76-lkmh\"], \"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": "1579023848036838",
"status": "COMPLETED",
"status_changed_ts": "1579026800154789",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167370",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8d31e0b58831697e922a607f87a6d42e0aef418c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d31e0b58831697e922a607f87a6d42e0aef418c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056697581486238224",
"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/8891267517105425024/+/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:49bdf544648c1310",
"user_agent:luci-scheduler"
],
"updated_ts": "1579026800167857",
"url": "https://ci.chromium.org/b/8891267517105425024",
"utcnow_ts": "1579026803479690"
}
}
[18:33:23.497] Invocation finished in 54m16.212226646s with status SUCCEEDED