[12:01:40.239] New invocation is queued and will start shortly
[12:01:41.423] Starting the invocation (attempt 1)
[12:01:41.493] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:01:41.493] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:01:41.493] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021748383042500592",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021748383042500592",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master"
]
}
[12:01:49.220] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612353702396833",
"id": "8856318320114451616",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612353707816540",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45110",
"builder:linux-chromeos-rel",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021748383042500592",
"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/8856318320114451616/+/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": "1612353707816688",
"url": "https://ci.chromium.org/b/8856318320114451616",
"utcnow_ts": "1612353709167863"
}
}
[12:01:49.220] Task URL: https://ci.chromium.org/b/8856318320114451616
[12:01:49.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:2:0) after 1m0s
[12:02:49.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:2:0)
[12:02:49.265] Timer tick, asking Buildbucket for the build status
[12:02:51.425] Build 8856318320114451616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:51.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:3:0) after 1m0s
[12:02:58.750] Received PubSub notification, asking Buildbucket for the build status
[12:02:59.624] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:51.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:3:0)
[12:03:51.580] Timer tick, asking Buildbucket for the build status
[12:03:52.897] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:52.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:5:0) after 1m0s
[12:04:52.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:5:0)
[12:04:52.927] Timer tick, asking Buildbucket for the build status
[12:04:54.202] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:54.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:6:0) after 1m0s
[12:05:54.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:6:0)
[12:05:54.406] Timer tick, asking Buildbucket for the build status
[12:05:55.649] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:55.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:7:0) after 1m0s
[12:06:55.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:7:0)
[12:06:55.735] Timer tick, asking Buildbucket for the build status
[12:06:57.007] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:57.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:8:0) after 1m0s
[12:07:57.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:8:0)
[12:07:57.020] Timer tick, asking Buildbucket for the build status
[12:07:58.592] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:58.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:9:0) after 1m0s
[12:08:58.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:9:0)
[12:08:58.656] Timer tick, asking Buildbucket for the build status
[12:08:59.200] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:59.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:10:0) after 1m0s
[12:09:59.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:10:0)
[12:09:59.214] Timer tick, asking Buildbucket for the build status
[12:10:02.428] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:02.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:11:0) after 1m0s
[12:11:02.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:11:0)
[12:11:02.557] Timer tick, asking Buildbucket for the build status
[12:11:05.854] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:05.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:12:0) after 1m0s
[12:12:05.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:12:0)
[12:12:05.877] Timer tick, asking Buildbucket for the build status
[12:12:08.444] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:08.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:13:0) after 1m0s
[12:13:09.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:13:0)
[12:13:09.149] Timer tick, asking Buildbucket for the build status
[12:13:10.063] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:10.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:14:0) after 1m0s
[12:14:10.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:14:0)
[12:14:10.125] Timer tick, asking Buildbucket for the build status
[12:14:12.428] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:12.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:15:0) after 1m0s
[12:15:12.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:15:0)
[12:15:12.493] Timer tick, asking Buildbucket for the build status
[12:15:12.679] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:12.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:16:0) after 1m0s
[12:16:12.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:16:0)
[12:16:12.715] Timer tick, asking Buildbucket for the build status
[12:16:13.493] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:13.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:17:0) after 1m0s
[12:17:13.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:17:0)
[12:17:13.538] Timer tick, asking Buildbucket for the build status
[12:17:14.868] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:14.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:18:0) after 1m0s
[12:18:14.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:18:0)
[12:18:14.938] Timer tick, asking Buildbucket for the build status
[12:18:16.441] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:19:0) after 1m0s
[12:19:16.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:19:0)
[12:19:16.486] Timer tick, asking Buildbucket for the build status
[12:19:16.791] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:16.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:20:0) after 1m0s
[12:20:16.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:20:0)
[12:20:16.955] Timer tick, asking Buildbucket for the build status
[12:20:17.175] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:17.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:21:0) after 1m0s
[12:21:17.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:21:0)
[12:21:17.225] Timer tick, asking Buildbucket for the build status
[12:21:19.647] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:19.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:22:0) after 1m0s
[12:22:19.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:22:0)
[12:22:19.709] Timer tick, asking Buildbucket for the build status
[12:22:22.590] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:22.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:23:0) after 1m0s
[12:23:22.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:23:0)
[12:23:22.671] Timer tick, asking Buildbucket for the build status
[12:23:23.362] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:23.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:24:0) after 1m0s
[12:24:23.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:24:0)
[12:24:23.419] Timer tick, asking Buildbucket for the build status
[12:24:23.644] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:23.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:25:0) after 1m0s
[12:25:23.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:25:0)
[12:25:23.690] Timer tick, asking Buildbucket for the build status
[12:25:25.039] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:26:0) after 1m0s
[12:26:25.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:26:0)
[12:26:25.081] Timer tick, asking Buildbucket for the build status
[12:26:25.395] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:25.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:27:0) after 1m0s
[12:27:25.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:27:0)
[12:27:25.448] Timer tick, asking Buildbucket for the build status
[12:27:26.218] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:26.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:28:0) after 1m0s
[12:28:26.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:28:0)
[12:28:26.252] Timer tick, asking Buildbucket for the build status
[12:28:27.405] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:27.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:29:0) after 1m0s
[12:29:27.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:29:0)
[12:29:27.465] Timer tick, asking Buildbucket for the build status
[12:29:29.105] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:29.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:30:0) after 1m0s
[12:30:29.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:30:0)
[12:30:29.161] Timer tick, asking Buildbucket for the build status
[12:30:30.232] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:31:0) after 1m0s
[12:31:30.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:31:0)
[12:31:30.330] Timer tick, asking Buildbucket for the build status
[12:31:33.126] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:33.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:32:0) after 1m0s
[12:32:33.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:32:0)
[12:32:33.154] Timer tick, asking Buildbucket for the build status
[12:32:33.638] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:33.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:33:0) after 1m0s
[12:33:33.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:33:0)
[12:33:33.715] Timer tick, asking Buildbucket for the build status
[12:33:34.841] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:34.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:34:0) after 1m0s
[12:34:34.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:34:0)
[12:34:34.864] Timer tick, asking Buildbucket for the build status
[12:34:34.957] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:34.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:35:0) after 1m0s
[12:35:35.044] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:35:0)
[12:35:35.044] Timer tick, asking Buildbucket for the build status
[12:35:37.569] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:37.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:36:0) after 1m0s
[12:36:37.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:36:0)
[12:36:37.604] Timer tick, asking Buildbucket for the build status
[12:36:38.554] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:38.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:37:0) after 1m0s
[12:37:38.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:37:0)
[12:37:38.607] Timer tick, asking Buildbucket for the build status
[12:37:38.787] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:38.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:38:0) after 1m0s
[12:38:38.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:38:0)
[12:38:38.767] Timer tick, asking Buildbucket for the build status
[12:38:38.928] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:38.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:39:0) after 1m0s
[12:39:38.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:39:0)
[12:39:38.979] Timer tick, asking Buildbucket for the build status
[12:39:40.821] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:40.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:40:0) after 1m0s
[12:40:40.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:40:0)
[12:40:40.859] Timer tick, asking Buildbucket for the build status
[12:40:41.593] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:41:0) after 1m0s
[12:41:41.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:41:0)
[12:41:41.614] Timer tick, asking Buildbucket for the build status
[12:41:41.890] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:41.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:42:0) after 1m0s
[12:42:41.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:42:0)
[12:42:41.889] Timer tick, asking Buildbucket for the build status
[12:42:42.687] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:42.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:43:0) after 1m0s
[12:43:42.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:43:0)
[12:43:42.813] Timer tick, asking Buildbucket for the build status
[12:43:45.510] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:45.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:44:0) after 1m0s
[12:44:45.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:44:0)
[12:44:45.604] Timer tick, asking Buildbucket for the build status
[12:44:46.385] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:46.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:45:0) after 1m0s
[12:45:46.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:45:0)
[12:45:46.490] Timer tick, asking Buildbucket for the build status
[12:45:46.605] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:46.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:46:0) after 1m0s
[12:46:46.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:46:0)
[12:46:46.662] Timer tick, asking Buildbucket for the build status
[12:46:47.184] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:47.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:47:0) after 1m0s
[12:47:47.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:47:0)
[12:47:47.220] Timer tick, asking Buildbucket for the build status
[12:47:47.538] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:47.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:48:0) after 1m0s
[12:48:47.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:48:0)
[12:48:47.595] Timer tick, asking Buildbucket for the build status
[12:48:47.750] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:47.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:49:0) after 1m0s
[12:49:47.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:49:0)
[12:49:47.762] Timer tick, asking Buildbucket for the build status
[12:49:48.124] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:48.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:50:0) after 1m0s
[12:50:48.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:50:0)
[12:50:48.218] Timer tick, asking Buildbucket for the build status
[12:50:48.428] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:48.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:51:0) after 1m0s
[12:51:48.488] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:51:0)
[12:51:48.488] Timer tick, asking Buildbucket for the build status
[12:51:49.133] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:49.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:52:0) after 1m0s
[12:52:49.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:52:0)
[12:52:49.199] Timer tick, asking Buildbucket for the build status
[12:52:49.563] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:49.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:53:0) after 1m0s
[12:53:49.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:53:0)
[12:53:49.649] Timer tick, asking Buildbucket for the build status
[12:53:49.850] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:54:0) after 1m0s
[12:54:49.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:54:0)
[12:54:49.930] Timer tick, asking Buildbucket for the build status
[12:54:50.644] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:50.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:55:0) after 1m0s
[12:55:50.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:55:0)
[12:55:50.687] Timer tick, asking Buildbucket for the build status
[12:55:52.259] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:52.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:56:0) after 1m0s
[12:56:52.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:56:0)
[12:56:52.289] Timer tick, asking Buildbucket for the build status
[12:56:52.787] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:52.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:57:0) after 1m0s
[12:57:52.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:57:0)
[12:57:52.802] Timer tick, asking Buildbucket for the build status
[12:57:52.947] Build 8856318320114451616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:52.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021748383042500592:58:0) after 1m0s
[12:58:45.610] Received PubSub notification, asking Buildbucket for the build status
[12:58:45.962] Build 8856318320114451616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:58:45.963] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612357119466100",
"created_by": "project:chromium",
"created_ts": "1612353702396833",
"id": "8856318320114451616",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\"}}",
"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\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"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-47-mh2j\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45110, \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"got_revision_cp\": \"refs/heads/master@{#850068}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"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.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\": \"2738891e4296450bae2167ac8203c03d67b374e7\", \"swarm_hashes_refs/heads/master(at){#850068}_without_patch\": {\"absl_hardening_tests\": \"4642a82ee14699f369f2b4c138e14eb6d0f48157\", \"accessibility_unittests\": \"7d33ca91db629ca26c4a346451bb6c7742367542\", \"angle_unittests\": \"2299a011c137318d8915f7c34521691a0fee4275\", \"app_list_unittests\": \"b660d5b7fa7170cdd55571cb687472883525df3d\", \"app_shell_unittests\": \"ddc17addd2dbf559fdc5fdcf9a56451d07bd4521\", \"ash_unittests\": \"c3558a4992a13c6902bd3b1a121398aaeba19c95\", \"aura_unittests\": \"3e4e677ffccd853903dd52d374b291ae0cf2f82d\", \"base_unittests\": \"4c03953457f6644fafe49b4282b0c97cbda96272\", \"base_util_unittests\": \"ee517618b73b6c0d85a144c532645c8740a67157\", \"blink_common_unittests\": \"15ceca815fc2298b869f0611ef5f1d4e9b3e9b20\", \"blink_fuzzer_unittests\": \"aaff286f6a4221e019d02a06e4b746af031cbf6a\", \"blink_heap_unittests\": \"420b1c647cce78c8df0f4a0cceed2496cdc8c1b0\", \"blink_platform_unittests\": \"541d25a5fcf9905ee49b76daeab7125070b8969f\", \"blink_unittests\": \"42fc32405a5b4a1231880a474332ce8a49eca52e\", \"boringssl_crypto_tests\": \"039068d5fe9ae21727c5f1a66e6a7fb6277ba9ab\", \"boringssl_ssl_tests\": \"0e80e5f100e36fb62a75e69de43cf2e88f5790aa\", \"browser_tests\": \"4ed2c1309dccbfe91a3f4599d528301529ad85d1\", \"capture_unittests\": \"1dc69ac48702584ad45728351ccaeaae872b84f3\", \"cast_unittests\": \"6e79d1d68985d1ec38d62f0d0b0541cc39924b59\", \"cc_unittests\": \"678a1acaf9dfa30ca71a3ca699ac297cd96ed5bd\", \"chrome_app_unittests\": \"73a5a4c92072e2c22812919050a95ba874a7fd94\", \"chromedriver_unittests\": \"7b798b145ec04cff7ac1dd45e02c4bd4953909d3\", \"chromeos_components_unittests\": \"1afcf26c53ebd10ee45a86d683ec740e1d03204d\", \"chromeos_unittests\": \"8f2c2fb8b8692d1413df6a5ba13ae517ae54533e\", \"components_browsertests\": \"a29807fa8aacf8b8b391b624aaea6c9a4397813a\", \"components_unittests\": \"bd0bf578646cd735694dad38000a6c618c21afcf\", \"compositor_unittests\": \"83af21c2a0051adbbfcec0d3359edfef2e6f7a5d\", \"content_browsertests\": \"eb90d9e9a3c1e8276e30f89cba5c92640c62eb71\", \"content_unittests\": \"282e754108c17498b54af1698e66ae583ed41cf4\", \"crashpad_tests\": \"fe57e361b7708bd3089383ea99d4b48155ed63e1\", \"crypto_unittests\": \"9de4dab01588737445639bb883db27aa5bc6f40d\", \"dbus_unittests\": \"dc349397260db8df038d10d90aad6024d6038ca2\", \"device_unittests\": \"07750464f099f2a64d15c92ab8e22959355d7434\", \"display_unittests\": \"f4a1c36bcf1e180a191749b13e100334f4b46f7c\", \"events_unittests\": \"45a8154ad567ab7f109d5232431dcf7dda357f52\", \"exo_unittests\": \"425edef822c8d3bb5970bb6cc0edad6bdc0d982e\", \"extensions_browsertests\": \"253817361dfb966d553ec98d2039d268c098586a\", \"extensions_unittests\": \"90578451c5e119fbeef99573ef5fed43811310d1\", \"filesystem_service_unittests\": \"7b0040678de5d4d6947a7b8cab96a4852ca9f3d1\", \"gcm_unit_tests\": \"da292976f1152bec26346af58ab5f42dea0b57be\", \"gfx_unittests\": \"d67f5eca4eeef13d195df7d8ebf4b1c39e772a5b\", \"gin_unittests\": \"96a3840ac4d859d3041f476238a9dd8df1487684\", \"gl_unittests_ozone\": \"de87805a720ee4a434d0847e2c761710c05389f8\", \"google_apis_unittests\": \"5d7ad4ec86c9d24d1e07f21912a7416c48c0b3f2\", \"gpu_unittests\": \"317abf6013b6f89d0e53ae3eb2abb1c4d723cd57\", \"gwp_asan_unittests\": \"bcca2d8e6d535cc6e840317c27b63eddd8d5e9fa\", \"interactive_ui_tests\": \"f80c158587385a3866d27dcdc982270754c77eee\", \"ipc_tests\": \"926d749852a61bc6a48d4588d907c9a2da54905f\", \"jingle_unittests\": \"894e0d95c6e62e7f7be40a1dcf49ca2ebb83207a\", \"keyboard_unittests\": \"8395d843964a32f2021838b5b2583b61f81052c7\", \"latency_unittests\": \"e9f9ea4bad8ef4e9620c394b99581338d8e71c25\", \"libjingle_xmpp_unittests\": \"03d5d09d59ee3c1ae1e4d7eeef070e5546837c73\", \"liburlpattern_unittests\": \"77acefe0e6a4bbddcb44461af9e829f5177a7126\", \"media_blink_unittests\": \"0d4cf779a2c40aab514af3eda8f24a3dc51acb5d\", \"media_unittests\": \"c5d9472457de6b093e2f9abae218ef4e9ec957a9\", \"message_center_unittests\": \"85e21a8ccd4d03ae65089912774cfb3de6fbd9ac\", \"midi_unittests\": \"ab5fd331f3682489dcfb7f51031b5e842927df3e\", \"mojo_core_unittests\": \"27af55139833906963a6e0f3e89e16f77fa0c5a2\", \"mojo_unittests\": \"388e4b11d2b2245518693e4e60044d82bf5e9a3e\", \"nacl_helper_nonsfi_unittests\": \"e919a3c86056a1658fb24a7f31f951964c95bdeb\", \"nacl_loader_unittests\": \"db3774098744ece3b4789cc54aa57b95c09a38a0\", \"native_theme_unittests\": \"2ca29dd32455d3fbac5e0670cc3a121b8a60d19c\", \"net_unittests\": \"2eaa1e508b7badc4296d7c75e1f6892719973f87\", \"ozone_gl_unittests\": \"69a753e5f8f320765ca4bdb648de6d71185b008e\", \"ozone_unittests\": \"f0539271fd7eda3aa136856ab2d1226a3095b203\", \"ozone_x11_unittests\": \"871f8a92e96176168c7235966bc02dbdba1ce268\", \"pdf_unittests\": \"fff1bec7603ae632e3a4a8d7132106175e243efc\", \"perfetto_unittests\": \"9192d075680f45e43383d5a8742e7ff333ec5afe\", \"ppapi_unittests\": \"adb5ec76f90416ced905e8150f0fa96f295b8c20\", \"printing_unittests\": \"989137b8026ccd5c4b4a78ff01dd3199f411e887\", \"remoting_unittests\": \"ea91008087dfc54686e52ab6eb16ce6ec084e0e3\", \"sandbox_linux_unittests\": \"fff69ab6448198aa7ab9eca45e6dd0df1f298167\", \"service_manager_unittests\": \"8998892793052088975598cd9e19306b6ab18abc\", \"services_unittests\": \"a9258e7f9f007381d65ceeb4ceb7b04238b054cc\", \"shell_dialogs_unittests\": \"f0cb503e98f55fdfd986a59973cb827d7dace90e\", \"shell_encryption_unittests\": \"328fbb00725998dcdfddf416eefede53c812b177\", \"skia_unittests\": \"f7b86b9837d2684230246ab810354b513e5cf257\", \"snapshot_unittests\": \"1d2a7c3322412fbdcfcbc35396dec1d230fb424f\", \"sql_unittests\": \"3f8e8a0c9a3d4c4e6607d98c2c3fe02c442cbeca\", \"storage_unittests\": \"9c2b3fab765a610f95eaf5b11f4a66d239a1355b\", \"sync_integration_tests\": \"40eb21f113c332700a71db6e20d1baccffa14130\", \"traffic_annotation_auditor_unittests\": \"5b15af4775cfcc8b91cc7cc26ea15d86af7a123c\", \"ui_base_unittests\": \"5f62927817194fe0d9f04c23e7bd0365ad5e92df\", \"ui_chromeos_unittests\": \"01860dee0c11b8c14e841f8fd7be46dc17f6290b\", \"ui_touch_selection_unittests\": \"edab2ab49859c3647d56c7423155ea7bdb73c90f\", \"unit_tests\": \"5dbb352cce4ddfaa333f8e57cc0c48196b973b71\", \"url_unittests\": \"d99ea02081d1606968576b43bc83088b8bdd2b88\", \"usage_time_limit_unittests\": \"ea01c826db081b342345f8fb2680c094e2cc20ba\", \"views_unittests\": \"b618efae093dd21fc190957cde21e2d917c62a3c\", \"viz_unittests\": \"0a8e721e8c17fc7dae3f11a15f83eec49be6dd22\", \"wayland_client_perftests\": \"459b5fb47ab9e68c2356d9f080dc15500402393a\", \"wm_unittests\": \"223c72ffbe899933b4639e0d43fe803c1888a0e7\", \"wtf_unittests\": \"0d58ca019763a73b586df701584c7d01a143ff46\", \"zlib_unittests\": \"43f3c91e914694611d90c229f43777f3a28bfdcc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9a735a3fee4d607751977496b4455c795aec36ba7fa141f862fdf473488122eb_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_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-47-mh2j\"], \"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": "1612353770809571",
"status": "COMPLETED",
"status_changed_ts": "1612357121105573",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45110",
"builder:linux-chromeos-rel",
"buildset:commit/git/2738891e4296450bae2167ac8203c03d67b374e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2738891e4296450bae2167ac8203c03d67b374e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021748383042500592",
"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/8856318320114451616/+/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:518096c01e379f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612357121514341",
"url": "https://ci.chromium.org/b/8856318320114451616",
"utcnow_ts": "1612357125943958"
}
}
[12:58:45.963] Invocation finished in 57m5.742700693s with status SUCCEEDED