[10:52:52.345] New invocation is queued and will start shortly
[10:52:53.637] Starting the invocation (attempt 1)
[10:52:53.680] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:52:53.681] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:52:53.681] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9106279681155951712",
"parameters_json": "{\"builder_name\":\"Deterministic Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Android",
"scheduler_invocation_id:9106279681155951712",
"scheduler_job_id:chromium/Deterministic Android",
"user_agent:luci-scheduler",
"buildset:commit/git/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"gitiles_ref:refs/heads/master"
]
}
[10:52:56.737] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531738373757170",
"experimental": true,
"id": "8940849618957138688",
"lease_expiration_ts": "1531774375937200",
"lease_key": "1680050580",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1531738376526330",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/2120",
"builder:Deterministic Android",
"buildset:commit/git/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106279681155951712",
"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/2120",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8940849618957138688",
"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/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"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/8940849618957138688/+/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:9106279681155951712",
"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:cb76dd9a681a4367362c098d4bb68c8c82db75c2",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ebb8a4e3af43210",
"user_agent:luci-scheduler"
],
"updated_ts": "1531738376526480",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/2120",
"utcnow_ts": "1531738376689010"
}
}
[10:52:56.737] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/2120
[10:52:56.737] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:2:0) after 1m0s
[10:53:26.715] Received PubSub notification, asking Buildbucket for the build status
[10:53:26.793] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:56.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:2:0)
[10:53:56.758] Timer tick, asking Buildbucket for the build status
[10:53:56.861] Build 8940849618957138688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:53:56.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:4:0) after 1m0s
[10:54:56.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:4:0)
[10:54:56.901] Timer tick, asking Buildbucket for the build status
[10:54:57.106] Build 8940849618957138688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:54:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:5:0) after 1m0s
[10:55:04.026] Received PubSub notification, asking Buildbucket for the build status
[10:55:04.068] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:57.138] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:5:0)
[10:55:57.138] Timer tick, asking Buildbucket for the build status
[10:55:57.417] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:57.417] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:7:0) after 1m0s
[10:56:57.438] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:7:0)
[10:56:57.438] Timer tick, asking Buildbucket for the build status
[10:56:57.473] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:57.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:8:0) after 1m0s
[10:57:57.496] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:8:0)
[10:57:57.496] Timer tick, asking Buildbucket for the build status
[10:57:57.687] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:57.687] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:9:0) after 1m0s
[10:58:57.703] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:9:0)
[10:58:57.703] Timer tick, asking Buildbucket for the build status
[10:58:57.798] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.798] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:10:0) after 1m0s
[10:59:57.816] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:10:0)
[10:59:57.816] Timer tick, asking Buildbucket for the build status
[10:59:57.989] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:57.989] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:11:0) after 1m0s
[11:00:58.010] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:11:0)
[11:00:58.010] Timer tick, asking Buildbucket for the build status
[11:00:58.086] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:58.086] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:12:0) after 1m0s
[11:01:58.110] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:12:0)
[11:01:58.110] Timer tick, asking Buildbucket for the build status
[11:01:58.162] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:58.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:13:0) after 1m0s
[11:02:58.183] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:13:0)
[11:02:58.183] Timer tick, asking Buildbucket for the build status
[11:02:58.592] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:58.592] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:14:0) after 1m0s
[11:03:58.627] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:14:0)
[11:03:58.627] Timer tick, asking Buildbucket for the build status
[11:03:59.491] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:59.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:15:0) after 1m0s
[11:04:59.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:15:0)
[11:04:59.545] Timer tick, asking Buildbucket for the build status
[11:04:59.644] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:59.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:16:0) after 1m0s
[11:05:59.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:16:0)
[11:05:59.676] Timer tick, asking Buildbucket for the build status
[11:05:59.715] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:59.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:17:0) after 1m0s
[11:06:59.737] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:17:0)
[11:06:59.737] Timer tick, asking Buildbucket for the build status
[11:06:59.934] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:18:0) after 1m0s
[11:07:59.956] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:18:0)
[11:07:59.956] Timer tick, asking Buildbucket for the build status
[11:08:00.018] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:00.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:19:0) after 1m0s
[11:09:00.038] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:19:0)
[11:09:00.038] Timer tick, asking Buildbucket for the build status
[11:09:00.183] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:00.183] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:20:0) after 1m0s
[11:10:00.244] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:20:0)
[11:10:00.244] Timer tick, asking Buildbucket for the build status
[11:10:01.170] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:01.170] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:21:0) after 1m0s
[11:11:01.189] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:21:0)
[11:11:01.189] Timer tick, asking Buildbucket for the build status
[11:11:01.247] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:01.247] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:22:0) after 1m0s
[11:12:01.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:22:0)
[11:12:01.268] Timer tick, asking Buildbucket for the build status
[11:12:01.305] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:01.305] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:23:0) after 1m0s
[11:13:01.343] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:23:0)
[11:13:01.343] Timer tick, asking Buildbucket for the build status
[11:13:01.685] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:01.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:24:0) after 1m0s
[11:14:01.703] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:24:0)
[11:14:01.703] Timer tick, asking Buildbucket for the build status
[11:14:01.755] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:01.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:25:0) after 1m0s
[11:15:01.781] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:25:0)
[11:15:01.781] Timer tick, asking Buildbucket for the build status
[11:15:01.817] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:01.817] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:26:0) after 1m0s
[11:16:01.843] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:26:0)
[11:16:01.843] Timer tick, asking Buildbucket for the build status
[11:16:01.937] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:01.937] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:27:0) after 1m0s
[11:17:13.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:27:0)
[11:17:13.391] Timer tick, asking Buildbucket for the build status
[11:17:13.482] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:13.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:28:0) after 1m0s
[11:18:13.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:28:0)
[11:18:13.508] Timer tick, asking Buildbucket for the build status
[11:18:13.586] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:13.586] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:29:0) after 1m0s
[11:19:13.619] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:29:0)
[11:19:13.619] Timer tick, asking Buildbucket for the build status
[11:19:13.760] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:13.761] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:30:0) after 1m0s
[11:20:13.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:30:0)
[11:20:13.783] Timer tick, asking Buildbucket for the build status
[11:20:13.820] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:13.821] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:31:0) after 1m0s
[11:21:13.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:31:0)
[11:21:13.945] Timer tick, asking Buildbucket for the build status
[11:21:13.999] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:13.999] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:32:0) after 1m0s
[11:22:14.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:32:0)
[11:22:14.102] Timer tick, asking Buildbucket for the build status
[11:22:14.256] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:14.256] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:33:0) after 1m0s
[11:23:14.278] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:33:0)
[11:23:14.278] Timer tick, asking Buildbucket for the build status
[11:23:14.325] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:14.325] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:34:0) after 1m0s
[11:24:14.371] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:34:0)
[11:24:14.371] Timer tick, asking Buildbucket for the build status
[11:24:14.436] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:14.436] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:35:0) after 1m0s
[11:25:14.460] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:35:0)
[11:25:14.460] Timer tick, asking Buildbucket for the build status
[11:25:14.512] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:36:0) after 1m0s
[11:26:14.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:36:0)
[11:26:14.534] Timer tick, asking Buildbucket for the build status
[11:26:14.570] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:14.570] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:37:0) after 1m0s
[11:27:14.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:37:0)
[11:27:14.645] Timer tick, asking Buildbucket for the build status
[11:27:14.727] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:14.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:38:0) after 1m0s
[11:28:14.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:38:0)
[11:28:14.746] Timer tick, asking Buildbucket for the build status
[11:28:14.945] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:39:0) after 1m0s
[11:29:14.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:39:0)
[11:29:14.962] Timer tick, asking Buildbucket for the build status
[11:29:15.031] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:15.031] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:40:0) after 1m0s
[11:30:15.053] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:40:0)
[11:30:15.053] Timer tick, asking Buildbucket for the build status
[11:30:15.114] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:15.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:41:0) after 1m0s
[11:31:15.141] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:41:0)
[11:31:15.141] Timer tick, asking Buildbucket for the build status
[11:31:15.360] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:15.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:42:0) after 1m0s
[11:32:15.393] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:42:0)
[11:32:15.393] Timer tick, asking Buildbucket for the build status
[11:32:15.439] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:15.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:43:0) after 1m0s
[11:33:15.464] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:43:0)
[11:33:15.464] Timer tick, asking Buildbucket for the build status
[11:33:15.519] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:15.519] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:44:0) after 1m0s
[11:34:15.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:44:0)
[11:34:15.555] Timer tick, asking Buildbucket for the build status
[11:34:15.624] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:15.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:45:0) after 1m0s
[11:35:15.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:45:0)
[11:35:15.640] Timer tick, asking Buildbucket for the build status
[11:35:15.701] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:15.701] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:46:0) after 1m0s
[11:36:15.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:46:0)
[11:36:15.720] Timer tick, asking Buildbucket for the build status
[11:36:16.448] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:16.448] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:47:0) after 1m0s
[11:37:16.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:47:0)
[11:37:16.469] Timer tick, asking Buildbucket for the build status
[11:37:16.519] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:16.519] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:48:0) after 1m0s
[11:38:16.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:48:0)
[11:38:16.645] Timer tick, asking Buildbucket for the build status
[11:38:16.679] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:16.679] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:49:0) after 1m0s
[11:39:16.697] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:49:0)
[11:39:16.697] Timer tick, asking Buildbucket for the build status
[11:39:16.811] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:16.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:50:0) after 1m0s
[11:40:16.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:50:0)
[11:40:16.828] Timer tick, asking Buildbucket for the build status
[11:40:16.908] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:16.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:51:0) after 1m0s
[11:41:16.927] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:51:0)
[11:41:16.927] Timer tick, asking Buildbucket for the build status
[11:41:16.988] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:16.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:52:0) after 1m0s
[11:42:17.008] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:52:0)
[11:42:17.008] Timer tick, asking Buildbucket for the build status
[11:42:17.102] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:17.102] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:53:0) after 1m0s
[11:43:17.119] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:53:0)
[11:43:17.119] Timer tick, asking Buildbucket for the build status
[11:43:17.494] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:17.494] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:54:0) after 1m0s
[11:44:17.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:54:0)
[11:44:17.519] Timer tick, asking Buildbucket for the build status
[11:44:17.596] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:17.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:55:0) after 1m0s
[11:45:17.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:55:0)
[11:45:17.614] Timer tick, asking Buildbucket for the build status
[11:45:17.667] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:17.667] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:56:0) after 1m0s
[11:46:17.685] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:56:0)
[11:46:17.685] Timer tick, asking Buildbucket for the build status
[11:46:18.250] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:18.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:57:0) after 1m0s
[11:47:18.285] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:57:0)
[11:47:18.285] Timer tick, asking Buildbucket for the build status
[11:47:18.359] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:18.359] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:58:0) after 1m0s
[11:48:18.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:58:0)
[11:48:18.376] Timer tick, asking Buildbucket for the build status
[11:48:18.559] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:59:0) after 1m0s
[11:49:18.580] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:59:0)
[11:49:18.580] Timer tick, asking Buildbucket for the build status
[11:49:18.616] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:18.616] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:60:0) after 1m0s
[11:50:18.635] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:60:0)
[11:50:18.635] Timer tick, asking Buildbucket for the build status
[11:50:18.953] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:18.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:61:0) after 1m0s
[11:51:19.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:61:0)
[11:51:19.002] Timer tick, asking Buildbucket for the build status
[11:51:19.091] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:19.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:62:0) after 1m0s
[11:52:19.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:62:0)
[11:52:19.121] Timer tick, asking Buildbucket for the build status
[11:52:19.155] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:19.155] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:63:0) after 1m0s
[11:53:19.271] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:63:0)
[11:53:19.271] Timer tick, asking Buildbucket for the build status
[11:53:19.375] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:19.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:64:0) after 1m0s
[11:54:19.426] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:64:0)
[11:54:19.426] Timer tick, asking Buildbucket for the build status
[11:54:19.547] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:19.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:65:0) after 1m0s
[11:55:19.606] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:65:0)
[11:55:19.606] Timer tick, asking Buildbucket for the build status
[11:55:19.697] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:19.697] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:66:0) after 1m0s
[11:56:19.714] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:66:0)
[11:56:19.714] Timer tick, asking Buildbucket for the build status
[11:56:19.754] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:19.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:67:0) after 1m0s
[11:57:19.773] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:67:0)
[11:57:19.773] Timer tick, asking Buildbucket for the build status
[11:57:20.192] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:20.192] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:68:0) after 1m0s
[11:58:20.302] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:68:0)
[11:58:20.302] Timer tick, asking Buildbucket for the build status
[11:58:20.380] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:69:0) after 1m0s
[11:59:20.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:69:0)
[11:59:20.399] Timer tick, asking Buildbucket for the build status
[11:59:20.483] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:20.483] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:70:0) after 1m0s
[12:00:20.565] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:70:0)
[12:00:20.565] Timer tick, asking Buildbucket for the build status
[12:00:20.683] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:20.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:71:0) after 1m0s
[12:01:20.829] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:71:0)
[12:01:20.829] Timer tick, asking Buildbucket for the build status
[12:01:20.931] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:20.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:72:0) after 1m0s
[12:02:20.953] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:72:0)
[12:02:20.953] Timer tick, asking Buildbucket for the build status
[12:02:21.029] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:21.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:73:0) after 1m0s
[12:03:21.049] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:73:0)
[12:03:21.049] Timer tick, asking Buildbucket for the build status
[12:03:21.122] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:21.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:74:0) after 1m0s
[12:04:21.145] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:74:0)
[12:04:21.145] Timer tick, asking Buildbucket for the build status
[12:04:21.466] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:21.466] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:75:0) after 1m0s
[12:05:21.495] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:75:0)
[12:05:21.495] Timer tick, asking Buildbucket for the build status
[12:05:21.536] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:21.536] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:76:0) after 1m0s
[12:06:21.558] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:76:0)
[12:06:21.558] Timer tick, asking Buildbucket for the build status
[12:06:21.644] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:21.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:77:0) after 1m0s
[12:07:21.666] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:77:0)
[12:07:21.666] Timer tick, asking Buildbucket for the build status
[12:07:21.818] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:21.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:78:0) after 1m0s
[12:08:21.840] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:78:0)
[12:08:21.840] Timer tick, asking Buildbucket for the build status
[12:08:21.898] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:21.898] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:79:0) after 1m0s
[12:09:21.925] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:79:0)
[12:09:21.925] Timer tick, asking Buildbucket for the build status
[12:09:22.029] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:22.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:80:0) after 1m0s
[12:10:22.134] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:80:0)
[12:10:22.134] Timer tick, asking Buildbucket for the build status
[12:10:22.229] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:22.229] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:81:0) after 1m0s
[12:11:22.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:81:0)
[12:11:22.257] Timer tick, asking Buildbucket for the build status
[12:11:22.324] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:22.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:82:0) after 1m0s
[12:12:22.363] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:82:0)
[12:12:22.363] Timer tick, asking Buildbucket for the build status
[12:12:22.435] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:22.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:83:0) after 1m0s
[12:13:22.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:83:0)
[12:13:22.488] Timer tick, asking Buildbucket for the build status
[12:13:22.695] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:22.695] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:84:0) after 1m0s
[12:14:22.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:84:0)
[12:14:22.718] Timer tick, asking Buildbucket for the build status
[12:14:22.870] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:22.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:85:0) after 1m0s
[12:15:22.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:85:0)
[12:15:22.951] Timer tick, asking Buildbucket for the build status
[12:15:22.998] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:22.998] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:86:0) after 1m0s
[12:16:23.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:86:0)
[12:16:23.027] Timer tick, asking Buildbucket for the build status
[12:16:23.089] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:23.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:87:0) after 1m0s
[12:17:23.110] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:87:0)
[12:17:23.110] Timer tick, asking Buildbucket for the build status
[12:17:23.147] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:23.147] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:88:0) after 1m0s
[12:18:23.168] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:88:0)
[12:18:23.168] Timer tick, asking Buildbucket for the build status
[12:18:23.307] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:23.307] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:89:0) after 1m0s
[12:19:23.330] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:89:0)
[12:19:23.331] Timer tick, asking Buildbucket for the build status
[12:19:23.365] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:23.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:90:0) after 1m0s
[12:20:23.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:90:0)
[12:20:23.403] Timer tick, asking Buildbucket for the build status
[12:20:23.457] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:23.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:91:0) after 1m0s
[12:21:23.588] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:91:0)
[12:21:23.588] Timer tick, asking Buildbucket for the build status
[12:21:23.662] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:23.662] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:92:0) after 1m0s
[12:22:23.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:92:0)
[12:22:23.720] Timer tick, asking Buildbucket for the build status
[12:22:23.770] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:23.770] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:93:0) after 1m0s
[12:23:23.796] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:93:0)
[12:23:23.796] Timer tick, asking Buildbucket for the build status
[12:23:23.857] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:23.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:94:0) after 1m0s
[12:24:23.880] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:94:0)
[12:24:23.880] Timer tick, asking Buildbucket for the build status
[12:24:23.940] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:95:0) after 1m0s
[12:25:24.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:95:0)
[12:25:24.001] Timer tick, asking Buildbucket for the build status
[12:25:24.073] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:24.073] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:96:0) after 1m0s
[12:26:24.096] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:96:0)
[12:26:24.096] Timer tick, asking Buildbucket for the build status
[12:26:24.275] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:24.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:97:0) after 1m0s
[12:27:24.323] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:97:0)
[12:27:24.323] Timer tick, asking Buildbucket for the build status
[12:27:24.392] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:98:0) after 1m0s
[12:28:24.415] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:98:0)
[12:28:24.415] Timer tick, asking Buildbucket for the build status
[12:28:24.527] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:24.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:99:0) after 1m0s
[12:29:24.627] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:99:0)
[12:29:24.627] Timer tick, asking Buildbucket for the build status
[12:29:24.702] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:24.702] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:100:0) after 1m0s
[12:30:24.725] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:100:0)
[12:30:24.725] Timer tick, asking Buildbucket for the build status
[12:30:24.791] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:24.791] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:101:0) after 1m0s
[12:31:24.813] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:101:0)
[12:31:24.813] Timer tick, asking Buildbucket for the build status
[12:31:24.866] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:24.866] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:102:0) after 1m0s
[12:32:24.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:102:0)
[12:32:24.988] Timer tick, asking Buildbucket for the build status
[12:32:25.094] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:25.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:103:0) after 1m0s
[12:33:25.220] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:103:0)
[12:33:25.220] Timer tick, asking Buildbucket for the build status
[12:33:25.277] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:25.277] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:104:0) after 1m0s
[12:34:25.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:104:0)
[12:34:25.367] Timer tick, asking Buildbucket for the build status
[12:34:25.619] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:25.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:105:0) after 1m0s
[12:35:25.639] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:105:0)
[12:35:25.639] Timer tick, asking Buildbucket for the build status
[12:35:25.755] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:25.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:106:0) after 1m0s
[12:36:25.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:106:0)
[12:36:25.833] Timer tick, asking Buildbucket for the build status
[12:36:25.909] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:25.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:107:0) after 1m0s
[12:37:25.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:107:0)
[12:37:25.931] Timer tick, asking Buildbucket for the build status
[12:37:25.970] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:25.970] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:108:0) after 1m0s
[12:38:26.074] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:108:0)
[12:38:26.075] Timer tick, asking Buildbucket for the build status
[12:38:26.150] Build 8940849618957138688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:26.150] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9106279681155951712:109:0) after 1m0s
[12:39:14.753] Received PubSub notification, asking Buildbucket for the build status
[12:39:14.822] Build 8940849618957138688: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:39:14.822] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1531744753720680",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531738373757170",
"experimental": true,
"failure_reason": "BUILD_FAILURE",
"id": "8940849618957138688",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Second build\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"Second build\"}, \"humanReason\": \"Step('Second build') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8940849618957138688/+/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\": [\"3684-658b5dc\"], \"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\": \"e6ce828ef60c4c1438867b535efbbb5d9a177c0e\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23976}\", \"$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@{#575219}\", \"got_src_revision\": \"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\", \"got_revision_cp\": \"refs/heads/master@{#575219}\", \"branch\": \"refs/heads/master\", \"revision\": \"b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Android\", \"got_webrtc_revision\": \"c528c0a07f85cc8ff0880f0401fb272878dfdf10\", \"mastername\": \"chromium.android\", \"got_angle_revision\": \"76c1d14b8e212db9822a6398343a344ff9028298\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1531738373757170, \"tags\": [\"builder:Deterministic Android\", \"buildset:commit/git/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9106279681155951712\", \"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\": \"8940849618957138688\"}}, \"got_v8_revision\": \"5d21e79d2975a4a59894ba4646b7517dde12b303\", \"got_v8_revision_cp\": \"refs/heads/6.9.400@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": true, \"is_luci\": true}, \"buildnumber\": 2120, \"path_config\": \"generic\", \"bot_id\": \"swarm628-c4\", \"got_buildtools_revision\": \"0dd5c6f980d22be96b728155249df2da355989d9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1531738405767070",
"status": "COMPLETED",
"status_changed_ts": "1531744754286490",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/2120",
"builder:Deterministic Android",
"buildset:commit/git/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106279681155951712",
"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/2120",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8940849618957138688",
"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/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b06141eceaf5bc42a1e36e59ace24b8fb52cf1ad",
"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/8940849618957138688/+/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:9106279681155951712",
"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:cb76dd9a681a4367362c098d4bb68c8c82db75c2",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3ebb8a4e3af43210",
"user_agent:luci-scheduler"
],
"updated_ts": "1531744754287560",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Deterministic Android/2120",
"utcnow_ts": "1531744754804880"
}
}
[12:39:14.822] Invocation finished in 1h46m22.544945105s with status FAILED