[21:15:12.615] New invocation is queued and will start shortly
[21:15:13.998] Starting the invocation (attempt 1)
[21:15:14.092] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:15:14.092] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:15:14.092] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077521288650319664",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"12bd84a156548fc77e488310991443bca47ac336\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9077521288650319664",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/12bd84a156548fc77e488310991443bca47ac336",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12bd84a156548fc77e488310991443bca47ac336",
"gitiles_ref:refs/heads/master"
]
}
[21:15:16.010] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1559164514368101",
"id": "8912091226140164688",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"12bd84a156548fc77e488310991443bca47ac336\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559164515312835",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/8040",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/12bd84a156548fc77e488310991443bca47ac336",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12bd84a156548fc77e488310991443bca47ac336",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077521288650319664",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912091226140164688/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1559164515312954",
"url": "https://ci.chromium.org/b/8912091226140164688",
"utcnow_ts": "1559164515989107"
}
}
[21:15:16.010] Task URL: https://ci.chromium.org/b/8912091226140164688
[21:15:16.010] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:2:0) after 1m0s
[21:15:22.322] Received PubSub notification, asking Buildbucket for the build status
[21:15:23.096] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:16.031] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:2:0)
[21:16:16.031] Timer tick, asking Buildbucket for the build status
[21:16:17.709] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:17.709] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:4:0) after 1m0s
[21:17:17.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:4:0)
[21:17:17.736] Timer tick, asking Buildbucket for the build status
[21:17:18.165] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:18.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:5:0) after 1m0s
[21:18:18.211] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:5:0)
[21:18:18.211] Timer tick, asking Buildbucket for the build status
[21:18:19.027] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:19.027] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:6:0) after 1m0s
[21:19:19.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:6:0)
[21:19:19.105] Timer tick, asking Buildbucket for the build status
[21:19:19.416] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:19.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:7:0) after 1m0s
[21:20:19.463] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:7:0)
[21:20:19.463] Timer tick, asking Buildbucket for the build status
[21:20:22.446] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:22.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:8:0) after 1m0s
[21:21:22.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:8:0)
[21:21:22.480] Timer tick, asking Buildbucket for the build status
[21:21:22.632] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:22.632] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:9:0) after 1m0s
[21:22:22.697] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:9:0)
[21:22:22.697] Timer tick, asking Buildbucket for the build status
[21:22:25.589] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:10:0) after 1m0s
[21:23:26.572] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:10:0)
[21:23:26.572] Timer tick, asking Buildbucket for the build status
[21:23:26.900] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:26.900] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:11:0) after 1m0s
[21:24:27.057] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:11:0)
[21:24:27.057] Timer tick, asking Buildbucket for the build status
[21:24:27.386] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:27.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:12:0) after 1m0s
[21:25:27.397] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:12:0)
[21:25:27.397] Timer tick, asking Buildbucket for the build status
[21:25:27.916] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:27.916] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:13:0) after 1m0s
[21:26:27.980] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:13:0)
[21:26:27.980] Timer tick, asking Buildbucket for the build status
[21:26:28.258] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:28.258] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:14:0) after 1m0s
[21:27:28.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:14:0)
[21:27:28.276] Timer tick, asking Buildbucket for the build status
[21:27:28.391] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:28.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:15:0) after 1m0s
[21:28:28.439] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:15:0)
[21:28:28.439] Timer tick, asking Buildbucket for the build status
[21:28:28.766] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:28.766] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:16:0) after 1m0s
[21:29:29.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:16:0)
[21:29:29.725] Timer tick, asking Buildbucket for the build status
[21:29:29.973] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:17:0) after 1m0s
[21:30:30.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:17:0)
[21:30:30.035] Timer tick, asking Buildbucket for the build status
[21:30:30.221] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:30.221] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:18:0) after 1m0s
[21:31:30.252] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:18:0)
[21:31:30.252] Timer tick, asking Buildbucket for the build status
[21:31:30.966] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:30.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:19:0) after 1m0s
[21:32:31.109] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:19:0)
[21:32:31.109] Timer tick, asking Buildbucket for the build status
[21:32:31.473] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:31.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:20:0) after 1m0s
[21:33:31.512] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:20:0)
[21:33:31.512] Timer tick, asking Buildbucket for the build status
[21:33:32.014] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:32.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:21:0) after 1m0s
[21:34:32.103] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:21:0)
[21:34:32.103] Timer tick, asking Buildbucket for the build status
[21:34:32.747] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:32.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:22:0) after 1m0s
[21:35:32.837] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:22:0)
[21:35:32.837] Timer tick, asking Buildbucket for the build status
[21:35:33.197] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:33.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:23:0) after 1m0s
[21:36:33.230] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:23:0)
[21:36:33.230] Timer tick, asking Buildbucket for the build status
[21:36:35.034] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:35.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:24:0) after 1m0s
[21:37:35.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:24:0)
[21:37:35.121] Timer tick, asking Buildbucket for the build status
[21:37:35.262] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:35.262] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:25:0) after 1m0s
[21:38:35.249] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:25:0)
[21:38:35.249] Timer tick, asking Buildbucket for the build status
[21:38:35.715] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:35.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:26:0) after 1m0s
[21:39:35.730] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:26:0)
[21:39:35.730] Timer tick, asking Buildbucket for the build status
[21:39:37.880] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:37.880] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:27:0) after 1m0s
[21:40:37.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:27:0)
[21:40:37.949] Timer tick, asking Buildbucket for the build status
[21:40:38.134] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:38.134] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:28:0) after 1m0s
[21:41:38.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:28:0)
[21:41:38.221] Timer tick, asking Buildbucket for the build status
[21:41:38.608] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:38.608] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:29:0) after 1m0s
[21:42:38.701] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:29:0)
[21:42:38.701] Timer tick, asking Buildbucket for the build status
[21:42:39.505] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:39.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:30:0) after 1m0s
[21:43:39.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:30:0)
[21:43:39.556] Timer tick, asking Buildbucket for the build status
[21:43:41.827] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:41.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:31:0) after 1m0s
[21:44:41.973] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:31:0)
[21:44:41.973] Timer tick, asking Buildbucket for the build status
[21:44:42.195] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:42.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:32:0) after 1m0s
[21:45:42.307] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:32:0)
[21:45:42.307] Timer tick, asking Buildbucket for the build status
[21:45:44.686] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:44.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:33:0) after 1m0s
[21:46:44.686] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:33:0)
[21:46:44.686] Timer tick, asking Buildbucket for the build status
[21:46:44.870] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:44.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:34:0) after 1m0s
[21:47:44.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:34:0)
[21:47:44.928] Timer tick, asking Buildbucket for the build status
[21:47:45.123] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:45.123] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:35:0) after 1m0s
[21:48:45.232] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:35:0)
[21:48:45.232] Timer tick, asking Buildbucket for the build status
[21:48:45.590] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:45.590] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:36:0) after 1m0s
[21:49:45.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:36:0)
[21:49:45.764] Timer tick, asking Buildbucket for the build status
[21:49:46.207] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:46.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:37:0) after 1m0s
[21:50:46.226] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:37:0)
[21:50:46.226] Timer tick, asking Buildbucket for the build status
[21:50:46.506] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:46.506] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:38:0) after 1m0s
[21:51:46.634] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:38:0)
[21:51:46.634] Timer tick, asking Buildbucket for the build status
[21:51:46.988] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:39:0) after 1m0s
[21:52:47.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:39:0)
[21:52:47.069] Timer tick, asking Buildbucket for the build status
[21:52:47.200] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:47.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:40:0) after 1m0s
[21:53:47.243] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:40:0)
[21:53:47.243] Timer tick, asking Buildbucket for the build status
[21:53:48.142] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:48.142] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:41:0) after 1m0s
[21:54:48.171] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:41:0)
[21:54:48.171] Timer tick, asking Buildbucket for the build status
[21:54:48.508] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:48.508] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:42:0) after 1m0s
[21:55:48.566] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:42:0)
[21:55:48.566] Timer tick, asking Buildbucket for the build status
[21:55:48.861] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:48.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:43:0) after 1m0s
[21:56:49.005] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:43:0)
[21:56:49.005] Timer tick, asking Buildbucket for the build status
[21:56:49.179] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:49.179] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:44:0) after 1m0s
[21:57:49.212] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:44:0)
[21:57:49.213] Timer tick, asking Buildbucket for the build status
[21:57:49.315] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:49.315] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:45:0) after 1m0s
[21:58:49.380] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:45:0)
[21:58:49.380] Timer tick, asking Buildbucket for the build status
[21:58:49.511] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:49.511] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:46:0) after 1m0s
[21:59:49.533] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:46:0)
[21:59:49.533] Timer tick, asking Buildbucket for the build status
[21:59:49.631] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:49.631] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:47:0) after 1m0s
[22:00:49.865] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:47:0)
[22:00:49.865] Timer tick, asking Buildbucket for the build status
[22:00:49.984] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:49.984] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:48:0) after 1m0s
[22:01:50.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:48:0)
[22:01:50.006] Timer tick, asking Buildbucket for the build status
[22:01:50.259] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:50.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:49:0) after 1m0s
[22:02:50.248] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:49:0)
[22:02:50.248] Timer tick, asking Buildbucket for the build status
[22:02:50.984] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:50.985] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:50:0) after 1m0s
[22:03:51.007] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:50:0)
[22:03:51.007] Timer tick, asking Buildbucket for the build status
[22:03:51.389] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:51.389] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:51:0) after 1m0s
[22:04:51.428] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:51:0)
[22:04:51.428] Timer tick, asking Buildbucket for the build status
[22:04:51.680] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.680] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:52:0) after 1m0s
[22:05:51.713] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:52:0)
[22:05:51.713] Timer tick, asking Buildbucket for the build status
[22:05:52.113] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:52.113] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:53:0) after 1m0s
[22:06:52.198] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:53:0)
[22:06:52.198] Timer tick, asking Buildbucket for the build status
[22:06:52.557] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:52.557] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:54:0) after 1m0s
[22:07:52.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:54:0)
[22:07:52.582] Timer tick, asking Buildbucket for the build status
[22:07:52.924] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:52.925] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:55:0) after 1m0s
[22:08:52.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:55:0)
[22:08:52.911] Timer tick, asking Buildbucket for the build status
[22:08:53.423] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:53.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:56:0) after 1m0s
[22:09:53.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:56:0)
[22:09:53.479] Timer tick, asking Buildbucket for the build status
[22:09:54.726] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:57:0) after 1m0s
[22:10:54.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:57:0)
[22:10:54.764] Timer tick, asking Buildbucket for the build status
[22:10:54.871] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:54.871] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:58:0) after 1m0s
[22:11:54.893] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:58:0)
[22:11:54.893] Timer tick, asking Buildbucket for the build status
[22:11:56.156] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:56.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:59:0) after 1m0s
[22:12:56.240] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:59:0)
[22:12:56.240] Timer tick, asking Buildbucket for the build status
[22:12:56.857] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:56.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:60:0) after 1m0s
[22:13:56.875] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:60:0)
[22:13:56.875] Timer tick, asking Buildbucket for the build status
[22:13:58.727] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:58.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:61:0) after 1m0s
[22:14:58.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:61:0)
[22:14:58.778] Timer tick, asking Buildbucket for the build status
[22:14:59.290] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:59.290] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:62:0) after 1m0s
[22:15:59.311] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:62:0)
[22:15:59.311] Timer tick, asking Buildbucket for the build status
[22:16:00.214] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:00.214] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:63:0) after 1m0s
[22:17:00.303] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:63:0)
[22:17:00.304] Timer tick, asking Buildbucket for the build status
[22:17:00.546] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:00.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:64:0) after 1m0s
[22:18:00.558] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:64:0)
[22:18:00.558] Timer tick, asking Buildbucket for the build status
[22:18:00.951] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:00.951] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:65:0) after 1m0s
[22:19:01.012] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:65:0)
[22:19:01.012] Timer tick, asking Buildbucket for the build status
[22:19:01.931] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:01.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:66:0) after 1m0s
[22:20:01.958] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:66:0)
[22:20:01.958] Timer tick, asking Buildbucket for the build status
[22:20:02.145] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:02.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:67:0) after 1m0s
[22:21:02.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:67:0)
[22:21:02.351] Timer tick, asking Buildbucket for the build status
[22:21:03.370] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:03.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:68:0) after 1m0s
[22:22:03.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:68:0)
[22:22:03.391] Timer tick, asking Buildbucket for the build status
[22:22:03.781] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:69:0) after 1m0s
[22:23:03.855] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:69:0)
[22:23:03.855] Timer tick, asking Buildbucket for the build status
[22:23:05.834] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:05.834] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:70:0) after 1m0s
[22:24:05.857] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:70:0)
[22:24:05.857] Timer tick, asking Buildbucket for the build status
[22:24:05.992] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:05.992] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:71:0) after 1m0s
[22:25:06.133] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:71:0)
[22:25:06.133] Timer tick, asking Buildbucket for the build status
[22:25:06.347] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:06.347] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:72:0) after 1m0s
[22:26:06.653] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:72:0)
[22:26:06.653] Timer tick, asking Buildbucket for the build status
[22:26:07.255] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:07.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:73:0) after 1m0s
[22:27:07.300] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:73:0)
[22:27:07.300] Timer tick, asking Buildbucket for the build status
[22:27:10.818] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:10.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:74:0) after 1m0s
[22:28:11.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:74:0)
[22:28:11.473] Timer tick, asking Buildbucket for the build status
[22:28:11.775] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:11.775] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:75:0) after 1m0s
[22:29:11.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:75:0)
[22:29:11.906] Timer tick, asking Buildbucket for the build status
[22:29:12.013] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:12.013] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:76:0) after 1m0s
[22:30:12.114] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:76:0)
[22:30:12.114] Timer tick, asking Buildbucket for the build status
[22:30:12.379] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:12.379] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:77:0) after 1m0s
[22:31:12.576] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:77:0)
[22:31:12.576] Timer tick, asking Buildbucket for the build status
[22:31:14.875] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:14.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:78:0) after 1m0s
[22:32:14.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:78:0)
[22:32:14.931] Timer tick, asking Buildbucket for the build status
[22:32:15.631] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:15.631] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:79:0) after 1m0s
[22:33:16.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:79:0)
[22:33:16.027] Timer tick, asking Buildbucket for the build status
[22:33:16.180] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:16.180] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:80:0) after 1m0s
[22:34:16.294] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:80:0)
[22:34:16.294] Timer tick, asking Buildbucket for the build status
[22:34:16.503] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:16.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:81:0) after 1m0s
[22:35:16.589] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:81:0)
[22:35:16.589] Timer tick, asking Buildbucket for the build status
[22:35:17.394] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:17.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:82:0) after 1m0s
[22:36:17.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:82:0)
[22:36:17.809] Timer tick, asking Buildbucket for the build status
[22:36:18.801] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:18.801] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:83:0) after 1m0s
[22:37:18.867] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:83:0)
[22:37:18.867] Timer tick, asking Buildbucket for the build status
[22:37:19.042] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:19.042] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:84:0) after 1m0s
[22:38:19.249] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:84:0)
[22:38:19.249] Timer tick, asking Buildbucket for the build status
[22:38:19.436] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:19.436] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:85:0) after 1m0s
[22:39:19.602] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:85:0)
[22:39:19.602] Timer tick, asking Buildbucket for the build status
[22:39:23.082] Build 8912091226140164688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:23.082] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9077521288650319664:86:0) after 1m0s
[22:40:08.566] Received PubSub notification, asking Buildbucket for the build status
[22:40:09.254] Build 8912091226140164688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:40:09.254] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1559169607972025",
"created_by": "project:chromium",
"created_ts": "1559164514368101",
"id": "8912091226140164688",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"12bd84a156548fc77e488310991443bca47ac336\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-trusty-32-18-a785\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 8040, \"got_angle_revision\": \"2008c8ffefdde461a951289f18152581d5492c82\", \"got_dawn_revision\": \"26d3cf08c209c662a6e2298c301272e2eb8246e4\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"12bd84a156548fc77e488310991443bca47ac336\", \"got_revision_cp\": \"refs/heads/master@{#664461}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"2cb25737c9eb7893ac752674e75d400b4419f67c\", \"got_v8_revision_cp\": \"refs/heads/7.6.303@{#1}\", \"got_webrtc_revision\": \"740cc35145c01d956b97c82364212b403a4b1704\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28109}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"12bd84a156548fc77e488310991443bca47ac336\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-trusty-32-18-a785\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559164518595082",
"status": "COMPLETED",
"status_changed_ts": "1559169608346481",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/8040",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/12bd84a156548fc77e488310991443bca47ac336",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/12bd84a156548fc77e488310991443bca47ac336",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077521288650319664",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8912091226140164688/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:451e43fef67c5810",
"user_agent:luci-scheduler"
],
"updated_ts": "1559169608350110",
"url": "https://ci.chromium.org/b/8912091226140164688",
"utcnow_ts": "1559169609245087"
}
}
[22:40:09.254] Invocation finished in 1h24m56.663167377s with status SUCCEEDED