[00:43:16.111] New invocation is queued and will start shortly
[00:43:17.208] Starting the invocation (attempt 1)
[00:43:17.254] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:43:17.254] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:43:17.254] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030578970154862816",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9030578970154862816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"gitiles_ref:refs/heads/master"
]
}
[00:43:18.120] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603932197352970",
"id": "8865148908187779120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603932197968460",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42367",
"builder:linux-chromeos-rel",
"buildset:commit/git/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030578970154862816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865148908187779120/+/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": "1603932197968688",
"url": "https://ci.chromium.org/b/8865148908187779120",
"utcnow_ts": "1603932198110840"
}
}
[00:43:18.120] Task URL: https://ci.chromium.org/b/8865148908187779120
[00:43:18.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:2:0) after 1m0s
[00:43:20.057] Received PubSub notification, asking Buildbucket for the build status
[00:43:20.141] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:18.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:2:0)
[00:44:18.145] Timer tick, asking Buildbucket for the build status
[00:44:18.253] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:18.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:4:0) after 1m0s
[00:45:18.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:4:0)
[00:45:18.277] Timer tick, asking Buildbucket for the build status
[00:45:18.589] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:18.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:5:0) after 1m0s
[00:46:18.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:5:0)
[00:46:18.595] Timer tick, asking Buildbucket for the build status
[00:46:18.747] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:18.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:6:0) after 1m0s
[00:47:18.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:6:0)
[00:47:18.806] Timer tick, asking Buildbucket for the build status
[00:47:18.966] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:18.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:7:0) after 1m0s
[00:48:18.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:7:0)
[00:48:18.992] Timer tick, asking Buildbucket for the build status
[00:48:19.112] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:19.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:8:0) after 1m0s
[00:49:19.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:8:0)
[00:49:19.123] Timer tick, asking Buildbucket for the build status
[00:49:19.559] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:19.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:9:0) after 1m0s
[00:50:19.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:9:0)
[00:50:19.584] Timer tick, asking Buildbucket for the build status
[00:50:19.651] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:19.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:10:0) after 1m0s
[00:51:19.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:10:0)
[00:51:19.698] Timer tick, asking Buildbucket for the build status
[00:51:19.760] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:19.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:11:0) after 1m0s
[00:52:19.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:11:0)
[00:52:19.766] Timer tick, asking Buildbucket for the build status
[00:52:20.065] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:20.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:12:0) after 1m0s
[00:53:20.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:12:0)
[00:53:20.095] Timer tick, asking Buildbucket for the build status
[00:53:20.377] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:20.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:13:0) after 1m0s
[00:54:20.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:13:0)
[00:54:20.361] Timer tick, asking Buildbucket for the build status
[00:54:20.424] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:20.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:14:0) after 1m0s
[00:55:20.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:14:0)
[00:55:20.482] Timer tick, asking Buildbucket for the build status
[00:55:20.552] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:20.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:15:0) after 1m0s
[00:56:20.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:15:0)
[00:56:20.571] Timer tick, asking Buildbucket for the build status
[00:56:20.654] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:20.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:16:0) after 1m0s
[00:57:20.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:16:0)
[00:57:20.679] Timer tick, asking Buildbucket for the build status
[00:57:20.913] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:20.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:17:0) after 1m0s
[00:58:20.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:17:0)
[00:58:20.923] Timer tick, asking Buildbucket for the build status
[00:58:21.061] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:21.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:18:0) after 1m0s
[00:59:21.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:18:0)
[00:59:21.171] Timer tick, asking Buildbucket for the build status
[00:59:21.338] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:19:0) after 1m0s
[01:00:21.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:19:0)
[01:00:21.372] Timer tick, asking Buildbucket for the build status
[01:00:21.585] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:21.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:20:0) after 1m0s
[01:01:21.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:20:0)
[01:01:21.614] Timer tick, asking Buildbucket for the build status
[01:01:21.890] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:21.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:21:0) after 1m0s
[01:02:21.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:21:0)
[01:02:21.936] Timer tick, asking Buildbucket for the build status
[01:02:22.018] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:22.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:22:0) after 1m0s
[01:03:22.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:22:0)
[01:03:22.056] Timer tick, asking Buildbucket for the build status
[01:03:22.238] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:22.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:23:0) after 1m0s
[01:04:22.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:23:0)
[01:04:22.335] Timer tick, asking Buildbucket for the build status
[01:04:22.439] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:22.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:24:0) after 1m0s
[01:05:22.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:24:0)
[01:05:22.447] Timer tick, asking Buildbucket for the build status
[01:05:22.626] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:22.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:25:0) after 1m0s
[01:06:22.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:25:0)
[01:06:22.765] Timer tick, asking Buildbucket for the build status
[01:06:22.833] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:22.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:26:0) after 1m0s
[01:07:22.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:26:0)
[01:07:22.881] Timer tick, asking Buildbucket for the build status
[01:07:23.117] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:23.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:27:0) after 1m0s
[01:08:23.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:27:0)
[01:08:23.179] Timer tick, asking Buildbucket for the build status
[01:08:23.294] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:23.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:28:0) after 1m0s
[01:09:23.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:28:0)
[01:09:23.356] Timer tick, asking Buildbucket for the build status
[01:09:23.426] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:23.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:29:0) after 1m0s
[01:10:23.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:29:0)
[01:10:23.533] Timer tick, asking Buildbucket for the build status
[01:10:23.876] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:23.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:30:0) after 1m0s
[01:11:23.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:30:0)
[01:11:23.900] Timer tick, asking Buildbucket for the build status
[01:11:23.977] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:23.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:31:0) after 1m0s
[01:12:25.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:31:0)
[01:12:25.199] Timer tick, asking Buildbucket for the build status
[01:12:25.285] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:25.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:32:0) after 1m0s
[01:13:25.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:32:0)
[01:13:25.328] Timer tick, asking Buildbucket for the build status
[01:13:25.597] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:33:0) after 1m0s
[01:14:25.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:33:0)
[01:14:25.674] Timer tick, asking Buildbucket for the build status
[01:14:25.835] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:25.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:34:0) after 1m0s
[01:15:25.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:34:0)
[01:15:25.866] Timer tick, asking Buildbucket for the build status
[01:15:25.936] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:25.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:35:0) after 1m0s
[01:16:26.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:35:0)
[01:16:26.001] Timer tick, asking Buildbucket for the build status
[01:16:26.063] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:26.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:36:0) after 1m0s
[01:17:26.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:36:0)
[01:17:26.048] Timer tick, asking Buildbucket for the build status
[01:17:26.125] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:26.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:37:0) after 1m0s
[01:18:26.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:37:0)
[01:18:26.324] Timer tick, asking Buildbucket for the build status
[01:18:26.486] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:26.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:38:0) after 1m0s
[01:19:26.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:38:0)
[01:19:26.698] Timer tick, asking Buildbucket for the build status
[01:19:26.768] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:26.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:39:0) after 1m0s
[01:20:26.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:39:0)
[01:20:26.920] Timer tick, asking Buildbucket for the build status
[01:20:27.168] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:27.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:40:0) after 1m0s
[01:21:27.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:40:0)
[01:21:27.678] Timer tick, asking Buildbucket for the build status
[01:21:27.829] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:27.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:41:0) after 1m0s
[01:22:28.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:41:0)
[01:22:28.728] Timer tick, asking Buildbucket for the build status
[01:22:28.800] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:28.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:42:0) after 1m0s
[01:23:29.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:42:0)
[01:23:29.199] Timer tick, asking Buildbucket for the build status
[01:23:29.365] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:29.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:43:0) after 1m0s
[01:24:29.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:43:0)
[01:24:29.622] Timer tick, asking Buildbucket for the build status
[01:24:31.421] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:31.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:44:0) after 1m0s
[01:25:31.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:44:0)
[01:25:31.464] Timer tick, asking Buildbucket for the build status
[01:25:31.653] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:31.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:45:0) after 1m0s
[01:26:31.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:45:0)
[01:26:31.710] Timer tick, asking Buildbucket for the build status
[01:26:32.108] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:32.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:46:0) after 1m0s
[01:27:32.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:46:0)
[01:27:32.172] Timer tick, asking Buildbucket for the build status
[01:27:32.249] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:32.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:47:0) after 1m0s
[01:28:32.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:47:0)
[01:28:32.345] Timer tick, asking Buildbucket for the build status
[01:28:32.670] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:32.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:48:0) after 1m0s
[01:29:32.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:48:0)
[01:29:32.765] Timer tick, asking Buildbucket for the build status
[01:29:32.878] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:32.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:49:0) after 1m0s
[01:30:32.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:49:0)
[01:30:32.966] Timer tick, asking Buildbucket for the build status
[01:30:33.097] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:33.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:50:0) after 1m0s
[01:31:33.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:50:0)
[01:31:33.214] Timer tick, asking Buildbucket for the build status
[01:31:33.331] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:33.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:51:0) after 1m0s
[01:32:33.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:51:0)
[01:32:33.480] Timer tick, asking Buildbucket for the build status
[01:32:33.885] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:33.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:52:0) after 1m0s
[01:33:33.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:52:0)
[01:33:33.924] Timer tick, asking Buildbucket for the build status
[01:33:34.053] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:34.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:53:0) after 1m0s
[01:34:34.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:53:0)
[01:34:34.121] Timer tick, asking Buildbucket for the build status
[01:34:34.248] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:34.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:54:0) after 1m0s
[01:35:34.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:54:0)
[01:35:34.873] Timer tick, asking Buildbucket for the build status
[01:35:34.998] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:34.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:55:0) after 1m0s
[01:36:35.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:55:0)
[01:36:35.178] Timer tick, asking Buildbucket for the build status
[01:36:36.117] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:36.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:56:0) after 1m0s
[01:37:36.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:56:0)
[01:37:36.239] Timer tick, asking Buildbucket for the build status
[01:37:36.382] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:57:0) after 1m0s
[01:38:36.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:57:0)
[01:38:36.479] Timer tick, asking Buildbucket for the build status
[01:38:36.670] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:36.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:58:0) after 1m0s
[01:39:36.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:58:0)
[01:39:36.807] Timer tick, asking Buildbucket for the build status
[01:39:36.981] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:36.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:59:0) after 1m0s
[01:40:37.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:59:0)
[01:40:37.098] Timer tick, asking Buildbucket for the build status
[01:40:38.174] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:38.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:60:0) after 1m0s
[01:41:38.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:60:0)
[01:41:38.339] Timer tick, asking Buildbucket for the build status
[01:41:38.690] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:38.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:61:0) after 1m0s
[01:42:38.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:61:0)
[01:42:38.756] Timer tick, asking Buildbucket for the build status
[01:42:40.123] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:40.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:62:0) after 1m0s
[01:43:40.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:62:0)
[01:43:40.243] Timer tick, asking Buildbucket for the build status
[01:43:40.584] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:40.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:63:0) after 1m0s
[01:44:40.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:63:0)
[01:44:40.735] Timer tick, asking Buildbucket for the build status
[01:44:40.821] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:40.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:64:0) after 1m0s
[01:45:40.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:64:0)
[01:45:40.907] Timer tick, asking Buildbucket for the build status
[01:45:40.985] Build 8865148908187779120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9030578970154862816:65:0) after 1m0s
[01:45:48.647] Received PubSub notification, asking Buildbucket for the build status
[01:45:49.387] Build 8865148908187779120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:45:49.387] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603935943309063",
"created_by": "project:chromium",
"created_ts": "1603932197352970",
"id": "8865148908187779120",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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\": \"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\", \"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-71-8jzk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42367, \"got_angle_revision\": \"77defeb04693452a4227da1c04e88cc72f690a64\", \"got_dawn_revision\": \"2971eb6749352d057e534d99af0fbad0862da771\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\", \"got_revision_cp\": \"refs/heads/master@{#821980}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"2be25f08deafe1fb1eada032cd3b2aae3021f367\", \"got_v8_revision\": \"81a2c08d617cb1f1d3fbed2b58b307d4916c9741\", \"got_v8_revision_cp\": \"refs/heads/8.8.204@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2\", \"swarm_hashes_refs/heads/master(at){#821980}_without_patch\": {\"absl_hardening_tests\": \"8ed3065bf96d952f397d26d76f7e5c4cd175696e\", \"accessibility_unittests\": \"4559d8c0ae711d81d37401d7013ddd69da6438b3\", \"angle_unittests\": \"2ee544689165d72680fac007ffed99371164cae3\", \"app_list_unittests\": \"4b96c5d4bf9274e7b9087080fc94e35d22fb49fa\", \"app_shell_unittests\": \"b1c0bb81df83256e27f4b1f42905c46135776dca\", \"ash_unittests\": \"c61c587b04e28a2cd83c6e46db6ae9bc7c02f1df\", \"aura_unittests\": \"8ee21fefa48afe3777abea49cba7c6f4566ce16b\", \"base_unittests\": \"bf00baadcc9687d4a130549487f9dfce8ba46102\", \"base_util_unittests\": \"d9783a07ee6ff010f23bc25c531b28290636f53f\", \"blink_common_unittests\": \"f2e66f6451a5cb2c6a0704d678a6d31e6cd825ed\", \"blink_fuzzer_unittests\": \"7b6980a7d44c0b73ec7ac643fdcb453514ecad8e\", \"blink_heap_unittests\": \"d7567530ea5df2c01278b271ba91fd362ac83b31\", \"blink_platform_unittests\": \"83de0484ad688533e8a1a113ed1ae825d73816ec\", \"blink_unittests\": \"df097956c74a9231054089bafee9f69925c598af\", \"boringssl_crypto_tests\": \"279d0995530200fa198ef595f18b91ffbcb49720\", \"boringssl_ssl_tests\": \"9e352109e6d037bb596b0b5eeac2c3f533232ae7\", \"browser_tests\": \"0ba5e5244bda314b9b42b887ea4757f9269d428b\", \"capture_unittests\": \"175c60499055f1e6c79c7ed4c6fc0aeca9678f5d\", \"cast_unittests\": \"3739f3ed5ef6852f9c461279e5162b4f391d4e33\", \"cc_unittests\": \"2856e691bcc8c0824816ca815601ce4cbf5b86fd\", \"chrome_app_unittests\": \"6709a96806a4b677daf416950ada99c778fb98a4\", \"chromedriver_unittests\": \"ffcfa0e0b040caa157b9ff625e272783e2809c27\", \"chromeos_components_unittests\": \"c499efffcb56698bd19bf82a6cec7095b0a73be7\", \"chromeos_unittests\": \"7c22716fe8cd3c5ac52e9c82572936919ba32564\", \"components_browsertests\": \"4cb371171fd4fe01de5a5226b56018a906f99155\", \"components_unittests\": \"30cd39568292db743037ba06485204d6d3700268\", \"compositor_unittests\": \"40495c289fdf615b8a16286d1318e5475901dd72\", \"content_browsertests\": \"41d20faaf516614fe3b0cbdb70dd7e778b58ac2d\", \"content_unittests\": \"84dce93f7c5bbf1ba37be716693dc2bda6d48f55\", \"crypto_unittests\": \"efba00855090980375ccbd7ab3f3b9a30b9f5e71\", \"dbus_unittests\": \"4b2b27589980e923318a0dbc79d4aea85ecd82e0\", \"device_unittests\": \"5d048684b1c89649b07b14bb279eb5c53190e751\", \"display_unittests\": \"78bbfeec3f998b966564eac90eb30f40db467734\", \"events_unittests\": \"09e346cf12c16e22af91920c135083326f76079c\", \"exo_unittests\": \"00c307a6d63c80d4d728bbeddeebf06222ddcad4\", \"extensions_browsertests\": \"c7a23c74a44ca8cf9b0f28628b17bf1b1129037e\", \"extensions_unittests\": \"a669e5e62c71c49ff466289e882f71f63dcca473\", \"filesystem_service_unittests\": \"0067b0decd78d56b5c014c7e6438b6432c516976\", \"gcm_unit_tests\": \"ee6288b7e70304de77afd7e7b9c2c90610c5fcee\", \"gfx_unittests\": \"9eab0eaccf4229a7713daedca9490835d3f8ec3c\", \"gin_unittests\": \"f501aeb52f2ae6af9595b76682c358ccf8c4cc9b\", \"gl_unittests_ozone\": \"453065bf919ce60c1e3c14616dd9b04aea0f29e6\", \"google_apis_unittests\": \"46fb1c7e7964ec787596d50e674314b9086f15be\", \"gpu_unittests\": \"4efdeb76365a0ac7e817e862da764a15679c265b\", \"gwp_asan_unittests\": \"dc2484fb92c39154217ce0c6ba99315c844c7988\", \"interactive_ui_tests\": \"5c075f39b0f27ca3c09f697334dedfe66b960a02\", \"ipc_tests\": \"5aca16b4c062e679cdbb254ea23882cbe173a724\", \"jingle_unittests\": \"592c8d0194a560af2ab579abaff3937bb8770894\", \"keyboard_unittests\": \"c3968257f45990753b45ab63b7bf91e51f272c39\", \"latency_unittests\": \"56db1d8abcc3bd694a1e3883e76aad2734f2bcd3\", \"libjingle_xmpp_unittests\": \"1cd2ba8da867d3fb734f5b45dd4bf9a0929977ff\", \"liburlpattern_unittests\": \"bf4803e92b6265be2ee23006db4edf71c68271de\", \"media_blink_unittests\": \"0bdcc60ed2a90a2eab5754b1c1755f2206a4b81f\", \"media_unittests\": \"40e97a3014c6f56934e615c4caf3d66d4fbd680b\", \"message_center_unittests\": \"c13122f51e93a503b7f31b956deabd6ad8a7f58d\", \"midi_unittests\": \"761bae3b35af3a503a77f09da185e7bbcbaf9289\", \"mojo_core_unittests\": \"c38ddcaec5bf2347c2c9843b3761fe6b1ecfa0fd\", \"mojo_unittests\": \"419ce2bec50ef258b35c266ad8888559331398f0\", \"nacl_helper_nonsfi_unittests\": \"9d8a5b024d6ec2857c571485cb624c476982009c\", \"nacl_loader_unittests\": \"f92cac42e820d036178e066f88f373eae938eddf\", \"native_theme_unittests\": \"7708058f7d07a89288e1411c02c780e577f9654e\", \"net_unittests\": \"349d2e9a48c9b2356a9fd83304ace0d453f79528\", \"ozone_gl_unittests\": \"bd805744b524b4fd4be47f7553f05f984b4bf48e\", \"ozone_unittests\": \"17714a891820694d391f8ce78d6801a931031b59\", \"ozone_x11_unittests\": \"54144b6377bed066f8783c7a0337c862861cf197\", \"pdf_unittests\": \"759a324d21f946f4c88144bcb0f1478f169eac9d\", \"perfetto_unittests\": \"2bd7410c40c467285e469db23e2fe3eaed80117b\", \"ppapi_unittests\": \"d7d386d7972f8473ed3f6e36c5e4bb3f741d8898\", \"printing_unittests\": \"53b0be9d780011ed87654d95cc6ecbda61f10fe1\", \"remoting_unittests\": \"53c3633167fec6d54a90368af90daceeeff2eb34\", \"sandbox_linux_unittests\": \"8c1311fe4c33de0886fed803fe57d3eee18fab73\", \"service_manager_unittests\": \"df6951369258a8900d53da116e97198a1c3ac5d2\", \"services_unittests\": \"3f2c2260f7c9d4db19179f257ab7e23e057dab59\", \"shell_dialogs_unittests\": \"31d9effece99f0de60cc275243064b62335f7d2e\", \"skia_unittests\": \"3facf5cad2e20736a90d4174be7a65031d62d329\", \"snapshot_unittests\": \"bdb63a00bff46a7bb324df7c6c76b94ccc4c5d9a\", \"sql_unittests\": \"0e59bb2dffeb05e10abbf505c367e5e65c3cb8f7\", \"storage_unittests\": \"b077564c75ce1508678521c95a782ed0fc3fe372\", \"sync_integration_tests\": \"6aba52532786b5a9a1dad02759613d0f80f3096f\", \"traffic_annotation_auditor_unittests\": \"e506c53c7a7e0b311ae1ed4b8873d634dd5058c3\", \"ui_base_unittests\": \"6010bdf207a1a9147ab486d2df2a5c02d7b7bc25\", \"ui_chromeos_unittests\": \"a55ba6e5417bd5e4a95b02c098b60561594d2f3b\", \"ui_touch_selection_unittests\": \"9a0acec66e3b68946101e62dd6cf70a8959eed85\", \"unit_tests\": \"13f389b87c34e63f4211bd35e794af4316ee76a0\", \"url_unittests\": \"f38ea9a7aad7d3d86c71833ed2614912b9ff7393\", \"usage_time_limit_unittests\": \"dd625b1a529ef7e6fd0867a1dea5909585549f45\", \"views_unittests\": \"7c3f30699a2c8a95a6c50a38be9650f37ebb2364\", \"viz_unittests\": \"2d9350d70cc468e161a1212cf94a1814f5e08236\", \"wayland_client_perftests\": \"b9b3d36664171b6031234ebefdb096a730d30adc\", \"wm_unittests\": \"5b091d469240903cc72f4907c0299a2ce644a696\", \"wtf_unittests\": \"eacf527b6a28eb2a94db102c4979072a70d954cf\", \"zlib_unittests\": \"1fe7b7abc55ef12d8556c5b1820ca9584e324884\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_8d3964f240d2ac33279430ad9dd2ee384a3154a98873fec61a3662901a9c4b2a_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-71-8jzk\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603932199508521",
"status": "COMPLETED",
"status_changed_ts": "1603935943790871",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42367",
"builder:linux-chromeos-rel",
"buildset:commit/git/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ff9cfc6dc5065742409f27f3068e3b1c7e72ec2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030578970154862816",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865148908187779120/+/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:4f8aa09d3c3d7610",
"user_agent:luci-scheduler"
],
"updated_ts": "1603935943795244",
"url": "https://ci.chromium.org/b/8865148908187779120",
"utcnow_ts": "1603935949326198"
}
}
[01:45:49.387] Invocation finished in 1h2m33.29495854s with status SUCCEEDED