[19:39:36.224] New invocation is queued and will start shortly
[19:39:37.409] Starting the invocation (attempt 1)
[19:39:37.444] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:39:37.444] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:39:37.444] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9089486103255151472",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"828b4910087d2855cd279a06d8ae7b0ede44a680\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9089486103255151472",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680",
"gitiles_ref:refs/heads/master"
]
}
[19:39:38.986] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547753977560561",
"id": "8924056041184302528",
"lease_expiration_ts": "1547872778586092",
"lease_key": "1648084501",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"828b4910087d2855cd279a06d8ae7b0ede44a680\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1547753978820772",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/4566",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089486103255151472",
"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)/4566",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924056041184302528",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680",
"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/8924056041184302528/+/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:9089486103255151472",
"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:a4409a893063f601d69aad6940b99bce63360704",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:42762501dc758b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1547753978820862",
"url": "https://ci.chromium.org/b/8924056041184302528",
"utcnow_ts": "1547753978977474"
}
}
[19:39:38.986] Task URL: https://ci.chromium.org/b/8924056041184302528
[19:39:38.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:2:0) after 1m0s
[19:39:40.905] Received PubSub notification, asking Buildbucket for the build status
[19:39:40.968] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:39.048] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:2:0)
[19:40:39.048] Timer tick, asking Buildbucket for the build status
[19:40:39.128] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:39.129] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:4:0) after 1m0s
[19:41:39.226] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:4:0)
[19:41:39.226] Timer tick, asking Buildbucket for the build status
[19:41:39.337] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:5:0) after 1m0s
[19:42:39.393] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:5:0)
[19:42:39.393] Timer tick, asking Buildbucket for the build status
[19:42:39.532] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:39.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:6:0) after 1m0s
[19:43:39.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:6:0)
[19:43:39.549] Timer tick, asking Buildbucket for the build status
[19:43:39.593] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:39.593] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:7:0) after 1m0s
[19:44:39.611] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:7:0)
[19:44:39.611] Timer tick, asking Buildbucket for the build status
[19:44:39.941] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:39.941] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:8:0) after 1m0s
[19:45:39.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:8:0)
[19:45:39.962] Timer tick, asking Buildbucket for the build status
[19:45:40.399] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:40.399] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:9:0) after 1m0s
[19:46:40.430] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:9:0)
[19:46:40.431] Timer tick, asking Buildbucket for the build status
[19:46:40.504] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:40.504] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:10:0) after 1m0s
[19:47:40.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:10:0)
[19:47:40.522] Timer tick, asking Buildbucket for the build status
[19:47:40.649] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:40.649] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:11:0) after 1m0s
[19:48:40.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:11:0)
[19:48:40.665] Timer tick, asking Buildbucket for the build status
[19:48:40.774] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:40.774] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:12:0) after 1m0s
[19:49:40.851] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:12:0)
[19:49:40.851] Timer tick, asking Buildbucket for the build status
[19:49:40.888] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:40.888] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:13:0) after 1m0s
[19:50:40.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:13:0)
[19:50:40.904] Timer tick, asking Buildbucket for the build status
[19:50:40.967] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:40.967] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:14:0) after 1m0s
[19:51:40.993] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:14:0)
[19:51:40.993] Timer tick, asking Buildbucket for the build status
[19:51:41.084] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:41.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:15:0) after 1m0s
[19:52:41.138] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:15:0)
[19:52:41.138] Timer tick, asking Buildbucket for the build status
[19:52:41.182] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:41.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:16:0) after 1m0s
[19:53:41.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:16:0)
[19:53:41.200] Timer tick, asking Buildbucket for the build status
[19:53:41.236] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:41.236] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:17:0) after 1m0s
[19:54:41.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:17:0)
[19:54:41.298] Timer tick, asking Buildbucket for the build status
[19:54:41.352] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:41.352] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:18:0) after 1m0s
[19:55:41.410] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:18:0)
[19:55:41.410] Timer tick, asking Buildbucket for the build status
[19:55:41.444] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:19:0) after 1m0s
[19:56:41.462] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:19:0)
[19:56:41.462] Timer tick, asking Buildbucket for the build status
[19:56:41.517] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:41.517] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:20:0) after 1m0s
[19:57:41.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:20:0)
[19:57:41.537] Timer tick, asking Buildbucket for the build status
[19:57:41.607] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:41.607] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:21:0) after 1m0s
[19:58:41.632] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:21:0)
[19:58:41.632] Timer tick, asking Buildbucket for the build status
[19:58:41.668] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:41.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:22:0) after 1m0s
[19:59:41.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:22:0)
[19:59:41.719] Timer tick, asking Buildbucket for the build status
[19:59:41.777] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:41.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:23:0) after 1m0s
[20:00:41.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:23:0)
[20:00:41.799] Timer tick, asking Buildbucket for the build status
[20:00:41.898] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:41.898] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:24:0) after 1m0s
[20:01:41.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:24:0)
[20:01:41.926] Timer tick, asking Buildbucket for the build status
[20:01:41.965] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:41.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:25:0) after 1m0s
[20:02:41.986] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:25:0)
[20:02:41.986] Timer tick, asking Buildbucket for the build status
[20:02:42.247] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:42.247] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:26:0) after 1m0s
[20:03:42.267] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:26:0)
[20:03:42.267] Timer tick, asking Buildbucket for the build status
[20:03:42.349] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:42.349] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:27:0) after 1m0s
[20:04:42.381] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:27:0)
[20:04:42.381] Timer tick, asking Buildbucket for the build status
[20:04:42.440] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:42.440] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:28:0) after 1m0s
[20:05:42.458] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:28:0)
[20:05:42.458] Timer tick, asking Buildbucket for the build status
[20:05:42.498] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:42.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:29:0) after 1m0s
[20:06:42.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:29:0)
[20:06:42.519] Timer tick, asking Buildbucket for the build status
[20:06:42.563] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:42.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:30:0) after 1m0s
[20:07:42.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:30:0)
[20:07:42.596] Timer tick, asking Buildbucket for the build status
[20:07:42.678] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:42.678] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:31:0) after 1m0s
[20:08:42.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:31:0)
[20:08:42.696] Timer tick, asking Buildbucket for the build status
[20:08:42.760] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:42.760] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:32:0) after 1m0s
[20:09:42.780] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:32:0)
[20:09:42.780] Timer tick, asking Buildbucket for the build status
[20:09:42.814] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:42.814] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:33:0) after 1m0s
[20:10:42.847] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:33:0)
[20:10:42.847] Timer tick, asking Buildbucket for the build status
[20:10:42.901] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:42.901] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:34:0) after 1m0s
[20:11:42.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:34:0)
[20:11:42.919] Timer tick, asking Buildbucket for the build status
[20:11:43.004] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:43.004] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:35:0) after 1m0s
[20:12:43.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:35:0)
[20:12:43.061] Timer tick, asking Buildbucket for the build status
[20:12:43.165] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:43.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:36:0) after 1m0s
[20:13:43.183] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:36:0)
[20:13:43.183] Timer tick, asking Buildbucket for the build status
[20:13:43.261] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:43.261] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:37:0) after 1m0s
[20:14:43.282] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:37:0)
[20:14:43.282] Timer tick, asking Buildbucket for the build status
[20:14:43.346] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:43.346] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:38:0) after 1m0s
[20:15:43.370] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:38:0)
[20:15:43.370] Timer tick, asking Buildbucket for the build status
[20:15:43.498] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:43.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:39:0) after 1m0s
[20:16:43.525] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:39:0)
[20:16:43.525] Timer tick, asking Buildbucket for the build status
[20:16:43.600] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:43.600] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:40:0) after 1m0s
[20:17:43.622] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:40:0)
[20:17:43.622] Timer tick, asking Buildbucket for the build status
[20:17:43.655] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:41:0) after 1m0s
[20:18:43.677] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:41:0)
[20:18:43.677] Timer tick, asking Buildbucket for the build status
[20:18:43.753] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:43.753] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:42:0) after 1m0s
[20:19:43.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:42:0)
[20:19:43.772] Timer tick, asking Buildbucket for the build status
[20:19:43.809] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:43.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:43:0) after 1m0s
[20:20:43.829] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:43:0)
[20:20:43.829] Timer tick, asking Buildbucket for the build status
[20:20:43.890] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:43.890] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:44:0) after 1m0s
[20:21:43.966] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:44:0)
[20:21:43.966] Timer tick, asking Buildbucket for the build status
[20:21:44.054] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:44.055] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:45:0) after 1m0s
[20:22:44.095] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:45:0)
[20:22:44.095] Timer tick, asking Buildbucket for the build status
[20:22:44.165] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:44.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:46:0) after 1m0s
[20:23:44.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:46:0)
[20:23:44.436] Timer tick, asking Buildbucket for the build status
[20:23:44.535] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:44.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:47:0) after 1m0s
[20:24:44.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:47:0)
[20:24:44.555] Timer tick, asking Buildbucket for the build status
[20:24:44.666] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:44.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:48:0) after 1m0s
[20:25:44.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:48:0)
[20:25:44.684] Timer tick, asking Buildbucket for the build status
[20:25:44.727] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:44.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:49:0) after 1m0s
[20:26:44.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:49:0)
[20:26:44.746] Timer tick, asking Buildbucket for the build status
[20:26:44.782] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:44.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:50:0) after 1m0s
[20:27:44.808] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:50:0)
[20:27:44.808] Timer tick, asking Buildbucket for the build status
[20:27:44.879] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:44.879] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:51:0) after 1m0s
[20:28:44.900] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:51:0)
[20:28:44.900] Timer tick, asking Buildbucket for the build status
[20:28:44.961] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:44.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:52:0) after 1m0s
[20:29:45.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:52:0)
[20:29:45.006] Timer tick, asking Buildbucket for the build status
[20:29:45.077] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:45.077] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:53:0) after 1m0s
[20:30:45.115] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:53:0)
[20:30:45.115] Timer tick, asking Buildbucket for the build status
[20:30:45.200] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:45.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:54:0) after 1m0s
[20:31:45.225] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:54:0)
[20:31:45.225] Timer tick, asking Buildbucket for the build status
[20:31:45.353] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:45.353] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:55:0) after 1m0s
[20:32:45.380] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:55:0)
[20:32:45.380] Timer tick, asking Buildbucket for the build status
[20:32:45.439] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:45.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:56:0) after 1m0s
[20:33:45.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:56:0)
[20:33:45.461] Timer tick, asking Buildbucket for the build status
[20:33:45.535] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:45.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:57:0) after 1m0s
[20:34:45.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:57:0)
[20:34:45.556] Timer tick, asking Buildbucket for the build status
[20:34:45.620] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:45.620] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:58:0) after 1m0s
[20:35:45.656] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:58:0)
[20:35:45.656] Timer tick, asking Buildbucket for the build status
[20:35:45.805] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:45.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:59:0) after 1m0s
[20:36:45.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:59:0)
[20:36:45.826] Timer tick, asking Buildbucket for the build status
[20:36:45.863] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:45.863] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:60:0) after 1m0s
[20:37:45.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:60:0)
[20:37:45.884] Timer tick, asking Buildbucket for the build status
[20:37:45.953] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:45.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:61:0) after 1m0s
[20:38:45.979] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:61:0)
[20:38:45.979] Timer tick, asking Buildbucket for the build status
[20:38:46.078] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:46.078] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:62:0) after 1m0s
[20:39:46.101] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:62:0)
[20:39:46.101] Timer tick, asking Buildbucket for the build status
[20:39:46.154] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:46.154] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:63:0) after 1m0s
[20:40:46.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:63:0)
[20:40:46.209] Timer tick, asking Buildbucket for the build status
[20:40:46.251] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:46.251] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:64:0) after 1m0s
[20:41:46.282] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:64:0)
[20:41:46.282] Timer tick, asking Buildbucket for the build status
[20:41:46.357] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:46.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:65:0) after 1m0s
[20:42:46.377] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:65:0)
[20:42:46.377] Timer tick, asking Buildbucket for the build status
[20:42:46.441] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:46.441] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:66:0) after 1m0s
[20:43:46.503] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:66:0)
[20:43:46.503] Timer tick, asking Buildbucket for the build status
[20:43:46.666] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:46.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:67:0) after 1m0s
[20:44:46.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:67:0)
[20:44:46.702] Timer tick, asking Buildbucket for the build status
[20:44:46.743] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:46.743] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:68:0) after 1m0s
[20:45:46.782] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:68:0)
[20:45:46.782] Timer tick, asking Buildbucket for the build status
[20:45:46.913] Build 8924056041184302528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:46.913] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9089486103255151472:69:0) after 1m0s
[20:45:47.598] Received PubSub notification, asking Buildbucket for the build status
[20:45:47.642] Build 8924056041184302528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:45:47.642] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1547757946358248",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547753977560561",
"id": "8924056041184302528",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"828b4910087d2855cd279a06d8ae7b0ede44a680\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"id\": [\"swarm627-c4\"], \"server_version\": [\"4002-2ab655d\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"828b4910087d2855cd279a06d8ae7b0ede44a680\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"799cddcb83fda2911491fd653023c38a28bba1bd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26298}\", \"$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@{#623801}\", \"branch\": \"refs/heads/master\", \"revision\": \"828b4910087d2855cd279a06d8ae7b0ede44a680\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"74ba99062c48b278675cfe52643719202296fddc\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"b3bdd2acc4f034eb3676df5dc7fd516da1288a6c\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547753977560561, \"tags\": [\"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089486103255151472\", \"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\": \"8924056041184302528\"}}, \"got_v8_revision\": \"50fddcc942f5a57668b67a6361c003daf0a2f74e\", \"got_v8_revision_cp\": \"refs/heads/7.3.437@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 4566, \"path_config\": \"generic\", \"bot_id\": \"swarm627-c4\", \"got_buildtools_revision\": \"40194ab03962d8183b2796fa58a39ec8a9e83a41\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1547753980120602",
"status": "COMPLETED",
"status_changed_ts": "1547757947168289",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/4566",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089486103255151472",
"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)/4566",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924056041184302528",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/828b4910087d2855cd279a06d8ae7b0ede44a680",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/828b4910087d2855cd279a06d8ae7b0ede44a680",
"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/8924056041184302528/+/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:9089486103255151472",
"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:a4409a893063f601d69aad6940b99bce63360704",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:42762501dc758b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1547757947169520",
"url": "https://ci.chromium.org/b/8924056041184302528",
"utcnow_ts": "1547757947633268"
}
}
[20:45:47.642] Invocation finished in 1h6m11.440655655s with status SUCCEEDED