[17:29:14.661] New invocation is queued and will start shortly
[17:29:16.416] Starting the invocation (attempt 1)
[17:29:16.495] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:29:16.496] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:29:16.496] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9049541042580809232",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dead5d3124b9a2236c5f2e72a732828e7b97ac67\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9049541042580809232",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"gitiles_ref:refs/heads/master"
]
}
[17:29:17.902] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585848556702588",
"id": "8884110979765897936",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dead5d3124b9a2236c5f2e72a732828e7b97ac67\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585848557732832",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170542",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049541042580809232",
"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/8884110979765897936/+/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": "1585848557732975",
"url": "https://ci.chromium.org/b/8884110979765897936",
"utcnow_ts": "1585848557883198"
}
}
[17:29:17.902] Task URL: https://ci.chromium.org/b/8884110979765897936
[17:29:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:2:0) after 1m0s
[17:30:17.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:2:0)
[17:30:17.986] Timer tick, asking Buildbucket for the build status
[17:30:18.135] Build 8884110979765897936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:18.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:3:0) after 1m0s
[17:31:18.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:3:0)
[17:31:18.204] Timer tick, asking Buildbucket for the build status
[17:31:18.329] Build 8884110979765897936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:18.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:4:0) after 1m0s
[17:32:18.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:4:0)
[17:32:18.383] Timer tick, asking Buildbucket for the build status
[17:32:18.514] Build 8884110979765897936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:18.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:5:0) after 1m0s
[17:33:18.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:5:0)
[17:33:18.587] Timer tick, asking Buildbucket for the build status
[17:33:19.484] Build 8884110979765897936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:19.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:6:0) after 1m0s
[17:34:04.756] Received PubSub notification, asking Buildbucket for the build status
[17:34:04.939] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:19.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:6:0)
[17:34:19.541] Timer tick, asking Buildbucket for the build status
[17:34:19.744] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:19.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:8:0) after 1m0s
[17:35:19.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:8:0)
[17:35:19.830] Timer tick, asking Buildbucket for the build status
[17:35:20.015] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:20.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:9:0) after 1m0s
[17:36:20.041] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:9:0)
[17:36:20.041] Timer tick, asking Buildbucket for the build status
[17:36:20.171] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:20.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:10:0) after 1m0s
[17:37:20.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:10:0)
[17:37:20.211] Timer tick, asking Buildbucket for the build status
[17:37:20.445] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:20.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:11:0) after 1m0s
[17:38:20.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:11:0)
[17:38:20.474] Timer tick, asking Buildbucket for the build status
[17:38:22.864] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:22.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:12:0) after 1m0s
[17:39:22.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:12:0)
[17:39:22.908] Timer tick, asking Buildbucket for the build status
[17:39:23.419] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:23.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:13:0) after 1m0s
[17:40:23.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:13:0)
[17:40:23.549] Timer tick, asking Buildbucket for the build status
[17:40:24.913] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:24.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:14:0) after 1m0s
[17:41:24.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:14:0)
[17:41:24.933] Timer tick, asking Buildbucket for the build status
[17:41:25.140] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:25.140] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:15:0) after 1m0s
[17:42:25.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:15:0)
[17:42:25.166] Timer tick, asking Buildbucket for the build status
[17:42:25.426] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:25.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:16:0) after 1m0s
[17:43:25.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:16:0)
[17:43:25.445] Timer tick, asking Buildbucket for the build status
[17:43:25.952] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:25.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:17:0) after 1m0s
[17:44:26.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:17:0)
[17:44:26.029] Timer tick, asking Buildbucket for the build status
[17:44:26.549] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:26.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:18:0) after 1m0s
[17:45:26.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:18:0)
[17:45:26.587] Timer tick, asking Buildbucket for the build status
[17:45:26.859] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:26.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:19:0) after 1m0s
[17:46:27.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:19:0)
[17:46:27.013] Timer tick, asking Buildbucket for the build status
[17:46:27.189] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:27.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:20:0) after 1m0s
[17:47:27.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:20:0)
[17:47:27.406] Timer tick, asking Buildbucket for the build status
[17:47:27.583] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:27.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:21:0) after 1m0s
[17:48:27.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:21:0)
[17:48:27.714] Timer tick, asking Buildbucket for the build status
[17:48:28.134] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:28.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:22:0) after 1m0s
[17:49:28.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:22:0)
[17:49:28.256] Timer tick, asking Buildbucket for the build status
[17:49:28.469] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:28.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:23:0) after 1m0s
[17:50:28.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:23:0)
[17:50:28.521] Timer tick, asking Buildbucket for the build status
[17:50:29.698] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:29.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:24:0) after 1m0s
[17:51:29.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:24:0)
[17:51:29.767] Timer tick, asking Buildbucket for the build status
[17:51:29.941] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:29.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:25:0) after 1m0s
[17:52:30.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:25:0)
[17:52:30.037] Timer tick, asking Buildbucket for the build status
[17:52:30.269] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:30.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:26:0) after 1m0s
[17:53:30.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:26:0)
[17:53:30.507] Timer tick, asking Buildbucket for the build status
[17:53:30.663] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:30.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:27:0) after 1m0s
[17:54:30.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:27:0)
[17:54:30.859] Timer tick, asking Buildbucket for the build status
[17:54:31.133] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:31.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:28:0) after 1m0s
[17:55:31.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:28:0)
[17:55:31.407] Timer tick, asking Buildbucket for the build status
[17:55:32.080] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:32.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:29:0) after 1m0s
[17:56:32.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:29:0)
[17:56:32.649] Timer tick, asking Buildbucket for the build status
[17:56:32.783] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:32.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:30:0) after 1m0s
[17:57:33.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:30:0)
[17:57:33.181] Timer tick, asking Buildbucket for the build status
[17:57:33.344] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:33.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:31:0) after 1m0s
[17:58:33.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:31:0)
[17:58:33.506] Timer tick, asking Buildbucket for the build status
[17:58:33.781] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:33.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:32:0) after 1m0s
[17:59:33.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:32:0)
[17:59:33.846] Timer tick, asking Buildbucket for the build status
[17:59:34.250] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:34.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:33:0) after 1m0s
[18:00:34.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:33:0)
[18:00:34.256] Timer tick, asking Buildbucket for the build status
[18:00:37.874] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:37.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:34:0) after 1m0s
[18:01:37.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:34:0)
[18:01:37.991] Timer tick, asking Buildbucket for the build status
[18:01:38.792] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:38.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:35:0) after 1m0s
[18:02:38.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:35:0)
[18:02:38.853] Timer tick, asking Buildbucket for the build status
[18:02:39.533] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:39.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:36:0) after 1m0s
[18:03:39.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:36:0)
[18:03:39.587] Timer tick, asking Buildbucket for the build status
[18:03:39.758] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:39.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:37:0) after 1m0s
[18:04:39.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:37:0)
[18:04:39.975] Timer tick, asking Buildbucket for the build status
[18:04:41.208] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:41.208] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:38:0) after 1m0s
[18:05:41.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:38:0)
[18:05:41.231] Timer tick, asking Buildbucket for the build status
[18:05:41.455] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:41.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:39:0) after 1m0s
[18:06:41.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:39:0)
[18:06:41.466] Timer tick, asking Buildbucket for the build status
[18:06:41.841] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:41.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:40:0) after 1m0s
[18:07:41.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:40:0)
[18:07:41.916] Timer tick, asking Buildbucket for the build status
[18:07:42.062] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:41:0) after 1m0s
[18:08:42.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:41:0)
[18:08:42.086] Timer tick, asking Buildbucket for the build status
[18:08:42.568] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:42.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:42:0) after 1m0s
[18:09:42.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:42:0)
[18:09:42.593] Timer tick, asking Buildbucket for the build status
[18:09:44.763] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:44.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:43:0) after 1m0s
[18:10:44.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:43:0)
[18:10:44.884] Timer tick, asking Buildbucket for the build status
[18:10:45.062] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:45.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:44:0) after 1m0s
[18:11:45.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:44:0)
[18:11:45.088] Timer tick, asking Buildbucket for the build status
[18:11:45.490] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:45.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:45:0) after 1m0s
[18:12:45.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:45:0)
[18:12:45.555] Timer tick, asking Buildbucket for the build status
[18:12:45.770] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:45.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:46:0) after 1m0s
[18:13:45.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:46:0)
[18:13:45.881] Timer tick, asking Buildbucket for the build status
[18:13:46.094] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:46.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:47:0) after 1m0s
[18:14:46.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:47:0)
[18:14:46.184] Timer tick, asking Buildbucket for the build status
[18:14:46.607] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:48:0) after 1m0s
[18:15:46.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:48:0)
[18:15:46.687] Timer tick, asking Buildbucket for the build status
[18:15:46.988] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:46.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:49:0) after 1m0s
[18:16:47.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:49:0)
[18:16:47.109] Timer tick, asking Buildbucket for the build status
[18:16:47.539] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:47.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:50:0) after 1m0s
[18:17:47.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:50:0)
[18:17:47.584] Timer tick, asking Buildbucket for the build status
[18:17:47.805] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:47.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:51:0) after 1m0s
[18:18:47.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:51:0)
[18:18:47.874] Timer tick, asking Buildbucket for the build status
[18:18:48.021] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:48.021] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:52:0) after 1m0s
[18:19:47.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:52:0)
[18:19:47.997] Timer tick, asking Buildbucket for the build status
[18:19:48.301] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:48.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:53:0) after 1m0s
[18:20:48.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:53:0)
[18:20:48.406] Timer tick, asking Buildbucket for the build status
[18:20:48.943] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:48.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:54:0) after 1m0s
[18:21:48.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:54:0)
[18:21:48.923] Timer tick, asking Buildbucket for the build status
[18:21:49.454] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:49.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:55:0) after 1m0s
[18:22:49.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:55:0)
[18:22:49.486] Timer tick, asking Buildbucket for the build status
[18:22:49.895] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:49.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:56:0) after 1m0s
[18:23:49.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:56:0)
[18:23:49.967] Timer tick, asking Buildbucket for the build status
[18:23:50.164] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:50.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:57:0) after 1m0s
[18:24:50.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:57:0)
[18:24:50.270] Timer tick, asking Buildbucket for the build status
[18:24:50.746] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:50.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:58:0) after 1m0s
[18:25:50.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:58:0)
[18:25:50.771] Timer tick, asking Buildbucket for the build status
[18:25:50.895] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:50.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:59:0) after 1m0s
[18:26:50.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:59:0)
[18:26:50.944] Timer tick, asking Buildbucket for the build status
[18:26:51.103] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:51.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:60:0) after 1m0s
[18:27:51.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:60:0)
[18:27:51.155] Timer tick, asking Buildbucket for the build status
[18:27:51.377] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:51.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:61:0) after 1m0s
[18:28:51.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:61:0)
[18:28:51.424] Timer tick, asking Buildbucket for the build status
[18:28:51.615] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:51.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:62:0) after 1m0s
[18:29:51.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:62:0)
[18:29:51.672] Timer tick, asking Buildbucket for the build status
[18:29:52.717] Build 8884110979765897936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:52.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9049541042580809232:63:0) after 1m0s
[18:30:00.947] Received PubSub notification, asking Buildbucket for the build status
[18:30:05.142] Build 8884110979765897936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:30:05.142] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585852199793878",
"created_by": "project:chromium",
"created_ts": "1585848556702588",
"id": "8884110979765897936",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dead5d3124b9a2236c5f2e72a732828e7b97ac67\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$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-central1-b-8-46-eg9s\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170542, \"got_angle_revision\": \"75a2ee30c3ec8bce68e0ac35c2c36ed78c75a7d3\", \"got_dawn_revision\": \"5b29904d76bc6543d2592568929bb548925982f7\", \"got_nacl_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"got_revision\": \"dead5d3124b9a2236c5f2e72a732828e7b97ac67\", \"got_revision_cp\": \"refs/heads/master@{#755884}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"0774188da4bb970b22e302aa24be64bf27f8ce05\", \"got_v8_revision\": \"1668abddd8147c49c8f2f90b78dc2701f3794a30\", \"got_v8_revision_cp\": \"refs/heads/8.3.110@{#1}\", \"got_webrtc_revision\": \"55c991cc81ae26e43e94f56c218f9254436aa269\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30983}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dead5d3124b9a2236c5f2e72a732828e7b97ac67\", \"swarm_hashes_refs/heads/master(at){#755884}_without_patch\": {\"accessibility_unittests\": \"5b92933efd09e75c63696c1f3ef7f9339310365e\", \"angle_unittests\": \"e5091b7483b46924757a9d4279319e9163ecbb8b\", \"app_shell_unittests\": \"acf5927e90b4b497bd8aa0dd068d514fd71c27d2\", \"aura_unittests\": \"e26e64f4b02f873c529bcd50dd47c5b9944076db\", \"base_unittests\": \"355238e38cf070408a0b64f15640c81bcc1fffc8\", \"base_util_unittests\": \"ecdb8b3dd652c2252771ba055985c2cf8bcf6bf2\", \"blink_common_unittests\": \"cd87b878b72a951b1a2ea7233b2b5a78c5b0ca60\", \"blink_fuzzer_unittests\": \"132ba02479e5f1da04743e07e3223c406b8db997\", \"blink_heap_unittests\": \"152a01a5a2bb09f1cf24e70cfad2a2c2cdbf2efd\", \"blink_platform_unittests\": \"2512531450ce1f89ad8fc30edfc4f578daa289a7\", \"blink_python_tests\": \"50bbc38b37ca388dc054621b8df11025fcd88730\", \"blink_unittests\": \"611e390e22f067bcb56199f4400d00c12536c8bb\", \"blink_web_tests\": \"e7ad12c07b05ec0f3ae3aa3247ac769f884c3114\", \"boringssl_crypto_tests\": \"aa943bea86647c9a44adfbc96ffd6feb53fe4e66\", \"boringssl_ssl_tests\": \"dee089891e79afb0202fa03410b22fd995344c31\", \"browser_tests\": \"6aad755cc331d6bd074701a83cd891bef07d5f7d\", \"cacheinvalidation_unittests\": \"d957fc44f8f9ab5c3facc62dff4f97acff066ab1\", \"capture_unittests\": \"82d798957e12e15478326798e346cc92419ebf78\", \"cast_unittests\": \"1e584cf1f3f44c5b2040b40df62262c51b7dccf3\", \"cc_unittests\": \"411cd1a969cf5e452cf8d5857650e583da1f34f7\", \"chrome_app_unittests\": \"178d54fd96219e4bb4807d912a9d6d0c0539b38b\", \"chromedriver_unittests\": \"51901780a0286df0aaed71642d4915da4dd5de3e\", \"components_browsertests\": \"19da4b6d50cf04e2cfa467fe9dd581bc2f7e1f0c\", \"components_unittests\": \"75fb9a3ce5199a62e62702a2fd9b7c2d2f49aedd\", \"compositor_unittests\": \"d96d2756566ee66e6ff98f8f13ca0797162e2e9a\", \"content_browsertests\": \"3f181b15c752318efc81bc0a0c73ef4f2040b97c\", \"content_shell_crash_test\": \"d12bbd6b47fd6ba1c039f1294d6a319b82a3f799\", \"content_unittests\": \"a40bada40ebb0c934b9713df65aa83bd3e24082c\", \"crashpad_tests\": \"d9fe05eac36e5e2f7a48ebcbf4e67b6bf6d23384\", \"cronet_tests\": \"cb0b0bbe2036d0d64c1f5f8349f726fb180f0f0f\", \"cronet_unittests\": \"3cf25324cb7b1ade2edbe5e5478279bd1707d9e3\", \"crypto_unittests\": \"4b418cdbdc0d29ac12d5400791ebcebbd45b2272\", \"dbus_unittests\": \"95d86851acd6922058e5e641878f7d84c2d8d6da\", \"device_unittests\": \"8ba8d2249b0f31b47bafab1137f0332646e2e298\", \"display_unittests\": \"d84ee8d8c604e39ce5f3f998fa4232bf85811b3b\", \"events_unittests\": \"29e8c13300d98ab3d489a1de234262981870c1c4\", \"extensions_browsertests\": \"d143005941cfbef90901b3c09ec67ec8fb21c307\", \"extensions_unittests\": \"e589c690ea71a019b19c00abc8d64fa0f2f928a0\", \"filesystem_service_unittests\": \"19f6b9a40748010066ae908e9ec8535ac620d9a4\", \"flatbuffers_unittests\": \"72ac4f314db80fab790f3135fe10fde26e7da08e\", \"gcm_unit_tests\": \"bcb7dece5b8b15dee64632aff81372fbefe524af\", \"gfx_unittests\": \"dcc93f4916302cdaefcfdbf9bf6263d1de45e7ff\", \"gin_unittests\": \"4bed7668f1afe2a5cd0f5842d20e66f34417402b\", \"google_apis_unittests\": \"2bc86d3ba537c1775f9ccd379e9b1498898b7470\", \"gpu_unittests\": \"a65e5ee495ffb9530f8b525442e6ebd98e0e1b79\", \"grit_python_unittests\": \"8c3aba08684a4851b7eb5e55316c1cdda2ef1701\", \"gwp_asan_unittests\": \"323edf37e7f0ce89cf516d5b64ba976a5482c189\", \"headless_browsertests\": \"cb49ded328febd34a96063b7715ad923fc3e4973\", \"headless_unittests\": \"2064422eceea7346525f82162edd3c92b118a8d2\", \"interactive_ui_tests\": \"1147808863187f9bbf582b1baf2ac6b27a92e7ed\", \"ipc_tests\": \"011d5f2a6473b4b7d74debbb53bf0bb85c431577\", \"jingle_unittests\": \"66e551346499461a51c798304075e62019afcb06\", \"latency_unittests\": \"c70f65c0d10ad4d5659a3bc2b02f7c80210a0469\", \"libjingle_xmpp_unittests\": \"d753fa66530859a24e7cbe87d2c7e8f562523a81\", \"media_blink_unittests\": \"8bdeb86ebe9cf55b9f9b662af142577e2cbb67b7\", \"media_unittests\": \"5ae9214336236c1a5258d7caaa9ce1f89277a212\", \"message_center_unittests\": \"8efcb57f9a005e991a01b2b680d96d3d3824d1ce\", \"metrics_python_tests\": \"9919849ac7baca48bd5fac89ce22af25672d7bb0\", \"midi_unittests\": \"2690191546a3135f424573d6854d7c9a3a2cbfd2\", \"mojo_core_unittests\": \"a2326162428d2e8332c5955e742f848ecb450b92\", \"mojo_unittests\": \"9e1efddf38874d6bec6bc33142c9c778986fe44e\", \"nacl_helper_nonsfi_unittests\": \"b9d3531e71d5cd74c0e771b7fb031df228437bd7\", \"nacl_loader_unittests\": \"874e640ef117cb2fbccd43a13cc48cc85e3bfe0c\", \"native_theme_unittests\": \"037c7c9457c25ec858f984a4e642d655dcde2ff3\", \"net_unittests\": \"3dd9647350069878a941d446cb9c5aed3f2d9245\", \"pdf_unittests\": \"3613ce7f0bec7393c79fdd084c26427842015f01\", \"perfetto_unittests\": \"d128e2cdf5a77fb2c37cf02ccac3ab973d36bde1\", \"ppapi_unittests\": \"60b27679754c93043b59939477aa81793ad6c28c\", \"printing_unittests\": \"aa46dbd46179c5b250b70586ec555b7c950d532c\", \"remoting_unittests\": \"708143bc2cd60fce377ffba84418685b81d7c379\", \"sandbox_linux_unittests\": \"c3d2ac7bc0f8d74cedf7e2b6ecb434fb949e43c3\", \"service_manager_unittests\": \"cd2fcc8c4fef0eaea2a4edc6e9aebe54b1ea7483\", \"services_unittests\": \"0d5edfa669198f4fbbe30a63aadc3d592c3ca55a\", \"shell_dialogs_unittests\": \"3a5e39d722e18867e82bf2284c2c5cca5620c121\", \"skia_unittests\": \"0a9181b908d92e00e309303dfd615b62a876716d\", \"snapshot_unittests\": \"8ed97180c774133f40c05448479739256df723ee\", \"sql_unittests\": \"015e7a7b8c4cb8878032d2be06665c2ded95214e\", \"storage_unittests\": \"f864eedaaacf4afdb7bc22105000f77862becc7b\", \"sync_integration_tests\": \"b6b471ca178c253182daa957d4dfc42036e83acd\", \"telemetry_gpu_unittests\": \"f052a8148f61536a8ee6b711066b6e9ce0571d6f\", \"telemetry_perf_unittests\": \"458186c1fe2e36070053f1d6f9eac31384e4d792\", \"telemetry_unittests\": \"8197248c4ccb646e045ff29cd7cf35e756e73528\", \"traffic_annotation_auditor_unittests\": \"ebdd94586621161c5dd688ee287cf549683f1ee3\", \"ui_base_unittests\": \"915f8950e8f055b62b3f68a1e54fa3708ef6ca64\", \"ui_touch_selection_unittests\": \"0bb9a9a5ba2520e9166e29dad972e4818fea56f6\", \"unit_tests\": \"aabe668545543180bee3896dad64d4b4b47a237a\", \"url_unittests\": \"bead2868878c8914e0eace075c5bde0cdf12db2b\", \"views_perftests\": \"900ae80c3b01d85ba4f3588724a2266e8a16a2a7\", \"views_unittests\": \"2ed763e0b6405150ea1f98e4a98b1b40f1361d07\", \"viz_unittests\": \"50aa4ede34dfe1a4eea985c351c4f3a28c8ef8d6\", \"vr_common_unittests\": \"98ac709d4d0b872e867ee26882c1b50c0d63ae77\", \"vr_pixeltests\": \"e23f4a40063a4f8ba572eef5c9eec695ef1e5536\", \"webdriver_wpt_tests\": \"764cd18527af5776ae6809ba430e5bc6f97111e8\", \"weblayer_browsertests\": \"5ef9bcb4101f1d4fb2cb4c7f353ed70cfa1e6cc5\", \"wm_unittests\": \"7fe8318eefc35af9325f269e4dc8bb4ee7c16edd\", \"wtf_unittests\": \"ebad859326cb4588c026f5ed455c8e61d2e31446\", \"xr_browser_tests\": \"4645b627a7e8b72c0fa11d8d96b40e0d5fc06f3f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_53a0dbabd2cebac1b8e64bcbfab336eb0021d1dc40b2d4ba5321eb0a070d508c_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_de45c485d8a81e16b8ff53eb76d94467eed5e11e118465311a13e72e2b141cb1_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-central1-b-8-46-eg9s\"], \"image\": [\"chrome-xenial-20032900-6802ab9ce34\"], \"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\": [\"4926-84b94b1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585848843116988",
"status": "COMPLETED",
"status_changed_ts": "1585852200432381",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170542",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dead5d3124b9a2236c5f2e72a732828e7b97ac67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049541042580809232",
"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/8884110979765897936/+/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:4b54c22a418db910",
"user_agent:luci-scheduler"
],
"updated_ts": "1585852200436889",
"url": "https://ci.chromium.org/b/8884110979765897936",
"utcnow_ts": "1585852205110639"
}
}
[18:30:05.142] Invocation finished in 1h0m50.507366935s with status SUCCEEDED