[05:57:12.108] New invocation is queued and will start shortly
[05:57:13.202] Starting the invocation (attempt 1)
[05:57:13.232] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:57:13.232] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:57:13.232] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085279786887772448",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9085279786887772448",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"gitiles_ref:refs/heads/master"
]
}
[05:57:14.252] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551765433360697",
"id": "8919849724906928160",
"lease_expiration_ts": "1551884234044330",
"lease_key": "1637960250",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551765434128711",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5520",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085279786887772448",
"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/8919849724906928160/+/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:43653ef5ee4b2510",
"user_agent:luci-scheduler"
],
"updated_ts": "1551765434128800",
"url": "https://ci.chromium.org/b/8919849724906928160",
"utcnow_ts": "1551765434240107"
}
}
[05:57:14.252] Task URL: https://ci.chromium.org/b/8919849724906928160
[05:57:14.252] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:2:0) after 1m0s
[05:57:15.333] Received PubSub notification, asking Buildbucket for the build status
[05:57:15.391] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:14.278] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:2:0)
[05:58:14.278] Timer tick, asking Buildbucket for the build status
[05:58:14.356] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:14.356] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:4:0) after 1m0s
[05:59:14.371] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:4:0)
[05:59:14.371] Timer tick, asking Buildbucket for the build status
[05:59:14.405] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:14.405] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:5:0) after 1m0s
[06:00:14.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:5:0)
[06:00:14.425] Timer tick, asking Buildbucket for the build status
[06:00:14.468] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:14.468] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:6:0) after 1m0s
[06:01:14.487] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:6:0)
[06:01:14.488] Timer tick, asking Buildbucket for the build status
[06:01:14.689] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:14.689] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:7:0) after 1m0s
[06:02:14.685] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:7:0)
[06:02:14.685] Timer tick, asking Buildbucket for the build status
[06:02:14.736] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:14.736] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:8:0) after 1m0s
[06:03:14.749] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:8:0)
[06:03:14.749] Timer tick, asking Buildbucket for the build status
[06:03:14.820] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:14.820] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:9:0) after 1m0s
[06:04:14.832] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:9:0)
[06:04:14.832] Timer tick, asking Buildbucket for the build status
[06:04:14.869] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:14.869] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:10:0) after 1m0s
[06:05:14.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:10:0)
[06:05:14.906] Timer tick, asking Buildbucket for the build status
[06:05:14.959] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:14.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:11:0) after 1m0s
[06:06:14.976] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:11:0)
[06:06:14.976] Timer tick, asking Buildbucket for the build status
[06:06:15.012] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:15.012] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:12:0) after 1m0s
[06:07:15.038] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:12:0)
[06:07:15.038] Timer tick, asking Buildbucket for the build status
[06:07:15.117] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:13:0) after 1m0s
[06:08:15.089] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:13:0)
[06:08:15.089] Timer tick, asking Buildbucket for the build status
[06:08:15.135] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:15.135] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:14:0) after 1m0s
[06:09:15.158] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:14:0)
[06:09:15.158] Timer tick, asking Buildbucket for the build status
[06:09:15.217] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:15.217] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:15:0) after 1m0s
[06:10:15.306] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:15:0)
[06:10:15.306] Timer tick, asking Buildbucket for the build status
[06:10:15.344] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:15.344] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:16:0) after 1m0s
[06:11:15.363] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:16:0)
[06:11:15.363] Timer tick, asking Buildbucket for the build status
[06:11:15.423] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:15.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:17:0) after 1m0s
[06:12:15.392] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:17:0)
[06:12:15.392] Timer tick, asking Buildbucket for the build status
[06:12:15.539] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:15.539] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:18:0) after 1m0s
[06:13:15.536] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:18:0)
[06:13:15.536] Timer tick, asking Buildbucket for the build status
[06:13:15.767] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:15.767] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:19:0) after 1m0s
[06:14:15.773] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:19:0)
[06:14:15.773] Timer tick, asking Buildbucket for the build status
[06:14:15.826] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:15.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:20:0) after 1m0s
[06:15:15.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:20:0)
[06:15:15.822] Timer tick, asking Buildbucket for the build status
[06:15:15.943] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:15.943] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:21:0) after 1m0s
[06:16:16.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:21:0)
[06:16:16.002] Timer tick, asking Buildbucket for the build status
[06:16:16.086] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:16.086] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:22:0) after 1m0s
[06:17:16.079] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:22:0)
[06:17:16.079] Timer tick, asking Buildbucket for the build status
[06:17:16.133] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:16.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:23:0) after 1m0s
[06:18:16.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:23:0)
[06:18:16.154] Timer tick, asking Buildbucket for the build status
[06:18:16.197] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:16.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:24:0) after 1m0s
[06:19:16.204] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:24:0)
[06:19:16.204] Timer tick, asking Buildbucket for the build status
[06:19:16.253] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:16.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:25:0) after 1m0s
[06:20:16.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:25:0)
[06:20:16.276] Timer tick, asking Buildbucket for the build status
[06:20:16.333] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:16.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:26:0) after 1m0s
[06:21:16.365] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:26:0)
[06:21:16.365] Timer tick, asking Buildbucket for the build status
[06:21:16.471] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:16.471] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:27:0) after 1m0s
[06:22:16.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:27:0)
[06:22:16.475] Timer tick, asking Buildbucket for the build status
[06:22:16.585] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:16.585] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:28:0) after 1m0s
[06:23:16.617] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:28:0)
[06:23:16.617] Timer tick, asking Buildbucket for the build status
[06:23:16.683] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:16.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:29:0) after 1m0s
[06:24:16.699] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:29:0)
[06:24:16.699] Timer tick, asking Buildbucket for the build status
[06:24:17.119] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:17.119] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:30:0) after 1m0s
[06:25:17.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:30:0)
[06:25:17.139] Timer tick, asking Buildbucket for the build status
[06:25:17.195] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:17.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:31:0) after 1m0s
[06:26:17.194] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:31:0)
[06:26:17.194] Timer tick, asking Buildbucket for the build status
[06:26:17.272] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:17.272] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:32:0) after 1m0s
[06:27:17.285] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:32:0)
[06:27:17.285] Timer tick, asking Buildbucket for the build status
[06:27:17.336] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:17.336] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:33:0) after 1m0s
[06:28:17.354] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:33:0)
[06:28:17.354] Timer tick, asking Buildbucket for the build status
[06:28:17.389] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:17.389] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:34:0) after 1m0s
[06:29:17.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:34:0)
[06:29:17.414] Timer tick, asking Buildbucket for the build status
[06:29:17.474] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:17.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:35:0) after 1m0s
[06:30:17.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:35:0)
[06:30:17.449] Timer tick, asking Buildbucket for the build status
[06:30:17.493] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:17.493] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:36:0) after 1m0s
[06:31:17.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:36:0)
[06:31:17.493] Timer tick, asking Buildbucket for the build status
[06:31:17.559] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:17.559] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:37:0) after 1m0s
[06:32:17.541] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:37:0)
[06:32:17.541] Timer tick, asking Buildbucket for the build status
[06:32:17.674] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:17.674] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:38:0) after 1m0s
[06:33:17.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:38:0)
[06:33:17.694] Timer tick, asking Buildbucket for the build status
[06:33:17.768] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:17.768] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:39:0) after 1m0s
[06:34:17.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:39:0)
[06:34:17.770] Timer tick, asking Buildbucket for the build status
[06:34:17.826] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:40:0) after 1m0s
[06:35:17.847] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:40:0)
[06:35:17.847] Timer tick, asking Buildbucket for the build status
[06:35:17.911] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:17.911] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:41:0) after 1m0s
[06:36:17.889] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:41:0)
[06:36:17.889] Timer tick, asking Buildbucket for the build status
[06:36:17.968] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:17.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:42:0) after 1m0s
[06:37:17.991] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:42:0)
[06:37:17.991] Timer tick, asking Buildbucket for the build status
[06:37:18.072] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:18.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:43:0) after 1m0s
[06:38:18.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:43:0)
[06:38:18.098] Timer tick, asking Buildbucket for the build status
[06:38:18.189] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:18.189] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:44:0) after 1m0s
[06:39:18.195] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:44:0)
[06:39:18.195] Timer tick, asking Buildbucket for the build status
[06:39:18.253] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:18.254] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:45:0) after 1m0s
[06:40:18.305] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:45:0)
[06:40:18.305] Timer tick, asking Buildbucket for the build status
[06:40:18.413] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:18.413] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:46:0) after 1m0s
[06:41:18.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:46:0)
[06:41:18.421] Timer tick, asking Buildbucket for the build status
[06:41:18.478] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:18.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:47:0) after 1m0s
[06:42:18.532] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:47:0)
[06:42:18.532] Timer tick, asking Buildbucket for the build status
[06:42:18.605] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:18.605] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:48:0) after 1m0s
[06:43:18.627] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:48:0)
[06:43:18.627] Timer tick, asking Buildbucket for the build status
[06:43:18.699] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:18.699] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:49:0) after 1m0s
[06:44:18.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:49:0)
[06:44:18.727] Timer tick, asking Buildbucket for the build status
[06:44:18.766] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:18.766] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:50:0) after 1m0s
[06:45:18.785] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:50:0)
[06:45:18.785] Timer tick, asking Buildbucket for the build status
[06:45:18.847] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:18.847] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:51:0) after 1m0s
[06:46:18.867] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:51:0)
[06:46:18.867] Timer tick, asking Buildbucket for the build status
[06:46:18.980] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:18.980] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:52:0) after 1m0s
[06:47:19.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:52:0)
[06:47:19.001] Timer tick, asking Buildbucket for the build status
[06:47:19.060] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:19.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:53:0) after 1m0s
[06:48:19.050] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:53:0)
[06:48:19.050] Timer tick, asking Buildbucket for the build status
[06:48:19.106] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:19.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:54:0) after 1m0s
[06:49:19.118] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:54:0)
[06:49:19.119] Timer tick, asking Buildbucket for the build status
[06:49:19.283] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:19.283] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:55:0) after 1m0s
[06:50:19.312] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:55:0)
[06:50:19.312] Timer tick, asking Buildbucket for the build status
[06:50:19.478] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:19.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:56:0) after 1m0s
[06:51:19.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:56:0)
[06:51:19.502] Timer tick, asking Buildbucket for the build status
[06:51:19.584] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:19.584] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:57:0) after 1m0s
[06:52:19.605] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:57:0)
[06:52:19.605] Timer tick, asking Buildbucket for the build status
[06:52:19.767] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:19.767] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:58:0) after 1m0s
[06:53:19.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:58:0)
[06:53:19.810] Timer tick, asking Buildbucket for the build status
[06:53:19.886] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:19.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:59:0) after 1m0s
[06:54:19.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:59:0)
[06:54:19.955] Timer tick, asking Buildbucket for the build status
[06:54:20.060] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:20.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:60:0) after 1m0s
[06:55:20.083] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:60:0)
[06:55:20.083] Timer tick, asking Buildbucket for the build status
[06:55:20.203] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:20.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:61:0) after 1m0s
[06:56:20.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:61:0)
[06:56:20.221] Timer tick, asking Buildbucket for the build status
[06:56:20.274] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:20.274] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:62:0) after 1m0s
[06:57:20.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:62:0)
[06:57:20.295] Timer tick, asking Buildbucket for the build status
[06:57:20.334] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:20.334] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:63:0) after 1m0s
[06:58:20.358] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:63:0)
[06:58:20.358] Timer tick, asking Buildbucket for the build status
[06:58:20.475] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:20.475] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:64:0) after 1m0s
[06:59:20.498] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:64:0)
[06:59:20.498] Timer tick, asking Buildbucket for the build status
[06:59:20.538] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:20.538] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:65:0) after 1m0s
[07:00:20.561] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:65:0)
[07:00:20.561] Timer tick, asking Buildbucket for the build status
[07:00:20.629] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:66:0) after 1m0s
[07:01:20.674] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:66:0)
[07:01:20.674] Timer tick, asking Buildbucket for the build status
[07:01:20.715] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:20.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:67:0) after 1m0s
[07:02:20.737] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:67:0)
[07:02:20.737] Timer tick, asking Buildbucket for the build status
[07:02:20.923] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:20.923] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:68:0) after 1m0s
[07:03:20.987] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:68:0)
[07:03:20.987] Timer tick, asking Buildbucket for the build status
[07:03:21.109] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:21.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:69:0) after 1m0s
[07:04:21.149] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:69:0)
[07:04:21.149] Timer tick, asking Buildbucket for the build status
[07:04:21.295] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:21.295] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:70:0) after 1m0s
[07:05:21.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:70:0)
[07:05:21.268] Timer tick, asking Buildbucket for the build status
[07:05:21.311] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:21.311] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:71:0) after 1m0s
[07:06:21.297] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:71:0)
[07:06:21.297] Timer tick, asking Buildbucket for the build status
[07:06:21.477] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:21.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:72:0) after 1m0s
[07:07:21.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:72:0)
[07:07:21.493] Timer tick, asking Buildbucket for the build status
[07:07:21.676] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:21.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:73:0) after 1m0s
[07:08:21.700] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:73:0)
[07:08:21.700] Timer tick, asking Buildbucket for the build status
[07:08:21.777] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:21.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:74:0) after 1m0s
[07:09:21.779] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:74:0)
[07:09:21.779] Timer tick, asking Buildbucket for the build status
[07:09:21.816] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:21.816] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:75:0) after 1m0s
[07:10:21.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:75:0)
[07:10:21.822] Timer tick, asking Buildbucket for the build status
[07:10:22.193] Build 8919849724906928160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:22.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9085279786887772448:76:0) after 1m0s
[07:10:45.810] Received PubSub notification, asking Buildbucket for the build status
[07:10:45.976] Build 8919849724906928160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:10:45.976] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551769845122220",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551765433360697",
"id": "8919849724906928160",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-a-n2zs\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-a\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision\": \"d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"c8eff1c1e1b88b98b2cf1fa52efbac487f4a747c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26947}\", \"$build/goma\": {\"jobs\": 500.0}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#637607}\", \"branch\": \"refs/heads/master\", \"revision\": \"d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"b678940d3a444c9bf5b53a0bc2e2951f222dab6a\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"b8cff9e9347cee1fe46092a10ae039df2fdd4c33\", \"got_v8_revision\": \"d8b0d88de4b7d73ea02abb8511c146944d6ccf67\", \"got_v8_revision_cp\": \"refs/heads/7.4.271@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 5520, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-a-n2zs\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551765434835260",
"status": "COMPLETED",
"status_changed_ts": "1551769845564002",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5520",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6bfeb0d8c0ea591184951aac9fd7bbbabc557d6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085279786887772448",
"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/8919849724906928160/+/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:43653ef5ee4b2510",
"user_agent:luci-scheduler"
],
"updated_ts": "1551769845565533",
"url": "https://ci.chromium.org/b/8919849724906928160",
"utcnow_ts": "1551769845915732"
}
}
[07:10:45.976] Invocation finished in 1h13m33.887419848s with status SUCCEEDED