[02:07:20.854] New invocation is queued and will start shortly
[02:07:22.055] Starting the invocation (attempt 1)
[02:07:22.104] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:07:22.104] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:07:22.104] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077955893784473552",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3acf4b2539c301276641d78a865bde7ac5385b6e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9077955893784473552",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3acf4b2539c301276641d78a865bde7ac5385b6e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3acf4b2539c301276641d78a865bde7ac5385b6e",
"gitiles_ref:refs/heads/master"
]
}
[02:07:23.053] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558750042369012",
"id": "8912525831530722048",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3acf4b2539c301276641d78a865bde7ac5385b6e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558750042930516",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13530",
"builder:Windows deterministic",
"buildset:commit/git/3acf4b2539c301276641d78a865bde7ac5385b6e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3acf4b2539c301276641d78a865bde7ac5385b6e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077955893784473552",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912525831530722048/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558750042930645",
"url": "https://ci.chromium.org/b/8912525831530722048",
"utcnow_ts": "1558750043042235"
}
}
[02:07:23.053] Task URL: https://ci.chromium.org/b/8912525831530722048
[02:07:23.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:2:0) after 1m0s
[02:07:29.456] Received PubSub notification, asking Buildbucket for the build status
[02:07:29.586] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:2:0)
[02:08:23.076] Timer tick, asking Buildbucket for the build status
[02:08:23.292] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:4:0) after 1m0s
[02:09:23.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:4:0)
[02:09:23.377] Timer tick, asking Buildbucket for the build status
[02:09:24.705] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:24.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:5:0) after 1m0s
[02:10:24.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:5:0)
[02:10:24.763] Timer tick, asking Buildbucket for the build status
[02:10:25.709] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:25.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:6:0) after 1m0s
[02:11:25.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:6:0)
[02:11:25.748] Timer tick, asking Buildbucket for the build status
[02:11:26.924] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:26.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:7:0) after 1m0s
[02:12:26.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:7:0)
[02:12:26.944] Timer tick, asking Buildbucket for the build status
[02:12:27.111] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:27.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:8:0) after 1m0s
[02:13:27.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:8:0)
[02:13:27.174] Timer tick, asking Buildbucket for the build status
[02:13:27.661] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:27.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:9:0) after 1m0s
[02:14:27.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:9:0)
[02:14:27.682] Timer tick, asking Buildbucket for the build status
[02:14:28.454] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:28.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:10:0) after 1m0s
[02:15:28.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:10:0)
[02:15:28.750] Timer tick, asking Buildbucket for the build status
[02:15:28.904] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:28.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:11:0) after 1m0s
[02:16:29.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:11:0)
[02:16:29.204] Timer tick, asking Buildbucket for the build status
[02:16:30.716] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:30.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:12:0) after 1m0s
[02:17:30.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:12:0)
[02:17:30.787] Timer tick, asking Buildbucket for the build status
[02:17:32.838] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:32.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:13:0) after 1m0s
[02:18:32.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:13:0)
[02:18:32.985] Timer tick, asking Buildbucket for the build status
[02:18:34.845] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:34.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:14:0) after 1m0s
[02:19:35.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:14:0)
[02:19:35.085] Timer tick, asking Buildbucket for the build status
[02:19:36.067] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:36.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:15:0) after 1m0s
[02:20:36.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:15:0)
[02:20:36.147] Timer tick, asking Buildbucket for the build status
[02:20:36.346] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:36.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:16:0) after 1m0s
[02:21:36.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:16:0)
[02:21:36.523] Timer tick, asking Buildbucket for the build status
[02:21:37.456] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:37.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:17:0) after 1m0s
[02:22:37.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:17:0)
[02:22:37.519] Timer tick, asking Buildbucket for the build status
[02:22:38.988] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:38.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:18:0) after 1m0s
[02:23:39.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:18:0)
[02:23:39.206] Timer tick, asking Buildbucket for the build status
[02:23:39.460] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:39.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:19:0) after 1m0s
[02:24:39.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:19:0)
[02:24:39.484] Timer tick, asking Buildbucket for the build status
[02:24:42.096] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:42.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:20:0) after 1m0s
[02:25:42.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:20:0)
[02:25:42.210] Timer tick, asking Buildbucket for the build status
[02:25:44.103] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:44.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:21:0) after 1m0s
[02:26:44.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:21:0)
[02:26:44.225] Timer tick, asking Buildbucket for the build status
[02:26:44.352] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:44.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:22:0) after 1m0s
[02:27:44.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:22:0)
[02:27:44.369] Timer tick, asking Buildbucket for the build status
[02:27:45.713] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:23:0) after 1m0s
[02:28:45.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:23:0)
[02:28:45.734] Timer tick, asking Buildbucket for the build status
[02:28:46.830] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:46.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:24:0) after 1m0s
[02:29:46.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:24:0)
[02:29:46.941] Timer tick, asking Buildbucket for the build status
[02:29:48.208] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:48.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:25:0) after 1m0s
[02:30:48.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:25:0)
[02:30:48.227] Timer tick, asking Buildbucket for the build status
[02:30:48.484] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:48.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:26:0) after 1m0s
[02:31:48.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:26:0)
[02:31:48.528] Timer tick, asking Buildbucket for the build status
[02:31:49.062] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:49.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:27:0) after 1m0s
[02:32:49.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:27:0)
[02:32:49.151] Timer tick, asking Buildbucket for the build status
[02:32:49.669] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:49.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:28:0) after 1m0s
[02:33:49.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:28:0)
[02:33:49.692] Timer tick, asking Buildbucket for the build status
[02:33:50.982] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:50.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:29:0) after 1m0s
[02:34:50.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:29:0)
[02:34:50.983] Timer tick, asking Buildbucket for the build status
[02:34:51.555] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:51.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:30:0) after 1m0s
[02:35:51.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:30:0)
[02:35:51.593] Timer tick, asking Buildbucket for the build status
[02:35:54.201] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:31:0) after 1m0s
[02:36:54.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:31:0)
[02:36:54.226] Timer tick, asking Buildbucket for the build status
[02:36:54.632] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:54.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:32:0) after 1m0s
[02:37:54.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:32:0)
[02:37:54.677] Timer tick, asking Buildbucket for the build status
[02:37:57.453] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:57.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:33:0) after 1m0s
[02:38:57.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:33:0)
[02:38:57.638] Timer tick, asking Buildbucket for the build status
[02:38:57.988] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:57.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:34:0) after 1m0s
[02:39:58.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:34:0)
[02:39:58.181] Timer tick, asking Buildbucket for the build status
[02:39:58.969] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:58.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:35:0) after 1m0s
[02:40:59.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:35:0)
[02:40:59.225] Timer tick, asking Buildbucket for the build status
[02:41:01.339] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:01.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:36:0) after 1m0s
[02:42:01.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:36:0)
[02:42:01.320] Timer tick, asking Buildbucket for the build status
[02:42:02.186] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:02.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:37:0) after 1m0s
[02:43:02.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:37:0)
[02:43:02.232] Timer tick, asking Buildbucket for the build status
[02:43:02.413] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:02.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:38:0) after 1m0s
[02:44:02.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:38:0)
[02:44:02.432] Timer tick, asking Buildbucket for the build status
[02:44:03.061] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:03.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:39:0) after 1m0s
[02:45:03.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:39:0)
[02:45:03.094] Timer tick, asking Buildbucket for the build status
[02:45:05.475] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:05.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:40:0) after 1m0s
[02:46:05.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:40:0)
[02:46:05.566] Timer tick, asking Buildbucket for the build status
[02:46:07.837] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:07.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:41:0) after 1m0s
[02:47:07.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:41:0)
[02:47:07.958] Timer tick, asking Buildbucket for the build status
[02:47:08.764] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:08.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:42:0) after 1m0s
[02:48:08.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:42:0)
[02:48:08.972] Timer tick, asking Buildbucket for the build status
[02:48:11.070] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:11.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:43:0) after 1m0s
[02:49:11.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:43:0)
[02:49:11.102] Timer tick, asking Buildbucket for the build status
[02:49:12.761] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:12.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:44:0) after 1m0s
[02:50:12.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:44:0)
[02:50:12.791] Timer tick, asking Buildbucket for the build status
[02:50:15.149] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:15.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:45:0) after 1m0s
[02:51:15.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:45:0)
[02:51:15.264] Timer tick, asking Buildbucket for the build status
[02:51:17.586] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:17.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:46:0) after 1m0s
[02:52:17.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:46:0)
[02:52:17.631] Timer tick, asking Buildbucket for the build status
[02:52:18.043] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:18.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:47:0) after 1m0s
[02:53:18.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:47:0)
[02:53:18.173] Timer tick, asking Buildbucket for the build status
[02:53:18.394] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:18.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:48:0) after 1m0s
[02:54:18.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:48:0)
[02:54:18.516] Timer tick, asking Buildbucket for the build status
[02:54:19.997] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:19.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:49:0) after 1m0s
[02:55:20.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:49:0)
[02:55:20.339] Timer tick, asking Buildbucket for the build status
[02:55:20.573] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:20.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:50:0) after 1m0s
[02:56:20.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:50:0)
[02:56:20.625] Timer tick, asking Buildbucket for the build status
[02:56:21.429] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:21.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:51:0) after 1m0s
[02:57:21.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:51:0)
[02:57:21.854] Timer tick, asking Buildbucket for the build status
[02:57:24.013] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:24.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:52:0) after 1m0s
[02:58:24.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:52:0)
[02:58:24.181] Timer tick, asking Buildbucket for the build status
[02:58:24.811] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:24.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:53:0) after 1m0s
[02:59:25.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:53:0)
[02:59:25.056] Timer tick, asking Buildbucket for the build status
[02:59:27.328] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:27.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:54:0) after 1m0s
[03:00:27.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:54:0)
[03:00:27.398] Timer tick, asking Buildbucket for the build status
[03:00:27.718] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:27.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:55:0) after 1m0s
[03:01:27.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:55:0)
[03:01:27.738] Timer tick, asking Buildbucket for the build status
[03:01:28.926] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:28.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:56:0) after 1m0s
[03:02:29.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:56:0)
[03:02:29.106] Timer tick, asking Buildbucket for the build status
[03:02:29.737] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:29.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:57:0) after 1m0s
[03:03:29.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:57:0)
[03:03:29.763] Timer tick, asking Buildbucket for the build status
[03:03:30.207] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:30.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:58:0) after 1m0s
[03:04:30.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:58:0)
[03:04:30.192] Timer tick, asking Buildbucket for the build status
[03:04:30.297] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:30.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:59:0) after 1m0s
[03:05:30.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:59:0)
[03:05:30.845] Timer tick, asking Buildbucket for the build status
[03:05:31.119] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:31.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:60:0) after 1m0s
[03:06:31.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:60:0)
[03:06:31.866] Timer tick, asking Buildbucket for the build status
[03:06:32.129] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:32.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:61:0) after 1m0s
[03:07:32.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:61:0)
[03:07:32.463] Timer tick, asking Buildbucket for the build status
[03:07:33.102] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:33.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:62:0) after 1m0s
[03:08:33.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:62:0)
[03:08:33.460] Timer tick, asking Buildbucket for the build status
[03:08:33.869] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:33.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:63:0) after 1m0s
[03:09:34.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:63:0)
[03:09:34.383] Timer tick, asking Buildbucket for the build status
[03:09:34.859] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:34.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:64:0) after 1m0s
[03:10:35.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:64:0)
[03:10:35.058] Timer tick, asking Buildbucket for the build status
[03:10:35.298] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:35.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:65:0) after 1m0s
[03:11:35.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:65:0)
[03:11:35.332] Timer tick, asking Buildbucket for the build status
[03:11:35.492] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:35.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:66:0) after 1m0s
[03:12:36.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:66:0)
[03:12:36.242] Timer tick, asking Buildbucket for the build status
[03:12:36.859] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:36.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:67:0) after 1m0s
[03:13:36.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:67:0)
[03:13:36.975] Timer tick, asking Buildbucket for the build status
[03:13:37.570] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:37.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:68:0) after 1m0s
[03:14:38.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:68:0)
[03:14:38.278] Timer tick, asking Buildbucket for the build status
[03:14:40.076] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:40.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:69:0) after 1m0s
[03:15:40.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:69:0)
[03:15:40.169] Timer tick, asking Buildbucket for the build status
[03:15:40.365] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:40.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:70:0) after 1m0s
[03:16:40.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:70:0)
[03:16:40.866] Timer tick, asking Buildbucket for the build status
[03:16:42.025] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:42.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:71:0) after 1m0s
[03:17:42.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:71:0)
[03:17:42.092] Timer tick, asking Buildbucket for the build status
[03:17:42.884] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:42.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:72:0) after 1m0s
[03:18:42.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:72:0)
[03:18:42.914] Timer tick, asking Buildbucket for the build status
[03:18:43.078] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:43.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:73:0) after 1m0s
[03:19:43.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:73:0)
[03:19:43.282] Timer tick, asking Buildbucket for the build status
[03:19:43.605] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:43.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:74:0) after 1m0s
[03:20:43.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:74:0)
[03:20:43.747] Timer tick, asking Buildbucket for the build status
[03:20:43.871] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:43.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:75:0) after 1m0s
[03:21:43.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:75:0)
[03:21:43.901] Timer tick, asking Buildbucket for the build status
[03:21:44.162] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:76:0) after 1m0s
[03:22:44.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:76:0)
[03:22:44.499] Timer tick, asking Buildbucket for the build status
[03:22:45.788] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:45.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:77:0) after 1m0s
[03:23:46.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:77:0)
[03:23:46.043] Timer tick, asking Buildbucket for the build status
[03:23:46.178] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:46.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:78:0) after 1m0s
[03:24:46.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:78:0)
[03:24:46.228] Timer tick, asking Buildbucket for the build status
[03:24:46.377] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:79:0) after 1m0s
[03:25:46.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:79:0)
[03:25:46.595] Timer tick, asking Buildbucket for the build status
[03:25:46.692] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:46.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:80:0) after 1m0s
[03:26:46.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:80:0)
[03:26:46.837] Timer tick, asking Buildbucket for the build status
[03:26:46.969] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:81:0) after 1m0s
[03:27:47.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:81:0)
[03:27:47.053] Timer tick, asking Buildbucket for the build status
[03:27:47.293] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:47.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:82:0) after 1m0s
[03:28:47.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:82:0)
[03:28:47.315] Timer tick, asking Buildbucket for the build status
[03:28:47.487] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:47.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:83:0) after 1m0s
[03:29:47.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:83:0)
[03:29:47.524] Timer tick, asking Buildbucket for the build status
[03:29:48.970] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:48.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:84:0) after 1m0s
[03:30:48.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:84:0)
[03:30:48.996] Timer tick, asking Buildbucket for the build status
[03:30:49.358] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:49.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:85:0) after 1m0s
[03:31:49.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:85:0)
[03:31:49.382] Timer tick, asking Buildbucket for the build status
[03:31:49.463] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:49.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:86:0) after 1m0s
[03:32:49.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:86:0)
[03:32:49.491] Timer tick, asking Buildbucket for the build status
[03:32:49.602] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:49.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:87:0) after 1m0s
[03:33:49.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:87:0)
[03:33:49.662] Timer tick, asking Buildbucket for the build status
[03:33:49.785] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:49.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:88:0) after 1m0s
[03:34:49.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:88:0)
[03:34:49.814] Timer tick, asking Buildbucket for the build status
[03:34:50.335] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:50.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:89:0) after 1m0s
[03:35:50.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:89:0)
[03:35:50.384] Timer tick, asking Buildbucket for the build status
[03:35:50.513] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:50.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:90:0) after 1m0s
[03:36:50.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:90:0)
[03:36:50.571] Timer tick, asking Buildbucket for the build status
[03:36:50.883] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:50.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:91:0) after 1m0s
[03:37:50.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:91:0)
[03:37:50.869] Timer tick, asking Buildbucket for the build status
[03:37:51.021] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:51.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:92:0) after 1m0s
[03:38:51.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:92:0)
[03:38:51.148] Timer tick, asking Buildbucket for the build status
[03:38:51.624] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:51.624] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:93:0) after 1m0s
[03:39:51.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:93:0)
[03:39:51.697] Timer tick, asking Buildbucket for the build status
[03:39:52.399] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:94:0) after 1m0s
[03:40:52.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:94:0)
[03:40:52.463] Timer tick, asking Buildbucket for the build status
[03:40:52.929] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:52.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:95:0) after 1m0s
[03:41:53.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:95:0)
[03:41:53.002] Timer tick, asking Buildbucket for the build status
[03:41:53.170] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:53.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:96:0) after 1m0s
[03:42:53.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:96:0)
[03:42:53.794] Timer tick, asking Buildbucket for the build status
[03:42:55.192] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:55.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:97:0) after 1m0s
[03:43:55.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:97:0)
[03:43:55.321] Timer tick, asking Buildbucket for the build status
[03:43:55.411] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:55.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:98:0) after 1m0s
[03:44:55.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:98:0)
[03:44:55.458] Timer tick, asking Buildbucket for the build status
[03:44:56.174] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:56.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:99:0) after 1m0s
[03:45:56.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:99:0)
[03:45:56.205] Timer tick, asking Buildbucket for the build status
[03:45:56.405] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:56.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:100:0) after 1m0s
[03:46:56.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:100:0)
[03:46:56.464] Timer tick, asking Buildbucket for the build status
[03:46:56.625] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:56.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:101:0) after 1m0s
[03:47:56.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:101:0)
[03:47:56.695] Timer tick, asking Buildbucket for the build status
[03:47:57.006] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:57.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:102:0) after 1m0s
[03:48:57.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:102:0)
[03:48:57.025] Timer tick, asking Buildbucket for the build status
[03:48:57.726] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:57.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:103:0) after 1m0s
[03:49:57.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:103:0)
[03:49:57.749] Timer tick, asking Buildbucket for the build status
[03:49:58.484] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:58.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:104:0) after 1m0s
[03:50:58.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:104:0)
[03:50:58.556] Timer tick, asking Buildbucket for the build status
[03:50:59.333] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:59.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:105:0) after 1m0s
[03:51:59.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:105:0)
[03:51:59.404] Timer tick, asking Buildbucket for the build status
[03:51:59.492] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:59.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:106:0) after 1m0s
[03:53:01.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:106:0)
[03:53:01.947] Timer tick, asking Buildbucket for the build status
[03:53:02.045] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:02.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:107:0) after 1m0s
[03:54:02.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:107:0)
[03:54:02.150] Timer tick, asking Buildbucket for the build status
[03:54:02.312] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:02.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:108:0) after 1m0s
[03:55:02.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:108:0)
[03:55:02.372] Timer tick, asking Buildbucket for the build status
[03:55:02.781] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:02.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:109:0) after 1m0s
[03:56:02.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:109:0)
[03:56:02.868] Timer tick, asking Buildbucket for the build status
[03:56:03.406] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:03.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:110:0) after 1m0s
[03:57:03.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:110:0)
[03:57:03.558] Timer tick, asking Buildbucket for the build status
[03:57:04.558] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:04.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:111:0) after 1m0s
[03:58:04.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:111:0)
[03:58:04.633] Timer tick, asking Buildbucket for the build status
[03:58:04.801] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:04.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:112:0) after 1m0s
[03:59:04.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:112:0)
[03:59:04.841] Timer tick, asking Buildbucket for the build status
[03:59:05.264] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:05.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:113:0) after 1m0s
[04:00:05.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:113:0)
[04:00:05.335] Timer tick, asking Buildbucket for the build status
[04:00:05.482] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:05.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:114:0) after 1m0s
[04:01:05.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:114:0)
[04:01:05.560] Timer tick, asking Buildbucket for the build status
[04:01:05.731] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:05.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:115:0) after 1m0s
[04:02:05.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:115:0)
[04:02:05.749] Timer tick, asking Buildbucket for the build status
[04:02:07.921] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:07.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:116:0) after 1m0s
[04:03:07.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:116:0)
[04:03:07.990] Timer tick, asking Buildbucket for the build status
[04:03:08.325] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:08.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:117:0) after 1m0s
[04:04:08.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:117:0)
[04:04:08.363] Timer tick, asking Buildbucket for the build status
[04:04:08.761] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:08.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:118:0) after 1m0s
[04:05:08.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:118:0)
[04:05:08.780] Timer tick, asking Buildbucket for the build status
[04:05:09.664] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:09.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:119:0) after 1m0s
[04:06:09.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:119:0)
[04:06:09.763] Timer tick, asking Buildbucket for the build status
[04:06:09.847] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:120:0) after 1m0s
[04:07:09.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:120:0)
[04:07:09.927] Timer tick, asking Buildbucket for the build status
[04:07:10.136] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:10.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:121:0) after 1m0s
[04:08:10.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:121:0)
[04:08:10.154] Timer tick, asking Buildbucket for the build status
[04:08:10.305] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:10.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:122:0) after 1m0s
[04:09:10.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:122:0)
[04:09:10.325] Timer tick, asking Buildbucket for the build status
[04:09:10.459] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:10.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:123:0) after 1m0s
[04:10:11.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:123:0)
[04:10:11.565] Timer tick, asking Buildbucket for the build status
[04:10:11.992] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:11.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:124:0) after 1m0s
[04:11:12.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:124:0)
[04:11:12.151] Timer tick, asking Buildbucket for the build status
[04:11:12.437] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:12.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:125:0) after 1m0s
[04:12:12.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:125:0)
[04:12:12.513] Timer tick, asking Buildbucket for the build status
[04:12:13.292] Build 8912525831530722048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:13.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077955893784473552:126:0) after 1m0s
[04:12:24.533] Received PubSub notification, asking Buildbucket for the build status
[04:12:24.887] Build 8912525831530722048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:12:24.887] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558757540633267",
"created_by": "project:chromium",
"created_ts": "1558750042369012",
"id": "8912525831530722048",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3acf4b2539c301276641d78a865bde7ac5385b6e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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\": \"windows-deterministic-0-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13530, \"got_angle_revision\": \"40757b28bdc8bd1ac7312dd975a33f96abe87429\", \"got_dawn_revision\": \"8aa036e9e498fb99756d507fc7a6b6f7ebce6767\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"3acf4b2539c301276641d78a865bde7ac5385b6e\", \"got_revision_cp\": \"refs/heads/master@{#663372}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"376299599eab0ec7932adb28c55209e8daf3b8d8\", \"got_v8_revision_cp\": \"refs/heads/7.6.274@{#1}\", \"got_webrtc_revision\": \"8b096a03b48562b5f4e84784a3d6b06917dee334\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28065}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3acf4b2539c301276641d78a865bde7ac5385b6e\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-7052\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558750048108416",
"status": "COMPLETED",
"status_changed_ts": "1558757543448166",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13530",
"builder:Windows deterministic",
"buildset:commit/git/3acf4b2539c301276641d78a865bde7ac5385b6e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3acf4b2539c301276641d78a865bde7ac5385b6e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077955893784473552",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912525831530722048/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:45058fa570f65b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558757543578631",
"url": "https://ci.chromium.org/b/8912525831530722048",
"utcnow_ts": "1558757544878884"
}
}
[04:12:24.887] Invocation finished in 2h5m4.049861016s with status SUCCEEDED