[11:38:08.115] New invocation is queued and will start shortly
[11:38:09.501] Starting the invocation (attempt 1)
[11:38:09.541] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:38:09.541] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:38:09.541] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9095495794412724768",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"251b2c57e70df2437579ca35e293e57ddc69c81b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9095495794412724768",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b",
"gitiles_ref:refs/heads/master"
]
}
[11:38:11.172] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542022689620392",
"id": "8930065732167739312",
"lease_expiration_ts": "1542141490945177",
"lease_key": "116813379",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"251b2c57e70df2437579ca35e293e57ddc69c81b\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1542022691092014",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/3307",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9095495794412724768",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_dimension:builder:Deterministic Linux (dbg)",
"swarming_dimension:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux (dbg)/3307",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8930065732167739312",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c8ae520b7ed0f3687d2af0e926104b1bcbf0f37",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b",
"swarming_tag:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8930065732167739312/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9095495794412724768",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:43eed580a711b6fa3c17ee91a8c14ff22cb7390f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4120887e8f260510",
"user_agent:luci-scheduler"
],
"updated_ts": "1542022691092096",
"url": "https://ci.chromium.org/b/8930065732167739312",
"utcnow_ts": "1542022691161717"
}
}
[11:38:11.172] Task URL: https://ci.chromium.org/b/8930065732167739312
[11:38:11.172] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:2:0) after 1m0s
[11:38:13.604] Received PubSub notification, asking Buildbucket for the build status
[11:38:13.643] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.189] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:2:0)
[11:39:11.189] Timer tick, asking Buildbucket for the build status
[11:39:11.258] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.258] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:4:0) after 1m0s
[11:40:11.277] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:4:0)
[11:40:11.277] Timer tick, asking Buildbucket for the build status
[11:40:11.356] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.356] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:5:0) after 1m0s
[11:41:11.374] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:5:0)
[11:41:11.374] Timer tick, asking Buildbucket for the build status
[11:41:11.440] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:11.440] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:6:0) after 1m0s
[11:42:11.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:6:0)
[11:42:11.501] Timer tick, asking Buildbucket for the build status
[11:42:11.595] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:11.595] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:7:0) after 1m0s
[11:43:11.622] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:7:0)
[11:43:11.622] Timer tick, asking Buildbucket for the build status
[11:43:11.691] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:11.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:8:0) after 1m0s
[11:44:11.737] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:8:0)
[11:44:11.737] Timer tick, asking Buildbucket for the build status
[11:44:11.907] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:11.907] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:9:0) after 1m0s
[11:45:11.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:9:0)
[11:45:11.926] Timer tick, asking Buildbucket for the build status
[11:45:12.017] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:12.017] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:10:0) after 1m0s
[11:46:12.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:10:0)
[11:46:12.041] Timer tick, asking Buildbucket for the build status
[11:46:12.086] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:12.086] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:11:0) after 1m0s
[11:47:12.106] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:11:0)
[11:47:12.106] Timer tick, asking Buildbucket for the build status
[11:47:12.171] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:12:0) after 1m0s
[11:48:12.199] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:12:0)
[11:48:12.199] Timer tick, asking Buildbucket for the build status
[11:48:12.276] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:12.277] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:13:0) after 1m0s
[11:49:12.303] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:13:0)
[11:49:12.303] Timer tick, asking Buildbucket for the build status
[11:49:12.343] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:12.343] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:14:0) after 1m0s
[11:50:12.379] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:14:0)
[11:50:12.379] Timer tick, asking Buildbucket for the build status
[11:50:12.447] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:12.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:15:0) after 1m0s
[11:51:12.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:15:0)
[11:51:12.475] Timer tick, asking Buildbucket for the build status
[11:51:12.529] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:12.529] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:16:0) after 1m0s
[11:52:12.641] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:16:0)
[11:52:12.641] Timer tick, asking Buildbucket for the build status
[11:52:12.751] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:12.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:17:0) after 1m0s
[11:53:12.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:17:0)
[11:53:12.770] Timer tick, asking Buildbucket for the build status
[11:53:12.845] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:12.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:18:0) after 1m0s
[11:54:12.913] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:18:0)
[11:54:12.913] Timer tick, asking Buildbucket for the build status
[11:54:12.958] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:12.958] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:19:0) after 1m0s
[11:55:12.978] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:19:0)
[11:55:12.978] Timer tick, asking Buildbucket for the build status
[11:55:13.053] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:13.053] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:20:0) after 1m0s
[11:56:13.157] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:20:0)
[11:56:13.157] Timer tick, asking Buildbucket for the build status
[11:56:13.227] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:13.227] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:21:0) after 1m0s
[11:57:13.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:21:0)
[11:57:13.262] Timer tick, asking Buildbucket for the build status
[11:57:13.330] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:22:0) after 1m0s
[11:58:13.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:22:0)
[11:58:13.351] Timer tick, asking Buildbucket for the build status
[11:58:13.404] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:13.404] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:23:0) after 1m0s
[11:59:13.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:23:0)
[11:59:13.425] Timer tick, asking Buildbucket for the build status
[11:59:13.545] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:13.545] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:24:0) after 1m0s
[12:00:13.675] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:24:0)
[12:00:13.675] Timer tick, asking Buildbucket for the build status
[12:00:13.716] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:13.716] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:25:0) after 1m0s
[12:01:13.781] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:25:0)
[12:01:13.781] Timer tick, asking Buildbucket for the build status
[12:01:13.917] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:13.917] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:26:0) after 1m0s
[12:02:13.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:26:0)
[12:02:13.949] Timer tick, asking Buildbucket for the build status
[12:02:14.003] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:27:0) after 1m0s
[12:03:14.083] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:27:0)
[12:03:14.083] Timer tick, asking Buildbucket for the build status
[12:03:14.160] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:14.160] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:28:0) after 1m0s
[12:04:14.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:28:0)
[12:04:14.219] Timer tick, asking Buildbucket for the build status
[12:04:14.327] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.327] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:29:0) after 1m0s
[12:05:14.349] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:29:0)
[12:05:14.349] Timer tick, asking Buildbucket for the build status
[12:05:14.402] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:14.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:30:0) after 1m0s
[12:06:14.429] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:30:0)
[12:06:14.429] Timer tick, asking Buildbucket for the build status
[12:06:14.610] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:14.610] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:31:0) after 1m0s
[12:07:14.641] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:31:0)
[12:07:14.641] Timer tick, asking Buildbucket for the build status
[12:07:14.678] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:14.678] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:32:0) after 1m0s
[12:08:14.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:32:0)
[12:08:14.810] Timer tick, asking Buildbucket for the build status
[12:08:14.889] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:14.889] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:33:0) after 1m0s
[12:09:14.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:33:0)
[12:09:14.911] Timer tick, asking Buildbucket for the build status
[12:09:14.978] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:14.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:34:0) after 1m0s
[12:10:15.080] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:34:0)
[12:10:15.080] Timer tick, asking Buildbucket for the build status
[12:10:15.214] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:15.214] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:35:0) after 1m0s
[12:11:15.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:35:0)
[12:11:15.235] Timer tick, asking Buildbucket for the build status
[12:11:15.290] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:15.290] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:36:0) after 1m0s
[12:12:15.336] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:36:0)
[12:12:15.336] Timer tick, asking Buildbucket for the build status
[12:12:15.390] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:15.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:37:0) after 1m0s
[12:13:15.460] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:37:0)
[12:13:15.460] Timer tick, asking Buildbucket for the build status
[12:13:15.521] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:15.521] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:38:0) after 1m0s
[12:14:15.550] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:38:0)
[12:14:15.550] Timer tick, asking Buildbucket for the build status
[12:14:15.701] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:15.701] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:39:0) after 1m0s
[12:15:15.723] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:39:0)
[12:15:15.723] Timer tick, asking Buildbucket for the build status
[12:15:15.789] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:15.789] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:40:0) after 1m0s
[12:16:15.815] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:40:0)
[12:16:15.815] Timer tick, asking Buildbucket for the build status
[12:16:15.927] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:15.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:41:0) after 1m0s
[12:17:15.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:41:0)
[12:17:15.949] Timer tick, asking Buildbucket for the build status
[12:17:16.043] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:42:0) after 1m0s
[12:18:16.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:42:0)
[12:18:16.065] Timer tick, asking Buildbucket for the build status
[12:18:16.243] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:43:0) after 1m0s
[12:19:16.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:43:0)
[12:19:16.270] Timer tick, asking Buildbucket for the build status
[12:19:16.349] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:16.349] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:44:0) after 1m0s
[12:20:16.372] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:44:0)
[12:20:16.372] Timer tick, asking Buildbucket for the build status
[12:20:16.455] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:16.455] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:45:0) after 1m0s
[12:21:16.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:45:0)
[12:21:16.475] Timer tick, asking Buildbucket for the build status
[12:21:16.517] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:16.517] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:46:0) after 1m0s
[12:22:16.546] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:46:0)
[12:22:16.546] Timer tick, asking Buildbucket for the build status
[12:22:16.625] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:47:0) after 1m0s
[12:23:16.675] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:47:0)
[12:23:16.675] Timer tick, asking Buildbucket for the build status
[12:23:16.739] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:16.739] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:48:0) after 1m0s
[12:24:16.773] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:48:0)
[12:24:16.773] Timer tick, asking Buildbucket for the build status
[12:24:16.826] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:16.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:49:0) after 1m0s
[12:25:16.854] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:49:0)
[12:25:16.854] Timer tick, asking Buildbucket for the build status
[12:25:16.937] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:16.937] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:50:0) after 1m0s
[12:26:16.999] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:50:0)
[12:26:16.999] Timer tick, asking Buildbucket for the build status
[12:26:17.060] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:17.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:51:0) after 1m0s
[12:27:17.120] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:51:0)
[12:27:17.120] Timer tick, asking Buildbucket for the build status
[12:27:17.203] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:17.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:52:0) after 1m0s
[12:28:17.259] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:52:0)
[12:28:17.259] Timer tick, asking Buildbucket for the build status
[12:28:17.295] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:53:0) after 1m0s
[12:29:17.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:53:0)
[12:29:17.403] Timer tick, asking Buildbucket for the build status
[12:29:17.465] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:17.465] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:54:0) after 1m0s
[12:30:17.487] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:54:0)
[12:30:17.487] Timer tick, asking Buildbucket for the build status
[12:30:17.619] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:17.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:55:0) after 1m0s
[12:31:17.643] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:55:0)
[12:31:17.643] Timer tick, asking Buildbucket for the build status
[12:31:17.754] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:17.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:56:0) after 1m0s
[12:32:17.776] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:56:0)
[12:32:17.776] Timer tick, asking Buildbucket for the build status
[12:32:17.899] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:17.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:57:0) after 1m0s
[12:33:17.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:57:0)
[12:33:17.928] Timer tick, asking Buildbucket for the build status
[12:33:17.975] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:17.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:58:0) after 1m0s
[12:34:18.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:58:0)
[12:34:18.006] Timer tick, asking Buildbucket for the build status
[12:34:18.063] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:18.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:59:0) after 1m0s
[12:35:18.087] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:59:0)
[12:35:18.087] Timer tick, asking Buildbucket for the build status
[12:35:18.185] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:18.185] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:60:0) after 1m0s
[12:36:18.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:60:0)
[12:36:18.260] Timer tick, asking Buildbucket for the build status
[12:36:18.317] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:18.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:61:0) after 1m0s
[12:37:18.342] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:61:0)
[12:37:18.343] Timer tick, asking Buildbucket for the build status
[12:37:18.381] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:18.381] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:62:0) after 1m0s
[12:38:18.407] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:62:0)
[12:38:18.407] Timer tick, asking Buildbucket for the build status
[12:38:18.533] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:18.533] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:63:0) after 1m0s
[12:39:18.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:63:0)
[12:39:18.555] Timer tick, asking Buildbucket for the build status
[12:39:18.687] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:18.687] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:64:0) after 1m0s
[12:40:18.711] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:64:0)
[12:40:18.711] Timer tick, asking Buildbucket for the build status
[12:40:18.793] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:65:0) after 1m0s
[12:41:18.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:65:0)
[12:41:18.822] Timer tick, asking Buildbucket for the build status
[12:41:18.861] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:18.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:66:0) after 1m0s
[12:42:18.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:66:0)
[12:42:18.896] Timer tick, asking Buildbucket for the build status
[12:42:18.964] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:18.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:67:0) after 1m0s
[12:43:19.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:67:0)
[12:43:19.027] Timer tick, asking Buildbucket for the build status
[12:43:19.128] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:19.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:68:0) after 1m0s
[12:44:19.262] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:68:0)
[12:44:19.262] Timer tick, asking Buildbucket for the build status
[12:44:19.524] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:19.524] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:69:0) after 1m0s
[12:45:19.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:69:0)
[12:45:19.573] Timer tick, asking Buildbucket for the build status
[12:45:19.625] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:19.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:70:0) after 1m0s
[12:46:19.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:70:0)
[12:46:19.650] Timer tick, asking Buildbucket for the build status
[12:46:20.246] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:20.246] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:71:0) after 1m0s
[12:47:20.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:71:0)
[12:47:20.387] Timer tick, asking Buildbucket for the build status
[12:47:20.526] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:20.526] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:72:0) after 1m0s
[12:48:20.564] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:72:0)
[12:48:20.564] Timer tick, asking Buildbucket for the build status
[12:48:20.671] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:20.671] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:73:0) after 1m0s
[12:49:20.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:73:0)
[12:49:20.696] Timer tick, asking Buildbucket for the build status
[12:49:20.854] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:20.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:74:0) after 1m0s
[12:50:20.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:74:0)
[12:50:20.884] Timer tick, asking Buildbucket for the build status
[12:50:21.011] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:21.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:75:0) after 1m0s
[12:51:21.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:75:0)
[12:51:21.066] Timer tick, asking Buildbucket for the build status
[12:51:21.250] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:21.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:76:0) after 1m0s
[12:52:21.272] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:76:0)
[12:52:21.272] Timer tick, asking Buildbucket for the build status
[12:52:21.304] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:21.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:77:0) after 1m0s
[12:53:21.327] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:77:0)
[12:53:21.327] Timer tick, asking Buildbucket for the build status
[12:53:21.401] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:21.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:78:0) after 1m0s
[12:54:21.420] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:78:0)
[12:54:21.420] Timer tick, asking Buildbucket for the build status
[12:54:21.484] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:21.484] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:79:0) after 1m0s
[12:55:21.508] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:79:0)
[12:55:21.508] Timer tick, asking Buildbucket for the build status
[12:55:21.647] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:21.647] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:80:0) after 1m0s
[12:56:21.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:80:0)
[12:56:21.672] Timer tick, asking Buildbucket for the build status
[12:56:21.852] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:21.852] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:81:0) after 1m0s
[12:57:21.918] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:81:0)
[12:57:21.918] Timer tick, asking Buildbucket for the build status
[12:57:21.987] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:21.987] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:82:0) after 1m0s
[12:58:22.009] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:82:0)
[12:58:22.009] Timer tick, asking Buildbucket for the build status
[12:58:22.068] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:22.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:83:0) after 1m0s
[12:59:22.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:83:0)
[12:59:22.184] Timer tick, asking Buildbucket for the build status
[12:59:22.223] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:22.257] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:84:0) after 1m0s
[13:00:22.286] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:84:0)
[13:00:22.286] Timer tick, asking Buildbucket for the build status
[13:00:22.477] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:22.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:85:0) after 1m0s
[13:01:22.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:85:0)
[13:01:22.556] Timer tick, asking Buildbucket for the build status
[13:01:22.634] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:22.634] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:86:0) after 1m0s
[13:02:22.697] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:86:0)
[13:02:22.697] Timer tick, asking Buildbucket for the build status
[13:02:22.766] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:22.766] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:87:0) after 1m0s
[13:03:22.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:87:0)
[13:03:22.809] Timer tick, asking Buildbucket for the build status
[13:03:22.951] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:22.951] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:88:0) after 1m0s
[13:04:22.973] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:88:0)
[13:04:22.973] Timer tick, asking Buildbucket for the build status
[13:04:23.062] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:23.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:89:0) after 1m0s
[13:05:23.104] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:89:0)
[13:05:23.104] Timer tick, asking Buildbucket for the build status
[13:05:23.188] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:23.188] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:90:0) after 1m0s
[13:06:23.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:90:0)
[13:06:23.213] Timer tick, asking Buildbucket for the build status
[13:06:23.329] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:23.330] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:91:0) after 1m0s
[13:07:23.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:91:0)
[13:07:23.357] Timer tick, asking Buildbucket for the build status
[13:07:23.427] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:23.427] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:92:0) after 1m0s
[13:08:23.462] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:92:0)
[13:08:23.462] Timer tick, asking Buildbucket for the build status
[13:08:23.614] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:23.614] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:93:0) after 1m0s
[13:09:23.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:93:0)
[13:09:23.650] Timer tick, asking Buildbucket for the build status
[13:09:23.692] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.693] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:94:0) after 1m0s
[13:10:23.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:94:0)
[13:10:23.928] Timer tick, asking Buildbucket for the build status
[13:10:23.964] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:23.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:95:0) after 1m0s
[13:11:24.081] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:95:0)
[13:11:24.081] Timer tick, asking Buildbucket for the build status
[13:11:24.149] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:24.149] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:96:0) after 1m0s
[13:12:24.174] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:96:0)
[13:12:24.174] Timer tick, asking Buildbucket for the build status
[13:12:24.304] Build 8930065732167739312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:24.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9095495794412724768:97:0) after 1m0s
[13:12:44.359] Received PubSub notification, asking Buildbucket for the build status
[13:12:44.408] Build 8930065732167739312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:12:44.408] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1542028363376120",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542022689620392",
"id": "8930065732167739312",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"251b2c57e70df2437579ca35e293e57ddc69c81b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3844-124e68f\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"swarm627-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\", \"got_swarming_client_revision\": \"f78187ab77127de42555afe0ad410bebde6ac6a5\", \"got_revision\": \"251b2c57e70df2437579ca35e293e57ddc69c81b\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"2960ec33667a11bb4445dfe8154aef133c741afe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25544}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#607190}\", \"branch\": \"refs/heads/master\", \"revision\": \"251b2c57e70df2437579ca35e293e57ddc69c81b\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"e769ed90c3599a131166d5a61625c1b64318fb91\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"50bf0429394d3c1c191922cd45489db442785f75\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1542022689620392, \"tags\": [\"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9095495794412724768\", \"scheduler_job_id:chromium/Deterministic Linux (dbg)\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8930065732167739312\"}}, \"got_v8_revision\": \"9d6971eb2913c552f9f43609683e273c0051c6fc\", \"got_v8_revision_cp\": \"refs/heads/7.2.293@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 3307, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"c1c711941ce22bf98c3a69ce630f6ee94557325e\", \"gpu_perftests\": \"dff19388903377f548f38e82beebffeb92f66b28\", \"telemetry_unittests\": \"7a13f44f7d61a9356758b2515f2db589f9b7c4a2\", \"load_library_perf_tests\": \"dcd91c4c99e2317b46945d70c51aac4e0c37d648\", \"devtools_closure_compile\": \"f34cd94e2af18153ed5740b13b878f2afa380b25\", \"chromedriver_py_tests\": \"f1bf78fe1dd19ce15fe739cb7f88b7bc55896d2d\", \"mojo_core_unittests\": \"7cf9cb074e3b53d1e479a788cfab4c1683e0518a\", \"leveldb_service_unittests\": \"574f1dad1c2364e3523275af08dd89449a8c4667\", \"storage_unittests\": \"8d251556f9aa273d95d45713d66a94ff14d5be97\", \"jingle_unittests\": \"b7c3c297320038b8b9f867fed9b38d925bd39e7b\", \"angle_unittests\": \"cb75766cadbcc2b7f0d284ac275b5059bd082218\", \"cast_unittests\": \"a38c8065cb538edb867ff0d0025baa831461e4ec\", \"cc_unittests\": \"df3a398b43af51f93d8984e578d83206720465b7\", \"blink_common_unittests\": \"68a8b8773feb6ace264e2fc9355fdb1957a84bc0\", \"crashpad_tests\": \"ea5d4a3aec3b44cb35d69631eac437b75b8081a9\", \"telemetry_perf_tests_without_chrome\": \"ab8c446a30f94612fa135e80faeb4766ed531919\", \"metrics_python_tests\": \"e6d38ad9f88b3c09dbca34b5cf7aed24b945a71d\", \"message_center_unittests\": \"e4495963200aa3d68b3e6a6b012c2f5db3dc9381\", \"ppapi_unittests\": \"a8589f084c25b418031ea0a63290b5821d4008e6\", \"extensions_unittests\": \"b1d04d024725f79250cc61387cb378c2add517fa\", \"gl_unittests_ozone\": \"67e8fcde08066a9eb1ed0756f3749fe8e01498af\", \"app_shell_unittests\": \"f7e3dc89e1081321170eeb813b3c96c03b8cc8bb\", \"boringssl_ssl_tests\": \"386a2bc461b29ac38db0f59c9b23b33caedafc14\", \"chrome_app_unittests\": \"1d2773c26f898b5e0957dd34e2b5350a779434e6\", \"gl_unittests\": \"c3d2d7ac470006675b784bc980ceb0be337949f0\", \"gcm_unit_tests\": \"a1da1a6cc1a37fadb5f08a7ad89a40fec7c37826\", \"views_unittests\": \"5708d1f20ab540bbc3aa0ca29b3227fc7dbe6241\", \"content_shell_crash_test\": \"cef27724b7956a2f0056e9b7ec9c2304d677200d\", \"dawn_end2end_tests\": \"59d2261fb76c55e03f369034cf58c341dc355657\", \"ui_base_unittests\": \"645f4d85fc93f822958581c2011240ac16d08986\", \"gles2_conform_test\": \"f11e6d3948919a3539852100033752b6269e4ca8\", \"cronet_unittests\": \"189345e878e5b7fcbc50f9c546f31ff286fd40ea\", \"performance_test_suite\": \"6108f8fe7a0cc8ac19da0470fdca9b5585689a4c\", \"wm_unittests\": \"f97da5f91adf7ddf63547c8e1254b1cd58aadde2\", \"boringssl_crypto_tests\": \"0b66464c4d933cf6acbacc95f1a407fc2036b646\", \"gin_unittests\": \"1ed838c0df54a3c6dc88152822499355f05ac449\", \"performance_browser_tests\": \"cf282e2a1c37492602a9607bd027e0c651da434e\", \"extensions_browsertests\": \"cbe1ef85f35d5d114ccb4997165c5501ff30bac5\", \"webkit_python_tests\": \"952aa5a0014206cd02a3375eea22689422c93c3d\", \"headless_unittests\": \"369c92fec8e216e213bfb37e968805395bd9148a\", \"webkit_layout_tests\": \"b394782aeeba154eec01422a4f5665a5eca687ed\", \"breakpad_unittests\": \"38ca8beec6d18d1eea18d0852b3a8864d9055b0a\", \"vr_pixeltests\": \"139cebd37276b80df77e21eee6b6d6cca00c2cc3\", \"webkit_unit_tests\": \"cbc0a196b0927269ef72521cc5561a6ca0884361\", \"content_unittests\": \"1148c6a03d4c881e896ccf7d32419ae850b76bad\", \"performance_webview_test_suite\": \"4d96710e7fca6addb562006347ab86660246ab46\", \"wtf_unittests\": \"bb2f5d25d09646ac20ff64b4c3533c37111db4c9\", \"webkit_layout_tests_exparchive\": \"ddc166e0c924870149f09a09aa6ffacb97f8029a\", \"traffic_annotation_auditor_unittests\": \"ce14357248070bf0b05d0a28f5cd56f39f3b5003\", \"libjingle_xmpp_unittests\": \"9f651ef4d215411a6161f3c222be93e0c938c7d5\", \"remoting_unittests\": \"b9e6b34d88cfa58925803cb523b68f90f410b8ba\", \"vr_common_perftests\": \"fbddac1fb343192803dbbfc0c069b5315ddb55b5\", \"filesystem_service_unittests\": \"eba08137bf40f2a1e903d63a09b47907f3fc2a71\", \"gl_tests\": \"de09829cf4e89b72360bb9f85d08fb0ca1cc916c\", \"base_perftests\": \"c61edc2dd7b755f4d2daa9787bee4f9a7b946905\", \"headless_browsertests\": \"7f48459979d511f6966554e47265bd1f3d4ff924\", \"capture_unittests\": \"c7027345abf56585e361658c0019ccea63e2d471\", \"telemetry_gpu_unittests\": \"9a6ef26c949917665b9a97692f189ba0ea9b3c36\", \"command_buffer_perftests\": \"f87df8828089dae63dfdb801fb32deb1e6a4b12e\", \"media_unittests\": \"634ff7545e86db1c0735aeebb821a05921a4f322\", \"gfx_unittests\": \"7acb958ca78969e11f7de57fb7eebaae06ac1527\", \"chromedriver_unittests\": \"425cd93e6e69be875b0eb838ff87a217b2f94637\", \"views_mus_interactive_ui_tests\": \"c4221cd00833f17c0280401df786d5010bf556e6\", \"sandbox_linux_unittests\": \"07db1c3f5393c3444aafdd3608d16482127d2266\", \"media_service_unittests\": \"ac3b741651cc97f94835ef6683c688f8c6fde3c8\", \"compositor_unittests\": \"d5992642dee3ce2a60a2c26f5c1e19b0bebf9caf\", \"latency_unittests\": \"5291efcce474bb34113eedef4eb76b98fc0ed86e\", \"swiftshader_unittests\": \"360e9171cc66654b196788b53263f2ebd51a3a8e\", \"telemetry_perf_unittests\": \"a7a263459c6b9e9c0ba36e78572678ba42050c92\", \"media_blink_unittests\": \"4e53b75d89e14f3bd747b9091321d7d57582b30e\", \"views_mus_unittests\": \"9acdf88a8ccebd23f05b0d535923a934a6ba6531\", \"components_unittests\": \"e831dc2208f4ff4736480d02de2df13d58748a44\", \"snapshot_unittests\": \"7eb9c268d7706f58225e33ba18706818eea2cdb3\", \"accessibility_unittests\": \"c2638b8b67b6e7f9a7b4f55809c6850e052c3887\", \"net_perftests\": \"f7b38d8cba34d16c1b917800277db919f47ccfab\", \"aura_unittests\": \"d92e89ced11109da24c3aa2a2abfa3e7ec4800ea\", \"services_unittests\": \"c7d529d4ddd4961f8aa812ff75785628cb4162e8\", \"components_perftests\": \"edae10da7bc3d7949e03e2dfb028d1032220c501\", \"events_unittests\": \"fda60ea3155f4ae2cbef78ed9e14111bf11111ed\", \"tracing_perftests\": \"58f16d518d1d8f53301adc3563f734f474a6affb\", \"chromedriver_replay_unittests\": \"80dcdd65faa3e5c2519bd1b938064835370c9584\", \"ui_touch_selection_unittests\": \"9198eb1f0d795c5aa2ab91558e32ab223b8d614b\", \"cacheinvalidation_unittests\": \"ced6310205ecb1d83bfde9509d80a3a34a02cc96\", \"viz_unittests\": \"7bcc81f4b1d7e897f157da26511ba835a6152a14\", \"browser_tests\": \"25675ccae20bc50e7d625240c777126a4be921c7\", \"net_unittests\": \"a96b513053018f89256d01970c8ca4c6bdfa9fe7\", \"pdf_unittests\": \"df703e305d95b9e213dd201bd720d3fb1a40e244\", \"dbus_unittests\": \"13b721175cd83725d1be16702d0c09983a520770\", \"vr_common_unittests\": \"ae23a62059cea776ef32feffa626eca486088067\", \"gpu_unittests\": \"92ca0de5c260cc2868d9d667854a23aa2a46fc8d\", \"native_theme_unittests\": \"b65d9cad6a90d9c4d9d40ed8b67c342fff690770\", \"interactive_ui_tests\": \"0f50a77e3eba9d4d393149b46ee0257b373bf39b\", \"mojo_unittests\": \"8bd9585448a90654ce02e1b6d2d0ea540c209bdf\", \"url_unittests\": \"427cd8ec757b8964cafdc888ed88f6d03bdc082c\", \"media_perftests\": \"935dc0425d2bcf8dbda09b1b9fc184ced7fb158a\", \"cronet_tests\": \"9bb03da8baaf6b3df91a1fe6eacedd2b6a616478\", \"google_apis_unittests\": \"b49ba05ecfd57e4faf78164777c310cb4728ed50\", \"telemetry_gpu_integration_test\": \"278b353eff90788016dd9f049ac53cfaa5311575\", \"blink_heap_unittests\": \"60e1b45ad6b90f69e6a4e59110557cb7c11aabf1\", \"midi_unittests\": \"e54310f7dfc09435bd18d0eeaa82440a422dfc6d\", \"base_unittests\": \"966ce0fb24b52e8124729bd68a92f6bd81ffbf72\", \"sync_integration_tests\": \"2aab2d6d8480cfc35ca4efa2208e2ac79c2ad03b\", \"sql_unittests\": \"22d12ad1f158ce9648f9deebed0a4dbcc0608b81\", \"nacl_helper_nonsfi_unittests\": \"d7c0ace6dad87a83d449f2f63685fa55a4a0905b\", \"blink_fuzzer_unittests\": \"ec32ea9a1ab08d35c4cbd8f502ef2c3633b9f391\", \"zucchini_unittests\": \"50157defcac74d42bb5ba5aa6472d83473a9c912\", \"ipc_tests\": \"9f7e43309cf9d3f89459aeea40ffc0fcad0db371\", \"courgette_unittests\": \"6a753bc0173b107871ec30ba8eaa5541fa12e14e\", \"devtools_eslint\": \"0a7533a321abb89e37a5122f9ce2b08242be7799\", \"blink_platform_unittests\": \"1978f4d6deb87886a86f2dc2a37ea8bd92bad880\", \"components_browsertests\": \"ec363631771f358e1b10932667dc6c0dff65aa72\", \"views_perftests\": \"ebb311eaa50bb7b3c1f790ba54b1acdbf67fb53b\", \"angle_perftests\": \"95d3adac610ecd787a6dbf68772f0e8c2d530443\", \"display_unittests\": \"f73966283a97c0cb795bd054584b0f743e2e1999\", \"service_manager_unittests\": \"60958abc109a458a3b04bc97f6b98f3bce44b3a0\", \"angle_end2end_tests\": \"4f18a45327ae9238f1cace28994270a0780c3d0a\", \"skia_unittests\": \"10fa84495972b54b265dd5c68cd5967be471cd22\", \"angle_white_box_tests\": \"db38f77d672dca9a8a0b295f4a696dd0008fdf42\", \"crypto_unittests\": \"69d328795a2fc8e967fe11d1010d57589d320c4b\", \"nacl_loader_unittests\": \"53e24bac7556e8723db7dd32c1e01b3a2bfd4989\", \"unit_tests\": \"90b10a4e6852ba807c668a794bde84aaee01299a\", \"content_browsertests\": \"91e19d6c585bc44b6bedc9b71fd97f33cd9d698a\", \"printing_unittests\": \"0a0dc55a554b9139193f9f3fc17b2650577a51bb\", \"device_unittests\": \"1b3c7e4c7f471bc87466da87e7a211c9dd558f87\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm627-c4\", \"got_buildtools_revision\": \"13a00f110ef910a25763346d6538b60f12845656\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1542022692654419",
"status": "COMPLETED",
"status_changed_ts": "1542028364067948",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/3307",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9095495794412724768",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_dimension:builder:Deterministic Linux (dbg)",
"swarming_dimension:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux (dbg)/3307",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8930065732167739312",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c8ae520b7ed0f3687d2af0e926104b1bcbf0f37",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/251b2c57e70df2437579ca35e293e57ddc69c81b",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/251b2c57e70df2437579ca35e293e57ddc69c81b",
"swarming_tag:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8930065732167739312/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9095495794412724768",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:43eed580a711b6fa3c17ee91a8c14ff22cb7390f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4120887e8f260510",
"user_agent:luci-scheduler"
],
"updated_ts": "1542028364069824",
"url": "https://ci.chromium.org/b/8930065732167739312",
"utcnow_ts": "1542028364392571"
}
}
[13:12:44.408] Invocation finished in 1h34m36.312392869s with status SUCCEEDED