[00:13:52.027] New invocation is queued and will start shortly
[00:13:53.314] Starting the invocation (attempt 1)
[00:13:53.351] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:13:53.351] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:13:53.351] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082855269541138624",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"eb41c03cce897fffa4509602d1d6cb7c0552b779\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9082855269541138624",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"gitiles_ref:refs/heads/master"
]
}
[00:13:54.826] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554077633527942",
"id": "8917425207304778128",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb41c03cce897fffa4509602d1d6cb7c0552b779\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554077634645288",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12658",
"builder:Windows deterministic",
"buildset:commit/git/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082855269541138624",
"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/8917425207304778128/+/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": "1554077634645400",
"url": "https://ci.chromium.org/b/8917425207304778128",
"utcnow_ts": "1554077634811402"
}
}
[00:13:54.826] Task URL: https://ci.chromium.org/b/8917425207304778128
[00:13:54.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:2:0) after 1m0s
[00:13:56.446] Received PubSub notification, asking Buildbucket for the build status
[00:13:56.600] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:54.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:2:0)
[00:14:54.846] Timer tick, asking Buildbucket for the build status
[00:14:55.272] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:55.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:4:0) after 1m0s
[00:15:55.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:4:0)
[00:15:55.308] Timer tick, asking Buildbucket for the build status
[00:15:55.615] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:55.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:5:0) after 1m0s
[00:16:55.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:5:0)
[00:16:55.642] Timer tick, asking Buildbucket for the build status
[00:16:55.781] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:55.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:6:0) after 1m0s
[00:17:55.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:6:0)
[00:17:55.842] Timer tick, asking Buildbucket for the build status
[00:17:56.529] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:7:0) after 1m0s
[00:18:56.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:7:0)
[00:18:56.500] Timer tick, asking Buildbucket for the build status
[00:18:57.278] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:57.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:8:0) after 1m0s
[00:19:57.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:8:0)
[00:19:57.268] Timer tick, asking Buildbucket for the build status
[00:19:57.610] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:57.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:9:0) after 1m0s
[00:20:57.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:9:0)
[00:20:57.614] Timer tick, asking Buildbucket for the build status
[00:20:57.952] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:57.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:10:0) after 1m0s
[00:21:57.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:10:0)
[00:21:57.969] Timer tick, asking Buildbucket for the build status
[00:21:59.345] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:59.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:11:0) after 1m0s
[00:22:59.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:11:0)
[00:22:59.363] Timer tick, asking Buildbucket for the build status
[00:23:00.602] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:00.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:12:0) after 1m0s
[00:24:00.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:12:0)
[00:24:00.621] Timer tick, asking Buildbucket for the build status
[00:24:01.601] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:01.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:13:0) after 1m0s
[00:25:01.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:13:0)
[00:25:01.619] Timer tick, asking Buildbucket for the build status
[00:25:02.178] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:02.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:14:0) after 1m0s
[00:26:02.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:14:0)
[00:26:02.194] Timer tick, asking Buildbucket for the build status
[00:26:02.311] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:02.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:15:0) after 1m0s
[00:27:02.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:15:0)
[00:27:02.330] Timer tick, asking Buildbucket for the build status
[00:27:03.329] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:03.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:16:0) after 1m0s
[00:28:03.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:16:0)
[00:28:03.336] Timer tick, asking Buildbucket for the build status
[00:28:03.536] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:03.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:17:0) after 1m0s
[00:29:03.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:17:0)
[00:29:03.554] Timer tick, asking Buildbucket for the build status
[00:29:04.085] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:04.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:18:0) after 1m0s
[00:30:04.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:18:0)
[00:30:04.079] Timer tick, asking Buildbucket for the build status
[00:30:04.706] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:04.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:19:0) after 1m0s
[00:31:04.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:19:0)
[00:31:04.695] Timer tick, asking Buildbucket for the build status
[00:31:04.951] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:04.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:20:0) after 1m0s
[00:32:04.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:20:0)
[00:32:04.946] Timer tick, asking Buildbucket for the build status
[00:32:05.932] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:05.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:21:0) after 1m0s
[00:33:05.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:21:0)
[00:33:05.949] Timer tick, asking Buildbucket for the build status
[00:33:06.222] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:22:0) after 1m0s
[00:34:06.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:22:0)
[00:34:06.250] Timer tick, asking Buildbucket for the build status
[00:34:06.394] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:06.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:23:0) after 1m0s
[00:35:06.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:23:0)
[00:35:06.413] Timer tick, asking Buildbucket for the build status
[00:35:06.573] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:06.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:24:0) after 1m0s
[00:36:06.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:24:0)
[00:36:06.592] Timer tick, asking Buildbucket for the build status
[00:36:06.905] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:06.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:25:0) after 1m0s
[00:37:06.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:25:0)
[00:37:06.929] Timer tick, asking Buildbucket for the build status
[00:37:07.148] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:07.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:26:0) after 1m0s
[00:38:07.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:26:0)
[00:38:07.167] Timer tick, asking Buildbucket for the build status
[00:38:07.316] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:07.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:27:0) after 1m0s
[00:39:07.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:27:0)
[00:39:07.305] Timer tick, asking Buildbucket for the build status
[00:39:07.920] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:07.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:28:0) after 1m0s
[00:40:07.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:28:0)
[00:40:07.987] Timer tick, asking Buildbucket for the build status
[00:40:08.131] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:08.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:29:0) after 1m0s
[00:41:08.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:29:0)
[00:41:08.152] Timer tick, asking Buildbucket for the build status
[00:41:08.368] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:08.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:30:0) after 1m0s
[00:42:08.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:30:0)
[00:42:08.384] Timer tick, asking Buildbucket for the build status
[00:42:08.561] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:08.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:31:0) after 1m0s
[00:43:08.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:31:0)
[00:43:08.578] Timer tick, asking Buildbucket for the build status
[00:43:09.475] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:09.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:32:0) after 1m0s
[00:44:09.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:32:0)
[00:44:09.496] Timer tick, asking Buildbucket for the build status
[00:44:09.729] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:09.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:33:0) after 1m0s
[00:45:09.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:33:0)
[00:45:09.750] Timer tick, asking Buildbucket for the build status
[00:45:10.470] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:10.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:34:0) after 1m0s
[00:46:10.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:34:0)
[00:46:10.495] Timer tick, asking Buildbucket for the build status
[00:46:10.589] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:10.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:35:0) after 1m0s
[00:47:10.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:35:0)
[00:47:10.609] Timer tick, asking Buildbucket for the build status
[00:47:10.998] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:10.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:36:0) after 1m0s
[00:48:11.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:36:0)
[00:48:11.019] Timer tick, asking Buildbucket for the build status
[00:48:11.320] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:11.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:37:0) after 1m0s
[00:49:11.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:37:0)
[00:49:11.342] Timer tick, asking Buildbucket for the build status
[00:49:12.175] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:38:0) after 1m0s
[00:50:12.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:38:0)
[00:50:12.194] Timer tick, asking Buildbucket for the build status
[00:50:12.780] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:12.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:39:0) after 1m0s
[00:51:12.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:39:0)
[00:51:12.755] Timer tick, asking Buildbucket for the build status
[00:51:13.569] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:13.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:40:0) after 1m0s
[00:52:13.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:40:0)
[00:52:13.589] Timer tick, asking Buildbucket for the build status
[00:52:13.780] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:13.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:41:0) after 1m0s
[00:53:13.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:41:0)
[00:53:13.801] Timer tick, asking Buildbucket for the build status
[00:53:14.034] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:14.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:42:0) after 1m0s
[00:54:14.070] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:42:0)
[00:54:14.070] Timer tick, asking Buildbucket for the build status
[00:54:14.181] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:14.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:43:0) after 1m0s
[00:55:14.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:43:0)
[00:55:14.202] Timer tick, asking Buildbucket for the build status
[00:55:14.485] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:14.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:44:0) after 1m0s
[00:56:14.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:44:0)
[00:56:14.515] Timer tick, asking Buildbucket for the build status
[00:56:14.978] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:14.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:45:0) after 1m0s
[00:57:14.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:45:0)
[00:57:14.989] Timer tick, asking Buildbucket for the build status
[00:57:15.176] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:15.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:46:0) after 1m0s
[00:58:15.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:46:0)
[00:58:15.159] Timer tick, asking Buildbucket for the build status
[00:58:15.531] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:15.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:47:0) after 1m0s
[00:59:15.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:47:0)
[00:59:15.550] Timer tick, asking Buildbucket for the build status
[00:59:15.963] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:15.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:48:0) after 1m0s
[01:00:15.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:48:0)
[01:00:15.982] Timer tick, asking Buildbucket for the build status
[01:00:16.536] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:16.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:49:0) after 1m0s
[01:01:16.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:49:0)
[01:01:16.554] Timer tick, asking Buildbucket for the build status
[01:01:16.677] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:16.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:50:0) after 1m0s
[01:02:16.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:50:0)
[01:02:16.695] Timer tick, asking Buildbucket for the build status
[01:02:17.446] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:17.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:51:0) after 1m0s
[01:03:17.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:51:0)
[01:03:17.463] Timer tick, asking Buildbucket for the build status
[01:03:19.903] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:19.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:52:0) after 1m0s
[01:04:19.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:52:0)
[01:04:19.880] Timer tick, asking Buildbucket for the build status
[01:04:20.134] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:20.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:53:0) after 1m0s
[01:05:20.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:53:0)
[01:05:20.117] Timer tick, asking Buildbucket for the build status
[01:05:20.489] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:20.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:54:0) after 1m0s
[01:06:20.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:54:0)
[01:06:20.506] Timer tick, asking Buildbucket for the build status
[01:06:20.747] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:20.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:55:0) after 1m0s
[01:07:20.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:55:0)
[01:07:20.768] Timer tick, asking Buildbucket for the build status
[01:07:20.885] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:56:0) after 1m0s
[01:08:20.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:56:0)
[01:08:20.904] Timer tick, asking Buildbucket for the build status
[01:08:21.098] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:21.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:57:0) after 1m0s
[01:09:21.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:57:0)
[01:09:21.137] Timer tick, asking Buildbucket for the build status
[01:09:21.260] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:21.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:58:0) after 1m0s
[01:10:21.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:58:0)
[01:10:21.275] Timer tick, asking Buildbucket for the build status
[01:10:21.515] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:21.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:59:0) after 1m0s
[01:11:21.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:59:0)
[01:11:21.515] Timer tick, asking Buildbucket for the build status
[01:11:21.614] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:21.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:60:0) after 1m0s
[01:12:21.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:60:0)
[01:12:21.606] Timer tick, asking Buildbucket for the build status
[01:12:21.722] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:21.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:61:0) after 1m0s
[01:13:21.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:61:0)
[01:13:21.740] Timer tick, asking Buildbucket for the build status
[01:13:21.980] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:21.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:62:0) after 1m0s
[01:14:21.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:62:0)
[01:14:21.987] Timer tick, asking Buildbucket for the build status
[01:14:22.415] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:22.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:63:0) after 1m0s
[01:15:22.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:63:0)
[01:15:22.435] Timer tick, asking Buildbucket for the build status
[01:15:22.572] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:22.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:64:0) after 1m0s
[01:16:22.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:64:0)
[01:16:22.592] Timer tick, asking Buildbucket for the build status
[01:16:22.721] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:22.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:65:0) after 1m0s
[01:17:22.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:65:0)
[01:17:22.722] Timer tick, asking Buildbucket for the build status
[01:17:22.920] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:22.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:66:0) after 1m0s
[01:18:22.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:66:0)
[01:18:22.939] Timer tick, asking Buildbucket for the build status
[01:18:23.255] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:23.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:67:0) after 1m0s
[01:19:23.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:67:0)
[01:19:23.276] Timer tick, asking Buildbucket for the build status
[01:19:23.818] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:23.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:68:0) after 1m0s
[01:20:23.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:68:0)
[01:20:23.836] Timer tick, asking Buildbucket for the build status
[01:20:24.091] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:24.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:69:0) after 1m0s
[01:21:24.111] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:69:0)
[01:21:24.111] Timer tick, asking Buildbucket for the build status
[01:21:24.380] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:24.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:70:0) after 1m0s
[01:22:24.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:70:0)
[01:22:24.457] Timer tick, asking Buildbucket for the build status
[01:22:24.911] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:24.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:71:0) after 1m0s
[01:23:24.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:71:0)
[01:23:24.985] Timer tick, asking Buildbucket for the build status
[01:23:25.461] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:25.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:72:0) after 1m0s
[01:24:25.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:72:0)
[01:24:25.454] Timer tick, asking Buildbucket for the build status
[01:24:25.721] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:25.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:73:0) after 1m0s
[01:25:25.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:73:0)
[01:25:25.744] Timer tick, asking Buildbucket for the build status
[01:25:26.475] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:26.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:74:0) after 1m0s
[01:26:26.464] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:74:0)
[01:26:26.464] Timer tick, asking Buildbucket for the build status
[01:26:26.587] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:26.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:75:0) after 1m0s
[01:27:26.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:75:0)
[01:27:26.623] Timer tick, asking Buildbucket for the build status
[01:27:27.312] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:27.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:76:0) after 1m0s
[01:28:27.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:76:0)
[01:28:27.329] Timer tick, asking Buildbucket for the build status
[01:28:27.658] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:27.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:77:0) after 1m0s
[01:29:27.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:77:0)
[01:29:27.679] Timer tick, asking Buildbucket for the build status
[01:29:28.081] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:28.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:78:0) after 1m0s
[01:30:28.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:78:0)
[01:30:28.105] Timer tick, asking Buildbucket for the build status
[01:30:28.409] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:28.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:79:0) after 1m0s
[01:31:28.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:79:0)
[01:31:28.429] Timer tick, asking Buildbucket for the build status
[01:31:28.550] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:28.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:80:0) after 1m0s
[01:32:28.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:80:0)
[01:32:28.570] Timer tick, asking Buildbucket for the build status
[01:32:29.325] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:29.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:81:0) after 1m0s
[01:33:29.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:81:0)
[01:33:29.343] Timer tick, asking Buildbucket for the build status
[01:33:29.687] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:29.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:82:0) after 1m0s
[01:34:29.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:82:0)
[01:34:29.787] Timer tick, asking Buildbucket for the build status
[01:34:29.973] Build 8917425207304778128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9082855269541138624:83:0) after 1m0s
[01:34:36.937] Received PubSub notification, asking Buildbucket for the build status
[01:34:37.079] Build 8917425207304778128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:34:37.079] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554082475845041",
"created_by": "project:chromium",
"created_ts": "1554077633527942",
"id": "8917425207304778128",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb41c03cce897fffa4509602d1d6cb7c0552b779\"}}",
"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\": 12658, \"got_angle_revision\": \"3115a0fc17fa8fb5af2490c58946de708dc17a17\", \"got_dawn_revision\": \"10c24684ab9cd1c747e251d7cb4590c43eecad6b\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"eb41c03cce897fffa4509602d1d6cb7c0552b779\", \"got_revision_cp\": \"refs/heads/master@{#646162}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"804cfc5fb2ab8c49facc7a5f486c1555c2cbad63\", \"got_v8_revision_cp\": \"refs/heads/7.5.149@{#1}\", \"got_webrtc_revision\": \"708eccc1bd2d0163e35df9605649e6cadd68f589\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27374}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"eb41c03cce897fffa4509602d1d6cb7c0552b779\"}, \"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": "1554077635797338",
"status": "COMPLETED",
"status_changed_ts": "1554082476465974",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12658",
"builder:Windows deterministic",
"buildset:commit/git/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eb41c03cce897fffa4509602d1d6cb7c0552b779",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082855269541138624",
"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/8917425207304778128/+/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:43ef1059cdd33310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554082476480238",
"url": "https://ci.chromium.org/b/8917425207304778128",
"utcnow_ts": "1554082477062932"
}
}
[01:34:37.079] Invocation finished in 1h20m45.068103738s with status SUCCEEDED