[03:10:34.274] New invocation is queued and will start shortly
[03:10:35.360] Starting the invocation (attempt 1)
[03:10:35.428] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:10:35.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:10:35.428] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082934749248265776",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e1d03be9721b0b704d56ee09680c91ff0b50a7c9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9082934749248265776",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"gitiles_ref:refs/heads/master"
]
}
[03:10:36.712] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554001835609416",
"id": "8917504687182605296",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e1d03be9721b0b704d56ee09680c91ff0b50a7c9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554001836475972",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12645",
"builder:Windows deterministic",
"buildset:commit/git/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082934749248265776",
"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/8917504687182605296/+/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": "1554001836476183",
"url": "https://ci.chromium.org/b/8917504687182605296",
"utcnow_ts": "1554001836703387"
}
}
[03:10:36.712] Task URL: https://ci.chromium.org/b/8917504687182605296
[03:10:36.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:2:0) after 1m0s
[03:10:38.593] Received PubSub notification, asking Buildbucket for the build status
[03:10:38.768] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:36.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:2:0)
[03:11:36.715] Timer tick, asking Buildbucket for the build status
[03:11:37.183] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:37.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:4:0) after 1m0s
[03:12:37.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:4:0)
[03:12:37.212] Timer tick, asking Buildbucket for the build status
[03:12:37.721] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:37.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:5:0) after 1m0s
[03:13:37.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:5:0)
[03:13:37.742] Timer tick, asking Buildbucket for the build status
[03:13:37.872] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:37.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:6:0) after 1m0s
[03:14:37.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:6:0)
[03:14:37.889] Timer tick, asking Buildbucket for the build status
[03:14:37.999] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:7:0) after 1m0s
[03:15:37.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:7:0)
[03:15:37.991] Timer tick, asking Buildbucket for the build status
[03:15:38.514] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:38.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:8:0) after 1m0s
[03:16:38.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:8:0)
[03:16:38.510] Timer tick, asking Buildbucket for the build status
[03:16:38.632] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:38.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:9:0) after 1m0s
[03:17:38.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:9:0)
[03:17:38.646] Timer tick, asking Buildbucket for the build status
[03:17:39.074] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:39.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:10:0) after 1m0s
[03:18:39.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:10:0)
[03:18:39.175] Timer tick, asking Buildbucket for the build status
[03:18:39.329] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:39.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:11:0) after 1m0s
[03:19:39.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:11:0)
[03:19:39.308] Timer tick, asking Buildbucket for the build status
[03:19:39.532] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:39.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:12:0) after 1m0s
[03:20:39.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:12:0)
[03:20:39.549] Timer tick, asking Buildbucket for the build status
[03:20:39.693] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:39.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:13:0) after 1m0s
[03:21:39.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:13:0)
[03:21:39.710] Timer tick, asking Buildbucket for the build status
[03:21:40.176] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:40.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:14:0) after 1m0s
[03:22:40.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:14:0)
[03:22:40.205] Timer tick, asking Buildbucket for the build status
[03:22:40.361] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:40.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:15:0) after 1m0s
[03:23:40.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:15:0)
[03:23:40.381] Timer tick, asking Buildbucket for the build status
[03:23:41.003] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:41.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:16:0) after 1m0s
[03:24:40.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:16:0)
[03:24:40.988] Timer tick, asking Buildbucket for the build status
[03:24:42.381] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:42.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:17:0) after 1m0s
[03:25:42.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:17:0)
[03:25:42.412] Timer tick, asking Buildbucket for the build status
[03:25:42.698] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:42.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:18:0) after 1m0s
[03:26:42.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:18:0)
[03:26:42.720] Timer tick, asking Buildbucket for the build status
[03:26:43.197] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:43.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:19:0) after 1m0s
[03:27:43.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:19:0)
[03:27:43.200] Timer tick, asking Buildbucket for the build status
[03:27:43.448] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:43.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:20:0) after 1m0s
[03:28:43.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:20:0)
[03:28:43.464] Timer tick, asking Buildbucket for the build status
[03:28:44.268] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:44.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:21:0) after 1m0s
[03:29:44.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:21:0)
[03:29:44.285] Timer tick, asking Buildbucket for the build status
[03:29:45.085] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:45.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:22:0) after 1m0s
[03:30:45.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:22:0)
[03:30:45.082] Timer tick, asking Buildbucket for the build status
[03:30:45.246] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:45.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:23:0) after 1m0s
[03:31:45.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:23:0)
[03:31:45.278] Timer tick, asking Buildbucket for the build status
[03:31:45.653] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:45.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:24:0) after 1m0s
[03:32:45.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:24:0)
[03:32:45.674] Timer tick, asking Buildbucket for the build status
[03:32:45.811] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:45.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:25:0) after 1m0s
[03:33:45.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:25:0)
[03:33:45.828] Timer tick, asking Buildbucket for the build status
[03:33:45.993] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:45.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:26:0) after 1m0s
[03:34:45.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:26:0)
[03:34:45.984] Timer tick, asking Buildbucket for the build status
[03:34:46.313] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:46.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:27:0) after 1m0s
[03:35:46.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:27:0)
[03:35:46.330] Timer tick, asking Buildbucket for the build status
[03:35:46.492] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:46.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:28:0) after 1m0s
[03:36:46.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:28:0)
[03:36:46.528] Timer tick, asking Buildbucket for the build status
[03:36:46.655] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:46.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:29:0) after 1m0s
[03:37:46.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:29:0)
[03:37:46.677] Timer tick, asking Buildbucket for the build status
[03:37:47.656] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:47.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:30:0) after 1m0s
[03:38:47.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:30:0)
[03:38:47.683] Timer tick, asking Buildbucket for the build status
[03:38:47.851] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:47.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:31:0) after 1m0s
[03:39:47.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:31:0)
[03:39:47.883] Timer tick, asking Buildbucket for the build status
[03:39:49.659] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:49.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:32:0) after 1m0s
[03:40:49.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:32:0)
[03:40:49.648] Timer tick, asking Buildbucket for the build status
[03:40:49.824] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:49.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:33:0) after 1m0s
[03:41:49.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:33:0)
[03:41:49.807] Timer tick, asking Buildbucket for the build status
[03:41:50.259] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:50.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:34:0) after 1m0s
[03:42:50.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:34:0)
[03:42:50.277] Timer tick, asking Buildbucket for the build status
[03:42:50.572] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:50.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:35:0) after 1m0s
[03:43:50.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:35:0)
[03:43:50.592] Timer tick, asking Buildbucket for the build status
[03:43:51.657] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:51.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:36:0) after 1m0s
[03:44:51.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:36:0)
[03:44:51.675] Timer tick, asking Buildbucket for the build status
[03:44:52.695] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:52.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:37:0) after 1m0s
[03:45:52.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:37:0)
[03:45:52.727] Timer tick, asking Buildbucket for the build status
[03:45:53.409] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:53.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:38:0) after 1m0s
[03:46:53.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:38:0)
[03:46:53.394] Timer tick, asking Buildbucket for the build status
[03:46:53.608] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:39:0) after 1m0s
[03:47:53.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:39:0)
[03:47:53.644] Timer tick, asking Buildbucket for the build status
[03:47:53.804] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:40:0) after 1m0s
[03:48:53.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:40:0)
[03:48:53.823] Timer tick, asking Buildbucket for the build status
[03:48:54.410] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:54.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:41:0) after 1m0s
[03:49:54.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:41:0)
[03:49:54.485] Timer tick, asking Buildbucket for the build status
[03:49:54.602] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:54.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:42:0) after 1m0s
[03:50:54.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:42:0)
[03:50:54.579] Timer tick, asking Buildbucket for the build status
[03:50:54.993] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:54.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:43:0) after 1m0s
[03:51:55.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:43:0)
[03:51:55.021] Timer tick, asking Buildbucket for the build status
[03:51:55.183] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:55.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:44:0) after 1m0s
[03:52:55.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:44:0)
[03:52:55.200] Timer tick, asking Buildbucket for the build status
[03:52:55.589] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:55.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:45:0) after 1m0s
[03:53:55.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:45:0)
[03:53:55.626] Timer tick, asking Buildbucket for the build status
[03:53:56.050] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:56.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:46:0) after 1m0s
[03:54:56.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:46:0)
[03:54:56.068] Timer tick, asking Buildbucket for the build status
[03:54:56.257] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:56.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:47:0) after 1m0s
[03:55:56.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:47:0)
[03:55:56.275] Timer tick, asking Buildbucket for the build status
[03:55:56.786] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:56.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:48:0) after 1m0s
[03:56:56.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:48:0)
[03:56:56.778] Timer tick, asking Buildbucket for the build status
[03:56:57.093] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:49:0) after 1m0s
[03:57:57.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:49:0)
[03:57:57.113] Timer tick, asking Buildbucket for the build status
[03:57:57.228] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:57.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:50:0) after 1m0s
[03:58:57.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:50:0)
[03:58:57.248] Timer tick, asking Buildbucket for the build status
[03:58:57.810] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:57.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:51:0) after 1m0s
[03:59:57.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:51:0)
[03:59:57.835] Timer tick, asking Buildbucket for the build status
[03:59:58.297] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:58.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:52:0) after 1m0s
[04:00:58.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:52:0)
[04:00:58.318] Timer tick, asking Buildbucket for the build status
[04:00:58.714] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:58.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:53:0) after 1m0s
[04:01:58.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:53:0)
[04:01:58.746] Timer tick, asking Buildbucket for the build status
[04:01:58.858] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:58.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:54:0) after 1m0s
[04:02:58.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:54:0)
[04:02:58.879] Timer tick, asking Buildbucket for the build status
[04:02:59.069] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:59.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:55:0) after 1m0s
[04:03:59.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:55:0)
[04:03:59.090] Timer tick, asking Buildbucket for the build status
[04:04:00.196] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:00.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:56:0) after 1m0s
[04:05:00.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:56:0)
[04:05:00.211] Timer tick, asking Buildbucket for the build status
[04:05:00.393] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:00.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:57:0) after 1m0s
[04:06:00.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:57:0)
[04:06:00.428] Timer tick, asking Buildbucket for the build status
[04:06:00.882] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:00.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:58:0) after 1m0s
[04:07:00.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:58:0)
[04:07:00.891] Timer tick, asking Buildbucket for the build status
[04:07:01.021] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:01.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:59:0) after 1m0s
[04:08:01.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:59:0)
[04:08:01.089] Timer tick, asking Buildbucket for the build status
[04:08:01.519] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:01.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:60:0) after 1m0s
[04:09:01.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:60:0)
[04:09:01.538] Timer tick, asking Buildbucket for the build status
[04:09:01.900] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:01.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:61:0) after 1m0s
[04:10:01.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:61:0)
[04:10:01.979] Timer tick, asking Buildbucket for the build status
[04:10:02.510] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:02.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:62:0) after 1m0s
[04:11:02.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:62:0)
[04:11:02.503] Timer tick, asking Buildbucket for the build status
[04:11:02.796] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:02.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:63:0) after 1m0s
[04:12:02.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:63:0)
[04:12:02.782] Timer tick, asking Buildbucket for the build status
[04:12:02.890] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:02.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:64:0) after 1m0s
[04:13:02.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:64:0)
[04:13:02.924] Timer tick, asking Buildbucket for the build status
[04:13:03.526] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:03.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:65:0) after 1m0s
[04:14:03.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:65:0)
[04:14:03.551] Timer tick, asking Buildbucket for the build status
[04:14:03.662] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:03.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:66:0) after 1m0s
[04:15:03.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:66:0)
[04:15:03.680] Timer tick, asking Buildbucket for the build status
[04:15:04.224] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:04.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:67:0) after 1m0s
[04:16:04.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:67:0)
[04:16:04.248] Timer tick, asking Buildbucket for the build status
[04:16:04.396] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:04.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:68:0) after 1m0s
[04:17:04.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:68:0)
[04:17:04.430] Timer tick, asking Buildbucket for the build status
[04:17:05.032] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:05.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:69:0) after 1m0s
[04:18:05.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:69:0)
[04:18:05.108] Timer tick, asking Buildbucket for the build status
[04:18:06.205] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:06.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:70:0) after 1m0s
[04:19:06.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:70:0)
[04:19:06.177] Timer tick, asking Buildbucket for the build status
[04:19:06.271] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:06.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:71:0) after 1m0s
[04:20:06.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:71:0)
[04:20:06.296] Timer tick, asking Buildbucket for the build status
[04:20:06.390] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:06.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:72:0) after 1m0s
[04:21:06.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:72:0)
[04:21:06.372] Timer tick, asking Buildbucket for the build status
[04:21:06.691] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:06.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:73:0) after 1m0s
[04:22:06.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:73:0)
[04:22:06.723] Timer tick, asking Buildbucket for the build status
[04:22:07.512] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:74:0) after 1m0s
[04:23:07.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:74:0)
[04:23:07.524] Timer tick, asking Buildbucket for the build status
[04:23:07.660] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:07.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:75:0) after 1m0s
[04:24:07.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:75:0)
[04:24:07.638] Timer tick, asking Buildbucket for the build status
[04:24:07.770] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:07.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:76:0) after 1m0s
[04:25:07.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:76:0)
[04:25:07.791] Timer tick, asking Buildbucket for the build status
[04:25:07.996] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:07.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:77:0) after 1m0s
[04:26:08.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:77:0)
[04:26:08.023] Timer tick, asking Buildbucket for the build status
[04:26:08.182] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:08.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:78:0) after 1m0s
[04:27:08.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:78:0)
[04:27:08.175] Timer tick, asking Buildbucket for the build status
[04:27:08.386] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:08.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:79:0) after 1m0s
[04:28:08.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:79:0)
[04:28:08.403] Timer tick, asking Buildbucket for the build status
[04:28:08.517] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:08.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:80:0) after 1m0s
[04:29:08.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:80:0)
[04:29:08.536] Timer tick, asking Buildbucket for the build status
[04:29:09.087] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:09.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:81:0) after 1m0s
[04:30:09.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:81:0)
[04:30:09.106] Timer tick, asking Buildbucket for the build status
[04:30:09.276] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:09.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:82:0) after 1m0s
[04:31:09.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:82:0)
[04:31:09.272] Timer tick, asking Buildbucket for the build status
[04:31:11.485] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:11.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:83:0) after 1m0s
[04:32:11.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:83:0)
[04:32:11.509] Timer tick, asking Buildbucket for the build status
[04:32:12.118] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:12.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:84:0) after 1m0s
[04:33:12.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:84:0)
[04:33:12.144] Timer tick, asking Buildbucket for the build status
[04:33:12.247] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:12.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:85:0) after 1m0s
[04:34:12.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:85:0)
[04:34:12.265] Timer tick, asking Buildbucket for the build status
[04:34:12.375] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:12.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:86:0) after 1m0s
[04:35:12.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:86:0)
[04:35:12.394] Timer tick, asking Buildbucket for the build status
[04:35:12.876] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:12.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:87:0) after 1m0s
[04:36:12.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:87:0)
[04:36:12.986] Timer tick, asking Buildbucket for the build status
[04:36:13.143] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:13.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:88:0) after 1m0s
[04:37:13.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:88:0)
[04:37:13.139] Timer tick, asking Buildbucket for the build status
[04:37:13.264] Build 8917504687182605296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:13.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082934749248265776:89:0) after 1m0s
[04:37:18.445] Received PubSub notification, asking Buildbucket for the build status
[04:37:18.691] Build 8917504687182605296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:37:18.691] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1554007037774361",
"created_by": "project:chromium",
"created_ts": "1554001835609416",
"id": "8917504687182605296",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e1d03be9721b0b704d56ee09680c91ff0b50a7c9\"}}",
"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\": 12645, \"got_angle_revision\": \"3115a0fc17fa8fb5af2490c58946de708dc17a17\", \"got_dawn_revision\": \"10c24684ab9cd1c747e251d7cb4590c43eecad6b\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"e1d03be9721b0b704d56ee09680c91ff0b50a7c9\", \"got_revision_cp\": \"refs/heads/master@{#646122}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"3003370ea28adc128d88e51651567154e94e7f06\", \"got_v8_revision_cp\": \"refs/heads/7.5.148@{#1}\", \"got_webrtc_revision\": \"0c7a9cae28edcd574177abd40f81c57857e284e3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27370}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e1d03be9721b0b704d56ee09680c91ff0b50a7c9\"}, \"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\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554001838079717",
"status": "COMPLETED",
"status_changed_ts": "1554007038163215",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12645",
"builder:Windows deterministic",
"buildset:commit/git/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e1d03be9721b0b704d56ee09680c91ff0b50a7c9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082934749248265776",
"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/8917504687182605296/+/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:43ea8bc33fecf610",
"user_agent:luci-scheduler"
],
"updated_ts": "1554007038172121",
"url": "https://ci.chromium.org/b/8917504687182605296",
"utcnow_ts": "1554007038683041"
}
}
[04:37:18.691] Invocation finished in 1h26m44.43500762s with status SUCCEEDED