[04:32:42.192] New invocation is queued and will start shortly
[04:32:43.507] Starting the invocation (attempt 1)
[04:32:43.539] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:32:43.539] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:32:43.539] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9088003012071222448",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9088003012071222448",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"gitiles_ref:refs/heads/master"
]
}
[04:32:45.255] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549168363628714",
"id": "8922572949898305552",
"lease_expiration_ts": "1549287164784823",
"lease_key": "224815177",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549168364952653",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11818",
"builder:Windows deterministic",
"buildset:commit/git/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088003012071222448",
"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/8922572949898305552/+/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:42ca72d3f9327110",
"user_agent:luci-scheduler"
],
"updated_ts": "1549168364952783",
"url": "https://ci.chromium.org/b/8922572949898305552",
"utcnow_ts": "1549168365243323"
}
}
[04:32:45.255] Task URL: https://ci.chromium.org/b/8922572949898305552
[04:32:45.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:2:0) after 1m0s
[04:33:45.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:2:0)
[04:33:45.274] Timer tick, asking Buildbucket for the build status
[04:33:45.356] Build 8922572949898305552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:45.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:3:0) after 1m0s
[04:34:45.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:3:0)
[04:34:45.374] Timer tick, asking Buildbucket for the build status
[04:34:45.477] Build 8922572949898305552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:45.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:4:0) after 1m0s
[04:35:08.644] Received PubSub notification, asking Buildbucket for the build status
[04:35:08.710] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:45.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:4:0)
[04:35:45.497] Timer tick, asking Buildbucket for the build status
[04:35:45.675] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:45.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:6:0) after 1m0s
[04:36:45.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:6:0)
[04:36:45.695] Timer tick, asking Buildbucket for the build status
[04:36:45.851] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:45.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:7:0) after 1m0s
[04:37:45.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:7:0)
[04:37:45.875] Timer tick, asking Buildbucket for the build status
[04:37:46.002] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:46.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:8:0) after 1m0s
[04:38:46.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:8:0)
[04:38:46.022] Timer tick, asking Buildbucket for the build status
[04:38:46.111] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:46.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:9:0) after 1m0s
[04:39:46.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:9:0)
[04:39:46.127] Timer tick, asking Buildbucket for the build status
[04:39:46.218] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:46.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:10:0) after 1m0s
[04:40:46.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:10:0)
[04:40:46.249] Timer tick, asking Buildbucket for the build status
[04:40:46.406] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:11:0) after 1m0s
[04:41:46.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:11:0)
[04:41:46.421] Timer tick, asking Buildbucket for the build status
[04:41:46.463] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:46.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:12:0) after 1m0s
[04:42:46.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:12:0)
[04:42:46.481] Timer tick, asking Buildbucket for the build status
[04:42:46.655] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:46.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:13:0) after 1m0s
[04:43:46.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:13:0)
[04:43:46.671] Timer tick, asking Buildbucket for the build status
[04:43:46.735] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:46.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:14:0) after 1m0s
[04:44:46.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:14:0)
[04:44:46.750] Timer tick, asking Buildbucket for the build status
[04:44:46.861] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:46.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:15:0) after 1m0s
[04:45:46.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:15:0)
[04:45:46.877] Timer tick, asking Buildbucket for the build status
[04:45:47.073] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:47.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:16:0) after 1m0s
[04:46:47.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:16:0)
[04:46:47.090] Timer tick, asking Buildbucket for the build status
[04:46:47.163] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:47.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:17:0) after 1m0s
[04:47:47.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:17:0)
[04:47:47.181] Timer tick, asking Buildbucket for the build status
[04:47:47.234] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:47.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:18:0) after 1m0s
[04:48:47.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:18:0)
[04:48:47.249] Timer tick, asking Buildbucket for the build status
[04:48:47.326] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:19:0) after 1m0s
[04:49:47.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:19:0)
[04:49:47.347] Timer tick, asking Buildbucket for the build status
[04:49:47.763] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:47.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:20:0) after 1m0s
[04:50:47.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:20:0)
[04:50:47.781] Timer tick, asking Buildbucket for the build status
[04:50:47.851] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:47.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:21:0) after 1m0s
[04:51:47.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:21:0)
[04:51:47.868] Timer tick, asking Buildbucket for the build status
[04:51:47.921] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:47.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:22:0) after 1m0s
[04:52:47.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:22:0)
[04:52:47.942] Timer tick, asking Buildbucket for the build status
[04:52:48.012] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:48.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:23:0) after 1m0s
[04:53:48.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:23:0)
[04:53:48.050] Timer tick, asking Buildbucket for the build status
[04:53:48.257] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:48.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:24:0) after 1m0s
[04:54:48.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:24:0)
[04:54:48.276] Timer tick, asking Buildbucket for the build status
[04:54:48.372] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:48.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:25:0) after 1m0s
[04:55:48.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:25:0)
[04:55:48.391] Timer tick, asking Buildbucket for the build status
[04:55:48.543] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:48.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:26:0) after 1m0s
[04:56:48.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:26:0)
[04:56:48.562] Timer tick, asking Buildbucket for the build status
[04:56:48.612] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:48.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:27:0) after 1m0s
[04:57:48.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:27:0)
[04:57:48.628] Timer tick, asking Buildbucket for the build status
[04:57:48.757] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:48.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:28:0) after 1m0s
[04:58:48.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:28:0)
[04:58:48.784] Timer tick, asking Buildbucket for the build status
[04:58:48.894] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:48.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:29:0) after 1m0s
[04:59:48.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:29:0)
[04:59:48.911] Timer tick, asking Buildbucket for the build status
[04:59:49.012] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:49.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:30:0) after 1m0s
[05:00:49.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:30:0)
[05:00:49.029] Timer tick, asking Buildbucket for the build status
[05:00:49.125] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:49.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:31:0) after 1m0s
[05:01:49.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:31:0)
[05:01:49.143] Timer tick, asking Buildbucket for the build status
[05:01:49.456] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:49.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:32:0) after 1m0s
[05:02:49.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:32:0)
[05:02:49.519] Timer tick, asking Buildbucket for the build status
[05:02:49.622] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:49.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:33:0) after 1m0s
[05:03:49.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:33:0)
[05:03:49.642] Timer tick, asking Buildbucket for the build status
[05:03:49.709] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:49.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:34:0) after 1m0s
[05:04:49.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:34:0)
[05:04:49.730] Timer tick, asking Buildbucket for the build status
[05:04:49.918] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:49.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:35:0) after 1m0s
[05:05:49.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:35:0)
[05:05:49.940] Timer tick, asking Buildbucket for the build status
[05:05:49.982] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:49.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:36:0) after 1m0s
[05:06:50.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:36:0)
[05:06:50.003] Timer tick, asking Buildbucket for the build status
[05:06:50.360] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:50.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:37:0) after 1m0s
[05:07:50.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:37:0)
[05:07:50.378] Timer tick, asking Buildbucket for the build status
[05:07:50.451] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:50.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:38:0) after 1m0s
[05:08:50.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:38:0)
[05:08:50.470] Timer tick, asking Buildbucket for the build status
[05:08:50.514] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:50.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:39:0) after 1m0s
[05:09:50.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:39:0)
[05:09:50.541] Timer tick, asking Buildbucket for the build status
[05:09:50.613] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:50.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:40:0) after 1m0s
[05:10:50.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:40:0)
[05:10:50.695] Timer tick, asking Buildbucket for the build status
[05:10:50.809] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:50.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:41:0) after 1m0s
[05:11:50.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:41:0)
[05:11:50.878] Timer tick, asking Buildbucket for the build status
[05:11:51.000] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:51.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:42:0) after 1m0s
[05:12:51.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:42:0)
[05:12:51.019] Timer tick, asking Buildbucket for the build status
[05:12:51.145] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:43:0) after 1m0s
[05:13:51.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:43:0)
[05:13:51.165] Timer tick, asking Buildbucket for the build status
[05:13:51.275] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:51.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:44:0) after 1m0s
[05:14:51.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:44:0)
[05:14:51.325] Timer tick, asking Buildbucket for the build status
[05:14:51.510] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:51.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:45:0) after 1m0s
[05:15:51.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:45:0)
[05:15:51.531] Timer tick, asking Buildbucket for the build status
[05:15:51.627] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:51.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:46:0) after 1m0s
[05:16:51.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:46:0)
[05:16:51.646] Timer tick, asking Buildbucket for the build status
[05:16:51.803] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:51.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:47:0) after 1m0s
[05:17:51.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:47:0)
[05:17:51.823] Timer tick, asking Buildbucket for the build status
[05:17:51.881] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:51.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:48:0) after 1m0s
[05:18:51.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:48:0)
[05:18:51.899] Timer tick, asking Buildbucket for the build status
[05:18:52.183] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:52.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:49:0) after 1m0s
[05:19:52.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:49:0)
[05:19:52.202] Timer tick, asking Buildbucket for the build status
[05:19:52.305] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:52.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:50:0) after 1m0s
[05:20:52.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:50:0)
[05:20:52.327] Timer tick, asking Buildbucket for the build status
[05:20:52.520] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:52.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:51:0) after 1m0s
[05:21:52.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:51:0)
[05:21:52.542] Timer tick, asking Buildbucket for the build status
[05:21:52.625] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:52.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:52:0) after 1m0s
[05:22:52.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:52:0)
[05:22:52.646] Timer tick, asking Buildbucket for the build status
[05:22:52.772] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:52.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:53:0) after 1m0s
[05:23:52.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:53:0)
[05:23:52.792] Timer tick, asking Buildbucket for the build status
[05:23:52.976] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:52.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:54:0) after 1m0s
[05:24:52.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:54:0)
[05:24:52.998] Timer tick, asking Buildbucket for the build status
[05:24:53.066] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:53.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:55:0) after 1m0s
[05:25:53.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:55:0)
[05:25:53.089] Timer tick, asking Buildbucket for the build status
[05:25:53.156] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:53.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:56:0) after 1m0s
[05:26:53.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:56:0)
[05:26:53.174] Timer tick, asking Buildbucket for the build status
[05:26:53.319] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:53.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:57:0) after 1m0s
[05:27:53.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:57:0)
[05:27:53.438] Timer tick, asking Buildbucket for the build status
[05:27:53.513] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:53.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:58:0) after 1m0s
[05:28:53.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:58:0)
[05:28:53.543] Timer tick, asking Buildbucket for the build status
[05:28:53.692] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:53.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:59:0) after 1m0s
[05:29:53.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:59:0)
[05:29:53.711] Timer tick, asking Buildbucket for the build status
[05:29:53.792] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:53.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:60:0) after 1m0s
[05:30:53.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:60:0)
[05:30:53.812] Timer tick, asking Buildbucket for the build status
[05:30:54.175] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:54.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:61:0) after 1m0s
[05:31:54.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:61:0)
[05:31:54.199] Timer tick, asking Buildbucket for the build status
[05:31:54.463] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:54.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:62:0) after 1m0s
[05:32:54.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:62:0)
[05:32:54.482] Timer tick, asking Buildbucket for the build status
[05:32:54.572] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:54.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:63:0) after 1m0s
[05:33:54.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:63:0)
[05:33:54.593] Timer tick, asking Buildbucket for the build status
[05:33:54.655] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:54.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:64:0) after 1m0s
[05:34:54.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:64:0)
[05:34:54.675] Timer tick, asking Buildbucket for the build status
[05:34:54.737] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:54.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:65:0) after 1m0s
[05:35:54.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:65:0)
[05:35:54.758] Timer tick, asking Buildbucket for the build status
[05:35:54.823] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:54.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:66:0) after 1m0s
[05:36:54.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:66:0)
[05:36:54.841] Timer tick, asking Buildbucket for the build status
[05:36:54.926] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:54.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:67:0) after 1m0s
[05:37:54.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:67:0)
[05:37:54.945] Timer tick, asking Buildbucket for the build status
[05:37:55.234] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:55.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:68:0) after 1m0s
[05:38:55.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:68:0)
[05:38:55.253] Timer tick, asking Buildbucket for the build status
[05:38:55.434] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:55.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:69:0) after 1m0s
[05:39:55.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:69:0)
[05:39:55.652] Timer tick, asking Buildbucket for the build status
[05:39:56.157] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:56.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:70:0) after 1m0s
[05:40:56.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:70:0)
[05:40:56.177] Timer tick, asking Buildbucket for the build status
[05:40:56.402] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:56.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:71:0) after 1m0s
[05:41:56.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:71:0)
[05:41:56.424] Timer tick, asking Buildbucket for the build status
[05:41:56.615] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:56.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:72:0) after 1m0s
[05:42:56.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:72:0)
[05:42:56.641] Timer tick, asking Buildbucket for the build status
[05:42:56.713] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:56.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:73:0) after 1m0s
[05:43:56.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:73:0)
[05:43:56.804] Timer tick, asking Buildbucket for the build status
[05:43:56.894] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:56.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:74:0) after 1m0s
[05:44:56.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:74:0)
[05:44:56.916] Timer tick, asking Buildbucket for the build status
[05:44:56.981] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:56.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:75:0) after 1m0s
[05:45:57.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:75:0)
[05:45:57.003] Timer tick, asking Buildbucket for the build status
[05:45:57.105] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:57.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:76:0) after 1m0s
[05:46:57.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:76:0)
[05:46:57.124] Timer tick, asking Buildbucket for the build status
[05:46:57.292] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:57.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:77:0) after 1m0s
[05:47:57.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:77:0)
[05:47:57.291] Timer tick, asking Buildbucket for the build status
[05:47:57.332] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:57.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:78:0) after 1m0s
[05:48:57.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:78:0)
[05:48:57.362] Timer tick, asking Buildbucket for the build status
[05:48:57.498] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:57.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:79:0) after 1m0s
[05:49:57.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:79:0)
[05:49:57.552] Timer tick, asking Buildbucket for the build status
[05:49:57.878] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:57.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:80:0) after 1m0s
[05:50:57.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:80:0)
[05:50:57.939] Timer tick, asking Buildbucket for the build status
[05:50:58.009] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:58.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:81:0) after 1m0s
[05:51:58.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:81:0)
[05:51:58.152] Timer tick, asking Buildbucket for the build status
[05:51:58.235] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:58.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:82:0) after 1m0s
[05:52:58.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:82:0)
[05:52:58.266] Timer tick, asking Buildbucket for the build status
[05:52:58.339] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:58.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:83:0) after 1m0s
[05:53:58.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:83:0)
[05:53:58.361] Timer tick, asking Buildbucket for the build status
[05:53:58.434] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:58.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:84:0) after 1m0s
[05:54:58.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:84:0)
[05:54:58.482] Timer tick, asking Buildbucket for the build status
[05:54:58.657] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:58.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:85:0) after 1m0s
[05:55:58.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:85:0)
[05:55:58.679] Timer tick, asking Buildbucket for the build status
[05:55:58.789] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:58.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:86:0) after 1m0s
[05:56:58.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:86:0)
[05:56:58.821] Timer tick, asking Buildbucket for the build status
[05:56:58.890] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:58.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:87:0) after 1m0s
[05:57:59.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:87:0)
[05:57:59.018] Timer tick, asking Buildbucket for the build status
[05:57:59.315] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:59.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:88:0) after 1m0s
[05:58:59.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:88:0)
[05:58:59.338] Timer tick, asking Buildbucket for the build status
[05:58:59.425] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:59.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:89:0) after 1m0s
[05:59:59.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:89:0)
[05:59:59.445] Timer tick, asking Buildbucket for the build status
[05:59:59.529] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:59.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:90:0) after 1m0s
[06:00:59.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:90:0)
[06:00:59.532] Timer tick, asking Buildbucket for the build status
[06:00:59.579] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:59.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:91:0) after 1m0s
[06:01:59.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:91:0)
[06:01:59.602] Timer tick, asking Buildbucket for the build status
[06:01:59.758] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:59.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:92:0) after 1m0s
[06:02:59.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:92:0)
[06:02:59.778] Timer tick, asking Buildbucket for the build status
[06:03:00.181] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:00.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:93:0) after 1m0s
[06:04:00.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:93:0)
[06:04:00.224] Timer tick, asking Buildbucket for the build status
[06:04:00.370] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:00.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:94:0) after 1m0s
[06:05:00.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:94:0)
[06:05:00.357] Timer tick, asking Buildbucket for the build status
[06:05:00.440] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:00.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:95:0) after 1m0s
[06:06:00.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:95:0)
[06:06:00.463] Timer tick, asking Buildbucket for the build status
[06:06:00.853] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:00.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:96:0) after 1m0s
[06:07:00.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:96:0)
[06:07:00.877] Timer tick, asking Buildbucket for the build status
[06:07:01.240] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:01.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:97:0) after 1m0s
[06:08:01.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:97:0)
[06:08:01.304] Timer tick, asking Buildbucket for the build status
[06:08:01.598] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:01.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:98:0) after 1m0s
[06:09:01.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:98:0)
[06:09:01.644] Timer tick, asking Buildbucket for the build status
[06:09:02.199] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:02.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:99:0) after 1m0s
[06:10:02.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:99:0)
[06:10:02.261] Timer tick, asking Buildbucket for the build status
[06:10:02.330] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:02.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:100:0) after 1m0s
[06:11:02.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:100:0)
[06:11:02.418] Timer tick, asking Buildbucket for the build status
[06:11:02.486] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:02.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:101:0) after 1m0s
[06:12:02.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:101:0)
[06:12:02.518] Timer tick, asking Buildbucket for the build status
[06:12:02.660] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:02.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:102:0) after 1m0s
[06:13:02.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:102:0)
[06:13:02.699] Timer tick, asking Buildbucket for the build status
[06:13:02.870] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:02.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:103:0) after 1m0s
[06:14:02.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:103:0)
[06:14:02.897] Timer tick, asking Buildbucket for the build status
[06:14:03.704] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:03.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:104:0) after 1m0s
[06:15:03.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:104:0)
[06:15:03.775] Timer tick, asking Buildbucket for the build status
[06:15:03.842] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:03.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:105:0) after 1m0s
[06:16:03.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:105:0)
[06:16:03.873] Timer tick, asking Buildbucket for the build status
[06:16:03.952] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:03.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:106:0) after 1m0s
[06:17:03.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:106:0)
[06:17:03.972] Timer tick, asking Buildbucket for the build status
[06:17:04.059] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:04.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:107:0) after 1m0s
[06:18:04.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:107:0)
[06:18:04.078] Timer tick, asking Buildbucket for the build status
[06:18:04.177] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:04.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:108:0) after 1m0s
[06:19:04.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:108:0)
[06:19:04.204] Timer tick, asking Buildbucket for the build status
[06:19:04.815] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:04.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:109:0) after 1m0s
[06:20:04.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:109:0)
[06:20:04.836] Timer tick, asking Buildbucket for the build status
[06:20:04.953] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:04.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:110:0) after 1m0s
[06:21:04.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:110:0)
[06:21:04.975] Timer tick, asking Buildbucket for the build status
[06:21:05.053] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:05.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:111:0) after 1m0s
[06:22:05.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:111:0)
[06:22:05.228] Timer tick, asking Buildbucket for the build status
[06:22:05.512] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:05.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:112:0) after 1m0s
[06:23:05.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:112:0)
[06:23:05.545] Timer tick, asking Buildbucket for the build status
[06:23:05.790] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:05.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:113:0) after 1m0s
[06:24:05.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:113:0)
[06:24:05.848] Timer tick, asking Buildbucket for the build status
[06:24:05.900] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:05.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:114:0) after 1m0s
[06:25:05.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:114:0)
[06:25:05.938] Timer tick, asking Buildbucket for the build status
[06:25:06.088] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:06.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:115:0) after 1m0s
[06:26:06.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:115:0)
[06:26:06.121] Timer tick, asking Buildbucket for the build status
[06:26:06.206] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:06.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:116:0) after 1m0s
[06:27:06.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:116:0)
[06:27:06.230] Timer tick, asking Buildbucket for the build status
[06:27:06.302] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:06.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:117:0) after 1m0s
[06:28:06.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:117:0)
[06:28:06.342] Timer tick, asking Buildbucket for the build status
[06:28:06.556] Build 8922572949898305552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:06.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088003012071222448:118:0) after 1m0s
[06:28:15.327] Received PubSub notification, asking Buildbucket for the build status
[06:28:15.401] Build 8922572949898305552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:28:15.401] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549175294277800",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549168363628714",
"id": "8922572949898305552",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4050-0cfc6e2\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"baa3741c9fbe88e83e6d8cbbea1ca44d53e1da24\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26516}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#628610}\", \"branch\": \"refs/heads/master\", \"revision\": \"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"a34d7766c56ae5e648c1db91edd380069f2300d6\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"52d861bd49cc29fa19ae03c248cad122d77f4703\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549168363628714, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11818\", \"builder:Windows deterministic\", \"buildset:commit/git/ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9088003012071222448\", \"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\": \"8922572949898305552\"}}, \"got_v8_revision\": \"51032543c9bbe00bd67621fc0c55eb26929dc5b0\", \"got_v8_revision_cp\": \"refs/heads/7.4.56@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11818, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf3465732e5d5363f0e44a8fac54550f62dd1d0\", \"key\": \"buildset\"}, {\"value\": \"9088003012071222448\", \"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\": 11818, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"fac723e58b3bfb6839db07130deafc9ca5897acc\"}, \"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\": \"8922572949898305552\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"ccf3465732e5d5363f0e44a8fac54550f62dd1d0\"}}, \"createTime\": \"2019-02-03T04:32:43.628714Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"6fbda1b24c1893a893b17aa219b765b9e7c801d8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549168507776363",
"status": "COMPLETED",
"status_changed_ts": "1549175294884805",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11818",
"builder:Windows deterministic",
"buildset:commit/git/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccf3465732e5d5363f0e44a8fac54550f62dd1d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088003012071222448",
"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/8922572949898305552/+/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:42ca72d3f9327110",
"user_agent:luci-scheduler"
],
"updated_ts": "1549175294889980",
"url": "https://ci.chromium.org/b/8922572949898305552",
"utcnow_ts": "1549175295391116"
}
}
[06:28:15.401] Invocation finished in 1h55m33.227599685s with status SUCCEEDED