[21:40:14.377] New invocation is queued and will start shortly
[21:40:15.638] Starting the invocation (attempt 1)
[21:40:15.824] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:40:15.824] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:40:15.824] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021802579601112736",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021802579601112736",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"gitiles_ref:refs/heads/master"
]
}
[21:40:17.419] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612302015974208",
"id": "8856372517256390368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612302017043704",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180210",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021802579601112736",
"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/8856372517256390368/+/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": "1612302017043827",
"url": "https://ci.chromium.org/b/8856372517256390368",
"utcnow_ts": "1612302017393699"
}
}
[21:40:17.419] Task URL: https://ci.chromium.org/b/8856372517256390368
[21:40:17.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:2:0) after 1m0s
[21:40:19.173] Received PubSub notification, asking Buildbucket for the build status
[21:40:19.277] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:17.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:2:0)
[21:41:17.511] Timer tick, asking Buildbucket for the build status
[21:41:17.630] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:17.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:4:0) after 1m0s
[21:42:17.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:4:0)
[21:42:17.822] Timer tick, asking Buildbucket for the build status
[21:42:18.014] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:5:0) after 1m0s
[21:43:18.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:5:0)
[21:43:18.113] Timer tick, asking Buildbucket for the build status
[21:43:18.230] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:18.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:6:0) after 1m0s
[21:44:18.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:6:0)
[21:44:18.337] Timer tick, asking Buildbucket for the build status
[21:44:18.444] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:18.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:7:0) after 1m0s
[21:45:18.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:7:0)
[21:45:18.509] Timer tick, asking Buildbucket for the build status
[21:45:18.663] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:18.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:8:0) after 1m0s
[21:46:18.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:8:0)
[21:46:18.647] Timer tick, asking Buildbucket for the build status
[21:46:19.315] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:19.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:9:0) after 1m0s
[21:47:19.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:9:0)
[21:47:19.424] Timer tick, asking Buildbucket for the build status
[21:47:19.750] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:19.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:10:0) after 1m0s
[21:48:19.790] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:10:0)
[21:48:19.790] Timer tick, asking Buildbucket for the build status
[21:48:19.852] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:19.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:11:0) after 1m0s
[21:49:19.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:11:0)
[21:49:19.869] Timer tick, asking Buildbucket for the build status
[21:49:19.933] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:19.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:12:0) after 1m0s
[21:50:19.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:12:0)
[21:50:19.963] Timer tick, asking Buildbucket for the build status
[21:50:20.072] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:13:0) after 1m0s
[21:51:20.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:13:0)
[21:51:20.093] Timer tick, asking Buildbucket for the build status
[21:51:20.216] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:14:0) after 1m0s
[21:52:20.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:14:0)
[21:52:20.244] Timer tick, asking Buildbucket for the build status
[21:52:20.358] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:20.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:15:0) after 1m0s
[21:53:20.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:15:0)
[21:53:20.342] Timer tick, asking Buildbucket for the build status
[21:53:20.418] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:20.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:16:0) after 1m0s
[21:54:20.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:16:0)
[21:54:20.438] Timer tick, asking Buildbucket for the build status
[21:54:20.510] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:20.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:17:0) after 1m0s
[21:55:20.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:17:0)
[21:55:20.669] Timer tick, asking Buildbucket for the build status
[21:55:20.782] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:20.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:18:0) after 1m0s
[21:56:20.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:18:0)
[21:56:20.812] Timer tick, asking Buildbucket for the build status
[21:56:20.896] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:20.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:19:0) after 1m0s
[21:57:20.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:19:0)
[21:57:20.951] Timer tick, asking Buildbucket for the build status
[21:57:21.045] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:21.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:20:0) after 1m0s
[21:58:21.041] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:20:0)
[21:58:21.041] Timer tick, asking Buildbucket for the build status
[21:58:21.135] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:21.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:21:0) after 1m0s
[21:59:21.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:21:0)
[21:59:21.234] Timer tick, asking Buildbucket for the build status
[21:59:21.341] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:21.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:22:0) after 1m0s
[22:00:21.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:22:0)
[22:00:21.328] Timer tick, asking Buildbucket for the build status
[22:00:21.670] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:21.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:23:0) after 1m0s
[22:01:21.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:23:0)
[22:01:21.723] Timer tick, asking Buildbucket for the build status
[22:01:21.783] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:21.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:24:0) after 1m0s
[22:02:21.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:24:0)
[22:02:21.834] Timer tick, asking Buildbucket for the build status
[22:02:21.950] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:21.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:25:0) after 1m0s
[22:03:21.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:25:0)
[22:03:21.993] Timer tick, asking Buildbucket for the build status
[22:03:22.184] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:22.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:26:0) after 1m0s
[22:04:22.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:26:0)
[22:04:22.231] Timer tick, asking Buildbucket for the build status
[22:04:22.297] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:22.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:27:0) after 1m0s
[22:05:22.368] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:27:0)
[22:05:22.368] Timer tick, asking Buildbucket for the build status
[22:05:22.505] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:22.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:28:0) after 1m0s
[22:06:22.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:28:0)
[22:06:22.542] Timer tick, asking Buildbucket for the build status
[22:06:22.662] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:22.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:29:0) after 1m0s
[22:07:22.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:29:0)
[22:07:22.699] Timer tick, asking Buildbucket for the build status
[22:07:22.757] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:22.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:30:0) after 1m0s
[22:08:22.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:30:0)
[22:08:22.826] Timer tick, asking Buildbucket for the build status
[22:08:22.915] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:22.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:31:0) after 1m0s
[22:09:22.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:31:0)
[22:09:22.971] Timer tick, asking Buildbucket for the build status
[22:09:23.042] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:23.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:32:0) after 1m0s
[22:10:23.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:32:0)
[22:10:23.086] Timer tick, asking Buildbucket for the build status
[22:10:23.194] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:23.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:33:0) after 1m0s
[22:11:23.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:33:0)
[22:11:23.227] Timer tick, asking Buildbucket for the build status
[22:11:23.287] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:23.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:34:0) after 1m0s
[22:12:23.361] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:34:0)
[22:12:23.361] Timer tick, asking Buildbucket for the build status
[22:12:23.472] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:23.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:35:0) after 1m0s
[22:13:23.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:35:0)
[22:13:23.492] Timer tick, asking Buildbucket for the build status
[22:13:23.571] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:23.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:36:0) after 1m0s
[22:14:23.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:36:0)
[22:14:23.641] Timer tick, asking Buildbucket for the build status
[22:14:23.942] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:23.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:37:0) after 1m0s
[22:15:24.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:37:0)
[22:15:24.730] Timer tick, asking Buildbucket for the build status
[22:15:24.885] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:24.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:38:0) after 1m0s
[22:16:24.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:38:0)
[22:16:24.981] Timer tick, asking Buildbucket for the build status
[22:16:25.508] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:25.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:39:0) after 1m0s
[22:17:25.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:39:0)
[22:17:25.650] Timer tick, asking Buildbucket for the build status
[22:17:25.705] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:25.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:40:0) after 1m0s
[22:18:25.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:40:0)
[22:18:25.725] Timer tick, asking Buildbucket for the build status
[22:18:25.786] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:25.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:41:0) after 1m0s
[22:19:26.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:41:0)
[22:19:26.020] Timer tick, asking Buildbucket for the build status
[22:19:26.080] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:26.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:42:0) after 1m0s
[22:20:26.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:42:0)
[22:20:26.171] Timer tick, asking Buildbucket for the build status
[22:20:26.330] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:26.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:43:0) after 1m0s
[22:21:26.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:43:0)
[22:21:26.454] Timer tick, asking Buildbucket for the build status
[22:21:26.564] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:26.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:44:0) after 1m0s
[22:22:26.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:44:0)
[22:22:26.691] Timer tick, asking Buildbucket for the build status
[22:22:26.843] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:26.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:45:0) after 1m0s
[22:23:26.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:45:0)
[22:23:26.891] Timer tick, asking Buildbucket for the build status
[22:23:27.233] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:27.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:46:0) after 1m0s
[22:24:27.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:46:0)
[22:24:27.328] Timer tick, asking Buildbucket for the build status
[22:24:27.459] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:27.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:47:0) after 1m0s
[22:25:27.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:47:0)
[22:25:27.503] Timer tick, asking Buildbucket for the build status
[22:25:27.678] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:27.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:48:0) after 1m0s
[22:26:27.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:48:0)
[22:26:27.654] Timer tick, asking Buildbucket for the build status
[22:26:27.732] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:27.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:49:0) after 1m0s
[22:27:27.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:49:0)
[22:27:27.776] Timer tick, asking Buildbucket for the build status
[22:27:27.876] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:27.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:50:0) after 1m0s
[22:28:28.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:50:0)
[22:28:28.028] Timer tick, asking Buildbucket for the build status
[22:28:28.496] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:28.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:51:0) after 1m0s
[22:29:28.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:51:0)
[22:29:28.566] Timer tick, asking Buildbucket for the build status
[22:29:28.669] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:28.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:52:0) after 1m0s
[22:30:28.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:52:0)
[22:30:28.691] Timer tick, asking Buildbucket for the build status
[22:30:28.854] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:28.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:53:0) after 1m0s
[22:31:28.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:53:0)
[22:31:28.882] Timer tick, asking Buildbucket for the build status
[22:31:29.160] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:29.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:54:0) after 1m0s
[22:32:29.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:54:0)
[22:32:29.176] Timer tick, asking Buildbucket for the build status
[22:32:29.238] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:29.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:55:0) after 1m0s
[22:33:29.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:55:0)
[22:33:29.258] Timer tick, asking Buildbucket for the build status
[22:33:29.329] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:29.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:56:0) after 1m0s
[22:34:29.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:56:0)
[22:34:29.448] Timer tick, asking Buildbucket for the build status
[22:34:29.509] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:29.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:57:0) after 1m0s
[22:35:29.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:57:0)
[22:35:29.605] Timer tick, asking Buildbucket for the build status
[22:35:29.853] Build 8856372517256390368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:29.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021802579601112736:58:0) after 1m0s
[22:36:21.346] Received PubSub notification, asking Buildbucket for the build status
[22:36:21.435] Build 8856372517256390368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:36:21.435] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612305378702510",
"created_by": "project:chromium",
"created_ts": "1612302015974208",
"id": "8856372517256390368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\", \"project\": \"chromium/src\"}], \"$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-60-0fyf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180210, \"got_angle_revision\": \"7476121298462f0e15b3ed85acc54188c4d31859\", \"got_dawn_revision\": \"dd988bca844a6003a7249221680fc3e4f2d8b445\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\", \"got_revision_cp\": \"refs/heads/master@{#849772}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"90801c815c96dd55224b008d11b63d51400b049d\", \"got_v8_revision_cp\": \"refs/heads/9.0.115@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f0d0c221e8e872f878d0f0f08f349bfab4b242b\", \"swarm_hashes_refs/heads/master(at){#849772}_without_patch\": {\"absl_hardening_tests\": \"9e7a0c02e96ae4bd267954e671f2917389c76aa4\", \"accessibility_unittests\": \"b61dabd87a74817cf70a67931ccaa3bc91ccfa55\", \"angle_unittests\": \"81e64395370051cc70f74b6c5776f3563d72916c\", \"app_shell_unittests\": \"4ca6bf37817b4e8746df608b0674c0195bdec643\", \"aura_unittests\": \"00b80db91aba5748c6873c866c092e792b8f67eb\", \"base_unittests\": \"395ccf6e66a405db93802d378127f4f6a35acf36\", \"base_util_unittests\": \"c3d82ef65871b87b60a141788977935df4e665df\", \"blink_common_unittests\": \"81fbea64863332ac8827bf818ab216566ebd5659\", \"blink_fuzzer_unittests\": \"b20a02273617b5cc52d82dac56f1dd4c0a63952c\", \"blink_heap_unittests\": \"64b144b495b1c28799f7b0ef453fcb33cf1c76df\", \"blink_platform_unittests\": \"1e6408a0bf97385b99beac6e6b09794d5e189026\", \"blink_python_tests\": \"981c602f4e390e6950868ab9ee515d2d165542fd\", \"blink_unittests\": \"0703c258a7234feb7f734b6ff9d13f5829dcf118\", \"blink_web_tests\": \"85a200decffd7f1d87adf989aeaa8617ab3e0c9b\", \"boringssl_crypto_tests\": \"843afe89cf8e4bf573a98497a0a20159c16d4ebd\", \"boringssl_ssl_tests\": \"96f1456e6a1630b8e9396bfb1283dcb3a2dcf55c\", \"browser_tests\": \"12346e6effe3a48bbc228459fcb7cbf8058d3349\", \"capture_unittests\": \"34ac2c350dbda4ff245f81121883ec5e7d137d9f\", \"cast_unittests\": \"ee64b50620a304f5a84a9b802b10a5bf9a0e6cf1\", \"cc_unittests\": \"a035ce2ef5ff4fdac96b06fa5c7814d13ef1e783\", \"chrome_app_unittests\": \"5f5bfae7204d9f19e8747ce5197d1fddbd680f7b\", \"chromedriver_unittests\": \"115d2b9a2eed9b9794be7c4be4a1765b2567d70e\", \"components_browsertests\": \"ab42b4bb915b07bfbe3accd1ea6c872d2ba9a7ca\", \"components_unittests\": \"06d038b2c74938dc4956986bbcc21e064043af91\", \"compositor_unittests\": \"1730979818976a0018da229aa38b66db4452c367\", \"content_browsertests\": \"92d1c5b2f77e3f642e1d247db7f7023eac1d703d\", \"content_shell_crash_test\": \"39d9beafda17a9951b85336c93e3ee045a1cdf87\", \"content_unittests\": \"25cf5810fb57856f7bb9ee3c7343d84ff50a05d3\", \"crashpad_tests\": \"df9bc31ca71081d8f1323362b341a6767f2f10a1\", \"cronet_tests\": \"2da616b4d323577d538596ba93cee6018f375796\", \"cronet_unittests\": \"d93992f4f3310f6a49c6bb96fe4eb595f1143880\", \"crypto_unittests\": \"9a0d38657460a758f6dce6eb24b160bacd10a5dc\", \"dbus_unittests\": \"03cdf77f421041fd11d3d2ccc879cc3168af518a\", \"device_unittests\": \"75590b68ae1cacd8b8df121e53ec3770fb7310aa\", \"display_unittests\": \"842742879b53b0182a915b3fd2466047e1ea7948\", \"events_unittests\": \"9b71b2466859d80b7025f4a8c84e09933b7d55b5\", \"extensions_browsertests\": \"f3335f09996be04321cea95f7db53c1f165c3f12\", \"extensions_unittests\": \"0d1e1b4a043f84ade57c739eaffe6ff6f898cc49\", \"filesystem_service_unittests\": \"9a73310e6bf727032223851b3961faf161044c5e\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"342c18d690e50c9fd2b347b40aece5faf28ed0fd\", \"gfx_unittests\": \"b5272aae6273c298fc48ee3cc4c970a18bfb8391\", \"gin_unittests\": \"496c6bba21132a8fc9bcaee297e0c97c2cc94ca8\", \"google_apis_unittests\": \"79584f867d0fe58f8a0ff1e4c324a20508955274\", \"gpu_unittests\": \"8e372c420611dcc3468e5ffb959d126afb3dab83\", \"grit_python_unittests\": \"0532571aa381198443b89c1ec536d42cf612fc22\", \"gwp_asan_unittests\": \"ce0a7acba820a0ae40754b785320266549ec65c8\", \"headless_browsertests\": \"97ee24465290312557f6721fb4300261050711c6\", \"headless_unittests\": \"7c596eeeb3a03418b3f13ae4ee33d7d06019715b\", \"interactive_ui_tests\": \"1d591aa5bbe866a8e741df356604e4171eda5cae\", \"ipc_tests\": \"8c0b66235e4fc272689adf90fc3c91f265e95cad\", \"jingle_unittests\": \"2b78bba088720ded0c2077be09d49003ec39cd3a\", \"latency_unittests\": \"4b6342ee07ef5aac4369205165e45f84d0bf12eb\", \"libjingle_xmpp_unittests\": \"105af0a13fbd4f61abfc13f6c41c5718ac138428\", \"liburlpattern_unittests\": \"03c0fe8d65aecf986d4b02e0fec8368533bca4e9\", \"media_blink_unittests\": \"ea6ab90517f93c8836025f6f225c01cd5975b1d1\", \"media_unittests\": \"0678406df5d423983003b975ec09ee7e77656677\", \"message_center_unittests\": \"962892641adbaceb65805074e0f7e8bf5690770f\", \"metrics_python_tests\": \"9aab73c0296b32d83e41a244f7654c2a41232fce\", \"midi_unittests\": \"b380f454f78a982a775bea546576cde61f86ff23\", \"mojo_core_unittests\": \"c2112b393f6b07f8249e95c6539d7d4716758e02\", \"mojo_unittests\": \"717f5ead086bfabc58645621e5227f667b69eef2\", \"nacl_helper_nonsfi_unittests\": \"bafa9df896bfae54a550a9cc3f9372275608506e\", \"nacl_loader_unittests\": \"879cec1789fcb2096fda156f6df89980f0a5ec51\", \"native_theme_unittests\": \"e8c1508a1bf11e625f1f5f4e6aaf249cba51af00\", \"net_unittests\": \"5f1ff239309850ca4b6be64505dd72caaf1dfbed\", \"openscreen_unittests\": \"ad30d1dd8854920c57fe94d14847c3fc5b7de8f5\", \"pdf_unittests\": \"24cce66d1160a0da8bef6db3232a5fbf70246528\", \"perfetto_unittests\": \"f3828ed3fda7768f35de47d83fd4b64995706d9f\", \"ppapi_unittests\": \"6e32c292d1bec5ddc45882316c284ca6ce3e8e12\", \"printing_unittests\": \"c607bcd732c75cf49c205f5ab6418fb8fb128ccc\", \"remoting_unittests\": \"9d6d3e4aa13beb80d4605a41d4a9079437247064\", \"sandbox_linux_unittests\": \"391f1bda018417af6bd79eed511602dac0ff0a29\", \"service_manager_unittests\": \"3fdd7856019c12bd4a53888f6bb75b845fc8197d\", \"services_unittests\": \"ddc464792ba54aae1c3d97b68eea70da4be947be\", \"shell_dialogs_unittests\": \"ee31f6b7818601a32e18dfcd87a00af2938f0b93\", \"skia_unittests\": \"f0e6de0d08d1b2bf2f929ecab168e79384e18265\", \"snapshot_unittests\": \"19bbbae90c5d089bb9050206c2b47b4c3df7fc95\", \"sql_unittests\": \"243f3d2a150de4f8f28ffc4735c07c9b9ebe6aa6\", \"storage_unittests\": \"6b0f7b557ef9552e19e1edf18182b7530fa15a73\", \"sync_integration_tests\": \"b33f3933b65f18b3e5125e3dd0eda6a060c18edd\", \"telemetry_gpu_unittests\": \"c1f99c89ad561c22b38066b74c6f8457d2d06776\", \"telemetry_perf_unittests\": \"87f6b5ae64feae1a28938105c0f33c339834d050\", \"telemetry_unittests\": \"3e510a9fda1c325653ef214bd4a45caf59fbc259\", \"traffic_annotation_auditor_unittests\": \"bf82e66020731b9fd216a157401d3a510a764320\", \"ui_base_unittests\": \"07c5f2f90fa76fea918ee155870261e856027399\", \"ui_touch_selection_unittests\": \"6aa340b7f3bf11246b9c69535b44598c562b2147\", \"unit_tests\": \"6d509b45c5042c47543fbe7f4fcf2e727d2450bf\", \"url_unittests\": \"0d46cb568a0ec311e6da0488af47a9fb2fa35c6a\", \"views_perftests\": \"db189fddccebbdd435cc35186d9871dbdcd290a1\", \"views_unittests\": \"81e301d45c64fabf37ac1627c51cff41df22ac90\", \"viz_unittests\": \"b6ce69b498c09cb4d1fe7716c358a41cf304a9d8\", \"vr_common_unittests\": \"819d8de8d0968fed8ada93215ecd8fb7d46db9cd\", \"vr_pixeltests\": \"5cd5db1f80ed63ce77b4e6f31aaaf2464e714af1\", \"webdriver_wpt_tests\": \"c1228c6840dcf8dd0fb7d25cced0fd4056616d07\", \"weblayer_browsertests\": \"7b80aecf210cb20722d15f18cdb8f7c68901f132\", \"weblayer_unittests\": \"6e98892cf6d264e7bca2a1ceb0dc67cc84043a3d\", \"wm_unittests\": \"55577f9a126b9b8483ceb6d5a6890ed459f600dd\", \"wtf_unittests\": \"9547a3546ea37069b2e2be4310210845e0657d94\", \"x11_unittests\": \"a4193c7413a013ded7b17f87d09eefcdc5c4ce16\", \"xr_browser_tests\": \"92c1c293b6d27ec7efac48b6151448361ac0cf22\", \"zlib_unittests\": \"31d1e83b01afa4a5c1a6c5d017d280294e819859\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_21b7b67cc5140fc86b3165a24b457b619be37de8070af45371e28279dc829f76_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-60-0fyf\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612302018589150",
"status": "COMPLETED",
"status_changed_ts": "1612305379035713",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180210",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f0d0c221e8e872f878d0f0f08f349bfab4b242b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021802579601112736",
"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/8856372517256390368/+/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:517d81eb6324c610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612305379040519",
"url": "https://ci.chromium.org/b/8856372517256390368",
"utcnow_ts": "1612305381421753"
}
}
[22:36:21.435] Invocation finished in 56m7.081453577s with status SUCCEEDED