[23:32:52.259] New invocation is queued and will start shortly
[23:32:53.500] Starting the invocation (attempt 1)
[23:32:53.538] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:32:53.538] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:32:53.538] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9107862611522714016",
"parameters_json": "{\"builder_name\":\"Deterministic Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1f40cfd0e54a31751192c653400d563fe9227213\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Android",
"scheduler_invocation_id:9107862611522714016",
"scheduler_job_id:chromium/Deterministic Android",
"user_agent:luci-scheduler",
"buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213",
"gitiles_ref:refs/heads/master"
]
}
[23:32:54.536] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530228773640279",
"experimental": true,
"id": "8942432549409642496",
"lease_expiration_ts": "1530264774284784",
"lease_key": "1493371250",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1f40cfd0e54a31751192c653400d563fe9227213\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1530228774452096",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/1944",
"builder:Deterministic Android",
"buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107862611522714016",
"scheduler_job_id:chromium/Deterministic Android",
"swarming_dimension:builder:Deterministic Android",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Android/1944",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8942432549409642496",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Deterministic Android",
"swarming_tag:buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213",
"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/8942432549409642496/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"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:9107862611522714016",
"swarming_tag:scheduler_job_id:chromium/Deterministic Android",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e618f9cc20d5710",
"user_agent:luci-scheduler"
],
"updated_ts": "1530228774452189",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/1944",
"utcnow_ts": "1530228774527010"
}
}
[23:32:54.536] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/1944
[23:32:54.536] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:2:0) after 1m0s
[23:33:31.517] Received PubSub notification, asking Buildbucket for the build status
[23:33:31.584] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:2:0)
[23:33:54.555] Timer tick, asking Buildbucket for the build status
[23:33:54.595] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.595] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:4:0) after 1m0s
[23:34:54.615] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:4:0)
[23:34:54.615] Timer tick, asking Buildbucket for the build status
[23:34:54.910] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:54.910] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:5:0) after 1m0s
[23:35:54.932] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:5:0)
[23:35:54.932] Timer tick, asking Buildbucket for the build status
[23:35:55.045] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:55.045] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:6:0) after 1m0s
[23:36:55.151] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:6:0)
[23:36:55.151] Timer tick, asking Buildbucket for the build status
[23:36:55.229] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:55.229] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:7:0) after 1m0s
[23:37:55.314] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:7:0)
[23:37:55.314] Timer tick, asking Buildbucket for the build status
[23:37:55.375] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:55.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:8:0) after 1m0s
[23:38:55.396] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:8:0)
[23:38:55.396] Timer tick, asking Buildbucket for the build status
[23:38:55.520] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:55.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:9:0) after 1m0s
[23:39:55.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:9:0)
[23:39:55.542] Timer tick, asking Buildbucket for the build status
[23:39:55.857] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:55.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:10:0) after 1m0s
[23:40:55.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:10:0)
[23:40:55.888] Timer tick, asking Buildbucket for the build status
[23:40:56.018] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:56.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:11:0) after 1m0s
[23:41:56.057] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:11:0)
[23:41:56.057] Timer tick, asking Buildbucket for the build status
[23:41:56.118] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:56.119] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:12:0) after 1m0s
[23:42:56.168] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:12:0)
[23:42:56.168] Timer tick, asking Buildbucket for the build status
[23:42:56.237] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:56.237] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:13:0) after 1m0s
[23:43:56.256] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:13:0)
[23:43:56.256] Timer tick, asking Buildbucket for the build status
[23:43:56.318] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:14:0) after 1m0s
[23:44:56.338] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:14:0)
[23:44:56.338] Timer tick, asking Buildbucket for the build status
[23:44:56.400] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:56.400] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:15:0) after 1m0s
[23:45:56.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:15:0)
[23:45:56.424] Timer tick, asking Buildbucket for the build status
[23:45:56.585] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:56.585] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:16:0) after 1m0s
[23:46:56.609] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:16:0)
[23:46:56.609] Timer tick, asking Buildbucket for the build status
[23:46:56.706] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:56.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:17:0) after 1m0s
[23:47:56.730] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:17:0)
[23:47:56.730] Timer tick, asking Buildbucket for the build status
[23:47:56.884] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:56.884] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:18:0) after 1m0s
[23:48:56.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:18:0)
[23:48:56.906] Timer tick, asking Buildbucket for the build status
[23:48:56.962] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:56.962] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:19:0) after 1m0s
[23:49:56.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:19:0)
[23:49:56.985] Timer tick, asking Buildbucket for the build status
[23:49:57.060] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:57.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:20:0) after 1m0s
[23:50:57.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:20:0)
[23:50:57.213] Timer tick, asking Buildbucket for the build status
[23:50:57.285] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:57.285] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:21:0) after 1m0s
[23:51:57.313] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:21:0)
[23:51:57.313] Timer tick, asking Buildbucket for the build status
[23:51:57.369] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:57.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:22:0) after 1m0s
[23:52:57.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:22:0)
[23:52:57.390] Timer tick, asking Buildbucket for the build status
[23:52:57.466] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:57.466] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:23:0) after 1m0s
[23:53:57.486] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:23:0)
[23:53:57.486] Timer tick, asking Buildbucket for the build status
[23:53:57.543] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:57.543] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:24:0) after 1m0s
[23:54:57.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:24:0)
[23:54:57.573] Timer tick, asking Buildbucket for the build status
[23:54:57.785] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:57.785] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:25:0) after 1m0s
[23:55:57.817] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:25:0)
[23:55:57.817] Timer tick, asking Buildbucket for the build status
[23:55:57.869] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:57.869] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:26:0) after 1m0s
[23:56:57.903] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:26:0)
[23:56:57.903] Timer tick, asking Buildbucket for the build status
[23:56:58.425] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:27:0) after 1m0s
[23:57:58.516] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:27:0)
[23:57:58.516] Timer tick, asking Buildbucket for the build status
[23:57:58.636] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:58.636] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:28:0) after 1m0s
[23:58:58.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:28:0)
[23:58:58.655] Timer tick, asking Buildbucket for the build status
[23:58:58.747] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:58.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:29:0) after 1m0s
[23:59:58.765] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:29:0)
[23:59:58.765] Timer tick, asking Buildbucket for the build status
[23:59:58.906] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:58.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:30:0) after 1m0s
[00:00:58.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:30:0)
[00:00:58.948] Timer tick, asking Buildbucket for the build status
[00:00:59.019] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:59.019] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:31:0) after 1m0s
[00:01:59.047] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:31:0)
[00:01:59.047] Timer tick, asking Buildbucket for the build status
[00:01:59.102] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:59.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:32:0) after 1m0s
[00:02:59.126] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:32:0)
[00:02:59.126] Timer tick, asking Buildbucket for the build status
[00:02:59.195] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:59.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:33:0) after 1m0s
[00:03:59.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:33:0)
[00:03:59.219] Timer tick, asking Buildbucket for the build status
[00:03:59.318] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:59.318] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:34:0) after 1m0s
[00:04:59.378] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:34:0)
[00:04:59.378] Timer tick, asking Buildbucket for the build status
[00:04:59.442] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:59.442] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:35:0) after 1m0s
[00:05:59.484] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:35:0)
[00:05:59.484] Timer tick, asking Buildbucket for the build status
[00:05:59.710] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:59.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:36:0) after 1m0s
[00:06:59.733] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:36:0)
[00:06:59.733] Timer tick, asking Buildbucket for the build status
[00:06:59.886] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:59.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:37:0) after 1m0s
[00:07:59.909] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:37:0)
[00:07:59.909] Timer tick, asking Buildbucket for the build status
[00:07:59.961] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:59.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:38:0) after 1m0s
[00:08:59.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:38:0)
[00:08:59.981] Timer tick, asking Buildbucket for the build status
[00:09:00.109] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:00.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:39:0) after 1m0s
[00:10:00.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:39:0)
[00:10:00.130] Timer tick, asking Buildbucket for the build status
[00:10:00.186] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:00.186] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:40:0) after 1m0s
[00:11:00.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:40:0)
[00:11:00.213] Timer tick, asking Buildbucket for the build status
[00:11:00.373] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:00.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:41:0) after 1m0s
[00:12:00.397] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:41:0)
[00:12:00.397] Timer tick, asking Buildbucket for the build status
[00:12:00.556] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:00.556] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:42:0) after 1m0s
[00:13:00.577] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:42:0)
[00:13:00.577] Timer tick, asking Buildbucket for the build status
[00:13:00.632] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:00.632] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:43:0) after 1m0s
[00:14:00.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:43:0)
[00:14:00.746] Timer tick, asking Buildbucket for the build status
[00:14:00.948] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:00.948] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:44:0) after 1m0s
[00:15:00.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:44:0)
[00:15:00.982] Timer tick, asking Buildbucket for the build status
[00:15:01.118] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:01.118] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:45:0) after 1m0s
[00:16:01.146] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:45:0)
[00:16:01.146] Timer tick, asking Buildbucket for the build status
[00:16:01.212] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:01.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:46:0) after 1m0s
[00:17:01.234] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:46:0)
[00:17:01.234] Timer tick, asking Buildbucket for the build status
[00:17:01.275] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:01.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:47:0) after 1m0s
[00:18:01.318] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:47:0)
[00:18:01.318] Timer tick, asking Buildbucket for the build status
[00:18:01.362] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:01.362] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:48:0) after 1m0s
[00:19:01.396] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:48:0)
[00:19:01.396] Timer tick, asking Buildbucket for the build status
[00:19:01.451] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:01.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:49:0) after 1m0s
[00:20:01.474] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:49:0)
[00:20:01.474] Timer tick, asking Buildbucket for the build status
[00:20:01.909] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:01.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:50:0) after 1m0s
[00:21:01.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:50:0)
[00:21:01.931] Timer tick, asking Buildbucket for the build status
[00:21:01.968] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:01.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:51:0) after 1m0s
[00:22:01.992] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:51:0)
[00:22:01.992] Timer tick, asking Buildbucket for the build status
[00:22:02.056] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:02.056] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:52:0) after 1m0s
[00:23:02.078] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:52:0)
[00:23:02.078] Timer tick, asking Buildbucket for the build status
[00:23:02.158] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:02.158] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:53:0) after 1m0s
[00:24:02.183] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:53:0)
[00:24:02.183] Timer tick, asking Buildbucket for the build status
[00:24:02.410] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:02.410] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:54:0) after 1m0s
[00:25:02.576] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:54:0)
[00:25:02.576] Timer tick, asking Buildbucket for the build status
[00:25:02.658] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:02.658] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:55:0) after 1m0s
[00:26:02.680] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:55:0)
[00:26:02.680] Timer tick, asking Buildbucket for the build status
[00:26:02.780] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:02.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:56:0) after 1m0s
[00:27:02.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:56:0)
[00:27:02.820] Timer tick, asking Buildbucket for the build status
[00:27:02.875] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:02.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:57:0) after 1m0s
[00:28:02.898] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:57:0)
[00:28:02.898] Timer tick, asking Buildbucket for the build status
[00:28:03.027] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:03.027] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:58:0) after 1m0s
[00:29:03.050] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:58:0)
[00:29:03.050] Timer tick, asking Buildbucket for the build status
[00:29:03.081] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:03.081] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:59:0) after 1m0s
[00:30:03.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:59:0)
[00:30:03.105] Timer tick, asking Buildbucket for the build status
[00:30:06.745] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:06.745] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:60:0) after 1m0s
[00:31:06.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:60:0)
[00:31:06.770] Timer tick, asking Buildbucket for the build status
[00:31:06.826] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:06.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:61:0) after 1m0s
[00:32:07.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:61:0)
[00:32:07.013] Timer tick, asking Buildbucket for the build status
[00:32:07.151] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.151] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:62:0) after 1m0s
[00:33:07.174] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:62:0)
[00:33:07.174] Timer tick, asking Buildbucket for the build status
[00:33:07.264] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.264] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:63:0) after 1m0s
[00:34:07.284] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:63:0)
[00:34:07.284] Timer tick, asking Buildbucket for the build status
[00:34:07.491] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:07.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:64:0) after 1m0s
[00:35:07.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:64:0)
[00:35:07.521] Timer tick, asking Buildbucket for the build status
[00:35:07.619] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:07.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:65:0) after 1m0s
[00:36:07.641] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:65:0)
[00:36:07.641] Timer tick, asking Buildbucket for the build status
[00:36:07.726] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:07.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:66:0) after 1m0s
[00:37:07.865] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:66:0)
[00:37:07.865] Timer tick, asking Buildbucket for the build status
[00:37:08.002] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:08.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:67:0) after 1m0s
[00:38:08.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:67:0)
[00:38:08.032] Timer tick, asking Buildbucket for the build status
[00:38:08.372] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:08.372] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:68:0) after 1m0s
[00:39:08.397] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:68:0)
[00:39:08.397] Timer tick, asking Buildbucket for the build status
[00:39:08.460] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:08.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:69:0) after 1m0s
[00:40:08.498] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:69:0)
[00:40:08.498] Timer tick, asking Buildbucket for the build status
[00:40:08.625] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:08.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:70:0) after 1m0s
[00:41:08.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:70:0)
[00:41:08.650] Timer tick, asking Buildbucket for the build status
[00:41:08.820] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:08.820] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:71:0) after 1m0s
[00:42:08.871] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:71:0)
[00:42:08.871] Timer tick, asking Buildbucket for the build status
[00:42:08.933] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:08.933] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:72:0) after 1m0s
[00:43:08.954] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:72:0)
[00:43:08.954] Timer tick, asking Buildbucket for the build status
[00:43:09.035] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:09.035] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:73:0) after 1m0s
[00:44:09.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:73:0)
[00:44:09.061] Timer tick, asking Buildbucket for the build status
[00:44:09.120] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:09.120] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:74:0) after 1m0s
[00:45:09.145] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:74:0)
[00:45:09.145] Timer tick, asking Buildbucket for the build status
[00:45:09.272] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:09.272] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:75:0) after 1m0s
[00:46:09.305] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:75:0)
[00:46:09.305] Timer tick, asking Buildbucket for the build status
[00:46:09.366] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:09.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:76:0) after 1m0s
[00:47:09.389] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:76:0)
[00:47:09.389] Timer tick, asking Buildbucket for the build status
[00:47:09.453] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:09.453] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:77:0) after 1m0s
[00:48:09.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:77:0)
[00:48:09.473] Timer tick, asking Buildbucket for the build status
[00:48:09.551] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:78:0) after 1m0s
[00:49:09.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:78:0)
[00:49:09.573] Timer tick, asking Buildbucket for the build status
[00:49:09.642] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:09.642] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:79:0) after 1m0s
[00:50:09.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:79:0)
[00:50:09.667] Timer tick, asking Buildbucket for the build status
[00:50:09.752] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:09.752] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:80:0) after 1m0s
[00:51:09.780] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:80:0)
[00:51:09.780] Timer tick, asking Buildbucket for the build status
[00:51:09.858] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:09.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:81:0) after 1m0s
[00:52:09.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:81:0)
[00:52:09.879] Timer tick, asking Buildbucket for the build status
[00:52:10.037] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:10.037] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:82:0) after 1m0s
[00:53:10.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:82:0)
[00:53:10.061] Timer tick, asking Buildbucket for the build status
[00:53:10.092] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:83:0) after 1m0s
[00:54:10.201] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:83:0)
[00:54:10.201] Timer tick, asking Buildbucket for the build status
[00:54:10.261] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:10.261] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:84:0) after 1m0s
[00:55:10.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:84:0)
[00:55:10.422] Timer tick, asking Buildbucket for the build status
[00:55:10.627] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:10.628] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:85:0) after 1m0s
[00:56:10.652] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:85:0)
[00:56:10.652] Timer tick, asking Buildbucket for the build status
[00:56:10.708] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:10.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:86:0) after 1m0s
[00:57:10.733] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:86:0)
[00:57:10.733] Timer tick, asking Buildbucket for the build status
[00:57:10.783] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:10.783] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:87:0) after 1m0s
[00:58:10.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:87:0)
[00:58:10.809] Timer tick, asking Buildbucket for the build status
[00:58:10.858] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:10.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:88:0) after 1m0s
[00:59:10.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:88:0)
[00:59:10.884] Timer tick, asking Buildbucket for the build status
[00:59:10.939] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:10.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:89:0) after 1m0s
[01:00:10.991] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:89:0)
[01:00:10.991] Timer tick, asking Buildbucket for the build status
[01:00:11.039] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:11.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:90:0) after 1m0s
[01:01:11.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:90:0)
[01:01:11.061] Timer tick, asking Buildbucket for the build status
[01:01:11.192] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:11.192] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:91:0) after 1m0s
[01:02:11.217] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:91:0)
[01:02:11.217] Timer tick, asking Buildbucket for the build status
[01:02:11.252] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:11.252] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:92:0) after 1m0s
[01:03:11.277] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:92:0)
[01:03:11.277] Timer tick, asking Buildbucket for the build status
[01:03:11.392] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:11.392] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:93:0) after 1m0s
[01:04:11.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:93:0)
[01:04:11.424] Timer tick, asking Buildbucket for the build status
[01:04:11.460] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:11.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:94:0) after 1m0s
[01:05:11.564] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:94:0)
[01:05:11.564] Timer tick, asking Buildbucket for the build status
[01:05:11.657] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:11.657] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:95:0) after 1m0s
[01:06:11.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:95:0)
[01:06:11.682] Timer tick, asking Buildbucket for the build status
[01:06:11.750] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:11.750] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:96:0) after 1m0s
[01:07:11.776] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:96:0)
[01:07:11.776] Timer tick, asking Buildbucket for the build status
[01:07:11.840] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:11.840] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:97:0) after 1m0s
[01:08:11.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:97:0)
[01:08:11.866] Timer tick, asking Buildbucket for the build status
[01:08:12.063] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:12.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:98:0) after 1m0s
[01:09:12.111] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:98:0)
[01:09:12.111] Timer tick, asking Buildbucket for the build status
[01:09:12.146] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:12.146] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:99:0) after 1m0s
[01:10:12.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:99:0)
[01:10:12.166] Timer tick, asking Buildbucket for the build status
[01:10:12.223] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:12.223] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:100:0) after 1m0s
[01:11:12.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:100:0)
[01:11:12.247] Timer tick, asking Buildbucket for the build status
[01:11:12.285] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:12.285] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:101:0) after 1m0s
[01:12:12.663] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:101:0)
[01:12:12.663] Timer tick, asking Buildbucket for the build status
[01:12:12.766] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:12.766] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:102:0) after 1m0s
[01:13:12.795] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:102:0)
[01:13:12.795] Timer tick, asking Buildbucket for the build status
[01:13:12.994] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:12.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:103:0) after 1m0s
[01:14:13.029] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:103:0)
[01:14:13.029] Timer tick, asking Buildbucket for the build status
[01:14:13.084] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:13.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:104:0) after 1m0s
[01:15:13.106] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:104:0)
[01:15:13.106] Timer tick, asking Buildbucket for the build status
[01:15:13.144] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:13.144] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:105:0) after 1m0s
[01:16:13.251] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:105:0)
[01:16:13.251] Timer tick, asking Buildbucket for the build status
[01:16:13.409] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:13.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:106:0) after 1m0s
[01:17:13.434] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:106:0)
[01:17:13.434] Timer tick, asking Buildbucket for the build status
[01:17:13.469] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:13.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:107:0) after 1m0s
[01:18:13.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:107:0)
[01:18:13.563] Timer tick, asking Buildbucket for the build status
[01:18:13.604] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:13.604] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:108:0) after 1m0s
[01:19:13.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:108:0)
[01:19:13.681] Timer tick, asking Buildbucket for the build status
[01:19:13.737] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:13.737] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:109:0) after 1m0s
[01:20:13.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:109:0)
[01:20:13.758] Timer tick, asking Buildbucket for the build status
[01:20:13.808] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:13.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:110:0) after 1m0s
[01:21:13.829] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:110:0)
[01:21:13.829] Timer tick, asking Buildbucket for the build status
[01:21:13.871] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:13.871] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:111:0) after 1m0s
[01:22:13.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:111:0)
[01:22:13.901] Timer tick, asking Buildbucket for the build status
[01:22:13.954] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:13.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:112:0) after 1m0s
[01:23:14.005] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:112:0)
[01:23:14.005] Timer tick, asking Buildbucket for the build status
[01:23:14.059] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:14.059] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:113:0) after 1m0s
[01:24:14.224] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:113:0)
[01:24:14.224] Timer tick, asking Buildbucket for the build status
[01:24:14.313] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:14.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:114:0) after 1m0s
[01:25:14.401] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:114:0)
[01:25:14.401] Timer tick, asking Buildbucket for the build status
[01:25:14.438] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:115:0) after 1m0s
[01:26:14.471] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:115:0)
[01:26:14.471] Timer tick, asking Buildbucket for the build status
[01:26:14.533] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.533] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:116:0) after 1m0s
[01:27:14.558] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:116:0)
[01:27:14.558] Timer tick, asking Buildbucket for the build status
[01:27:14.618] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:14.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:117:0) after 1m0s
[01:28:14.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:117:0)
[01:28:14.642] Timer tick, asking Buildbucket for the build status
[01:28:14.697] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:14.697] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:118:0) after 1m0s
[01:29:14.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:118:0)
[01:29:14.724] Timer tick, asking Buildbucket for the build status
[01:29:14.785] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:14.785] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:119:0) after 1m0s
[01:30:14.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:119:0)
[01:30:14.810] Timer tick, asking Buildbucket for the build status
[01:30:14.937] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:14.937] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:120:0) after 1m0s
[01:31:14.958] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:120:0)
[01:31:14.958] Timer tick, asking Buildbucket for the build status
[01:31:15.063] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:15.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:121:0) after 1m0s
[01:32:15.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:121:0)
[01:32:15.085] Timer tick, asking Buildbucket for the build status
[01:32:15.140] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:15.140] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:122:0) after 1m0s
[01:33:15.211] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:122:0)
[01:33:15.211] Timer tick, asking Buildbucket for the build status
[01:33:15.278] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:15.278] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:123:0) after 1m0s
[01:34:15.302] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:123:0)
[01:34:15.302] Timer tick, asking Buildbucket for the build status
[01:34:15.469] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:15.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:124:0) after 1m0s
[01:35:15.492] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:124:0)
[01:35:15.492] Timer tick, asking Buildbucket for the build status
[01:35:15.917] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:15.917] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:125:0) after 1m0s
[01:36:15.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:125:0)
[01:36:15.963] Timer tick, asking Buildbucket for the build status
[01:36:16.171] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:16.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:126:0) after 1m0s
[01:37:16.228] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:126:0)
[01:37:16.228] Timer tick, asking Buildbucket for the build status
[01:37:16.302] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:16.302] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:127:0) after 1m0s
[01:38:16.325] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:127:0)
[01:38:16.325] Timer tick, asking Buildbucket for the build status
[01:38:16.413] Build 8942432549409642496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:16.413] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9107862611522714016:128:0) after 1m0s
[01:38:59.681] Received PubSub notification, asking Buildbucket for the build status
[01:38:59.726] Build 8942432549409642496: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[01:38:59.726] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1530236338512509",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1530228773640279",
"experimental": true,
"failure_reason": "INFRA_FAILURE",
"id": "8942432549409642496",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1f40cfd0e54a31751192c653400d563fe9227213\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\\nFailure Uncaught Exception\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"recipe infra failure: Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\", \"bootstrapperCallStack\": [], \"type\": \"RECIPE_INFRA_FAILURE\"}, \"recipeResult\": {\"failure\": {\"exception\": {\"traceback\": [\"Traceback (most recent call last):\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/run.py\\\", line 287, in _new_run\", \" recipe_result = recipe_script.run(api, self.properties, self.environ)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 108, in run\", \" self.run_steps, properties, environ, self.PROPERTIES, api=api)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 636, in invoke_with_properties\", \" arg_names, **additional_args)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/loader.py\\\", line 599, in _invoke_with_properties\", \" return callable_obj(*props, **additional_args)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipes/swarming/deterministic_build.py\\\", line 194, in RunSteps\", \" str(api.chromium.output_dir).rstrip('\\\\\\\\/') + '.2')\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/isolate/api.py\\\", line 350, in compare_build_artifacts\", \" self.archive_differences(first_dir, second_dir, step_result.json.output)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/recipe_engine/recipe_engine/recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/isolate/api.py\\\", line 302, in archive_differences\", \" diffs = list(itertools.chain.from_iterable(values.itervalues()))\", \"AttributeError: 'NoneType' object has no attribute 'itervalues'\"]}, \"humanReason\": \"Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8942432549409642496/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Android\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3675-21ffa58\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"swarm628-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"030b88a205c9ce9a82d68798bfaa059fd55216cd\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"1f40cfd0e54a31751192c653400d563fe9227213\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23773}\", \"$build/goma\": {\"jobs\": 150}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_src_revision_cp\": \"refs/heads/master@{#571323}\", \"got_src_revision\": \"1f40cfd0e54a31751192c653400d563fe9227213\", \"got_revision_cp\": \"refs/heads/master@{#571323}\", \"branch\": \"refs/heads/master\", \"revision\": \"1f40cfd0e54a31751192c653400d563fe9227213\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Android\", \"got_webrtc_revision\": \"0601d68ac85e201d7bf377373daf249c07a7ab74\", \"mastername\": \"chromium.android\", \"got_angle_revision\": \"fa5d84be414528212b9aaee23551c8dc1971ad38\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1530228773640279, \"tags\": [\"builder:Deterministic Android\", \"buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9107862611522714016\", \"scheduler_job_id:chromium/Deterministic Android\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8942432549409642496\"}}, \"got_v8_revision\": \"d3714bb851cd75866f115e60aeb3b9ff99bcbb6f\", \"got_v8_revision_cp\": \"refs/heads/6.9.237@{#2}\", \"$recipe_engine/runtime\": {\"is_experimental\": true, \"is_luci\": true}, \"buildnumber\": 1944, \"path_config\": \"generic\", \"bot_id\": \"swarm628-c4\", \"got_buildtools_revision\": \"9c9fd97928dd45a0d9738af90f7c03b082f1216f\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1530228810609353",
"status": "COMPLETED",
"status_changed_ts": "1530236339191970",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/1944",
"builder:Deterministic Android",
"buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9107862611522714016",
"scheduler_job_id:chromium/Deterministic Android",
"swarming_dimension:builder:Deterministic Android",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Android/1944",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8942432549409642496",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Deterministic Android",
"swarming_tag:buildset:commit/git/1f40cfd0e54a31751192c653400d563fe9227213",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f40cfd0e54a31751192c653400d563fe9227213",
"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/8942432549409642496/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"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:9107862611522714016",
"swarming_tag:scheduler_job_id:chromium/Deterministic Android",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e618f9cc20d5710",
"user_agent:luci-scheduler"
],
"updated_ts": "1530236339193197",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/1944",
"utcnow_ts": "1530236339712094"
}
}
[01:38:59.726] Invocation finished in 2h6m7.484194693s with status FAILED