[14:02:18.155] New invocation is queued and will start shortly
[14:02:19.233] Starting the invocation (attempt 1)
[14:02:19.298] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:02:19.298] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:02:19.298] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082078373205593328",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9082078373205593328",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"gitiles_ref:refs/heads/master"
]
}
[14:02:42.956] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554818539572028",
"id": "8916648311008028864",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554818551383868",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12794",
"builder:Windows deterministic",
"buildset:commit/git/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082078373205593328",
"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/8916648311008028864/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554818551384037",
"url": "https://ci.chromium.org/b/8916648311008028864",
"utcnow_ts": "1554818562945873"
}
}
[14:02:42.956] Task URL: https://ci.chromium.org/b/8916648311008028864
[14:02:42.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:2:0) after 1m0s
[14:02:50.758] Received PubSub notification, asking Buildbucket for the build status
[14:02:51.868] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:42.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:2:0)
[14:03:42.980] Timer tick, asking Buildbucket for the build status
[14:03:43.186] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:43.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:4:0) after 1m0s
[14:04:43.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:4:0)
[14:04:43.203] Timer tick, asking Buildbucket for the build status
[14:04:43.354] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:43.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:5:0) after 1m0s
[14:05:43.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:5:0)
[14:05:43.371] Timer tick, asking Buildbucket for the build status
[14:05:43.900] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:43.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:6:0) after 1m0s
[14:06:43.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:6:0)
[14:06:43.915] Timer tick, asking Buildbucket for the build status
[14:06:44.877] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:44.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:7:0) after 1m0s
[14:07:44.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:7:0)
[14:07:44.872] Timer tick, asking Buildbucket for the build status
[14:07:45.320] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:45.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:8:0) after 1m0s
[14:08:45.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:8:0)
[14:08:45.344] Timer tick, asking Buildbucket for the build status
[14:08:45.783] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:45.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:9:0) after 1m0s
[14:09:45.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:9:0)
[14:09:45.804] Timer tick, asking Buildbucket for the build status
[14:09:45.971] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:45.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:10:0) after 1m0s
[14:10:46.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:10:0)
[14:10:46.014] Timer tick, asking Buildbucket for the build status
[14:10:46.163] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:46.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:11:0) after 1m0s
[14:11:46.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:11:0)
[14:11:46.174] Timer tick, asking Buildbucket for the build status
[14:11:47.126] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:47.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:12:0) after 1m0s
[14:12:47.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:12:0)
[14:12:47.144] Timer tick, asking Buildbucket for the build status
[14:12:47.268] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:13:0) after 1m0s
[14:13:47.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:13:0)
[14:13:47.266] Timer tick, asking Buildbucket for the build status
[14:13:48.182] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:48.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:14:0) after 1m0s
[14:14:48.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:14:0)
[14:14:48.221] Timer tick, asking Buildbucket for the build status
[14:14:48.516] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:48.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:15:0) after 1m0s
[14:15:48.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:15:0)
[14:15:48.507] Timer tick, asking Buildbucket for the build status
[14:15:48.626] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:48.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:16:0) after 1m0s
[14:16:48.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:16:0)
[14:16:48.610] Timer tick, asking Buildbucket for the build status
[14:16:48.733] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:48.733] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:17:0) after 1m0s
[14:17:48.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:17:0)
[14:17:48.727] Timer tick, asking Buildbucket for the build status
[14:17:48.850] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:48.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:18:0) after 1m0s
[14:18:48.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:18:0)
[14:18:48.873] Timer tick, asking Buildbucket for the build status
[14:18:49.276] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:49.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:19:0) after 1m0s
[14:19:49.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:19:0)
[14:19:49.295] Timer tick, asking Buildbucket for the build status
[14:19:49.596] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:49.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:20:0) after 1m0s
[14:20:49.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:20:0)
[14:20:49.575] Timer tick, asking Buildbucket for the build status
[14:20:50.292] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:50.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:21:0) after 1m0s
[14:21:50.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:21:0)
[14:21:50.311] Timer tick, asking Buildbucket for the build status
[14:21:51.563] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:51.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:22:0) after 1m0s
[14:22:51.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:22:0)
[14:22:51.582] Timer tick, asking Buildbucket for the build status
[14:22:51.700] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:51.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:23:0) after 1m0s
[14:23:51.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:23:0)
[14:23:51.686] Timer tick, asking Buildbucket for the build status
[14:23:51.987] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:51.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:24:0) after 1m0s
[14:24:52.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:24:0)
[14:24:52.009] Timer tick, asking Buildbucket for the build status
[14:24:52.511] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:52.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:25:0) after 1m0s
[14:25:52.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:25:0)
[14:25:52.532] Timer tick, asking Buildbucket for the build status
[14:25:52.640] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:52.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:26:0) after 1m0s
[14:26:52.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:26:0)
[14:26:52.657] Timer tick, asking Buildbucket for the build status
[14:26:52.762] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:52.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:27:0) after 1m0s
[14:27:52.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:27:0)
[14:27:52.782] Timer tick, asking Buildbucket for the build status
[14:27:52.883] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:28:0) after 1m0s
[14:28:52.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:28:0)
[14:28:52.886] Timer tick, asking Buildbucket for the build status
[14:28:53.002] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:53.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:29:0) after 1m0s
[14:29:53.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:29:0)
[14:29:53.022] Timer tick, asking Buildbucket for the build status
[14:29:53.127] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:53.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:30:0) after 1m0s
[14:30:53.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:30:0)
[14:30:53.164] Timer tick, asking Buildbucket for the build status
[14:30:53.971] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:53.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:31:0) after 1m0s
[14:31:53.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:31:0)
[14:31:53.992] Timer tick, asking Buildbucket for the build status
[14:31:54.136] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:32:0) after 1m0s
[14:32:54.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:32:0)
[14:32:54.138] Timer tick, asking Buildbucket for the build status
[14:32:55.185] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:55.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:33:0) after 1m0s
[14:33:55.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:33:0)
[14:33:55.293] Timer tick, asking Buildbucket for the build status
[14:33:55.873] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:34:0) after 1m0s
[14:34:55.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:34:0)
[14:34:55.896] Timer tick, asking Buildbucket for the build status
[14:34:56.017] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:56.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:35:0) after 1m0s
[14:35:56.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:35:0)
[14:35:56.039] Timer tick, asking Buildbucket for the build status
[14:35:56.223] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:56.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:36:0) after 1m0s
[14:36:56.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:36:0)
[14:36:56.265] Timer tick, asking Buildbucket for the build status
[14:36:56.694] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:56.694] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:37:0) after 1m0s
[14:37:56.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:37:0)
[14:37:56.719] Timer tick, asking Buildbucket for the build status
[14:37:56.813] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:56.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:38:0) after 1m0s
[14:38:56.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:38:0)
[14:38:56.831] Timer tick, asking Buildbucket for the build status
[14:38:57.464] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:57.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:39:0) after 1m0s
[14:39:57.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:39:0)
[14:39:57.486] Timer tick, asking Buildbucket for the build status
[14:39:57.635] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:57.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:40:0) after 1m0s
[14:40:57.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:40:0)
[14:40:57.660] Timer tick, asking Buildbucket for the build status
[14:40:57.970] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:57.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:41:0) after 1m0s
[14:41:57.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:41:0)
[14:41:57.945] Timer tick, asking Buildbucket for the build status
[14:41:58.059] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:58.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:42:0) after 1m0s
[14:42:58.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:42:0)
[14:42:58.046] Timer tick, asking Buildbucket for the build status
[14:42:58.294] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:58.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:43:0) after 1m0s
[14:43:58.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:43:0)
[14:43:58.313] Timer tick, asking Buildbucket for the build status
[14:43:59.162] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:59.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:44:0) after 1m0s
[14:44:59.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:44:0)
[14:44:59.168] Timer tick, asking Buildbucket for the build status
[14:44:59.297] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:59.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:45:0) after 1m0s
[14:45:59.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:45:0)
[14:45:59.277] Timer tick, asking Buildbucket for the build status
[14:45:59.689] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:59.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:46:0) after 1m0s
[14:46:59.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:46:0)
[14:46:59.712] Timer tick, asking Buildbucket for the build status
[14:46:59.804] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:59.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:47:0) after 1m0s
[14:47:59.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:47:0)
[14:47:59.820] Timer tick, asking Buildbucket for the build status
[14:48:00.011] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:00.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:48:0) after 1m0s
[14:49:00.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:48:0)
[14:49:00.048] Timer tick, asking Buildbucket for the build status
[14:49:00.291] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:00.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:49:0) after 1m0s
[14:50:00.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:49:0)
[14:50:00.313] Timer tick, asking Buildbucket for the build status
[14:50:00.470] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:00.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:50:0) after 1m0s
[14:51:00.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:50:0)
[14:51:00.515] Timer tick, asking Buildbucket for the build status
[14:51:01.222] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:01.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:51:0) after 1m0s
[14:52:01.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:51:0)
[14:52:01.292] Timer tick, asking Buildbucket for the build status
[14:52:01.450] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:01.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:52:0) after 1m0s
[14:53:01.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:52:0)
[14:53:01.468] Timer tick, asking Buildbucket for the build status
[14:53:01.580] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:01.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:53:0) after 1m0s
[14:54:01.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:53:0)
[14:54:01.586] Timer tick, asking Buildbucket for the build status
[14:54:01.891] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:01.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:54:0) after 1m0s
[14:55:01.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:54:0)
[14:55:01.918] Timer tick, asking Buildbucket for the build status
[14:55:02.691] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:02.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:55:0) after 1m0s
[14:56:02.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:55:0)
[14:56:02.756] Timer tick, asking Buildbucket for the build status
[14:56:03.124] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:03.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:56:0) after 1m0s
[14:57:03.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:56:0)
[14:57:03.149] Timer tick, asking Buildbucket for the build status
[14:57:03.338] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:03.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:57:0) after 1m0s
[14:58:03.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:57:0)
[14:58:03.313] Timer tick, asking Buildbucket for the build status
[14:58:03.454] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:03.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:58:0) after 1m0s
[14:59:03.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:58:0)
[14:59:03.473] Timer tick, asking Buildbucket for the build status
[14:59:04.106] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:04.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:59:0) after 1m0s
[15:00:04.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:59:0)
[15:00:04.134] Timer tick, asking Buildbucket for the build status
[15:00:08.732] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:08.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:60:0) after 1m0s
[15:01:08.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:60:0)
[15:01:08.759] Timer tick, asking Buildbucket for the build status
[15:01:09.031] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:61:0) after 1m0s
[15:02:09.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:61:0)
[15:02:09.053] Timer tick, asking Buildbucket for the build status
[15:02:09.261] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:09.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:62:0) after 1m0s
[15:03:09.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:62:0)
[15:03:09.253] Timer tick, asking Buildbucket for the build status
[15:03:09.443] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:09.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:63:0) after 1m0s
[15:04:09.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:63:0)
[15:04:09.457] Timer tick, asking Buildbucket for the build status
[15:04:09.737] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:09.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:64:0) after 1m0s
[15:05:09.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:64:0)
[15:05:09.810] Timer tick, asking Buildbucket for the build status
[15:05:10.171] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:10.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:65:0) after 1m0s
[15:06:10.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:65:0)
[15:06:10.147] Timer tick, asking Buildbucket for the build status
[15:06:10.339] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:10.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:66:0) after 1m0s
[15:07:10.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:66:0)
[15:07:10.351] Timer tick, asking Buildbucket for the build status
[15:07:11.087] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:11.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:67:0) after 1m0s
[15:08:11.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:67:0)
[15:08:11.183] Timer tick, asking Buildbucket for the build status
[15:08:11.584] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:11.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:68:0) after 1m0s
[15:09:11.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:68:0)
[15:09:11.604] Timer tick, asking Buildbucket for the build status
[15:09:11.888] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:11.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:69:0) after 1m0s
[15:10:11.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:69:0)
[15:10:11.886] Timer tick, asking Buildbucket for the build status
[15:10:12.378] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:12.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:70:0) after 1m0s
[15:11:12.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:70:0)
[15:11:12.396] Timer tick, asking Buildbucket for the build status
[15:11:12.614] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:12.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:71:0) after 1m0s
[15:12:12.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:71:0)
[15:12:12.596] Timer tick, asking Buildbucket for the build status
[15:12:13.110] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:13.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:72:0) after 1m0s
[15:13:13.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:72:0)
[15:13:13.152] Timer tick, asking Buildbucket for the build status
[15:13:13.257] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:13.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:73:0) after 1m0s
[15:14:13.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:73:0)
[15:14:13.297] Timer tick, asking Buildbucket for the build status
[15:14:13.479] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:13.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:74:0) after 1m0s
[15:15:13.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:74:0)
[15:15:13.499] Timer tick, asking Buildbucket for the build status
[15:15:13.582] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:13.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:75:0) after 1m0s
[15:16:13.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:75:0)
[15:16:13.590] Timer tick, asking Buildbucket for the build status
[15:16:14.014] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:14.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:76:0) after 1m0s
[15:17:14.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:76:0)
[15:17:14.045] Timer tick, asking Buildbucket for the build status
[15:17:14.303] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:14.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:77:0) after 1m0s
[15:18:14.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:77:0)
[15:18:14.300] Timer tick, asking Buildbucket for the build status
[15:18:14.995] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:14.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:78:0) after 1m0s
[15:19:14.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:78:0)
[15:19:14.979] Timer tick, asking Buildbucket for the build status
[15:19:15.690] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:15.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:79:0) after 1m0s
[15:20:15.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:79:0)
[15:20:15.716] Timer tick, asking Buildbucket for the build status
[15:20:16.176] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:16.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:80:0) after 1m0s
[15:21:16.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:80:0)
[15:21:16.229] Timer tick, asking Buildbucket for the build status
[15:21:16.824] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:16.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:81:0) after 1m0s
[15:22:16.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:81:0)
[15:22:16.802] Timer tick, asking Buildbucket for the build status
[15:22:17.080] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:17.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:82:0) after 1m0s
[15:23:17.107] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:82:0)
[15:23:17.107] Timer tick, asking Buildbucket for the build status
[15:23:17.571] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:17.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:83:0) after 1m0s
[15:24:17.596] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:83:0)
[15:24:17.596] Timer tick, asking Buildbucket for the build status
[15:24:17.739] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:17.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:84:0) after 1m0s
[15:25:17.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:84:0)
[15:25:17.762] Timer tick, asking Buildbucket for the build status
[15:25:17.943] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:17.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:85:0) after 1m0s
[15:26:17.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:85:0)
[15:26:17.956] Timer tick, asking Buildbucket for the build status
[15:26:18.074] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:18.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:86:0) after 1m0s
[15:27:18.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:86:0)
[15:27:18.146] Timer tick, asking Buildbucket for the build status
[15:27:18.768] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:18.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:87:0) after 1m0s
[15:28:18.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:87:0)
[15:28:18.791] Timer tick, asking Buildbucket for the build status
[15:28:19.401] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:19.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:88:0) after 1m0s
[15:29:19.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:88:0)
[15:29:19.390] Timer tick, asking Buildbucket for the build status
[15:29:19.575] Build 8916648311008028864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:19.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082078373205593328:89:0) after 1m0s
[15:30:09.001] Received PubSub notification, asking Buildbucket for the build status
[15:30:09.105] Build 8916648311008028864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:30:09.105] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554823808069532",
"created_by": "project:chromium",
"created_ts": "1554818539572028",
"id": "8916648311008028864",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-93fj\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 12794, \"got_angle_revision\": \"304f857d260e517fe7c01fb9fb25dc4011ec5073\", \"got_dawn_revision\": \"865cad89b916a56fefd90d4eb1ab4345819bd4ec\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad\", \"got_revision_cp\": \"refs/heads/master@{#649086}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"783343158eb1b147df7e6669f1d03c690c878e21\", \"got_v8_revision_cp\": \"refs/heads/7.5.224@{#1}\", \"got_webrtc_revision\": \"6fdbba3a0381a5b828d5c466bcacfbe9e7a44dff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27496}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-93fj\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554818570253572",
"status": "COMPLETED",
"status_changed_ts": "1554823808518892",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12794",
"builder:Windows deterministic",
"buildset:commit/git/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e8f3cf0bbe085fee0d1b468e84395aad3ebb2cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082078373205593328",
"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/8916648311008028864/+/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:441b3a039e830d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1554823808523247",
"url": "https://ci.chromium.org/b/8916648311008028864",
"utcnow_ts": "1554823809096348"
}
}
[15:30:09.105] Invocation finished in 1h27m51.012245794s with status SUCCEEDED