[23:11:38.963] New invocation is queued and will start shortly
[23:11:40.753] Starting the invocation (attempt 1)
[23:11:40.798] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:11:40.798] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:11:40.798] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022793395227758992",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"44b4d7f39c4b97b4c5557d14935285316f1948f8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022793395227758992",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"gitiles_ref:refs/heads/master"
]
}
[23:11:41.811] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611357100925491",
"id": "8857363332498715968",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44b4d7f39c4b97b4c5557d14935285316f1948f8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611357101634191",
"tags": [
"build_address:luci.chromium.ci/win-official/1440",
"builder:win-official",
"buildset:commit/git/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022793395227758992",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857363332498715968/+/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": "1611357101634296",
"url": "https://ci.chromium.org/b/8857363332498715968",
"utcnow_ts": "1611357101804284"
}
}
[23:11:41.811] Task URL: https://ci.chromium.org/b/8857363332498715968
[23:11:41.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:2:0) after 1m0s
[23:12:26.973] Received PubSub notification, asking Buildbucket for the build status
[23:12:27.096] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:41.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:2:0)
[23:12:41.935] Timer tick, asking Buildbucket for the build status
[23:12:42.027] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:42.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:4:0) after 1m0s
[23:13:42.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:4:0)
[23:13:42.099] Timer tick, asking Buildbucket for the build status
[23:13:42.236] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:42.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:5:0) after 1m0s
[23:14:42.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:5:0)
[23:14:42.259] Timer tick, asking Buildbucket for the build status
[23:14:42.452] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:42.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:6:0) after 1m0s
[23:15:42.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:6:0)
[23:15:42.434] Timer tick, asking Buildbucket for the build status
[23:15:42.533] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:42.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:7:0) after 1m0s
[23:16:42.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:7:0)
[23:16:42.558] Timer tick, asking Buildbucket for the build status
[23:16:42.724] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:42.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:8:0) after 1m0s
[23:17:42.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:8:0)
[23:17:42.834] Timer tick, asking Buildbucket for the build status
[23:17:42.959] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:9:0) after 1m0s
[23:18:42.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:9:0)
[23:18:42.939] Timer tick, asking Buildbucket for the build status
[23:18:43.044] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:43.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:10:0) after 1m0s
[23:19:43.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:10:0)
[23:19:43.067] Timer tick, asking Buildbucket for the build status
[23:19:43.210] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:43.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:11:0) after 1m0s
[23:20:43.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:11:0)
[23:20:43.247] Timer tick, asking Buildbucket for the build status
[23:20:43.541] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:43.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:12:0) after 1m0s
[23:21:43.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:12:0)
[23:21:43.735] Timer tick, asking Buildbucket for the build status
[23:21:44.035] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:44.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:13:0) after 1m0s
[23:22:44.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:13:0)
[23:22:44.112] Timer tick, asking Buildbucket for the build status
[23:22:44.380] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:44.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:14:0) after 1m0s
[23:23:44.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:14:0)
[23:23:44.420] Timer tick, asking Buildbucket for the build status
[23:23:44.537] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:44.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:15:0) after 1m0s
[23:24:44.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:15:0)
[23:24:44.570] Timer tick, asking Buildbucket for the build status
[23:24:44.802] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:44.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:16:0) after 1m0s
[23:25:44.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:16:0)
[23:25:44.925] Timer tick, asking Buildbucket for the build status
[23:25:44.988] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:44.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:17:0) after 1m0s
[23:26:45.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:17:0)
[23:26:45.058] Timer tick, asking Buildbucket for the build status
[23:26:45.306] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:45.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:18:0) after 1m0s
[23:27:45.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:18:0)
[23:27:45.340] Timer tick, asking Buildbucket for the build status
[23:27:45.397] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:45.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:19:0) after 1m0s
[23:28:45.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:19:0)
[23:28:45.498] Timer tick, asking Buildbucket for the build status
[23:28:45.562] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:45.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:20:0) after 1m0s
[23:29:45.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:20:0)
[23:29:45.652] Timer tick, asking Buildbucket for the build status
[23:29:45.726] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:21:0) after 1m0s
[23:30:45.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:21:0)
[23:30:45.788] Timer tick, asking Buildbucket for the build status
[23:30:45.948] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:45.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:22:0) after 1m0s
[23:31:45.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:22:0)
[23:31:45.960] Timer tick, asking Buildbucket for the build status
[23:31:46.062] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:46.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:23:0) after 1m0s
[23:32:46.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:23:0)
[23:32:46.087] Timer tick, asking Buildbucket for the build status
[23:32:46.155] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:46.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:24:0) after 1m0s
[23:33:46.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:24:0)
[23:33:46.207] Timer tick, asking Buildbucket for the build status
[23:33:46.494] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:46.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:25:0) after 1m0s
[23:34:46.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:25:0)
[23:34:46.490] Timer tick, asking Buildbucket for the build status
[23:34:46.613] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:46.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:26:0) after 1m0s
[23:35:46.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:26:0)
[23:35:46.619] Timer tick, asking Buildbucket for the build status
[23:35:46.673] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:46.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:27:0) after 1m0s
[23:36:46.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:27:0)
[23:36:46.898] Timer tick, asking Buildbucket for the build status
[23:36:47.025] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:47.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:28:0) after 1m0s
[23:37:47.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:28:0)
[23:37:47.174] Timer tick, asking Buildbucket for the build status
[23:37:47.282] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:47.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:29:0) after 1m0s
[23:38:47.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:29:0)
[23:38:47.458] Timer tick, asking Buildbucket for the build status
[23:38:47.751] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:47.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:30:0) after 1m0s
[23:39:47.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:30:0)
[23:39:47.783] Timer tick, asking Buildbucket for the build status
[23:39:47.852] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:47.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:31:0) after 1m0s
[23:40:47.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:31:0)
[23:40:47.954] Timer tick, asking Buildbucket for the build status
[23:40:48.049] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:48.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:32:0) after 1m0s
[23:41:48.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:32:0)
[23:41:48.227] Timer tick, asking Buildbucket for the build status
[23:41:48.702] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:48.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:33:0) after 1m0s
[23:42:48.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:33:0)
[23:42:48.769] Timer tick, asking Buildbucket for the build status
[23:42:49.151] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:49.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:34:0) after 1m0s
[23:43:49.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:34:0)
[23:43:49.254] Timer tick, asking Buildbucket for the build status
[23:43:49.344] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:49.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:35:0) after 1m0s
[23:44:49.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:35:0)
[23:44:49.368] Timer tick, asking Buildbucket for the build status
[23:44:49.699] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:49.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:36:0) after 1m0s
[23:45:49.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:36:0)
[23:45:49.795] Timer tick, asking Buildbucket for the build status
[23:45:49.897] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:49.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:37:0) after 1m0s
[23:46:50.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:37:0)
[23:46:50.005] Timer tick, asking Buildbucket for the build status
[23:46:50.408] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:50.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:38:0) after 1m0s
[23:47:50.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:38:0)
[23:47:50.423] Timer tick, asking Buildbucket for the build status
[23:47:50.838] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:50.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:39:0) after 1m0s
[23:48:50.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:39:0)
[23:48:50.911] Timer tick, asking Buildbucket for the build status
[23:48:51.037] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:51.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:40:0) after 1m0s
[23:49:51.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:40:0)
[23:49:51.152] Timer tick, asking Buildbucket for the build status
[23:49:51.409] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:51.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:41:0) after 1m0s
[23:50:51.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:41:0)
[23:50:51.534] Timer tick, asking Buildbucket for the build status
[23:50:51.650] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:51.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:42:0) after 1m0s
[23:51:51.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:42:0)
[23:51:51.719] Timer tick, asking Buildbucket for the build status
[23:51:51.836] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:51.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:43:0) after 1m0s
[23:52:51.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:43:0)
[23:52:51.898] Timer tick, asking Buildbucket for the build status
[23:52:51.992] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:51.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:44:0) after 1m0s
[23:53:51.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:44:0)
[23:53:51.995] Timer tick, asking Buildbucket for the build status
[23:53:52.151] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:52.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:45:0) after 1m0s
[23:54:52.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:45:0)
[23:54:52.173] Timer tick, asking Buildbucket for the build status
[23:54:52.247] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:52.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:46:0) after 1m0s
[23:55:52.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:46:0)
[23:55:52.337] Timer tick, asking Buildbucket for the build status
[23:55:52.417] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:52.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:47:0) after 1m0s
[23:56:52.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:47:0)
[23:56:52.478] Timer tick, asking Buildbucket for the build status
[23:56:52.628] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:52.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:48:0) after 1m0s
[23:57:52.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:48:0)
[23:57:52.715] Timer tick, asking Buildbucket for the build status
[23:57:53.123] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:53.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:49:0) after 1m0s
[23:58:53.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:49:0)
[23:58:53.184] Timer tick, asking Buildbucket for the build status
[23:58:53.242] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:53.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:50:0) after 1m0s
[23:59:53.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:50:0)
[23:59:53.292] Timer tick, asking Buildbucket for the build status
[23:59:53.381] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:53.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:51:0) after 1m0s
[00:00:53.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:51:0)
[00:00:53.514] Timer tick, asking Buildbucket for the build status
[00:00:53.643] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:53.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:52:0) after 1m0s
[00:01:53.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:52:0)
[00:01:53.789] Timer tick, asking Buildbucket for the build status
[00:01:53.948] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:53.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:53:0) after 1m0s
[00:02:53.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:53:0)
[00:02:53.992] Timer tick, asking Buildbucket for the build status
[00:02:54.300] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:54.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:54:0) after 1m0s
[00:03:54.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:54:0)
[00:03:54.345] Timer tick, asking Buildbucket for the build status
[00:03:54.593] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:54.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:55:0) after 1m0s
[00:04:54.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:55:0)
[00:04:54.815] Timer tick, asking Buildbucket for the build status
[00:04:55.164] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:55.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:56:0) after 1m0s
[00:05:55.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:56:0)
[00:05:55.195] Timer tick, asking Buildbucket for the build status
[00:05:55.472] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:55.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:57:0) after 1m0s
[00:06:55.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:57:0)
[00:06:55.518] Timer tick, asking Buildbucket for the build status
[00:06:55.932] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:55.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:58:0) after 1m0s
[00:07:55.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:58:0)
[00:07:55.951] Timer tick, asking Buildbucket for the build status
[00:07:56.299] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:56.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:59:0) after 1m0s
[00:08:56.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:59:0)
[00:08:56.398] Timer tick, asking Buildbucket for the build status
[00:08:56.698] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:56.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:60:0) after 1m0s
[00:09:56.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:60:0)
[00:09:56.747] Timer tick, asking Buildbucket for the build status
[00:09:56.850] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:56.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:61:0) after 1m0s
[00:10:56.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:61:0)
[00:10:56.874] Timer tick, asking Buildbucket for the build status
[00:10:56.948] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:56.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:62:0) after 1m0s
[00:11:56.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:62:0)
[00:11:56.934] Timer tick, asking Buildbucket for the build status
[00:11:57.106] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:63:0) after 1m0s
[00:12:57.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:63:0)
[00:12:57.124] Timer tick, asking Buildbucket for the build status
[00:12:57.187] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:57.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:64:0) after 1m0s
[00:13:57.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:64:0)
[00:13:57.256] Timer tick, asking Buildbucket for the build status
[00:13:57.598] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:65:0) after 1m0s
[00:14:57.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:65:0)
[00:14:57.577] Timer tick, asking Buildbucket for the build status
[00:14:57.650] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:57.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:66:0) after 1m0s
[00:15:57.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:66:0)
[00:15:57.657] Timer tick, asking Buildbucket for the build status
[00:15:57.744] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:57.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:67:0) after 1m0s
[00:16:57.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:67:0)
[00:16:57.793] Timer tick, asking Buildbucket for the build status
[00:16:57.933] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:68:0) after 1m0s
[00:17:57.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:68:0)
[00:17:57.985] Timer tick, asking Buildbucket for the build status
[00:17:58.229] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:58.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:69:0) after 1m0s
[00:18:58.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:69:0)
[00:18:58.317] Timer tick, asking Buildbucket for the build status
[00:18:58.387] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:58.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:70:0) after 1m0s
[00:19:58.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:70:0)
[00:19:58.515] Timer tick, asking Buildbucket for the build status
[00:19:58.637] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:58.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:71:0) after 1m0s
[00:20:58.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:71:0)
[00:20:58.648] Timer tick, asking Buildbucket for the build status
[00:20:58.737] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:58.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:72:0) after 1m0s
[00:21:58.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:72:0)
[00:21:58.768] Timer tick, asking Buildbucket for the build status
[00:21:59.218] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:59.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:73:0) after 1m0s
[00:22:59.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:73:0)
[00:22:59.279] Timer tick, asking Buildbucket for the build status
[00:22:59.352] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:59.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:74:0) after 1m0s
[00:23:59.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:74:0)
[00:23:59.405] Timer tick, asking Buildbucket for the build status
[00:23:59.793] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:59.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:75:0) after 1m0s
[00:24:59.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:75:0)
[00:24:59.906] Timer tick, asking Buildbucket for the build status
[00:25:00.676] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:00.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:76:0) after 1m0s
[00:26:00.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:76:0)
[00:26:00.727] Timer tick, asking Buildbucket for the build status
[00:26:00.789] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:00.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:77:0) after 1m0s
[00:27:00.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:77:0)
[00:27:00.906] Timer tick, asking Buildbucket for the build status
[00:27:01.066] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:01.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:78:0) after 1m0s
[00:28:01.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:78:0)
[00:28:01.114] Timer tick, asking Buildbucket for the build status
[00:28:01.367] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:01.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:79:0) after 1m0s
[00:29:01.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:79:0)
[00:29:01.449] Timer tick, asking Buildbucket for the build status
[00:29:01.524] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:01.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:80:0) after 1m0s
[00:30:01.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:80:0)
[00:30:01.583] Timer tick, asking Buildbucket for the build status
[00:30:01.656] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:01.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:81:0) after 1m0s
[00:31:01.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:81:0)
[00:31:01.678] Timer tick, asking Buildbucket for the build status
[00:31:02.638] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:02.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:82:0) after 1m0s
[00:32:02.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:82:0)
[00:32:02.768] Timer tick, asking Buildbucket for the build status
[00:32:03.097] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:03.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:83:0) after 1m0s
[00:33:03.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:83:0)
[00:33:03.187] Timer tick, asking Buildbucket for the build status
[00:33:04.356] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:04.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:84:0) after 1m0s
[00:34:04.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:84:0)
[00:34:04.414] Timer tick, asking Buildbucket for the build status
[00:34:04.492] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:04.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:85:0) after 1m0s
[00:35:04.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:85:0)
[00:35:04.686] Timer tick, asking Buildbucket for the build status
[00:35:05.610] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:05.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:86:0) after 1m0s
[00:36:05.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:86:0)
[00:36:05.720] Timer tick, asking Buildbucket for the build status
[00:36:05.785] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:05.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:87:0) after 1m0s
[00:37:05.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:87:0)
[00:37:05.824] Timer tick, asking Buildbucket for the build status
[00:37:08.598] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:88:0) after 1m0s
[00:38:08.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:88:0)
[00:38:08.626] Timer tick, asking Buildbucket for the build status
[00:38:08.860] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:08.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:89:0) after 1m0s
[00:39:08.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:89:0)
[00:39:08.913] Timer tick, asking Buildbucket for the build status
[00:39:10.160] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:10.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:90:0) after 1m0s
[00:40:10.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:90:0)
[00:40:10.175] Timer tick, asking Buildbucket for the build status
[00:40:10.956] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:91:0) after 1m0s
[00:41:11.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:91:0)
[00:41:11.035] Timer tick, asking Buildbucket for the build status
[00:41:11.611] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:11.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:92:0) after 1m0s
[00:42:11.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:92:0)
[00:42:11.701] Timer tick, asking Buildbucket for the build status
[00:42:12.033] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:12.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:93:0) after 1m0s
[00:43:12.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:93:0)
[00:43:12.075] Timer tick, asking Buildbucket for the build status
[00:43:12.389] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:12.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:94:0) after 1m0s
[00:44:12.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:94:0)
[00:44:12.431] Timer tick, asking Buildbucket for the build status
[00:44:13.284] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:13.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:95:0) after 1m0s
[00:45:13.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:95:0)
[00:45:13.421] Timer tick, asking Buildbucket for the build status
[00:45:13.898] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:13.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:96:0) after 1m0s
[00:46:13.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:96:0)
[00:46:13.952] Timer tick, asking Buildbucket for the build status
[00:46:14.212] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:14.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:97:0) after 1m0s
[00:47:14.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:97:0)
[00:47:14.342] Timer tick, asking Buildbucket for the build status
[00:47:14.645] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:14.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:98:0) after 1m0s
[00:48:14.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:98:0)
[00:48:14.734] Timer tick, asking Buildbucket for the build status
[00:48:14.844] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:14.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:99:0) after 1m0s
[00:49:15.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:99:0)
[00:49:15.020] Timer tick, asking Buildbucket for the build status
[00:49:15.128] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:15.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:100:0) after 1m0s
[00:50:15.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:100:0)
[00:50:15.228] Timer tick, asking Buildbucket for the build status
[00:50:15.900] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:15.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:101:0) after 1m0s
[00:51:16.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:101:0)
[00:51:16.104] Timer tick, asking Buildbucket for the build status
[00:51:16.926] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:16.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:102:0) after 1m0s
[00:52:17.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:102:0)
[00:52:17.000] Timer tick, asking Buildbucket for the build status
[00:52:17.944] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:17.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:103:0) after 1m0s
[00:53:18.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:103:0)
[00:53:18.083] Timer tick, asking Buildbucket for the build status
[00:53:18.181] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:104:0) after 1m0s
[00:54:18.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:104:0)
[00:54:18.217] Timer tick, asking Buildbucket for the build status
[00:54:18.535] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:18.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:105:0) after 1m0s
[00:55:18.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:105:0)
[00:55:18.629] Timer tick, asking Buildbucket for the build status
[00:55:18.718] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:18.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:106:0) after 1m0s
[00:56:18.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:106:0)
[00:56:18.751] Timer tick, asking Buildbucket for the build status
[00:56:18.839] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:18.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:107:0) after 1m0s
[00:57:18.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:107:0)
[00:57:18.840] Timer tick, asking Buildbucket for the build status
[00:57:18.990] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:18.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:108:0) after 1m0s
[00:58:19.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:108:0)
[00:58:19.015] Timer tick, asking Buildbucket for the build status
[00:58:19.159] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:109:0) after 1m0s
[00:59:19.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:109:0)
[00:59:19.231] Timer tick, asking Buildbucket for the build status
[00:59:19.306] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:19.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:110:0) after 1m0s
[01:00:19.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:110:0)
[01:00:19.504] Timer tick, asking Buildbucket for the build status
[01:00:19.628] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:19.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:111:0) after 1m0s
[01:01:19.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:111:0)
[01:01:19.800] Timer tick, asking Buildbucket for the build status
[01:01:19.876] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:19.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:112:0) after 1m0s
[01:02:19.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:112:0)
[01:02:19.934] Timer tick, asking Buildbucket for the build status
[01:02:20.081] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:20.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:113:0) after 1m0s
[01:03:20.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:113:0)
[01:03:20.183] Timer tick, asking Buildbucket for the build status
[01:03:20.338] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:20.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:114:0) after 1m0s
[01:04:20.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:114:0)
[01:04:20.401] Timer tick, asking Buildbucket for the build status
[01:04:21.026] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:21.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:115:0) after 1m0s
[01:05:21.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:115:0)
[01:05:21.112] Timer tick, asking Buildbucket for the build status
[01:05:21.203] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:21.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:116:0) after 1m0s
[01:06:21.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:116:0)
[01:06:21.279] Timer tick, asking Buildbucket for the build status
[01:06:21.489] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:21.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:117:0) after 1m0s
[01:07:21.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:117:0)
[01:07:21.561] Timer tick, asking Buildbucket for the build status
[01:07:21.754] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:21.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:118:0) after 1m0s
[01:08:21.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:118:0)
[01:08:21.790] Timer tick, asking Buildbucket for the build status
[01:08:22.106] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:22.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:119:0) after 1m0s
[01:09:22.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:119:0)
[01:09:22.125] Timer tick, asking Buildbucket for the build status
[01:09:22.412] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:22.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:120:0) after 1m0s
[01:10:22.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:120:0)
[01:10:22.469] Timer tick, asking Buildbucket for the build status
[01:10:22.735] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:22.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:121:0) after 1m0s
[01:11:22.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:121:0)
[01:11:22.777] Timer tick, asking Buildbucket for the build status
[01:11:23.102] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:23.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:122:0) after 1m0s
[01:12:23.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:122:0)
[01:12:23.166] Timer tick, asking Buildbucket for the build status
[01:12:23.585] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:23.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:123:0) after 1m0s
[01:13:23.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:123:0)
[01:13:23.668] Timer tick, asking Buildbucket for the build status
[01:13:23.764] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:23.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:124:0) after 1m0s
[01:14:23.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:124:0)
[01:14:23.795] Timer tick, asking Buildbucket for the build status
[01:14:23.928] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:23.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:125:0) after 1m0s
[01:15:24.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:125:0)
[01:15:24.015] Timer tick, asking Buildbucket for the build status
[01:15:24.142] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:24.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:126:0) after 1m0s
[01:16:24.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:126:0)
[01:16:24.179] Timer tick, asking Buildbucket for the build status
[01:16:24.271] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:24.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:127:0) after 1m0s
[01:17:24.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:127:0)
[01:17:24.313] Timer tick, asking Buildbucket for the build status
[01:17:24.832] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:24.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:128:0) after 1m0s
[01:18:24.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:128:0)
[01:18:24.905] Timer tick, asking Buildbucket for the build status
[01:18:25.174] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:25.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:129:0) after 1m0s
[01:19:25.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:129:0)
[01:19:25.247] Timer tick, asking Buildbucket for the build status
[01:19:25.323] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:25.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:130:0) after 1m0s
[01:20:25.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:130:0)
[01:20:25.361] Timer tick, asking Buildbucket for the build status
[01:20:25.453] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:25.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:131:0) after 1m0s
[01:21:25.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:131:0)
[01:21:25.521] Timer tick, asking Buildbucket for the build status
[01:21:25.661] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:25.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:132:0) after 1m0s
[01:22:25.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:132:0)
[01:22:25.699] Timer tick, asking Buildbucket for the build status
[01:22:26.276] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:26.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:133:0) after 1m0s
[01:23:26.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:133:0)
[01:23:26.305] Timer tick, asking Buildbucket for the build status
[01:23:27.938] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:27.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:134:0) after 1m0s
[01:24:27.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:134:0)
[01:24:27.965] Timer tick, asking Buildbucket for the build status
[01:24:28.038] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:28.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:135:0) after 1m0s
[01:25:28.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:135:0)
[01:25:28.056] Timer tick, asking Buildbucket for the build status
[01:25:28.255] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:28.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:136:0) after 1m0s
[01:26:28.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:136:0)
[01:26:28.292] Timer tick, asking Buildbucket for the build status
[01:26:28.384] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:137:0) after 1m0s
[01:27:28.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:137:0)
[01:27:28.394] Timer tick, asking Buildbucket for the build status
[01:27:28.766] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:28.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:138:0) after 1m0s
[01:28:28.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:138:0)
[01:28:28.848] Timer tick, asking Buildbucket for the build status
[01:28:29.117] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:29.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:139:0) after 1m0s
[01:29:29.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:139:0)
[01:29:29.172] Timer tick, asking Buildbucket for the build status
[01:29:29.333] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:29.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:140:0) after 1m0s
[01:30:29.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:140:0)
[01:30:29.482] Timer tick, asking Buildbucket for the build status
[01:30:29.604] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:29.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:141:0) after 1m0s
[01:31:29.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:141:0)
[01:31:29.789] Timer tick, asking Buildbucket for the build status
[01:31:29.858] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:29.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:142:0) after 1m0s
[01:32:29.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:142:0)
[01:32:29.942] Timer tick, asking Buildbucket for the build status
[01:32:30.589] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:30.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:143:0) after 1m0s
[01:33:30.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:143:0)
[01:33:30.602] Timer tick, asking Buildbucket for the build status
[01:33:31.024] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:31.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:144:0) after 1m0s
[01:34:31.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:144:0)
[01:34:31.081] Timer tick, asking Buildbucket for the build status
[01:34:31.372] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:31.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:145:0) after 1m0s
[01:35:31.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:145:0)
[01:35:31.414] Timer tick, asking Buildbucket for the build status
[01:35:31.480] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:31.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:146:0) after 1m0s
[01:36:31.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:146:0)
[01:36:31.486] Timer tick, asking Buildbucket for the build status
[01:36:31.585] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:31.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:147:0) after 1m0s
[01:37:31.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:147:0)
[01:37:31.615] Timer tick, asking Buildbucket for the build status
[01:37:31.699] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:31.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:148:0) after 1m0s
[01:38:31.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:148:0)
[01:38:31.755] Timer tick, asking Buildbucket for the build status
[01:38:32.004] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:32.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:149:0) after 1m0s
[01:39:32.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:149:0)
[01:39:32.085] Timer tick, asking Buildbucket for the build status
[01:39:32.258] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:32.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:150:0) after 1m0s
[01:40:32.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:150:0)
[01:40:32.332] Timer tick, asking Buildbucket for the build status
[01:40:32.396] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:32.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:151:0) after 1m0s
[01:41:32.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:151:0)
[01:41:32.496] Timer tick, asking Buildbucket for the build status
[01:41:32.812] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:32.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:152:0) after 1m0s
[01:42:32.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:152:0)
[01:42:32.891] Timer tick, asking Buildbucket for the build status
[01:42:33.307] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:33.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:153:0) after 1m0s
[01:43:33.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:153:0)
[01:43:33.332] Timer tick, asking Buildbucket for the build status
[01:43:33.556] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:33.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:154:0) after 1m0s
[01:44:33.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:154:0)
[01:44:33.569] Timer tick, asking Buildbucket for the build status
[01:44:33.669] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:33.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:155:0) after 1m0s
[01:45:33.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:155:0)
[01:45:33.724] Timer tick, asking Buildbucket for the build status
[01:45:33.852] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:156:0) after 1m0s
[01:46:33.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:156:0)
[01:46:33.905] Timer tick, asking Buildbucket for the build status
[01:46:34.024] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:34.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:157:0) after 1m0s
[01:47:34.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:157:0)
[01:47:34.122] Timer tick, asking Buildbucket for the build status
[01:47:34.222] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:34.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:158:0) after 1m0s
[01:48:34.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:158:0)
[01:48:34.323] Timer tick, asking Buildbucket for the build status
[01:48:34.384] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:34.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:159:0) after 1m0s
[01:49:34.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:159:0)
[01:49:34.415] Timer tick, asking Buildbucket for the build status
[01:49:34.578] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:34.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:160:0) after 1m0s
[01:50:34.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:160:0)
[01:50:34.561] Timer tick, asking Buildbucket for the build status
[01:50:34.681] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:161:0) after 1m0s
[01:51:34.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:161:0)
[01:51:34.823] Timer tick, asking Buildbucket for the build status
[01:51:35.274] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:35.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:162:0) after 1m0s
[01:52:35.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:162:0)
[01:52:35.367] Timer tick, asking Buildbucket for the build status
[01:52:35.478] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:35.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:163:0) after 1m0s
[01:53:35.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:163:0)
[01:53:35.479] Timer tick, asking Buildbucket for the build status
[01:53:35.601] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:35.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:164:0) after 1m0s
[01:54:35.740] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:164:0)
[01:54:35.740] Timer tick, asking Buildbucket for the build status
[01:54:35.877] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:165:0) after 1m0s
[01:55:35.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:165:0)
[01:55:35.997] Timer tick, asking Buildbucket for the build status
[01:55:36.045] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:36.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:166:0) after 1m0s
[01:56:36.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:166:0)
[01:56:36.176] Timer tick, asking Buildbucket for the build status
[01:56:36.239] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:36.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:167:0) after 1m0s
[01:57:36.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:167:0)
[01:57:36.386] Timer tick, asking Buildbucket for the build status
[01:57:36.576] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:36.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:168:0) after 1m0s
[01:58:36.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:168:0)
[01:58:36.859] Timer tick, asking Buildbucket for the build status
[01:58:36.976] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:169:0) after 1m0s
[01:59:37.385] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:169:0)
[01:59:37.385] Timer tick, asking Buildbucket for the build status
[01:59:37.468] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:37.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:170:0) after 1m0s
[02:00:37.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:170:0)
[02:00:37.747] Timer tick, asking Buildbucket for the build status
[02:00:38.336] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:38.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:171:0) after 1m0s
[02:01:38.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:171:0)
[02:01:38.358] Timer tick, asking Buildbucket for the build status
[02:01:38.524] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:172:0) after 1m0s
[02:02:38.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:172:0)
[02:02:38.581] Timer tick, asking Buildbucket for the build status
[02:02:38.892] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:38.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:173:0) after 1m0s
[02:03:38.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:173:0)
[02:03:38.947] Timer tick, asking Buildbucket for the build status
[02:03:39.047] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:174:0) after 1m0s
[02:04:39.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:174:0)
[02:04:39.121] Timer tick, asking Buildbucket for the build status
[02:04:39.290] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:39.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:175:0) after 1m0s
[02:05:39.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:175:0)
[02:05:39.391] Timer tick, asking Buildbucket for the build status
[02:05:39.666] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:39.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:176:0) after 1m0s
[02:06:39.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:176:0)
[02:06:39.726] Timer tick, asking Buildbucket for the build status
[02:06:39.830] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:39.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:177:0) after 1m0s
[02:07:39.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:177:0)
[02:07:39.866] Timer tick, asking Buildbucket for the build status
[02:07:39.989] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:39.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:178:0) after 1m0s
[02:08:40.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:178:0)
[02:08:40.036] Timer tick, asking Buildbucket for the build status
[02:08:40.355] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:40.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:179:0) after 1m0s
[02:09:40.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:179:0)
[02:09:40.410] Timer tick, asking Buildbucket for the build status
[02:09:40.502] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:40.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:180:0) after 1m0s
[02:10:40.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:180:0)
[02:10:40.563] Timer tick, asking Buildbucket for the build status
[02:10:40.660] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:40.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:181:0) after 1m0s
[02:11:40.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:181:0)
[02:11:40.662] Timer tick, asking Buildbucket for the build status
[02:11:40.738] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:40.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:182:0) after 1m0s
[02:12:40.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:182:0)
[02:12:40.840] Timer tick, asking Buildbucket for the build status
[02:12:40.988] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:40.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:183:0) after 1m0s
[02:13:41.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:183:0)
[02:13:41.086] Timer tick, asking Buildbucket for the build status
[02:13:41.270] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:41.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:184:0) after 1m0s
[02:14:41.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:184:0)
[02:14:41.348] Timer tick, asking Buildbucket for the build status
[02:14:41.513] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:41.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:185:0) after 1m0s
[02:15:41.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:185:0)
[02:15:41.555] Timer tick, asking Buildbucket for the build status
[02:15:41.798] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:41.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:186:0) after 1m0s
[02:16:42.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:186:0)
[02:16:42.003] Timer tick, asking Buildbucket for the build status
[02:16:42.174] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:42.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:187:0) after 1m0s
[02:17:42.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:187:0)
[02:17:42.311] Timer tick, asking Buildbucket for the build status
[02:17:42.518] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:42.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:188:0) after 1m0s
[02:18:42.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:188:0)
[02:18:42.604] Timer tick, asking Buildbucket for the build status
[02:18:42.660] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:42.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:189:0) after 1m0s
[02:19:42.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:189:0)
[02:19:42.922] Timer tick, asking Buildbucket for the build status
[02:19:42.987] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:42.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:190:0) after 1m0s
[02:20:43.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:190:0)
[02:20:43.105] Timer tick, asking Buildbucket for the build status
[02:20:43.172] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:43.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:191:0) after 1m0s
[02:21:43.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:191:0)
[02:21:43.325] Timer tick, asking Buildbucket for the build status
[02:21:43.495] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:43.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:192:0) after 1m0s
[02:22:43.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:192:0)
[02:22:43.709] Timer tick, asking Buildbucket for the build status
[02:22:44.039] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:44.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:193:0) after 1m0s
[02:23:44.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:193:0)
[02:23:44.182] Timer tick, asking Buildbucket for the build status
[02:23:44.317] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:44.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:194:0) after 1m0s
[02:24:44.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:194:0)
[02:24:44.583] Timer tick, asking Buildbucket for the build status
[02:24:44.830] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:44.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:195:0) after 1m0s
[02:25:44.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:195:0)
[02:25:44.940] Timer tick, asking Buildbucket for the build status
[02:25:45.017] Build 8857363332498715968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:45.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022793395227758992:196:0) after 1m0s
[02:26:37.950] Received PubSub notification, asking Buildbucket for the build status
[02:26:38.033] Build 8857363332498715968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:26:38.033] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611368796811428",
"created_by": "project:chromium",
"created_ts": "1611357100925491",
"id": "8857363332498715968",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44b4d7f39c4b97b4c5557d14935285316f1948f8\"}}",
"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\": \"44b4d7f39c4b97b4c5557d14935285316f1948f8\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-ndrv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1440, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"2c32fa6476085fce294e4d8be4ae6aed92240caa\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"44b4d7f39c4b97b4c5557d14935285316f1948f8\", \"got_revision_cp\": \"refs/heads/master@{#846383}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3fe96b789504e16d64a558d95db670b435662cbd\", \"got_v8_revision\": \"53d05d723a6a1f09bb5c642abd20fd987f0f3c93\", \"got_v8_revision_cp\": \"refs/heads/9.0.54@{#1}\", \"got_webrtc_revision\": \"8df643b38785f9b6fe8ae4bbf295839d28136d08\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33058}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"44b4d7f39c4b97b4c5557d14935285316f1948f8\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-ndrv\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611357146122657",
"status": "COMPLETED",
"status_changed_ts": "1611368797556641",
"tags": [
"build_address:luci.chromium.ci/win-official/1440",
"builder:win-official",
"buildset:commit/git/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44b4d7f39c4b97b4c5557d14935285316f1948f8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022793395227758992",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857363332498715968/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51452fa7ac00cd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611368797561600",
"url": "https://ci.chromium.org/b/8857363332498715968",
"utcnow_ts": "1611368798008864"
}
}
[02:26:38.033] Invocation finished in 3h14m59.093697318s with status SUCCEEDED