[03:02:20.174] New invocation is queued and will start shortly
[03:02:21.278] Starting the invocation (attempt 1)
[03:02:21.323] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:21.323] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:21.323] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022869478625348208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022869478625348208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"gitiles_ref:refs/heads/master"
]
}
[03:02:22.908] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611284541510832",
"id": "8857439416559384896",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611284542513397",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44809",
"builder:linux-chromeos-rel",
"buildset:commit/git/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022869478625348208",
"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/8857439416559384896/+/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": "1611284542513593",
"url": "https://ci.chromium.org/b/8857439416559384896",
"utcnow_ts": "1611284542828399"
}
}
[03:02:22.908] Task URL: https://ci.chromium.org/b/8857439416559384896
[03:02:22.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:2:0) after 1m0s
[03:02:25.871] Received PubSub notification, asking Buildbucket for the build status
[03:02:25.987] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:23.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:2:0)
[03:03:23.039] Timer tick, asking Buildbucket for the build status
[03:03:23.143] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:23.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:4:0) after 1m0s
[03:04:23.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:4:0)
[03:04:23.294] Timer tick, asking Buildbucket for the build status
[03:04:23.363] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:23.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:5:0) after 1m0s
[03:05:23.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:5:0)
[03:05:23.540] Timer tick, asking Buildbucket for the build status
[03:05:23.661] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:23.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:6:0) after 1m0s
[03:06:23.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:6:0)
[03:06:23.817] Timer tick, asking Buildbucket for the build status
[03:06:23.997] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:23.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:7:0) after 1m0s
[03:07:24.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:7:0)
[03:07:24.039] Timer tick, asking Buildbucket for the build status
[03:07:24.193] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:8:0) after 1m0s
[03:08:24.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:8:0)
[03:08:24.283] Timer tick, asking Buildbucket for the build status
[03:08:24.478] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:24.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:9:0) after 1m0s
[03:09:24.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:9:0)
[03:09:24.467] Timer tick, asking Buildbucket for the build status
[03:09:25.052] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:25.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:10:0) after 1m0s
[03:10:25.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:10:0)
[03:10:25.143] Timer tick, asking Buildbucket for the build status
[03:10:26.148] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:26.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:11:0) after 1m0s
[03:11:26.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:11:0)
[03:11:26.238] Timer tick, asking Buildbucket for the build status
[03:11:26.530] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:26.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:12:0) after 1m0s
[03:12:26.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:12:0)
[03:12:26.879] Timer tick, asking Buildbucket for the build status
[03:12:27.072] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:27.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:13:0) after 1m0s
[03:13:27.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:13:0)
[03:13:27.121] Timer tick, asking Buildbucket for the build status
[03:13:27.239] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:27.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:14:0) after 1m0s
[03:14:27.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:14:0)
[03:14:27.453] Timer tick, asking Buildbucket for the build status
[03:14:27.608] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:15:0) after 1m0s
[03:15:27.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:15:0)
[03:15:27.725] Timer tick, asking Buildbucket for the build status
[03:15:27.837] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:27.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:16:0) after 1m0s
[03:16:28.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:16:0)
[03:16:28.010] Timer tick, asking Buildbucket for the build status
[03:16:28.307] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:28.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:17:0) after 1m0s
[03:17:28.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:17:0)
[03:17:28.437] Timer tick, asking Buildbucket for the build status
[03:17:28.824] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:28.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:18:0) after 1m0s
[03:18:28.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:18:0)
[03:18:28.869] Timer tick, asking Buildbucket for the build status
[03:18:29.107] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:29.107] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:19:0) after 1m0s
[03:19:29.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:19:0)
[03:19:29.186] Timer tick, asking Buildbucket for the build status
[03:19:29.257] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:29.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:20:0) after 1m0s
[03:20:29.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:20:0)
[03:20:29.335] Timer tick, asking Buildbucket for the build status
[03:20:29.396] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:29.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:21:0) after 1m0s
[03:21:29.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:21:0)
[03:21:29.490] Timer tick, asking Buildbucket for the build status
[03:21:29.561] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:29.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:22:0) after 1m0s
[03:22:29.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:22:0)
[03:22:29.573] Timer tick, asking Buildbucket for the build status
[03:22:29.692] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:29.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:23:0) after 1m0s
[03:23:29.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:23:0)
[03:23:29.779] Timer tick, asking Buildbucket for the build status
[03:23:29.857] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:29.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:24:0) after 1m0s
[03:24:29.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:24:0)
[03:24:29.937] Timer tick, asking Buildbucket for the build status
[03:24:30.116] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:30.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:25:0) after 1m0s
[03:25:30.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:25:0)
[03:25:30.128] Timer tick, asking Buildbucket for the build status
[03:25:30.195] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:30.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:26:0) after 1m0s
[03:26:30.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:26:0)
[03:26:30.231] Timer tick, asking Buildbucket for the build status
[03:26:30.303] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:30.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:27:0) after 1m0s
[03:27:30.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:27:0)
[03:27:30.373] Timer tick, asking Buildbucket for the build status
[03:27:30.487] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:30.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:28:0) after 1m0s
[03:28:30.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:28:0)
[03:28:30.562] Timer tick, asking Buildbucket for the build status
[03:28:30.629] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:30.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:29:0) after 1m0s
[03:29:30.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:29:0)
[03:29:30.705] Timer tick, asking Buildbucket for the build status
[03:29:30.777] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:30.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:30:0) after 1m0s
[03:30:30.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:30:0)
[03:30:30.940] Timer tick, asking Buildbucket for the build status
[03:30:31.047] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:31.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:31:0) after 1m0s
[03:31:31.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:31:0)
[03:31:31.211] Timer tick, asking Buildbucket for the build status
[03:31:31.470] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:31.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:32:0) after 1m0s
[03:32:31.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:32:0)
[03:32:31.488] Timer tick, asking Buildbucket for the build status
[03:32:31.742] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:31.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:33:0) after 1m0s
[03:33:31.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:33:0)
[03:33:31.889] Timer tick, asking Buildbucket for the build status
[03:33:32.222] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:32.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:34:0) after 1m0s
[03:34:32.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:34:0)
[03:34:32.371] Timer tick, asking Buildbucket for the build status
[03:34:32.441] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:32.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:35:0) after 1m0s
[03:35:32.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:35:0)
[03:35:32.537] Timer tick, asking Buildbucket for the build status
[03:35:32.610] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:32.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:36:0) after 1m0s
[03:36:32.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:36:0)
[03:36:32.674] Timer tick, asking Buildbucket for the build status
[03:36:32.803] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:32.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:37:0) after 1m0s
[03:37:32.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:37:0)
[03:37:32.888] Timer tick, asking Buildbucket for the build status
[03:37:33.358] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:33.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:38:0) after 1m0s
[03:38:33.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:38:0)
[03:38:33.465] Timer tick, asking Buildbucket for the build status
[03:38:33.536] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:33.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:39:0) after 1m0s
[03:39:33.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:39:0)
[03:39:33.663] Timer tick, asking Buildbucket for the build status
[03:39:33.990] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:33.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:40:0) after 1m0s
[03:40:34.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:40:0)
[03:40:34.077] Timer tick, asking Buildbucket for the build status
[03:40:34.143] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:34.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:41:0) after 1m0s
[03:41:34.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:41:0)
[03:41:34.218] Timer tick, asking Buildbucket for the build status
[03:41:34.409] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:42:0) after 1m0s
[03:42:34.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:42:0)
[03:42:34.475] Timer tick, asking Buildbucket for the build status
[03:42:34.573] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:34.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:43:0) after 1m0s
[03:43:34.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:43:0)
[03:43:34.684] Timer tick, asking Buildbucket for the build status
[03:43:35.002] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:35.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:44:0) after 1m0s
[03:44:35.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:44:0)
[03:44:35.183] Timer tick, asking Buildbucket for the build status
[03:44:35.298] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:35.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:45:0) after 1m0s
[03:45:35.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:45:0)
[03:45:35.384] Timer tick, asking Buildbucket for the build status
[03:45:35.503] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:35.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:46:0) after 1m0s
[03:46:35.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:46:0)
[03:46:35.516] Timer tick, asking Buildbucket for the build status
[03:46:35.669] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:35.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:47:0) after 1m0s
[03:47:35.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:47:0)
[03:47:35.757] Timer tick, asking Buildbucket for the build status
[03:47:35.863] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:35.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:48:0) after 1m0s
[03:48:36.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:48:0)
[03:48:36.003] Timer tick, asking Buildbucket for the build status
[03:48:37.039] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:37.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:49:0) after 1m0s
[03:49:37.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:49:0)
[03:49:37.051] Timer tick, asking Buildbucket for the build status
[03:49:38.599] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:38.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:50:0) after 1m0s
[03:50:38.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:50:0)
[03:50:38.595] Timer tick, asking Buildbucket for the build status
[03:50:38.719] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:38.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:51:0) after 1m0s
[03:51:38.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:51:0)
[03:51:38.837] Timer tick, asking Buildbucket for the build status
[03:51:38.993] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:38.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:52:0) after 1m0s
[03:52:39.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:52:0)
[03:52:39.089] Timer tick, asking Buildbucket for the build status
[03:52:39.202] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:39.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:53:0) after 1m0s
[03:53:39.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:53:0)
[03:53:39.306] Timer tick, asking Buildbucket for the build status
[03:53:39.431] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:39.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:54:0) after 1m0s
[03:54:39.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:54:0)
[03:54:39.489] Timer tick, asking Buildbucket for the build status
[03:54:39.612] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:55:0) after 1m0s
[03:55:39.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:55:0)
[03:55:39.620] Timer tick, asking Buildbucket for the build status
[03:55:39.756] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:39.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:56:0) after 1m0s
[03:56:39.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:56:0)
[03:56:39.837] Timer tick, asking Buildbucket for the build status
[03:56:39.998] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:39.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:57:0) after 1m0s
[03:57:40.043] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:57:0)
[03:57:40.043] Timer tick, asking Buildbucket for the build status
[03:57:40.243] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:40.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:58:0) after 1m0s
[03:58:40.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:58:0)
[03:58:40.268] Timer tick, asking Buildbucket for the build status
[03:58:40.521] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:40.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:59:0) after 1m0s
[03:59:40.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:59:0)
[03:59:40.584] Timer tick, asking Buildbucket for the build status
[03:59:40.748] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:40.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:60:0) after 1m0s
[04:00:40.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:60:0)
[04:00:40.790] Timer tick, asking Buildbucket for the build status
[04:00:40.930] Build 8857439416559384896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:40.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022869478625348208:61:0) after 1m0s
[04:00:46.305] Received PubSub notification, asking Buildbucket for the build status
[04:00:46.385] Build 8857439416559384896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:00:46.385] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611288045197823",
"created_by": "project:chromium",
"created_ts": "1611284541510832",
"id": "8857439416559384896",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\"}}",
"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\": \"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\", \"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-1-uong\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44809, \"got_angle_revision\": \"70f493d6e6ab13cb372345af9aac58df0d5add69\", \"got_dawn_revision\": \"b5cfe3a7746c59903d3fe216e3876cb80c1d71c7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\", \"got_revision_cp\": \"refs/heads/master@{#845896}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5b36fbc5ed2b330b2bc02c93ff2bdb58d03ae051\", \"got_v8_revision\": \"bba017b40897bdf1fed939a76260569fe84bdc71\", \"got_v8_revision_cp\": \"refs/heads/9.0.42@{#1}\", \"got_webrtc_revision\": \"6031b74664d0be40d7f913600d17f6d8d1323729\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33052}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5df69caa3381d9edc7c6debdac2dd88f4fa00cab\", \"swarm_hashes_refs/heads/master(at){#845896}_without_patch\": {\"absl_hardening_tests\": \"3151adb7c45a0290ae045b8f0d9c7c12a03c1434\", \"accessibility_unittests\": \"970883c02249abaf8478e533b9f8b9843fe3383e\", \"angle_unittests\": \"0b090b4dac252280423cca053bacc805aed08b4a\", \"app_list_unittests\": \"f5052a31b83aebb54439bc32ff7a46823b58a637\", \"app_shell_unittests\": \"8601834325eaf1fe82d4552ca548c063723c5b01\", \"ash_unittests\": \"e8ea999604a4af77125b8ecf471d99b64274528b\", \"aura_unittests\": \"29c35df842fb589ac74750bec68d1dcf3ec82638\", \"base_unittests\": \"3e3745ced16fc2fd5155563503571304cb514c23\", \"base_util_unittests\": \"27a298df72b9a0b6fbb041c1dc25fbda0f713e4b\", \"blink_common_unittests\": \"ec66d4096d098197cf7874f2f80bbb8486811cb9\", \"blink_fuzzer_unittests\": \"dec031f65a77aa22570b51bd7fa2e1663449d951\", \"blink_heap_unittests\": \"060ad723401e5289bc77dc3d5ed0b7c3be7e34a7\", \"blink_platform_unittests\": \"8520c7edc8a2952b321511184f9a5eb239e9210f\", \"blink_unittests\": \"6039e08d3586a73d27d455d3abf7fbc0d42a949d\", \"boringssl_crypto_tests\": \"2bd7f532816149661c538fc674e72290ab79e90b\", \"boringssl_ssl_tests\": \"dff5cc183b3fff9618bca6792e4d5b9f65f63c11\", \"browser_tests\": \"dce8057d462d416cbf0ec1390440e28c3d90d44a\", \"capture_unittests\": \"dbbd2ad447fe3939d6b26ebbc64a4513384c9b53\", \"cast_unittests\": \"d8b3c234e0f1d44d819bfba16b3f959b0e286343\", \"cc_unittests\": \"f0f393771e7df52865ad3e1dc113c7eda70f621a\", \"chrome_app_unittests\": \"27620bcf1344597aaeadd993b32b62618498f8d4\", \"chromedriver_unittests\": \"59bc0251a0331c5102ecff7e32bbd453d75c3f4f\", \"chromeos_components_unittests\": \"19dadc0fcea0ade6e70a6814845eedce2ce9fabc\", \"chromeos_unittests\": \"7481f0ddec64b9f4c116a57a72e21a99a7735611\", \"components_browsertests\": \"9fdcad1dc54f95cbaf1fc2d65474d12a7fc43e3f\", \"components_unittests\": \"1c2420fe3ccc008452df99d396a23e5e8dea5476\", \"compositor_unittests\": \"60854e9fcd9d40c4cec6c61d86bfd32af946835b\", \"content_browsertests\": \"99481d76c382f5e63e3d0c5cd00e2a8a86aa1c39\", \"content_unittests\": \"0738ac907aa32ef61303a77078473efdbcd9a4a8\", \"crashpad_tests\": \"66dc0c0c3e8386b59ab3fed69e3c91bb2a110ab1\", \"crypto_unittests\": \"38ed057fcad4109e145c579dfc1b6335585bbe32\", \"dbus_unittests\": \"2e53e6fc49457070ee4e217b8f78065a424fa1c6\", \"device_unittests\": \"c5d283f26a3413f7bfc0c86e2bde15dea679caa5\", \"display_unittests\": \"1012e8f6e65b52f2950a964809c1a9ddcb58c762\", \"events_unittests\": \"5de0f7e78ad52df30db1b06d657af80b75434c4c\", \"exo_unittests\": \"ec29197330b4f6b93f0590a31e9ea570c9cc37bf\", \"extensions_browsertests\": \"ef96f6124fd050cc5e3a4e4b23da0f17ef60dd1a\", \"extensions_unittests\": \"38e3d3af9f0f70b61a3207e72cf99117bfec6d58\", \"filesystem_service_unittests\": \"70ce29ea7ddd77520c9dcb6d420c92b3a982039f\", \"gcm_unit_tests\": \"918f7b544f700cd0d0d4c3397eeca11276d04d46\", \"gfx_unittests\": \"2d4af161858f5ca779f493d6598a49cdb5374f64\", \"gin_unittests\": \"8545d3af143183eb5dcd3d42e07d6b01e47cf424\", \"gl_unittests_ozone\": \"2d2d3977e6ba1e7935bad8baf4a26839fa7d0e0f\", \"google_apis_unittests\": \"f1df69b965bc1896d2373ec9ae276fc85815c1f8\", \"gpu_unittests\": \"fe79777f7231b52bd7869de97e785d6bac2299ae\", \"gwp_asan_unittests\": \"99180766f2425246591b609f83d61aec8d241daf\", \"interactive_ui_tests\": \"b97b1d980d17cc597c469a87eb4b3e03c17778cf\", \"ipc_tests\": \"190c96896b397603a0727a72bcf417b5282ddfcf\", \"jingle_unittests\": \"5771e674d99906ad4e871f0a4bf463626aba4401\", \"keyboard_unittests\": \"02c30729c47bb4dd58f657f012625fc3d019eae9\", \"latency_unittests\": \"a54c7781e18720d5fc581279b10f23b894405c61\", \"libjingle_xmpp_unittests\": \"050526312a04a21ce0e9b4c6dd0764e64d7a5fb5\", \"liburlpattern_unittests\": \"5bbb1d1eb8f442cecdf2a6f85064e9133df5c05c\", \"media_blink_unittests\": \"7c7ebd1761187936cdf0ed9415b0e4c826d22475\", \"media_unittests\": \"c27d757543511a031181f3b29092520cce87530c\", \"message_center_unittests\": \"ac95f4b52afb36ecfbf8b1023eb19593b053cc08\", \"midi_unittests\": \"ec4d9a91f869a0f67aa5206d454e505526e6b8bf\", \"mojo_core_unittests\": \"cf60daf7ecaf0405f5ba26696830436f8c3af202\", \"mojo_unittests\": \"8568e0f38500757cb33a9612e9991e346cc1687f\", \"nacl_helper_nonsfi_unittests\": \"88aa628af6030b8cda093aead73ca544a1a27b2d\", \"nacl_loader_unittests\": \"184e430596c43e6128b2581e6a494329f1b39b16\", \"native_theme_unittests\": \"fd5ab5bb6b901f00d74df7196a1a9da3360b9ff4\", \"net_unittests\": \"106d42876ae8ed0b5d2955c31cd4d6c3150f4054\", \"ozone_gl_unittests\": \"ff6815e735dc7fe838b8de73b2fcb074cc76bf93\", \"ozone_unittests\": \"48f4d062f89c9cb10cd0d9f78e212b48d10acb26\", \"ozone_x11_unittests\": \"9a8407a5d6668362dcaffdee48ba4cb17e9760f9\", \"pdf_unittests\": \"f9d7659d55456b2f8772c3d798d4869adfea8d83\", \"perfetto_unittests\": \"37b8dffbefbd848bee3e44da9fde88c1072b3cd9\", \"ppapi_unittests\": \"b5ff590d1a84a99a7b200ccc61a16714cf520c6a\", \"printing_unittests\": \"8cd053b6bf5a57b53d22a36d67b9fc500cebf3b9\", \"remoting_unittests\": \"f4e17679cbdc1a068ee4e5a2b0f35ca7899d5d69\", \"sandbox_linux_unittests\": \"00988c0136336391ea19e99c0fd70a4859b0c3ba\", \"service_manager_unittests\": \"113969c806db517cd43a651a2cf3a248a0071cf7\", \"services_unittests\": \"4518ec42929784a6063b23e5bd47be61f312500a\", \"shell_dialogs_unittests\": \"bcb797be76f9c4ad3bd959d0be8538dddb34c35a\", \"shell_encryption_unittests\": \"d935c74afa8edefdab334a784e5391dc8566bdc0\", \"skia_unittests\": \"3278e7523724b51ffe23c179b161cf4ea621b131\", \"snapshot_unittests\": \"be50b960c74487a9186a939f8f61bb644d5dfb74\", \"sql_unittests\": \"01138a02d5f3d92f7b67408c072d4fae492f5bf3\", \"storage_unittests\": \"13e390def6dec256a2b527034f4d161fb32cebd5\", \"sync_integration_tests\": \"489b7bfdb4355f8a171082dae69b26499f1b7a14\", \"traffic_annotation_auditor_unittests\": \"21cf6ef28704a3712024d8bef70b052a69355b0b\", \"ui_base_unittests\": \"11e93933e806cbd45dbcb861514248f1f754e1cf\", \"ui_chromeos_unittests\": \"072817f696435e9c7f9a2398fc88c00bf3fb44bd\", \"ui_touch_selection_unittests\": \"e8933d5d12e0e1dc7365e1f331589bdd8a8ec587\", \"unit_tests\": \"8cf5532230fac4ce9378229f48d044a11a4d1510\", \"url_unittests\": \"011d043dcb91db1e30eca413a101394045ca9ab1\", \"usage_time_limit_unittests\": \"97a00278868f32bead56f78429b8cead35f7d11c\", \"views_unittests\": \"1c80061c2554b4fe7ab5711de410e9c9009f7982\", \"viz_unittests\": \"a1a5abd6a539c876c2dd340e93e726a518f2fad4\", \"wayland_client_perftests\": \"d22d5ff0242b53fcb2b10680c200be5c8a0ae3b0\", \"wm_unittests\": \"bc214ea4cf710912aa8c04e4c7201a75ce9a5636\", \"wtf_unittests\": \"cca080c2d4d19e32538ab23bd59e983d79be14ac\", \"zlib_unittests\": \"c417f19b97ac6cfbd8e089c18898cfeea1d40f0a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_821d31345f542667479b2b218f49698206c649e4692b986670b0d93c55460459_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_d1ade7eeaf5d4bfec46cdcc4260ff7266c5ca5ac9f935f1643683e9e2b66e303_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-1-uong\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611284545121908",
"status": "COMPLETED",
"status_changed_ts": "1611288045645413",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44809",
"builder:linux-chromeos-rel",
"buildset:commit/git/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5df69caa3381d9edc7c6debdac2dd88f4fa00cab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022869478625348208",
"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/8857439416559384896/+/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:5140dc7da3bfba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611288045651738",
"url": "https://ci.chromium.org/b/8857439416559384896",
"utcnow_ts": "1611288046377209"
}
}
[04:00:46.385] Invocation finished in 58m26.228164163s with status SUCCEEDED