[22:30:16.349] New invocation is queued and will start shortly
[22:30:17.504] Starting the invocation (attempt 1)
[22:30:17.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:30:17.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:30:17.605] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061480903086735296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"be97f7cf483947f2fb25084cea4c322ada00b681\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061480903086735296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/be97f7cf483947f2fb25084cea4c322ada00b681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be97f7cf483947f2fb25084cea4c322ada00b681",
"gitiles_ref:refs/heads/master"
]
}
[22:30:18.971] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574461817806823",
"id": "8896050840890905360",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be97f7cf483947f2fb25084cea4c322ada00b681\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574461818549224",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165168",
"builder:Linux Builder (dbg)",
"buildset:commit/git/be97f7cf483947f2fb25084cea4c322ada00b681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be97f7cf483947f2fb25084cea4c322ada00b681",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061480903086735296",
"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/8896050840890905360/+/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": "1574461818549399",
"url": "https://ci.chromium.org/b/8896050840890905360",
"utcnow_ts": "1574461818953542"
}
}
[22:30:18.971] Task URL: https://ci.chromium.org/b/8896050840890905360
[22:30:18.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:2:0) after 1m0s
[22:31:19.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:2:0)
[22:31:19.294] Timer tick, asking Buildbucket for the build status
[22:31:19.881] Build 8896050840890905360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:19.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:3:0) after 1m0s
[22:32:20.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:3:0)
[22:32:20.033] Timer tick, asking Buildbucket for the build status
[22:32:20.339] Build 8896050840890905360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:20.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:4:0) after 1m0s
[22:33:20.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:4:0)
[22:33:20.438] Timer tick, asking Buildbucket for the build status
[22:33:20.644] Build 8896050840890905360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:20.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:5:0) after 1m0s
[22:34:20.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:5:0)
[22:34:20.751] Timer tick, asking Buildbucket for the build status
[22:34:24.006] Build 8896050840890905360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:6:0) after 1m0s
[22:35:10.358] Received PubSub notification, asking Buildbucket for the build status
[22:35:10.742] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:6:0)
[22:35:24.225] Timer tick, asking Buildbucket for the build status
[22:35:24.388] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:8:0) after 1m0s
[22:36:24.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:8:0)
[22:36:24.501] Timer tick, asking Buildbucket for the build status
[22:36:25.250] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:25.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:9:0) after 1m0s
[22:37:25.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:9:0)
[22:37:25.313] Timer tick, asking Buildbucket for the build status
[22:37:25.432] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:25.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:10:0) after 1m0s
[22:38:25.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:10:0)
[22:38:25.614] Timer tick, asking Buildbucket for the build status
[22:38:28.703] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:28.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:11:0) after 1m0s
[22:39:28.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:11:0)
[22:39:28.790] Timer tick, asking Buildbucket for the build status
[22:39:29.053] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:12:0) after 1m0s
[22:40:29.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:12:0)
[22:40:29.119] Timer tick, asking Buildbucket for the build status
[22:40:29.386] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:29.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:13:0) after 1m0s
[22:41:29.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:13:0)
[22:41:29.425] Timer tick, asking Buildbucket for the build status
[22:41:29.663] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:29.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:14:0) after 1m0s
[22:42:29.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:14:0)
[22:42:29.732] Timer tick, asking Buildbucket for the build status
[22:42:30.908] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:30.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:15:0) after 1m0s
[22:43:31.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:15:0)
[22:43:31.159] Timer tick, asking Buildbucket for the build status
[22:43:31.408] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:16:0) after 1m0s
[22:44:31.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:16:0)
[22:44:31.461] Timer tick, asking Buildbucket for the build status
[22:44:32.213] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:32.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:17:0) after 1m0s
[22:45:32.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:17:0)
[22:45:32.285] Timer tick, asking Buildbucket for the build status
[22:45:32.542] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:32.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:18:0) after 1m0s
[22:46:32.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:18:0)
[22:46:32.652] Timer tick, asking Buildbucket for the build status
[22:46:32.904] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:32.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:19:0) after 1m0s
[22:47:33.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:19:0)
[22:47:33.027] Timer tick, asking Buildbucket for the build status
[22:47:33.467] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:33.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:20:0) after 1m0s
[22:48:33.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:20:0)
[22:48:33.603] Timer tick, asking Buildbucket for the build status
[22:48:33.901] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:33.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:21:0) after 1m0s
[22:49:33.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:21:0)
[22:49:33.984] Timer tick, asking Buildbucket for the build status
[22:49:34.234] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:34.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:22:0) after 1m0s
[22:50:34.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:22:0)
[22:50:34.289] Timer tick, asking Buildbucket for the build status
[22:50:34.939] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:34.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:23:0) after 1m0s
[22:51:35.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:23:0)
[22:51:35.004] Timer tick, asking Buildbucket for the build status
[22:51:35.246] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:35.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:24:0) after 1m0s
[22:52:35.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:24:0)
[22:52:35.417] Timer tick, asking Buildbucket for the build status
[22:52:36.298] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:36.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:25:0) after 1m0s
[22:53:36.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:25:0)
[22:53:36.327] Timer tick, asking Buildbucket for the build status
[22:53:36.628] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:36.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:26:0) after 1m0s
[22:54:36.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:26:0)
[22:54:36.674] Timer tick, asking Buildbucket for the build status
[22:54:37.172] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:37.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:27:0) after 1m0s
[22:55:37.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:27:0)
[22:55:37.207] Timer tick, asking Buildbucket for the build status
[22:55:37.664] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:37.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:28:0) after 1m0s
[22:56:37.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:28:0)
[22:56:37.898] Timer tick, asking Buildbucket for the build status
[22:56:38.132] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:38.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:29:0) after 1m0s
[22:57:38.420] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:29:0)
[22:57:38.420] Timer tick, asking Buildbucket for the build status
[22:57:38.571] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:38.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:30:0) after 1m0s
[22:58:38.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:30:0)
[22:58:38.916] Timer tick, asking Buildbucket for the build status
[22:58:39.118] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:39.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:31:0) after 1m0s
[22:59:39.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:31:0)
[22:59:39.225] Timer tick, asking Buildbucket for the build status
[22:59:39.490] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:32:0) after 1m0s
[23:00:39.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:32:0)
[23:00:39.599] Timer tick, asking Buildbucket for the build status
[23:00:39.950] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:39.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:33:0) after 1m0s
[23:01:39.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:33:0)
[23:01:39.957] Timer tick, asking Buildbucket for the build status
[23:01:40.705] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:40.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:34:0) after 1m0s
[23:02:40.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:34:0)
[23:02:40.720] Timer tick, asking Buildbucket for the build status
[23:02:41.131] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:41.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:35:0) after 1m0s
[23:03:41.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:35:0)
[23:03:41.145] Timer tick, asking Buildbucket for the build status
[23:03:41.326] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:41.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:36:0) after 1m0s
[23:04:41.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:36:0)
[23:04:41.357] Timer tick, asking Buildbucket for the build status
[23:04:44.895] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:37:0) after 1m0s
[23:05:45.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:37:0)
[23:05:45.068] Timer tick, asking Buildbucket for the build status
[23:05:45.541] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:45.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:38:0) after 1m0s
[23:06:45.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:38:0)
[23:06:45.615] Timer tick, asking Buildbucket for the build status
[23:06:45.843] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:39:0) after 1m0s
[23:07:46.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:39:0)
[23:07:46.015] Timer tick, asking Buildbucket for the build status
[23:07:47.098] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:47.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:40:0) after 1m0s
[23:08:47.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:40:0)
[23:08:47.254] Timer tick, asking Buildbucket for the build status
[23:08:47.692] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:47.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:41:0) after 1m0s
[23:09:47.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:41:0)
[23:09:47.688] Timer tick, asking Buildbucket for the build status
[23:09:49.226] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:42:0) after 1m0s
[23:10:49.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:42:0)
[23:10:49.294] Timer tick, asking Buildbucket for the build status
[23:10:49.552] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:49.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:43:0) after 1m0s
[23:11:49.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:43:0)
[23:11:49.573] Timer tick, asking Buildbucket for the build status
[23:11:49.987] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:49.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:44:0) after 1m0s
[23:12:50.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:44:0)
[23:12:50.394] Timer tick, asking Buildbucket for the build status
[23:12:51.835] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:51.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:45:0) after 1m0s
[23:13:51.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:45:0)
[23:13:51.869] Timer tick, asking Buildbucket for the build status
[23:13:52.943] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:52.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:46:0) after 1m0s
[23:14:53.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:46:0)
[23:14:53.045] Timer tick, asking Buildbucket for the build status
[23:14:53.873] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:53.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:47:0) after 1m0s
[23:15:54.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:47:0)
[23:15:54.016] Timer tick, asking Buildbucket for the build status
[23:15:54.408] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:48:0) after 1m0s
[23:16:54.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:48:0)
[23:16:54.601] Timer tick, asking Buildbucket for the build status
[23:16:55.420] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:55.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:49:0) after 1m0s
[23:17:55.453] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:49:0)
[23:17:55.453] Timer tick, asking Buildbucket for the build status
[23:17:55.689] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:55.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:50:0) after 1m0s
[23:18:55.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:50:0)
[23:18:55.769] Timer tick, asking Buildbucket for the build status
[23:18:56.439] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:56.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:51:0) after 1m0s
[23:19:56.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:51:0)
[23:19:56.563] Timer tick, asking Buildbucket for the build status
[23:19:56.777] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:56.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:52:0) after 1m0s
[23:20:56.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:52:0)
[23:20:56.931] Timer tick, asking Buildbucket for the build status
[23:20:58.349] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:58.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:53:0) after 1m0s
[23:21:58.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:53:0)
[23:21:58.480] Timer tick, asking Buildbucket for the build status
[23:22:00.103] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:00.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:54:0) after 1m0s
[23:23:00.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:54:0)
[23:23:00.199] Timer tick, asking Buildbucket for the build status
[23:23:01.318] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:01.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:55:0) after 1m0s
[23:24:01.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:55:0)
[23:24:01.618] Timer tick, asking Buildbucket for the build status
[23:24:01.903] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:01.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:56:0) after 1m0s
[23:25:01.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:56:0)
[23:25:01.898] Timer tick, asking Buildbucket for the build status
[23:25:02.342] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:02.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:57:0) after 1m0s
[23:26:02.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:57:0)
[23:26:02.429] Timer tick, asking Buildbucket for the build status
[23:26:02.651] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:58:0) after 1m0s
[23:27:02.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:58:0)
[23:27:02.811] Timer tick, asking Buildbucket for the build status
[23:27:02.981] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:02.981] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:59:0) after 1m0s
[23:28:03.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:59:0)
[23:28:03.130] Timer tick, asking Buildbucket for the build status
[23:28:03.387] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:03.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:60:0) after 1m0s
[23:29:03.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:60:0)
[23:29:03.410] Timer tick, asking Buildbucket for the build status
[23:29:03.685] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:03.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:61:0) after 1m0s
[23:30:03.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:61:0)
[23:30:03.748] Timer tick, asking Buildbucket for the build status
[23:30:03.964] Build 8896050840890905360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:03.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061480903086735296:62:0) after 1m0s
[23:30:11.082] Received PubSub notification, asking Buildbucket for the build status
[23:30:11.357] Build 8896050840890905360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:30:11.357] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574465410028226",
"created_by": "project:chromium",
"created_ts": "1574461817806823",
"id": "8896050840890905360",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be97f7cf483947f2fb25084cea4c322ada00b681\"}}",
"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-119-hzp0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165168, \"got_angle_revision\": \"4c7db77e0185d4c41465c4d0fd97845fe775c44d\", \"got_dawn_revision\": \"f6eb890f4c9c3d67478c832103e1332e585aa132\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"be97f7cf483947f2fb25084cea4c322ada00b681\", \"got_revision_cp\": \"refs/heads/master@{#718323}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"c8d4d4254ca1b38f64ca852e2467dad59a1e9d6c\", \"got_v8_revision\": \"dd8cccb78854b7d7436ff76f841e81ff7a105faf\", \"got_v8_revision_cp\": \"refs/heads/8.0.348@{#1}\", \"got_webrtc_revision\": \"e43b53126600d247fbfde8b5c29b22ee22e59c86\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29873}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"be97f7cf483947f2fb25084cea4c322ada00b681\", \"swarm_hashes_refs/heads/master(at){#718323}_without_patch\": {\"accessibility_unittests\": \"5e87e1d763f68dc3a8c14ab569b567e5d4ee6278\", \"angle_unittests\": \"91ea51cb9b75bb83d891add5a74fa6f11abb03f1\", \"app_shell_unittests\": \"cf2265de53053a15990018a75fbf5fc63f757392\", \"aura_unittests\": \"853b898bf730492801376c0666bde5479c0eb531\", \"base_unittests\": \"5d408ec58ea0b2ec0ffde861116d0f46e3850a32\", \"base_util_unittests\": \"c95898ecafbe5f06865628b13ab9d8b04d2c7f4e\", \"blink_common_unittests\": \"57929d61152f0ef93bf67d42a3eac383bc343d07\", \"blink_fuzzer_unittests\": \"51e5ffe9a3a9a5f9187012b5c61c1ce89be78151\", \"blink_heap_unittests\": \"ff9c1112b00c1630f58a8f8b67c34ed11b01bb8f\", \"blink_platform_unittests\": \"aba273771e63ece94be2543881ac07b37e875446\", \"blink_python_tests\": \"b8ff3142de5bc969cf8dae1ed6d91fdfe330d693\", \"blink_unittests\": \"4c683cb100354d8a9143a36639c4b7130a0f39be\", \"blink_web_tests_exparchive\": \"43b7a9ecbff0f4d7e4f72bc07fa8ffcbd295b99c\", \"boringssl_crypto_tests\": \"c7d3233d6d960e7fb0dbac6e09c4c62edd8dc439\", \"boringssl_ssl_tests\": \"0feef370ae26d3b823bb9bbeff2b952712085e7b\", \"browser_tests\": \"8a0b19c0bcfd8a32d62d0e12206c073f987d80e4\", \"cacheinvalidation_unittests\": \"16255181db682d33d12a7edf124bbd586d41d1ab\", \"capture_unittests\": \"3edad48607f8ed854ef4f9e5ee95313d415210f4\", \"cast_unittests\": \"bf5fabea39894ad5c9c80d56c0c996ee46e28fa8\", \"cc_unittests\": \"714477066dcbaeaac991285d64d5e7de06c87813\", \"chrome_app_unittests\": \"bed791f70837c7602660cb07c66d9f7ca8ae7f5c\", \"chromedriver_unittests\": \"de571beaaf5de493cba1183af9e6201fcce276ca\", \"color_unittests\": \"58df6e523fa76b50e8f5977a4b487dfcea678214\", \"components_browsertests\": \"2feb357affcdc78b6674976b07bd30c8b1c48fe6\", \"components_unittests\": \"2351cf989aa9f5ae37d621cf9fde78f4d08d13fc\", \"compositor_unittests\": \"8895ae0c9837ac11ee2f1afb097af049b5991d9d\", \"content_browsertests\": \"23a86471bf5f3b8589b7662ffe056f829c9dbaed\", \"content_shell_crash_test\": \"cc4f342b55a5b779d1997e1ed24d9ba721f00476\", \"content_unittests\": \"3653036c0501d30c9865ec198e30ecc3ce973434\", \"crashpad_tests\": \"81073c58b370d51f57a740a3f32f45eca1dac8a2\", \"cronet_tests\": \"50bf0775a460342ba368a0dac9cb45b1bf47a18f\", \"cronet_unittests\": \"5f30ca60c19a9d7cc4938491473f675119b7df9f\", \"crypto_unittests\": \"254d914a5029799beaed5c596b7907ecceb5cfe6\", \"dbus_unittests\": \"a12278cb225dd978bae44290c7b3b3e3417095f7\", \"device_unittests\": \"06c91243b44d0474cdd24c3a3ed1f3cdf18736c9\", \"devtools_lint_check\": \"5bcbc1a1768a1980ddec922a979cebc08a7d380c\", \"devtools_type_check\": \"de90d91c8eaba6382055ec35a0fb769e2d503ab8\", \"display_unittests\": \"38e15dd748a595c4fb1f1eb469b01ea05ee4e4b3\", \"events_unittests\": \"1775fe029729293560e7be63f000d0ae28c3be06\", \"extensions_browsertests\": \"cbf70b60019eeffc6073ba1cd93ce3fd5b68b5aa\", \"extensions_unittests\": \"12d6c85e117ae6d43db1859f7b0886ab1239e0b6\", \"filesystem_service_unittests\": \"713ac7c1f268fdd7973659276ee7ad08baa5a77a\", \"flatbuffers_unittests\": \"68877d8b1262b0aa771438df1015a16a10098d5e\", \"gcm_unit_tests\": \"7f70a6f2241be9f3b165b3b6015d97e98c71e66d\", \"gfx_unittests\": \"f799b6a0eb7077ddb3c320b797ce8086601209bb\", \"gin_unittests\": \"657e1729ab1d803929623bbd72d72e1297b409ae\", \"google_apis_unittests\": \"970ff35f3f4fe8730c48eea975fb712b069a51a4\", \"gpu_unittests\": \"b2af6b1d4e9bf66a19e101c6614030177bdfc66a\", \"grit_python_unittests\": \"4267cdfe96cbb5881cc25cc5b15aab4b2f28c5f5\", \"gwp_asan_unittests\": \"5a25c62a968bbc29d39b786c19f5ce1ffb5738d0\", \"headless_browsertests\": \"e22aadf3cc1558ecd70fc238bb722bb774f07d43\", \"headless_unittests\": \"afa5ac05ead94d0a2c2682ff0bb7368b1a3d2c53\", \"interactive_ui_tests\": \"e24e94e304fbaaa0ddd4d61d1d4e9368a4687d61\", \"ipc_tests\": \"0f9d7ddc6d16ee974a03c8f6272926b35a760110\", \"jingle_unittests\": \"8f8681f7079caf4ef23e97177a7aa1360215e5b3\", \"latency_unittests\": \"2e1a3c0ded7000cf9107f700c20f2bc81abf511e\", \"libjingle_xmpp_unittests\": \"ea7a9f3e99086768e209bcf92a67af6a0fb2b8e5\", \"media_blink_unittests\": \"83aec67d1be1108011b812e371d05eca21dc4aaa\", \"media_service_unittests\": \"bfedf853ffdd18aa47474bf434710e4b500e9763\", \"media_unittests\": \"161928fb76015e4736d60ca317da2c79bb5559f1\", \"message_center_unittests\": \"9fd53c155b7d8f2a033703cf78731857917c5faa\", \"metrics_python_tests\": \"f12e3e29a77bf8ba1bec22abaf19fcafed4c1f07\", \"midi_unittests\": \"8451b91520bc1b8770d0e5cce3ee319ce65e3951\", \"mojo_core_unittests\": \"bb2aff7bfade9a4d0d7d45eca801f6e266f77961\", \"mojo_unittests\": \"fa269fdf30d9cdf116cc8c8755657ff826771e1a\", \"nacl_helper_nonsfi_unittests\": \"680e4e13f90c999c9c3a381c4c2c3be4e5f0905f\", \"nacl_loader_unittests\": \"85ccf65f8b155a17ba41f0cb0704139b8f6ba407\", \"native_theme_unittests\": \"4fa22783086c82b5f56fa635261f51d11db84d0f\", \"net_unittests\": \"f5ea52e9b765318e538361209aafe6edf79acc44\", \"pdf_unittests\": \"4d248223527af770feaff2bdcfb24ddceb8218f6\", \"perfetto_unittests\": \"277e7da4c3ee436ea7ecbe5e683bcbeb8751dbc5\", \"ppapi_unittests\": \"a637a64278b5f47fe0edaff25be05b7da53ce6b0\", \"printing_unittests\": \"7f814a9704f5990d23e5ebc30d4d6895589ba205\", \"remoting_unittests\": \"bf079329aeee054fcb6247f0a3075ecbe26d552d\", \"sandbox_linux_unittests\": \"2029d3f3c8425c553051307f7267eb13f73acd99\", \"service_manager_unittests\": \"b79babd9dab4da0fd08d9839f2164fc4621ea2e3\", \"services_unittests\": \"2383b50b00e2eab0b4bc6a716ecf7709d7891e4b\", \"shell_dialogs_unittests\": \"d3a7454a780323d0d2ebeb0538e15aeb037d484a\", \"skia_unittests\": \"f5d52cb194f1fceb78462cad17a426e463bdcfd2\", \"snapshot_unittests\": \"ecbb993ce68c6373eddb027031af324ade8b9da9\", \"sql_unittests\": \"85937253afb6f4b5a352efa5efc5262cce767f75\", \"storage_unittests\": \"8cbc2956a119faf85cb9537d1c16ec41d8f34432\", \"sync_integration_tests\": \"ab3106d83ef2933841d4751e8eaf3caf6c10e5a7\", \"telemetry_gpu_unittests\": \"8c157fa9b59edd3d11a2c23a3e5e4a69022e5d13\", \"telemetry_perf_unittests\": \"cf47433d30a1abbe969096227ec09f331815cecd\", \"telemetry_unittests\": \"6d8ac614133f7fe7bed7d8eb3c618c2441a9d410\", \"traffic_annotation_auditor_unittests\": \"422eb8610930396082bb531d50e039a93a4d4371\", \"ui_base_unittests\": \"edae9bc4546fd9ab46e83a6627d8db2313a39839\", \"ui_touch_selection_unittests\": \"cfd6260b70eeba9d7fdbca954205b7ca0916b0d2\", \"unit_tests\": \"101b156f6ac3508939067a4ce4d6dc310f087d9e\", \"url_unittests\": \"d4d3d99324c663d1fcbb9769e140dba56fb12c51\", \"views_perftests\": \"43356bba7ff63e861eadebc2acd45d30acbcc5ad\", \"views_unittests\": \"60368bd626eaf52fe4ad7b1e1c512722ac7bbb00\", \"viz_unittests\": \"57c0ceceb1904caa3a419a5b512b97d2a4f48395\", \"vr_common_unittests\": \"300f207b993d7b04a4ce0510cc1cee47dc8d7450\", \"vr_pixeltests\": \"e1b847e09635e971f6deff1d15a8fc2b6d4316e0\", \"webdriver_wpt_tests\": \"e7d4abe90939014dff3efbef529f58a9ef5db26c\", \"weblayer_browsertests\": \"8d1b17a9e6f9cdc13b43337e9770deb1e82e2352\", \"wm_unittests\": \"6d96d060e6d3f4d364a07222389eec6f702f9f45\", \"wtf_unittests\": \"77128c7689703aa9d6cfac0298198f90cf4b21f5\", \"xr_browser_tests\": \"98b2b917884c375865fd8c6f4446e7df32f87b21\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"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-119-hzp0\"], \"image\": [\"chrome-xenial-19112100-3085293b610\"], \"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\": [\"4651-c74e250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574462108296469",
"status": "COMPLETED",
"status_changed_ts": "1574465410285461",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165168",
"builder:Linux Builder (dbg)",
"buildset:commit/git/be97f7cf483947f2fb25084cea4c322ada00b681",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/be97f7cf483947f2fb25084cea4c322ada00b681",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061480903086735296",
"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/8896050840890905360/+/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:48ae0e8b66741010",
"user_agent:luci-scheduler"
],
"updated_ts": "1574465410289988",
"url": "https://ci.chromium.org/b/8896050840890905360",
"utcnow_ts": "1574465411342551"
}
}
[23:30:11.357] Invocation finished in 59m55.028904019s with status SUCCEEDED