[23:15:32.212] New invocation is queued and will start shortly
[23:15:33.537] Starting the invocation (attempt 1)
[23:15:33.570] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:15:33.571] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:15:33.571] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9098804005449473520",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e75e449f30141d8e4810504c01334e8a7021ad9a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9098804005449473520",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e75e449f30141d8e4810504c01334e8a7021ad9a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a",
"gitiles_ref:refs/heads/master"
]
}
[23:15:35.719] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538867733681828",
"id": "8933373943246288032",
"lease_expiration_ts": "1539011735167210",
"lease_key": "1747618761",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e75e449f30141d8e4810504c01334e8a7021ad9a\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538867735568923",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10321",
"builder:Windows deterministic",
"buildset:commit/git/e75e449f30141d8e4810504c01334e8a7021ad9a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098804005449473520",
"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/10321",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933373943246288032",
"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/e75e449f30141d8e4810504c01334e8a7021ad9a",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a",
"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/8933373943246288032/+/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:9098804005449473520",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40647bafbbe79210",
"user_agent:luci-scheduler"
],
"updated_ts": "1538867735569352",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10321",
"utcnow_ts": "1538867735698862"
}
}
[23:15:35.719] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10321
[23:15:35.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:2:0) after 1m0s
[23:15:39.408] Received PubSub notification, asking Buildbucket for the build status
[23:15:39.464] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:2:0)
[23:16:35.737] Timer tick, asking Buildbucket for the build status
[23:16:35.784] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:4:0) after 1m0s
[23:17:35.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:4:0)
[23:17:35.805] Timer tick, asking Buildbucket for the build status
[23:17:35.881] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:35.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:5:0) after 1m0s
[23:18:35.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:5:0)
[23:18:35.911] Timer tick, asking Buildbucket for the build status
[23:18:36.020] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:6:0) after 1m0s
[23:19:36.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:6:0)
[23:19:36.040] Timer tick, asking Buildbucket for the build status
[23:19:36.121] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:7:0) after 1m0s
[23:20:36.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:7:0)
[23:20:36.136] Timer tick, asking Buildbucket for the build status
[23:20:36.229] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:8:0) after 1m0s
[23:21:36.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:8:0)
[23:21:36.251] Timer tick, asking Buildbucket for the build status
[23:21:36.420] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:36.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:9:0) after 1m0s
[23:22:36.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:9:0)
[23:22:36.526] Timer tick, asking Buildbucket for the build status
[23:22:36.609] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:36.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:10:0) after 1m0s
[23:23:36.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:10:0)
[23:23:36.630] Timer tick, asking Buildbucket for the build status
[23:23:36.723] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:36.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:11:0) after 1m0s
[23:24:36.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:11:0)
[23:24:36.751] Timer tick, asking Buildbucket for the build status
[23:24:36.828] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:36.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:12:0) after 1m0s
[23:25:36.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:12:0)
[23:25:36.844] Timer tick, asking Buildbucket for the build status
[23:25:37.140] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:37.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:13:0) after 1m0s
[23:26:37.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:13:0)
[23:26:37.159] Timer tick, asking Buildbucket for the build status
[23:26:37.237] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:37.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:14:0) after 1m0s
[23:27:37.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:14:0)
[23:27:37.252] Timer tick, asking Buildbucket for the build status
[23:27:37.562] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:15:0) after 1m0s
[23:28:37.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:15:0)
[23:28:37.579] Timer tick, asking Buildbucket for the build status
[23:28:37.637] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:37.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:16:0) after 1m0s
[23:29:37.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:16:0)
[23:29:37.659] Timer tick, asking Buildbucket for the build status
[23:29:37.742] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:37.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:17:0) after 1m0s
[23:30:37.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:17:0)
[23:30:37.781] Timer tick, asking Buildbucket for the build status
[23:30:37.821] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:18:0) after 1m0s
[23:31:37.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:18:0)
[23:31:37.853] Timer tick, asking Buildbucket for the build status
[23:31:37.936] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:37.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:19:0) after 1m0s
[23:32:37.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:19:0)
[23:32:37.952] Timer tick, asking Buildbucket for the build status
[23:32:38.010] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:20:0) after 1m0s
[23:33:38.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:20:0)
[23:33:38.044] Timer tick, asking Buildbucket for the build status
[23:33:38.090] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:21:0) after 1m0s
[23:34:38.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:21:0)
[23:34:38.197] Timer tick, asking Buildbucket for the build status
[23:34:38.263] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:38.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:22:0) after 1m0s
[23:35:38.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:22:0)
[23:35:38.281] Timer tick, asking Buildbucket for the build status
[23:35:38.519] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:38.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:23:0) after 1m0s
[23:36:38.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:23:0)
[23:36:38.537] Timer tick, asking Buildbucket for the build status
[23:36:38.605] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:38.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:24:0) after 1m0s
[23:37:38.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:24:0)
[23:37:38.622] Timer tick, asking Buildbucket for the build status
[23:37:38.683] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:25:0) after 1m0s
[23:38:38.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:25:0)
[23:38:38.763] Timer tick, asking Buildbucket for the build status
[23:38:38.817] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:38.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:26:0) after 1m0s
[23:39:38.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:26:0)
[23:39:38.873] Timer tick, asking Buildbucket for the build status
[23:39:39.047] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:27:0) after 1m0s
[23:40:39.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:27:0)
[23:40:39.067] Timer tick, asking Buildbucket for the build status
[23:40:39.134] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:39.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:28:0) after 1m0s
[23:41:39.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:28:0)
[23:41:39.152] Timer tick, asking Buildbucket for the build status
[23:41:39.275] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:29:0) after 1m0s
[23:42:39.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:29:0)
[23:42:39.293] Timer tick, asking Buildbucket for the build status
[23:42:39.510] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:30:0) after 1m0s
[23:43:39.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:30:0)
[23:43:39.533] Timer tick, asking Buildbucket for the build status
[23:43:39.610] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:31:0) after 1m0s
[23:44:39.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:31:0)
[23:44:39.648] Timer tick, asking Buildbucket for the build status
[23:44:39.750] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:39.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:32:0) after 1m0s
[23:45:39.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:32:0)
[23:45:39.766] Timer tick, asking Buildbucket for the build status
[23:45:39.868] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:39.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:33:0) after 1m0s
[23:46:39.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:33:0)
[23:46:39.887] Timer tick, asking Buildbucket for the build status
[23:46:40.035] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:40.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:34:0) after 1m0s
[23:47:40.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:34:0)
[23:47:40.056] Timer tick, asking Buildbucket for the build status
[23:47:40.211] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:40.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:35:0) after 1m0s
[23:48:40.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:35:0)
[23:48:40.228] Timer tick, asking Buildbucket for the build status
[23:48:40.359] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:36:0) after 1m0s
[23:49:40.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:36:0)
[23:49:40.392] Timer tick, asking Buildbucket for the build status
[23:49:40.501] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:37:0) after 1m0s
[23:50:40.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:37:0)
[23:50:40.524] Timer tick, asking Buildbucket for the build status
[23:50:40.593] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:38:0) after 1m0s
[23:51:40.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:38:0)
[23:51:40.672] Timer tick, asking Buildbucket for the build status
[23:51:40.741] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:40.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:39:0) after 1m0s
[23:52:40.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:39:0)
[23:52:40.770] Timer tick, asking Buildbucket for the build status
[23:52:40.847] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:40:0) after 1m0s
[23:53:40.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:40:0)
[23:53:40.868] Timer tick, asking Buildbucket for the build status
[23:53:40.936] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:40.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:41:0) after 1m0s
[23:54:40.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:41:0)
[23:54:40.973] Timer tick, asking Buildbucket for the build status
[23:54:41.296] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:42:0) after 1m0s
[23:55:41.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:42:0)
[23:55:41.322] Timer tick, asking Buildbucket for the build status
[23:55:41.398] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:41.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:43:0) after 1m0s
[23:56:41.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:43:0)
[23:56:41.419] Timer tick, asking Buildbucket for the build status
[23:56:41.584] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:41.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:44:0) after 1m0s
[23:57:41.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:44:0)
[23:57:41.603] Timer tick, asking Buildbucket for the build status
[23:57:41.738] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:41.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:45:0) after 1m0s
[23:58:41.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:45:0)
[23:58:41.758] Timer tick, asking Buildbucket for the build status
[23:58:41.824] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:41.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:46:0) after 1m0s
[23:59:41.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:46:0)
[23:59:41.899] Timer tick, asking Buildbucket for the build status
[23:59:42.118] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:47:0) after 1m0s
[00:00:42.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:47:0)
[00:00:42.216] Timer tick, asking Buildbucket for the build status
[00:00:42.498] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:42.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:48:0) after 1m0s
[00:01:42.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:48:0)
[00:01:42.549] Timer tick, asking Buildbucket for the build status
[00:01:42.742] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:42.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:49:0) after 1m0s
[00:02:42.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:49:0)
[00:02:42.763] Timer tick, asking Buildbucket for the build status
[00:02:42.832] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:42.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:50:0) after 1m0s
[00:03:42.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:50:0)
[00:03:42.869] Timer tick, asking Buildbucket for the build status
[00:03:43.035] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:43.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:51:0) after 1m0s
[00:04:43.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:51:0)
[00:04:43.056] Timer tick, asking Buildbucket for the build status
[00:04:43.119] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:52:0) after 1m0s
[00:05:43.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:52:0)
[00:05:43.152] Timer tick, asking Buildbucket for the build status
[00:05:43.216] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:43.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:53:0) after 1m0s
[00:06:43.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:53:0)
[00:06:43.237] Timer tick, asking Buildbucket for the build status
[00:06:43.413] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:43.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:54:0) after 1m0s
[00:07:43.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:54:0)
[00:07:43.436] Timer tick, asking Buildbucket for the build status
[00:07:43.506] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:43.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:55:0) after 1m0s
[00:08:43.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:55:0)
[00:08:43.526] Timer tick, asking Buildbucket for the build status
[00:08:43.613] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:43.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:56:0) after 1m0s
[00:09:43.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:56:0)
[00:09:43.635] Timer tick, asking Buildbucket for the build status
[00:09:43.779] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:43.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:57:0) after 1m0s
[00:10:43.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:57:0)
[00:10:43.810] Timer tick, asking Buildbucket for the build status
[00:10:43.903] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:43.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:58:0) after 1m0s
[00:11:43.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:58:0)
[00:11:43.937] Timer tick, asking Buildbucket for the build status
[00:11:44.023] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:44.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:59:0) after 1m0s
[00:12:44.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:59:0)
[00:12:44.093] Timer tick, asking Buildbucket for the build status
[00:12:44.170] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:44.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:60:0) after 1m0s
[00:13:44.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:60:0)
[00:13:44.189] Timer tick, asking Buildbucket for the build status
[00:13:44.298] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:61:0) after 1m0s
[00:14:44.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:61:0)
[00:14:44.387] Timer tick, asking Buildbucket for the build status
[00:14:44.455] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:44.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:62:0) after 1m0s
[00:15:44.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:62:0)
[00:15:44.484] Timer tick, asking Buildbucket for the build status
[00:15:44.556] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:44.556] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:63:0) after 1m0s
[00:16:44.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:63:0)
[00:16:44.742] Timer tick, asking Buildbucket for the build status
[00:16:44.797] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:44.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:64:0) after 1m0s
[00:17:44.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:64:0)
[00:17:44.941] Timer tick, asking Buildbucket for the build status
[00:17:45.063] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:45.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:65:0) after 1m0s
[00:18:45.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:65:0)
[00:18:45.093] Timer tick, asking Buildbucket for the build status
[00:18:45.270] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:45.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:66:0) after 1m0s
[00:19:45.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:66:0)
[00:19:45.313] Timer tick, asking Buildbucket for the build status
[00:19:45.527] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:45.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:67:0) after 1m0s
[00:20:45.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:67:0)
[00:20:45.548] Timer tick, asking Buildbucket for the build status
[00:20:45.608] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:45.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:68:0) after 1m0s
[00:21:45.628] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:68:0)
[00:21:45.628] Timer tick, asking Buildbucket for the build status
[00:21:45.720] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:45.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:69:0) after 1m0s
[00:22:45.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:69:0)
[00:22:45.771] Timer tick, asking Buildbucket for the build status
[00:22:46.025] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:46.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:70:0) after 1m0s
[00:23:46.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:70:0)
[00:23:46.050] Timer tick, asking Buildbucket for the build status
[00:23:46.132] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:46.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:71:0) after 1m0s
[00:24:46.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:71:0)
[00:24:46.222] Timer tick, asking Buildbucket for the build status
[00:24:46.398] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:46.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:72:0) after 1m0s
[00:25:46.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:72:0)
[00:25:46.420] Timer tick, asking Buildbucket for the build status
[00:25:46.526] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:46.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:73:0) after 1m0s
[00:26:46.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:73:0)
[00:26:46.544] Timer tick, asking Buildbucket for the build status
[00:26:46.659] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:46.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:74:0) after 1m0s
[00:27:46.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:74:0)
[00:27:46.678] Timer tick, asking Buildbucket for the build status
[00:27:46.924] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:46.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:75:0) after 1m0s
[00:28:46.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:75:0)
[00:28:46.945] Timer tick, asking Buildbucket for the build status
[00:28:47.071] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:47.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:76:0) after 1m0s
[00:29:47.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:76:0)
[00:29:47.105] Timer tick, asking Buildbucket for the build status
[00:29:47.202] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:47.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:77:0) after 1m0s
[00:30:47.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:77:0)
[00:30:47.226] Timer tick, asking Buildbucket for the build status
[00:30:47.312] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:47.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:78:0) after 1m0s
[00:31:47.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:78:0)
[00:31:47.336] Timer tick, asking Buildbucket for the build status
[00:31:47.420] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:47.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:79:0) after 1m0s
[00:32:47.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:79:0)
[00:32:47.459] Timer tick, asking Buildbucket for the build status
[00:32:47.608] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:47.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:80:0) after 1m0s
[00:33:47.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:80:0)
[00:33:47.634] Timer tick, asking Buildbucket for the build status
[00:33:48.007] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:48.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:81:0) after 1m0s
[00:34:48.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:81:0)
[00:34:48.029] Timer tick, asking Buildbucket for the build status
[00:34:48.223] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:48.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:82:0) after 1m0s
[00:35:48.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:82:0)
[00:35:48.246] Timer tick, asking Buildbucket for the build status
[00:35:48.325] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:48.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:83:0) after 1m0s
[00:36:48.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:83:0)
[00:36:48.348] Timer tick, asking Buildbucket for the build status
[00:36:48.431] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:48.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:84:0) after 1m0s
[00:37:48.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:84:0)
[00:37:48.537] Timer tick, asking Buildbucket for the build status
[00:37:48.623] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:48.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:85:0) after 1m0s
[00:38:48.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:85:0)
[00:38:48.689] Timer tick, asking Buildbucket for the build status
[00:38:48.738] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:48.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:86:0) after 1m0s
[00:39:48.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:86:0)
[00:39:48.869] Timer tick, asking Buildbucket for the build status
[00:39:48.957] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:48.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:87:0) after 1m0s
[00:40:49.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:87:0)
[00:40:49.079] Timer tick, asking Buildbucket for the build status
[00:40:49.250] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:49.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:88:0) after 1m0s
[00:41:49.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:88:0)
[00:41:49.318] Timer tick, asking Buildbucket for the build status
[00:41:49.469] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:49.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:89:0) after 1m0s
[00:42:49.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:89:0)
[00:42:49.502] Timer tick, asking Buildbucket for the build status
[00:42:49.560] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:49.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:90:0) after 1m0s
[00:43:49.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:90:0)
[00:43:49.667] Timer tick, asking Buildbucket for the build status
[00:43:49.786] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:49.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:91:0) after 1m0s
[00:44:49.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:91:0)
[00:44:49.807] Timer tick, asking Buildbucket for the build status
[00:44:49.905] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:49.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:92:0) after 1m0s
[00:45:50.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:92:0)
[00:45:50.068] Timer tick, asking Buildbucket for the build status
[00:45:50.142] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:50.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:93:0) after 1m0s
[00:46:50.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:93:0)
[00:46:50.238] Timer tick, asking Buildbucket for the build status
[00:46:50.294] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:50.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:94:0) after 1m0s
[00:47:50.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:94:0)
[00:47:50.315] Timer tick, asking Buildbucket for the build status
[00:47:50.397] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:50.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:95:0) after 1m0s
[00:48:50.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:95:0)
[00:48:50.414] Timer tick, asking Buildbucket for the build status
[00:48:50.482] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:50.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:96:0) after 1m0s
[00:49:50.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:96:0)
[00:49:50.503] Timer tick, asking Buildbucket for the build status
[00:49:50.717] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:50.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:97:0) after 1m0s
[00:50:50.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:97:0)
[00:50:50.842] Timer tick, asking Buildbucket for the build status
[00:50:50.948] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:98:0) after 1m0s
[00:51:50.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:98:0)
[00:51:50.971] Timer tick, asking Buildbucket for the build status
[00:51:51.096] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:51.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:99:0) after 1m0s
[00:52:51.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:99:0)
[00:52:51.196] Timer tick, asking Buildbucket for the build status
[00:52:51.282] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:51.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:100:0) after 1m0s
[00:53:51.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:100:0)
[00:53:51.313] Timer tick, asking Buildbucket for the build status
[00:53:51.436] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:51.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:101:0) after 1m0s
[00:54:51.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:101:0)
[00:54:51.457] Timer tick, asking Buildbucket for the build status
[00:54:51.591] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:51.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:102:0) after 1m0s
[00:55:51.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:102:0)
[00:55:51.618] Timer tick, asking Buildbucket for the build status
[00:55:51.692] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:51.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:103:0) after 1m0s
[00:56:51.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:103:0)
[00:56:51.717] Timer tick, asking Buildbucket for the build status
[00:56:51.854] Build 8933373943246288032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:51.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098804005449473520:104:0) after 1m0s
[00:57:17.945] Received PubSub notification, asking Buildbucket for the build status
[00:57:18.014] Build 8933373943246288032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:57:18.014] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538873836295361",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538867733681828",
"id": "8933373943246288032",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e75e449f30141d8e4810504c01334e8a7021ad9a\"}}",
"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/8933373943246288032/+/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\": [\"3801-8761768\"], \"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\": \"e75e449f30141d8e4810504c01334e8a7021ad9a\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25031}\", \"$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@{#597446}\", \"branch\": \"refs/heads/master\", \"revision\": \"e75e449f30141d8e4810504c01334e8a7021ad9a\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"343f4144bee5ddde5efec1e1217403b444386cb4\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"450bf36f7918d18f3186be8ce570d9eac63b309d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538867733681828, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/e75e449f30141d8e4810504c01334e8a7021ad9a\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9098804005449473520\", \"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\": \"8933373943246288032\"}}, \"got_v8_revision\": \"4ce1c1202f98c06ba0775ccbff2bc00334b04118\", \"got_v8_revision_cp\": \"refs/heads/7.1.276@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10321, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"2a41e18f4ecd620e16c8f2c3bb21f2c7014f60e9\", \"notification_helper_unittests\": \"fc854cbbdb55a3105677ff86dc313100f4af94b4\", \"gpu_perftests\": \"160b58c0da90fee83aff4caaebc20be323b8862b\", \"vr_common_unittests\": \"96c77095d03f4cf3c5ccc1b9f0fd0dcd85799bc4\", \"telemetry_unittests\": \"3462ce06cc1a4d0fa67193e73a1b4cd9bb210322\", \"load_library_perf_tests\": \"7b5f68a450f018c5c6ec5a0555916684e334b573\", \"devtools_closure_compile\": \"947ceeb4ceaa909d325ac331beebc7cb1b6ac0a7\", \"chromedriver_py_tests\": \"2914d003c646a367c7050387acab7a551dc7de30\", \"mojo_core_unittests\": \"2e41cce0117970fe692237d7dcf6a29d308bbeab\", \"install_static_unittests\": \"2764fefbfcdd127190771d78535f772b72274f26\", \"leveldb_service_unittests\": \"40e61c80617a2223105c36b5f49dd16aa7a5ad66\", \"mini_installer_tests\": \"8485b05f44bd78aca73c5859dd83f09ff10d299b\", \"jingle_unittests\": \"01df860252f87aadf6013d3686e4e06512a7ce14\", \"sbox_integration_tests\": \"8c1c1c5376dc16d23fd3195d3d49b1a25172f74c\", \"angle_unittests\": \"fdb4facbe3943a6e18c603100d56dc0bbbfd1333\", \"cast_unittests\": \"0b9d676156ab1c4ccd1a95f9950e75c81862895c\", \"cc_unittests\": \"209e74c04f00c6eef19c49097822fed260c39914\", \"blink_common_unittests\": \"72a8fc69ef5cd97e287f0ebd3262e20f4fe70d36\", \"crashpad_tests\": \"f48764fdb3ad7cd7a7b17608ab088b55b2c50d06\", \"telemetry_perf_tests_without_chrome\": \"d60d8d85c3ad37fb91aaa61dacbdfc1cefcb7fa7\", \"metrics_python_tests\": \"b4e4090d7c42df6b958c4a40710412bed7ee9ad6\", \"message_center_unittests\": \"e22c412fa703a8e7269a2b7d5200b34353bc7b6f\", \"ppapi_unittests\": \"1045e32cc9f7f94a585813ed6e16462dd1cdea0b\", \"extensions_unittests\": \"94bd1941c667e54901d65af8a293cf6158a48231\", \"gl_unittests_ozone\": \"0cfd76a3e1f0d76197409a03085137a4c252315b\", \"app_shell_unittests\": \"86ba7512bd4c5273bf3f994ffd3a13afddf4a8bc\", \"chrome_app_unittests\": \"41be580a81ba931d60e22b713477059156d2ea15\", \"gl_unittests\": \"0cfd76a3e1f0d76197409a03085137a4c252315b\", \"gcm_unit_tests\": \"69f7c572460d6e04c91de333d62765cc22ee08b2\", \"views_unittests\": \"521f8a9630e53ea5e3aed8b7facc6b9ae2759c23\", \"content_shell_crash_test\": \"935d2fd915f79fe05b7309dedd3cdf8745307f21\", \"ui_base_unittests\": \"9444fdbcfbf67959fc4d7f5bf43631fe75688354\", \"gles2_conform_test\": \"a7a6cfba9dfbd5055445b1c94e523bb685e3d47e\", \"cronet_unittests\": \"369774c7da747a90995f63189e8fadf321a7b7ef\", \"performance_test_suite\": \"73b659829a2b6cfcb72fcc7f7155711ca4b67867\", \"wm_unittests\": \"e668c40c2226d07c19f260557ad98278c4a0c8c0\", \"boringssl_crypto_tests\": \"76b00aa8b78058f21ddd777e5fc991a9f7faf440\", \"gin_unittests\": \"ffd0213a9595bdc1363150e34a520e0503c7a2d0\", \"performance_browser_tests\": \"281cb624bea3c3c81692030c680610c84ee50b2a\", \"extensions_browsertests\": \"ef393886326ec26077776fe9960b44f6677d007d\", \"webkit_python_tests\": \"1182026ac5ebb0aa298541a05a56d3d287256f41\", \"headless_unittests\": \"b9f70bd9aa357f0851cdd6dd28c4ce27acb66d6d\", \"webkit_layout_tests\": \"6cfbe9bac1457dc8fd46255429b684bf8dbe6e42\", \"vr_pixeltests\": \"61ce06933f6c3230516d17922bf1f4ea6ffb4aa6\", \"setup_unittests\": \"fd2000c7aa987293a627d640b3c9978ac30e8184\", \"webkit_unit_tests\": \"719b9c3b05fba9b8d69d1aef2c96f26706dc675e\", \"content_unittests\": \"a8d817e5ebe49c72d56c268a2574c85204875f20\", \"performance_webview_test_suite\": \"cc23cd4a816d71825c40a9459a9399752d0781af\", \"wtf_unittests\": \"adea6a8727c5e6a904230e12b634d19691bb8a04\", \"webkit_layout_tests_exparchive\": \"d2f79d64a4ff24a95e4cf7182cb659a3ad876ac1\", \"chrome_cleaner_unittests\": \"c813d2f0e30abb4926bf0c04b8404334d5a126d6\", \"traffic_annotation_auditor_unittests\": \"8f616183037e13e45d0da0dbc13bdd0d1f031279\", \"libjingle_xmpp_unittests\": \"5243e0023cc94931975d8a1048e63f1e19eb20ad\", \"remoting_unittests\": \"2f881208605cee353a01ab6ab5b004a1901afcf9\", \"vr_common_perftests\": \"d4341940b3f070f0210b95cef9e0310517a30ad0\", \"filesystem_service_unittests\": \"41d97b8acd4c80a5326eadd1e453617324424f63\", \"chrome_elf_import_unittests\": \"1faae9a09eaa9583bcb62465ed59595aef903a36\", \"installer_util_unittests\": \"2b351cc8da51e8c15df0436b75b4192450a75755\", \"gfx_unittests\": \"1b8eb37297e1507bb3afc7c7cd639e620525c97c\", \"headless_browsertests\": \"18b2ac98a1bbf86a0be8de3388edd693d09fa9cd\", \"capture_unittests\": \"4a37f1ad3a64ee909add2b0f385d7af81e5e1732\", \"chrome_elf_unittests\": \"b9587230c4852d1ca18d117db67726f96886306a\", \"telemetry_gpu_unittests\": \"74deb9a1eacc0a042f05d8bc40d1ee07a6fc879d\", \"command_buffer_perftests\": \"cf4b36f95e1080b5fdc74ae9c7d442357c52414a\", \"media_unittests\": \"24e0d3335b42dea7b46470f55e461b942ee20739\", \"chromedriver_unittests\": \"0b74f80cd61dfc7aefbeb6c834d803b229d06f92\", \"views_mus_interactive_ui_tests\": \"47c9a5dc8a72f52745a807aef7fd61f9b1ac8332\", \"storage_unittests\": \"86eac66794a859e635c7eedf2eb289d8c3812741\", \"media_service_unittests\": \"fb41b1b1c1b18b81d9dcfbcbe48a697137e7d28f\", \"compositor_unittests\": \"ab60b2544bf7e4acc8df712c2323d2520e765dcd\", \"latency_unittests\": \"bd12e78de8383d5f332ce5d1b061603fd60b28ac\", \"video_decode_accelerator_unittest\": \"38f029eddb78d7f378aa106c0efc02a8ee7dd9f8\", \"swiftshader_unittests\": \"5d7fddbc688528b7f4c07ebd04fc2e61773d1829\", \"telemetry_perf_unittests\": \"e22f010e1c394d11ee5517951d16f2a396d6ef2f\", \"media_blink_unittests\": \"116e7255115343a0e80d7eaab27a4340a2959264\", \"views_mus_unittests\": \"8d1b1ccb7f12ff06ec46a095858c202adb7c8132\", \"components_unittests\": \"b708f7495903eb73679401a3b5f91d9772b178e3\", \"snapshot_unittests\": \"54d02b7b1e42ad4649c9f4c3553d86c809313731\", \"accessibility_unittests\": \"0072e40fef83e6cc2b59d802adb425adea04df05\", \"net_perftests\": \"4568bcebffe160e89bec817cec79ff6c1faefa39\", \"aura_unittests\": \"ce68e0fb15a54ec8739eded2acfcb9da28df4e7e\", \"services_unittests\": \"9f9bbb81664a5ec1fd7e94e7a83ba61356af738e\", \"components_perftests\": \"45a6566bcb35da905dfbc17fd634a9c2b22e1942\", \"events_unittests\": \"748e6d39ed369ee94b18c6cd782e009757052cca\", \"tracing_perftests\": \"adb32e69723867aa1f257ae85d17e8aad864bfba\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"2a851f2f03f6f558ac611742029dfb6918d28890\", \"cacheinvalidation_unittests\": \"abe6765495f6b4a7a28bd6c064941c4127771e4d\", \"viz_unittests\": \"bfdd70f94d428edd511347ea28520d20b93a47d0\", \"browser_tests\": \"b9e1c11264ba403a857984bd4bb289b2a0eca031\", \"net_unittests\": \"1f5706b67fd6c2b3cad2c8cd6b5af14dedbf1351\", \"midi_unittests\": \"31e585719d714b50806a2148d3ee191e8d46e0f2\", \"boringssl_ssl_tests\": \"04a7c75a795271bcf061f279bd666ca2e87e74a5\", \"sbox_validation_tests\": \"69ac120f79c78f51972058640fb5b4d217762f86\", \"elevation_service_unittests\": \"94f3c5e97c118b716ae0aa037da3828be70cac04\", \"gpu_unittests\": \"30f3cf2c425cf7b324e1133ffd3bb42210917efd\", \"native_theme_unittests\": \"ed00408cc55944221f538c9af3a75ade6cfdbb7a\", \"interactive_ui_tests\": \"7ef6831919b5af99f0f4fddf4c1f3854955156fe\", \"mojo_unittests\": \"b5dd206bcc42cc3eee2f2e5827ead0d9d48ca861\", \"url_unittests\": \"0040fd56aeee8bf440880b665f2bbd9525ee4a64\", \"media_perftests\": \"0443b40fc4f87d5fed0b5e9a8a20f20f2c2336b9\", \"cronet_tests\": \"2a425c6d81111fe99881e8164a5becde974bab46\", \"google_apis_unittests\": \"90db85ac7be35eeae9df9173321737eb760a0f10\", \"telemetry_gpu_integration_test\": \"a58a852bec87a288d5777f1b46a8bf8ccd5c3935\", \"blink_heap_unittests\": \"9803770d375823be51d94f63daf7e43d92c2fe63\", \"base_unittests\": \"a581a8c3498df7252d51af08d0b4a9a48907b258\", \"sync_integration_tests\": \"a517b42376531296ff4dd4b5147b861389fac745\", \"sql_unittests\": \"0badaff5a06e6e3956974729ce35b1960d55faec\", \"courgette_unittests\": \"b0b685f3602093638ab944e10dc0bb170c1f5144\", \"blink_fuzzer_unittests\": \"7faf804c1a2e7846ec7ef41134167a336701a256\", \"gl_tests\": \"cd66fc8ad85544d82849e015d4cf567dd8517d99\", \"zucchini_unittests\": \"c8a85a4e06570b0bb0689510c28479f8f3424615\", \"ipc_tests\": \"1cd12a80ce476a167f58ec611a0c2c8ca178dd07\", \"angle_end2end_tests\": \"9042135d1666db3e6dacdca67fe5987ecd5aac9a\", \"blink_platform_unittests\": \"954c41aab5117653d50e5e2c414e7924ea18326b\", \"components_browsertests\": \"ab24583426cbf99c5dea05e229fa7bbefb4ca8b0\", \"views_perftests\": \"babc18b0bab47f8d377ad59443187a5788e10266\", \"angle_perftests\": \"04ab3288c69aad103e1948f85f1a6337b5b995ce\", \"display_unittests\": \"1b8fd6ece5c2c055d384ee6178d3ec2440ca6cf3\", \"sbox_unittests\": \"108659137e672b3c7182355d009b76f9999182d5\", \"service_manager_unittests\": \"5a79fc2a9213f92ff9c6f27d08d9d96dad1a08c0\", \"pdf_unittests\": \"48f430e8b1254a492c0c5b94c521444ae99e287c\", \"skia_unittests\": \"352425939224f8f0529d7656da7705d916e034dc\", \"angle_white_box_tests\": \"0eb176a27cccb49efab8ef1d82221d746b85356a\", \"crypto_unittests\": \"d12d2e9e29559748b18d0f28925b03474c16ba48\", \"nacl_loader_unittests\": \"43b6471fad65cc5e89e676958e7473b064489e94\", \"unit_tests\": \"c8c3e89cee5a5896ad96aba351103c99b4505ec3\", \"content_browsertests\": \"1c225b6b4124585617f0f7638d88aed7bea0229c\", \"printing_unittests\": \"1ab8f6d1279ca80450ac563824ddece58b2593d6\", \"device_unittests\": \"6c0e51ab65bf38a2dcde159db5ed4007a2a267c8\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538867738494581",
"status": "COMPLETED",
"status_changed_ts": "1538873837407873",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10321",
"builder:Windows deterministic",
"buildset:commit/git/e75e449f30141d8e4810504c01334e8a7021ad9a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098804005449473520",
"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/10321",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933373943246288032",
"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/e75e449f30141d8e4810504c01334e8a7021ad9a",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e75e449f30141d8e4810504c01334e8a7021ad9a",
"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/8933373943246288032/+/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:9098804005449473520",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40647bafbbe79210",
"user_agent:luci-scheduler"
],
"updated_ts": "1538873837409789",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10321",
"utcnow_ts": "1538873837997404"
}
}
[00:57:18.014] Invocation finished in 1h41m45.818015471s with status SUCCEEDED