[00:47:14.377] New invocation is queued and will start shortly
[00:47:15.545] Starting the invocation (attempt 1)
[00:47:15.579] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:47:15.579] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:47:15.579] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099975996575760224",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7504077ef5191493914ebee0f03d5a897acb5ce6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099975996575760224",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/7504077ef5191493914ebee0f03d5a897acb5ce6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6",
"gitiles_ref:refs/heads/master"
]
}
[00:47:17.071] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537750035655534",
"id": "8934545934571685152",
"lease_expiration_ts": "1537894036850715",
"lease_key": "1967226191",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7504077ef5191493914ebee0f03d5a897acb5ce6\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1537750036963056",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10180",
"builder:Windows deterministic",
"buildset:commit/git/7504077ef5191493914ebee0f03d5a897acb5ce6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099975996575760224",
"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/10180",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934545934571685152",
"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/7504077ef5191493914ebee0f03d5a897acb5ce6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6",
"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/8934545934571685152/+/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:9099975996575760224",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4021dcf851d8a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1537750036963154",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10180",
"utcnow_ts": "1537750037033355"
}
}
[00:47:17.071] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10180
[00:47:17.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:2:0) after 1m0s
[00:47:19.094] Received PubSub notification, asking Buildbucket for the build status
[00:47:19.135] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:17.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:2:0)
[00:48:17.090] Timer tick, asking Buildbucket for the build status
[00:48:17.145] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:17.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:4:0) after 1m0s
[00:49:17.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:4:0)
[00:49:17.242] Timer tick, asking Buildbucket for the build status
[00:49:17.324] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:17.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:5:0) after 1m0s
[00:50:17.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:5:0)
[00:50:17.343] Timer tick, asking Buildbucket for the build status
[00:50:17.427] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:17.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:6:0) after 1m0s
[00:51:17.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:6:0)
[00:51:17.443] Timer tick, asking Buildbucket for the build status
[00:51:17.526] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:17.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:7:0) after 1m0s
[00:52:17.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:7:0)
[00:52:17.548] Timer tick, asking Buildbucket for the build status
[00:52:17.613] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:17.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:8:0) after 1m0s
[00:53:17.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:8:0)
[00:53:17.634] Timer tick, asking Buildbucket for the build status
[00:53:17.672] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:17.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:9:0) after 1m0s
[00:54:17.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:9:0)
[00:54:17.724] Timer tick, asking Buildbucket for the build status
[00:54:17.983] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:17.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:10:0) after 1m0s
[00:55:18.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:10:0)
[00:55:18.004] Timer tick, asking Buildbucket for the build status
[00:55:18.137] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:18.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:11:0) after 1m0s
[00:56:18.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:11:0)
[00:56:18.175] Timer tick, asking Buildbucket for the build status
[00:56:18.354] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:18.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:12:0) after 1m0s
[00:57:18.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:12:0)
[00:57:18.371] Timer tick, asking Buildbucket for the build status
[00:57:18.442] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:18.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:13:0) after 1m0s
[00:58:18.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:13:0)
[00:58:18.470] Timer tick, asking Buildbucket for the build status
[00:58:18.525] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:18.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:14:0) after 1m0s
[00:59:18.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:14:0)
[00:59:18.541] Timer tick, asking Buildbucket for the build status
[00:59:18.632] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:18.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:15:0) after 1m0s
[01:00:18.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:15:0)
[01:00:18.672] Timer tick, asking Buildbucket for the build status
[01:00:18.820] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:18.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:16:0) after 1m0s
[01:01:18.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:16:0)
[01:01:18.839] Timer tick, asking Buildbucket for the build status
[01:01:18.900] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:18.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:17:0) after 1m0s
[01:02:18.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:17:0)
[01:02:18.920] Timer tick, asking Buildbucket for the build status
[01:02:18.986] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:18.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:18:0) after 1m0s
[01:03:19.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:18:0)
[01:03:19.016] Timer tick, asking Buildbucket for the build status
[01:03:19.076] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:19.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:19:0) after 1m0s
[01:04:19.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:19:0)
[01:04:19.097] Timer tick, asking Buildbucket for the build status
[01:04:19.191] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:19.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:20:0) after 1m0s
[01:05:19.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:20:0)
[01:05:19.246] Timer tick, asking Buildbucket for the build status
[01:05:19.289] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:19.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:21:0) after 1m0s
[01:06:19.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:21:0)
[01:06:19.306] Timer tick, asking Buildbucket for the build status
[01:06:19.418] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:19.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:22:0) after 1m0s
[01:07:19.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:22:0)
[01:07:19.436] Timer tick, asking Buildbucket for the build status
[01:07:19.472] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:19.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:23:0) after 1m0s
[01:08:19.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:23:0)
[01:08:19.490] Timer tick, asking Buildbucket for the build status
[01:08:19.558] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:19.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:24:0) after 1m0s
[01:09:19.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:24:0)
[01:09:19.643] Timer tick, asking Buildbucket for the build status
[01:09:19.741] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:19.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:25:0) after 1m0s
[01:10:19.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:25:0)
[01:10:19.762] Timer tick, asking Buildbucket for the build status
[01:10:19.824] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:19.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:26:0) after 1m0s
[01:11:19.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:26:0)
[01:11:19.842] Timer tick, asking Buildbucket for the build status
[01:11:19.883] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:19.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:27:0) after 1m0s
[01:12:19.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:27:0)
[01:12:19.902] Timer tick, asking Buildbucket for the build status
[01:12:19.983] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:19.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:28:0) after 1m0s
[01:13:20.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:28:0)
[01:13:20.002] Timer tick, asking Buildbucket for the build status
[01:13:20.176] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:20.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:29:0) after 1m0s
[01:14:20.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:29:0)
[01:14:20.313] Timer tick, asking Buildbucket for the build status
[01:14:20.526] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:20.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:30:0) after 1m0s
[01:15:20.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:30:0)
[01:15:20.559] Timer tick, asking Buildbucket for the build status
[01:15:20.715] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:20.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:31:0) after 1m0s
[01:16:20.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:31:0)
[01:16:20.733] Timer tick, asking Buildbucket for the build status
[01:16:20.769] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:20.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:32:0) after 1m0s
[01:17:20.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:32:0)
[01:17:20.797] Timer tick, asking Buildbucket for the build status
[01:17:20.858] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:20.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:33:0) after 1m0s
[01:18:20.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:33:0)
[01:18:20.905] Timer tick, asking Buildbucket for the build status
[01:18:20.972] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:20.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:34:0) after 1m0s
[01:19:20.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:34:0)
[01:19:20.991] Timer tick, asking Buildbucket for the build status
[01:19:21.064] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:21.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:35:0) after 1m0s
[01:20:21.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:35:0)
[01:20:21.087] Timer tick, asking Buildbucket for the build status
[01:20:21.161] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:21.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:36:0) after 1m0s
[01:21:21.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:36:0)
[01:21:21.186] Timer tick, asking Buildbucket for the build status
[01:21:21.280] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:21.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:37:0) after 1m0s
[01:22:21.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:37:0)
[01:22:21.300] Timer tick, asking Buildbucket for the build status
[01:22:21.373] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:21.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:38:0) after 1m0s
[01:23:21.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:38:0)
[01:23:21.432] Timer tick, asking Buildbucket for the build status
[01:23:21.483] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:21.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:39:0) after 1m0s
[01:24:21.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:39:0)
[01:24:21.502] Timer tick, asking Buildbucket for the build status
[01:24:21.564] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:21.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:40:0) after 1m0s
[01:25:21.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:40:0)
[01:25:21.584] Timer tick, asking Buildbucket for the build status
[01:25:21.643] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:21.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:41:0) after 1m0s
[01:26:21.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:41:0)
[01:26:21.780] Timer tick, asking Buildbucket for the build status
[01:26:21.844] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:21.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:42:0) after 1m0s
[01:27:21.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:42:0)
[01:27:21.861] Timer tick, asking Buildbucket for the build status
[01:27:21.925] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:21.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:43:0) after 1m0s
[01:28:21.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:43:0)
[01:28:21.947] Timer tick, asking Buildbucket for the build status
[01:28:22.069] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:22.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:44:0) after 1m0s
[01:29:22.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:44:0)
[01:29:22.103] Timer tick, asking Buildbucket for the build status
[01:29:22.175] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:22.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:45:0) after 1m0s
[01:30:22.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:45:0)
[01:30:22.214] Timer tick, asking Buildbucket for the build status
[01:30:22.297] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:22.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:46:0) after 1m0s
[01:31:22.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:46:0)
[01:31:22.431] Timer tick, asking Buildbucket for the build status
[01:31:22.546] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:22.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:47:0) after 1m0s
[01:32:22.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:47:0)
[01:32:22.586] Timer tick, asking Buildbucket for the build status
[01:32:22.640] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:22.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:48:0) after 1m0s
[01:33:22.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:48:0)
[01:33:22.663] Timer tick, asking Buildbucket for the build status
[01:33:22.826] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:22.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:49:0) after 1m0s
[01:34:22.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:49:0)
[01:34:22.852] Timer tick, asking Buildbucket for the build status
[01:34:22.891] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:22.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:50:0) after 1m0s
[01:35:22.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:50:0)
[01:35:22.914] Timer tick, asking Buildbucket for the build status
[01:35:22.994] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:22.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:51:0) after 1m0s
[01:36:23.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:51:0)
[01:36:23.012] Timer tick, asking Buildbucket for the build status
[01:36:23.161] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:23.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:52:0) after 1m0s
[01:37:23.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:52:0)
[01:37:23.184] Timer tick, asking Buildbucket for the build status
[01:37:23.251] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:23.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:53:0) after 1m0s
[01:38:23.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:53:0)
[01:38:23.282] Timer tick, asking Buildbucket for the build status
[01:38:23.350] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:23.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:54:0) after 1m0s
[01:39:23.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:54:0)
[01:39:23.504] Timer tick, asking Buildbucket for the build status
[01:39:23.563] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:23.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:55:0) after 1m0s
[01:40:23.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:55:0)
[01:40:23.589] Timer tick, asking Buildbucket for the build status
[01:40:23.639] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:23.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:56:0) after 1m0s
[01:41:23.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:56:0)
[01:41:23.661] Timer tick, asking Buildbucket for the build status
[01:41:23.760] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:23.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:57:0) after 1m0s
[01:42:23.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:57:0)
[01:42:23.781] Timer tick, asking Buildbucket for the build status
[01:42:23.868] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:23.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:58:0) after 1m0s
[01:43:23.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:58:0)
[01:43:23.887] Timer tick, asking Buildbucket for the build status
[01:43:23.924] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:23.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:59:0) after 1m0s
[01:44:23.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:59:0)
[01:44:23.948] Timer tick, asking Buildbucket for the build status
[01:44:24.180] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:24.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:60:0) after 1m0s
[01:45:24.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:60:0)
[01:45:24.210] Timer tick, asking Buildbucket for the build status
[01:45:24.309] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:24.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:61:0) after 1m0s
[01:46:24.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:61:0)
[01:46:24.333] Timer tick, asking Buildbucket for the build status
[01:46:24.369] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:24.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:62:0) after 1m0s
[01:47:24.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:62:0)
[01:47:24.394] Timer tick, asking Buildbucket for the build status
[01:47:24.594] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:24.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:63:0) after 1m0s
[01:48:24.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:63:0)
[01:48:24.761] Timer tick, asking Buildbucket for the build status
[01:48:24.946] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:24.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:64:0) after 1m0s
[01:49:24.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:64:0)
[01:49:24.969] Timer tick, asking Buildbucket for the build status
[01:49:25.045] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:25.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:65:0) after 1m0s
[01:50:25.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:65:0)
[01:50:25.172] Timer tick, asking Buildbucket for the build status
[01:50:25.281] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:66:0) after 1m0s
[01:51:25.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:66:0)
[01:51:25.302] Timer tick, asking Buildbucket for the build status
[01:51:25.354] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:25.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:67:0) after 1m0s
[01:52:25.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:67:0)
[01:52:25.480] Timer tick, asking Buildbucket for the build status
[01:52:25.534] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:25.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:68:0) after 1m0s
[01:53:25.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:68:0)
[01:53:25.661] Timer tick, asking Buildbucket for the build status
[01:53:25.762] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:25.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:69:0) after 1m0s
[01:54:25.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:69:0)
[01:54:25.892] Timer tick, asking Buildbucket for the build status
[01:54:26.046] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:70:0) after 1m0s
[01:55:26.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:70:0)
[01:55:26.072] Timer tick, asking Buildbucket for the build status
[01:55:26.135] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:26.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:71:0) after 1m0s
[01:56:26.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:71:0)
[01:56:26.173] Timer tick, asking Buildbucket for the build status
[01:56:26.213] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:72:0) after 1m0s
[01:57:26.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:72:0)
[01:57:26.321] Timer tick, asking Buildbucket for the build status
[01:57:26.529] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:26.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:73:0) after 1m0s
[01:58:26.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:73:0)
[01:58:26.555] Timer tick, asking Buildbucket for the build status
[01:58:26.622] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:26.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:74:0) after 1m0s
[01:59:26.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:74:0)
[01:59:26.644] Timer tick, asking Buildbucket for the build status
[01:59:26.709] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:26.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:75:0) after 1m0s
[02:00:26.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:75:0)
[02:00:26.730] Timer tick, asking Buildbucket for the build status
[02:00:26.792] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:26.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:76:0) after 1m0s
[02:01:26.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:76:0)
[02:01:26.814] Timer tick, asking Buildbucket for the build status
[02:01:27.025] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:27.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:77:0) after 1m0s
[02:02:27.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:77:0)
[02:02:27.061] Timer tick, asking Buildbucket for the build status
[02:02:27.152] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:27.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:78:0) after 1m0s
[02:03:27.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:78:0)
[02:03:27.191] Timer tick, asking Buildbucket for the build status
[02:03:27.268] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:27.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:79:0) after 1m0s
[02:04:27.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:79:0)
[02:04:27.289] Timer tick, asking Buildbucket for the build status
[02:04:27.487] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:27.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:80:0) after 1m0s
[02:05:27.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:80:0)
[02:05:27.510] Timer tick, asking Buildbucket for the build status
[02:05:27.703] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:27.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:81:0) after 1m0s
[02:06:27.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:81:0)
[02:06:27.760] Timer tick, asking Buildbucket for the build status
[02:06:27.857] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:82:0) after 1m0s
[02:07:27.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:82:0)
[02:07:27.886] Timer tick, asking Buildbucket for the build status
[02:07:28.006] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:28.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:83:0) after 1m0s
[02:08:28.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:83:0)
[02:08:28.027] Timer tick, asking Buildbucket for the build status
[02:08:28.206] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:28.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:84:0) after 1m0s
[02:09:28.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:84:0)
[02:09:28.227] Timer tick, asking Buildbucket for the build status
[02:09:28.294] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:28.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:85:0) after 1m0s
[02:10:28.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:85:0)
[02:10:28.377] Timer tick, asking Buildbucket for the build status
[02:10:28.654] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:28.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:86:0) after 1m0s
[02:11:28.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:86:0)
[02:11:28.674] Timer tick, asking Buildbucket for the build status
[02:11:28.717] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:28.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:87:0) after 1m0s
[02:12:28.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:87:0)
[02:12:28.737] Timer tick, asking Buildbucket for the build status
[02:12:28.988] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:28.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:88:0) after 1m0s
[02:13:29.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:88:0)
[02:13:29.018] Timer tick, asking Buildbucket for the build status
[02:13:29.598] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:29.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:89:0) after 1m0s
[02:14:29.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:89:0)
[02:14:29.620] Timer tick, asking Buildbucket for the build status
[02:14:29.690] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:29.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:90:0) after 1m0s
[02:15:29.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:90:0)
[02:15:29.710] Timer tick, asking Buildbucket for the build status
[02:15:29.784] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:29.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:91:0) after 1m0s
[02:16:29.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:91:0)
[02:16:29.815] Timer tick, asking Buildbucket for the build status
[02:16:29.893] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:29.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:92:0) after 1m0s
[02:17:29.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:92:0)
[02:17:29.920] Timer tick, asking Buildbucket for the build status
[02:17:29.985] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:29.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:93:0) after 1m0s
[02:18:30.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:93:0)
[02:18:30.013] Timer tick, asking Buildbucket for the build status
[02:18:30.114] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:30.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:94:0) after 1m0s
[02:19:30.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:94:0)
[02:19:30.202] Timer tick, asking Buildbucket for the build status
[02:19:30.352] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:30.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:95:0) after 1m0s
[02:20:30.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:95:0)
[02:20:30.377] Timer tick, asking Buildbucket for the build status
[02:20:30.558] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:30.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:96:0) after 1m0s
[02:21:30.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:96:0)
[02:21:30.681] Timer tick, asking Buildbucket for the build status
[02:21:31.194] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:31.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:97:0) after 1m0s
[02:22:31.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:97:0)
[02:22:31.215] Timer tick, asking Buildbucket for the build status
[02:22:32.215] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:32.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:98:0) after 1m0s
[02:23:32.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:98:0)
[02:23:32.236] Timer tick, asking Buildbucket for the build status
[02:23:32.365] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:32.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:99:0) after 1m0s
[02:24:32.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:99:0)
[02:24:32.387] Timer tick, asking Buildbucket for the build status
[02:24:33.917] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:33.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:100:0) after 1m0s
[02:25:33.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:100:0)
[02:25:33.954] Timer tick, asking Buildbucket for the build status
[02:25:34.097] Build 8934545934571685152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:34.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099975996575760224:101:0) after 1m0s
[02:25:52.986] Received PubSub notification, asking Buildbucket for the build status
[02:25:53.084] Build 8934545934571685152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:25:53.084] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1537755951763210",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1537750035655534",
"id": "8934545934571685152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7504077ef5191493914ebee0f03d5a897acb5ce6\"}}",
"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/8934545934571685152/+/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\": [\"3783-5e6adab\"], \"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\": \"9f85491d8830fe6f3b8e0efa2e954f71bd9c64af\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"7504077ef5191493914ebee0f03d5a897acb5ce6\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24780}\", \"$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@{#593463}\", \"branch\": \"refs/heads/master\", \"revision\": \"7504077ef5191493914ebee0f03d5a897acb5ce6\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"0ad0c27a0bcee24ec9dc5c27df090034e152bd65\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"55e57f96fbf84a7d42e807d25a304362b76da917\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1537750035655534, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/7504077ef5191493914ebee0f03d5a897acb5ce6\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099975996575760224\", \"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\": \"8934545934571685152\"}}, \"got_v8_revision\": \"2f22d11156ccb1f88587896c27151944cad48cba\", \"got_v8_revision_cp\": \"refs/heads/7.1.163@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10180, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"314761de097e67d00ada5f4aabf483290a4098d9\", \"notification_helper_unittests\": \"7e829213b6317a26cec297c142bf440b3bffd52e\", \"gpu_perftests\": \"75887b9f09125b71b6706cf008b1414929b89a39\", \"vr_common_unittests\": \"db3b8c314e2e88f9b836aefec3c3d0f2150ff276\", \"telemetry_unittests\": \"22f1c178adfb8c6fca25b5ca500481175a47779e\", \"load_library_perf_tests\": \"8d4762b7a4a084b6f986a705050f6849dcce96e3\", \"devtools_closure_compile\": \"4c14287de95e3ba0fa6108ba6cb04b37f0119f15\", \"chromedriver_py_tests\": \"7a5f83e2161cb49d9f1c3b74de9243ec613c77b4\", \"mojo_core_unittests\": \"02df1ba071024bcd3fe54c557a7cfa47b55953a0\", \"install_static_unittests\": \"db30dd750715021e0d3ad0427f8c3c0e883f1ba2\", \"leveldb_service_unittests\": \"60d2d8ebd93e6a89baf85c42902c7ef909d5e9a4\", \"mini_installer_tests\": \"6c4b6ac58cf65dabeb8718bc17d6f8ae0007cb43\", \"jingle_unittests\": \"9a5e17036d95078b248f5658c58deea76da8d249\", \"sbox_integration_tests\": \"4a923b829898ab00ffdc84750a1e0beb2c6ca19a\", \"angle_unittests\": \"36283d923d4014be8d56671705c88c87ad601a01\", \"cast_unittests\": \"e1b1f30de57255190d2ed1315600ade46dcda94e\", \"cc_unittests\": \"90f1f0cd53c6120716dd4229eba2105efbdd7f0b\", \"blink_common_unittests\": \"bd44ff080b86b5da281ea5ad0fd5e026dddaa2fc\", \"crashpad_tests\": \"cb61d015b365751d1a356bd65f7cd1adfa4598b8\", \"telemetry_perf_tests_without_chrome\": \"1ff02ba161f854b0a0f27da778812c4e7be0598d\", \"metrics_python_tests\": \"e95e6ad6e22e16e8380bffda0940094e78415b5f\", \"message_center_unittests\": \"2054d1523d088d25b68ebea5f8e33ebceb39dd9c\", \"ppapi_unittests\": \"cc8dcfb37a6a92c3e495d4033e88e30ef516691f\", \"extensions_unittests\": \"b55603da2b6b2291e852c341e65a301f41b49d42\", \"gl_unittests_ozone\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"app_shell_unittests\": \"113e1e22a8eb0eda012a80a361eca5bbe63b088f\", \"chrome_app_unittests\": \"5caf21058463312d5fed4e5fb1cb9ba2ecb119d1\", \"gl_unittests\": \"cb33148ad4c6dfa98676664abc7a76028a7bdb44\", \"gcm_unit_tests\": \"43ee812707947794de0fcc11f101d502c43340e8\", \"views_unittests\": \"a7643cfaa1fbd4ea7b85c5f93e97c594f67ea9f3\", \"content_shell_crash_test\": \"9371c97270759b9d64ea1dfe264a0f912d031510\", \"ui_base_unittests\": \"571ebd402575382f175e706dd6fd2362fd7920c0\", \"gles2_conform_test\": \"44e5605930fc986aa3d998038c43777ce4594430\", \"cronet_unittests\": \"bca59d50526c2b272553e57d3c9c64f066f561ef\", \"performance_test_suite\": \"2d17bfab7cc28755b85216f213be431012e2faa8\", \"wm_unittests\": \"6d81ccef56cbe8fc628870ba2ccbacb62a745030\", \"boringssl_crypto_tests\": \"42f0af3f82979a9893eac3c3643f76b84529b018\", \"gin_unittests\": \"16220a40eb407a8730506c3d2f6ed6f352e82b85\", \"performance_browser_tests\": \"dbfe9d2808f47ce01c7f312473540d36f4c991f2\", \"extensions_browsertests\": \"90dbdbae7ab8af5e94fdf55ca713ad76ba879c06\", \"webkit_python_tests\": \"d158224fa80fea9494613a4e2cc83c3710e1a2b2\", \"headless_unittests\": \"dc4ac7efe25a76997edf9ebcfdfab7a20092635f\", \"webkit_layout_tests\": \"42b0f6677d4213d61f1240dd96e6f5849809ee69\", \"vr_pixeltests\": \"c7b36001bf0543a3edad2ce27210064ed960ecef\", \"setup_unittests\": \"56b710e3858f41e578d21cafc12288fe8cede364\", \"webkit_unit_tests\": \"88841a0ea97fe066c9d6e519b5545a62f68f9c8f\", \"content_unittests\": \"b0e7f8ba9a4a35beba6c0538949f77a6fc8e3a96\", \"performance_webview_test_suite\": \"0397cdd4202052f339af5572d12a4bcfbe74cd83\", \"wtf_unittests\": \"6330a5dd1e366167efe69357e7de6901969305ce\", \"webkit_layout_tests_exparchive\": \"cabb909d6ffe5c711c8350b1647225a5f27776e1\", \"chrome_cleaner_unittests\": \"eb72f4c9469f60516caa1db856d6f444b41201c8\", \"traffic_annotation_auditor_unittests\": \"84f8f55a7ce53e8d25db995da87ee68e7f3a085e\", \"libjingle_xmpp_unittests\": \"c49e03c8a53c9da5f9ca94a7107852ee8b304bf9\", \"remoting_unittests\": \"b857e2a08ff90ca0be81357d2c6a70275f1a1f06\", \"vr_common_perftests\": \"7098ee888c4a743894fdf55d71666182b2fd2d23\", \"filesystem_service_unittests\": \"610cb3d5b1811147f3b61b816795fecc7356feb7\", \"chrome_elf_import_unittests\": \"70ab3cadb8f462dd5f896f13f5bfb0ab31a0fb0d\", \"installer_util_unittests\": \"8b9baa90163b5d64f67bebe0dde432b999d9edc0\", \"gfx_unittests\": \"7fd8dd90526223ea5785a35af0b15416cdb84d12\", \"headless_browsertests\": \"38ce9cba9dbc526f3fdfae22e4c2479458199b68\", \"capture_unittests\": \"518d4b9475a17da1cde25e23e318b02a66eb4858\", \"chrome_elf_unittests\": \"649c0966ce9489637572063a48c994e56df08aad\", \"telemetry_gpu_unittests\": \"4ac9381e6a8d96a78b0c3b3c7c9fa029d5f40814\", \"command_buffer_perftests\": \"b97d04e37c33e2ae8740760b1e34f1de506c567e\", \"media_unittests\": \"92ad1c5fb53859e8a622d3f3ac8bc976a04969a1\", \"chromedriver_unittests\": \"f8f845058506bb682338bdeaebbb355382e8c356\", \"views_mus_interactive_ui_tests\": \"cf198b71a31f6941b38f18630473a5be6c1843f6\", \"storage_unittests\": \"76b0f740408ff5af8eaf808a2993fcefb8180b6c\", \"media_service_unittests\": \"50f178e9d9b1242c24f9b6ccb84115fe5a70b37d\", \"compositor_unittests\": \"097352350326634427c0b03fc5b1abe842e9abf5\", \"latency_unittests\": \"69e025be9e309fddee93bf6d3b8c749fd19823ff\", \"video_decode_accelerator_unittest\": \"dc6a0e16949c563d8ebaf3df4a8609e283512761\", \"swiftshader_unittests\": \"878de22b3275f0ccb373e993ceeba1231407f3dd\", \"telemetry_perf_unittests\": \"1cda13b15d446812f64fd923008594c0495f5d71\", \"media_blink_unittests\": \"c545850e24c889a05fddc55d96d468bdfe9fb85e\", \"views_mus_unittests\": \"712caa446c8c3bbc32033f42156b86e77f844d4d\", \"components_unittests\": \"62a3a0bc2c8ba6ab42feb64d0a242565d156e4b8\", \"snapshot_unittests\": \"0c39568ec9a82d1c59378202eb8c11557f4e7f2b\", \"accessibility_unittests\": \"d24f6f2b39eb6004958bfa2924e52e2d37e19c57\", \"net_perftests\": \"40ec6344ecd55d2db0b2cea9826770b5078170e2\", \"aura_unittests\": \"92c031e2a5bb760fb96150861f23e257ac994d70\", \"services_unittests\": \"ce781da786ba30c91ebda02fe2c93dce7d6647b2\", \"components_perftests\": \"0bfe94c5833740bb205cd580a740ed166cd8e9ba\", \"events_unittests\": \"61478936914c00e63f29d019719fa99c2af95c9d\", \"tracing_perftests\": \"2e0057526c5b177d2e565b17036ddb960a1e0416\", \"chromedriver_replay_unittests\": \"3d75567c4cb5be67d5cf025731258bcd9f3d4b8a\", \"ui_touch_selection_unittests\": \"45db612be15175e731ce5d4e37e20b838e63b5ed\", \"cacheinvalidation_unittests\": \"470ce1496e645fb24338f72c0841750602a6619d\", \"viz_unittests\": \"19c20fc044f39caf1d775769d2832a01915bae0e\", \"browser_tests\": \"aa024cbaeebb7ef4d4bac999a6e61257195bffa3\", \"net_unittests\": \"4bbeec85fd7ae0ed2f8f7d9da683b137dc70fcd5\", \"midi_unittests\": \"9aae1a581541c8ef687cb66dae0e1ee7181debc8\", \"boringssl_ssl_tests\": \"0ba0fcaff6f265a48cc1c1f2b1babea798fea781\", \"sbox_validation_tests\": \"d15adb290a5bce94bc16392b15c5393ca62e6f1e\", \"elevation_service_unittests\": \"05a9f9d45ad20ac1b2cbebd9f184c74744da6254\", \"gpu_unittests\": \"ea28aee3e71ff16fab6e13a05beb3c6c50f6dbcb\", \"native_theme_unittests\": \"253f2428c006be9f266ff2fa78e9e7485a3f8b36\", \"interactive_ui_tests\": \"bb0625dd027d005399fcb65085660a5897a6f531\", \"mojo_unittests\": \"33620517a98a1b536e7e1fc928570830aa9e3d2b\", \"url_unittests\": \"09a06ab47fc2e88ae8de2230f772ff9ed5349be5\", \"media_perftests\": \"474fa4269211c0945306d30ce60507726c858dd0\", \"cronet_tests\": \"e7929eb7e48e0083193b7b9e67369af246b59f45\", \"google_apis_unittests\": \"5c675c442c67c89f956b23552d1b63cd9cd6c58d\", \"telemetry_gpu_integration_test\": \"d5dfec90e60aabb6cf6fbd60c99bc94c5c24067d\", \"blink_heap_unittests\": \"29f5f35ecb3aaebbaf774446bbcef22702e431dd\", \"base_unittests\": \"4443796ab94f8574ba4e0d6f8e73ba714db421a2\", \"sync_integration_tests\": \"8b94c825106c83fdb8c7f2aca048a609e7103847\", \"sql_unittests\": \"0c82cf8097d8ff4c73c58d453cda44d80ed15df7\", \"courgette_unittests\": \"82303ce9819980655b6189049f61d94926da0782\", \"blink_fuzzer_unittests\": \"01a2b69f4b10bac82890ed6695d6bca80af1e531\", \"gl_tests\": \"aad1fc63e0028fc93a81356594b16b19522e17cf\", \"zucchini_unittests\": \"5da8593b94100e441824f2ae1a55abfdbc07b9e4\", \"ipc_tests\": \"de87119a47f2b478e95081b6880e8a2e279c43a8\", \"angle_end2end_tests\": \"a8119e8ea4d599c672434baccf7f71381995a99a\", \"blink_platform_unittests\": \"dd60383f672836c167905f1e93fd64286297af57\", \"components_browsertests\": \"8738cd1cb4368959e25574bd3f09d3f7d63ebd47\", \"views_perftests\": \"00692d728f71d755a96ecb4a404c91a9ea7746fd\", \"angle_perftests\": \"55ec85e31e7aee4b59de1628a993aa9eff53b09a\", \"display_unittests\": \"5359341a8c6fa449ef4401f9abfb449039549f3b\", \"sbox_unittests\": \"258c2a9ba87697429803db4247c29be882a5b0d1\", \"service_manager_unittests\": \"7651c1a43b63d0f33b26cf2293b86a03c97b407a\", \"pdf_unittests\": \"4966aeff27af58f0eaca5bb09d8ee4d6307cc13a\", \"skia_unittests\": \"053a3987484fb4c0e54ec550e44662a99f84edd5\", \"angle_white_box_tests\": \"e167a97d329013427fd9085d2394caf7ef166b8d\", \"crypto_unittests\": \"6426c53042791fd08bea24507d020c953d5a5c62\", \"nacl_loader_unittests\": \"43613e5e0514f0d70d9533f7d2bd3d9a1a318ea4\", \"unit_tests\": \"9123025ec7f6b2d50bd09cd65a98ec1e4c526096\", \"content_browsertests\": \"02a5c012ad82462746e2793432b4c37f078c70f6\", \"printing_unittests\": \"88de371e60dd22e5eaf94b4d3d7cc0b6dc35699f\", \"device_unittests\": \"46b3d894cc22f1ccd87081e31ca9f5778ba6a971\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1537750037627604",
"status": "COMPLETED",
"status_changed_ts": "1537755952386083",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10180",
"builder:Windows deterministic",
"buildset:commit/git/7504077ef5191493914ebee0f03d5a897acb5ce6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099975996575760224",
"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/10180",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8934545934571685152",
"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/7504077ef5191493914ebee0f03d5a897acb5ce6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7504077ef5191493914ebee0f03d5a897acb5ce6",
"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/8934545934571685152/+/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:9099975996575760224",
"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:ded6e667eedfba9ca83cacf95f0d2164f0246aeb",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4021dcf851d8a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1537755952387842",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10180",
"utcnow_ts": "1537755953062391"
}
}
[02:25:53.084] Invocation finished in 1h38m38.723936952s with status SUCCEEDED