[03:26:38.607] New invocation is queued and will start shortly
[03:26:39.777] Starting the invocation (attempt 1)
[03:26:39.815] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:26:39.815] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:26:39.815] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054123902753363728",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"250f59406553006ebaead1915df96502621fd993\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054123902753363728",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/250f59406553006ebaead1915df96502621fd993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/250f59406553006ebaead1915df96502621fd993",
"gitiles_ref:refs/heads/master"
]
}
[03:26:40.568] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581477999944537",
"id": "8888693840689471152",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"250f59406553006ebaead1915df96502621fd993\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581478000441553",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168501",
"builder:Linux Builder (dbg)",
"buildset:commit/git/250f59406553006ebaead1915df96502621fd993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/250f59406553006ebaead1915df96502621fd993",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054123902753363728",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888693840689471152/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1581478000441652",
"url": "https://ci.chromium.org/b/8888693840689471152",
"utcnow_ts": "1581478000561279"
}
}
[03:26:40.568] Task URL: https://ci.chromium.org/b/8888693840689471152
[03:26:40.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:2:0) after 1m0s
[03:27:40.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:2:0)
[03:27:40.675] Timer tick, asking Buildbucket for the build status
[03:27:41.209] Build 8888693840689471152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:41.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:3:0) after 1m0s
[03:28:41.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:3:0)
[03:28:41.211] Timer tick, asking Buildbucket for the build status
[03:28:41.382] Build 8888693840689471152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:41.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:4:0) after 1m0s
[03:29:41.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:4:0)
[03:29:41.410] Timer tick, asking Buildbucket for the build status
[03:29:42.270] Build 8888693840689471152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:42.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:5:0) after 1m0s
[03:30:42.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:5:0)
[03:30:42.372] Timer tick, asking Buildbucket for the build status
[03:30:42.507] Build 8888693840689471152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:42.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:6:0) after 1m0s
[03:31:05.240] Received PubSub notification, asking Buildbucket for the build status
[03:31:05.761] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:42.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:6:0)
[03:31:42.553] Timer tick, asking Buildbucket for the build status
[03:31:42.725] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:42.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:8:0) after 1m0s
[03:32:42.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:8:0)
[03:32:42.775] Timer tick, asking Buildbucket for the build status
[03:32:43.019] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:43.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:9:0) after 1m0s
[03:33:43.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:9:0)
[03:33:43.094] Timer tick, asking Buildbucket for the build status
[03:33:43.253] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:10:0) after 1m0s
[03:34:43.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:10:0)
[03:34:43.331] Timer tick, asking Buildbucket for the build status
[03:34:43.569] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:43.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:11:0) after 1m0s
[03:35:43.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:11:0)
[03:35:43.600] Timer tick, asking Buildbucket for the build status
[03:35:44.342] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:44.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:12:0) after 1m0s
[03:36:44.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:12:0)
[03:36:44.397] Timer tick, asking Buildbucket for the build status
[03:36:44.634] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:44.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:13:0) after 1m0s
[03:37:44.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:13:0)
[03:37:44.692] Timer tick, asking Buildbucket for the build status
[03:37:45.486] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:45.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:14:0) after 1m0s
[03:38:45.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:14:0)
[03:38:45.508] Timer tick, asking Buildbucket for the build status
[03:38:45.665] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:45.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:15:0) after 1m0s
[03:39:45.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:15:0)
[03:39:45.685] Timer tick, asking Buildbucket for the build status
[03:39:46.348] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:46.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:16:0) after 1m0s
[03:40:46.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:16:0)
[03:40:46.383] Timer tick, asking Buildbucket for the build status
[03:40:46.551] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:46.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:17:0) after 1m0s
[03:41:46.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:17:0)
[03:41:46.573] Timer tick, asking Buildbucket for the build status
[03:41:46.716] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:46.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:18:0) after 1m0s
[03:42:46.740] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:18:0)
[03:42:46.740] Timer tick, asking Buildbucket for the build status
[03:42:47.356] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:47.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:19:0) after 1m0s
[03:43:47.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:19:0)
[03:43:47.382] Timer tick, asking Buildbucket for the build status
[03:43:47.791] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:47.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:20:0) after 1m0s
[03:44:47.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:20:0)
[03:44:47.829] Timer tick, asking Buildbucket for the build status
[03:44:48.056] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:48.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:21:0) after 1m0s
[03:45:48.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:21:0)
[03:45:48.119] Timer tick, asking Buildbucket for the build status
[03:45:48.265] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:48.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:22:0) after 1m0s
[03:46:48.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:22:0)
[03:46:48.407] Timer tick, asking Buildbucket for the build status
[03:46:48.576] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:48.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:23:0) after 1m0s
[03:47:48.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:23:0)
[03:47:48.589] Timer tick, asking Buildbucket for the build status
[03:47:49.619] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:49.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:24:0) after 1m0s
[03:48:49.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:24:0)
[03:48:49.673] Timer tick, asking Buildbucket for the build status
[03:48:50.202] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:50.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:25:0) after 1m0s
[03:49:50.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:25:0)
[03:49:50.195] Timer tick, asking Buildbucket for the build status
[03:49:51.482] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:51.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:26:0) after 1m0s
[03:50:51.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:26:0)
[03:50:51.477] Timer tick, asking Buildbucket for the build status
[03:50:51.796] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:51.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:27:0) after 1m0s
[03:51:51.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:27:0)
[03:51:51.815] Timer tick, asking Buildbucket for the build status
[03:51:51.959] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:51.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:28:0) after 1m0s
[03:52:52.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:28:0)
[03:52:52.058] Timer tick, asking Buildbucket for the build status
[03:52:53.357] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:53.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:29:0) after 1m0s
[03:53:53.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:29:0)
[03:53:53.445] Timer tick, asking Buildbucket for the build status
[03:53:53.868] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:53.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:30:0) after 1m0s
[03:54:53.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:30:0)
[03:54:53.923] Timer tick, asking Buildbucket for the build status
[03:54:54.123] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:31:0) after 1m0s
[03:55:54.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:31:0)
[03:55:54.176] Timer tick, asking Buildbucket for the build status
[03:55:54.333] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:54.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:32:0) after 1m0s
[03:56:54.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:32:0)
[03:56:54.387] Timer tick, asking Buildbucket for the build status
[03:56:54.535] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:54.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:33:0) after 1m0s
[03:57:54.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:33:0)
[03:57:54.573] Timer tick, asking Buildbucket for the build status
[03:57:54.858] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:54.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:34:0) after 1m0s
[03:58:54.961] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:34:0)
[03:58:54.961] Timer tick, asking Buildbucket for the build status
[03:58:55.133] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:55.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:35:0) after 1m0s
[03:59:55.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:35:0)
[03:59:55.194] Timer tick, asking Buildbucket for the build status
[03:59:55.820] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:55.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:36:0) after 1m0s
[04:00:55.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:36:0)
[04:00:55.941] Timer tick, asking Buildbucket for the build status
[04:00:56.296] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:56.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:37:0) after 1m0s
[04:01:56.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:37:0)
[04:01:56.380] Timer tick, asking Buildbucket for the build status
[04:01:57.009] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:57.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:38:0) after 1m0s
[04:02:57.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:38:0)
[04:02:57.036] Timer tick, asking Buildbucket for the build status
[04:02:58.327] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:58.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:39:0) after 1m0s
[04:03:58.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:39:0)
[04:03:58.372] Timer tick, asking Buildbucket for the build status
[04:03:58.539] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:58.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:40:0) after 1m0s
[04:04:58.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:40:0)
[04:04:58.701] Timer tick, asking Buildbucket for the build status
[04:04:59.001] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:59.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:41:0) after 1m0s
[04:05:59.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:41:0)
[04:05:59.046] Timer tick, asking Buildbucket for the build status
[04:05:59.259] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:59.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:42:0) after 1m0s
[04:06:59.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:42:0)
[04:06:59.310] Timer tick, asking Buildbucket for the build status
[04:06:59.437] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:43:0) after 1m0s
[04:07:59.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:43:0)
[04:07:59.465] Timer tick, asking Buildbucket for the build status
[04:07:59.597] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:59.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:44:0) after 1m0s
[04:08:59.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:44:0)
[04:08:59.672] Timer tick, asking Buildbucket for the build status
[04:08:59.881] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:59.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:45:0) after 1m0s
[04:09:59.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:45:0)
[04:09:59.973] Timer tick, asking Buildbucket for the build status
[04:10:00.118] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:00.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:46:0) after 1m0s
[04:11:00.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:46:0)
[04:11:00.210] Timer tick, asking Buildbucket for the build status
[04:11:00.372] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:00.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:47:0) after 1m0s
[04:12:00.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:47:0)
[04:12:00.462] Timer tick, asking Buildbucket for the build status
[04:12:01.267] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:01.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:48:0) after 1m0s
[04:13:01.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:48:0)
[04:13:01.285] Timer tick, asking Buildbucket for the build status
[04:13:01.488] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:01.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:49:0) after 1m0s
[04:14:01.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:49:0)
[04:14:01.584] Timer tick, asking Buildbucket for the build status
[04:14:01.915] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:01.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:50:0) after 1m0s
[04:15:02.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:50:0)
[04:15:02.079] Timer tick, asking Buildbucket for the build status
[04:15:02.253] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:02.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:51:0) after 1m0s
[04:16:02.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:51:0)
[04:16:02.309] Timer tick, asking Buildbucket for the build status
[04:16:02.678] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:02.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:52:0) after 1m0s
[04:17:02.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:52:0)
[04:17:02.757] Timer tick, asking Buildbucket for the build status
[04:17:03.198] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:03.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:53:0) after 1m0s
[04:18:03.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:53:0)
[04:18:03.215] Timer tick, asking Buildbucket for the build status
[04:18:03.796] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:03.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:54:0) after 1m0s
[04:19:03.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:54:0)
[04:19:03.853] Timer tick, asking Buildbucket for the build status
[04:19:04.035] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:04.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:55:0) after 1m0s
[04:20:04.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:55:0)
[04:20:04.199] Timer tick, asking Buildbucket for the build status
[04:20:05.894] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:05.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:56:0) after 1m0s
[04:21:05.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:56:0)
[04:21:05.923] Timer tick, asking Buildbucket for the build status
[04:21:06.256] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:57:0) after 1m0s
[04:22:06.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:57:0)
[04:22:06.321] Timer tick, asking Buildbucket for the build status
[04:22:06.597] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:06.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:58:0) after 1m0s
[04:23:06.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:58:0)
[04:23:06.635] Timer tick, asking Buildbucket for the build status
[04:23:07.680] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:07.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:59:0) after 1m0s
[04:24:07.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:59:0)
[04:24:07.805] Timer tick, asking Buildbucket for the build status
[04:24:08.402] Build 8888693840689471152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:08.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054123902753363728:60:0) after 1m0s
[04:24:09.265] Received PubSub notification, asking Buildbucket for the build status
[04:24:09.379] Build 8888693840689471152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:24:09.379] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581481448492411",
"created_by": "project:chromium",
"created_ts": "1581477999944537",
"id": "8888693840689471152",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"250f59406553006ebaead1915df96502621fd993\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-103-btod\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168501, \"got_angle_revision\": \"e1ccac7ff9f088c841a4393b633be402012cfb92\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"250f59406553006ebaead1915df96502621fd993\", \"got_revision_cp\": \"refs/heads/master@{#740539}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"6cbb02e0dc934ac1e21b182f0f632b4e806449a6\", \"got_v8_revision\": \"56ab990e69987df228757938e23a9bafccab2fe5\", \"got_v8_revision_cp\": \"refs/heads/8.2.57@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"250f59406553006ebaead1915df96502621fd993\", \"swarm_hashes_refs/heads/master(at){#740539}_without_patch\": {\"accessibility_unittests\": \"6d443fa96377636eb314b5e5f90f5c5e36f2b7c1\", \"angle_unittests\": \"fffcbb0505b010c5fc19a37e663c7a982c084f72\", \"app_shell_unittests\": \"3d84bad4c27ae4f1da4c6b6b403dae58796a2ee0\", \"aura_unittests\": \"5ee7923d91f40cb89b4deb7319e96d8218f0e783\", \"base_unittests\": \"cdd413b015c7c382a06b63c32d6c8083f835f7bf\", \"base_util_unittests\": \"55a9eb685b3449b7d31afbdca18e8a34fbd84456\", \"blink_common_unittests\": \"984cfeb8f3dcbfe7bc7dad7f977682fa0b22f39b\", \"blink_fuzzer_unittests\": \"f28f7f61f424c62f903d4d63446bc4e1f2a6afe5\", \"blink_heap_unittests\": \"fbf25da6e3629285f5a48265d0fddd24bbe2f087\", \"blink_platform_unittests\": \"13ae18f4c22bfd965d7cb2e638cdf0350e202299\", \"blink_python_tests\": \"66909fb4e1499e7733c5eb95536ed9ce6fc49608\", \"blink_unittests\": \"a5e0cec04310d21aa8bb4faa4b350a49293b90bd\", \"blink_web_tests\": \"05f9c69cf1896311be7baa393b57a5c2c69c80e5\", \"boringssl_crypto_tests\": \"9490a2627dc0e733a0d427c5d57d9710979ecfc4\", \"boringssl_ssl_tests\": \"505c4d84eb08ea9d832bb0eab16359c2e2f3535c\", \"browser_tests\": \"9ca5fd2cf76cd6ff6c2c5efedb7e1749939b1d3d\", \"cacheinvalidation_unittests\": \"dd903e62e7b680088570d6c4ef7968ebdea180e8\", \"capture_unittests\": \"c351080e2e6abca4c621faa9281970c557a4de49\", \"cast_unittests\": \"951094b717e2deee2831e52d4ab37917c99d00b9\", \"cc_unittests\": \"7e16182e78085b76bf06b67a04dabd799c17ecf2\", \"chrome_app_unittests\": \"8c745e907272d67bd278653781a9865fccc9826f\", \"chromedriver_unittests\": \"8912c5f84b298f216a475706b2446cbed92f5eff\", \"components_browsertests\": \"3c0bec41bc9fcf4e172cd15115e029e0ec16f036\", \"components_unittests\": \"70ff82f9e41d7ed561a4b493aaea51a83a492891\", \"compositor_unittests\": \"bbdd14935192e69d323e0640ae60f5778fa116b0\", \"content_browsertests\": \"322ccace509273b270070f8fcd57acebe11ba6e5\", \"content_shell_crash_test\": \"d1820ab6637aed0290ed5289ad32100c6ead27f7\", \"content_unittests\": \"f40358d691662bb239a6c1643515dfe10e992a97\", \"crashpad_tests\": \"3c3b0e349d025ba5cd23749ad53b686851972dbf\", \"cronet_tests\": \"d7c1e245e3ed8a8b04bb43a411b958ea676eeafd\", \"cronet_unittests\": \"fe8e7cc1a2aff67646a495fd9e5cad81a0433712\", \"crypto_unittests\": \"159377e3b2241df72c3aa3ea543673d594eccc7d\", \"dbus_unittests\": \"260ed106354a21b4e9a3b800a9250213d824825b\", \"device_unittests\": \"2ed1d35aa040fdadaed701aae4d317ca3b745ebb\", \"display_unittests\": \"b0a0383a49e5dfb4b368241c1ac49ae320c26094\", \"events_unittests\": \"c3549ea7c03a8731edd2df1e5e9a90c22d0bea8a\", \"extensions_browsertests\": \"f4adf15703879f8213446e6a46fb34f3425ca48b\", \"extensions_unittests\": \"fc0cfd2ba76cf397bd7074c806e2ca6a0e7a0bda\", \"filesystem_service_unittests\": \"2872a88302fbe79feebcf6e2b0cc77da498caad6\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"24f0c1c3516901fa5623f83b946964821fd6dd0b\", \"gfx_unittests\": \"6dea6bbd963caf6c092c03e0b9ad96f3abbaeebe\", \"gin_unittests\": \"27c7ec66ca5e3e6d32ca494099f1b4f2d6a5db37\", \"google_apis_unittests\": \"1b9b42e24266d888f3773ac0aa05c17a3cd10ddc\", \"gpu_unittests\": \"a0db475c9dc7cf237eb4cba9183baf8fb1d6534b\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"a0f0eab2143a64140c2dd5e76b155bce610a9f9a\", \"headless_browsertests\": \"622c17f36239f400a60df1c650a9b33bd3efa221\", \"headless_unittests\": \"c65d79e561d8db0bb71d99588bd9e202bade4c34\", \"interactive_ui_tests\": \"a350d0fd7a65c60c2f9db75f2dfb2ebdb6c0f77d\", \"ipc_tests\": \"3995c8b1fb8de7d08200a75f5328159a20671c45\", \"jingle_unittests\": \"488e476e0869d43292e344642dde4e45f5e8a309\", \"latency_unittests\": \"1034a1f6edbebec0495d760d37fae759c26ab8e9\", \"libjingle_xmpp_unittests\": \"c19cfdd69efeccab8f74d819e787c228bde1364f\", \"media_blink_unittests\": \"604d10b53945ff22e67a3629ab6227c11fec583f\", \"media_service_unittests\": \"9cd79f49dda153ce37ccc0fef942913f194095ee\", \"media_unittests\": \"f37d91b580ea798aa2651b559a8f297dfea510fb\", \"message_center_unittests\": \"0cc41bc70dfdf615a5040f2a12a4c227a307c2f2\", \"metrics_python_tests\": \"75c4752c1d04b02d009b8a0d6ff4f503078aacce\", \"midi_unittests\": \"49c8aedfeded1e234fd07fbb12e543c7993ee705\", \"mojo_core_unittests\": \"084da626e50d3c038bde389d1fbcfb3b517ad3b1\", \"mojo_unittests\": \"145bfa651065f4b4dcd71fc19ea20cc8bfbed4bf\", \"nacl_helper_nonsfi_unittests\": \"1b4721ccc7e74b8032182074de33fdbf16701175\", \"nacl_loader_unittests\": \"e7112f2f68d425bc17777246d9e5cf2060815cf7\", \"native_theme_unittests\": \"922b85a28005e81c8822b5ddc1c83ce736d4cf10\", \"net_unittests\": \"ccc4b074a6af8f335362af0e25215a2b165280b4\", \"pdf_unittests\": \"cd3fcd8982bd88492ec8de799dfde385b9dc2e0c\", \"perfetto_unittests\": \"b6f727039386e61627e75640e25e4b9d6f925c9e\", \"ppapi_unittests\": \"5fd72196ccfb56643dcf52f144a0c71234e0c3f0\", \"printing_unittests\": \"48eea4e6865cf2d43c5cc3165ae038c9dbe0562a\", \"remoting_unittests\": \"dca24c48ee3868252392c8e77b92acf51f9c4a74\", \"sandbox_linux_unittests\": \"7a2e4066b74009de43b8f8a025e5d6856fbd2ca7\", \"service_manager_unittests\": \"d25a4dbcd3f0c8314a79c3ed12b07c28928ce76c\", \"services_unittests\": \"037d895ac8938b4a66cb8794b9685e43c180d339\", \"shell_dialogs_unittests\": \"ffd56499cff6d17d22fdeb735fc326daf8a634bf\", \"skia_unittests\": \"d7da7dc0dc1e5700377a091c494302da298c2513\", \"snapshot_unittests\": \"ec273cae0cf5788526ccff8b53bc6b5c9685ff2b\", \"sql_unittests\": \"f21ccfb9235a78fee95c0aad387334c4a2da8892\", \"storage_unittests\": \"ee939d897c0559a45593e2f858af6e7c4aa9dfa6\", \"sync_integration_tests\": \"3d5ebeee0bd58525f2a88242d297bcc71bb57b59\", \"telemetry_gpu_unittests\": \"30ccd11b3a6be34816c95be836739b71659be06a\", \"telemetry_perf_unittests\": \"d9f07e146e08dcd4516edc232e1d37a961567417\", \"telemetry_unittests\": \"95576420fef6c45edae757c338dbcad4500e9986\", \"traffic_annotation_auditor_unittests\": \"f49113603996cc3170f6076af408963a9f68b0c8\", \"ui_base_unittests\": \"32efdd387eaed3b145e1e732d0e58d83ad09f9ce\", \"ui_touch_selection_unittests\": \"c7b60c316e978bc66c067daee144369d9813a1ac\", \"unit_tests\": \"3a7c1d2f807c019512e2b5c0fb8b6be902d41efe\", \"url_unittests\": \"41692b692026210d04257a1edee3a4d4095e0e17\", \"views_perftests\": \"e657d9782c6fd7f85f0bd1fc01dfed8e4492f0d3\", \"views_unittests\": \"2ce251f34f1352a1c30c65dda65264e64ca511fc\", \"viz_unittests\": \"698da8b6d219dd885507f82bb2066b2411e11f6d\", \"vr_common_unittests\": \"cec66d3925b8cf6af90b91a6e401f788cf7834f6\", \"vr_pixeltests\": \"c0cfcc5c841895ccf4820f239da8125c6681dd01\", \"webdriver_wpt_tests\": \"ab809469fa7e66b1c1403e2da78f54cd93499961\", \"weblayer_browsertests\": \"ba97b150e2729e4ac14252e685bb5c54e8e49286\", \"wm_unittests\": \"3acf8fbeb46a18d66150f25ca402246f3b617b40\", \"wtf_unittests\": \"487758f1533915967f677414840ff4ff67c01f3c\", \"xr_browser_tests\": \"6b422cb3c7b1a95e39d90dfbed09e3fd41d39abc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-103-btod\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581478264170618",
"status": "COMPLETED",
"status_changed_ts": "1581481448702811",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168501",
"builder:Linux Builder (dbg)",
"buildset:commit/git/250f59406553006ebaead1915df96502621fd993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/250f59406553006ebaead1915df96502621fd993",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054123902753363728",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888693840689471152/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4a5040c074db7710",
"user_agent:luci-scheduler"
],
"updated_ts": "1581481448719446",
"url": "https://ci.chromium.org/b/8888693840689471152",
"utcnow_ts": "1581481449370516"
}
}
[04:24:09.379] Invocation finished in 57m30.787678354s with status SUCCEEDED