[00:58:09.166] New invocation is queued and will start shortly
[00:58:11.857] Starting the invocation (attempt 1)
[00:58:11.907] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:58:11.909] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:58:11.910] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030940421582444800",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9030940421582444800",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"gitiles_ref:refs/heads/master"
]
}
[00:58:12.527] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603587492038828",
"id": "8865510357906719408",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603587492380875",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18757",
"builder:Linux CFI",
"buildset:commit/git/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030940421582444800",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865510357906719408/+/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": "1603587492381036",
"url": "https://ci.chromium.org/b/8865510357906719408",
"utcnow_ts": "1603587492518547"
}
}
[00:58:12.527] Task URL: https://ci.chromium.org/b/8865510357906719408
[00:58:12.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:2:0) after 1m0s
[00:58:16.041] Received PubSub notification, asking Buildbucket for the build status
[00:58:16.118] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:12.550] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:2:0)
[00:59:12.550] Timer tick, asking Buildbucket for the build status
[00:59:12.637] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:12.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:4:0) after 1m0s
[01:00:12.673] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:4:0)
[01:00:12.673] Timer tick, asking Buildbucket for the build status
[01:00:12.793] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:12.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:5:0) after 1m0s
[01:01:12.838] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:5:0)
[01:01:12.838] Timer tick, asking Buildbucket for the build status
[01:01:13.380] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:13.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:6:0) after 1m0s
[01:02:13.435] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:6:0)
[01:02:13.435] Timer tick, asking Buildbucket for the build status
[01:02:13.504] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:13.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:7:0) after 1m0s
[01:03:13.537] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:7:0)
[01:03:13.537] Timer tick, asking Buildbucket for the build status
[01:03:13.794] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:13.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:8:0) after 1m0s
[01:04:13.898] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:8:0)
[01:04:13.898] Timer tick, asking Buildbucket for the build status
[01:04:13.961] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:13.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:9:0) after 1m0s
[01:05:14.034] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:9:0)
[01:05:14.034] Timer tick, asking Buildbucket for the build status
[01:05:14.104] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:14.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:10:0) after 1m0s
[01:06:14.277] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:10:0)
[01:06:14.277] Timer tick, asking Buildbucket for the build status
[01:06:14.337] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:14.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:11:0) after 1m0s
[01:07:14.415] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:11:0)
[01:07:14.415] Timer tick, asking Buildbucket for the build status
[01:07:14.528] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:14.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:12:0) after 1m0s
[01:08:14.581] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:12:0)
[01:08:14.581] Timer tick, asking Buildbucket for the build status
[01:08:14.680] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:14.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:13:0) after 1m0s
[01:09:14.770] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:13:0)
[01:09:14.770] Timer tick, asking Buildbucket for the build status
[01:09:17.587] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:17.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:14:0) after 1m0s
[01:10:17.694] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:14:0)
[01:10:17.694] Timer tick, asking Buildbucket for the build status
[01:10:17.754] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:17.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:15:0) after 1m0s
[01:11:17.839] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:15:0)
[01:11:17.839] Timer tick, asking Buildbucket for the build status
[01:11:17.922] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:17.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:16:0) after 1m0s
[01:12:17.958] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:16:0)
[01:12:17.958] Timer tick, asking Buildbucket for the build status
[01:12:18.072] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:18.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:17:0) after 1m0s
[01:13:18.126] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:17:0)
[01:13:18.126] Timer tick, asking Buildbucket for the build status
[01:13:18.812] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:18.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:18:0) after 1m0s
[01:14:18.884] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:18:0)
[01:14:18.884] Timer tick, asking Buildbucket for the build status
[01:14:18.946] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:18.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:19:0) after 1m0s
[01:15:19.041] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:19:0)
[01:15:19.041] Timer tick, asking Buildbucket for the build status
[01:15:19.126] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:19.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:20:0) after 1m0s
[01:16:19.183] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:20:0)
[01:16:19.183] Timer tick, asking Buildbucket for the build status
[01:16:19.257] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:19.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:21:0) after 1m0s
[01:17:19.357] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:21:0)
[01:17:19.357] Timer tick, asking Buildbucket for the build status
[01:17:19.440] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:19.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:22:0) after 1m0s
[01:18:19.486] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:22:0)
[01:18:19.486] Timer tick, asking Buildbucket for the build status
[01:18:19.887] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:19.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:23:0) after 1m0s
[01:19:19.918] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:23:0)
[01:19:19.918] Timer tick, asking Buildbucket for the build status
[01:19:19.972] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:19.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:24:0) after 1m0s
[01:20:20.026] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:24:0)
[01:20:20.026] Timer tick, asking Buildbucket for the build status
[01:20:20.176] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:20.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:25:0) after 1m0s
[01:21:20.198] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:25:0)
[01:21:20.198] Timer tick, asking Buildbucket for the build status
[01:21:20.285] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:20.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:26:0) after 1m0s
[01:22:20.359] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:26:0)
[01:22:20.359] Timer tick, asking Buildbucket for the build status
[01:22:20.412] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:20.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:27:0) after 1m0s
[01:23:20.461] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:27:0)
[01:23:20.461] Timer tick, asking Buildbucket for the build status
[01:23:20.674] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:20.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:28:0) after 1m0s
[01:24:20.693] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:28:0)
[01:24:20.693] Timer tick, asking Buildbucket for the build status
[01:24:21.031] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:21.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:29:0) after 1m0s
[01:25:21.055] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:29:0)
[01:25:21.055] Timer tick, asking Buildbucket for the build status
[01:25:21.465] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:21.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:30:0) after 1m0s
[01:26:21.479] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:30:0)
[01:26:21.479] Timer tick, asking Buildbucket for the build status
[01:26:21.702] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:21.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:31:0) after 1m0s
[01:27:21.757] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:31:0)
[01:27:21.757] Timer tick, asking Buildbucket for the build status
[01:27:21.842] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:21.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:32:0) after 1m0s
[01:28:21.848] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:32:0)
[01:28:21.848] Timer tick, asking Buildbucket for the build status
[01:28:21.946] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:21.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:33:0) after 1m0s
[01:29:22.035] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:33:0)
[01:29:22.035] Timer tick, asking Buildbucket for the build status
[01:29:22.114] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:22.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:34:0) after 1m0s
[01:30:22.203] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:34:0)
[01:30:22.203] Timer tick, asking Buildbucket for the build status
[01:30:22.364] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:22.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:35:0) after 1m0s
[01:31:22.386] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:35:0)
[01:31:22.386] Timer tick, asking Buildbucket for the build status
[01:31:22.605] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:22.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:36:0) after 1m0s
[01:32:22.590] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:36:0)
[01:32:22.590] Timer tick, asking Buildbucket for the build status
[01:32:22.661] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:22.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:37:0) after 1m0s
[01:33:22.684] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:37:0)
[01:33:22.684] Timer tick, asking Buildbucket for the build status
[01:33:22.789] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:22.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:38:0) after 1m0s
[01:34:22.812] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:38:0)
[01:34:22.812] Timer tick, asking Buildbucket for the build status
[01:34:22.870] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:22.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:39:0) after 1m0s
[01:35:22.914] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:39:0)
[01:35:22.914] Timer tick, asking Buildbucket for the build status
[01:35:23.102] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:23.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:40:0) after 1m0s
[01:36:23.127] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:40:0)
[01:36:23.127] Timer tick, asking Buildbucket for the build status
[01:36:23.192] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:23.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:41:0) after 1m0s
[01:37:23.265] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:41:0)
[01:37:23.265] Timer tick, asking Buildbucket for the build status
[01:37:23.335] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:23.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:42:0) after 1m0s
[01:38:23.421] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:42:0)
[01:38:23.422] Timer tick, asking Buildbucket for the build status
[01:38:23.546] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:23.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:43:0) after 1m0s
[01:39:23.600] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:43:0)
[01:39:23.600] Timer tick, asking Buildbucket for the build status
[01:39:24.066] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:24.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:44:0) after 1m0s
[01:40:24.098] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:44:0)
[01:40:24.098] Timer tick, asking Buildbucket for the build status
[01:40:24.159] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:24.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:45:0) after 1m0s
[01:41:24.289] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:45:0)
[01:41:24.289] Timer tick, asking Buildbucket for the build status
[01:41:24.362] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:24.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:46:0) after 1m0s
[01:42:24.448] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:46:0)
[01:42:24.448] Timer tick, asking Buildbucket for the build status
[01:42:25.152] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:25.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:47:0) after 1m0s
[01:43:25.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:47:0)
[01:43:25.208] Timer tick, asking Buildbucket for the build status
[01:43:25.295] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:25.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:48:0) after 1m0s
[01:44:25.361] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:48:0)
[01:44:25.361] Timer tick, asking Buildbucket for the build status
[01:44:25.663] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:25.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:49:0) after 1m0s
[01:45:25.707] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:49:0)
[01:45:25.707] Timer tick, asking Buildbucket for the build status
[01:45:25.764] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:25.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:50:0) after 1m0s
[01:46:25.838] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:50:0)
[01:46:25.838] Timer tick, asking Buildbucket for the build status
[01:46:25.908] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:25.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:51:0) after 1m0s
[01:47:25.983] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:51:0)
[01:47:25.983] Timer tick, asking Buildbucket for the build status
[01:47:26.062] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:26.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:52:0) after 1m0s
[01:48:26.095] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:52:0)
[01:48:26.095] Timer tick, asking Buildbucket for the build status
[01:48:26.237] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:26.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:53:0) after 1m0s
[01:49:26.259] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:53:0)
[01:49:26.259] Timer tick, asking Buildbucket for the build status
[01:49:26.328] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:26.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:54:0) after 1m0s
[01:50:26.412] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:54:0)
[01:50:26.412] Timer tick, asking Buildbucket for the build status
[01:50:26.493] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:26.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:55:0) after 1m0s
[01:51:26.589] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:55:0)
[01:51:26.589] Timer tick, asking Buildbucket for the build status
[01:51:27.235] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:27.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:56:0) after 1m0s
[01:52:27.257] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:56:0)
[01:52:27.257] Timer tick, asking Buildbucket for the build status
[01:52:27.515] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:27.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:57:0) after 1m0s
[01:53:27.592] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:57:0)
[01:53:27.592] Timer tick, asking Buildbucket for the build status
[01:53:27.687] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:27.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:58:0) after 1m0s
[01:54:27.717] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:58:0)
[01:54:27.717] Timer tick, asking Buildbucket for the build status
[01:54:27.891] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:27.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:59:0) after 1m0s
[01:55:27.914] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:59:0)
[01:55:27.914] Timer tick, asking Buildbucket for the build status
[01:55:27.971] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:27.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:60:0) after 1m0s
[01:56:27.965] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:60:0)
[01:56:27.965] Timer tick, asking Buildbucket for the build status
[01:56:28.028] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:28.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:61:0) after 1m0s
[01:57:28.052] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:61:0)
[01:57:28.052] Timer tick, asking Buildbucket for the build status
[01:57:28.112] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:28.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:62:0) after 1m0s
[01:58:28.204] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:62:0)
[01:58:28.204] Timer tick, asking Buildbucket for the build status
[01:58:28.601] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:28.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:63:0) after 1m0s
[01:59:28.647] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:63:0)
[01:59:28.647] Timer tick, asking Buildbucket for the build status
[01:59:28.704] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:64:0) after 1m0s
[02:00:28.768] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:64:0)
[02:00:28.768] Timer tick, asking Buildbucket for the build status
[02:00:29.003] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:65:0) after 1m0s
[02:01:29.029] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:65:0)
[02:01:29.029] Timer tick, asking Buildbucket for the build status
[02:01:29.098] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:29.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:66:0) after 1m0s
[02:02:29.128] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:66:0)
[02:02:29.128] Timer tick, asking Buildbucket for the build status
[02:02:29.342] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:29.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:67:0) after 1m0s
[02:03:29.367] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:67:0)
[02:03:29.367] Timer tick, asking Buildbucket for the build status
[02:03:29.466] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:29.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:68:0) after 1m0s
[02:04:29.593] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:68:0)
[02:04:29.593] Timer tick, asking Buildbucket for the build status
[02:04:29.677] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:29.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:69:0) after 1m0s
[02:05:29.698] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:69:0)
[02:05:29.698] Timer tick, asking Buildbucket for the build status
[02:05:29.771] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:29.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:70:0) after 1m0s
[02:06:29.791] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:70:0)
[02:06:29.791] Timer tick, asking Buildbucket for the build status
[02:06:29.852] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:29.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:71:0) after 1m0s
[02:07:29.865] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:71:0)
[02:07:29.866] Timer tick, asking Buildbucket for the build status
[02:07:29.944] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:29.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:72:0) after 1m0s
[02:08:30.002] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:72:0)
[02:08:30.002] Timer tick, asking Buildbucket for the build status
[02:08:30.105] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:30.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:73:0) after 1m0s
[02:09:30.142] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:73:0)
[02:09:30.142] Timer tick, asking Buildbucket for the build status
[02:09:30.232] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:74:0) after 1m0s
[02:10:30.326] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:74:0)
[02:10:30.326] Timer tick, asking Buildbucket for the build status
[02:10:30.385] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:30.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:75:0) after 1m0s
[02:11:30.503] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:75:0)
[02:11:30.503] Timer tick, asking Buildbucket for the build status
[02:11:30.579] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:30.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:76:0) after 1m0s
[02:12:30.617] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:76:0)
[02:12:30.617] Timer tick, asking Buildbucket for the build status
[02:12:30.829] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:30.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:77:0) after 1m0s
[02:13:30.852] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:77:0)
[02:13:30.852] Timer tick, asking Buildbucket for the build status
[02:13:30.970] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:30.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:78:0) after 1m0s
[02:14:33.022] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:78:0)
[02:14:33.022] Timer tick, asking Buildbucket for the build status
[02:14:33.098] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:33.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:79:0) after 1m0s
[02:15:33.444] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:79:0)
[02:15:33.444] Timer tick, asking Buildbucket for the build status
[02:15:33.519] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:33.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:80:0) after 1m0s
[02:16:33.817] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:80:0)
[02:16:33.817] Timer tick, asking Buildbucket for the build status
[02:16:33.993] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:33.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:81:0) after 1m0s
[02:17:34.216] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:81:0)
[02:17:34.216] Timer tick, asking Buildbucket for the build status
[02:17:34.636] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:34.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:82:0) after 1m0s
[02:18:34.780] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:82:0)
[02:18:34.780] Timer tick, asking Buildbucket for the build status
[02:18:34.884] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:34.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:83:0) after 1m0s
[02:19:34.935] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:83:0)
[02:19:34.935] Timer tick, asking Buildbucket for the build status
[02:19:35.009] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:35.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:84:0) after 1m0s
[02:20:35.255] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:84:0)
[02:20:35.255] Timer tick, asking Buildbucket for the build status
[02:20:35.347] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:35.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:85:0) after 1m0s
[02:21:35.509] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:85:0)
[02:21:35.509] Timer tick, asking Buildbucket for the build status
[02:21:35.564] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:35.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:86:0) after 1m0s
[02:22:35.622] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:86:0)
[02:22:35.622] Timer tick, asking Buildbucket for the build status
[02:22:35.703] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:35.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:87:0) after 1m0s
[02:23:35.969] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:87:0)
[02:23:35.969] Timer tick, asking Buildbucket for the build status
[02:23:36.111] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:36.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:88:0) after 1m0s
[02:24:36.177] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:88:0)
[02:24:36.177] Timer tick, asking Buildbucket for the build status
[02:24:36.254] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:36.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:89:0) after 1m0s
[02:25:36.290] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:89:0)
[02:25:36.290] Timer tick, asking Buildbucket for the build status
[02:25:36.363] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:36.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:90:0) after 1m0s
[02:26:36.429] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:90:0)
[02:26:36.429] Timer tick, asking Buildbucket for the build status
[02:26:36.510] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:91:0) after 1m0s
[02:27:36.745] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:91:0)
[02:27:36.745] Timer tick, asking Buildbucket for the build status
[02:27:38.263] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:38.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:92:0) after 1m0s
[02:28:38.511] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:92:0)
[02:28:38.511] Timer tick, asking Buildbucket for the build status
[02:28:38.677] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:38.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:93:0) after 1m0s
[02:29:38.756] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:93:0)
[02:29:38.756] Timer tick, asking Buildbucket for the build status
[02:29:38.894] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:38.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:94:0) after 1m0s
[02:30:39.021] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:94:0)
[02:30:39.021] Timer tick, asking Buildbucket for the build status
[02:30:39.264] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:39.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:95:0) after 1m0s
[02:31:39.286] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:95:0)
[02:31:39.286] Timer tick, asking Buildbucket for the build status
[02:31:39.424] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:39.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:96:0) after 1m0s
[02:32:39.457] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:96:0)
[02:32:39.457] Timer tick, asking Buildbucket for the build status
[02:32:39.586] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:39.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:97:0) after 1m0s
[02:33:39.720] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:97:0)
[02:33:39.720] Timer tick, asking Buildbucket for the build status
[02:33:39.859] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:39.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:98:0) after 1m0s
[02:34:39.980] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:98:0)
[02:34:39.980] Timer tick, asking Buildbucket for the build status
[02:34:40.109] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:40.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:99:0) after 1m0s
[02:35:40.152] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:99:0)
[02:35:40.152] Timer tick, asking Buildbucket for the build status
[02:35:40.355] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:40.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:100:0) after 1m0s
[02:36:40.389] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:100:0)
[02:36:40.389] Timer tick, asking Buildbucket for the build status
[02:36:40.504] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:40.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:101:0) after 1m0s
[02:37:40.574] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:101:0)
[02:37:40.574] Timer tick, asking Buildbucket for the build status
[02:37:40.695] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:40.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:102:0) after 1m0s
[02:38:40.819] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:102:0)
[02:38:40.819] Timer tick, asking Buildbucket for the build status
[02:38:40.941] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:40.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:103:0) after 1m0s
[02:39:41.018] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:103:0)
[02:39:41.018] Timer tick, asking Buildbucket for the build status
[02:39:41.154] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:41.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:104:0) after 1m0s
[02:40:41.260] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:104:0)
[02:40:41.260] Timer tick, asking Buildbucket for the build status
[02:40:41.330] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:41.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:105:0) after 1m0s
[02:41:41.420] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:105:0)
[02:41:41.420] Timer tick, asking Buildbucket for the build status
[02:41:41.527] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:41.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:106:0) after 1m0s
[02:42:41.560] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:106:0)
[02:42:41.560] Timer tick, asking Buildbucket for the build status
[02:42:42.205] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:42.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:107:0) after 1m0s
[02:43:42.187] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:107:0)
[02:43:42.187] Timer tick, asking Buildbucket for the build status
[02:43:42.335] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:42.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:108:0) after 1m0s
[02:44:42.463] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:108:0)
[02:44:42.463] Timer tick, asking Buildbucket for the build status
[02:44:42.593] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:109:0) after 1m0s
[02:45:42.636] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:109:0)
[02:45:42.636] Timer tick, asking Buildbucket for the build status
[02:45:42.707] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:110:0) after 1m0s
[02:46:42.736] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:110:0)
[02:46:42.736] Timer tick, asking Buildbucket for the build status
[02:46:42.858] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:42.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:111:0) after 1m0s
[02:47:42.882] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:111:0)
[02:47:42.882] Timer tick, asking Buildbucket for the build status
[02:47:42.961] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:42.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:112:0) after 1m0s
[02:48:43.084] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:112:0)
[02:48:43.084] Timer tick, asking Buildbucket for the build status
[02:48:43.608] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:43.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:113:0) after 1m0s
[02:49:43.629] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:113:0)
[02:49:43.629] Timer tick, asking Buildbucket for the build status
[02:49:43.895] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:43.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:114:0) after 1m0s
[02:50:43.977] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:114:0)
[02:50:43.977] Timer tick, asking Buildbucket for the build status
[02:50:44.065] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:44.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:115:0) after 1m0s
[02:51:44.183] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:115:0)
[02:51:44.183] Timer tick, asking Buildbucket for the build status
[02:51:44.323] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:44.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:116:0) after 1m0s
[02:52:44.369] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:116:0)
[02:52:44.369] Timer tick, asking Buildbucket for the build status
[02:52:44.514] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:44.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:117:0) after 1m0s
[02:53:44.581] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:117:0)
[02:53:44.581] Timer tick, asking Buildbucket for the build status
[02:53:44.884] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:44.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:118:0) after 1m0s
[02:54:44.954] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:118:0)
[02:54:44.954] Timer tick, asking Buildbucket for the build status
[02:54:45.069] Build 8865510357906719408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:45.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030940421582444800:119:0) after 1m0s
[02:54:54.241] Received PubSub notification, asking Buildbucket for the build status
[02:54:54.315] Build 8865510357906719408: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:54:54.315] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603594493482526",
"created_by": "project:chromium",
"created_ts": "1603587492038828",
"id": "8865510357906719408",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\", \"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-32-19-8law\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"Linux CFI\", \"buildnumber\": 18757, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\", \"got_revision_cp\": \"refs/heads/master@{#820594}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"3065e6ef3016a906d1db82e1d4322cbd53d43247\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32481}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd\", \"swarm_hashes_refs/heads/master(at){#820594}_without_patch\": {\"absl_hardening_tests\": \"64f62512e9f03cbf530bff5711b084547b2ef664\", \"accessibility_unittests\": \"3739945aa5d8bcfcbd0b29739c55080409f2ce3f\", \"angle_unittests\": \"a1749d9f60c0db4381604a60b18caf14ec8ac235\", \"app_shell_unittests\": \"4919b4fe5ba32b9572e1286149086dadb3993bef\", \"aura_unittests\": \"18a4c8a77864a3a0c97cc896af17388cbd5106e0\", \"base_unittests\": \"6c4ed79285daf6081f34b2f9bb7e635e41abf453\", \"base_util_unittests\": \"bd7ba5f422993e3378e5f50327aef1d8f9db4923\", \"blink_common_unittests\": \"a6c2f0055abd616553130179ca456139412e9049\", \"blink_fuzzer_unittests\": \"d57eb4db31814d594bf90d1e9fd51d10d0c6196e\", \"blink_heap_unittests\": \"fc7b4b1939866ba961318d42eb36ed916ef6c016\", \"blink_platform_unittests\": \"253b58230c34c35d8b91833cde0df79dbcf138c4\", \"blink_unittests\": \"1124db479f01ff2a28f44cc83e831a29b2100b87\", \"boringssl_crypto_tests\": \"e149e781e9928a29b4dd9583f17cfba686900c7b\", \"boringssl_ssl_tests\": \"67e991e557e4bcf147544aa74198c7c6c19a1c54\", \"browser_tests\": \"a36fff29c44008c7d1481495939bd88ccb0901f7\", \"capture_unittests\": \"c65a1e95a130051627305b7996906eee72a91638\", \"cast_unittests\": \"ae27d0c76df19edfe7c7621686230f7cee08b67e\", \"cc_unittests\": \"b32ea1bb7212ebc012d9a9aa2fee189d2d4fa836\", \"chrome_app_unittests\": \"27d8058f95a4d2864063f5cfd721deb6b42936da\", \"chromedriver_unittests\": \"7512f82e9f52597e0d1e3274965fcba23fa93103\", \"components_browsertests\": \"9fff8fe8cb34097e84ec3ac7d2212ee99e447bfc\", \"components_unittests\": \"6f51fc6d5fcb858b7185139a11da1c650db71842\", \"compositor_unittests\": \"168e6d8f3a970208d6e5b5b09250910ac71c6617\", \"content_browsertests\": \"ffd2274858344efc08e51b2b43e818214bf63eaa\", \"content_unittests\": \"a6b4ab5303812c8fc3f32a678665fb2ac75a1ded\", \"cronet_tests\": \"34a7f5aa0b198b79c0432388d7b4778143136dc8\", \"cronet_unittests\": \"41d9ae9b8fc4583f20412912b8a04e1a410e3533\", \"crypto_unittests\": \"1fa4a8923d25457a5b0e23a99d4d2bede6a7ceba\", \"dbus_unittests\": \"0182c7b13835f4cce4225d6092d3350a1308b816\", \"device_unittests\": \"09ed4caa769a4d8e41959813260f74875e7112a9\", \"display_unittests\": \"fe60f68ce7141d28f72f1485b74fd438a096b809\", \"events_unittests\": \"8ef9b84a71101b4167b482a24d690dfdc2136133\", \"extensions_browsertests\": \"173c67f69b3def91794d8cecd3cbd9fc24307585\", \"extensions_unittests\": \"e7ada6bc5fa77b666ac886802d46af324da924fa\", \"filesystem_service_unittests\": \"8f90508c7671967fb2c394c6b7a087cf2034f255\", \"gcm_unit_tests\": \"02661237e30f4180db0d76b1a08430c946fd910b\", \"gfx_unittests\": \"9a03ea27f3352505c2dd33a1527c1611da49a623\", \"gin_unittests\": \"bd528d53cfacc7a2bbf1957bc96d1be081594092\", \"gl_tests\": \"93229f565da29620141945bf4d29da9bb6ba2a0b\", \"gl_unittests\": \"26e2346fb941913bfc5019f46c9922f215190a2c\", \"google_apis_unittests\": \"bf89a523537426561107118530b60128294db7bd\", \"gpu_unittests\": \"93049575d660c1e58f8d2edc9d3b17dd8e460254\", \"gwp_asan_unittests\": \"9b4c1237e71d0d3384f222e9fd8250de35a3b406\", \"headless_browsertests\": \"bd1f685638ee4944f07d9db07d06c8edc0c35708\", \"headless_unittests\": \"c664f6b613da99e853cddfb6e1f8874af5171286\", \"interactive_ui_tests\": \"3a9143f5b40d6d83cc4561983afce9e1bc7d05d5\", \"ipc_tests\": \"e608f8ef54c3593e442131bc5ca69bad2447bb80\", \"jingle_unittests\": \"15a5a0007f3cfa873f4116e1a441bb887348a332\", \"latency_unittests\": \"4f81ed8d54222d0450c20b42c42509d65cf7314f\", \"libjingle_xmpp_unittests\": \"04bc62d64e01dd731326a93760cec313311785d2\", \"media_blink_unittests\": \"e4fb62c96a401b96dbe6b9caa4b0e0c7adbd47fd\", \"media_unittests\": \"2c59499b4f69e646aee433aaa333f5366413c5c6\", \"message_center_unittests\": \"b245a5e1b74cff39707f5b886ef63828bd871d38\", \"midi_unittests\": \"0abd54edda4ebf01018e9d9c5bc061b2ad985c84\", \"mojo_core_unittests\": \"f11ade16466cc34749b7a32dbc3aca860f9a8d20\", \"mojo_unittests\": \"8a4ce008f1c628839724626945f2c5be1547d945\", \"nacl_helper_nonsfi_unittests\": \"2ee71dae2e47ae9d8a7e39a784c5186400d7319d\", \"nacl_loader_unittests\": \"dcea8fcf54aa24131ca35971cb1d75bdf20ece9a\", \"native_theme_unittests\": \"054ee7039471ff4c8835f8ad9704397c9d2a297a\", \"net_unittests\": \"653cbf6086987250af9d460b52c012a3d8f74cee\", \"pdf_unittests\": \"6195ab67ad2894d8165b7c691cddb8583b1f2d6b\", \"perfetto_unittests\": \"f52f5e9c31bddf461a7f0a9cc7119b1210c495d0\", \"ppapi_unittests\": \"95fdbe7af30626652b09c926aa9eeba7b606a7b1\", \"printing_unittests\": \"bb674dd07ad20f936fda8ac49aa1208002fca6a3\", \"remoting_unittests\": \"aaf69d541ab27ac63a78fdc64706c9bc36d5dc3c\", \"sandbox_linux_unittests\": \"7a9d081b42297dcd6d9879ba15d2147670786d1a\", \"service_manager_unittests\": \"c1057f7313344ebe7f7f50d0470aa1e07421adf0\", \"services_unittests\": \"635aad6e3025d8ec53b722464aff0ae11c1c161a\", \"shell_dialogs_unittests\": \"594f2915e137e13af5cebdf88699e26ebf789e29\", \"skia_unittests\": \"ca5c1aea2304b33ea083b8bf3f9eb9c73ba02bd5\", \"snapshot_unittests\": \"a5426969ad1fd9ab0f816f1f1da9c27f343ec97d\", \"sql_unittests\": \"6824602b65e1d34bad5d7b33da6d089fb19ad4af\", \"storage_unittests\": \"943e6db1a2997f737f258c5487106e8410230417\", \"sync_integration_tests\": \"a005e8d54d3ac7124cb53ba13d81637c36e7d23c\", \"traffic_annotation_auditor_unittests\": \"d355622da93f150e8d1c87c17afb0479b99e6519\", \"ui_base_unittests\": \"94915ef8c5d27a6893e77ed4fae33581531be1a5\", \"ui_touch_selection_unittests\": \"1b7e23a66aa7008855db2c464e6d7e37e809b9d1\", \"unit_tests\": \"05d98b6290c2d8cae67e865cca1802d039cc626f\", \"url_unittests\": \"28db030ff4a76c96a139160e87d9adabbf82591f\", \"views_unittests\": \"7197693180d30d1b061c97ab0b330a97e850dcaa\", \"viz_unittests\": \"8ab053bc042e3f533c77a9fa0424eb315eecf17d\", \"vr_common_unittests\": \"1271de3bcacdaf12f093c05f67b7d89460895e06\", \"vr_pixeltests\": \"1bf87932679a3b83ad340de5269fb75ea46ea29b\", \"wm_unittests\": \"31bec348c9521126915722dcc4312c2a6a0e21dc\", \"wtf_unittests\": \"14cc959b47235f1eea6d42d826a7e4c537c3569e\", \"zlib_unittests\": \"742eb25b54aa81e53626b9a97f5900e337217451\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4546ef179f50eb727f19fe6abd7705a7b8921ff8263e4e2e8e2644dd615ef1a7_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-19-8law\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603587495144550",
"status": "COMPLETED",
"status_changed_ts": "1603594493766143",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18757",
"builder:Linux CFI",
"buildset:commit/git/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3aacaf7d3beb25e2ec8c2bb66de5e07f38d439fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030940421582444800",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865510357906719408/+/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:4f7614d3b83c7310",
"user_agent:luci-scheduler"
],
"updated_ts": "1603594493774842",
"url": "https://ci.chromium.org/b/8865510357906719408",
"utcnow_ts": "1603594494305865"
}
}
[02:54:54.315] Invocation finished in 1h56m45.165726148s with status SUCCEEDED