[15:27:14.259] New invocation is queued and will start shortly
[15:27:15.383] Starting the invocation (attempt 1)
[15:27:15.425] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:27:15.426] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:27:15.426] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078358553883474688",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"237dc0cc54ebf78775589a5ee79438fff8ad9f14\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9078358553883474688",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"gitiles_ref:refs/heads/master"
]
}
[15:27:16.265] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558366035659506",
"id": "8912928491751528496",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"237dc0cc54ebf78775589a5ee79438fff8ad9f14\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558366036146721",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13463",
"builder:Windows deterministic",
"buildset:commit/git/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078358553883474688",
"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/8912928491751528496/+/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": "1558366036146840",
"url": "https://ci.chromium.org/b/8912928491751528496",
"utcnow_ts": "1558366036250888"
}
}
[15:27:16.265] Task URL: https://ci.chromium.org/b/8912928491751528496
[15:27:16.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:2:0) after 1m0s
[15:27:21.835] Received PubSub notification, asking Buildbucket for the build status
[15:27:22.575] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:16.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:2:0)
[15:28:16.311] Timer tick, asking Buildbucket for the build status
[15:28:16.939] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:16.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:4:0) after 1m0s
[15:29:17.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:4:0)
[15:29:17.002] Timer tick, asking Buildbucket for the build status
[15:29:17.429] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:5:0) after 1m0s
[15:30:17.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:5:0)
[15:30:17.498] Timer tick, asking Buildbucket for the build status
[15:30:17.691] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:17.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:6:0) after 1m0s
[15:31:17.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:6:0)
[15:31:17.722] Timer tick, asking Buildbucket for the build status
[15:31:17.966] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:17.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:7:0) after 1m0s
[15:32:18.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:7:0)
[15:32:18.049] Timer tick, asking Buildbucket for the build status
[15:32:18.302] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:18.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:8:0) after 1m0s
[15:33:18.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:8:0)
[15:33:18.327] Timer tick, asking Buildbucket for the build status
[15:33:18.503] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:18.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:9:0) after 1m0s
[15:34:18.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:9:0)
[15:34:18.526] Timer tick, asking Buildbucket for the build status
[15:34:18.944] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:18.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:10:0) after 1m0s
[15:35:18.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:10:0)
[15:35:18.966] Timer tick, asking Buildbucket for the build status
[15:35:19.711] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:19.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:11:0) after 1m0s
[15:36:19.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:11:0)
[15:36:19.744] Timer tick, asking Buildbucket for the build status
[15:36:20.053] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:20.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:12:0) after 1m0s
[15:37:20.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:12:0)
[15:37:20.148] Timer tick, asking Buildbucket for the build status
[15:37:20.915] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:20.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:13:0) after 1m0s
[15:38:20.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:13:0)
[15:38:20.925] Timer tick, asking Buildbucket for the build status
[15:38:21.274] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:21.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:14:0) after 1m0s
[15:39:21.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:14:0)
[15:39:21.289] Timer tick, asking Buildbucket for the build status
[15:39:21.529] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:21.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:15:0) after 1m0s
[15:40:21.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:15:0)
[15:40:21.708] Timer tick, asking Buildbucket for the build status
[15:40:22.234] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:22.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:16:0) after 1m0s
[15:41:22.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:16:0)
[15:41:22.344] Timer tick, asking Buildbucket for the build status
[15:41:22.549] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:22.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:17:0) after 1m0s
[15:42:22.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:17:0)
[15:42:22.635] Timer tick, asking Buildbucket for the build status
[15:42:23.135] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:23.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:18:0) after 1m0s
[15:43:23.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:18:0)
[15:43:23.156] Timer tick, asking Buildbucket for the build status
[15:43:23.871] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:23.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:19:0) after 1m0s
[15:44:23.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:19:0)
[15:44:23.924] Timer tick, asking Buildbucket for the build status
[15:44:24.089] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:24.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:20:0) after 1m0s
[15:45:24.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:20:0)
[15:45:24.120] Timer tick, asking Buildbucket for the build status
[15:45:25.943] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:25.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:21:0) after 1m0s
[15:46:25.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:21:0)
[15:46:25.963] Timer tick, asking Buildbucket for the build status
[15:46:26.561] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:26.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:22:0) after 1m0s
[15:47:26.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:22:0)
[15:47:26.692] Timer tick, asking Buildbucket for the build status
[15:47:26.848] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:26.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:23:0) after 1m0s
[15:48:26.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:23:0)
[15:48:26.997] Timer tick, asking Buildbucket for the build status
[15:48:27.692] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:27.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:24:0) after 1m0s
[15:49:27.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:24:0)
[15:49:27.710] Timer tick, asking Buildbucket for the build status
[15:49:27.997] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:27.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:25:0) after 1m0s
[15:50:28.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:25:0)
[15:50:28.041] Timer tick, asking Buildbucket for the build status
[15:50:29.216] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:29.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:26:0) after 1m0s
[15:51:29.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:26:0)
[15:51:29.206] Timer tick, asking Buildbucket for the build status
[15:51:29.472] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:29.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:27:0) after 1m0s
[15:52:29.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:27:0)
[15:52:29.538] Timer tick, asking Buildbucket for the build status
[15:52:29.906] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:29.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:28:0) after 1m0s
[15:53:30.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:28:0)
[15:53:30.005] Timer tick, asking Buildbucket for the build status
[15:53:30.477] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:30.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:29:0) after 1m0s
[15:54:30.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:29:0)
[15:54:30.506] Timer tick, asking Buildbucket for the build status
[15:54:30.769] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:30.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:30:0) after 1m0s
[15:55:30.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:30:0)
[15:55:30.880] Timer tick, asking Buildbucket for the build status
[15:55:32.450] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:32.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:31:0) after 1m0s
[15:56:32.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:31:0)
[15:56:32.472] Timer tick, asking Buildbucket for the build status
[15:56:32.588] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:32.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:32:0) after 1m0s
[15:57:32.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:32:0)
[15:57:32.617] Timer tick, asking Buildbucket for the build status
[15:57:34.053] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:34.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:33:0) after 1m0s
[15:58:34.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:33:0)
[15:58:34.099] Timer tick, asking Buildbucket for the build status
[15:58:34.213] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:34.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:34:0) after 1m0s
[15:59:34.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:34:0)
[15:59:34.258] Timer tick, asking Buildbucket for the build status
[15:59:34.396] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:34.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:35:0) after 1m0s
[16:00:34.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:35:0)
[16:00:34.430] Timer tick, asking Buildbucket for the build status
[16:00:34.690] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:34.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:36:0) after 1m0s
[16:01:34.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:36:0)
[16:01:34.679] Timer tick, asking Buildbucket for the build status
[16:01:34.761] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:37:0) after 1m0s
[16:02:34.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:37:0)
[16:02:34.830] Timer tick, asking Buildbucket for the build status
[16:02:34.986] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:34.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:38:0) after 1m0s
[16:03:35.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:38:0)
[16:03:35.013] Timer tick, asking Buildbucket for the build status
[16:03:35.190] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:35.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:39:0) after 1m0s
[16:04:35.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:39:0)
[16:04:35.206] Timer tick, asking Buildbucket for the build status
[16:04:35.628] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:35.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:40:0) after 1m0s
[16:05:35.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:40:0)
[16:05:35.648] Timer tick, asking Buildbucket for the build status
[16:05:35.822] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:35.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:41:0) after 1m0s
[16:06:35.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:41:0)
[16:06:35.814] Timer tick, asking Buildbucket for the build status
[16:06:38.491] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:38.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:42:0) after 1m0s
[16:07:38.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:42:0)
[16:07:38.510] Timer tick, asking Buildbucket for the build status
[16:07:38.835] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:38.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:43:0) after 1m0s
[16:08:38.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:43:0)
[16:08:38.877] Timer tick, asking Buildbucket for the build status
[16:08:38.981] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:38.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:44:0) after 1m0s
[16:09:39.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:44:0)
[16:09:39.005] Timer tick, asking Buildbucket for the build status
[16:09:40.302] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:40.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:45:0) after 1m0s
[16:10:40.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:45:0)
[16:10:40.322] Timer tick, asking Buildbucket for the build status
[16:10:40.876] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:40.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:46:0) after 1m0s
[16:11:40.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:46:0)
[16:11:40.894] Timer tick, asking Buildbucket for the build status
[16:11:41.119] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:47:0) after 1m0s
[16:12:41.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:47:0)
[16:12:41.186] Timer tick, asking Buildbucket for the build status
[16:12:41.834] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:41.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:48:0) after 1m0s
[16:13:42.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:48:0)
[16:13:42.076] Timer tick, asking Buildbucket for the build status
[16:13:42.228] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:42.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:49:0) after 1m0s
[16:14:42.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:49:0)
[16:14:42.218] Timer tick, asking Buildbucket for the build status
[16:14:42.323] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:42.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:50:0) after 1m0s
[16:15:42.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:50:0)
[16:15:42.394] Timer tick, asking Buildbucket for the build status
[16:15:42.641] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:42.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:51:0) after 1m0s
[16:16:42.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:51:0)
[16:16:42.668] Timer tick, asking Buildbucket for the build status
[16:16:42.870] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:42.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:52:0) after 1m0s
[16:17:42.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:52:0)
[16:17:42.895] Timer tick, asking Buildbucket for the build status
[16:17:43.393] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:43.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:53:0) after 1m0s
[16:18:43.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:53:0)
[16:18:43.380] Timer tick, asking Buildbucket for the build status
[16:18:43.902] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:43.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:54:0) after 1m0s
[16:19:43.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:54:0)
[16:19:43.911] Timer tick, asking Buildbucket for the build status
[16:19:44.103] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:44.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:55:0) after 1m0s
[16:20:44.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:55:0)
[16:20:44.099] Timer tick, asking Buildbucket for the build status
[16:20:45.565] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:45.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:56:0) after 1m0s
[16:21:45.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:56:0)
[16:21:45.563] Timer tick, asking Buildbucket for the build status
[16:21:47.290] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:47.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:57:0) after 1m0s
[16:22:47.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:57:0)
[16:22:47.319] Timer tick, asking Buildbucket for the build status
[16:22:47.750] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:47.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:58:0) after 1m0s
[16:23:47.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:58:0)
[16:23:47.796] Timer tick, asking Buildbucket for the build status
[16:23:49.895] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:49.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:59:0) after 1m0s
[16:24:49.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:59:0)
[16:24:49.910] Timer tick, asking Buildbucket for the build status
[16:24:50.428] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:50.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:60:0) after 1m0s
[16:25:50.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:60:0)
[16:25:50.445] Timer tick, asking Buildbucket for the build status
[16:25:50.589] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:50.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:61:0) after 1m0s
[16:26:50.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:61:0)
[16:26:50.695] Timer tick, asking Buildbucket for the build status
[16:26:51.147] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:51.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:62:0) after 1m0s
[16:27:51.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:62:0)
[16:27:51.135] Timer tick, asking Buildbucket for the build status
[16:27:53.341] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:53.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:63:0) after 1m0s
[16:28:53.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:63:0)
[16:28:53.373] Timer tick, asking Buildbucket for the build status
[16:28:56.957] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:56.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:64:0) after 1m0s
[16:29:56.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:64:0)
[16:29:56.973] Timer tick, asking Buildbucket for the build status
[16:29:57.562] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:57.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:65:0) after 1m0s
[16:30:57.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:65:0)
[16:30:57.618] Timer tick, asking Buildbucket for the build status
[16:30:58.662] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:58.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:66:0) after 1m0s
[16:31:58.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:66:0)
[16:31:58.707] Timer tick, asking Buildbucket for the build status
[16:32:00.301] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:00.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:67:0) after 1m0s
[16:33:00.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:67:0)
[16:33:00.313] Timer tick, asking Buildbucket for the build status
[16:33:00.889] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:00.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:68:0) after 1m0s
[16:34:00.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:68:0)
[16:34:00.911] Timer tick, asking Buildbucket for the build status
[16:34:01.359] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:01.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:69:0) after 1m0s
[16:35:01.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:69:0)
[16:35:01.369] Timer tick, asking Buildbucket for the build status
[16:35:01.582] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:01.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:70:0) after 1m0s
[16:36:01.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:70:0)
[16:36:01.602] Timer tick, asking Buildbucket for the build status
[16:36:01.722] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:01.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:71:0) after 1m0s
[16:37:01.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:71:0)
[16:37:01.766] Timer tick, asking Buildbucket for the build status
[16:37:02.101] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:02.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:72:0) after 1m0s
[16:38:02.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:72:0)
[16:38:02.125] Timer tick, asking Buildbucket for the build status
[16:38:02.520] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:02.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:73:0) after 1m0s
[16:39:02.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:73:0)
[16:39:02.533] Timer tick, asking Buildbucket for the build status
[16:39:02.692] Build 8912928491751528496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:02.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9078358553883474688:74:0) after 1m0s
[16:40:01.222] Received PubSub notification, asking Buildbucket for the build status
[16:40:01.643] Build 8912928491751528496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:40:01.643] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558370400533475",
"created_by": "project:chromium",
"created_ts": "1558366035659506",
"id": "8912928491751528496",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"237dc0cc54ebf78775589a5ee79438fff8ad9f14\"}}",
"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-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13463, \"got_angle_revision\": \"70958d15db70251fc1650ae3920acce06998038b\", \"got_dawn_revision\": \"027a8f6464c56c7294a622118d5c1f0fbf9cf58f\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"237dc0cc54ebf78775589a5ee79438fff8ad9f14\", \"got_revision_cp\": \"refs/heads/master@{#661349}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"c9178292dbb05caf07f5e1cd985f90383fbba6b9\", \"got_v8_revision_cp\": \"refs/heads/7.6.203@{#1}\", \"got_webrtc_revision\": \"519d74a5fcd869478f2bd8cc3a38eca7dfb608e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27973}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"237dc0cc54ebf78775589a5ee79438fff8ad9f14\"}, \"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-7052\"], \"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\": [\"4302-e8210c4\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558366040094471",
"status": "COMPLETED",
"status_changed_ts": "1558370400819246",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13463",
"builder:Windows deterministic",
"buildset:commit/git/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/237dc0cc54ebf78775589a5ee79438fff8ad9f14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078358553883474688",
"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/8912928491751528496/+/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:44eeac2ee9018e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558370400824293",
"url": "https://ci.chromium.org/b/8912928491751528496",
"utcnow_ts": "1558370401624586"
}
}
[16:40:01.643] Invocation finished in 1h12m47.401600292s with status SUCCEEDED