[15:17:36.167] New invocation is queued and will start shortly
[15:17:37.432] Starting the invocation (attempt 1)
[15:17:37.464] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:17:37.464] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:17:37.464] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087781244563030336",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b17840912bf84e8766e9c27a09cff38cbef69af9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9087781244563030336",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b17840912bf84e8766e9c27a09cff38cbef69af9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b17840912bf84e8766e9c27a09cff38cbef69af9",
"gitiles_ref:refs/heads/master"
]
}
[15:17:39.323] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549379857572217",
"id": "8922351182424972256",
"lease_expiration_ts": "1549498658850086",
"lease_key": "1963394800",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b17840912bf84e8766e9c27a09cff38cbef69af9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549379859121367",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/4932",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/b17840912bf84e8766e9c27a09cff38cbef69af9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b17840912bf84e8766e9c27a09cff38cbef69af9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087781244563030336",
"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/8922351182424972256/+/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:42d70df879b96810",
"user_agent:luci-scheduler"
],
"updated_ts": "1549379859121498",
"url": "https://ci.chromium.org/b/8922351182424972256",
"utcnow_ts": "1549379859307064"
}
}
[15:17:39.323] Task URL: https://ci.chromium.org/b/8922351182424972256
[15:17:39.323] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:2:0) after 1m0s
[15:17:41.775] Received PubSub notification, asking Buildbucket for the build status
[15:17:41.836] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:39.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:2:0)
[15:18:39.345] Timer tick, asking Buildbucket for the build status
[15:18:39.513] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:39.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:4:0) after 1m0s
[15:19:39.533] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:4:0)
[15:19:39.533] Timer tick, asking Buildbucket for the build status
[15:19:39.618] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:39.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:5:0) after 1m0s
[15:20:39.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:5:0)
[15:20:39.672] Timer tick, asking Buildbucket for the build status
[15:20:39.715] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:39.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:6:0) after 1m0s
[15:21:39.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:6:0)
[15:21:39.760] Timer tick, asking Buildbucket for the build status
[15:21:39.878] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:39.878] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:7:0) after 1m0s
[15:22:39.897] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:7:0)
[15:22:39.897] Timer tick, asking Buildbucket for the build status
[15:22:40.073] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:40.073] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:8:0) after 1m0s
[15:23:40.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:8:0)
[15:23:40.153] Timer tick, asking Buildbucket for the build status
[15:23:40.206] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:40.206] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:9:0) after 1m0s
[15:24:40.236] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:9:0)
[15:24:40.236] Timer tick, asking Buildbucket for the build status
[15:24:40.355] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:40.355] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:10:0) after 1m0s
[15:25:40.375] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:10:0)
[15:25:40.375] Timer tick, asking Buildbucket for the build status
[15:25:40.554] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:40.554] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:11:0) after 1m0s
[15:26:40.646] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:11:0)
[15:26:40.646] Timer tick, asking Buildbucket for the build status
[15:26:41.350] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:41.350] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:12:0) after 1m0s
[15:27:41.374] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:12:0)
[15:27:41.374] Timer tick, asking Buildbucket for the build status
[15:27:41.436] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:41.436] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:13:0) after 1m0s
[15:28:41.456] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:13:0)
[15:28:41.456] Timer tick, asking Buildbucket for the build status
[15:28:41.957] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:41.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:14:0) after 1m0s
[15:29:41.986] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:14:0)
[15:29:41.986] Timer tick, asking Buildbucket for the build status
[15:29:42.269] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:42.269] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:15:0) after 1m0s
[15:30:42.292] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:15:0)
[15:30:42.292] Timer tick, asking Buildbucket for the build status
[15:30:42.625] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:42.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:16:0) after 1m0s
[15:31:42.648] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:16:0)
[15:31:42.648] Timer tick, asking Buildbucket for the build status
[15:31:42.704] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:42.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:17:0) after 1m0s
[15:32:42.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:17:0)
[15:32:42.728] Timer tick, asking Buildbucket for the build status
[15:32:42.818] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:42.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:18:0) after 1m0s
[15:33:42.841] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:18:0)
[15:33:42.841] Timer tick, asking Buildbucket for the build status
[15:33:43.243] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:43.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:19:0) after 1m0s
[15:34:43.265] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:19:0)
[15:34:43.265] Timer tick, asking Buildbucket for the build status
[15:34:43.472] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:43.472] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:20:0) after 1m0s
[15:35:43.490] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:20:0)
[15:35:43.490] Timer tick, asking Buildbucket for the build status
[15:35:43.536] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:43.536] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:21:0) after 1m0s
[15:36:43.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:21:0)
[15:36:43.556] Timer tick, asking Buildbucket for the build status
[15:36:43.648] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:43.648] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:22:0) after 1m0s
[15:37:43.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:22:0)
[15:37:43.692] Timer tick, asking Buildbucket for the build status
[15:37:43.761] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:43.761] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:23:0) after 1m0s
[15:38:43.782] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:23:0)
[15:38:43.782] Timer tick, asking Buildbucket for the build status
[15:38:43.849] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:43.849] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:24:0) after 1m0s
[15:39:43.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:24:0)
[15:39:43.866] Timer tick, asking Buildbucket for the build status
[15:39:43.954] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:25:0) after 1m0s
[15:40:43.974] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:25:0)
[15:40:43.974] Timer tick, asking Buildbucket for the build status
[15:40:44.272] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:44.273] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:26:0) after 1m0s
[15:41:44.289] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:26:0)
[15:41:44.289] Timer tick, asking Buildbucket for the build status
[15:41:44.335] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:44.335] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:27:0) after 1m0s
[15:42:44.379] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:27:0)
[15:42:44.379] Timer tick, asking Buildbucket for the build status
[15:42:44.491] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:44.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:28:0) after 1m0s
[15:43:44.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:28:0)
[15:43:44.514] Timer tick, asking Buildbucket for the build status
[15:43:44.883] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:44.883] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:29:0) after 1m0s
[15:44:44.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:29:0)
[15:44:44.906] Timer tick, asking Buildbucket for the build status
[15:44:45.066] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:45.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:30:0) after 1m0s
[15:45:45.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:30:0)
[15:45:45.105] Timer tick, asking Buildbucket for the build status
[15:45:45.392] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:45.393] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:31:0) after 1m0s
[15:46:45.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:31:0)
[15:46:45.470] Timer tick, asking Buildbucket for the build status
[15:46:45.542] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:45.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:32:0) after 1m0s
[15:47:45.566] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:32:0)
[15:47:45.566] Timer tick, asking Buildbucket for the build status
[15:47:47.406] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:47.406] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:33:0) after 1m0s
[15:48:47.426] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:33:0)
[15:48:47.426] Timer tick, asking Buildbucket for the build status
[15:48:47.477] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:47.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:34:0) after 1m0s
[15:49:47.509] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:34:0)
[15:49:47.509] Timer tick, asking Buildbucket for the build status
[15:49:47.713] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:47.713] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:35:0) after 1m0s
[15:50:47.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:35:0)
[15:50:47.734] Timer tick, asking Buildbucket for the build status
[15:50:47.782] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:47.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:36:0) after 1m0s
[15:51:47.813] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:36:0)
[15:51:47.813] Timer tick, asking Buildbucket for the build status
[15:51:47.888] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:47.888] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:37:0) after 1m0s
[15:52:47.908] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:37:0)
[15:52:47.908] Timer tick, asking Buildbucket for the build status
[15:52:48.064] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:48.064] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:38:0) after 1m0s
[15:53:48.084] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:38:0)
[15:53:48.084] Timer tick, asking Buildbucket for the build status
[15:53:48.447] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:48.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:39:0) after 1m0s
[15:54:48.464] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:39:0)
[15:54:48.464] Timer tick, asking Buildbucket for the build status
[15:54:48.533] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:48.533] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:40:0) after 1m0s
[15:55:48.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:40:0)
[15:55:48.553] Timer tick, asking Buildbucket for the build status
[15:55:48.710] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:48.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:41:0) after 1m0s
[15:56:48.745] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:41:0)
[15:56:48.745] Timer tick, asking Buildbucket for the build status
[15:56:48.870] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:48.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:42:0) after 1m0s
[15:57:48.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:42:0)
[15:57:48.977] Timer tick, asking Buildbucket for the build status
[15:57:49.073] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:49.073] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:43:0) after 1m0s
[15:58:49.095] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:43:0)
[15:58:49.095] Timer tick, asking Buildbucket for the build status
[15:58:49.139] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:49.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:44:0) after 1m0s
[15:59:49.158] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:44:0)
[15:59:49.158] Timer tick, asking Buildbucket for the build status
[15:59:49.230] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:49.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:45:0) after 1m0s
[16:00:49.255] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:45:0)
[16:00:49.255] Timer tick, asking Buildbucket for the build status
[16:00:49.299] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:49.299] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:46:0) after 1m0s
[16:01:49.326] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:46:0)
[16:01:49.326] Timer tick, asking Buildbucket for the build status
[16:01:49.457] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:49.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:47:0) after 1m0s
[16:02:49.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:47:0)
[16:02:49.489] Timer tick, asking Buildbucket for the build status
[16:02:49.640] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:49.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:48:0) after 1m0s
[16:03:49.689] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:48:0)
[16:03:49.689] Timer tick, asking Buildbucket for the build status
[16:03:49.853] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:49.853] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:49:0) after 1m0s
[16:04:49.959] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:49:0)
[16:04:49.959] Timer tick, asking Buildbucket for the build status
[16:04:50.383] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:50.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:50:0) after 1m0s
[16:05:50.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:50:0)
[16:05:50.403] Timer tick, asking Buildbucket for the build status
[16:05:50.469] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:50.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:51:0) after 1m0s
[16:06:50.574] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:51:0)
[16:06:50.574] Timer tick, asking Buildbucket for the build status
[16:06:50.685] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:50.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:52:0) after 1m0s
[16:07:50.794] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:52:0)
[16:07:50.794] Timer tick, asking Buildbucket for the build status
[16:07:50.870] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:50.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:53:0) after 1m0s
[16:08:50.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:53:0)
[16:08:50.896] Timer tick, asking Buildbucket for the build status
[16:08:51.025] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:51.025] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:54:0) after 1m0s
[16:09:51.050] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:54:0)
[16:09:51.050] Timer tick, asking Buildbucket for the build status
[16:09:51.112] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:51.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:55:0) after 1m0s
[16:10:51.140] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:55:0)
[16:10:51.140] Timer tick, asking Buildbucket for the build status
[16:10:51.208] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:51.208] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:56:0) after 1m0s
[16:11:51.231] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:56:0)
[16:11:51.231] Timer tick, asking Buildbucket for the build status
[16:11:51.342] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:51.342] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:57:0) after 1m0s
[16:12:51.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:57:0)
[16:12:51.367] Timer tick, asking Buildbucket for the build status
[16:12:51.562] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:51.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:58:0) after 1m0s
[16:13:51.581] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:58:0)
[16:13:51.581] Timer tick, asking Buildbucket for the build status
[16:13:51.708] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:51.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:59:0) after 1m0s
[16:14:51.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:59:0)
[16:14:51.734] Timer tick, asking Buildbucket for the build status
[16:14:51.789] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:51.789] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:60:0) after 1m0s
[16:15:51.812] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:60:0)
[16:15:51.812] Timer tick, asking Buildbucket for the build status
[16:15:51.968] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:51.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:61:0) after 1m0s
[16:16:52.005] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:61:0)
[16:16:52.005] Timer tick, asking Buildbucket for the build status
[16:16:52.237] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:52.237] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:62:0) after 1m0s
[16:17:52.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:62:0)
[16:17:52.258] Timer tick, asking Buildbucket for the build status
[16:17:52.640] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:52.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:63:0) after 1m0s
[16:18:52.677] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:63:0)
[16:18:52.677] Timer tick, asking Buildbucket for the build status
[16:18:52.878] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:52.878] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:64:0) after 1m0s
[16:19:52.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:64:0)
[16:19:52.904] Timer tick, asking Buildbucket for the build status
[16:19:52.985] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:52.985] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:65:0) after 1m0s
[16:20:53.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:65:0)
[16:20:53.035] Timer tick, asking Buildbucket for the build status
[16:20:53.129] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:53.129] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:66:0) after 1m0s
[16:21:53.158] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:66:0)
[16:21:53.158] Timer tick, asking Buildbucket for the build status
[16:21:53.313] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:53.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:67:0) after 1m0s
[16:22:53.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:67:0)
[16:22:53.351] Timer tick, asking Buildbucket for the build status
[16:22:53.857] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:53.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:68:0) after 1m0s
[16:23:53.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:68:0)
[16:23:53.888] Timer tick, asking Buildbucket for the build status
[16:23:53.986] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:53.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:69:0) after 1m0s
[16:24:54.011] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:69:0)
[16:24:54.011] Timer tick, asking Buildbucket for the build status
[16:24:54.078] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:54.078] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:70:0) after 1m0s
[16:25:54.099] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:70:0)
[16:25:54.099] Timer tick, asking Buildbucket for the build status
[16:25:55.042] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:55.042] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:71:0) after 1m0s
[16:26:55.104] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:71:0)
[16:26:55.104] Timer tick, asking Buildbucket for the build status
[16:26:55.350] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:55.350] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:72:0) after 1m0s
[16:27:55.370] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:72:0)
[16:27:55.370] Timer tick, asking Buildbucket for the build status
[16:27:55.691] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:55.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:73:0) after 1m0s
[16:28:55.711] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:73:0)
[16:28:55.711] Timer tick, asking Buildbucket for the build status
[16:28:55.827] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:55.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:74:0) after 1m0s
[16:29:55.851] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:74:0)
[16:29:55.851] Timer tick, asking Buildbucket for the build status
[16:29:56.335] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:56.335] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:75:0) after 1m0s
[16:30:56.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:75:0)
[16:30:56.367] Timer tick, asking Buildbucket for the build status
[16:30:56.422] Build 8922351182424972256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:56.422] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9087781244563030336:76:0) after 1m0s
[16:31:44.622] Received PubSub notification, asking Buildbucket for the build status
[16:31:45.032] Build 8922351182424972256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:31:45.033] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549384302595016",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549379857572217",
"id": "8922351182424972256",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b17840912bf84e8766e9c27a09cff38cbef69af9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"id\": [\"swarm627-c4\"], \"server_version\": [\"4055-721ffb4\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"b17840912bf84e8766e9c27a09cff38cbef69af9\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"00976d0db13531acd7533ae7556a69d00c7fa5c6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26543}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#629148}\", \"branch\": \"refs/heads/master\", \"revision\": \"b17840912bf84e8766e9c27a09cff38cbef69af9\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"167316b8338feb482f0c1d5239d28ed8a196331d\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"d01c504e0c185ab2afa53dce85f9d0a6a31d761e\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549379857572217, \"tags\": [\"build_address:luci.chromium.ci/Deterministic Linux (dbg)/4932\", \"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/b17840912bf84e8766e9c27a09cff38cbef69af9\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b17840912bf84e8766e9c27a09cff38cbef69af9\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087781244563030336\", \"scheduler_job_id:chromium/Deterministic Linux (dbg)\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8922351182424972256\"}}, \"got_v8_revision\": \"22770bddcb54c97cf3e903404637be7f09fd07c6\", \"got_v8_revision_cp\": \"refs/heads/7.4.71@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 4932, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Deterministic Linux (dbg)\", \"key\": \"builder\"}, {\"value\": \"commit/git/b17840912bf84e8766e9c27a09cff38cbef69af9\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/b17840912bf84e8766e9c27a09cff38cbef69af9\", \"key\": \"buildset\"}, {\"value\": \"9087781244563030336\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Deterministic Linux (dbg)\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Deterministic Linux (dbg)\", \"bucket\": \"ci\"}, \"number\": 4932, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ef156230f5cd7f8c23f689fa4dc7f92742cd904d\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922351182424972256\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"b17840912bf84e8766e9c27a09cff38cbef69af9\"}}, \"createTime\": \"2019-02-05T15:17:37.572217Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm627-c4\", \"got_buildtools_revision\": \"6fbda1b24c1893a893b17aa219b765b9e7c801d8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549379860990707",
"status": "COMPLETED",
"status_changed_ts": "1549384304031045",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/4932",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/b17840912bf84e8766e9c27a09cff38cbef69af9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b17840912bf84e8766e9c27a09cff38cbef69af9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087781244563030336",
"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/8922351182424972256/+/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:42d70df879b96810",
"user_agent:luci-scheduler"
],
"updated_ts": "1549384304032951",
"url": "https://ci.chromium.org/b/8922351182424972256",
"utcnow_ts": "1549384305020491"
}
}
[16:31:45.033] Invocation finished in 1h14m8.883916454s with status SUCCEEDED