[01:46:30.126] New invocation is queued and will start shortly
[01:46:31.292] Starting the invocation (attempt 1)
[01:46:31.324] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:46:31.324] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:46:31.324] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083664814151623424",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"643206e41bceb79715e115877932e8c1147d8b3e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9083664814151623424",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/643206e41bceb79715e115877932e8c1147d8b3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/643206e41bceb79715e115877932e8c1147d8b3e",
"gitiles_ref:refs/heads/master"
]
}
[01:46:32.603] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553305591501978",
"id": "8918234752044292672",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"643206e41bceb79715e115877932e8c1147d8b3e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553305592450528",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5816",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/643206e41bceb79715e115877932e8c1147d8b3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/643206e41bceb79715e115877932e8c1147d8b3e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083664814151623424",
"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/8918234752044292672/+/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:43c10be8b3ebe310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553305592450692",
"url": "https://ci.chromium.org/b/8918234752044292672",
"utcnow_ts": "1553305592592230"
}
}
[01:46:32.603] Task URL: https://ci.chromium.org/b/8918234752044292672
[01:46:32.603] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:2:0) after 1m0s
[01:46:34.453] Received PubSub notification, asking Buildbucket for the build status
[01:46:34.688] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.632] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:2:0)
[01:47:32.632] Timer tick, asking Buildbucket for the build status
[01:47:32.777] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:4:0) after 1m0s
[01:48:32.795] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:4:0)
[01:48:32.795] Timer tick, asking Buildbucket for the build status
[01:48:32.940] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:32.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:5:0) after 1m0s
[01:49:32.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:5:0)
[01:49:32.963] Timer tick, asking Buildbucket for the build status
[01:49:33.414] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.414] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:6:0) after 1m0s
[01:50:33.434] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:6:0)
[01:50:33.434] Timer tick, asking Buildbucket for the build status
[01:50:33.799] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:33.799] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:7:0) after 1m0s
[01:51:33.811] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:7:0)
[01:51:33.811] Timer tick, asking Buildbucket for the build status
[01:51:34.691] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:8:0) after 1m0s
[01:52:34.674] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:8:0)
[01:52:34.674] Timer tick, asking Buildbucket for the build status
[01:52:35.430] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:35.430] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:9:0) after 1m0s
[01:53:35.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:9:0)
[01:53:35.435] Timer tick, asking Buildbucket for the build status
[01:53:35.532] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:35.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:10:0) after 1m0s
[01:54:35.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:10:0)
[01:54:35.537] Timer tick, asking Buildbucket for the build status
[01:54:35.634] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:35.634] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:11:0) after 1m0s
[01:55:35.606] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:11:0)
[01:55:35.606] Timer tick, asking Buildbucket for the build status
[01:55:35.889] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:35.889] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:12:0) after 1m0s
[01:56:35.910] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:12:0)
[01:56:35.910] Timer tick, asking Buildbucket for the build status
[01:56:36.452] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:36.452] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:13:0) after 1m0s
[01:57:36.450] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:13:0)
[01:57:36.450] Timer tick, asking Buildbucket for the build status
[01:57:36.567] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:36.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:14:0) after 1m0s
[01:58:36.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:14:0)
[01:58:36.582] Timer tick, asking Buildbucket for the build status
[01:58:36.837] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:15:0) after 1m0s
[01:59:36.937] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:15:0)
[01:59:36.937] Timer tick, asking Buildbucket for the build status
[01:59:37.112] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:37.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:16:0) after 1m0s
[02:00:37.083] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:16:0)
[02:00:37.083] Timer tick, asking Buildbucket for the build status
[02:00:37.236] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:37.236] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:17:0) after 1m0s
[02:01:37.279] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:17:0)
[02:01:37.279] Timer tick, asking Buildbucket for the build status
[02:01:37.435] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:37.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:18:0) after 1m0s
[02:02:37.452] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:18:0)
[02:02:37.452] Timer tick, asking Buildbucket for the build status
[02:02:37.544] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:37.545] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:19:0) after 1m0s
[02:03:37.566] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:19:0)
[02:03:37.566] Timer tick, asking Buildbucket for the build status
[02:03:37.692] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:37.692] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:20:0) after 1m0s
[02:04:37.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:20:0)
[02:04:37.694] Timer tick, asking Buildbucket for the build status
[02:04:37.875] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:37.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:21:0) after 1m0s
[02:05:37.870] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:21:0)
[02:05:37.870] Timer tick, asking Buildbucket for the build status
[02:05:38.487] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:38.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:22:0) after 1m0s
[02:06:38.507] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:22:0)
[02:06:38.507] Timer tick, asking Buildbucket for the build status
[02:06:39.428] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:39.429] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:23:0) after 1m0s
[02:07:39.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:23:0)
[02:07:39.461] Timer tick, asking Buildbucket for the build status
[02:07:40.026] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:40.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:24:0) after 1m0s
[02:08:40.049] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:24:0)
[02:08:40.049] Timer tick, asking Buildbucket for the build status
[02:08:40.357] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:40.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:25:0) after 1m0s
[02:09:40.352] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:25:0)
[02:09:40.352] Timer tick, asking Buildbucket for the build status
[02:09:40.993] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:40.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:26:0) after 1m0s
[02:10:40.973] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:26:0)
[02:10:40.973] Timer tick, asking Buildbucket for the build status
[02:10:41.153] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:41.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:27:0) after 1m0s
[02:11:41.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:27:0)
[02:11:41.162] Timer tick, asking Buildbucket for the build status
[02:11:41.880] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:41.880] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:28:0) after 1m0s
[02:12:41.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:28:0)
[02:12:41.945] Timer tick, asking Buildbucket for the build status
[02:12:42.346] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:42.346] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:29:0) after 1m0s
[02:13:42.347] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:29:0)
[02:13:42.347] Timer tick, asking Buildbucket for the build status
[02:13:42.591] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:42.591] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:30:0) after 1m0s
[02:14:42.620] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:30:0)
[02:14:42.620] Timer tick, asking Buildbucket for the build status
[02:14:42.743] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:42.743] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:31:0) after 1m0s
[02:15:42.762] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:31:0)
[02:15:42.763] Timer tick, asking Buildbucket for the build status
[02:15:42.887] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:42.887] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:32:0) after 1m0s
[02:16:42.978] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:32:0)
[02:16:42.978] Timer tick, asking Buildbucket for the build status
[02:16:43.085] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:43.085] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:33:0) after 1m0s
[02:17:43.068] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:33:0)
[02:17:43.068] Timer tick, asking Buildbucket for the build status
[02:17:43.360] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:43.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:34:0) after 1m0s
[02:18:43.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:34:0)
[02:18:43.361] Timer tick, asking Buildbucket for the build status
[02:18:43.563] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:43.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:35:0) after 1m0s
[02:19:43.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:35:0)
[02:19:43.552] Timer tick, asking Buildbucket for the build status
[02:19:43.747] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:43.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:36:0) after 1m0s
[02:20:43.753] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:36:0)
[02:20:43.753] Timer tick, asking Buildbucket for the build status
[02:20:44.151] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:44.151] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:37:0) after 1m0s
[02:21:44.169] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:37:0)
[02:21:44.169] Timer tick, asking Buildbucket for the build status
[02:21:44.245] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:44.245] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:38:0) after 1m0s
[02:22:44.288] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:38:0)
[02:22:44.288] Timer tick, asking Buildbucket for the build status
[02:22:44.394] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:44.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:39:0) after 1m0s
[02:23:44.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:39:0)
[02:23:44.394] Timer tick, asking Buildbucket for the build status
[02:23:44.858] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:44.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:40:0) after 1m0s
[02:24:44.872] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:40:0)
[02:24:44.872] Timer tick, asking Buildbucket for the build status
[02:24:44.978] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:44.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:41:0) after 1m0s
[02:25:44.998] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:41:0)
[02:25:44.998] Timer tick, asking Buildbucket for the build status
[02:25:45.097] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:45.097] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:42:0) after 1m0s
[02:26:45.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:42:0)
[02:26:45.085] Timer tick, asking Buildbucket for the build status
[02:26:45.251] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:45.251] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:43:0) after 1m0s
[02:27:45.266] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:43:0)
[02:27:45.266] Timer tick, asking Buildbucket for the build status
[02:27:45.505] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:45.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:44:0) after 1m0s
[02:28:45.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:44:0)
[02:28:45.529] Timer tick, asking Buildbucket for the build status
[02:28:45.696] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:45.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:45:0) after 1m0s
[02:29:45.698] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:45:0)
[02:29:45.698] Timer tick, asking Buildbucket for the build status
[02:29:46.273] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:46.273] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:46:0) after 1m0s
[02:30:46.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:46:0)
[02:30:46.247] Timer tick, asking Buildbucket for the build status
[02:30:46.339] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:46.339] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:47:0) after 1m0s
[02:31:46.360] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:47:0)
[02:31:46.360] Timer tick, asking Buildbucket for the build status
[02:31:46.796] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:46.796] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:48:0) after 1m0s
[02:32:46.782] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:48:0)
[02:32:46.782] Timer tick, asking Buildbucket for the build status
[02:32:47.402] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:47.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:49:0) after 1m0s
[02:33:47.430] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:49:0)
[02:33:47.430] Timer tick, asking Buildbucket for the build status
[02:33:47.552] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:47.552] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:50:0) after 1m0s
[02:34:47.574] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:50:0)
[02:34:47.574] Timer tick, asking Buildbucket for the build status
[02:34:47.736] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:47.736] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:51:0) after 1m0s
[02:35:47.770] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:51:0)
[02:35:47.770] Timer tick, asking Buildbucket for the build status
[02:35:47.867] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:47.867] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:52:0) after 1m0s
[02:36:47.889] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:52:0)
[02:36:47.889] Timer tick, asking Buildbucket for the build status
[02:36:48.104] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:48.104] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:53:0) after 1m0s
[02:37:48.097] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:53:0)
[02:37:48.097] Timer tick, asking Buildbucket for the build status
[02:37:48.246] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:48.246] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:54:0) after 1m0s
[02:38:48.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:54:0)
[02:38:48.268] Timer tick, asking Buildbucket for the build status
[02:38:49.989] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:55:0) after 1m0s
[02:39:50.016] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:55:0)
[02:39:50.016] Timer tick, asking Buildbucket for the build status
[02:39:50.385] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:50.385] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:56:0) after 1m0s
[02:40:50.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:56:0)
[02:40:50.390] Timer tick, asking Buildbucket for the build status
[02:40:50.876] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:50.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:57:0) after 1m0s
[02:41:50.898] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:57:0)
[02:41:50.898] Timer tick, asking Buildbucket for the build status
[02:41:51.059] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:51.059] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:58:0) after 1m0s
[02:42:51.095] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:58:0)
[02:42:51.095] Timer tick, asking Buildbucket for the build status
[02:42:51.231] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:51.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:59:0) after 1m0s
[02:43:51.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:59:0)
[02:43:51.258] Timer tick, asking Buildbucket for the build status
[02:43:51.513] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:51.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:60:0) after 1m0s
[02:44:51.536] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:60:0)
[02:44:51.536] Timer tick, asking Buildbucket for the build status
[02:44:52.043] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:52.043] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:61:0) after 1m0s
[02:45:52.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:61:0)
[02:45:52.085] Timer tick, asking Buildbucket for the build status
[02:45:52.208] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:52.208] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:62:0) after 1m0s
[02:46:52.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:62:0)
[02:46:52.235] Timer tick, asking Buildbucket for the build status
[02:46:52.446] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:52.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:63:0) after 1m0s
[02:47:52.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:63:0)
[02:47:52.475] Timer tick, asking Buildbucket for the build status
[02:47:52.615] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:52.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:64:0) after 1m0s
[02:48:52.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:64:0)
[02:48:52.638] Timer tick, asking Buildbucket for the build status
[02:48:53.438] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:53.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:65:0) after 1m0s
[02:49:53.480] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:65:0)
[02:49:53.480] Timer tick, asking Buildbucket for the build status
[02:49:53.650] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:53.650] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:66:0) after 1m0s
[02:50:53.674] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:66:0)
[02:50:53.674] Timer tick, asking Buildbucket for the build status
[02:50:53.890] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:53.890] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:67:0) after 1m0s
[02:51:53.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:67:0)
[02:51:53.948] Timer tick, asking Buildbucket for the build status
[02:51:54.057] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:54.057] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:68:0) after 1m0s
[02:52:54.059] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:68:0)
[02:52:54.059] Timer tick, asking Buildbucket for the build status
[02:52:54.263] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:54.263] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:69:0) after 1m0s
[02:53:54.277] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:69:0)
[02:53:54.277] Timer tick, asking Buildbucket for the build status
[02:53:54.527] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:54.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:70:0) after 1m0s
[02:54:54.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:70:0)
[02:54:54.597] Timer tick, asking Buildbucket for the build status
[02:54:55.194] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:55.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:71:0) after 1m0s
[02:55:55.187] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:71:0)
[02:55:55.187] Timer tick, asking Buildbucket for the build status
[02:55:55.406] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:55.406] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:72:0) after 1m0s
[02:56:55.431] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:72:0)
[02:56:55.431] Timer tick, asking Buildbucket for the build status
[02:56:55.817] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:55.817] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:73:0) after 1m0s
[02:57:55.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:73:0)
[02:57:55.866] Timer tick, asking Buildbucket for the build status
[02:57:55.981] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:55.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:74:0) after 1m0s
[02:58:56.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:74:0)
[02:58:56.013] Timer tick, asking Buildbucket for the build status
[02:58:56.809] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:56.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:75:0) after 1m0s
[02:59:56.793] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:75:0)
[02:59:56.793] Timer tick, asking Buildbucket for the build status
[02:59:57.753] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:57.753] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:76:0) after 1m0s
[03:00:57.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:76:0)
[03:00:57.901] Timer tick, asking Buildbucket for the build status
[03:00:57.987] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:57.987] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:77:0) after 1m0s
[03:01:58.009] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:77:0)
[03:01:58.009] Timer tick, asking Buildbucket for the build status
[03:01:58.133] Build 8918234752044292672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:58.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083664814151623424:78:0) after 1m0s
[03:02:53.557] Received PubSub notification, asking Buildbucket for the build status
[03:02:53.669] Build 8918234752044292672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:02:53.669] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1553310172643223",
"created_by": "project:chromium",
"created_ts": "1553305591501978",
"id": "8918234752044292672",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"643206e41bceb79715e115877932e8c1147d8b3e\"}}",
"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\": \"gce-trusty-e833d7b0-us-west1-a-s6lc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 5816, \"got_angle_revision\": \"a8ff88143af676ac493e0df7d1a81997066c1cd0\", \"got_dawn_revision\": \"07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf\", \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_revision\": \"643206e41bceb79715e115877932e8c1147d8b3e\", \"got_revision_cp\": \"refs/heads/master@{#643643}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"15487fb22d31f800927f52379ff2109f308378f6\", \"got_v8_revision_cp\": \"refs/heads/7.5.99@{#1}\", \"got_webrtc_revision\": \"31660fdfeacce1ebd27650e0fdf1121b72c8b6f2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27245}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"643206e41bceb79715e115877932e8c1147d8b3e\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Deterministic Linux (dbg)\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-a-s6lc\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4216-cf4a3cd\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-a\"]}}, \"ui\": {\"info\": \"\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553305593684741",
"status": "COMPLETED",
"status_changed_ts": "1553310173364923",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5816",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/643206e41bceb79715e115877932e8c1147d8b3e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/643206e41bceb79715e115877932e8c1147d8b3e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083664814151623424",
"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/8918234752044292672/+/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:43c10be8b3ebe310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553310173374648",
"url": "https://ci.chromium.org/b/8918234752044292672",
"utcnow_ts": "1553310173659647"
}
}
[03:02:53.669] Invocation finished in 1h16m23.561280401s with status SUCCEEDED