[22:29:40.648] New invocation is queued and will start shortly
[22:29:41.821] Starting the invocation (attempt 1)
[22:29:41.858] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:29:41.858] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:29:41.858] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068275713000076336",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"f0efec460d84046692227236d024b32d9e454ce4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9068275713000076336",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/f0efec460d84046692227236d024b32d9e454ce4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0efec460d84046692227236d024b32d9e454ce4",
"gitiles_ref:refs/heads/master"
]
}
[22:29:43.819] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567981782637379",
"id": "8902845650247578208",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"f0efec460d84046692227236d024b32d9e454ce4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567981783444327",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14668",
"builder:Windows deterministic",
"buildset:commit/git/f0efec460d84046692227236d024b32d9e454ce4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0efec460d84046692227236d024b32d9e454ce4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068275713000076336",
"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/8902845650247578208/+/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": "1567981783444488",
"url": "https://ci.chromium.org/b/8902845650247578208",
"utcnow_ts": "1567981783806315"
}
}
[22:29:43.819] Task URL: https://ci.chromium.org/b/8902845650247578208
[22:29:43.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:2:0) after 1m0s
[22:29:51.215] Received PubSub notification, asking Buildbucket for the build status
[22:29:51.562] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:43.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:2:0)
[22:30:43.838] Timer tick, asking Buildbucket for the build status
[22:30:44.162] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:4:0) after 1m0s
[22:31:44.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:4:0)
[22:31:44.181] Timer tick, asking Buildbucket for the build status
[22:31:44.305] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:44.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:5:0) after 1m0s
[22:32:44.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:5:0)
[22:32:44.328] Timer tick, asking Buildbucket for the build status
[22:32:44.489] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:44.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:6:0) after 1m0s
[22:33:44.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:6:0)
[22:33:44.508] Timer tick, asking Buildbucket for the build status
[22:33:45.511] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:45.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:7:0) after 1m0s
[22:34:45.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:7:0)
[22:34:45.647] Timer tick, asking Buildbucket for the build status
[22:34:45.833] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:45.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:8:0) after 1m0s
[22:35:46.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:8:0)
[22:35:46.184] Timer tick, asking Buildbucket for the build status
[22:35:46.450] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:46.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:9:0) after 1m0s
[22:36:46.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:9:0)
[22:36:46.569] Timer tick, asking Buildbucket for the build status
[22:36:47.476] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:47.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:10:0) after 1m0s
[22:37:47.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:10:0)
[22:37:47.672] Timer tick, asking Buildbucket for the build status
[22:37:47.955] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:47.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:11:0) after 1m0s
[22:38:48.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:11:0)
[22:38:48.129] Timer tick, asking Buildbucket for the build status
[22:38:48.582] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:48.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:12:0) after 1m0s
[22:39:48.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:12:0)
[22:39:48.606] Timer tick, asking Buildbucket for the build status
[22:39:49.252] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:49.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:13:0) after 1m0s
[22:40:49.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:13:0)
[22:40:49.303] Timer tick, asking Buildbucket for the build status
[22:40:49.964] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:49.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:14:0) after 1m0s
[22:41:49.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:14:0)
[22:41:49.983] Timer tick, asking Buildbucket for the build status
[22:41:50.409] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:50.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:15:0) after 1m0s
[22:42:50.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:15:0)
[22:42:50.550] Timer tick, asking Buildbucket for the build status
[22:42:50.936] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:50.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:16:0) after 1m0s
[22:43:51.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:16:0)
[22:43:51.285] Timer tick, asking Buildbucket for the build status
[22:43:51.447] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:51.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:17:0) after 1m0s
[22:44:51.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:17:0)
[22:44:51.485] Timer tick, asking Buildbucket for the build status
[22:44:51.699] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:51.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:18:0) after 1m0s
[22:45:51.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:18:0)
[22:45:51.778] Timer tick, asking Buildbucket for the build status
[22:45:51.984] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:51.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:19:0) after 1m0s
[22:46:52.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:19:0)
[22:46:52.119] Timer tick, asking Buildbucket for the build status
[22:46:52.287] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:52.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:20:0) after 1m0s
[22:47:52.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:20:0)
[22:47:52.451] Timer tick, asking Buildbucket for the build status
[22:47:52.622] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:52.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:21:0) after 1m0s
[22:48:52.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:21:0)
[22:48:52.855] Timer tick, asking Buildbucket for the build status
[22:48:53.087] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:53.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:22:0) after 1m0s
[22:49:53.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:22:0)
[22:49:53.275] Timer tick, asking Buildbucket for the build status
[22:49:53.644] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:53.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:23:0) after 1m0s
[22:50:53.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:23:0)
[22:50:53.733] Timer tick, asking Buildbucket for the build status
[22:50:54.089] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:54.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:24:0) after 1m0s
[22:51:54.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:24:0)
[22:51:54.140] Timer tick, asking Buildbucket for the build status
[22:51:54.889] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:54.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:25:0) after 1m0s
[22:52:54.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:25:0)
[22:52:54.911] Timer tick, asking Buildbucket for the build status
[22:52:55.063] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:55.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:26:0) after 1m0s
[22:53:55.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:26:0)
[22:53:55.091] Timer tick, asking Buildbucket for the build status
[22:53:55.526] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:55.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:27:0) after 1m0s
[22:54:55.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:27:0)
[22:54:55.548] Timer tick, asking Buildbucket for the build status
[22:54:55.839] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:55.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:28:0) after 1m0s
[22:55:55.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:28:0)
[22:55:55.975] Timer tick, asking Buildbucket for the build status
[22:55:56.131] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:56.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:29:0) after 1m0s
[22:56:56.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:29:0)
[22:56:56.231] Timer tick, asking Buildbucket for the build status
[22:56:58.342] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:58.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:30:0) after 1m0s
[22:57:58.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:30:0)
[22:57:58.368] Timer tick, asking Buildbucket for the build status
[22:57:58.839] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:58.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:31:0) after 1m0s
[22:58:59.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:31:0)
[22:58:59.025] Timer tick, asking Buildbucket for the build status
[22:59:00.534] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:00.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:32:0) after 1m0s
[23:00:00.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:32:0)
[23:00:00.678] Timer tick, asking Buildbucket for the build status
[23:00:00.888] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:00.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:33:0) after 1m0s
[23:01:00.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:33:0)
[23:01:00.916] Timer tick, asking Buildbucket for the build status
[23:01:01.546] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:01.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:34:0) after 1m0s
[23:02:01.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:34:0)
[23:02:01.658] Timer tick, asking Buildbucket for the build status
[23:02:02.121] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:02.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:35:0) after 1m0s
[23:03:02.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:35:0)
[23:03:02.145] Timer tick, asking Buildbucket for the build status
[23:03:02.789] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:02.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:36:0) after 1m0s
[23:04:02.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:36:0)
[23:04:02.815] Timer tick, asking Buildbucket for the build status
[23:04:03.042] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:03.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:37:0) after 1m0s
[23:05:03.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:37:0)
[23:05:03.066] Timer tick, asking Buildbucket for the build status
[23:05:04.316] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:04.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:38:0) after 1m0s
[23:06:04.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:38:0)
[23:06:04.344] Timer tick, asking Buildbucket for the build status
[23:06:04.495] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:04.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:39:0) after 1m0s
[23:07:04.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:39:0)
[23:07:04.538] Timer tick, asking Buildbucket for the build status
[23:07:04.750] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:04.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:40:0) after 1m0s
[23:08:04.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:40:0)
[23:08:04.780] Timer tick, asking Buildbucket for the build status
[23:08:05.942] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:05.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:41:0) after 1m0s
[23:09:06.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:41:0)
[23:09:06.074] Timer tick, asking Buildbucket for the build status
[23:09:06.248] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:06.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:42:0) after 1m0s
[23:10:06.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:42:0)
[23:10:06.229] Timer tick, asking Buildbucket for the build status
[23:10:06.472] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:06.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:43:0) after 1m0s
[23:11:06.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:43:0)
[23:11:06.496] Timer tick, asking Buildbucket for the build status
[23:11:07.053] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:07.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:44:0) after 1m0s
[23:12:07.080] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:44:0)
[23:12:07.080] Timer tick, asking Buildbucket for the build status
[23:12:07.362] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:07.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:45:0) after 1m0s
[23:13:07.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:45:0)
[23:13:07.384] Timer tick, asking Buildbucket for the build status
[23:13:08.102] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:08.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:46:0) after 1m0s
[23:14:08.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:46:0)
[23:14:08.161] Timer tick, asking Buildbucket for the build status
[23:14:08.339] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:08.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:47:0) after 1m0s
[23:15:08.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:47:0)
[23:15:08.454] Timer tick, asking Buildbucket for the build status
[23:15:08.653] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:48:0) after 1m0s
[23:16:08.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:48:0)
[23:16:08.680] Timer tick, asking Buildbucket for the build status
[23:16:09.081] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:09.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:49:0) after 1m0s
[23:17:09.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:49:0)
[23:17:09.233] Timer tick, asking Buildbucket for the build status
[23:17:09.401] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:09.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:50:0) after 1m0s
[23:18:09.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:50:0)
[23:18:09.539] Timer tick, asking Buildbucket for the build status
[23:18:09.720] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:09.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:51:0) after 1m0s
[23:19:09.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:51:0)
[23:19:09.750] Timer tick, asking Buildbucket for the build status
[23:19:10.123] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:52:0) after 1m0s
[23:20:10.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:52:0)
[23:20:10.145] Timer tick, asking Buildbucket for the build status
[23:20:10.666] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:10.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:53:0) after 1m0s
[23:21:10.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:53:0)
[23:21:10.799] Timer tick, asking Buildbucket for the build status
[23:21:10.973] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:10.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:54:0) after 1m0s
[23:22:11.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:54:0)
[23:22:11.001] Timer tick, asking Buildbucket for the build status
[23:22:11.157] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:11.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:55:0) after 1m0s
[23:23:11.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:55:0)
[23:23:11.153] Timer tick, asking Buildbucket for the build status
[23:23:11.887] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:11.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:56:0) after 1m0s
[23:24:11.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:56:0)
[23:24:11.942] Timer tick, asking Buildbucket for the build status
[23:24:12.070] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:12.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:57:0) after 1m0s
[23:25:12.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:57:0)
[23:25:12.145] Timer tick, asking Buildbucket for the build status
[23:25:13.940] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:13.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:58:0) after 1m0s
[23:26:13.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:58:0)
[23:26:13.965] Timer tick, asking Buildbucket for the build status
[23:26:14.124] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:14.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:59:0) after 1m0s
[23:27:14.145] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:59:0)
[23:27:14.145] Timer tick, asking Buildbucket for the build status
[23:27:16.212] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:16.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:60:0) after 1m0s
[23:28:16.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:60:0)
[23:28:16.285] Timer tick, asking Buildbucket for the build status
[23:28:16.932] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:16.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:61:0) after 1m0s
[23:29:16.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:61:0)
[23:29:16.959] Timer tick, asking Buildbucket for the build status
[23:29:17.833] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:17.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:62:0) after 1m0s
[23:30:17.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:62:0)
[23:30:17.854] Timer tick, asking Buildbucket for the build status
[23:30:18.158] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:18.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:63:0) after 1m0s
[23:31:18.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:63:0)
[23:31:18.184] Timer tick, asking Buildbucket for the build status
[23:31:18.375] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:18.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:64:0) after 1m0s
[23:32:18.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:64:0)
[23:32:18.397] Timer tick, asking Buildbucket for the build status
[23:32:19.246] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:19.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:65:0) after 1m0s
[23:33:19.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:65:0)
[23:33:19.323] Timer tick, asking Buildbucket for the build status
[23:33:20.163] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:20.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:66:0) after 1m0s
[23:34:20.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:66:0)
[23:34:20.187] Timer tick, asking Buildbucket for the build status
[23:34:20.441] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:20.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:67:0) after 1m0s
[23:35:20.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:67:0)
[23:35:20.565] Timer tick, asking Buildbucket for the build status
[23:35:20.756] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:20.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:68:0) after 1m0s
[23:36:20.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:68:0)
[23:36:20.779] Timer tick, asking Buildbucket for the build status
[23:36:21.138] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:21.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:69:0) after 1m0s
[23:37:21.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:69:0)
[23:37:21.167] Timer tick, asking Buildbucket for the build status
[23:37:21.403] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:21.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:70:0) after 1m0s
[23:38:21.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:70:0)
[23:38:21.406] Timer tick, asking Buildbucket for the build status
[23:38:22.529] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:22.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:71:0) after 1m0s
[23:39:22.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:71:0)
[23:39:22.657] Timer tick, asking Buildbucket for the build status
[23:39:22.853] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:22.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:72:0) after 1m0s
[23:40:22.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:72:0)
[23:40:22.877] Timer tick, asking Buildbucket for the build status
[23:40:23.536] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:23.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:73:0) after 1m0s
[23:41:23.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:73:0)
[23:41:23.598] Timer tick, asking Buildbucket for the build status
[23:41:23.947] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:23.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:74:0) after 1m0s
[23:42:24.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:74:0)
[23:42:24.502] Timer tick, asking Buildbucket for the build status
[23:42:24.644] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:24.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:75:0) after 1m0s
[23:43:24.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:75:0)
[23:43:24.633] Timer tick, asking Buildbucket for the build status
[23:43:24.789] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:24.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:76:0) after 1m0s
[23:44:24.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:76:0)
[23:44:24.868] Timer tick, asking Buildbucket for the build status
[23:44:25.042] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:25.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:77:0) after 1m0s
[23:45:25.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:77:0)
[23:45:25.127] Timer tick, asking Buildbucket for the build status
[23:45:26.061] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:78:0) after 1m0s
[23:46:26.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:78:0)
[23:46:26.105] Timer tick, asking Buildbucket for the build status
[23:46:26.671] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:79:0) after 1m0s
[23:47:26.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:79:0)
[23:47:26.770] Timer tick, asking Buildbucket for the build status
[23:47:26.915] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:26.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:80:0) after 1m0s
[23:48:27.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:80:0)
[23:48:27.103] Timer tick, asking Buildbucket for the build status
[23:48:29.187] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:81:0) after 1m0s
[23:49:29.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:81:0)
[23:49:29.350] Timer tick, asking Buildbucket for the build status
[23:49:29.584] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:29.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:82:0) after 1m0s
[23:50:29.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:82:0)
[23:50:29.659] Timer tick, asking Buildbucket for the build status
[23:50:30.150] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:30.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:83:0) after 1m0s
[23:51:30.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:83:0)
[23:51:30.350] Timer tick, asking Buildbucket for the build status
[23:51:32.109] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:32.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:84:0) after 1m0s
[23:52:32.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:84:0)
[23:52:32.761] Timer tick, asking Buildbucket for the build status
[23:52:33.140] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:33.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:85:0) after 1m0s
[23:53:33.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:85:0)
[23:53:33.256] Timer tick, asking Buildbucket for the build status
[23:53:33.416] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:33.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:86:0) after 1m0s
[23:54:33.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:86:0)
[23:54:33.443] Timer tick, asking Buildbucket for the build status
[23:54:33.914] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:87:0) after 1m0s
[23:55:33.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:87:0)
[23:55:33.936] Timer tick, asking Buildbucket for the build status
[23:55:34.113] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:34.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:88:0) after 1m0s
[23:56:34.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:88:0)
[23:56:34.137] Timer tick, asking Buildbucket for the build status
[23:56:34.252] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:34.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:89:0) after 1m0s
[23:57:34.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:89:0)
[23:57:34.342] Timer tick, asking Buildbucket for the build status
[23:57:35.589] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:35.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:90:0) after 1m0s
[23:58:35.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:90:0)
[23:58:35.976] Timer tick, asking Buildbucket for the build status
[23:58:36.652] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:36.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:91:0) after 1m0s
[23:59:36.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:91:0)
[23:59:36.730] Timer tick, asking Buildbucket for the build status
[23:59:37.506] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:37.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:92:0) after 1m0s
[00:00:37.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:92:0)
[00:00:37.541] Timer tick, asking Buildbucket for the build status
[00:00:37.799] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:37.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:93:0) after 1m0s
[00:01:37.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:93:0)
[00:01:37.822] Timer tick, asking Buildbucket for the build status
[00:01:38.173] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:38.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:94:0) after 1m0s
[00:02:38.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:94:0)
[00:02:38.199] Timer tick, asking Buildbucket for the build status
[00:02:38.957] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:38.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:95:0) after 1m0s
[00:03:39.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:95:0)
[00:03:39.043] Timer tick, asking Buildbucket for the build status
[00:03:42.782] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:42.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:96:0) after 1m0s
[00:04:43.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:96:0)
[00:04:43.062] Timer tick, asking Buildbucket for the build status
[00:04:43.823] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:97:0) after 1m0s
[00:05:44.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:97:0)
[00:05:44.196] Timer tick, asking Buildbucket for the build status
[00:05:45.277] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:45.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:98:0) after 1m0s
[00:06:45.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:98:0)
[00:06:45.891] Timer tick, asking Buildbucket for the build status
[00:06:46.967] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:46.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:99:0) after 1m0s
[00:07:47.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:99:0)
[00:07:47.605] Timer tick, asking Buildbucket for the build status
[00:07:47.901] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:47.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:100:0) after 1m0s
[00:08:48.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:100:0)
[00:08:48.066] Timer tick, asking Buildbucket for the build status
[00:08:49.351] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:49.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:101:0) after 1m0s
[00:09:49.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:101:0)
[00:09:49.380] Timer tick, asking Buildbucket for the build status
[00:09:53.134] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:53.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:102:0) after 1m0s
[00:10:53.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:102:0)
[00:10:53.586] Timer tick, asking Buildbucket for the build status
[00:10:54.326] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:54.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:103:0) after 1m0s
[00:11:54.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:103:0)
[00:11:54.461] Timer tick, asking Buildbucket for the build status
[00:11:55.145] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:55.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:104:0) after 1m0s
[00:12:55.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:104:0)
[00:12:55.313] Timer tick, asking Buildbucket for the build status
[00:12:59.223] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:59.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:105:0) after 1m0s
[00:13:59.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:105:0)
[00:13:59.351] Timer tick, asking Buildbucket for the build status
[00:14:02.563] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:02.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:106:0) after 1m0s
[00:15:03.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:106:0)
[00:15:03.004] Timer tick, asking Buildbucket for the build status
[00:15:05.876] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:05.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:107:0) after 1m0s
[00:16:05.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:107:0)
[00:16:05.905] Timer tick, asking Buildbucket for the build status
[00:16:06.190] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:06.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:108:0) after 1m0s
[00:17:06.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:108:0)
[00:17:06.464] Timer tick, asking Buildbucket for the build status
[00:17:06.901] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:06.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:109:0) after 1m0s
[00:18:07.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:109:0)
[00:18:07.665] Timer tick, asking Buildbucket for the build status
[00:18:09.537] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:09.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:110:0) after 1m0s
[00:19:09.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:110:0)
[00:19:09.644] Timer tick, asking Buildbucket for the build status
[00:19:09.906] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:09.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:111:0) after 1m0s
[00:20:09.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:111:0)
[00:20:09.921] Timer tick, asking Buildbucket for the build status
[00:20:10.110] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:10.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:112:0) after 1m0s
[00:21:10.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:112:0)
[00:21:10.151] Timer tick, asking Buildbucket for the build status
[00:21:10.729] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:10.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:113:0) after 1m0s
[00:22:10.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:113:0)
[00:22:10.806] Timer tick, asking Buildbucket for the build status
[00:22:10.973] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:10.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:114:0) after 1m0s
[00:23:11.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:114:0)
[00:23:11.122] Timer tick, asking Buildbucket for the build status
[00:23:11.952] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:11.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:115:0) after 1m0s
[00:24:12.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:115:0)
[00:24:12.031] Timer tick, asking Buildbucket for the build status
[00:24:12.224] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:12.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:116:0) after 1m0s
[00:25:12.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:116:0)
[00:25:12.373] Timer tick, asking Buildbucket for the build status
[00:25:14.378] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:14.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:117:0) after 1m0s
[00:26:14.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:117:0)
[00:26:14.505] Timer tick, asking Buildbucket for the build status
[00:26:14.883] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:14.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:118:0) after 1m0s
[00:27:14.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:118:0)
[00:27:14.931] Timer tick, asking Buildbucket for the build status
[00:27:16.247] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:16.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:119:0) after 1m0s
[00:28:16.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:119:0)
[00:28:16.304] Timer tick, asking Buildbucket for the build status
[00:28:16.493] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:120:0) after 1m0s
[00:29:16.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:120:0)
[00:29:16.623] Timer tick, asking Buildbucket for the build status
[00:29:16.935] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:16.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:121:0) after 1m0s
[00:30:16.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:121:0)
[00:30:16.993] Timer tick, asking Buildbucket for the build status
[00:30:17.232] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:17.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:122:0) after 1m0s
[00:31:17.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:122:0)
[00:31:17.255] Timer tick, asking Buildbucket for the build status
[00:31:17.935] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:17.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:123:0) after 1m0s
[00:32:17.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:123:0)
[00:32:17.963] Timer tick, asking Buildbucket for the build status
[00:32:18.222] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:18.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:124:0) after 1m0s
[00:33:18.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:124:0)
[00:33:18.259] Timer tick, asking Buildbucket for the build status
[00:33:18.409] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:18.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:125:0) after 1m0s
[00:34:18.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:125:0)
[00:34:18.440] Timer tick, asking Buildbucket for the build status
[00:34:18.574] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:18.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:126:0) after 1m0s
[00:35:18.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:126:0)
[00:35:18.628] Timer tick, asking Buildbucket for the build status
[00:35:19.101] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:19.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:127:0) after 1m0s
[00:36:19.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:127:0)
[00:36:19.128] Timer tick, asking Buildbucket for the build status
[00:36:19.323] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:19.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:128:0) after 1m0s
[00:37:19.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:128:0)
[00:37:19.349] Timer tick, asking Buildbucket for the build status
[00:37:19.885] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:19.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:129:0) after 1m0s
[00:38:19.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:129:0)
[00:38:19.907] Timer tick, asking Buildbucket for the build status
[00:38:20.089] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:20.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:130:0) after 1m0s
[00:39:20.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:130:0)
[00:39:20.134] Timer tick, asking Buildbucket for the build status
[00:39:20.362] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:20.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:131:0) after 1m0s
[00:40:20.401] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:131:0)
[00:40:20.401] Timer tick, asking Buildbucket for the build status
[00:40:20.619] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:20.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:132:0) after 1m0s
[00:41:20.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:132:0)
[00:41:20.659] Timer tick, asking Buildbucket for the build status
[00:41:20.959] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:20.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:133:0) after 1m0s
[00:42:21.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:133:0)
[00:42:21.087] Timer tick, asking Buildbucket for the build status
[00:42:21.391] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:21.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:134:0) after 1m0s
[00:43:21.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:134:0)
[00:43:21.430] Timer tick, asking Buildbucket for the build status
[00:43:24.058] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:24.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:135:0) after 1m0s
[00:44:24.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:135:0)
[00:44:24.092] Timer tick, asking Buildbucket for the build status
[00:44:24.669] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:24.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:136:0) after 1m0s
[00:45:24.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:136:0)
[00:45:24.893] Timer tick, asking Buildbucket for the build status
[00:45:27.452] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:27.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:137:0) after 1m0s
[00:46:27.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:137:0)
[00:46:27.653] Timer tick, asking Buildbucket for the build status
[00:46:28.424] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:28.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:138:0) after 1m0s
[00:47:28.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:138:0)
[00:47:28.403] Timer tick, asking Buildbucket for the build status
[00:47:29.475] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:29.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:139:0) after 1m0s
[00:48:29.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:139:0)
[00:48:29.779] Timer tick, asking Buildbucket for the build status
[00:48:29.951] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:29.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:140:0) after 1m0s
[00:49:30.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:140:0)
[00:49:30.123] Timer tick, asking Buildbucket for the build status
[00:49:30.337] Build 8902845650247578208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:30.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068275713000076336:141:0) after 1m0s
[00:50:06.085] Received PubSub notification, asking Buildbucket for the build status
[00:50:06.217] Build 8902845650247578208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:50:06.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567990205251288",
"created_by": "project:chromium",
"created_ts": "1567981782637379",
"id": "8902845650247578208",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"f0efec460d84046692227236d024b32d9e454ce4\"}}",
"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-y5mg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14668, \"got_angle_revision\": \"a7ff7df26f281e956b59fad52f0408c3528648ed\", \"got_dawn_revision\": \"a900ccebcf428c99184ca44a3fad6030b2e43fc7\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"f0efec460d84046692227236d024b32d9e454ce4\", \"got_revision_cp\": \"refs/heads/master@{#694625}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"a4b8f8f42ecc8c2de54787f7d632eddd5cb7c97a\", \"got_v8_revision_cp\": \"refs/heads/7.9.1@{#1}\", \"got_webrtc_revision\": \"be2e5f78b3498858cd8694fa6d00a28ba8093f26\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29105}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"f0efec460d84046692227236d024b32d9e454ce4\"}, \"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-y5mg\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4417-ad2503b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567981790186635",
"status": "COMPLETED",
"status_changed_ts": "1567990205511522",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14668",
"builder:Windows deterministic",
"buildset:commit/git/f0efec460d84046692227236d024b32d9e454ce4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0efec460d84046692227236d024b32d9e454ce4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068275713000076336",
"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/8902845650247578208/+/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:472bd0d4e62f2b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1567990205515418",
"url": "https://ci.chromium.org/b/8902845650247578208",
"utcnow_ts": "1567990206210004"
}
}
[00:50:06.217] Invocation finished in 2h20m25.588645281s with status SUCCEEDED