[08:20:54.108] New invocation is queued and will start shortly
[08:20:55.322] Starting the invocation (attempt 1)
[08:20:55.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:20:55.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:20:55.357] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085361343028437280",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3897026e3f23b958feb4b523dbea296edbdeb10e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085361343028437280",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3897026e3f23b958feb4b523dbea296edbdeb10e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3897026e3f23b958feb4b523dbea296edbdeb10e",
"gitiles_ref:refs/heads/master"
]
}
[08:20:58.172] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551687655481027",
"id": "8919931280924138528",
"lease_expiration_ts": "1551806457451707",
"lease_key": "72088518",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3897026e3f23b958feb4b523dbea296edbdeb10e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551687657869998",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12292",
"builder:Windows deterministic",
"buildset:commit/git/3897026e3f23b958feb4b523dbea296edbdeb10e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3897026e3f23b958feb4b523dbea296edbdeb10e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085361343028437280",
"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/8919931280924138528/+/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:43609c2dbd485610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551687657870374",
"url": "https://ci.chromium.org/b/8919931280924138528",
"utcnow_ts": "1551687658149296"
}
}
[08:20:58.172] Task URL: https://ci.chromium.org/b/8919931280924138528
[08:20:58.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:2:0) after 1m0s
[08:20:59.049] Received PubSub notification, asking Buildbucket for the build status
[08:20:59.156] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:58.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:2:0)
[08:21:58.150] Timer tick, asking Buildbucket for the build status
[08:21:58.222] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:58.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:4:0) after 1m0s
[08:22:58.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:4:0)
[08:22:58.208] Timer tick, asking Buildbucket for the build status
[08:22:58.348] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:58.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:5:0) after 1m0s
[08:23:58.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:5:0)
[08:23:58.315] Timer tick, asking Buildbucket for the build status
[08:23:58.379] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:58.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:6:0) after 1m0s
[08:24:58.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:6:0)
[08:24:58.397] Timer tick, asking Buildbucket for the build status
[08:24:58.439] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:58.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:7:0) after 1m0s
[08:25:58.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:7:0)
[08:25:58.432] Timer tick, asking Buildbucket for the build status
[08:25:58.539] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:58.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:8:0) after 1m0s
[08:26:58.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:8:0)
[08:26:58.525] Timer tick, asking Buildbucket for the build status
[08:26:58.592] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:58.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:9:0) after 1m0s
[08:27:58.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:9:0)
[08:27:58.603] Timer tick, asking Buildbucket for the build status
[08:27:58.998] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:58.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:10:0) after 1m0s
[08:28:58.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:10:0)
[08:28:58.978] Timer tick, asking Buildbucket for the build status
[08:28:59.063] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:59.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:11:0) after 1m0s
[08:29:59.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:11:0)
[08:29:59.154] Timer tick, asking Buildbucket for the build status
[08:29:59.215] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:59.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:12:0) after 1m0s
[08:30:59.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:12:0)
[08:30:59.202] Timer tick, asking Buildbucket for the build status
[08:30:59.397] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:59.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:13:0) after 1m0s
[08:31:59.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:13:0)
[08:31:59.414] Timer tick, asking Buildbucket for the build status
[08:31:59.523] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:59.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:14:0) after 1m0s
[08:32:59.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:14:0)
[08:32:59.529] Timer tick, asking Buildbucket for the build status
[08:32:59.605] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:59.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:15:0) after 1m0s
[08:33:59.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:15:0)
[08:33:59.631] Timer tick, asking Buildbucket for the build status
[08:33:59.712] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:16:0) after 1m0s
[08:34:59.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:16:0)
[08:34:59.686] Timer tick, asking Buildbucket for the build status
[08:34:59.866] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:59.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:17:0) after 1m0s
[08:35:59.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:17:0)
[08:35:59.861] Timer tick, asking Buildbucket for the build status
[08:35:59.904] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:59.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:18:0) after 1m0s
[08:36:59.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:18:0)
[08:36:59.884] Timer tick, asking Buildbucket for the build status
[08:37:00.054] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:00.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:19:0) after 1m0s
[08:38:00.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:19:0)
[08:38:00.131] Timer tick, asking Buildbucket for the build status
[08:38:00.265] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:00.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:20:0) after 1m0s
[08:39:00.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:20:0)
[08:39:00.309] Timer tick, asking Buildbucket for the build status
[08:39:00.397] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:00.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:21:0) after 1m0s
[08:40:00.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:21:0)
[08:40:00.403] Timer tick, asking Buildbucket for the build status
[08:40:00.501] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:00.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:22:0) after 1m0s
[08:41:00.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:22:0)
[08:41:00.519] Timer tick, asking Buildbucket for the build status
[08:41:00.690] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:00.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:23:0) after 1m0s
[08:42:00.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:23:0)
[08:42:00.702] Timer tick, asking Buildbucket for the build status
[08:42:00.759] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:00.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:24:0) after 1m0s
[08:43:00.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:24:0)
[08:43:00.783] Timer tick, asking Buildbucket for the build status
[08:43:00.885] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:00.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:25:0) after 1m0s
[08:44:00.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:25:0)
[08:44:00.912] Timer tick, asking Buildbucket for the build status
[08:44:01.082] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:01.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:26:0) after 1m0s
[08:45:01.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:26:0)
[08:45:01.101] Timer tick, asking Buildbucket for the build status
[08:45:01.181] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:01.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:27:0) after 1m0s
[08:46:01.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:27:0)
[08:46:01.211] Timer tick, asking Buildbucket for the build status
[08:46:01.280] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:01.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:28:0) after 1m0s
[08:47:01.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:28:0)
[08:47:01.298] Timer tick, asking Buildbucket for the build status
[08:47:01.346] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:01.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:29:0) after 1m0s
[08:48:01.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:29:0)
[08:48:01.368] Timer tick, asking Buildbucket for the build status
[08:48:01.487] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:01.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:30:0) after 1m0s
[08:49:01.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:30:0)
[08:49:01.460] Timer tick, asking Buildbucket for the build status
[08:49:01.549] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:01.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:31:0) after 1m0s
[08:50:01.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:31:0)
[08:50:01.571] Timer tick, asking Buildbucket for the build status
[08:50:01.625] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:01.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:32:0) after 1m0s
[08:51:01.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:32:0)
[08:51:01.645] Timer tick, asking Buildbucket for the build status
[08:51:02.000] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:02.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:33:0) after 1m0s
[08:52:02.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:33:0)
[08:52:02.029] Timer tick, asking Buildbucket for the build status
[08:52:02.115] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:02.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:34:0) after 1m0s
[08:53:02.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:34:0)
[08:53:02.138] Timer tick, asking Buildbucket for the build status
[08:53:02.218] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:02.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:35:0) after 1m0s
[08:54:02.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:35:0)
[08:54:02.239] Timer tick, asking Buildbucket for the build status
[08:54:02.323] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:02.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:36:0) after 1m0s
[08:55:02.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:36:0)
[08:55:02.363] Timer tick, asking Buildbucket for the build status
[08:55:02.476] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:02.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:37:0) after 1m0s
[08:56:02.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:37:0)
[08:56:02.524] Timer tick, asking Buildbucket for the build status
[08:56:02.653] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:02.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:38:0) after 1m0s
[08:57:02.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:38:0)
[08:57:02.639] Timer tick, asking Buildbucket for the build status
[08:57:02.778] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:02.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:39:0) after 1m0s
[08:58:02.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:39:0)
[08:58:02.782] Timer tick, asking Buildbucket for the build status
[08:58:02.826] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:02.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:40:0) after 1m0s
[08:59:02.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:40:0)
[08:59:02.849] Timer tick, asking Buildbucket for the build status
[08:59:02.988] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:02.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:41:0) after 1m0s
[09:00:03.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:41:0)
[09:00:03.008] Timer tick, asking Buildbucket for the build status
[09:00:03.171] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:03.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:42:0) after 1m0s
[09:01:03.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:42:0)
[09:01:03.192] Timer tick, asking Buildbucket for the build status
[09:01:03.405] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:03.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:43:0) after 1m0s
[09:02:03.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:43:0)
[09:02:03.436] Timer tick, asking Buildbucket for the build status
[09:02:03.566] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:03.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:44:0) after 1m0s
[09:03:03.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:44:0)
[09:03:03.600] Timer tick, asking Buildbucket for the build status
[09:03:03.729] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:03.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:45:0) after 1m0s
[09:04:03.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:45:0)
[09:04:03.728] Timer tick, asking Buildbucket for the build status
[09:04:03.784] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:46:0) after 1m0s
[09:05:03.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:46:0)
[09:05:03.811] Timer tick, asking Buildbucket for the build status
[09:05:03.875] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:03.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:47:0) after 1m0s
[09:06:03.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:47:0)
[09:06:03.872] Timer tick, asking Buildbucket for the build status
[09:06:03.941] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:03.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:48:0) after 1m0s
[09:07:03.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:48:0)
[09:07:03.963] Timer tick, asking Buildbucket for the build status
[09:07:04.021] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:04.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:49:0) after 1m0s
[09:08:03.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:49:0)
[09:08:03.996] Timer tick, asking Buildbucket for the build status
[09:08:04.062] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:50:0) after 1m0s
[09:09:04.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:50:0)
[09:09:04.089] Timer tick, asking Buildbucket for the build status
[09:09:04.149] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:04.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:51:0) after 1m0s
[09:10:04.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:51:0)
[09:10:04.154] Timer tick, asking Buildbucket for the build status
[09:10:04.283] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:04.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:52:0) after 1m0s
[09:11:04.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:52:0)
[09:11:04.313] Timer tick, asking Buildbucket for the build status
[09:11:04.388] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:04.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:53:0) after 1m0s
[09:12:04.423] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:53:0)
[09:12:04.423] Timer tick, asking Buildbucket for the build status
[09:12:04.551] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:04.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:54:0) after 1m0s
[09:13:04.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:54:0)
[09:13:04.577] Timer tick, asking Buildbucket for the build status
[09:13:04.615] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:55:0) after 1m0s
[09:14:04.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:55:0)
[09:14:04.634] Timer tick, asking Buildbucket for the build status
[09:14:04.731] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:04.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:56:0) after 1m0s
[09:15:04.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:56:0)
[09:15:04.752] Timer tick, asking Buildbucket for the build status
[09:15:04.873] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:04.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:57:0) after 1m0s
[09:16:04.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:57:0)
[09:16:04.867] Timer tick, asking Buildbucket for the build status
[09:16:04.992] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:04.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:58:0) after 1m0s
[09:17:05.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:58:0)
[09:17:05.012] Timer tick, asking Buildbucket for the build status
[09:17:05.092] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:05.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:59:0) after 1m0s
[09:18:05.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:59:0)
[09:18:05.135] Timer tick, asking Buildbucket for the build status
[09:18:05.294] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:05.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:60:0) after 1m0s
[09:19:05.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:60:0)
[09:19:05.322] Timer tick, asking Buildbucket for the build status
[09:19:05.378] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:05.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:61:0) after 1m0s
[09:20:05.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:61:0)
[09:20:05.390] Timer tick, asking Buildbucket for the build status
[09:20:05.520] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:05.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:62:0) after 1m0s
[09:21:05.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:62:0)
[09:21:05.499] Timer tick, asking Buildbucket for the build status
[09:21:05.603] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:05.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:63:0) after 1m0s
[09:22:05.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:63:0)
[09:22:05.623] Timer tick, asking Buildbucket for the build status
[09:22:05.793] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:05.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:64:0) after 1m0s
[09:23:05.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:64:0)
[09:23:05.786] Timer tick, asking Buildbucket for the build status
[09:23:05.846] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:05.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:65:0) after 1m0s
[09:24:05.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:65:0)
[09:24:05.818] Timer tick, asking Buildbucket for the build status
[09:24:05.898] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:05.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:66:0) after 1m0s
[09:25:05.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:66:0)
[09:25:05.876] Timer tick, asking Buildbucket for the build status
[09:25:06.014] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:06.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:67:0) after 1m0s
[09:26:06.080] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:67:0)
[09:26:06.080] Timer tick, asking Buildbucket for the build status
[09:26:06.282] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:06.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:68:0) after 1m0s
[09:27:06.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:68:0)
[09:27:06.302] Timer tick, asking Buildbucket for the build status
[09:27:06.356] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:06.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:69:0) after 1m0s
[09:28:06.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:69:0)
[09:28:06.388] Timer tick, asking Buildbucket for the build status
[09:28:06.608] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:06.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:70:0) after 1m0s
[09:29:06.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:70:0)
[09:29:06.672] Timer tick, asking Buildbucket for the build status
[09:29:06.785] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:06.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:71:0) after 1m0s
[09:30:06.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:71:0)
[09:30:06.815] Timer tick, asking Buildbucket for the build status
[09:30:07.018] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:07.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:72:0) after 1m0s
[09:31:07.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:72:0)
[09:31:07.111] Timer tick, asking Buildbucket for the build status
[09:31:07.167] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:07.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:73:0) after 1m0s
[09:32:07.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:73:0)
[09:32:07.175] Timer tick, asking Buildbucket for the build status
[09:32:07.273] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:07.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:74:0) after 1m0s
[09:33:07.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:74:0)
[09:33:07.296] Timer tick, asking Buildbucket for the build status
[09:33:07.407] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:07.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:75:0) after 1m0s
[09:34:07.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:75:0)
[09:34:07.383] Timer tick, asking Buildbucket for the build status
[09:34:07.436] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:07.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:76:0) after 1m0s
[09:35:07.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:76:0)
[09:35:07.426] Timer tick, asking Buildbucket for the build status
[09:35:07.470] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:07.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:77:0) after 1m0s
[09:36:07.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:77:0)
[09:36:07.493] Timer tick, asking Buildbucket for the build status
[09:36:07.579] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:07.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:78:0) after 1m0s
[09:37:07.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:78:0)
[09:37:07.632] Timer tick, asking Buildbucket for the build status
[09:37:07.697] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:07.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:79:0) after 1m0s
[09:38:07.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:79:0)
[09:38:07.700] Timer tick, asking Buildbucket for the build status
[09:38:07.877] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:07.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:80:0) after 1m0s
[09:39:07.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:80:0)
[09:39:07.898] Timer tick, asking Buildbucket for the build status
[09:39:07.944] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:07.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:81:0) after 1m0s
[09:40:07.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:81:0)
[09:40:07.932] Timer tick, asking Buildbucket for the build status
[09:40:08.014] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:08.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:82:0) after 1m0s
[09:41:07.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:82:0)
[09:41:07.999] Timer tick, asking Buildbucket for the build status
[09:41:08.263] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:08.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:83:0) after 1m0s
[09:42:08.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:83:0)
[09:42:08.254] Timer tick, asking Buildbucket for the build status
[09:42:08.331] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:08.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:84:0) after 1m0s
[09:43:08.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:84:0)
[09:43:08.318] Timer tick, asking Buildbucket for the build status
[09:43:08.357] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:08.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:85:0) after 1m0s
[09:44:08.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:85:0)
[09:44:08.342] Timer tick, asking Buildbucket for the build status
[09:44:08.586] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:08.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:86:0) after 1m0s
[09:45:08.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:86:0)
[09:45:08.639] Timer tick, asking Buildbucket for the build status
[09:45:08.760] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:08.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:87:0) after 1m0s
[09:46:08.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:87:0)
[09:46:08.825] Timer tick, asking Buildbucket for the build status
[09:46:08.969] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:08.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:88:0) after 1m0s
[09:47:09.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:88:0)
[09:47:09.007] Timer tick, asking Buildbucket for the build status
[09:47:09.080] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:09.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:89:0) after 1m0s
[09:48:09.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:89:0)
[09:48:09.107] Timer tick, asking Buildbucket for the build status
[09:48:09.166] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:09.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:90:0) after 1m0s
[09:49:09.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:90:0)
[09:49:09.188] Timer tick, asking Buildbucket for the build status
[09:49:10.214] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:10.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:91:0) after 1m0s
[09:50:10.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:91:0)
[09:50:10.254] Timer tick, asking Buildbucket for the build status
[09:50:10.310] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:10.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:92:0) after 1m0s
[09:51:10.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:92:0)
[09:51:10.331] Timer tick, asking Buildbucket for the build status
[09:51:10.484] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:10.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:93:0) after 1m0s
[09:52:10.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:93:0)
[09:52:10.511] Timer tick, asking Buildbucket for the build status
[09:52:10.582] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:10.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:94:0) after 1m0s
[09:53:10.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:94:0)
[09:53:10.584] Timer tick, asking Buildbucket for the build status
[09:53:10.663] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:10.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:95:0) after 1m0s
[09:54:10.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:95:0)
[09:54:10.687] Timer tick, asking Buildbucket for the build status
[09:54:10.755] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:10.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:96:0) after 1m0s
[09:55:10.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:96:0)
[09:55:10.770] Timer tick, asking Buildbucket for the build status
[09:55:10.969] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:10.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:97:0) after 1m0s
[09:56:10.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:97:0)
[09:56:10.952] Timer tick, asking Buildbucket for the build status
[09:56:11.023] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:11.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:98:0) after 1m0s
[09:57:11.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:98:0)
[09:57:11.023] Timer tick, asking Buildbucket for the build status
[09:57:11.075] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:99:0) after 1m0s
[09:58:11.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:99:0)
[09:58:11.086] Timer tick, asking Buildbucket for the build status
[09:58:11.162] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:11.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:100:0) after 1m0s
[09:59:11.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:100:0)
[09:59:11.193] Timer tick, asking Buildbucket for the build status
[09:59:11.409] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:101:0) after 1m0s
[10:00:11.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:101:0)
[10:00:11.429] Timer tick, asking Buildbucket for the build status
[10:00:11.708] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:11.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:102:0) after 1m0s
[10:01:11.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:102:0)
[10:01:11.793] Timer tick, asking Buildbucket for the build status
[10:01:11.855] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:11.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:103:0) after 1m0s
[10:02:11.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:103:0)
[10:02:11.897] Timer tick, asking Buildbucket for the build status
[10:02:12.096] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:12.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:104:0) after 1m0s
[10:03:12.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:104:0)
[10:03:12.087] Timer tick, asking Buildbucket for the build status
[10:03:12.141] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:12.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:105:0) after 1m0s
[10:04:12.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:105:0)
[10:04:12.144] Timer tick, asking Buildbucket for the build status
[10:04:12.271] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:12.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:106:0) after 1m0s
[10:05:12.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:106:0)
[10:05:12.294] Timer tick, asking Buildbucket for the build status
[10:05:12.345] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:12.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:107:0) after 1m0s
[10:06:12.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:107:0)
[10:06:12.324] Timer tick, asking Buildbucket for the build status
[10:06:12.398] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:12.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:108:0) after 1m0s
[10:07:12.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:108:0)
[10:07:12.405] Timer tick, asking Buildbucket for the build status
[10:07:12.514] Build 8919931280924138528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:12.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085361343028437280:109:0) after 1m0s
[10:07:24.167] Received PubSub notification, asking Buildbucket for the build status
[10:07:24.212] Build 8919931280924138528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:07:24.212] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551694042532170",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551687655481027",
"id": "8919931280924138528",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3897026e3f23b958feb4b523dbea296edbdeb10e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-c-gw0h\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision\": \"3897026e3f23b958feb4b523dbea296edbdeb10e\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"a8ce0a434e12990eaac11312998237a4249f2e44\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26938}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#637222}\", \"branch\": \"refs/heads/master\", \"revision\": \"3897026e3f23b958feb4b523dbea296edbdeb10e\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"4a42742dc6e41142e40e8e85f6a2dfb6fb774cca\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"4d153383bd3a2359a44fb0c13a65119c0087702f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551687655481027, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12292\", \"builder:Windows deterministic\", \"buildset:commit/git/3897026e3f23b958feb4b523dbea296edbdeb10e\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3897026e3f23b958feb4b523dbea296edbdeb10e\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085361343028437280\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8919931280924138528\"}}, \"got_v8_revision\": \"e21801e6fb091231337aeae4a7c4ab1b79df3c53\", \"got_v8_revision_cp\": \"refs/heads/7.4.270@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12292, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/3897026e3f23b958feb4b523dbea296edbdeb10e\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/3897026e3f23b958feb4b523dbea296edbdeb10e\", \"key\": \"buildset\"}, {\"value\": \"9085361343028437280\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 12292, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8919931280924138528\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"3897026e3f23b958feb4b523dbea296edbdeb10e\"}}, \"createTime\": \"2019-03-04T08:20:55.481027Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-c-gw0h\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551687658558561",
"status": "COMPLETED",
"status_changed_ts": "1551694043535271",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12292",
"builder:Windows deterministic",
"buildset:commit/git/3897026e3f23b958feb4b523dbea296edbdeb10e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3897026e3f23b958feb4b523dbea296edbdeb10e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085361343028437280",
"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/8919931280924138528/+/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:43609c2dbd485610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551694043549889",
"url": "https://ci.chromium.org/b/8919931280924138528",
"utcnow_ts": "1551694044201496"
}
}
[10:07:24.212] Invocation finished in 1h46m30.121808123s with status SUCCEEDED