[12:36:34.391] New invocation is queued and will start shortly
[12:36:35.602] Starting the invocation (attempt 1)
[12:36:35.644] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:36:35.644] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:36:35.644] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021746187162969408",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0e87ecdb41bba6891cce90527f65e085ec10b4a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021746187162969408",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"gitiles_ref:refs/heads/master"
]
}
[12:36:40.481] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612355796142722",
"id": "8856316124662864480",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0e87ecdb41bba6891cce90527f65e085ec10b4a2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612355799269075",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180234",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021746187162969408",
"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/8856316124662864480/+/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": "1612355799269248",
"url": "https://ci.chromium.org/b/8856316124662864480",
"utcnow_ts": "1612355800370414"
}
}
[12:36:40.481] Task URL: https://ci.chromium.org/b/8856316124662864480
[12:36:40.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:2:0) after 1m0s
[12:37:02.542] Received PubSub notification, asking Buildbucket for the build status
[12:37:04.976] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:40.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:2:0)
[12:37:40.462] Timer tick, asking Buildbucket for the build status
[12:37:42.038] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:42.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:4:0) after 1m0s
[12:38:42.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:4:0)
[12:38:42.067] Timer tick, asking Buildbucket for the build status
[12:38:42.237] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:42.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:5:0) after 1m0s
[12:39:42.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:5:0)
[12:39:42.326] Timer tick, asking Buildbucket for the build status
[12:39:42.426] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:42.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:6:0) after 1m0s
[12:40:42.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:6:0)
[12:40:42.445] Timer tick, asking Buildbucket for the build status
[12:40:44.361] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:44.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:7:0) after 1m0s
[12:41:44.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:7:0)
[12:41:44.395] Timer tick, asking Buildbucket for the build status
[12:41:44.658] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:44.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:8:0) after 1m0s
[12:42:44.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:8:0)
[12:42:44.639] Timer tick, asking Buildbucket for the build status
[12:42:46.462] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:46.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:9:0) after 1m0s
[12:43:46.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:9:0)
[12:43:46.504] Timer tick, asking Buildbucket for the build status
[12:43:47.427] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:47.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:10:0) after 1m0s
[12:44:47.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:10:0)
[12:44:47.416] Timer tick, asking Buildbucket for the build status
[12:44:47.522] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:47.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:11:0) after 1m0s
[12:45:47.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:11:0)
[12:45:47.696] Timer tick, asking Buildbucket for the build status
[12:45:48.645] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:48.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:12:0) after 1m0s
[12:46:48.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:12:0)
[12:46:48.716] Timer tick, asking Buildbucket for the build status
[12:46:49.657] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:49.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:13:0) after 1m0s
[12:47:49.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:13:0)
[12:47:49.673] Timer tick, asking Buildbucket for the build status
[12:47:49.940] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:49.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:14:0) after 1m0s
[12:48:50.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:14:0)
[12:48:50.035] Timer tick, asking Buildbucket for the build status
[12:48:50.442] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:50.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:15:0) after 1m0s
[12:49:50.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:15:0)
[12:49:50.454] Timer tick, asking Buildbucket for the build status
[12:49:50.540] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:16:0) after 1m0s
[12:50:50.612] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:16:0)
[12:50:50.612] Timer tick, asking Buildbucket for the build status
[12:50:50.868] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:50.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:17:0) after 1m0s
[12:51:50.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:17:0)
[12:51:50.846] Timer tick, asking Buildbucket for the build status
[12:51:50.908] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:50.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:18:0) after 1m0s
[12:52:50.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:18:0)
[12:52:50.924] Timer tick, asking Buildbucket for the build status
[12:52:51.402] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:51.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:19:0) after 1m0s
[12:53:51.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:19:0)
[12:53:51.470] Timer tick, asking Buildbucket for the build status
[12:53:51.528] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:51.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:20:0) after 1m0s
[12:54:51.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:20:0)
[12:54:51.552] Timer tick, asking Buildbucket for the build status
[12:54:51.649] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:51.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:21:0) after 1m0s
[12:55:51.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:21:0)
[12:55:51.668] Timer tick, asking Buildbucket for the build status
[12:55:51.762] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:51.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:22:0) after 1m0s
[12:56:51.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:22:0)
[12:56:51.816] Timer tick, asking Buildbucket for the build status
[12:56:51.884] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:51.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:23:0) after 1m0s
[12:57:51.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:23:0)
[12:57:51.948] Timer tick, asking Buildbucket for the build status
[12:57:52.239] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:52.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:24:0) after 1m0s
[12:58:52.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:24:0)
[12:58:52.226] Timer tick, asking Buildbucket for the build status
[12:58:54.498] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:25:0) after 1m0s
[12:59:54.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:25:0)
[12:59:54.529] Timer tick, asking Buildbucket for the build status
[12:59:55.476] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:55.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:26:0) after 1m0s
[13:00:55.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:26:0)
[13:00:55.593] Timer tick, asking Buildbucket for the build status
[13:00:56.560] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:56.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:27:0) after 1m0s
[13:01:56.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:27:0)
[13:01:56.652] Timer tick, asking Buildbucket for the build status
[13:01:57.182] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:57.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:28:0) after 1m0s
[13:02:57.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:28:0)
[13:02:57.213] Timer tick, asking Buildbucket for the build status
[13:02:57.341] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:57.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:29:0) after 1m0s
[13:03:57.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:29:0)
[13:03:57.438] Timer tick, asking Buildbucket for the build status
[13:03:57.843] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:57.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:30:0) after 1m0s
[13:04:57.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:30:0)
[13:04:57.904] Timer tick, asking Buildbucket for the build status
[13:04:57.980] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:31:0) after 1m0s
[13:05:58.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:31:0)
[13:05:58.054] Timer tick, asking Buildbucket for the build status
[13:05:58.185] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:58.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:32:0) after 1m0s
[13:06:58.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:32:0)
[13:06:58.216] Timer tick, asking Buildbucket for the build status
[13:07:01.384] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:01.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:33:0) after 1m0s
[13:08:01.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:33:0)
[13:08:01.462] Timer tick, asking Buildbucket for the build status
[13:08:02.458] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:02.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:34:0) after 1m0s
[13:09:02.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:34:0)
[13:09:02.513] Timer tick, asking Buildbucket for the build status
[13:09:02.618] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:02.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:35:0) after 1m0s
[13:10:02.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:35:0)
[13:10:02.614] Timer tick, asking Buildbucket for the build status
[13:10:03.939] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:03.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:36:0) after 1m0s
[13:11:03.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:36:0)
[13:11:03.994] Timer tick, asking Buildbucket for the build status
[13:11:05.213] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:05.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:37:0) after 1m0s
[13:12:05.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:37:0)
[13:12:05.228] Timer tick, asking Buildbucket for the build status
[13:12:05.509] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:05.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:38:0) after 1m0s
[13:13:05.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:38:0)
[13:13:05.591] Timer tick, asking Buildbucket for the build status
[13:13:06.066] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:06.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:39:0) after 1m0s
[13:14:06.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:39:0)
[13:14:06.099] Timer tick, asking Buildbucket for the build status
[13:14:06.547] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:06.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:40:0) after 1m0s
[13:15:06.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:40:0)
[13:15:06.573] Timer tick, asking Buildbucket for the build status
[13:15:07.562] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:07.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:41:0) after 1m0s
[13:16:07.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:41:0)
[13:16:07.606] Timer tick, asking Buildbucket for the build status
[13:16:07.730] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:07.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:42:0) after 1m0s
[13:17:07.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:42:0)
[13:17:07.753] Timer tick, asking Buildbucket for the build status
[13:17:08.429] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:08.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:43:0) after 1m0s
[13:18:08.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:43:0)
[13:18:08.515] Timer tick, asking Buildbucket for the build status
[13:18:08.853] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:08.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:44:0) after 1m0s
[13:19:08.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:44:0)
[13:19:08.927] Timer tick, asking Buildbucket for the build status
[13:19:09.334] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:09.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:45:0) after 1m0s
[13:20:09.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:45:0)
[13:20:09.404] Timer tick, asking Buildbucket for the build status
[13:20:09.607] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:09.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:46:0) after 1m0s
[13:21:09.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:46:0)
[13:21:09.660] Timer tick, asking Buildbucket for the build status
[13:21:09.812] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:09.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:47:0) after 1m0s
[13:22:09.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:47:0)
[13:22:09.862] Timer tick, asking Buildbucket for the build status
[13:22:10.138] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:10.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:48:0) after 1m0s
[13:23:10.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:48:0)
[13:23:10.296] Timer tick, asking Buildbucket for the build status
[13:23:11.477] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:11.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:49:0) after 1m0s
[13:24:11.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:49:0)
[13:24:11.547] Timer tick, asking Buildbucket for the build status
[13:24:11.814] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:11.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:50:0) after 1m0s
[13:25:11.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:50:0)
[13:25:11.933] Timer tick, asking Buildbucket for the build status
[13:25:12.260] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:12.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:51:0) after 1m0s
[13:26:12.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:51:0)
[13:26:12.252] Timer tick, asking Buildbucket for the build status
[13:26:12.644] Build 8856316124662864480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021746187162969408:52:0) after 1m0s
[13:26:59.609] Received PubSub notification, asking Buildbucket for the build status
[13:26:59.686] Build 8856316124662864480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:26:59.686] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612358819027152",
"created_by": "project:chromium",
"created_ts": "1612355796142722",
"id": "8856316124662864480",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0e87ecdb41bba6891cce90527f65e085ec10b4a2\"}}",
"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\": \"0e87ecdb41bba6891cce90527f65e085ec10b4a2\", \"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-159-2xnu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180234, \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"0e87ecdb41bba6891cce90527f65e085ec10b4a2\", \"got_revision_cp\": \"refs/heads/master@{#850072}\", \"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\": \"0e87ecdb41bba6891cce90527f65e085ec10b4a2\", \"swarm_hashes_refs/heads/master(at){#850072}_without_patch\": {\"absl_hardening_tests\": \"4a86fc1f629fc02be03c7a243cbc94a6c3dbfadd\", \"accessibility_unittests\": \"be5fd39d1a2aa76753e18410aa803096be54157a\", \"angle_unittests\": \"7be03ce94970000281de7df57db46d714f2a6a61\", \"app_shell_unittests\": \"aa496698667d9b13845c53a8657af6403ff5150b\", \"aura_unittests\": \"327b7f222ff7bf72cad5334942c49b21ca2b9c0a\", \"base_unittests\": \"739f1247038cca244e762a598ebd4f3ab6062118\", \"base_util_unittests\": \"9957399c109b4c86c866040871234c8de7fd4b6e\", \"blink_common_unittests\": \"12951b496806c11e46adfc203f13a3267ea81270\", \"blink_fuzzer_unittests\": \"7846edc4ecc9d6ee98410848e4e8c288adbd2498\", \"blink_heap_unittests\": \"1c34e9ea9c02a8dee2743c1bec15f83abf81fb0c\", \"blink_platform_unittests\": \"fd83bbd4beb7cf80e59734d16759e14ba63122f0\", \"blink_python_tests\": \"7aa1e1f8e8c2835334ffe95182792f547858e25f\", \"blink_unittests\": \"597d8f8b8806ba9bb1713111088f4a3b5d1d7302\", \"blink_web_tests\": \"ecacc1817bf24e51bbb20d8b31fa442d2e3a4e8e\", \"boringssl_crypto_tests\": \"a96765e359cfc44563d8d9b1c5dc96725e3c8941\", \"boringssl_ssl_tests\": \"f8bd87da1655853cd5946ca39629e0cc06531a41\", \"browser_tests\": \"8a42fbded3ee7b003253ab207db550f9284bf940\", \"capture_unittests\": \"609377fa89e50b28885e1ecf538ee5b56afc775c\", \"cast_unittests\": \"19e21e10d76399086f7f5ec5b4bf303650116aa8\", \"cc_unittests\": \"f53f153cbf05e30b9ccaaa4fe87ea9975f780eb2\", \"chrome_app_unittests\": \"7dbbaeeab6415c50691cb2f913787f5db7d7c8b8\", \"chromedriver_unittests\": \"e12abcfee0863cec81ee5457de7449ddac656443\", \"components_browsertests\": \"7b2c690753cfdee57093041d7dc0c6d2f019bfea\", \"components_unittests\": \"11676c0cd3ca922eb2ca518c8843f4adf7ee748e\", \"compositor_unittests\": \"45cf624bc6ac9bf776e9f5baaeb4367a036d2be6\", \"content_browsertests\": \"015c3c31642adbda8c8ac55015d3b252a8480b94\", \"content_shell_crash_test\": \"acc60721e72a5f331e49316a58dd1da9eb8c22bf\", \"content_unittests\": \"ddffee8743d71944f10bec5b65f4a155dd0d3316\", \"crashpad_tests\": \"73c07cd5df0c38169d7091a9eed9c1d4e9a28629\", \"cronet_tests\": \"808f595556d14408553adc332bacb8e085d7a505\", \"cronet_unittests\": \"215f80cf66e384725e655f3e5f5b6f18954d8b49\", \"crypto_unittests\": \"5eb4361cf73419055faa3110dba201625b5d194d\", \"dbus_unittests\": \"3ec50510db48fd83f9729996a661262c476d039d\", \"device_unittests\": \"a5c4c85cb934c58864f2f59034ab208aa110f7d1\", \"display_unittests\": \"8d0a94080afe78ee071373434c7d3f19d5aee2d8\", \"events_unittests\": \"30c6863d6950299ca55ef38af4ce8d86071eb487\", \"extensions_browsertests\": \"37c78f6ac3109f13ddfc57dc1a623db81eda87fb\", \"extensions_unittests\": \"f107794ac3c65f29da4131cdef7f17b0ab56b0f2\", \"filesystem_service_unittests\": \"a4037ed9419033bba6291f799cb2d6b4f6a7248b\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"c5ea152471e4acd9db6f870926a3db2c88ef6822\", \"gfx_unittests\": \"2804ec7df44af3d6be804c7739157d739401a140\", \"gin_unittests\": \"6e9993619dd16769294ba7daced9004c78d04b08\", \"google_apis_unittests\": \"ba8e2caf32e7582615d9d49bf93b442a989e1549\", \"gpu_unittests\": \"9e72d7c82a8acb4ab74e83a6db1693b8be86690c\", \"grit_python_unittests\": \"0532571aa381198443b89c1ec536d42cf612fc22\", \"gwp_asan_unittests\": \"7974d84269651e6b6ef0f86124b65141accdd046\", \"headless_browsertests\": \"0843f78f0edf69d2505073f04bda5fa1b26da2fd\", \"headless_unittests\": \"0327547baebad060f406023f2eb16278906dda44\", \"interactive_ui_tests\": \"dc72aba158265d63a523777571ef0b3850468b94\", \"ipc_tests\": \"bd3038eb78b686f62322d2e4b748a76c5cb29095\", \"jingle_unittests\": \"7bb9bdb493cdc8c30fcb4a2f5e5ed9a513db2afa\", \"latency_unittests\": \"3f083026b512621d33ee51fa166c216fefae33b9\", \"libjingle_xmpp_unittests\": \"648ed0d75b963f6e51b400f0d989f52a7b887905\", \"liburlpattern_unittests\": \"3c31009a80eef760576e9bef8974e9ef3ac01c4d\", \"media_blink_unittests\": \"8b0463599549019bfd2df160268e4adb665f39ac\", \"media_unittests\": \"1148bf76df351cc7e384ed9cabd53d6e02e9b1cc\", \"message_center_unittests\": \"d7cb4ee51d1303a7a3411e19b5bd3c7ea45ac727\", \"metrics_python_tests\": \"b14fa95bc2357ed6c2e875d721cc67e3baccee8f\", \"midi_unittests\": \"53ad62ebd70ff1495c410d171b2bd76e246bc5dd\", \"mojo_core_unittests\": \"c82d2107d8991a25dce2f74034b41862cfa4fb68\", \"mojo_unittests\": \"69d119fc29d7f04618edf073f64407e467f5d094\", \"nacl_helper_nonsfi_unittests\": \"60408e43707de71e4aa660ae57a38fd33ec0fb74\", \"nacl_loader_unittests\": \"cf59792c268e654040df5549ac31ac23405f562d\", \"native_theme_unittests\": \"c71b92bf9fbe958892c78cb8ca2fb3e11185e2eb\", \"net_unittests\": \"dd6e4be34f87c9923492553486eb34798847be39\", \"openscreen_unittests\": \"596418db04cac9abf7c609ed1bb0bd627aa33231\", \"pdf_unittests\": \"9b8f94cbc6e561a0317f41102b22a6fe9df87eee\", \"perfetto_unittests\": \"6ff04075900dc05217c2d30aba467327eb3d34b7\", \"ppapi_unittests\": \"567e6b2a6cfcee03c20aff3ecd8c801309d6303a\", \"printing_unittests\": \"637815a53264eef0768096f3eec45430f4dc2b19\", \"remoting_unittests\": \"ac5eca618ee0017832f823dcc9ece0f1540febd6\", \"sandbox_linux_unittests\": \"8c25d274b354b6d14813c714d9c97571d5586f1f\", \"service_manager_unittests\": \"7897bcf25f748d552c472a742c24ad828934915b\", \"services_unittests\": \"3af4634d73c16eacd99af7b569a875007d4d9c0c\", \"shell_dialogs_unittests\": \"79b64cd77fca9dc08aed5960a2cf428f11ebf497\", \"skia_unittests\": \"7ff96c3c1ac6630663073845b85ec55fba54f051\", \"snapshot_unittests\": \"ce1924ec930540caf537eaf9eb2f28bba0b81a8b\", \"sql_unittests\": \"50d96de9b5d4e57c1e0686b010707d89e81c1c0a\", \"storage_unittests\": \"b5fab3f5e7f5d06006cc3a03a31639a5688166fb\", \"sync_integration_tests\": \"ec481db0ce2a1cae7b16dec6511c850e84723ae4\", \"telemetry_gpu_unittests\": \"14fcf0325488ccbc259ba5ee6d49001a36581fd9\", \"telemetry_perf_unittests\": \"58e5a9a07c6b0bbbfc29c75008f8d64ecdf15363\", \"telemetry_unittests\": \"383467e46ad271e69178b8b47ea824448e680281\", \"traffic_annotation_auditor_unittests\": \"d00645b185dc5c428164ebf6a784778efa28d31b\", \"ui_base_unittests\": \"f1350117410849fc2b0edf8a745fed3b1053a859\", \"ui_touch_selection_unittests\": \"f5d52f36cd9d81dfb0d8341afd5f4938e133cdc7\", \"unit_tests\": \"b7977411c3cf81f62d0720271b4152588ed3875b\", \"url_unittests\": \"7853c81766f4ca28a3f09c2907eef1a080b9b7db\", \"views_perftests\": \"992baa57cad682e33f3797b03ed900fcad482b6c\", \"views_unittests\": \"c3db0fd72fb56844b754f7cccd1465edcddef6e1\", \"viz_unittests\": \"64c571808a0fca0d4ae5445e82aa78bc9dca0548\", \"vr_common_unittests\": \"1bb52537dbd800609cc067ea60b1489e34ef097c\", \"vr_pixeltests\": \"73a441ac78078c98e304acf6b3c36de8212ed826\", \"webdriver_wpt_tests\": \"3be5944c3a8cb702e365f027f90969f5eb73ad77\", \"weblayer_browsertests\": \"47b1c244f91acc6f6484237debda9427be751392\", \"weblayer_unittests\": \"e38736d29d3308c8523d7dc89ec6902402c16084\", \"wm_unittests\": \"a14122bddb49369d12a71922ee38933129d8f42f\", \"wtf_unittests\": \"5c9eda135f6c621338b411394f1cc9910fffb737\", \"x11_unittests\": \"9ece7ed8a75813b8919beb277b62fea36d9878b9\", \"xr_browser_tests\": \"67e73d9e2c0c2d5bde2f77ac5e58483ad5a6da21\", \"zlib_unittests\": \"c794d537a91fad574a22d32b757ea38e54d7ff04\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a2b13219f689d34aa475139e9738b55334bf25923df6aa4978f2ee96f164d56_v2\", \"builder_7ed5e98f023cd9e0abec02df990f9772adc652ea96b625245e768b064914efaf_v2\", \"builder_8f7e40299f74ff9d94767563834f98f87bf45cac7401cd036aab5fd865cb3b58_v2\", \"builder_d1ade7eeaf5d4bfec46cdcc4260ff7266c5ca5ac9f935f1643683e9e2b66e303_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-159-2xnu\"], \"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": "1612355818665662",
"status": "COMPLETED",
"status_changed_ts": "1612358819345689",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180234",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e87ecdb41bba6891cce90527f65e085ec10b4a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021746187162969408",
"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/8856316124662864480/+/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:5180b6a99b8edc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612358819350882",
"url": "https://ci.chromium.org/b/8856316124662864480",
"utcnow_ts": "1612358819677633"
}
}
[13:26:59.686] Invocation finished in 50m25.311516131s with status SUCCEEDED