[18:29:46.295] New invocation is queued and will start shortly
[18:29:47.448] Starting the invocation (attempt 1)
[18:29:47.479] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:29:47.480] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:29:47.480] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9072820654890798560",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1030d37889de0541142503cc7f7ad7fa554ba2c0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9072820654890798560",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"gitiles_ref:refs/heads/master"
]
}
[18:29:48.541] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563647387832944",
"id": "8907390592614919488",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1030d37889de0541142503cc7f7ad7fa554ba2c0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563647388256254",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14188",
"builder:Windows deterministic",
"buildset:commit/git/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072820654890798560",
"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/8907390592614919488/+/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": "1563647388256875",
"url": "https://ci.chromium.org/b/8907390592614919488",
"utcnow_ts": "1563647388523750"
}
}
[18:29:48.541] Task URL: https://ci.chromium.org/b/8907390592614919488
[18:29:48.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:2:0) after 1m0s
[18:29:50.553] Received PubSub notification, asking Buildbucket for the build status
[18:29:51.831] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:48.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:2:0)
[18:30:48.596] Timer tick, asking Buildbucket for the build status
[18:30:48.736] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:48.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:4:0) after 1m0s
[18:31:48.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:4:0)
[18:31:48.876] Timer tick, asking Buildbucket for the build status
[18:31:52.472] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:52.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:5:0) after 1m0s
[18:32:52.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:5:0)
[18:32:52.493] Timer tick, asking Buildbucket for the build status
[18:32:55.062] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:55.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:6:0) after 1m0s
[18:33:55.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:6:0)
[18:33:55.082] Timer tick, asking Buildbucket for the build status
[18:33:55.353] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:55.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:7:0) after 1m0s
[18:34:55.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:7:0)
[18:34:55.375] Timer tick, asking Buildbucket for the build status
[18:34:55.937] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:55.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:8:0) after 1m0s
[18:35:56.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:8:0)
[18:35:56.007] Timer tick, asking Buildbucket for the build status
[18:35:56.926] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:56.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:9:0) after 1m0s
[18:36:57.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:9:0)
[18:36:57.040] Timer tick, asking Buildbucket for the build status
[18:37:00.072] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:00.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:10:0) after 1m0s
[18:38:00.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:10:0)
[18:38:00.101] Timer tick, asking Buildbucket for the build status
[18:38:00.990] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:00.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:11:0) after 1m0s
[18:39:01.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:11:0)
[18:39:01.010] Timer tick, asking Buildbucket for the build status
[18:39:01.536] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:01.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:12:0) after 1m0s
[18:40:01.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:12:0)
[18:40:01.627] Timer tick, asking Buildbucket for the build status
[18:40:02.029] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:02.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:13:0) after 1m0s
[18:41:02.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:13:0)
[18:41:02.055] Timer tick, asking Buildbucket for the build status
[18:41:02.508] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:02.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:14:0) after 1m0s
[18:42:02.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:14:0)
[18:42:02.533] Timer tick, asking Buildbucket for the build status
[18:42:02.841] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:02.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:15:0) after 1m0s
[18:43:02.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:15:0)
[18:43:02.856] Timer tick, asking Buildbucket for the build status
[18:43:03.147] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:03.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:16:0) after 1m0s
[18:44:03.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:16:0)
[18:44:03.198] Timer tick, asking Buildbucket for the build status
[18:44:03.939] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:03.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:17:0) after 1m0s
[18:45:03.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:17:0)
[18:45:03.966] Timer tick, asking Buildbucket for the build status
[18:45:04.246] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:04.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:18:0) after 1m0s
[18:46:04.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:18:0)
[18:46:04.273] Timer tick, asking Buildbucket for the build status
[18:46:04.496] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:04.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:19:0) after 1m0s
[18:47:04.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:19:0)
[18:47:04.536] Timer tick, asking Buildbucket for the build status
[18:47:04.734] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:04.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:20:0) after 1m0s
[18:48:04.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:20:0)
[18:48:04.811] Timer tick, asking Buildbucket for the build status
[18:48:05.012] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:05.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:21:0) after 1m0s
[18:49:05.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:21:0)
[18:49:05.014] Timer tick, asking Buildbucket for the build status
[18:49:05.565] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:05.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:22:0) after 1m0s
[18:50:05.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:22:0)
[18:50:05.730] Timer tick, asking Buildbucket for the build status
[18:50:05.927] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:05.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:23:0) after 1m0s
[18:51:05.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:23:0)
[18:51:05.969] Timer tick, asking Buildbucket for the build status
[18:51:06.162] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:06.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:24:0) after 1m0s
[18:52:06.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:24:0)
[18:52:06.185] Timer tick, asking Buildbucket for the build status
[18:52:06.736] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:06.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:25:0) after 1m0s
[18:53:06.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:25:0)
[18:53:06.756] Timer tick, asking Buildbucket for the build status
[18:53:06.932] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:06.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:26:0) after 1m0s
[18:54:07.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:26:0)
[18:54:07.240] Timer tick, asking Buildbucket for the build status
[18:54:07.730] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:07.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:27:0) after 1m0s
[18:55:08.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:27:0)
[18:55:08.040] Timer tick, asking Buildbucket for the build status
[18:55:08.821] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:08.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:28:0) after 1m0s
[18:56:09.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:28:0)
[18:56:09.078] Timer tick, asking Buildbucket for the build status
[18:56:09.755] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:09.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:29:0) after 1m0s
[18:57:10.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:29:0)
[18:57:10.031] Timer tick, asking Buildbucket for the build status
[18:57:10.686] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:10.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:30:0) after 1m0s
[18:58:10.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:30:0)
[18:58:10.725] Timer tick, asking Buildbucket for the build status
[18:58:10.958] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:10.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:31:0) after 1m0s
[18:59:11.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:31:0)
[18:59:11.173] Timer tick, asking Buildbucket for the build status
[18:59:11.433] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:11.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:32:0) after 1m0s
[19:00:11.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:32:0)
[19:00:11.519] Timer tick, asking Buildbucket for the build status
[19:00:11.817] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:11.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:33:0) after 1m0s
[19:01:11.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:33:0)
[19:01:11.844] Timer tick, asking Buildbucket for the build status
[19:01:11.996] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:11.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:34:0) after 1m0s
[19:02:12.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:34:0)
[19:02:12.064] Timer tick, asking Buildbucket for the build status
[19:02:12.290] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:12.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:35:0) after 1m0s
[19:03:12.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:35:0)
[19:03:12.347] Timer tick, asking Buildbucket for the build status
[19:03:12.546] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:12.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:36:0) after 1m0s
[19:04:12.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:36:0)
[19:04:12.604] Timer tick, asking Buildbucket for the build status
[19:04:12.819] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:12.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:37:0) after 1m0s
[19:05:12.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:37:0)
[19:05:12.872] Timer tick, asking Buildbucket for the build status
[19:05:13.146] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:13.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:38:0) after 1m0s
[19:06:13.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:38:0)
[19:06:13.281] Timer tick, asking Buildbucket for the build status
[19:06:13.928] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:13.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:39:0) after 1m0s
[19:07:14.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:39:0)
[19:07:14.314] Timer tick, asking Buildbucket for the build status
[19:07:14.581] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:14.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:40:0) after 1m0s
[19:08:14.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:40:0)
[19:08:14.810] Timer tick, asking Buildbucket for the build status
[19:08:15.038] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:15.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:41:0) after 1m0s
[19:09:15.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:41:0)
[19:09:15.233] Timer tick, asking Buildbucket for the build status
[19:09:16.710] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:16.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:42:0) after 1m0s
[19:10:16.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:42:0)
[19:10:16.831] Timer tick, asking Buildbucket for the build status
[19:10:17.016] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:17.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:43:0) after 1m0s
[19:11:17.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:43:0)
[19:11:17.148] Timer tick, asking Buildbucket for the build status
[19:11:17.326] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:44:0) after 1m0s
[19:12:17.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:44:0)
[19:12:17.355] Timer tick, asking Buildbucket for the build status
[19:12:17.602] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:17.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:45:0) after 1m0s
[19:13:17.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:45:0)
[19:13:17.639] Timer tick, asking Buildbucket for the build status
[19:13:19.423] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:19.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:46:0) after 1m0s
[19:14:19.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:46:0)
[19:14:19.446] Timer tick, asking Buildbucket for the build status
[19:14:20.714] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:20.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:47:0) after 1m0s
[19:15:20.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:47:0)
[19:15:20.751] Timer tick, asking Buildbucket for the build status
[19:15:21.163] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:21.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:48:0) after 1m0s
[19:16:21.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:48:0)
[19:16:21.218] Timer tick, asking Buildbucket for the build status
[19:16:22.182] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:22.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:49:0) after 1m0s
[19:17:22.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:49:0)
[19:17:22.310] Timer tick, asking Buildbucket for the build status
[19:17:22.882] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:22.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:50:0) after 1m0s
[19:18:23.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:50:0)
[19:18:23.004] Timer tick, asking Buildbucket for the build status
[19:18:23.186] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:23.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:51:0) after 1m0s
[19:19:23.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:51:0)
[19:19:23.230] Timer tick, asking Buildbucket for the build status
[19:19:23.532] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:23.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:52:0) after 1m0s
[19:20:23.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:52:0)
[19:20:23.659] Timer tick, asking Buildbucket for the build status
[19:20:24.363] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:24.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:53:0) after 1m0s
[19:21:24.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:53:0)
[19:21:24.512] Timer tick, asking Buildbucket for the build status
[19:21:24.675] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:24.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:54:0) after 1m0s
[19:22:24.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:54:0)
[19:22:24.813] Timer tick, asking Buildbucket for the build status
[19:22:25.030] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:25.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:55:0) after 1m0s
[19:23:25.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:55:0)
[19:23:25.061] Timer tick, asking Buildbucket for the build status
[19:23:25.669] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:25.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:56:0) after 1m0s
[19:24:25.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:56:0)
[19:24:25.815] Timer tick, asking Buildbucket for the build status
[19:24:28.456] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:28.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:57:0) after 1m0s
[19:25:28.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:57:0)
[19:25:28.509] Timer tick, asking Buildbucket for the build status
[19:25:28.802] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:28.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:58:0) after 1m0s
[19:26:28.890] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:58:0)
[19:26:28.890] Timer tick, asking Buildbucket for the build status
[19:26:30.070] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:30.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:59:0) after 1m0s
[19:27:30.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:59:0)
[19:27:30.216] Timer tick, asking Buildbucket for the build status
[19:27:30.517] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:30.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:60:0) after 1m0s
[19:28:30.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:60:0)
[19:28:30.551] Timer tick, asking Buildbucket for the build status
[19:28:31.686] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:31.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:61:0) after 1m0s
[19:29:31.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:61:0)
[19:29:31.813] Timer tick, asking Buildbucket for the build status
[19:29:32.034] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:32.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:62:0) after 1m0s
[19:30:32.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:62:0)
[19:30:32.111] Timer tick, asking Buildbucket for the build status
[19:30:32.447] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:32.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:63:0) after 1m0s
[19:31:32.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:63:0)
[19:31:32.473] Timer tick, asking Buildbucket for the build status
[19:31:32.706] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:32.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:64:0) after 1m0s
[19:32:32.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:64:0)
[19:32:32.798] Timer tick, asking Buildbucket for the build status
[19:32:33.029] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:33.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:65:0) after 1m0s
[19:33:33.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:65:0)
[19:33:33.344] Timer tick, asking Buildbucket for the build status
[19:33:33.925] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:33.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:66:0) after 1m0s
[19:34:34.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:66:0)
[19:34:34.039] Timer tick, asking Buildbucket for the build status
[19:34:34.515] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:34.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:67:0) after 1m0s
[19:35:37.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:67:0)
[19:35:37.072] Timer tick, asking Buildbucket for the build status
[19:35:37.441] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:37.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:68:0) after 1m0s
[19:36:37.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:68:0)
[19:36:37.820] Timer tick, asking Buildbucket for the build status
[19:36:38.460] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:38.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:69:0) after 1m0s
[19:37:38.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:69:0)
[19:37:38.910] Timer tick, asking Buildbucket for the build status
[19:37:39.540] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:39.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:70:0) after 1m0s
[19:38:39.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:70:0)
[19:38:39.662] Timer tick, asking Buildbucket for the build status
[19:38:40.426] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:71:0) after 1m0s
[19:39:41.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:71:0)
[19:39:41.397] Timer tick, asking Buildbucket for the build status
[19:39:44.259] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:44.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:72:0) after 1m0s
[19:40:44.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:72:0)
[19:40:44.316] Timer tick, asking Buildbucket for the build status
[19:40:44.968] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:44.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:73:0) after 1m0s
[19:41:45.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:73:0)
[19:41:45.002] Timer tick, asking Buildbucket for the build status
[19:41:45.153] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:74:0) after 1m0s
[19:42:45.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:74:0)
[19:42:45.180] Timer tick, asking Buildbucket for the build status
[19:42:46.910] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:46.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:75:0) after 1m0s
[19:43:46.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:75:0)
[19:43:46.913] Timer tick, asking Buildbucket for the build status
[19:43:47.196] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:47.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:76:0) after 1m0s
[19:44:47.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:76:0)
[19:44:47.263] Timer tick, asking Buildbucket for the build status
[19:44:47.788] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:47.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:77:0) after 1m0s
[19:45:47.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:77:0)
[19:45:47.779] Timer tick, asking Buildbucket for the build status
[19:45:48.255] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:48.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:78:0) after 1m0s
[19:46:48.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:78:0)
[19:46:48.301] Timer tick, asking Buildbucket for the build status
[19:46:48.456] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:48.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:79:0) after 1m0s
[19:47:48.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:79:0)
[19:47:48.544] Timer tick, asking Buildbucket for the build status
[19:47:48.713] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:48.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:80:0) after 1m0s
[19:48:48.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:80:0)
[19:48:48.747] Timer tick, asking Buildbucket for the build status
[19:48:49.301] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:49.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:81:0) after 1m0s
[19:49:49.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:81:0)
[19:49:49.444] Timer tick, asking Buildbucket for the build status
[19:49:49.767] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:82:0) after 1m0s
[19:50:49.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:82:0)
[19:50:49.797] Timer tick, asking Buildbucket for the build status
[19:50:50.059] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:50.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:83:0) after 1m0s
[19:51:50.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:83:0)
[19:51:50.157] Timer tick, asking Buildbucket for the build status
[19:51:51.095] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:51.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:84:0) after 1m0s
[19:52:51.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:84:0)
[19:52:51.124] Timer tick, asking Buildbucket for the build status
[19:52:52.196] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:52.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:85:0) after 1m0s
[19:53:52.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:85:0)
[19:53:52.220] Timer tick, asking Buildbucket for the build status
[19:53:52.637] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:52.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:86:0) after 1m0s
[19:54:52.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:86:0)
[19:54:52.669] Timer tick, asking Buildbucket for the build status
[19:54:52.910] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:52.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:87:0) after 1m0s
[19:55:52.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:87:0)
[19:55:52.937] Timer tick, asking Buildbucket for the build status
[19:55:53.254] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:53.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:88:0) after 1m0s
[19:56:53.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:88:0)
[19:56:53.278] Timer tick, asking Buildbucket for the build status
[19:56:53.468] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:53.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:89:0) after 1m0s
[19:57:53.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:89:0)
[19:57:53.495] Timer tick, asking Buildbucket for the build status
[19:57:53.842] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:53.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:90:0) after 1m0s
[19:58:53.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:90:0)
[19:58:53.869] Timer tick, asking Buildbucket for the build status
[19:58:54.852] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:54.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:91:0) after 1m0s
[19:59:54.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:91:0)
[19:59:54.880] Timer tick, asking Buildbucket for the build status
[19:59:55.099] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:55.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:92:0) after 1m0s
[20:00:55.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:92:0)
[20:00:55.122] Timer tick, asking Buildbucket for the build status
[20:00:55.340] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:55.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:93:0) after 1m0s
[20:01:55.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:93:0)
[20:01:55.393] Timer tick, asking Buildbucket for the build status
[20:01:55.541] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:55.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:94:0) after 1m0s
[20:02:55.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:94:0)
[20:02:55.565] Timer tick, asking Buildbucket for the build status
[20:02:56.441] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:56.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:95:0) after 1m0s
[20:03:56.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:95:0)
[20:03:56.564] Timer tick, asking Buildbucket for the build status
[20:03:56.746] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:56.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:96:0) after 1m0s
[20:04:56.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:96:0)
[20:04:56.826] Timer tick, asking Buildbucket for the build status
[20:04:57.250] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:57.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:97:0) after 1m0s
[20:05:57.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:97:0)
[20:05:57.549] Timer tick, asking Buildbucket for the build status
[20:05:58.046] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:58.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:98:0) after 1m0s
[20:06:58.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:98:0)
[20:06:58.495] Timer tick, asking Buildbucket for the build status
[20:06:58.741] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:58.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:99:0) after 1m0s
[20:07:58.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:99:0)
[20:07:58.871] Timer tick, asking Buildbucket for the build status
[20:07:59.247] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:59.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:100:0) after 1m0s
[20:08:59.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:100:0)
[20:08:59.372] Timer tick, asking Buildbucket for the build status
[20:09:00.690] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:00.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:101:0) after 1m0s
[20:10:00.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:101:0)
[20:10:00.750] Timer tick, asking Buildbucket for the build status
[20:10:00.917] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:00.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:102:0) after 1m0s
[20:11:01.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:102:0)
[20:11:01.035] Timer tick, asking Buildbucket for the build status
[20:11:01.434] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:01.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:103:0) after 1m0s
[20:12:01.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:103:0)
[20:12:01.555] Timer tick, asking Buildbucket for the build status
[20:12:01.746] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:01.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:104:0) after 1m0s
[20:13:01.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:104:0)
[20:13:01.865] Timer tick, asking Buildbucket for the build status
[20:13:02.350] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:02.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:105:0) after 1m0s
[20:14:02.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:105:0)
[20:14:02.431] Timer tick, asking Buildbucket for the build status
[20:14:03.625] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:03.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:106:0) after 1m0s
[20:15:03.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:106:0)
[20:15:03.662] Timer tick, asking Buildbucket for the build status
[20:15:03.953] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:03.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:107:0) after 1m0s
[20:16:04.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:107:0)
[20:16:04.083] Timer tick, asking Buildbucket for the build status
[20:16:04.543] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:04.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:108:0) after 1m0s
[20:17:04.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:108:0)
[20:17:04.574] Timer tick, asking Buildbucket for the build status
[20:17:05.092] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:05.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:109:0) after 1m0s
[20:18:05.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:109:0)
[20:18:05.117] Timer tick, asking Buildbucket for the build status
[20:18:05.244] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:05.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:110:0) after 1m0s
[20:19:05.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:110:0)
[20:19:05.273] Timer tick, asking Buildbucket for the build status
[20:19:05.385] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:05.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:111:0) after 1m0s
[20:20:05.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:111:0)
[20:20:05.411] Timer tick, asking Buildbucket for the build status
[20:20:06.034] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:06.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:112:0) after 1m0s
[20:21:06.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:112:0)
[20:21:06.224] Timer tick, asking Buildbucket for the build status
[20:21:08.653] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:113:0) after 1m0s
[20:22:08.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:113:0)
[20:22:08.734] Timer tick, asking Buildbucket for the build status
[20:22:10.927] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:10.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:114:0) after 1m0s
[20:23:10.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:114:0)
[20:23:10.961] Timer tick, asking Buildbucket for the build status
[20:23:11.108] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:11.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:115:0) after 1m0s
[20:24:11.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:115:0)
[20:24:11.129] Timer tick, asking Buildbucket for the build status
[20:24:11.407] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:11.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:116:0) after 1m0s
[20:25:11.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:116:0)
[20:25:11.467] Timer tick, asking Buildbucket for the build status
[20:25:11.733] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:11.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:117:0) after 1m0s
[20:26:11.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:117:0)
[20:26:11.819] Timer tick, asking Buildbucket for the build status
[20:26:11.960] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:11.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:118:0) after 1m0s
[20:27:12.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:118:0)
[20:27:12.056] Timer tick, asking Buildbucket for the build status
[20:27:12.197] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:12.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:119:0) after 1m0s
[20:28:12.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:119:0)
[20:28:12.256] Timer tick, asking Buildbucket for the build status
[20:28:12.741] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:12.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:120:0) after 1m0s
[20:29:12.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:120:0)
[20:29:12.987] Timer tick, asking Buildbucket for the build status
[20:29:13.308] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:13.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:121:0) after 1m0s
[20:30:13.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:121:0)
[20:30:13.334] Timer tick, asking Buildbucket for the build status
[20:30:14.458] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:14.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:122:0) after 1m0s
[20:31:14.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:122:0)
[20:31:14.518] Timer tick, asking Buildbucket for the build status
[20:31:15.569] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:15.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:123:0) after 1m0s
[20:32:15.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:123:0)
[20:32:15.599] Timer tick, asking Buildbucket for the build status
[20:32:16.003] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:16.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:124:0) after 1m0s
[20:33:16.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:124:0)
[20:33:16.112] Timer tick, asking Buildbucket for the build status
[20:33:16.419] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:16.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:125:0) after 1m0s
[20:34:16.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:125:0)
[20:34:16.555] Timer tick, asking Buildbucket for the build status
[20:34:16.757] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:16.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:126:0) after 1m0s
[20:35:16.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:126:0)
[20:35:16.988] Timer tick, asking Buildbucket for the build status
[20:35:17.381] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:17.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:127:0) after 1m0s
[20:36:17.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:127:0)
[20:36:17.527] Timer tick, asking Buildbucket for the build status
[20:36:17.692] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:17.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:128:0) after 1m0s
[20:37:17.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:128:0)
[20:37:17.950] Timer tick, asking Buildbucket for the build status
[20:37:18.135] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:18.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:129:0) after 1m0s
[20:38:18.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:129:0)
[20:38:18.262] Timer tick, asking Buildbucket for the build status
[20:38:18.715] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:18.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:130:0) after 1m0s
[20:39:18.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:130:0)
[20:39:18.755] Timer tick, asking Buildbucket for the build status
[20:39:18.945] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:18.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:131:0) after 1m0s
[20:40:18.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:131:0)
[20:40:18.973] Timer tick, asking Buildbucket for the build status
[20:40:19.324] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:19.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:132:0) after 1m0s
[20:41:19.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:132:0)
[20:41:19.328] Timer tick, asking Buildbucket for the build status
[20:41:20.144] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:20.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:133:0) after 1m0s
[20:42:20.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:133:0)
[20:42:20.174] Timer tick, asking Buildbucket for the build status
[20:42:23.424] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:23.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:134:0) after 1m0s
[20:43:23.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:134:0)
[20:43:23.687] Timer tick, asking Buildbucket for the build status
[20:43:23.853] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:23.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:135:0) after 1m0s
[20:44:23.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:135:0)
[20:44:23.954] Timer tick, asking Buildbucket for the build status
[20:44:27.345] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:27.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:136:0) after 1m0s
[20:45:27.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:136:0)
[20:45:27.370] Timer tick, asking Buildbucket for the build status
[20:45:27.855] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:27.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:137:0) after 1m0s
[20:46:28.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:137:0)
[20:46:28.119] Timer tick, asking Buildbucket for the build status
[20:46:28.729] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:28.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:138:0) after 1m0s
[20:47:28.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:138:0)
[20:47:28.833] Timer tick, asking Buildbucket for the build status
[20:47:29.014] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:29.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:139:0) after 1m0s
[20:48:29.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:139:0)
[20:48:29.277] Timer tick, asking Buildbucket for the build status
[20:48:29.602] Build 8907390592614919488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:29.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9072820654890798560:140:0) after 1m0s
[20:48:31.417] Received PubSub notification, asking Buildbucket for the build status
[20:48:31.618] Build 8907390592614919488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:48:31.618] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563655710045372",
"created_by": "project:chromium",
"created_ts": "1563647387832944",
"id": "8907390592614919488",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1030d37889de0541142503cc7f7ad7fa554ba2c0\"}}",
"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-so9v\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14188, \"got_angle_revision\": \"9ec3f51d11d92c8b87da27811cc7ab577705ea3c\", \"got_dawn_revision\": \"49aae0f3bde201d10f1b17d362b0d5b8f85dceb4\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"1030d37889de0541142503cc7f7ad7fa554ba2c0\", \"got_revision_cp\": \"refs/heads/master@{#679392}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"6ee5e916e2850ae9ae916fdbf285893db6d437e0\", \"got_v8_revision_cp\": \"refs/heads/7.7.287@{#1}\", \"got_webrtc_revision\": \"1a49c1338830b222bc6b22b4e0690615bcd6fa52\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28604}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1030d37889de0541142503cc7f7ad7fa554ba2c0\"}, \"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-so9v\"], \"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\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563647389533938",
"status": "COMPLETED",
"status_changed_ts": "1563655710899878",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14188",
"builder:Windows deterministic",
"buildset:commit/git/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1030d37889de0541142503cc7f7ad7fa554ba2c0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072820654890798560",
"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/8907390592614919488/+/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:46297735309ba610",
"user_agent:luci-scheduler"
],
"updated_ts": "1563655710935981",
"url": "https://ci.chromium.org/b/8907390592614919488",
"utcnow_ts": "1563655711610953"
}
}
[20:48:31.618] Invocation finished in 2h18m45.339954331s with status SUCCEEDED