[15:27:07.672] New invocation is queued and will start shortly
[15:27:08.777] Starting the invocation (attempt 1)
[15:27:08.829] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:27:08.835] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:27:08.835] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021735456789773184",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4c9c503f04e0e4c84f494e38425e094f05697d40\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021735456789773184",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4c9c503f04e0e4c84f494e38425e094f05697d40",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c9c503f04e0e4c84f494e38425e094f05697d40",
"gitiles_ref:refs/heads/master"
]
}
[15:27:09.585] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612366028998379",
"id": "8856305394735779504",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c9c503f04e0e4c84f494e38425e094f05697d40\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612366029457325",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180238",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4c9c503f04e0e4c84f494e38425e094f05697d40",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c9c503f04e0e4c84f494e38425e094f05697d40",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021735456789773184",
"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/8856305394735779504/+/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": "1612366029457662",
"url": "https://ci.chromium.org/b/8856305394735779504",
"utcnow_ts": "1612366029576082"
}
}
[15:27:09.585] Task URL: https://ci.chromium.org/b/8856305394735779504
[15:27:09.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:2:0) after 1m0s
[15:28:09.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:2:0)
[15:28:09.674] Timer tick, asking Buildbucket for the build status
[15:28:09.857] Build 8856305394735779504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:09.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:3:0) after 1m0s
[15:28:27.560] Received PubSub notification, asking Buildbucket for the build status
[15:28:27.634] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:09.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:3:0)
[15:29:09.973] Timer tick, asking Buildbucket for the build status
[15:29:10.109] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:10.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:5:0) after 1m0s
[15:30:10.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:5:0)
[15:30:10.106] Timer tick, asking Buildbucket for the build status
[15:30:10.223] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:10.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:6:0) after 1m0s
[15:31:10.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:6:0)
[15:31:10.246] Timer tick, asking Buildbucket for the build status
[15:31:10.418] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:7:0) after 1m0s
[15:32:10.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:7:0)
[15:32:10.480] Timer tick, asking Buildbucket for the build status
[15:32:10.666] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:10.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:8:0) after 1m0s
[15:33:10.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:8:0)
[15:33:10.684] Timer tick, asking Buildbucket for the build status
[15:33:10.809] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:10.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:9:0) after 1m0s
[15:34:10.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:9:0)
[15:34:10.828] Timer tick, asking Buildbucket for the build status
[15:34:10.892] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:10.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:10:0) after 1m0s
[15:35:10.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:10:0)
[15:35:10.928] Timer tick, asking Buildbucket for the build status
[15:35:11.044] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:11.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:11:0) after 1m0s
[15:36:11.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:11:0)
[15:36:11.103] Timer tick, asking Buildbucket for the build status
[15:36:11.347] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:11.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:12:0) after 1m0s
[15:37:11.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:12:0)
[15:37:11.434] Timer tick, asking Buildbucket for the build status
[15:37:11.538] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:11.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:13:0) after 1m0s
[15:38:11.706] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:13:0)
[15:38:11.706] Timer tick, asking Buildbucket for the build status
[15:38:11.765] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:11.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:14:0) after 1m0s
[15:39:12.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:14:0)
[15:39:12.005] Timer tick, asking Buildbucket for the build status
[15:39:12.125] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:12.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:15:0) after 1m0s
[15:40:12.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:15:0)
[15:40:12.224] Timer tick, asking Buildbucket for the build status
[15:40:12.303] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:12.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:16:0) after 1m0s
[15:41:12.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:16:0)
[15:41:12.718] Timer tick, asking Buildbucket for the build status
[15:41:12.905] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:12.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:17:0) after 1m0s
[15:42:12.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:17:0)
[15:42:12.998] Timer tick, asking Buildbucket for the build status
[15:42:13.231] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:13.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:18:0) after 1m0s
[15:43:13.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:18:0)
[15:43:13.227] Timer tick, asking Buildbucket for the build status
[15:43:13.285] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:13.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:19:0) after 1m0s
[15:44:13.571] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:19:0)
[15:44:13.571] Timer tick, asking Buildbucket for the build status
[15:44:13.633] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:13.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:20:0) after 1m0s
[15:45:13.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:20:0)
[15:45:13.668] Timer tick, asking Buildbucket for the build status
[15:45:13.914] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:13.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:21:0) after 1m0s
[15:46:14.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:21:0)
[15:46:14.356] Timer tick, asking Buildbucket for the build status
[15:46:14.426] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:14.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:22:0) after 1m0s
[15:47:14.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:22:0)
[15:47:14.528] Timer tick, asking Buildbucket for the build status
[15:47:14.913] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:14.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:23:0) after 1m0s
[15:48:15.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:23:0)
[15:48:15.072] Timer tick, asking Buildbucket for the build status
[15:48:15.157] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:24:0) after 1m0s
[15:49:15.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:24:0)
[15:49:15.289] Timer tick, asking Buildbucket for the build status
[15:49:15.368] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:15.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:25:0) after 1m0s
[15:50:15.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:25:0)
[15:50:15.507] Timer tick, asking Buildbucket for the build status
[15:50:15.557] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:15.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:26:0) after 1m0s
[15:51:15.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:26:0)
[15:51:15.691] Timer tick, asking Buildbucket for the build status
[15:51:15.797] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:15.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:27:0) after 1m0s
[15:52:15.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:27:0)
[15:52:15.994] Timer tick, asking Buildbucket for the build status
[15:52:16.106] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:16.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:28:0) after 1m0s
[15:53:16.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:28:0)
[15:53:16.170] Timer tick, asking Buildbucket for the build status
[15:53:16.248] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:16.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:29:0) after 1m0s
[15:54:16.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:29:0)
[15:54:16.358] Timer tick, asking Buildbucket for the build status
[15:54:16.431] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:16.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:30:0) after 1m0s
[15:55:16.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:30:0)
[15:55:16.665] Timer tick, asking Buildbucket for the build status
[15:55:17.063] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:17.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:31:0) after 1m0s
[15:56:17.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:31:0)
[15:56:17.093] Timer tick, asking Buildbucket for the build status
[15:56:17.157] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:17.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:32:0) after 1m0s
[15:57:17.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:32:0)
[15:57:17.190] Timer tick, asking Buildbucket for the build status
[15:57:17.294] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:17.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:33:0) after 1m0s
[15:58:17.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:33:0)
[15:58:17.403] Timer tick, asking Buildbucket for the build status
[15:58:17.467] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:17.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:34:0) after 1m0s
[15:59:17.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:34:0)
[15:59:17.474] Timer tick, asking Buildbucket for the build status
[15:59:17.552] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:17.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:35:0) after 1m0s
[16:00:17.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:35:0)
[16:00:17.685] Timer tick, asking Buildbucket for the build status
[16:00:18.539] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:18.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:36:0) after 1m0s
[16:01:18.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:36:0)
[16:01:18.626] Timer tick, asking Buildbucket for the build status
[16:01:18.799] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:18.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:37:0) after 1m0s
[16:02:18.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:37:0)
[16:02:18.838] Timer tick, asking Buildbucket for the build status
[16:02:18.951] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:18.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:38:0) after 1m0s
[16:03:18.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:38:0)
[16:03:18.997] Timer tick, asking Buildbucket for the build status
[16:03:19.101] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:19.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:39:0) after 1m0s
[16:04:19.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:39:0)
[16:04:19.230] Timer tick, asking Buildbucket for the build status
[16:04:19.806] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:19.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:40:0) after 1m0s
[16:05:19.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:40:0)
[16:05:19.845] Timer tick, asking Buildbucket for the build status
[16:05:19.994] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:19.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:41:0) after 1m0s
[16:06:20.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:41:0)
[16:06:20.029] Timer tick, asking Buildbucket for the build status
[16:06:20.085] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:20.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:42:0) after 1m0s
[16:07:20.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:42:0)
[16:07:20.090] Timer tick, asking Buildbucket for the build status
[16:07:20.638] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:20.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:43:0) after 1m0s
[16:08:20.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:43:0)
[16:08:20.687] Timer tick, asking Buildbucket for the build status
[16:08:20.754] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:20.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:44:0) after 1m0s
[16:09:20.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:44:0)
[16:09:20.812] Timer tick, asking Buildbucket for the build status
[16:09:21.022] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:21.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:45:0) after 1m0s
[16:10:21.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:45:0)
[16:10:21.071] Timer tick, asking Buildbucket for the build status
[16:10:21.193] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:21.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:46:0) after 1m0s
[16:11:21.249] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:46:0)
[16:11:21.249] Timer tick, asking Buildbucket for the build status
[16:11:21.306] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:21.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:47:0) after 1m0s
[16:12:21.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:47:0)
[16:12:21.354] Timer tick, asking Buildbucket for the build status
[16:12:21.936] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:21.936] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:48:0) after 1m0s
[16:13:21.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:48:0)
[16:13:21.988] Timer tick, asking Buildbucket for the build status
[16:13:22.725] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:22.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:49:0) after 1m0s
[16:14:22.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:49:0)
[16:14:22.752] Timer tick, asking Buildbucket for the build status
[16:14:22.850] Build 8856305394735779504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:22.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021735456789773184:50:0) after 1m0s
[16:14:55.068] Received PubSub notification, asking Buildbucket for the build status
[16:14:55.315] Build 8856305394735779504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:14:55.315] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612368893760186",
"created_by": "project:chromium",
"created_ts": "1612366028998379",
"id": "8856305394735779504",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4c9c503f04e0e4c84f494e38425e094f05697d40\"}}",
"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\": \"4c9c503f04e0e4c84f494e38425e094f05697d40\", \"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-174-h5w3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180238, \"got_angle_revision\": \"2e54a1e7fc49e1c1c659d7be659b832954112106\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"4c9c503f04e0e4c84f494e38425e094f05697d40\", \"got_revision_cp\": \"refs/heads/master@{#850112}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#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\": \"4c9c503f04e0e4c84f494e38425e094f05697d40\", \"swarm_hashes_refs/heads/master(at){#850112}_without_patch\": {\"absl_hardening_tests\": \"73fda4f9c8e763a0854d26273e228773761f16a8\", \"accessibility_unittests\": \"5ef5585800985afc72b11b72f22cbcc45fee10ca\", \"angle_unittests\": \"4ff706f51b9f8a7ef44ccb19111f690802aeed28\", \"app_shell_unittests\": \"a11e6112ca02ac9add8ded2cd37f21266449aab7\", \"aura_unittests\": \"e56ef2290d860a85ffe7f89636a3b5f67ef0af3a\", \"base_unittests\": \"180ce5aad35f00916aebe47c0a83c0dfa69475ac\", \"base_util_unittests\": \"dff6b2b0e7d1e6f834de8373bea5e12442c5e6b8\", \"blink_common_unittests\": \"9a2e8e53d04f4a3028b098adf383b2f8f0882587\", \"blink_fuzzer_unittests\": \"bcab912abfdeb0377d79bf8e532af07ecfc2269e\", \"blink_heap_unittests\": \"ca3b3b57e3340b52f7a388b16c9ca85aee5f2406\", \"blink_platform_unittests\": \"abe4fc02ec7292838e6a10190a378b6817c76564\", \"blink_python_tests\": \"833f10f99a2d5ddb9d94a0ff3350aead3d504e66\", \"blink_unittests\": \"30f8fe6131d60f3aab82bb45b30c8ce204e42b84\", \"blink_web_tests\": \"28d78aa80321ec882d85008061c4cd892cdca6e6\", \"boringssl_crypto_tests\": \"56f82a1a3f475b40ed9b19173373ef4ea0933abe\", \"boringssl_ssl_tests\": \"90393c36c412648fdf9852fed3c3972fd595bc79\", \"browser_tests\": \"bd0bc49934d57e5dae515b4f486b616e6ea90dac\", \"capture_unittests\": \"1fa0c4829d009c34146989e6c7087d65b258a73b\", \"cast_unittests\": \"8220ff4c32810b48b07354f2c448e960a2627c12\", \"cc_unittests\": \"152412a39d77773d7b02320d13b8ba41b1bdc7ae\", \"chrome_app_unittests\": \"cb290e6828c58780368228f66d81378b5e67e469\", \"chromedriver_unittests\": \"81abd61bd3b50a345a4531756935eb436959fd49\", \"components_browsertests\": \"f38bd5d4d6b24beed4cb814cb4c22aef4335dc53\", \"components_unittests\": \"2cef71b9c35c9540d7d9f3dbdd299d5daa804dcd\", \"compositor_unittests\": \"62e97475ea09aeed83fbd6b0d4320d28b43ef0ab\", \"content_browsertests\": \"d2c0376c83ed61f7e8d726cde4fb73a1b9ca5bbe\", \"content_shell_crash_test\": \"a408c31f10ce86c133b447678c545bef20014ccb\", \"content_unittests\": \"fce2e102c1abe57a9ad2683f719b18c4f23b265b\", \"crashpad_tests\": \"c8f19bf2a501e800de5963148e5db565e2a26dc4\", \"cronet_tests\": \"fcbc50cf09f3c28e8711a42518be4bf56afa2a8e\", \"cronet_unittests\": \"7d1da07ea4bbf7b5bf127344027f0861fcc39667\", \"crypto_unittests\": \"09411f6e651eee3299dba3b581c2650f788db7ec\", \"dbus_unittests\": \"e35dfd6a8fc2d5b10289685a40f8d8eb5afb9ed3\", \"device_unittests\": \"fcdddfd3faa94ce47608ff754b1669dadc97c2ba\", \"display_unittests\": \"6fc62a4b6a8824dbd1ab9bac59580fa835c6f9a7\", \"events_unittests\": \"48c09730139f9cfc4e5a177c79ce679c1f1e72a4\", \"extensions_browsertests\": \"26b61abe5f074ef9c67388b472afd1ef544a635a\", \"extensions_unittests\": \"eee14e8678d9b79a9a3dc1e2abc438d035606f0c\", \"filesystem_service_unittests\": \"27a642d4954e7ae2ae7b80d370aba2460aabb64c\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"27f72e5027b5f397e2624644015e7ffb54cbb322\", \"gfx_unittests\": \"87c62634d11545ad955f2bd92f47d1701823cbb8\", \"gin_unittests\": \"5bfbc97e7d1dfe7067606234503d04244522b35e\", \"google_apis_unittests\": \"3790a4217dc281a78534f5dd05afe6fbb9a5bca6\", \"gpu_unittests\": \"987b96b1070f4da0ac2c871be2b0d73ec714cd72\", \"grit_python_unittests\": \"0532571aa381198443b89c1ec536d42cf612fc22\", \"gwp_asan_unittests\": \"f902b1314d806e81523c960fee270fac58e6c5ef\", \"headless_browsertests\": \"ef01bd586a8440368fc27da6c4a468574264bcf7\", \"headless_unittests\": \"b82449178889fe92021f07a79302d075d2ac7897\", \"interactive_ui_tests\": \"446bcac92f93d857d8bfb97eef042e6a1229c5e1\", \"ipc_tests\": \"eac49a2661523839ee7e6cea5e62ba3c2e1c8393\", \"jingle_unittests\": \"9952528b53379f95c12f81f0843fbd4609193370\", \"latency_unittests\": \"cae803b39103021c66dec58d445d040e3d1dcd67\", \"libjingle_xmpp_unittests\": \"826a53a97a6450021e9591cf1bae07fb2c6941d6\", \"liburlpattern_unittests\": \"b9f449708939cae68078f02db208ac881903799b\", \"media_blink_unittests\": \"7cb2a7b58773718f606d32b7276948ff270076f2\", \"media_unittests\": \"45e91e58f24579436de84d8a8a4a162235a7dd86\", \"message_center_unittests\": \"aec5cc3bb2391f129ba042735560cc3fa68e4a69\", \"metrics_python_tests\": \"b14fa95bc2357ed6c2e875d721cc67e3baccee8f\", \"midi_unittests\": \"5121311ec0767c48a8ce0d7c13b2d210d034f386\", \"mojo_core_unittests\": \"d4c50720946eb4a81a2fad669d4e1a219c8a3b65\", \"mojo_unittests\": \"41428312dbac7e010ce21cbf82afc278401dd839\", \"nacl_helper_nonsfi_unittests\": \"7f6a03d5604cb17a5fddad91138e0f49969b96cb\", \"nacl_loader_unittests\": \"d25ac6e83c5b3a452f5a92d5f8dab8ff784267b4\", \"native_theme_unittests\": \"bd42101f920bd05b454b071a67e4ca9090bc6102\", \"net_unittests\": \"6503139493f8505efb0f38b0f98570bb45fa7c66\", \"openscreen_unittests\": \"1ae3d0765caf1997e484fe1bdc14149a5ac16910\", \"pdf_unittests\": \"cb4e968c8786b36c3e4a64d073219f002b349497\", \"perfetto_unittests\": \"3b303f558b31c50d740f9d6808db62d61f305bde\", \"ppapi_unittests\": \"ae8b4b62df2b8faec5e75b43ce64267c8185705f\", \"printing_unittests\": \"8b218c16b6fb535a7437f6f370cdd23d936f7ca8\", \"remoting_unittests\": \"0747f566c5bb52644b9fde8d36e60a5db1914f7d\", \"sandbox_linux_unittests\": \"7d6713cfb9f40efe4ca0d25cd97f595b6ae8e72b\", \"service_manager_unittests\": \"8e530d62303d78b6ac19ede8c4bcd68c7bb710cb\", \"services_unittests\": \"fe5921550e136657005e9719ac8d4074dbfa4e87\", \"shell_dialogs_unittests\": \"cda1d81017fd381663458970bdf1adde8fcbe225\", \"skia_unittests\": \"bc36dbac25e8998767c56b2234135e0bfe769ea4\", \"snapshot_unittests\": \"eba8c7fb412c9881a8656373505ae9de61ecf353\", \"sql_unittests\": \"4e3104651f93e7ca5234844cc998bcc652671ced\", \"storage_unittests\": \"d61a5cb2546b3c56cb13168305c68fd1a649699a\", \"sync_integration_tests\": \"981e82c5916615d73ea4d8ac55c0d4f401de2a33\", \"telemetry_gpu_unittests\": \"14fcf0325488ccbc259ba5ee6d49001a36581fd9\", \"telemetry_perf_unittests\": \"8b22d01aa09c48ed71a7978480f98ca40cd208f3\", \"telemetry_unittests\": \"f4997f5a05d9dea983464d0c89cdb6d9065d4807\", \"traffic_annotation_auditor_unittests\": \"78691526921e43f0017292afc5bb933da10252be\", \"ui_base_unittests\": \"213c6d1dee60431054f295ad9f8bb33fbdcbfa80\", \"ui_touch_selection_unittests\": \"15bbc713f662b2fb86a991b42aca90304d0268b9\", \"unit_tests\": \"69bec6c8e954a489e3c22be8f1ceb82502411302\", \"url_unittests\": \"e34fe1f663c2d65335fc58809e1769ae41338f76\", \"views_perftests\": \"c26df05aae26b5769a42bc50e03769c7654daedd\", \"views_unittests\": \"596392056682b0dcc937fec9bf36935ca64eb559\", \"viz_unittests\": \"fe93540eedc64fabb726aff8b0b7f483df20810b\", \"vr_common_unittests\": \"66146b4505760d112de1e5f001524d9b6c9745dc\", \"vr_pixeltests\": \"4d4d05de0563880bf3fb4c4eac9e8072e923ef7e\", \"webdriver_wpt_tests\": \"a7c124c9a7a21d3f0fc8dd885178304f514055f5\", \"weblayer_browsertests\": \"e7cff02bc463db1c204f167e2c4ee39c8077aff8\", \"weblayer_unittests\": \"a542c318d9f998aca5a88ce1850e84a9d380fcc3\", \"wm_unittests\": \"ce45b4d6fad882bd80685a3e1b9278043d0e6d30\", \"wtf_unittests\": \"018ca2ffe4ebc0e7c31ada1537240a0158a009f0\", \"x11_unittests\": \"7c2b5ffc2e1ea04c414739193ead69eca9de30be\", \"xr_browser_tests\": \"7a6e2032f34bcd43650f4a69a2d3f7cfe9a3c51e\", \"zlib_unittests\": \"5276b952d5274b9dc6b048591ce114ec41331eaa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_9ab392df551d6d5935f78898dbc2168b32a5d8764371ea104d9b75846e7336d7_v2\", \"builder_a72d480ca48f57bfd3a2a0028361f62534d8cf527dbb841fd2259fc00e36dedb_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_ea4e7feff896b82ff6619a3b9c5371d5e74ebdd508befd295e2f5ab02f5eefaf_v2\", \"builder_eb75bb97ad9a82e6e51bc242be3605b13562b7c1ffaba99d94159b9b70e03009_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_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-174-h5w3\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612366106799709",
"status": "COMPLETED",
"status_changed_ts": "1612368894550515",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180238",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4c9c503f04e0e4c84f494e38425e094f05697d40",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c9c503f04e0e4c84f494e38425e094f05697d40",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021735456789773184",
"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/8856305394735779504/+/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:518152ac2931d110",
"user_agent:luci-scheduler"
],
"updated_ts": "1612368894589711",
"url": "https://ci.chromium.org/b/8856305394735779504",
"utcnow_ts": "1612368895289323"
}
}
[16:14:55.315] Invocation finished in 47m47.660086974s with status SUCCEEDED