[03:13:16.339] New invocation is queued and will start shortly
[03:13:17.433] Starting the invocation (attempt 1)
[03:13:17.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:13:17.482] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:13:17.484] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035280574984649760",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"18c31c1d607118534cb063185bdfcd603a46ca4b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9035280574984649760",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/18c31c1d607118534cb063185bdfcd603a46ca4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/18c31c1d607118534cb063185bdfcd603a46ca4b",
"gitiles_ref:refs/heads/master"
]
}
[03:13:18.673] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599448397675787",
"id": "8869850512917540272",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"18c31c1d607118534cb063185bdfcd603a46ca4b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599448398220347",
"tags": [
"build_address:luci.chromium.ci/win32-official/479",
"builder:win32-official",
"buildset:commit/git/18c31c1d607118534cb063185bdfcd603a46ca4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/18c31c1d607118534cb063185bdfcd603a46ca4b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035280574984649760",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869850512917540272/+/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": "1599448398220466",
"url": "https://ci.chromium.org/b/8869850512917540272",
"utcnow_ts": "1599448398615341"
}
}
[03:13:18.674] Task URL: https://ci.chromium.org/b/8869850512917540272
[03:13:18.674] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:2:0) after 1m0s
[03:14:01.482] Received PubSub notification, asking Buildbucket for the build status
[03:14:01.557] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.822] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:2:0)
[03:14:18.822] Timer tick, asking Buildbucket for the build status
[03:14:18.929] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.929] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:4:0) after 1m0s
[03:15:18.914] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:4:0)
[03:15:18.914] Timer tick, asking Buildbucket for the build status
[03:15:19.136] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:19.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:5:0) after 1m0s
[03:16:19.481] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:5:0)
[03:16:19.481] Timer tick, asking Buildbucket for the build status
[03:16:19.605] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:19.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:6:0) after 1m0s
[03:17:19.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:6:0)
[03:17:19.816] Timer tick, asking Buildbucket for the build status
[03:17:20.021] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:20.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:7:0) after 1m0s
[03:18:20.081] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:7:0)
[03:18:20.081] Timer tick, asking Buildbucket for the build status
[03:18:20.218] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:20.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:8:0) after 1m0s
[03:19:20.255] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:8:0)
[03:19:20.255] Timer tick, asking Buildbucket for the build status
[03:19:20.593] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:20.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:9:0) after 1m0s
[03:20:20.681] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:9:0)
[03:20:20.681] Timer tick, asking Buildbucket for the build status
[03:20:20.910] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:20.910] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:10:0) after 1m0s
[03:21:20.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:10:0)
[03:21:20.955] Timer tick, asking Buildbucket for the build status
[03:21:21.247] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:21.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:11:0) after 1m0s
[03:22:21.272] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:11:0)
[03:22:21.272] Timer tick, asking Buildbucket for the build status
[03:22:21.593] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:21.593] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:12:0) after 1m0s
[03:23:21.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:12:0)
[03:23:21.617] Timer tick, asking Buildbucket for the build status
[03:23:21.750] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:21.750] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:13:0) after 1m0s
[03:24:21.837] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:13:0)
[03:24:21.837] Timer tick, asking Buildbucket for the build status
[03:24:21.988] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:21.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:14:0) after 1m0s
[03:25:22.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:14:0)
[03:25:22.019] Timer tick, asking Buildbucket for the build status
[03:25:22.103] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:22.103] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:15:0) after 1m0s
[03:26:22.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:15:0)
[03:26:22.095] Timer tick, asking Buildbucket for the build status
[03:26:22.296] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:22.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:16:0) after 1m0s
[03:27:22.330] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:16:0)
[03:27:22.330] Timer tick, asking Buildbucket for the build status
[03:27:22.409] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:22.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:17:0) after 1m0s
[03:28:22.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:17:0)
[03:28:22.456] Timer tick, asking Buildbucket for the build status
[03:28:22.598] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:22.599] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:18:0) after 1m0s
[03:29:22.648] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:18:0)
[03:29:22.648] Timer tick, asking Buildbucket for the build status
[03:29:22.749] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:22.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:19:0) after 1m0s
[03:30:22.780] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:19:0)
[03:30:22.780] Timer tick, asking Buildbucket for the build status
[03:30:22.841] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:22.841] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:20:0) after 1m0s
[03:31:23.028] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:20:0)
[03:31:23.028] Timer tick, asking Buildbucket for the build status
[03:31:23.203] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:23.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:21:0) after 1m0s
[03:32:23.311] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:21:0)
[03:32:23.311] Timer tick, asking Buildbucket for the build status
[03:32:23.415] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:22:0) after 1m0s
[03:33:24.513] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:22:0)
[03:33:24.513] Timer tick, asking Buildbucket for the build status
[03:33:24.691] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:24.693] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:23:0) after 1m0s
[03:34:24.915] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:23:0)
[03:34:24.915] Timer tick, asking Buildbucket for the build status
[03:34:25.138] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:25.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:24:0) after 1m0s
[03:35:25.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:24:0)
[03:35:25.394] Timer tick, asking Buildbucket for the build status
[03:35:25.560] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.561] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:25:0) after 1m0s
[03:36:26.311] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:25:0)
[03:36:26.311] Timer tick, asking Buildbucket for the build status
[03:36:26.377] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:26.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:26:0) after 1m0s
[03:37:26.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:26:0)
[03:37:26.784] Timer tick, asking Buildbucket for the build status
[03:37:26.940] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:26.940] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:27:0) after 1m0s
[03:38:27.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:27:0)
[03:38:27.145] Timer tick, asking Buildbucket for the build status
[03:38:27.310] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:27.310] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:28:0) after 1m0s
[03:39:28.086] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:28:0)
[03:39:28.086] Timer tick, asking Buildbucket for the build status
[03:39:28.171] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:28.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:29:0) after 1m0s
[03:40:28.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:29:0)
[03:40:28.341] Timer tick, asking Buildbucket for the build status
[03:40:28.488] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:28.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:30:0) after 1m0s
[03:41:29.031] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:30:0)
[03:41:29.032] Timer tick, asking Buildbucket for the build status
[03:41:29.167] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:29.168] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:31:0) after 1m0s
[03:42:29.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:31:0)
[03:42:29.240] Timer tick, asking Buildbucket for the build status
[03:42:29.342] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:29.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:32:0) after 1m0s
[03:43:29.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:32:0)
[03:43:29.588] Timer tick, asking Buildbucket for the build status
[03:43:29.990] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:29.990] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:33:0) after 1m0s
[03:44:29.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:33:0)
[03:44:29.971] Timer tick, asking Buildbucket for the build status
[03:44:30.234] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:30.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:34:0) after 1m0s
[03:45:30.367] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:34:0)
[03:45:30.367] Timer tick, asking Buildbucket for the build status
[03:45:30.627] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:30.627] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:35:0) after 1m0s
[03:46:30.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:35:0)
[03:46:30.797] Timer tick, asking Buildbucket for the build status
[03:46:30.867] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:30.867] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:36:0) after 1m0s
[03:47:30.944] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:36:0)
[03:47:30.944] Timer tick, asking Buildbucket for the build status
[03:47:31.027] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:31.027] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:37:0) after 1m0s
[03:48:31.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:37:0)
[03:48:31.271] Timer tick, asking Buildbucket for the build status
[03:48:31.907] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:31.907] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:38:0) after 1m0s
[03:49:31.887] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:38:0)
[03:49:31.887] Timer tick, asking Buildbucket for the build status
[03:49:31.991] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:31.991] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:39:0) after 1m0s
[03:50:32.018] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:39:0)
[03:50:32.018] Timer tick, asking Buildbucket for the build status
[03:50:32.157] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:32.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:40:0) after 1m0s
[03:51:35.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:40:0)
[03:51:35.927] Timer tick, asking Buildbucket for the build status
[03:51:36.111] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:36.111] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:41:0) after 1m0s
[03:52:36.420] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:41:0)
[03:52:36.420] Timer tick, asking Buildbucket for the build status
[03:52:36.533] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:36.533] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:42:0) after 1m0s
[03:53:37.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:42:0)
[03:53:37.116] Timer tick, asking Buildbucket for the build status
[03:53:37.277] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:37.277] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:43:0) after 1m0s
[03:54:37.273] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:43:0)
[03:54:37.273] Timer tick, asking Buildbucket for the build status
[03:54:37.372] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:37.372] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:44:0) after 1m0s
[03:55:37.454] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:44:0)
[03:55:37.454] Timer tick, asking Buildbucket for the build status
[03:55:37.514] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:37.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:45:0) after 1m0s
[03:56:37.587] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:45:0)
[03:56:37.587] Timer tick, asking Buildbucket for the build status
[03:56:37.798] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:37.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:46:0) after 1m0s
[03:57:37.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:46:0)
[03:57:37.892] Timer tick, asking Buildbucket for the build status
[03:57:37.989] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:37.989] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:47:0) after 1m0s
[03:58:38.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:47:0)
[03:58:38.008] Timer tick, asking Buildbucket for the build status
[03:58:38.123] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:38.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:48:0) after 1m0s
[03:59:38.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:48:0)
[03:59:38.394] Timer tick, asking Buildbucket for the build status
[03:59:38.702] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:38.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:49:0) after 1m0s
[04:00:38.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:49:0)
[04:00:38.800] Timer tick, asking Buildbucket for the build status
[04:00:38.872] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:38.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:50:0) after 1m0s
[04:01:38.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:50:0)
[04:01:38.961] Timer tick, asking Buildbucket for the build status
[04:01:39.015] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:39.015] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:51:0) after 1m0s
[04:02:39.205] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:51:0)
[04:02:39.205] Timer tick, asking Buildbucket for the build status
[04:02:39.314] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:39.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:52:0) after 1m0s
[04:03:39.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:52:0)
[04:03:39.445] Timer tick, asking Buildbucket for the build status
[04:03:39.726] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:39.726] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:53:0) after 1m0s
[04:04:40.135] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:53:0)
[04:04:40.135] Timer tick, asking Buildbucket for the build status
[04:04:40.538] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:40.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:54:0) after 1m0s
[04:05:40.747] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:54:0)
[04:05:40.747] Timer tick, asking Buildbucket for the build status
[04:05:40.798] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:40.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:55:0) after 1m0s
[04:06:40.799] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:55:0)
[04:06:40.799] Timer tick, asking Buildbucket for the build status
[04:06:40.866] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:40.866] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:56:0) after 1m0s
[04:07:40.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:56:0)
[04:07:40.890] Timer tick, asking Buildbucket for the build status
[04:07:41.193] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:41.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:57:0) after 1m0s
[04:08:41.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:57:0)
[04:08:41.263] Timer tick, asking Buildbucket for the build status
[04:08:41.391] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:41.391] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:58:0) after 1m0s
[04:09:41.489] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:58:0)
[04:09:41.489] Timer tick, asking Buildbucket for the build status
[04:09:41.593] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:59:0) after 1m0s
[04:10:41.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:59:0)
[04:10:41.655] Timer tick, asking Buildbucket for the build status
[04:10:41.814] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:41.814] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:60:0) after 1m0s
[04:11:41.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:60:0)
[04:11:41.831] Timer tick, asking Buildbucket for the build status
[04:11:41.940] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:41.940] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:61:0) after 1m0s
[04:12:41.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:61:0)
[04:12:41.996] Timer tick, asking Buildbucket for the build status
[04:12:42.098] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:42.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:62:0) after 1m0s
[04:13:42.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:62:0)
[04:13:42.125] Timer tick, asking Buildbucket for the build status
[04:13:42.251] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:42.251] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:63:0) after 1m0s
[04:14:42.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:63:0)
[04:14:42.280] Timer tick, asking Buildbucket for the build status
[04:14:42.362] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:42.362] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:64:0) after 1m0s
[04:15:42.551] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:64:0)
[04:15:42.551] Timer tick, asking Buildbucket for the build status
[04:15:42.697] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:42.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:65:0) after 1m0s
[04:16:43.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:65:0)
[04:16:43.161] Timer tick, asking Buildbucket for the build status
[04:16:43.287] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:43.287] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:66:0) after 1m0s
[04:17:43.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:66:0)
[04:17:43.338] Timer tick, asking Buildbucket for the build status
[04:17:43.412] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:43.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:67:0) after 1m0s
[04:18:43.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:67:0)
[04:18:43.453] Timer tick, asking Buildbucket for the build status
[04:18:43.679] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:43.679] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:68:0) after 1m0s
[04:19:43.967] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:68:0)
[04:19:43.967] Timer tick, asking Buildbucket for the build status
[04:19:44.145] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:44.145] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:69:0) after 1m0s
[04:20:44.229] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:69:0)
[04:20:44.229] Timer tick, asking Buildbucket for the build status
[04:20:44.309] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:44.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:70:0) after 1m0s
[04:21:44.334] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:70:0)
[04:21:44.334] Timer tick, asking Buildbucket for the build status
[04:21:44.404] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:44.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:71:0) after 1m0s
[04:22:44.792] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:71:0)
[04:22:44.792] Timer tick, asking Buildbucket for the build status
[04:22:44.942] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:44.942] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:72:0) after 1m0s
[04:23:44.984] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:72:0)
[04:23:44.984] Timer tick, asking Buildbucket for the build status
[04:23:45.117] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:45.117] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:73:0) after 1m0s
[04:24:45.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:73:0)
[04:24:45.181] Timer tick, asking Buildbucket for the build status
[04:24:45.541] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:45.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:74:0) after 1m0s
[04:25:45.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:74:0)
[04:25:45.570] Timer tick, asking Buildbucket for the build status
[04:25:46.097] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:46.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:75:0) after 1m0s
[04:26:46.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:75:0)
[04:26:46.183] Timer tick, asking Buildbucket for the build status
[04:26:46.325] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:46.325] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:76:0) after 1m0s
[04:27:46.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:76:0)
[04:27:46.387] Timer tick, asking Buildbucket for the build status
[04:27:46.654] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:46.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:77:0) after 1m0s
[04:28:46.812] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:77:0)
[04:28:46.812] Timer tick, asking Buildbucket for the build status
[04:28:46.959] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:46.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:78:0) after 1m0s
[04:29:46.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:78:0)
[04:29:46.991] Timer tick, asking Buildbucket for the build status
[04:29:47.179] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:47.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:79:0) after 1m0s
[04:30:47.253] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:79:0)
[04:30:47.253] Timer tick, asking Buildbucket for the build status
[04:30:47.351] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:47.351] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:80:0) after 1m0s
[04:31:47.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:80:0)
[04:31:47.387] Timer tick, asking Buildbucket for the build status
[04:31:47.557] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:47.557] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:81:0) after 1m0s
[04:32:47.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:81:0)
[04:32:47.590] Timer tick, asking Buildbucket for the build status
[04:32:47.863] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:47.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:82:0) after 1m0s
[04:33:47.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:82:0)
[04:33:47.907] Timer tick, asking Buildbucket for the build status
[04:33:48.225] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:48.225] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:83:0) after 1m0s
[04:34:48.294] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:83:0)
[04:34:48.294] Timer tick, asking Buildbucket for the build status
[04:34:48.462] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:48.462] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:84:0) after 1m0s
[04:35:48.492] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:84:0)
[04:35:48.492] Timer tick, asking Buildbucket for the build status
[04:35:48.584] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:48.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:85:0) after 1m0s
[04:36:48.735] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:85:0)
[04:36:48.735] Timer tick, asking Buildbucket for the build status
[04:36:49.552] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:49.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:86:0) after 1m0s
[04:37:49.597] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:86:0)
[04:37:49.597] Timer tick, asking Buildbucket for the build status
[04:37:49.675] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:49.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:87:0) after 1m0s
[04:38:49.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:87:0)
[04:38:49.797] Timer tick, asking Buildbucket for the build status
[04:38:49.953] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:49.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:88:0) after 1m0s
[04:39:49.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:88:0)
[04:39:49.975] Timer tick, asking Buildbucket for the build status
[04:39:50.050] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:50.050] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:89:0) after 1m0s
[04:40:50.186] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:89:0)
[04:40:50.186] Timer tick, asking Buildbucket for the build status
[04:40:50.457] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:50.458] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:90:0) after 1m0s
[04:41:50.545] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:90:0)
[04:41:50.545] Timer tick, asking Buildbucket for the build status
[04:41:50.663] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:50.663] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:91:0) after 1m0s
[04:42:50.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:91:0)
[04:42:50.691] Timer tick, asking Buildbucket for the build status
[04:42:50.761] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:50.761] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:92:0) after 1m0s
[04:43:50.785] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:92:0)
[04:43:50.785] Timer tick, asking Buildbucket for the build status
[04:43:51.302] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:51.302] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:93:0) after 1m0s
[04:44:51.520] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:93:0)
[04:44:51.520] Timer tick, asking Buildbucket for the build status
[04:44:51.632] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:51.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:94:0) after 1m0s
[04:45:51.649] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:94:0)
[04:45:51.649] Timer tick, asking Buildbucket for the build status
[04:45:51.728] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:51.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:95:0) after 1m0s
[04:46:51.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:95:0)
[04:46:51.797] Timer tick, asking Buildbucket for the build status
[04:46:51.943] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:51.943] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:96:0) after 1m0s
[04:47:51.965] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:96:0)
[04:47:51.965] Timer tick, asking Buildbucket for the build status
[04:47:52.083] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:52.083] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:97:0) after 1m0s
[04:48:52.115] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:97:0)
[04:48:52.115] Timer tick, asking Buildbucket for the build status
[04:48:52.267] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:52.268] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:98:0) after 1m0s
[04:49:52.318] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:98:0)
[04:49:52.318] Timer tick, asking Buildbucket for the build status
[04:49:52.418] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:52.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:99:0) after 1m0s
[04:50:52.454] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:99:0)
[04:50:52.454] Timer tick, asking Buildbucket for the build status
[04:50:52.677] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:52.677] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:100:0) after 1m0s
[04:51:52.716] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:100:0)
[04:51:52.716] Timer tick, asking Buildbucket for the build status
[04:51:52.804] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:52.804] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:101:0) after 1m0s
[04:52:53.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:101:0)
[04:52:53.029] Timer tick, asking Buildbucket for the build status
[04:52:53.211] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:53.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:102:0) after 1m0s
[04:53:53.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:102:0)
[04:53:53.244] Timer tick, asking Buildbucket for the build status
[04:53:53.380] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:53.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:103:0) after 1m0s
[04:54:53.485] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:103:0)
[04:54:53.485] Timer tick, asking Buildbucket for the build status
[04:54:53.558] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:53.559] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:104:0) after 1m0s
[04:55:53.642] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:104:0)
[04:55:53.642] Timer tick, asking Buildbucket for the build status
[04:55:54.200] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:105:0) after 1m0s
[04:56:54.825] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:105:0)
[04:56:54.826] Timer tick, asking Buildbucket for the build status
[04:56:54.901] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:54.901] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:106:0) after 1m0s
[04:57:55.315] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:106:0)
[04:57:55.315] Timer tick, asking Buildbucket for the build status
[04:57:55.537] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:55.537] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:107:0) after 1m0s
[04:58:55.903] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:107:0)
[04:58:55.903] Timer tick, asking Buildbucket for the build status
[04:58:56.131] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:56.131] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:108:0) after 1m0s
[04:59:56.237] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:108:0)
[04:59:56.237] Timer tick, asking Buildbucket for the build status
[04:59:56.313] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:56.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:109:0) after 1m0s
[05:00:56.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:109:0)
[05:00:56.601] Timer tick, asking Buildbucket for the build status
[05:00:56.728] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:56.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:110:0) after 1m0s
[05:01:56.929] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:110:0)
[05:01:56.929] Timer tick, asking Buildbucket for the build status
[05:01:57.003] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:57.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:111:0) after 1m0s
[05:02:57.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:111:0)
[05:02:57.075] Timer tick, asking Buildbucket for the build status
[05:02:57.320] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:57.320] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:112:0) after 1m0s
[05:03:57.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:112:0)
[05:03:57.348] Timer tick, asking Buildbucket for the build status
[05:03:57.682] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:57.682] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:113:0) after 1m0s
[05:04:57.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:113:0)
[05:04:57.819] Timer tick, asking Buildbucket for the build status
[05:04:58.177] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:58.177] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:114:0) after 1m0s
[05:05:58.222] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:114:0)
[05:05:58.222] Timer tick, asking Buildbucket for the build status
[05:05:58.282] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:58.282] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:115:0) after 1m0s
[05:06:58.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:115:0)
[05:06:58.477] Timer tick, asking Buildbucket for the build status
[05:06:58.581] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:58.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:116:0) after 1m0s
[05:07:58.713] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:116:0)
[05:07:58.713] Timer tick, asking Buildbucket for the build status
[05:07:58.829] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:58.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:117:0) after 1m0s
[05:08:59.088] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:117:0)
[05:08:59.088] Timer tick, asking Buildbucket for the build status
[05:08:59.488] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:59.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:118:0) after 1m0s
[05:09:59.530] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:118:0)
[05:09:59.530] Timer tick, asking Buildbucket for the build status
[05:09:59.608] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:59.608] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:119:0) after 1m0s
[05:10:59.719] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:119:0)
[05:10:59.719] Timer tick, asking Buildbucket for the build status
[05:10:59.948] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:59.948] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:120:0) after 1m0s
[05:11:59.980] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:120:0)
[05:11:59.980] Timer tick, asking Buildbucket for the build status
[05:12:00.116] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:00.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:121:0) after 1m0s
[05:13:00.319] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:121:0)
[05:13:00.319] Timer tick, asking Buildbucket for the build status
[05:13:00.590] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:00.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:122:0) after 1m0s
[05:14:01.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:122:0)
[05:14:01.073] Timer tick, asking Buildbucket for the build status
[05:14:01.214] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:01.214] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:123:0) after 1m0s
[05:15:01.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:123:0)
[05:15:01.345] Timer tick, asking Buildbucket for the build status
[05:15:01.459] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:01.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:124:0) after 1m0s
[05:16:01.485] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:124:0)
[05:16:01.485] Timer tick, asking Buildbucket for the build status
[05:16:01.630] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:01.630] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:125:0) after 1m0s
[05:17:01.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:125:0)
[05:17:01.694] Timer tick, asking Buildbucket for the build status
[05:17:01.764] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:01.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:126:0) after 1m0s
[05:18:01.794] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:126:0)
[05:18:01.794] Timer tick, asking Buildbucket for the build status
[05:18:01.872] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:01.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:127:0) after 1m0s
[05:19:01.947] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:127:0)
[05:19:01.947] Timer tick, asking Buildbucket for the build status
[05:19:03.108] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:03.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:128:0) after 1m0s
[05:20:03.147] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:128:0)
[05:20:03.147] Timer tick, asking Buildbucket for the build status
[05:20:03.215] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:03.215] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:129:0) after 1m0s
[05:21:03.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:129:0)
[05:21:03.239] Timer tick, asking Buildbucket for the build status
[05:21:03.319] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:03.319] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:130:0) after 1m0s
[05:22:03.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:130:0)
[05:22:03.370] Timer tick, asking Buildbucket for the build status
[05:22:03.457] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:03.457] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:131:0) after 1m0s
[05:23:03.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:131:0)
[05:23:03.557] Timer tick, asking Buildbucket for the build status
[05:23:03.642] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:03.642] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:132:0) after 1m0s
[05:24:03.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:132:0)
[05:24:03.804] Timer tick, asking Buildbucket for the build status
[05:24:04.013] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:04.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:133:0) after 1m0s
[05:25:04.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:133:0)
[05:25:04.024] Timer tick, asking Buildbucket for the build status
[05:25:04.180] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:04.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:134:0) after 1m0s
[05:26:04.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:134:0)
[05:26:04.231] Timer tick, asking Buildbucket for the build status
[05:26:04.303] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:04.303] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:135:0) after 1m0s
[05:27:04.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:135:0)
[05:27:04.336] Timer tick, asking Buildbucket for the build status
[05:27:04.415] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:04.415] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:136:0) after 1m0s
[05:28:04.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:136:0)
[05:28:04.574] Timer tick, asking Buildbucket for the build status
[05:28:04.714] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:04.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:137:0) after 1m0s
[05:29:04.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:137:0)
[05:29:04.917] Timer tick, asking Buildbucket for the build status
[05:29:05.597] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:05.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:138:0) after 1m0s
[05:30:05.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:138:0)
[05:30:05.636] Timer tick, asking Buildbucket for the build status
[05:30:05.803] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:05.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:139:0) after 1m0s
[05:31:05.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:139:0)
[05:31:05.946] Timer tick, asking Buildbucket for the build status
[05:31:06.054] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:06.054] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:140:0) after 1m0s
[05:32:06.205] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:140:0)
[05:32:06.205] Timer tick, asking Buildbucket for the build status
[05:32:06.359] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:06.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:141:0) after 1m0s
[05:33:06.767] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:141:0)
[05:33:06.767] Timer tick, asking Buildbucket for the build status
[05:33:06.887] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:06.887] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:142:0) after 1m0s
[05:34:06.924] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:142:0)
[05:34:06.924] Timer tick, asking Buildbucket for the build status
[05:34:07.049] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:07.050] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:143:0) after 1m0s
[05:35:07.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:143:0)
[05:35:07.057] Timer tick, asking Buildbucket for the build status
[05:35:07.390] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:07.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:144:0) after 1m0s
[05:36:07.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:144:0)
[05:36:07.427] Timer tick, asking Buildbucket for the build status
[05:36:07.570] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:07.570] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:145:0) after 1m0s
[05:37:07.671] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:145:0)
[05:37:07.671] Timer tick, asking Buildbucket for the build status
[05:37:07.782] Build 8869850512917540272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:07.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9035280574984649760:146:0) after 1m0s
[05:37:09.174] Received PubSub notification, asking Buildbucket for the build status
[05:37:09.276] Build 8869850512917540272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:37:09.276] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599457028533525",
"created_by": "project:chromium",
"created_ts": "1599448397675787",
"id": "8869850512917540272",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"18c31c1d607118534cb063185bdfcd603a46ca4b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"18c31c1d607118534cb063185bdfcd603a46ca4b\", \"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-1-7co9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 479, \"got_angle_revision\": \"72f6b99c29f28bd7461f383e9b7223a8f670b7cc\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"18c31c1d607118534cb063185bdfcd603a46ca4b\", \"got_revision_cp\": \"refs/heads/master@{#804652}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e63aa953ddfd935a022090b3ef221438a328d1a1\", \"got_v8_revision\": \"ef9e56c6d3efce74216a967f3774a5c8bc8c142a\", \"got_v8_revision_cp\": \"refs/heads/8.7.74@{#1}\", \"got_webrtc_revision\": \"d46db9f1523ae45909b4a6fdc90a140443068bc6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32036}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"18c31c1d607118534cb063185bdfcd603a46ca4b\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-1-7co9\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5507-6e8d655\"], \"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": "1599448440468436",
"status": "COMPLETED",
"status_changed_ts": "1599457028920399",
"tags": [
"build_address:luci.chromium.ci/win32-official/479",
"builder:win32-official",
"buildset:commit/git/18c31c1d607118534cb063185bdfcd603a46ca4b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/18c31c1d607118534cb063185bdfcd603a46ca4b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035280574984649760",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869850512917540272/+/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:4e7f5f43151ce510",
"user_agent:luci-scheduler"
],
"updated_ts": "1599457028925455",
"url": "https://ci.chromium.org/b/8869850512917540272",
"utcnow_ts": "1599457029268953"
}
}
[05:37:09.276] Invocation finished in 2h23m52.956335494s with status SUCCEEDED