[05:56:28.198] New invocation is queued and will start shortly
[05:56:29.647] Starting the invocation (attempt 1)
[05:56:29.693] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:56:29.693] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:56:29.693] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099322362717864848",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7a3547e6bd908f53239ddbac6549cb3bd1f115cc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099322362717864848",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"gitiles_ref:refs/heads/master"
]
}
[05:56:32.132] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538373389801195",
"id": "8933892300373846048",
"lease_expiration_ts": "1538517391808911",
"lease_key": "1984285280",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7a3547e6bd908f53239ddbac6549cb3bd1f115cc\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538373392029970",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10258",
"builder:Windows deterministic",
"buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099322362717864848",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10258",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933892300373846048",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8933892300373846048/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9099322362717864848",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4047049cb2baed10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538373392030134",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10258",
"utcnow_ts": "1538373392106593"
}
}
[05:56:32.132] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10258
[05:56:32.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:2:0) after 1m0s
[05:56:33.928] Received PubSub notification, asking Buildbucket for the build status
[05:56:34.013] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:32.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:2:0)
[05:57:32.300] Timer tick, asking Buildbucket for the build status
[05:57:32.358] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:32.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:4:0) after 1m0s
[05:58:32.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:4:0)
[05:58:32.437] Timer tick, asking Buildbucket for the build status
[05:58:32.555] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:32.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:5:0) after 1m0s
[05:59:32.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:5:0)
[05:59:32.571] Timer tick, asking Buildbucket for the build status
[05:59:32.628] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:32.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:6:0) after 1m0s
[06:00:32.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:6:0)
[06:00:32.644] Timer tick, asking Buildbucket for the build status
[06:00:32.749] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:32.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:7:0) after 1m0s
[06:01:32.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:7:0)
[06:01:32.773] Timer tick, asking Buildbucket for the build status
[06:01:32.919] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:32.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:8:0) after 1m0s
[06:02:32.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:8:0)
[06:02:32.977] Timer tick, asking Buildbucket for the build status
[06:02:33.013] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:33.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:9:0) after 1m0s
[06:03:33.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:9:0)
[06:03:33.044] Timer tick, asking Buildbucket for the build status
[06:03:33.094] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:33.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:10:0) after 1m0s
[06:04:33.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:10:0)
[06:04:33.113] Timer tick, asking Buildbucket for the build status
[06:04:33.241] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:33.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:11:0) after 1m0s
[06:05:33.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:11:0)
[06:05:33.260] Timer tick, asking Buildbucket for the build status
[06:05:33.321] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:33.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:12:0) after 1m0s
[06:06:33.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:12:0)
[06:06:33.382] Timer tick, asking Buildbucket for the build status
[06:06:33.636] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:33.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:13:0) after 1m0s
[06:07:33.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:13:0)
[06:07:33.657] Timer tick, asking Buildbucket for the build status
[06:07:33.711] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:33.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:14:0) after 1m0s
[06:08:33.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:14:0)
[06:08:33.732] Timer tick, asking Buildbucket for the build status
[06:08:33.813] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:33.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:15:0) after 1m0s
[06:09:33.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:15:0)
[06:09:33.845] Timer tick, asking Buildbucket for the build status
[06:09:33.917] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:33.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:16:0) after 1m0s
[06:10:33.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:16:0)
[06:10:33.934] Timer tick, asking Buildbucket for the build status
[06:10:34.009] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:34.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:17:0) after 1m0s
[06:11:34.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:17:0)
[06:11:34.026] Timer tick, asking Buildbucket for the build status
[06:11:34.079] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:34.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:18:0) after 1m0s
[06:12:34.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:18:0)
[06:12:34.096] Timer tick, asking Buildbucket for the build status
[06:12:34.153] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:34.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:19:0) after 1m0s
[06:13:34.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:19:0)
[06:13:34.185] Timer tick, asking Buildbucket for the build status
[06:13:34.264] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:34.264] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:20:0) after 1m0s
[06:14:34.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:20:0)
[06:14:34.300] Timer tick, asking Buildbucket for the build status
[06:14:34.500] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:34.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:21:0) after 1m0s
[06:15:34.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:21:0)
[06:15:34.519] Timer tick, asking Buildbucket for the build status
[06:15:34.579] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:34.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:22:0) after 1m0s
[06:16:34.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:22:0)
[06:16:34.600] Timer tick, asking Buildbucket for the build status
[06:16:34.670] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:34.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:23:0) after 1m0s
[06:17:34.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:23:0)
[06:17:34.688] Timer tick, asking Buildbucket for the build status
[06:17:34.722] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:34.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:24:0) after 1m0s
[06:18:34.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:24:0)
[06:18:34.780] Timer tick, asking Buildbucket for the build status
[06:18:34.829] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:34.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:25:0) after 1m0s
[06:19:34.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:25:0)
[06:19:34.915] Timer tick, asking Buildbucket for the build status
[06:19:34.971] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:34.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:26:0) after 1m0s
[06:20:34.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:26:0)
[06:20:34.989] Timer tick, asking Buildbucket for the build status
[06:20:35.056] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:35.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:27:0) after 1m0s
[06:21:35.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:27:0)
[06:21:35.224] Timer tick, asking Buildbucket for the build status
[06:21:35.299] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:35.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:28:0) after 1m0s
[06:22:35.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:28:0)
[06:22:35.382] Timer tick, asking Buildbucket for the build status
[06:22:35.448] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:35.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:29:0) after 1m0s
[06:23:35.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:29:0)
[06:23:35.470] Timer tick, asking Buildbucket for the build status
[06:23:35.510] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:35.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:30:0) after 1m0s
[06:24:35.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:30:0)
[06:24:35.532] Timer tick, asking Buildbucket for the build status
[06:24:35.738] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:35.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:31:0) after 1m0s
[06:25:35.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:31:0)
[06:25:35.757] Timer tick, asking Buildbucket for the build status
[06:25:35.804] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:35.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:32:0) after 1m0s
[06:26:35.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:32:0)
[06:26:35.828] Timer tick, asking Buildbucket for the build status
[06:26:35.878] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:35.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:33:0) after 1m0s
[06:27:35.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:33:0)
[06:27:35.898] Timer tick, asking Buildbucket for the build status
[06:27:36.188] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:36.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:34:0) after 1m0s
[06:28:36.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:34:0)
[06:28:36.208] Timer tick, asking Buildbucket for the build status
[06:28:36.247] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:36.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:35:0) after 1m0s
[06:29:36.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:35:0)
[06:29:36.267] Timer tick, asking Buildbucket for the build status
[06:29:36.329] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:36:0) after 1m0s
[06:30:36.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:36:0)
[06:30:36.348] Timer tick, asking Buildbucket for the build status
[06:30:36.483] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:36.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:37:0) after 1m0s
[06:31:36.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:37:0)
[06:31:36.506] Timer tick, asking Buildbucket for the build status
[06:31:36.565] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:36.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:38:0) after 1m0s
[06:32:36.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:38:0)
[06:32:36.583] Timer tick, asking Buildbucket for the build status
[06:32:36.763] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:36.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:39:0) after 1m0s
[06:33:36.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:39:0)
[06:33:36.830] Timer tick, asking Buildbucket for the build status
[06:33:37.132] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:37.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:40:0) after 1m0s
[06:34:37.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:40:0)
[06:34:37.156] Timer tick, asking Buildbucket for the build status
[06:34:37.235] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:37.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:41:0) after 1m0s
[06:35:37.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:41:0)
[06:35:37.253] Timer tick, asking Buildbucket for the build status
[06:35:37.488] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:37.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:42:0) after 1m0s
[06:36:37.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:42:0)
[06:36:37.504] Timer tick, asking Buildbucket for the build status
[06:36:37.574] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:37.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:43:0) after 1m0s
[06:37:37.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:43:0)
[06:37:37.595] Timer tick, asking Buildbucket for the build status
[06:37:37.647] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:37.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:44:0) after 1m0s
[06:38:37.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:44:0)
[06:38:37.668] Timer tick, asking Buildbucket for the build status
[06:38:37.744] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:37.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:45:0) after 1m0s
[06:39:37.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:45:0)
[06:39:37.763] Timer tick, asking Buildbucket for the build status
[06:39:37.834] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:37.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:46:0) after 1m0s
[06:40:37.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:46:0)
[06:40:37.867] Timer tick, asking Buildbucket for the build status
[06:40:37.927] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:37.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:47:0) after 1m0s
[06:41:37.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:47:0)
[06:41:37.946] Timer tick, asking Buildbucket for the build status
[06:41:37.999] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:48:0) after 1m0s
[06:42:38.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:48:0)
[06:42:38.021] Timer tick, asking Buildbucket for the build status
[06:42:38.066] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:38.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:49:0) after 1m0s
[06:43:38.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:49:0)
[06:43:38.228] Timer tick, asking Buildbucket for the build status
[06:43:38.346] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:38.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:50:0) after 1m0s
[06:44:38.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:50:0)
[06:44:38.415] Timer tick, asking Buildbucket for the build status
[06:44:38.503] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:38.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:51:0) after 1m0s
[06:45:38.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:51:0)
[06:45:38.534] Timer tick, asking Buildbucket for the build status
[06:45:38.601] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:38.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:52:0) after 1m0s
[06:46:38.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:52:0)
[06:46:38.733] Timer tick, asking Buildbucket for the build status
[06:46:38.771] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:38.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:53:0) after 1m0s
[06:47:38.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:53:0)
[06:47:38.790] Timer tick, asking Buildbucket for the build status
[06:47:38.840] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:38.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:54:0) after 1m0s
[06:48:38.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:54:0)
[06:48:38.929] Timer tick, asking Buildbucket for the build status
[06:48:39.007] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:39.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:55:0) after 1m0s
[06:49:39.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:55:0)
[06:49:39.025] Timer tick, asking Buildbucket for the build status
[06:49:39.077] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:39.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:56:0) after 1m0s
[06:50:39.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:56:0)
[06:50:39.108] Timer tick, asking Buildbucket for the build status
[06:50:39.169] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:39.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:57:0) after 1m0s
[06:51:39.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:57:0)
[06:51:39.302] Timer tick, asking Buildbucket for the build status
[06:51:39.441] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:39.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:58:0) after 1m0s
[06:52:39.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:58:0)
[06:52:39.458] Timer tick, asking Buildbucket for the build status
[06:52:39.519] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:39.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:59:0) after 1m0s
[06:53:39.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:59:0)
[06:53:39.554] Timer tick, asking Buildbucket for the build status
[06:53:39.612] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:60:0) after 1m0s
[06:54:39.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:60:0)
[06:54:39.636] Timer tick, asking Buildbucket for the build status
[06:54:39.703] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:61:0) after 1m0s
[06:55:39.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:61:0)
[06:55:39.855] Timer tick, asking Buildbucket for the build status
[06:55:39.939] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:62:0) after 1m0s
[06:56:39.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:62:0)
[06:56:39.980] Timer tick, asking Buildbucket for the build status
[06:56:40.041] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:40.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:63:0) after 1m0s
[06:57:40.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:63:0)
[06:57:40.067] Timer tick, asking Buildbucket for the build status
[06:57:40.105] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:40.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:64:0) after 1m0s
[06:58:40.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:64:0)
[06:58:40.913] Timer tick, asking Buildbucket for the build status
[06:58:40.962] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:40.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:65:0) after 1m0s
[06:59:41.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:65:0)
[06:59:41.916] Timer tick, asking Buildbucket for the build status
[06:59:41.981] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:41.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:66:0) after 1m0s
[07:00:42.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:66:0)
[07:00:42.122] Timer tick, asking Buildbucket for the build status
[07:00:42.200] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:42.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:67:0) after 1m0s
[07:01:42.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:67:0)
[07:01:42.707] Timer tick, asking Buildbucket for the build status
[07:01:42.905] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:42.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:68:0) after 1m0s
[07:02:43.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:68:0)
[07:02:43.214] Timer tick, asking Buildbucket for the build status
[07:02:43.267] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:69:0) after 1m0s
[07:03:43.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:69:0)
[07:03:43.299] Timer tick, asking Buildbucket for the build status
[07:03:43.403] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:43.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:70:0) after 1m0s
[07:04:44.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:70:0)
[07:04:44.628] Timer tick, asking Buildbucket for the build status
[07:04:44.713] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:44.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:71:0) after 1m0s
[07:05:45.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:71:0)
[07:05:45.912] Timer tick, asking Buildbucket for the build status
[07:05:46.041] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:46.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:72:0) after 1m0s
[07:06:47.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:72:0)
[07:06:47.333] Timer tick, asking Buildbucket for the build status
[07:06:47.410] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:47.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:73:0) after 1m0s
[07:07:48.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:73:0)
[07:07:48.714] Timer tick, asking Buildbucket for the build status
[07:07:48.755] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:48.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:74:0) after 1m0s
[07:08:50.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:74:0)
[07:08:50.089] Timer tick, asking Buildbucket for the build status
[07:08:50.158] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:50.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:75:0) after 1m0s
[07:09:52.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:75:0)
[07:09:52.017] Timer tick, asking Buildbucket for the build status
[07:09:52.053] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:52.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:76:0) after 1m0s
[07:10:53.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:76:0)
[07:10:53.633] Timer tick, asking Buildbucket for the build status
[07:10:53.791] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:53.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:77:0) after 1m0s
[07:11:54.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:77:0)
[07:11:54.728] Timer tick, asking Buildbucket for the build status
[07:11:54.808] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:54.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:78:0) after 1m0s
[07:12:55.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:78:0)
[07:12:55.781] Timer tick, asking Buildbucket for the build status
[07:12:55.879] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:55.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:79:0) after 1m0s
[07:13:56.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:79:0)
[07:13:56.796] Timer tick, asking Buildbucket for the build status
[07:13:56.864] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:80:0) after 1m0s
[07:14:56.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:80:0)
[07:14:56.974] Timer tick, asking Buildbucket for the build status
[07:14:57.087] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:57.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:81:0) after 1m0s
[07:15:58.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:81:0)
[07:15:58.664] Timer tick, asking Buildbucket for the build status
[07:15:58.738] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:58.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:82:0) after 1m0s
[07:17:00.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:82:0)
[07:17:00.514] Timer tick, asking Buildbucket for the build status
[07:17:00.578] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:00.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:83:0) after 1m0s
[07:18:00.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:83:0)
[07:18:00.598] Timer tick, asking Buildbucket for the build status
[07:18:00.811] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:00.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:84:0) after 1m0s
[07:19:00.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:84:0)
[07:19:00.834] Timer tick, asking Buildbucket for the build status
[07:19:00.969] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:00.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:85:0) after 1m0s
[07:20:01.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:85:0)
[07:20:01.032] Timer tick, asking Buildbucket for the build status
[07:20:01.241] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:01.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:86:0) after 1m0s
[07:21:01.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:86:0)
[07:21:01.873] Timer tick, asking Buildbucket for the build status
[07:21:02.013] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:02.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:87:0) after 1m0s
[07:22:02.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:87:0)
[07:22:02.036] Timer tick, asking Buildbucket for the build status
[07:22:02.104] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:02.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:88:0) after 1m0s
[07:23:02.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:88:0)
[07:23:02.139] Timer tick, asking Buildbucket for the build status
[07:23:02.482] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:02.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:89:0) after 1m0s
[07:24:02.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:89:0)
[07:24:02.951] Timer tick, asking Buildbucket for the build status
[07:24:03.021] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:03.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:90:0) after 1m0s
[07:25:03.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:90:0)
[07:25:03.042] Timer tick, asking Buildbucket for the build status
[07:25:03.088] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:03.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:91:0) after 1m0s
[07:26:03.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:91:0)
[07:26:03.534] Timer tick, asking Buildbucket for the build status
[07:26:04.026] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:92:0) after 1m0s
[07:27:05.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:92:0)
[07:27:05.354] Timer tick, asking Buildbucket for the build status
[07:27:05.418] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:05.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:93:0) after 1m0s
[07:28:07.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:93:0)
[07:28:07.113] Timer tick, asking Buildbucket for the build status
[07:28:07.302] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:07.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:94:0) after 1m0s
[07:29:07.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:94:0)
[07:29:07.953] Timer tick, asking Buildbucket for the build status
[07:29:08.000] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:08.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:95:0) after 1m0s
[07:30:11.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:95:0)
[07:30:11.050] Timer tick, asking Buildbucket for the build status
[07:30:11.116] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:11.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:96:0) after 1m0s
[07:31:11.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:96:0)
[07:31:11.307] Timer tick, asking Buildbucket for the build status
[07:31:11.406] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:11.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:97:0) after 1m0s
[07:32:12.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:97:0)
[07:32:12.278] Timer tick, asking Buildbucket for the build status
[07:32:12.423] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:12.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:98:0) after 1m0s
[07:33:13.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:98:0)
[07:33:13.708] Timer tick, asking Buildbucket for the build status
[07:33:13.806] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:13.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:99:0) after 1m0s
[07:34:16.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:99:0)
[07:34:16.083] Timer tick, asking Buildbucket for the build status
[07:34:16.150] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:16.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:100:0) after 1m0s
[07:35:16.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:100:0)
[07:35:16.824] Timer tick, asking Buildbucket for the build status
[07:35:16.992] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:16.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:101:0) after 1m0s
[07:36:17.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:101:0)
[07:36:17.015] Timer tick, asking Buildbucket for the build status
[07:36:17.110] Build 8933892300373846048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:17.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099322362717864848:102:0) after 1m0s
[07:36:30.719] Received PubSub notification, asking Buildbucket for the build status
[07:36:30.759] Build 8933892300373846048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:36:30.759] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538379389377592",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538373389801195",
"id": "8933892300373846048",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7a3547e6bd908f53239ddbac6549cb3bd1f115cc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8933892300373846048/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3793-c7dcd56\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"7a3547e6bd908f53239ddbac6549cb3bd1f115cc\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24875}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#595387}\", \"branch\": \"refs/heads/master\", \"revision\": \"7a3547e6bd908f53239ddbac6549cb3bd1f115cc\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"1ee9160a2e0bc6381caca2b8c42f7ce5507619bc\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"79207e6e11df06880ff0a9071479b2903d46d1b1\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538373389801195, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099322362717864848\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8933892300373846048\"}}, \"got_v8_revision\": \"ae37766fa5f1a1c4b57e2905f28664b5f1ae67d6\", \"got_v8_revision_cp\": \"refs/heads/7.1.216@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10258, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"0f57b9688d8eeccb90bd21b79d4b84ab18b77fd0\", \"notification_helper_unittests\": \"b45040c6aa4528570570e77289ce1daaaabf448c\", \"gpu_perftests\": \"5d78d57f4884ef739ce81c1a323f9e3ca5a187fb\", \"vr_common_unittests\": \"7e2526b8b904d1d4d0aece54e45ca1c724bba4fe\", \"telemetry_unittests\": \"14e6dab89b0b6c1194ca2bf8c0dc1661b2e6518e\", \"load_library_perf_tests\": \"feeaeaa48901fa56a186430f07250fb359c52ac8\", \"devtools_closure_compile\": \"47b1e90c378ab4480e13476081e61e888c71bb2f\", \"chromedriver_py_tests\": \"49f9a2abc2c8846d4426a073f411ae1cd67adaaa\", \"mojo_core_unittests\": \"ff4e2b7d10b4ce2fc80ff024e1a59df658619e48\", \"install_static_unittests\": \"5a5689f108e9013d492a9bd4238062bf359d4299\", \"leveldb_service_unittests\": \"a6efddbc8eb1ddc7c5303586d637e6c7d25f18a8\", \"mini_installer_tests\": \"d9f0b5ee62b182d65c60f872676589e7f58d4776\", \"jingle_unittests\": \"e4eb1b7f30f9b4ab814edb9e279d8f453dae5b19\", \"sbox_integration_tests\": \"74115a2b190de5200a1fab348dc3952a262699ce\", \"angle_unittests\": \"7b585b4d4d1e33540dc926a09ac683521a5fe6a2\", \"cast_unittests\": \"f068277ea2f31f16ba9a248bc53306407b8542d2\", \"cc_unittests\": \"9099672885d619de9ef6d2daa3306f4374d3bfcf\", \"blink_common_unittests\": \"bfb426c7d495ef1a12d84390469532b424fdde4d\", \"crashpad_tests\": \"3817c8acc79062efe73e2a0cef85d54f93786f24\", \"telemetry_perf_tests_without_chrome\": \"22821a666769262cd9177af97c5c7c93bc627b75\", \"metrics_python_tests\": \"f95b0e49247ef3a6ca3e61b77ec9e90ea94bdf49\", \"message_center_unittests\": \"324e6a7a90b7b1531a530b49528723989a945e55\", \"ppapi_unittests\": \"fe9cf696ffbf26a01c44e98288a774026f10051d\", \"extensions_unittests\": \"6bdb6c01c7a8ed847bc98a8915a801ab2efc6df4\", \"gl_unittests_ozone\": \"fca63421f3ee01b2d06eb6d436bf17d595b07283\", \"app_shell_unittests\": \"17a528e6c4e3a0c39849f225420fbd1c9c837ff7\", \"chrome_app_unittests\": \"9f74d1ea3aa44978d2a8885d9fa398af14564dc4\", \"gl_unittests\": \"fca63421f3ee01b2d06eb6d436bf17d595b07283\", \"gcm_unit_tests\": \"aeaf9c3c2679763bc5a7f62ff7175377f590755f\", \"views_unittests\": \"ec494029366339fd6e196bf5d4b64512020053e2\", \"content_shell_crash_test\": \"413c701b5cdb71d8d45e538418bd62763cfbd00e\", \"ui_base_unittests\": \"10d3253ab35c7650d640a19d08a0976b300f07de\", \"gles2_conform_test\": \"0fa98c3c8ac91f31e2639e386c6f95cb440b6f63\", \"cronet_unittests\": \"89002be42edda642382eaf2df93f6ce70c0d6e4d\", \"performance_test_suite\": \"6065ba3341eda0f1344d5c802a7298bee0b41e30\", \"wm_unittests\": \"11cce68584c81b3650c8a21ca0e115c475b11de2\", \"boringssl_crypto_tests\": \"9586c7c633ea9bfeb831cb37c1c2da88d26c847b\", \"gin_unittests\": \"6792b16b80d8b0d1eeb3dd2147bcbc45299ea0e5\", \"performance_browser_tests\": \"7dc23dc00e68a5b019ab93d3520aee30c6e5dcb1\", \"extensions_browsertests\": \"277c65be2a207d15fdc2f9cad554e1fcbaad464a\", \"webkit_python_tests\": \"1ff85678dae41a98d70676cb17d7abb7cd749bf6\", \"headless_unittests\": \"d10cddf49ab0f26fc3c98c085a7a5dd42b80e855\", \"webkit_layout_tests\": \"48962aa5fd51f6967eb57ec2c7e034e4cc4d3684\", \"vr_pixeltests\": \"b3271133caea1569e18d20c12331da3edaac4683\", \"setup_unittests\": \"86f23286f0b5fe0e49117d17722be6c54de19a27\", \"webkit_unit_tests\": \"ed167e335c890dfaaf056c4b4fe3355d34279f16\", \"content_unittests\": \"11963d9b0fb0907bd8c25a5f39633a68a8172938\", \"performance_webview_test_suite\": \"7449a1a2e6944bf792b10317a8b0fb1704f1cba8\", \"wtf_unittests\": \"aab7f8f0d2ef34ead6b8737ad41d180e4b436fc9\", \"webkit_layout_tests_exparchive\": \"ec7ca7fb492c5d5552b84b78fe8af77830d0f26d\", \"chrome_cleaner_unittests\": \"469113ccc3eb8bdabcec57429870247d90920a2b\", \"traffic_annotation_auditor_unittests\": \"4a1f1090a07d5655d50b45e85f162e4a52d1af25\", \"libjingle_xmpp_unittests\": \"86959065ea778de1dca8bddb86ae8116796cc2d9\", \"remoting_unittests\": \"4ebab7ffc4c3d2a4ede003529b672dc8eb3568ca\", \"vr_common_perftests\": \"da4c0a8c8b4e8f4e793958e752ad01d5b0b3812d\", \"filesystem_service_unittests\": \"6d27267d9620b80d1d32518416326dbf9057bf5f\", \"chrome_elf_import_unittests\": \"a87ac258ddc3df7f18c0d713f64b876e1dadff7b\", \"installer_util_unittests\": \"8f93ce0d905723755aad5787420e9140610ab102\", \"gfx_unittests\": \"b3612623e033a749612da63a5c2915ea13a32c7a\", \"headless_browsertests\": \"43169babc890340a996a3b6572b2ef7572abdb1e\", \"capture_unittests\": \"6b55f63f236a47d4e87c620ac1ee59c5b20b8a8d\", \"chrome_elf_unittests\": \"c1cc10351996385ff87e8ce58e5f37b616ab9882\", \"telemetry_gpu_unittests\": \"467b65ba18331a11970ae9c4fbc3524f19666c98\", \"command_buffer_perftests\": \"042d8195677ce2818a9796fd8ab306b08e4545b1\", \"media_unittests\": \"9f522099eb400abe630c8b7d591972004e9548d6\", \"chromedriver_unittests\": \"087257457756e968b2adfd50bb04295dbdb49eec\", \"views_mus_interactive_ui_tests\": \"95933199368fc006906c85ad63e98ee0b8476e72\", \"storage_unittests\": \"5d451540ff4ed54124c1d29522765d8535518769\", \"media_service_unittests\": \"9e9cf7cc33aa2c93664f33ad259fd7e361bf4659\", \"compositor_unittests\": \"52942bbefb1ac1947bae2f6e39f2c5a0954d48ad\", \"latency_unittests\": \"e2d81e8713ce4a4eab9d9b4d4e8a264dafca2cdc\", \"video_decode_accelerator_unittest\": \"1963a3ecd6a8f00d6c569a2f8b2d66e34be1aa54\", \"swiftshader_unittests\": \"3215d7f389f1414702f29d93246237cc5ed9956d\", \"telemetry_perf_unittests\": \"da12d0f271ab9490f1bdf60a4fc84f6d776279cb\", \"media_blink_unittests\": \"eae1d03d997cd7acb1235e6b85d843ab3cc58c9f\", \"views_mus_unittests\": \"3e61284823428429385d9f23ebe7a70ddb3e80a6\", \"components_unittests\": \"f9c5e570e005527becd058bf55bb450b312f55d9\", \"snapshot_unittests\": \"b619beb1f1db836079e8509f14d2cedfe612d802\", \"accessibility_unittests\": \"0b7b5097e1085c77889e820dbc3b44c489a018df\", \"net_perftests\": \"7c10368ce02f871ba4b6ab2e5f405cc092079f6b\", \"aura_unittests\": \"67cb9a461d0c505542dbc8e5400b533a4d3050d9\", \"services_unittests\": \"a48c79bfac44b919f176a81d49a6ff64380d4720\", \"components_perftests\": \"a2dec6429cca5c54051b67413d89c230d757351b\", \"events_unittests\": \"61ffa57a3832148bb3f1b9893faa0c7d2fbfa81e\", \"tracing_perftests\": \"a73f934a951bb56bf28054e3f8a172ead667b88a\", \"chromedriver_replay_unittests\": \"50c6aef6c493544165526b2b516579428eb4b618\", \"ui_touch_selection_unittests\": \"78a30d8154eff8429fa1b65169fd0edf42817765\", \"cacheinvalidation_unittests\": \"ea660eb18ec0d16c6da9759d386b512742397924\", \"viz_unittests\": \"c236b40acf0415bea63e0136bc20aba6ca7d71c8\", \"browser_tests\": \"143bbb21f536e187686efd89764657261b706cf1\", \"net_unittests\": \"c5dd0f85af9f1735e438019d0c41b9317e943b39\", \"midi_unittests\": \"f74d5c8d0aed098ae1d0eb038990c246e1f555c5\", \"boringssl_ssl_tests\": \"100e3ea281b8cdbcb4128b9a9e30b3afef0f3de0\", \"sbox_validation_tests\": \"a87ee11fb6fc963031d4eb12d07faac6855a2a01\", \"elevation_service_unittests\": \"08d3e1764069d0e06a93d1fa39b95136443302e7\", \"gpu_unittests\": \"b0a705784ab21eb8049e5e187f69a377b5ac0815\", \"native_theme_unittests\": \"ea8ff3bf36c0693e6d3c5a2d22436437fc996a33\", \"interactive_ui_tests\": \"21ff54a7ce97267c1971db3e6d5508824b340794\", \"mojo_unittests\": \"9e9cab4a7bada5630c9e75596528f930044a30d1\", \"url_unittests\": \"a25511cb532f7a1397cfb2749ea6514b64ebdf62\", \"media_perftests\": \"43d020c1b7aadae001c8c7049bbe7441184c63b7\", \"cronet_tests\": \"b22f9fb4f363fbb19350da58cfc4533d1136876a\", \"google_apis_unittests\": \"d618b72587ebc60387047fa0fc3bc079fda8fff0\", \"telemetry_gpu_integration_test\": \"0beae96fee9b0a6bb1ff231b902440f2ba48c1d9\", \"blink_heap_unittests\": \"7e4a84ed22f7ddfff234708f58853005a06ef7f8\", \"base_unittests\": \"585e8d2d1a59f1d7588a0393442cc00309d98b26\", \"sync_integration_tests\": \"74f0d5c861abb7ee24b699d902323553d1bfcd9b\", \"sql_unittests\": \"6ef5ec0b984c77d3244a576d56a7a24ab7c31e7c\", \"courgette_unittests\": \"732de7fc3a356b0b3b8cc7869a65f36721f63965\", \"blink_fuzzer_unittests\": \"1ad5f50cabbeaa6b52aec0d72c752e701087ce0a\", \"gl_tests\": \"b8de4149f7a196784cd40251d04d995304387f1c\", \"zucchini_unittests\": \"7f217fd7ac545e379d27baf2fe8cd47afdfb9605\", \"ipc_tests\": \"46199cdac67292b68b534d33bbf762e81c2fa392\", \"angle_end2end_tests\": \"4c63c956d6e4513bc8c416431ed08936a418053a\", \"blink_platform_unittests\": \"1f708437229f84af56916edfad95d5eef06e5d94\", \"components_browsertests\": \"554d97e5baf64fa3e2b94f792ef9fe29e8c8ced1\", \"views_perftests\": \"7139cabe91dc3311032b2a7b99863377286585bd\", \"angle_perftests\": \"3f1afc9b83d41f03f18def00563724d40ec0510b\", \"display_unittests\": \"ff2c407a2b9e018e6eab5c84ef1f23e18164cbb6\", \"sbox_unittests\": \"d77d22016ba8a6d1da09f2c9846422db161699df\", \"service_manager_unittests\": \"b1d5d188a38f0c072961544e6d7b86f0af975884\", \"pdf_unittests\": \"fac671d13a0a231ad3c68f77797369ef8452955b\", \"skia_unittests\": \"1205051f89ada83e1053cb74934e264300d32b44\", \"angle_white_box_tests\": \"cde1848b4b02500343b2ab16fd7eb203d5635d9e\", \"crypto_unittests\": \"1f02511a73c752fa3d805b2aae0dbe01033b8242\", \"nacl_loader_unittests\": \"8ea2d565bf18e9f08a2b70b4bf8f1a1b451f87e6\", \"unit_tests\": \"66c54c9d5b63d687376de8f5f38489a04b121995\", \"content_browsertests\": \"8e2f9d61c9d5a01f7ca868716a69297b0c579ead\", \"printing_unittests\": \"7c868a6be6517c92b43ce227372f6c3bf5375497\", \"device_unittests\": \"82fda7b39fe02ad4ab60b8416467c73b40a30ea0\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538373392816806",
"status": "COMPLETED",
"status_changed_ts": "1538379390087506",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10258",
"builder:Windows deterministic",
"buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099322362717864848",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/10258",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933892300373846048",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a3547e6bd908f53239ddbac6549cb3bd1f115cc",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8933892300373846048/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9099322362717864848",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4047049cb2baed10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538379390089604",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10258",
"utcnow_ts": "1538379390748303"
}
}
[07:36:30.759] Invocation finished in 1h40m2.57729212s with status SUCCEEDED