[21:47:16.658] New invocation is queued and will start shortly
[21:47:18.461] Starting the invocation (attempt 1)
[21:47:18.559] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:47:18.559] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:47:18.559] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059852862702168688",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c92e7ea27045a04f65d85c4e6add9c7a20483414\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059852862702168688",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"gitiles_ref:refs/heads/master"
]
}
[21:47:19.749] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576014438803772",
"id": "8894422799775787376",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c92e7ea27045a04f65d85c4e6add9c7a20483414\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576014439242799",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165919",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059852862702168688",
"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/8894422799775787376/+/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": "1576014439242947",
"url": "https://ci.chromium.org/b/8894422799775787376",
"utcnow_ts": "1576014439733867"
}
}
[21:47:19.749] Task URL: https://ci.chromium.org/b/8894422799775787376
[21:47:19.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:2:0) after 1m0s
[21:48:20.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:2:0)
[21:48:20.000] Timer tick, asking Buildbucket for the build status
[21:48:20.271] Build 8894422799775787376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:20.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:3:0) after 1m0s
[21:49:20.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:3:0)
[21:49:20.627] Timer tick, asking Buildbucket for the build status
[21:49:20.919] Build 8894422799775787376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:4:0) after 1m0s
[21:50:20.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:4:0)
[21:50:20.963] Timer tick, asking Buildbucket for the build status
[21:50:21.223] Build 8894422799775787376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:21.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:5:0) after 1m0s
[21:51:21.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:5:0)
[21:51:21.343] Timer tick, asking Buildbucket for the build status
[21:51:21.559] Build 8894422799775787376: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:21.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:6:0) after 1m0s
[21:52:21.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:6:0)
[21:52:21.898] Timer tick, asking Buildbucket for the build status
[21:52:22.069] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:22.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:7:0) after 1m0s
[21:52:37.844] Received PubSub notification, asking Buildbucket for the build status
[21:52:38.071] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:22.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:7:0)
[21:53:22.403] Timer tick, asking Buildbucket for the build status
[21:53:22.715] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:9:0) after 1m0s
[21:54:22.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:9:0)
[21:54:22.848] Timer tick, asking Buildbucket for the build status
[21:54:22.974] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:22.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:10:0) after 1m0s
[21:55:23.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:10:0)
[21:55:23.187] Timer tick, asking Buildbucket for the build status
[21:55:23.458] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:23.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:11:0) after 1m0s
[21:56:23.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:11:0)
[21:56:23.550] Timer tick, asking Buildbucket for the build status
[21:56:24.696] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:24.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:12:0) after 1m0s
[21:57:24.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:12:0)
[21:57:24.769] Timer tick, asking Buildbucket for the build status
[21:57:25.524] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:25.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:13:0) after 1m0s
[21:58:25.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:13:0)
[21:58:25.589] Timer tick, asking Buildbucket for the build status
[21:58:25.800] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:25.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:14:0) after 1m0s
[21:59:25.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:14:0)
[21:59:25.869] Timer tick, asking Buildbucket for the build status
[21:59:26.575] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:26.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:15:0) after 1m0s
[22:00:26.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:15:0)
[22:00:26.711] Timer tick, asking Buildbucket for the build status
[22:00:27.688] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:27.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:16:0) after 1m0s
[22:01:27.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:16:0)
[22:01:27.957] Timer tick, asking Buildbucket for the build status
[22:01:28.298] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:28.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:17:0) after 1m0s
[22:02:28.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:17:0)
[22:02:28.358] Timer tick, asking Buildbucket for the build status
[22:02:28.848] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:28.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:18:0) after 1m0s
[22:03:28.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:18:0)
[22:03:28.988] Timer tick, asking Buildbucket for the build status
[22:03:29.381] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:29.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:19:0) after 1m0s
[22:04:29.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:19:0)
[22:04:29.429] Timer tick, asking Buildbucket for the build status
[22:04:29.714] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:29.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:20:0) after 1m0s
[22:05:29.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:20:0)
[22:05:29.816] Timer tick, asking Buildbucket for the build status
[22:05:30.138] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:30.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:21:0) after 1m0s
[22:06:30.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:21:0)
[22:06:30.195] Timer tick, asking Buildbucket for the build status
[22:06:33.461] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:33.461] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:22:0) after 1m0s
[22:07:33.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:22:0)
[22:07:33.577] Timer tick, asking Buildbucket for the build status
[22:07:33.780] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:33.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:23:0) after 1m0s
[22:08:33.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:23:0)
[22:08:33.822] Timer tick, asking Buildbucket for the build status
[22:08:34.009] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:34.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:24:0) after 1m0s
[22:09:34.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:24:0)
[22:09:34.114] Timer tick, asking Buildbucket for the build status
[22:09:34.851] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:25:0) after 1m0s
[22:10:34.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:25:0)
[22:10:34.934] Timer tick, asking Buildbucket for the build status
[22:10:35.313] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:35.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:26:0) after 1m0s
[22:11:35.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:26:0)
[22:11:35.373] Timer tick, asking Buildbucket for the build status
[22:11:35.917] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:35.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:27:0) after 1m0s
[22:12:36.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:27:0)
[22:12:36.042] Timer tick, asking Buildbucket for the build status
[22:12:36.202] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:36.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:28:0) after 1m0s
[22:13:36.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:28:0)
[22:13:36.392] Timer tick, asking Buildbucket for the build status
[22:13:36.715] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:36.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:29:0) after 1m0s
[22:14:37.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:29:0)
[22:14:37.037] Timer tick, asking Buildbucket for the build status
[22:14:37.963] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:37.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:30:0) after 1m0s
[22:15:38.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:30:0)
[22:15:38.169] Timer tick, asking Buildbucket for the build status
[22:15:38.488] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:38.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:31:0) after 1m0s
[22:16:38.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:31:0)
[22:16:38.610] Timer tick, asking Buildbucket for the build status
[22:16:38.767] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:38.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:32:0) after 1m0s
[22:17:38.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:32:0)
[22:17:38.844] Timer tick, asking Buildbucket for the build status
[22:17:39.124] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:39.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:33:0) after 1m0s
[22:18:39.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:33:0)
[22:18:39.181] Timer tick, asking Buildbucket for the build status
[22:18:39.444] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:39.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:34:0) after 1m0s
[22:19:39.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:34:0)
[22:19:39.863] Timer tick, asking Buildbucket for the build status
[22:19:40.567] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:40.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:35:0) after 1m0s
[22:20:40.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:35:0)
[22:20:40.597] Timer tick, asking Buildbucket for the build status
[22:20:40.846] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:36:0) after 1m0s
[22:21:41.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:36:0)
[22:21:41.006] Timer tick, asking Buildbucket for the build status
[22:21:41.305] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:41.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:37:0) after 1m0s
[22:22:41.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:37:0)
[22:22:41.279] Timer tick, asking Buildbucket for the build status
[22:22:45.930] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:45.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:38:0) after 1m0s
[22:23:46.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:38:0)
[22:23:46.220] Timer tick, asking Buildbucket for the build status
[22:23:46.581] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:46.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:39:0) after 1m0s
[22:24:46.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:39:0)
[22:24:46.722] Timer tick, asking Buildbucket for the build status
[22:24:47.485] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:47.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:40:0) after 1m0s
[22:25:47.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:40:0)
[22:25:47.540] Timer tick, asking Buildbucket for the build status
[22:25:48.504] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:48.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:41:0) after 1m0s
[22:26:48.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:41:0)
[22:26:48.662] Timer tick, asking Buildbucket for the build status
[22:26:48.804] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:48.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:42:0) after 1m0s
[22:27:49.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:42:0)
[22:27:49.105] Timer tick, asking Buildbucket for the build status
[22:27:49.384] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:49.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:43:0) after 1m0s
[22:28:49.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:43:0)
[22:28:49.418] Timer tick, asking Buildbucket for the build status
[22:28:49.662] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:49.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:44:0) after 1m0s
[22:29:49.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:44:0)
[22:29:49.692] Timer tick, asking Buildbucket for the build status
[22:29:49.915] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:49.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:45:0) after 1m0s
[22:30:50.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:45:0)
[22:30:50.057] Timer tick, asking Buildbucket for the build status
[22:30:50.384] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:50.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:46:0) after 1m0s
[22:31:50.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:46:0)
[22:31:50.407] Timer tick, asking Buildbucket for the build status
[22:31:51.128] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:51.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:47:0) after 1m0s
[22:32:51.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:47:0)
[22:32:51.201] Timer tick, asking Buildbucket for the build status
[22:32:52.813] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:52.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:48:0) after 1m0s
[22:33:52.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:48:0)
[22:33:52.943] Timer tick, asking Buildbucket for the build status
[22:33:53.505] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:53.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:49:0) after 1m0s
[22:34:53.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:49:0)
[22:34:53.560] Timer tick, asking Buildbucket for the build status
[22:34:54.343] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:54.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:50:0) after 1m0s
[22:35:54.546] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:50:0)
[22:35:54.546] Timer tick, asking Buildbucket for the build status
[22:35:55.004] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:55.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:51:0) after 1m0s
[22:36:55.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:51:0)
[22:36:55.120] Timer tick, asking Buildbucket for the build status
[22:36:55.291] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:55.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:52:0) after 1m0s
[22:37:55.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:52:0)
[22:37:55.403] Timer tick, asking Buildbucket for the build status
[22:37:56.068] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:56.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:53:0) after 1m0s
[22:38:56.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:53:0)
[22:38:56.140] Timer tick, asking Buildbucket for the build status
[22:38:56.861] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:56.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:54:0) after 1m0s
[22:39:56.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:54:0)
[22:39:56.889] Timer tick, asking Buildbucket for the build status
[22:39:57.206] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:57.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:55:0) after 1m0s
[22:40:57.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:55:0)
[22:40:57.281] Timer tick, asking Buildbucket for the build status
[22:40:57.544] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:57.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:56:0) after 1m0s
[22:41:57.575] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:56:0)
[22:41:57.575] Timer tick, asking Buildbucket for the build status
[22:41:57.875] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:57.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:57:0) after 1m0s
[22:42:57.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:57:0)
[22:42:57.902] Timer tick, asking Buildbucket for the build status
[22:42:58.351] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:58.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:58:0) after 1m0s
[22:43:58.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:58:0)
[22:43:58.462] Timer tick, asking Buildbucket for the build status
[22:43:59.042] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:59.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:59:0) after 1m0s
[22:44:59.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:59:0)
[22:44:59.121] Timer tick, asking Buildbucket for the build status
[22:44:59.747] Build 8894422799775787376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:59.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059852862702168688:60:0) after 1m0s
[22:45:47.980] Received PubSub notification, asking Buildbucket for the build status
[22:45:49.848] Build 8894422799775787376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:45:49.848] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576017946893797",
"created_by": "project:chromium",
"created_ts": "1576014438803772",
"id": "8894422799775787376",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c92e7ea27045a04f65d85c4e6add9c7a20483414\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-70-wpz2\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165919, \"got_angle_revision\": \"351307eba33211be31cd1c6ea78ab23be8665f1e\", \"got_dawn_revision\": \"f15fc26d5bf5d71f342eb44109a201aa59ad1e8c\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"c92e7ea27045a04f65d85c4e6add9c7a20483414\", \"got_revision_cp\": \"refs/heads/master@{#723519}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"bb7c3388a50c012123e82491975576bee48a4300\", \"got_v8_revision\": \"a7b3b5faf0c910eb2421d9386bcce32de672ba97\", \"got_v8_revision_cp\": \"refs/heads/8.1.12@{#1}\", \"got_webrtc_revision\": \"ded86c1ad80b1f68ffdca2fdddcb4545a6b02ce6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30050}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c92e7ea27045a04f65d85c4e6add9c7a20483414\", \"swarm_hashes_refs/heads/master(at){#723519}_without_patch\": {\"accessibility_unittests\": \"3fb8f04277f0afb24bb80a6107d717e0ff4e6202\", \"angle_unittests\": \"9ed278b8a73329f38324e83191c001130adf5138\", \"app_shell_unittests\": \"839004509a9331bcf5b697a26ffc73f0e7d19ab4\", \"aura_unittests\": \"7c7a205869d2431eae216b966083d5ecf104d261\", \"base_unittests\": \"74a6672b5bf262c995d353730e14fdf79d1e22f3\", \"base_util_unittests\": \"6bf9824b65559a5e3c1ee086b084ac86c4cf0960\", \"blink_common_unittests\": \"dcc98b24da44533a8b1999a51c7f53fd374e8110\", \"blink_fuzzer_unittests\": \"afa503a7ea10c238bc9722bc4a15f2551e38c9b6\", \"blink_heap_unittests\": \"51145a67d02f4b8510a78746bdfd4391708d845e\", \"blink_platform_unittests\": \"0f0cbc1e2c8ec0297fd10ac68670a85b22b80224\", \"blink_python_tests\": \"3c01e71469755cb9aa11f8190fe10e0faeacaca4\", \"blink_unittests\": \"6afe89f1e789cca414c0dd07ad3be32325aa7f9c\", \"blink_web_tests\": \"09239ee64d14fbe59e3ec950253e9cc702eaa197\", \"boringssl_crypto_tests\": \"2718b71f4a67c8e94a6dd1e8dc2e60599b2e241c\", \"boringssl_ssl_tests\": \"dd3f4445c5fa16ef7e315d0f4bf8317135dabb5d\", \"browser_tests\": \"64bb6d210a26726acb950c3ed0754b047d90bdc6\", \"cacheinvalidation_unittests\": \"0fabada1bd23bf921f872fd17e6471cdd14011cb\", \"capture_unittests\": \"e2f49436f74c0900aaade12eb1d37b3535924c10\", \"cast_unittests\": \"20429949bca5b8aefa5668e0790089a137a882d7\", \"cc_unittests\": \"8114de845afed64e6b8ba098f1f98b7c659cc484\", \"chrome_app_unittests\": \"dc457d804de277f8927b10de672a7694360af98e\", \"chromedriver_unittests\": \"43e9bfb388fa8b06396eef0bde9291e279d76489\", \"components_browsertests\": \"7d51f6845271f1e765120da055ec3934957f6e29\", \"components_unittests\": \"fc1f8d7dfc997583908ff584d67f52661bd3246b\", \"compositor_unittests\": \"370902da2f364532341c9db70dfbf79020bcd5b6\", \"content_browsertests\": \"594749c03f80a38b3cca9778f7e318760702183b\", \"content_shell_crash_test\": \"d6c955208d4df5c9b4b6283c9d4eb50d7e11cbe6\", \"content_unittests\": \"1577b3eb3ff46637bc3c34a1e80ef7663971a90b\", \"crashpad_tests\": \"80af57f6623b768db9a7abd312a95f202dfbf3ef\", \"cronet_tests\": \"52cc60e10b2ea08af44aa52f5fc13ffa79b4aecb\", \"cronet_unittests\": \"0a5173ee6c882a4dc6d6ff51958806757e708ba6\", \"crypto_unittests\": \"4b816e27349ce4fe53ec3b788c33d64b71b495a0\", \"dbus_unittests\": \"a6906aafe34d7158983c773d6c517ad74b401151\", \"device_unittests\": \"f57abaa75c78093de364f1d6bfaf3707188a5fea\", \"devtools_lint_check\": \"a051b2d22eace6515b3043351536095487cf22ec\", \"devtools_type_check\": \"f7b71a790c163265fbaa0f1b27bf107e76f0a8e0\", \"display_unittests\": \"2369aa3a8b4ea51e802850944d183a69e6624c4e\", \"events_unittests\": \"22b72a85d291b87c74a9c7cf70cc4122c871919f\", \"extensions_browsertests\": \"d230dfe8464f7f54ab8bfd49a47fc92352047d76\", \"extensions_unittests\": \"d3bf4f4d31d113873c36d7e7a9e92a9570cf22f1\", \"filesystem_service_unittests\": \"eb5c328bfe118a14b8113d929965c9df6c2fb4a6\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"1e8c7755cf5c116a57a54f95611d113274f726df\", \"gfx_unittests\": \"54e40af019fc8937bef5e656a5d55e5dfd36d36b\", \"gin_unittests\": \"544c5c713b1186ddec3e1992e5ace0b869061f17\", \"google_apis_unittests\": \"750a8c009409ca40f0e9bc17b7374a2d9a246c31\", \"gpu_unittests\": \"a628fc8f1c79a5e62ec39e0d9c55e4dd16ec8d3f\", \"grit_python_unittests\": \"e57825f6dc47aecc895f8acb8b5f5fd25b3001dd\", \"gwp_asan_unittests\": \"acb424e8c48e6babdca6d983a39aeeb40c5fdaa8\", \"headless_browsertests\": \"5230fdfd07db045b089e6eef2a3f8b87cfbe40f1\", \"headless_unittests\": \"c5e4ec199c7d38dc677c2904bc9c8c4f415d78ae\", \"interactive_ui_tests\": \"92f3ed9d6435a5e9827677dec6b1cbe31c61f3b1\", \"ipc_tests\": \"36640e2348722ea4b30ae059b3354d7acf0030f8\", \"jingle_unittests\": \"ac9be0faac91d5bc9032ca5c9ab616e14e1be828\", \"latency_unittests\": \"5ce587e8b1b7ec7aec34366c13d4ae22a79bdd72\", \"libjingle_xmpp_unittests\": \"26c64cff6229c20c04ed6fe931354061eb86a725\", \"media_blink_unittests\": \"8b328e2fc3e4785026b4d980f942b6651733f2d4\", \"media_service_unittests\": \"fe4881d95d1452ff9b45f01b1d8d760b0b814810\", \"media_unittests\": \"64877ce1e7d2ad779b6471ca6465196e0c6e5d6a\", \"message_center_unittests\": \"4563b09c7ec331ab8870316d2a5880f63b963650\", \"metrics_python_tests\": \"8238ce70e0a16651315b300651f11ebc424e2c59\", \"midi_unittests\": \"a72878301ca0ebcb7de08f5793d218885a7c40f4\", \"mojo_core_unittests\": \"406bc2a0b6df151d5878664b7a679a3c14a87fee\", \"mojo_unittests\": \"6ec7db52d5f921645b0f2b7fab3be96ed1845073\", \"nacl_helper_nonsfi_unittests\": \"76b3ef913f787b77177681354f09c56f0665f483\", \"nacl_loader_unittests\": \"a8e1ed6c10df08365376171b630f209fad2bb7f3\", \"native_theme_unittests\": \"e388899f5808f2704546ce4334229385da06079e\", \"net_unittests\": \"f93ce839f81adb3ded52d843b2845d1d9df996e6\", \"pdf_unittests\": \"0e05fac72b5e718baecf27506ef15eea4a873054\", \"perfetto_unittests\": \"a118de110fa55ec6ebcf8bc7326d394219be3f6a\", \"ppapi_unittests\": \"06d139f2aa16a5d7f2fa1a934533272c6108e3d7\", \"printing_unittests\": \"cda8b2fb1d0af28c34677d56a9aaa637b8bf32e4\", \"remoting_unittests\": \"a2eda1fa4e787c19f985673eb4f8397469b6c6d0\", \"sandbox_linux_unittests\": \"5d2613b8bde7d5aa7747467beeeba4bd73a0b2df\", \"service_manager_unittests\": \"a26a4f9f4b31f23309f4520696b05a9e9f947f57\", \"services_unittests\": \"0e322db8a9012316de7e60e40bf325a3f54c1bce\", \"shell_dialogs_unittests\": \"fe115e90dd2aa7e4d142de833fbb334c8bb1f333\", \"skia_unittests\": \"0e601f678877da26f89f353d3905412ecb690f91\", \"snapshot_unittests\": \"178bf59615ad40299ff12c36198ad30b4704455b\", \"sql_unittests\": \"0a17edc89b965a2221282fe616cdfea48ddd716f\", \"storage_unittests\": \"4646f65784881793643510d52559f52915cd72c8\", \"sync_integration_tests\": \"f2cac93d64b29693bf384bff7d32bd0b38dd1207\", \"telemetry_gpu_unittests\": \"c17dd28e7e37917959d42a17a19f0865f3bbb73e\", \"telemetry_perf_unittests\": \"4a8bdf03145b61e9ef4443ad3d5231ee07df4bf1\", \"telemetry_unittests\": \"d2ff7a300823837ba5619495cf2aa9fd2e1d26f5\", \"traffic_annotation_auditor_unittests\": \"da9559e6c9b2bd31571a824d49636732cd8a5566\", \"ui_base_unittests\": \"98f256cd2b8cd88d2d412425547bae803a087027\", \"ui_touch_selection_unittests\": \"d8d070364cfcf2db5628ca04451de6ad84ad21b8\", \"unit_tests\": \"bef9ac78788f494d0dec3eaaa3847d8b4530ea18\", \"url_unittests\": \"c8fcf519e150d1c514f7871a05eae97d0996d5ef\", \"views_perftests\": \"180493472ee11de85c13046345efe6e930af78fa\", \"views_unittests\": \"50242f9a734459860e1f91a86b808f70a8b5c6fc\", \"viz_unittests\": \"43f3d31c05580fcaa9403add4e269f9daa4317fb\", \"vr_common_unittests\": \"d73766c1366737b16c0cc48a03e6e7d04bec0c02\", \"vr_pixeltests\": \"e10e46aa5f586f58c699f9e6392c24ba4c3394cc\", \"webdriver_wpt_tests\": \"b28ee40356d503d8d6e89b01bb2051ba4be34595\", \"weblayer_browsertests\": \"c38460197bed71d5922833c81542064fbe796625\", \"wm_unittests\": \"4670904a5dc031fab79774b37b10bbd76ec8ee82\", \"wtf_unittests\": \"27adaf19fe3f9c70986210f5f70a8a095201c4e4\", \"xr_browser_tests\": \"fd45183167450b2cadfa7be9286fd2b3198b5c01\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_21b7b67cc5140fc86b3165a24b457b619be37de8070af45371e28279dc829f76_v2\", \"builder_40238b720ec4feb1f57c327a3b72021d0e440ababc8fca32fd370c36be82ffcf_v2\", \"builder_4637b11d2a56187e25d14badb4fa154a12143e816e25cb34f5fe38c5c81ebb47_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-70-wpz2\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"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\": [\"4709-f2c88b6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576014725512293",
"status": "COMPLETED",
"status_changed_ts": "1576017947348214",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165919",
"builder:Linux Builder (dbg)",
"buildset:commit/git/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c92e7ea27045a04f65d85c4e6add9c7a20483414",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059852862702168688",
"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/8894422799775787376/+/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:490a9993086cdd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576017947360843",
"url": "https://ci.chromium.org/b/8894422799775787376",
"utcnow_ts": "1576017948875079"
}
}
[22:45:49.848] Invocation finished in 58m33.218802757s with status SUCCEEDED