[19:55:22.948] New invocation is queued and will start shortly
[19:55:24.135] Starting the invocation (attempt 1)
[19:55:24.211] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:55:24.212] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:55:24.212] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032318424746709520",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"648203adeef78218c59b0a086a9ca07293aa096c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9032318424746709520",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/648203adeef78218c59b0a086a9ca07293aa096c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/648203adeef78218c59b0a086a9ca07293aa096c",
"gitiles_ref:refs/heads/master"
]
}
[19:55:25.241] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1602273324364487",
"id": "8866888362590096736",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"648203adeef78218c59b0a086a9ca07293aa096c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602273325072835",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24216",
"builder:Linux Viz",
"buildset:commit/git/648203adeef78218c59b0a086a9ca07293aa096c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/648203adeef78218c59b0a086a9ca07293aa096c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032318424746709520",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8866888362590096736/+/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": "1602273325073052",
"url": "https://ci.chromium.org/b/8866888362590096736",
"utcnow_ts": "1602273325233373"
}
}
[19:55:25.241] Task URL: https://ci.chromium.org/b/8866888362590096736
[19:55:25.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:2:0) after 1m0s
[19:55:28.792] Received PubSub notification, asking Buildbucket for the build status
[19:55:28.877] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:25.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:2:0)
[19:56:25.428] Timer tick, asking Buildbucket for the build status
[19:56:25.607] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:25.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:4:0) after 1m0s
[19:57:25.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:4:0)
[19:57:25.933] Timer tick, asking Buildbucket for the build status
[19:57:26.004] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:26.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:5:0) after 1m0s
[19:58:26.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:5:0)
[19:58:26.174] Timer tick, asking Buildbucket for the build status
[19:58:26.275] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:26.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:6:0) after 1m0s
[19:59:26.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:6:0)
[19:59:26.357] Timer tick, asking Buildbucket for the build status
[19:59:26.474] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:26.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:7:0) after 1m0s
[20:00:26.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:7:0)
[20:00:26.526] Timer tick, asking Buildbucket for the build status
[20:00:26.682] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:26.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:8:0) after 1m0s
[20:01:26.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:8:0)
[20:01:26.720] Timer tick, asking Buildbucket for the build status
[20:01:26.819] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:9:0) after 1m0s
[20:02:26.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:9:0)
[20:02:26.913] Timer tick, asking Buildbucket for the build status
[20:02:26.967] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:26.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:10:0) after 1m0s
[20:03:26.985] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:10:0)
[20:03:26.985] Timer tick, asking Buildbucket for the build status
[20:03:27.117] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:27.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:11:0) after 1m0s
[20:04:27.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:11:0)
[20:04:27.173] Timer tick, asking Buildbucket for the build status
[20:04:27.236] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:27.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:12:0) after 1m0s
[20:05:27.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:12:0)
[20:05:27.227] Timer tick, asking Buildbucket for the build status
[20:05:27.354] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:27.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:13:0) after 1m0s
[20:06:27.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:13:0)
[20:06:27.452] Timer tick, asking Buildbucket for the build status
[20:06:27.533] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:27.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:14:0) after 1m0s
[20:07:27.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:14:0)
[20:07:27.697] Timer tick, asking Buildbucket for the build status
[20:07:27.904] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:27.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:15:0) after 1m0s
[20:08:27.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:15:0)
[20:08:27.993] Timer tick, asking Buildbucket for the build status
[20:08:28.219] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:28.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:16:0) after 1m0s
[20:09:28.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:16:0)
[20:09:28.276] Timer tick, asking Buildbucket for the build status
[20:09:28.602] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:28.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:17:0) after 1m0s
[20:10:28.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:17:0)
[20:10:28.611] Timer tick, asking Buildbucket for the build status
[20:10:28.712] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:28.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:18:0) after 1m0s
[20:11:28.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:18:0)
[20:11:28.876] Timer tick, asking Buildbucket for the build status
[20:11:29.004] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:29.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:19:0) after 1m0s
[20:12:29.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:19:0)
[20:12:29.410] Timer tick, asking Buildbucket for the build status
[20:12:29.466] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:29.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:20:0) after 1m0s
[20:13:29.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:20:0)
[20:13:29.626] Timer tick, asking Buildbucket for the build status
[20:13:29.872] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:29.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:21:0) after 1m0s
[20:14:29.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:21:0)
[20:14:29.917] Timer tick, asking Buildbucket for the build status
[20:14:29.968] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:29.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:22:0) after 1m0s
[20:15:30.080] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:22:0)
[20:15:30.080] Timer tick, asking Buildbucket for the build status
[20:15:30.186] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:30.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:23:0) after 1m0s
[20:16:30.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:23:0)
[20:16:30.315] Timer tick, asking Buildbucket for the build status
[20:16:30.364] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:30.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:24:0) after 1m0s
[20:17:30.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:24:0)
[20:17:30.366] Timer tick, asking Buildbucket for the build status
[20:17:30.421] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:30.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:25:0) after 1m0s
[20:18:30.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:25:0)
[20:18:30.603] Timer tick, asking Buildbucket for the build status
[20:18:30.868] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:30.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:26:0) after 1m0s
[20:19:30.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:26:0)
[20:19:30.945] Timer tick, asking Buildbucket for the build status
[20:19:31.089] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:31.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:27:0) after 1m0s
[20:20:31.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:27:0)
[20:20:31.158] Timer tick, asking Buildbucket for the build status
[20:20:31.281] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:31.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:28:0) after 1m0s
[20:21:31.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:28:0)
[20:21:31.477] Timer tick, asking Buildbucket for the build status
[20:21:31.555] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:31.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:29:0) after 1m0s
[20:22:31.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:29:0)
[20:22:31.598] Timer tick, asking Buildbucket for the build status
[20:22:31.656] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:31.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:30:0) after 1m0s
[20:23:31.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:30:0)
[20:23:31.769] Timer tick, asking Buildbucket for the build status
[20:23:31.940] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:31.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:31:0) after 1m0s
[20:24:31.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:31:0)
[20:24:31.977] Timer tick, asking Buildbucket for the build status
[20:24:32.658] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:32.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:32:0) after 1m0s
[20:25:32.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:32:0)
[20:25:32.816] Timer tick, asking Buildbucket for the build status
[20:25:33.207] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:33.207] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:33:0) after 1m0s
[20:26:33.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:33:0)
[20:26:33.393] Timer tick, asking Buildbucket for the build status
[20:26:33.468] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:34:0) after 1m0s
[20:27:33.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:34:0)
[20:27:33.610] Timer tick, asking Buildbucket for the build status
[20:27:33.668] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:33.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:35:0) after 1m0s
[20:28:33.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:35:0)
[20:28:33.784] Timer tick, asking Buildbucket for the build status
[20:28:33.893] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:33.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:36:0) after 1m0s
[20:29:33.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:36:0)
[20:29:33.942] Timer tick, asking Buildbucket for the build status
[20:29:34.100] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:34.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:37:0) after 1m0s
[20:30:34.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:37:0)
[20:30:34.142] Timer tick, asking Buildbucket for the build status
[20:30:34.264] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:34.264] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:38:0) after 1m0s
[20:31:34.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:38:0)
[20:31:34.341] Timer tick, asking Buildbucket for the build status
[20:31:34.406] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:34.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:39:0) after 1m0s
[20:32:34.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:39:0)
[20:32:34.434] Timer tick, asking Buildbucket for the build status
[20:32:34.643] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:34.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:40:0) after 1m0s
[20:33:34.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:40:0)
[20:33:34.668] Timer tick, asking Buildbucket for the build status
[20:33:35.158] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:35.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:41:0) after 1m0s
[20:34:35.258] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:41:0)
[20:34:35.258] Timer tick, asking Buildbucket for the build status
[20:34:35.373] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:35.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:42:0) after 1m0s
[20:35:35.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:42:0)
[20:35:35.353] Timer tick, asking Buildbucket for the build status
[20:35:35.701] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:35.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:43:0) after 1m0s
[20:36:35.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:43:0)
[20:36:35.748] Timer tick, asking Buildbucket for the build status
[20:36:35.969] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:35.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:44:0) after 1m0s
[20:37:35.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:44:0)
[20:37:35.997] Timer tick, asking Buildbucket for the build status
[20:37:36.096] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:36.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:45:0) after 1m0s
[20:38:36.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:45:0)
[20:38:36.125] Timer tick, asking Buildbucket for the build status
[20:38:36.272] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:36.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:46:0) after 1m0s
[20:39:36.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:46:0)
[20:39:36.301] Timer tick, asking Buildbucket for the build status
[20:39:36.586] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:36.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:47:0) after 1m0s
[20:40:36.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:47:0)
[20:40:36.613] Timer tick, asking Buildbucket for the build status
[20:40:37.068] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:37.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:48:0) after 1m0s
[20:41:37.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:48:0)
[20:41:37.085] Timer tick, asking Buildbucket for the build status
[20:41:38.113] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:38.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:49:0) after 1m0s
[20:42:38.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:49:0)
[20:42:38.212] Timer tick, asking Buildbucket for the build status
[20:42:38.343] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:38.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:50:0) after 1m0s
[20:43:38.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:50:0)
[20:43:38.406] Timer tick, asking Buildbucket for the build status
[20:43:38.584] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:38.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:51:0) after 1m0s
[20:44:38.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:51:0)
[20:44:38.608] Timer tick, asking Buildbucket for the build status
[20:44:38.794] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:38.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:52:0) after 1m0s
[20:45:38.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:52:0)
[20:45:38.871] Timer tick, asking Buildbucket for the build status
[20:45:38.977] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:38.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:53:0) after 1m0s
[20:46:39.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:53:0)
[20:46:39.039] Timer tick, asking Buildbucket for the build status
[20:46:39.118] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:39.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:54:0) after 1m0s
[20:47:39.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:54:0)
[20:47:39.273] Timer tick, asking Buildbucket for the build status
[20:47:39.415] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:39.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:55:0) after 1m0s
[20:48:39.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:55:0)
[20:48:39.476] Timer tick, asking Buildbucket for the build status
[20:48:39.537] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:39.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:56:0) after 1m0s
[20:49:39.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:56:0)
[20:49:39.592] Timer tick, asking Buildbucket for the build status
[20:49:39.657] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:39.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:57:0) after 1m0s
[20:50:39.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:57:0)
[20:50:39.672] Timer tick, asking Buildbucket for the build status
[20:50:39.791] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:39.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:58:0) after 1m0s
[20:51:39.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:58:0)
[20:51:39.832] Timer tick, asking Buildbucket for the build status
[20:51:40.109] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:40.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:59:0) after 1m0s
[20:52:40.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:59:0)
[20:52:40.115] Timer tick, asking Buildbucket for the build status
[20:52:40.178] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:40.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:60:0) after 1m0s
[20:53:40.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:60:0)
[20:53:40.210] Timer tick, asking Buildbucket for the build status
[20:53:40.259] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:40.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:61:0) after 1m0s
[20:54:40.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:61:0)
[20:54:40.297] Timer tick, asking Buildbucket for the build status
[20:54:40.504] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:40.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:62:0) after 1m0s
[20:55:40.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:62:0)
[20:55:40.601] Timer tick, asking Buildbucket for the build status
[20:55:41.082] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:41.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:63:0) after 1m0s
[20:56:41.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:63:0)
[20:56:41.136] Timer tick, asking Buildbucket for the build status
[20:56:41.391] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:41.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:64:0) after 1m0s
[20:57:41.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:64:0)
[20:57:41.438] Timer tick, asking Buildbucket for the build status
[20:57:41.710] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:41.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:65:0) after 1m0s
[20:58:41.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:65:0)
[20:58:41.795] Timer tick, asking Buildbucket for the build status
[20:58:41.860] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:41.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:66:0) after 1m0s
[20:59:42.023] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:66:0)
[20:59:42.023] Timer tick, asking Buildbucket for the build status
[20:59:42.112] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:42.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:67:0) after 1m0s
[21:00:42.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:67:0)
[21:00:42.200] Timer tick, asking Buildbucket for the build status
[21:00:42.484] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:42.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:68:0) after 1m0s
[21:01:42.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:68:0)
[21:01:42.565] Timer tick, asking Buildbucket for the build status
[21:01:42.728] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:42.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:69:0) after 1m0s
[21:02:42.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:69:0)
[21:02:42.766] Timer tick, asking Buildbucket for the build status
[21:02:42.875] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:42.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:70:0) after 1m0s
[21:03:43.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:70:0)
[21:03:43.196] Timer tick, asking Buildbucket for the build status
[21:03:43.253] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:71:0) after 1m0s
[21:04:43.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:71:0)
[21:04:43.356] Timer tick, asking Buildbucket for the build status
[21:04:43.506] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:43.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:72:0) after 1m0s
[21:05:43.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:72:0)
[21:05:43.549] Timer tick, asking Buildbucket for the build status
[21:05:43.692] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:43.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:73:0) after 1m0s
[21:06:43.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:73:0)
[21:06:43.742] Timer tick, asking Buildbucket for the build status
[21:06:43.812] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:43.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:74:0) after 1m0s
[21:07:43.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:74:0)
[21:07:43.811] Timer tick, asking Buildbucket for the build status
[21:07:43.876] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:43.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:75:0) after 1m0s
[21:08:43.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:75:0)
[21:08:43.976] Timer tick, asking Buildbucket for the build status
[21:08:44.056] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:44.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:76:0) after 1m0s
[21:09:44.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:76:0)
[21:09:44.106] Timer tick, asking Buildbucket for the build status
[21:09:44.177] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:44.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:77:0) after 1m0s
[21:10:44.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:77:0)
[21:10:44.293] Timer tick, asking Buildbucket for the build status
[21:10:44.715] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:44.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:78:0) after 1m0s
[21:11:44.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:78:0)
[21:11:44.741] Timer tick, asking Buildbucket for the build status
[21:11:45.233] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:45.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:79:0) after 1m0s
[21:12:45.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:79:0)
[21:12:45.263] Timer tick, asking Buildbucket for the build status
[21:12:45.444] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:45.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:80:0) after 1m0s
[21:13:45.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:80:0)
[21:13:45.517] Timer tick, asking Buildbucket for the build status
[21:13:45.575] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:45.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:81:0) after 1m0s
[21:14:45.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:81:0)
[21:14:45.608] Timer tick, asking Buildbucket for the build status
[21:14:45.660] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:45.660] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:82:0) after 1m0s
[21:15:45.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:82:0)
[21:15:45.697] Timer tick, asking Buildbucket for the build status
[21:15:45.808] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:45.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:83:0) after 1m0s
[21:16:45.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:83:0)
[21:16:45.882] Timer tick, asking Buildbucket for the build status
[21:16:45.963] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:45.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:84:0) after 1m0s
[21:17:46.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:84:0)
[21:17:46.049] Timer tick, asking Buildbucket for the build status
[21:17:46.127] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:46.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:85:0) after 1m0s
[21:18:46.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:85:0)
[21:18:46.178] Timer tick, asking Buildbucket for the build status
[21:18:46.246] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:46.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:86:0) after 1m0s
[21:19:46.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:86:0)
[21:19:46.301] Timer tick, asking Buildbucket for the build status
[21:19:46.387] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:46.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:87:0) after 1m0s
[21:20:46.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:87:0)
[21:20:46.429] Timer tick, asking Buildbucket for the build status
[21:20:46.529] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:88:0) after 1m0s
[21:21:46.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:88:0)
[21:21:46.601] Timer tick, asking Buildbucket for the build status
[21:21:46.664] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:46.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:89:0) after 1m0s
[21:22:46.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:89:0)
[21:22:46.813] Timer tick, asking Buildbucket for the build status
[21:22:46.996] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:46.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:90:0) after 1m0s
[21:23:47.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:90:0)
[21:23:47.045] Timer tick, asking Buildbucket for the build status
[21:23:47.124] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:47.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:91:0) after 1m0s
[21:24:47.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:91:0)
[21:24:47.135] Timer tick, asking Buildbucket for the build status
[21:24:47.205] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:47.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:92:0) after 1m0s
[21:25:47.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:92:0)
[21:25:47.223] Timer tick, asking Buildbucket for the build status
[21:25:47.348] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:47.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:93:0) after 1m0s
[21:26:47.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:93:0)
[21:26:47.400] Timer tick, asking Buildbucket for the build status
[21:26:47.530] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:47.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:94:0) after 1m0s
[21:27:47.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:94:0)
[21:27:47.613] Timer tick, asking Buildbucket for the build status
[21:27:47.792] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:47.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:95:0) after 1m0s
[21:28:47.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:95:0)
[21:28:47.869] Timer tick, asking Buildbucket for the build status
[21:28:47.990] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:47.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:96:0) after 1m0s
[21:29:48.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:96:0)
[21:29:48.039] Timer tick, asking Buildbucket for the build status
[21:29:48.297] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:48.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:97:0) after 1m0s
[21:30:48.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:97:0)
[21:30:48.365] Timer tick, asking Buildbucket for the build status
[21:30:48.466] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:48.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:98:0) after 1m0s
[21:31:48.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:98:0)
[21:31:48.511] Timer tick, asking Buildbucket for the build status
[21:31:48.600] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:48.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:99:0) after 1m0s
[21:32:48.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:99:0)
[21:32:48.682] Timer tick, asking Buildbucket for the build status
[21:32:48.807] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:48.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:100:0) after 1m0s
[21:33:48.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:100:0)
[21:33:48.826] Timer tick, asking Buildbucket for the build status
[21:33:48.932] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:48.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:101:0) after 1m0s
[21:34:49.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:101:0)
[21:34:49.048] Timer tick, asking Buildbucket for the build status
[21:34:49.143] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:49.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:102:0) after 1m0s
[21:35:49.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:102:0)
[21:35:49.197] Timer tick, asking Buildbucket for the build status
[21:35:49.310] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:103:0) after 1m0s
[21:36:49.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:103:0)
[21:36:49.415] Timer tick, asking Buildbucket for the build status
[21:36:49.594] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:49.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:104:0) after 1m0s
[21:37:49.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:104:0)
[21:37:49.578] Timer tick, asking Buildbucket for the build status
[21:37:49.787] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:49.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:105:0) after 1m0s
[21:38:49.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:105:0)
[21:38:49.877] Timer tick, asking Buildbucket for the build status
[21:38:50.078] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:50.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:106:0) after 1m0s
[21:39:50.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:106:0)
[21:39:50.138] Timer tick, asking Buildbucket for the build status
[21:39:50.224] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:50.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:107:0) after 1m0s
[21:40:50.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:107:0)
[21:40:50.274] Timer tick, asking Buildbucket for the build status
[21:40:50.612] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:50.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:108:0) after 1m0s
[21:41:50.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:108:0)
[21:41:50.637] Timer tick, asking Buildbucket for the build status
[21:41:50.819] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:50.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:109:0) after 1m0s
[21:42:50.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:109:0)
[21:42:50.850] Timer tick, asking Buildbucket for the build status
[21:42:50.937] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:50.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:110:0) after 1m0s
[21:43:50.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:110:0)
[21:43:50.974] Timer tick, asking Buildbucket for the build status
[21:43:51.054] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:51.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:111:0) after 1m0s
[21:44:51.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:111:0)
[21:44:51.064] Timer tick, asking Buildbucket for the build status
[21:44:51.165] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:51.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:112:0) after 1m0s
[21:45:51.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:112:0)
[21:45:51.266] Timer tick, asking Buildbucket for the build status
[21:45:51.394] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:51.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:113:0) after 1m0s
[21:46:51.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:113:0)
[21:46:51.449] Timer tick, asking Buildbucket for the build status
[21:46:51.586] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:51.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:114:0) after 1m0s
[21:47:51.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:114:0)
[21:47:51.664] Timer tick, asking Buildbucket for the build status
[21:47:51.797] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:51.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:115:0) after 1m0s
[21:48:51.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:115:0)
[21:48:51.805] Timer tick, asking Buildbucket for the build status
[21:48:51.864] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:51.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:116:0) after 1m0s
[21:49:51.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:116:0)
[21:49:51.932] Timer tick, asking Buildbucket for the build status
[21:49:52.005] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:52.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:117:0) after 1m0s
[21:50:52.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:117:0)
[21:50:52.094] Timer tick, asking Buildbucket for the build status
[21:50:52.228] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:52.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:118:0) after 1m0s
[21:51:52.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:118:0)
[21:51:52.273] Timer tick, asking Buildbucket for the build status
[21:51:52.334] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:52.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:119:0) after 1m0s
[21:52:52.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:119:0)
[21:52:52.380] Timer tick, asking Buildbucket for the build status
[21:52:52.455] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:52.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:120:0) after 1m0s
[21:53:52.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:120:0)
[21:53:52.507] Timer tick, asking Buildbucket for the build status
[21:53:52.793] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:52.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:121:0) after 1m0s
[21:54:52.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:121:0)
[21:54:52.844] Timer tick, asking Buildbucket for the build status
[21:54:52.944] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:52.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:122:0) after 1m0s
[21:55:53.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:122:0)
[21:55:53.036] Timer tick, asking Buildbucket for the build status
[21:55:53.407] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:53.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:123:0) after 1m0s
[21:56:53.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:123:0)
[21:56:53.478] Timer tick, asking Buildbucket for the build status
[21:56:53.539] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:53.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:124:0) after 1m0s
[21:57:53.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:124:0)
[21:57:53.581] Timer tick, asking Buildbucket for the build status
[21:57:54.033] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:54.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:125:0) after 1m0s
[21:58:54.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:125:0)
[21:58:54.059] Timer tick, asking Buildbucket for the build status
[21:58:54.181] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:54.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:126:0) after 1m0s
[21:59:54.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:126:0)
[21:59:54.294] Timer tick, asking Buildbucket for the build status
[21:59:54.360] Build 8866888362590096736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:54.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9032318424746709520:127:0) after 1m0s
[22:00:30.370] Received PubSub notification, asking Buildbucket for the build status
[22:00:30.446] Build 8866888362590096736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:00:30.446] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1602280828893588",
"created_by": "project:chromium",
"created_ts": "1602273324364487",
"id": "8866888362590096736",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"648203adeef78218c59b0a086a9ca07293aa096c\"}}",
"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\": \"648203adeef78218c59b0a086a9ca07293aa096c\", \"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-east1-d-8-15-27v3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 24216, \"got_angle_revision\": \"b2ff69f856d98312bbfe14f80d084b7d8795281f\", \"got_dawn_revision\": \"793a07e366362f2e3cdf07e144c1eff8de18efba\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"648203adeef78218c59b0a086a9ca07293aa096c\", \"got_revision_cp\": \"refs/heads/master@{#815741}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"51b03d58fb17b09120c886d5df36fb191d12fa8e\", \"got_v8_revision\": \"e4ed803f4c09ff72bb1eaadf59ec31facf507368\", \"got_v8_revision_cp\": \"refs/heads/8.8.64@{#1}\", \"got_webrtc_revision\": \"eacbd972ab7e85e9d61851ced57aba1eb194de3d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32316}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"648203adeef78218c59b0a086a9ca07293aa096c\", \"swarm_hashes\": {\"blink_web_tests\": \"c24a72fb780ff6e61868293aa3dbd9d271590fcc\", \"browser_tests\": \"b12504303da5c37eb56c3e28a9b87901cd554c49\", \"content_browsertests\": \"f08cd88a603d31fcf918bd246b2178cfc978a7b0\"}, \"swarm_hashes_refs/heads/master(at){#815741}_without_patch\": {\"blink_web_tests\": \"c24a72fb780ff6e61868293aa3dbd9d271590fcc\", \"browser_tests\": \"b12504303da5c37eb56c3e28a9b87901cd554c49\", \"content_browsertests\": \"f08cd88a603d31fcf918bd246b2178cfc978a7b0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_425d15596feb4c1a31f11fc1911a8217c0e7ee1a27198f37227d51247058a439_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_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-east1-d-8-15-27v3\"], \"image\": [\"chrome-xenial-20100400-30cd36dae64\"], \"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\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602273327672034",
"status": "COMPLETED",
"status_changed_ts": "1602280829590497",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24216",
"builder:Linux Viz",
"buildset:commit/git/648203adeef78218c59b0a086a9ca07293aa096c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/648203adeef78218c59b0a086a9ca07293aa096c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032318424746709520",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8866888362590096736/+/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:4f27c038ea22e710",
"user_agent:luci-scheduler"
],
"updated_ts": "1602280829613259",
"url": "https://ci.chromium.org/b/8866888362590096736",
"utcnow_ts": "1602280830432930"
}
}
[22:00:30.446] Invocation finished in 2h5m7.521674699s with status SUCCEEDED