[22:31:52.182] New invocation is queued and will start shortly
[22:31:54.208] Starting the invocation (attempt 1)
[22:31:54.265] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:31:54.265] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:31:54.265] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9060212445075452688",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dcc58ae0aa332072c165512113a07c4e9bf01938\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9060212445075452688",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/dcc58ae0aa332072c165512113a07c4e9bf01938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcc58ae0aa332072c165512113a07c4e9bf01938",
"gitiles_ref:refs/heads/master"
]
}
[22:31:55.839] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1575671514433994",
"id": "8894782382039970304",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcc58ae0aa332072c165512113a07c4e9bf01938\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575671515144069",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165753",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dcc58ae0aa332072c165512113a07c4e9bf01938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcc58ae0aa332072c165512113a07c4e9bf01938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060212445075452688",
"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/8894782382039970304/+/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": "1575671515144190",
"url": "https://ci.chromium.org/b/8894782382039970304",
"utcnow_ts": "1575671515818295"
}
}
[22:31:55.839] Task URL: https://ci.chromium.org/b/8894782382039970304
[22:31:55.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:2:0) after 1m0s
[22:32:55.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:2:0)
[22:32:55.907] Timer tick, asking Buildbucket for the build status
[22:32:56.455] Build 8894782382039970304: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:56.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:3:0) after 1m0s
[22:33:56.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:3:0)
[22:33:56.531] Timer tick, asking Buildbucket for the build status
[22:33:56.820] Build 8894782382039970304: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:4:0) after 1m0s
[22:34:56.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:4:0)
[22:34:56.903] Timer tick, asking Buildbucket for the build status
[22:34:57.196] Build 8894782382039970304: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:57.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:5:0) after 1m0s
[22:35:57.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:5:0)
[22:35:57.270] Timer tick, asking Buildbucket for the build status
[22:35:57.516] Build 8894782382039970304: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:6:0) after 1m0s
[22:36:57.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:6:0)
[22:36:57.634] Timer tick, asking Buildbucket for the build status
[22:36:57.836] Build 8894782382039970304: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:57.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:7:0) after 1m0s
[22:37:06.531] Received PubSub notification, asking Buildbucket for the build status
[22:37:06.821] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:57.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:7:0)
[22:37:57.955] Timer tick, asking Buildbucket for the build status
[22:37:59.677] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:59.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:9:0) after 1m0s
[22:38:59.803] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:9:0)
[22:38:59.803] Timer tick, asking Buildbucket for the build status
[22:39:00.098] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:00.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:10:0) after 1m0s
[22:40:00.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:10:0)
[22:40:00.397] Timer tick, asking Buildbucket for the build status
[22:40:00.593] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:00.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:11:0) after 1m0s
[22:41:00.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:11:0)
[22:41:00.719] Timer tick, asking Buildbucket for the build status
[22:41:00.939] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:00.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:12:0) after 1m0s
[22:42:01.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:12:0)
[22:42:01.021] Timer tick, asking Buildbucket for the build status
[22:42:01.879] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:01.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:13:0) after 1m0s
[22:43:01.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:13:0)
[22:43:01.901] Timer tick, asking Buildbucket for the build status
[22:43:02.270] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:02.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:14:0) after 1m0s
[22:44:02.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:14:0)
[22:44:02.305] Timer tick, asking Buildbucket for the build status
[22:44:02.945] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:02.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:15:0) after 1m0s
[22:45:03.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:15:0)
[22:45:03.086] Timer tick, asking Buildbucket for the build status
[22:45:03.271] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:03.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:16:0) after 1m0s
[22:46:03.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:16:0)
[22:46:03.403] Timer tick, asking Buildbucket for the build status
[22:46:03.722] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:03.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:17:0) after 1m0s
[22:47:03.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:17:0)
[22:47:03.770] Timer tick, asking Buildbucket for the build status
[22:47:04.095] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:04.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:18:0) after 1m0s
[22:48:04.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:18:0)
[22:48:04.221] Timer tick, asking Buildbucket for the build status
[22:48:04.423] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:04.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:19:0) after 1m0s
[22:49:04.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:19:0)
[22:49:04.596] Timer tick, asking Buildbucket for the build status
[22:49:05.676] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:05.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:20:0) after 1m0s
[22:50:05.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:20:0)
[22:50:05.771] Timer tick, asking Buildbucket for the build status
[22:50:06.666] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:06.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:21:0) after 1m0s
[22:51:06.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:21:0)
[22:51:06.788] Timer tick, asking Buildbucket for the build status
[22:51:06.947] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:06.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:22:0) after 1m0s
[22:52:06.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:22:0)
[22:52:06.996] Timer tick, asking Buildbucket for the build status
[22:52:07.148] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:07.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:23:0) after 1m0s
[22:53:07.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:23:0)
[22:53:07.306] Timer tick, asking Buildbucket for the build status
[22:53:07.461] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:07.461] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:24:0) after 1m0s
[22:54:07.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:24:0)
[22:54:07.814] Timer tick, asking Buildbucket for the build status
[22:54:08.128] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:08.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:25:0) after 1m0s
[22:55:08.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:25:0)
[22:55:08.304] Timer tick, asking Buildbucket for the build status
[22:55:08.466] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:26:0) after 1m0s
[22:56:08.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:26:0)
[22:56:08.489] Timer tick, asking Buildbucket for the build status
[22:56:08.946] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:08.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:27:0) after 1m0s
[22:57:09.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:27:0)
[22:57:09.087] Timer tick, asking Buildbucket for the build status
[22:57:09.900] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:09.900] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:28:0) after 1m0s
[22:58:09.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:28:0)
[22:58:09.999] Timer tick, asking Buildbucket for the build status
[22:58:11.078] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:11.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:29:0) after 1m0s
[22:59:11.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:29:0)
[22:59:11.312] Timer tick, asking Buildbucket for the build status
[22:59:11.519] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:11.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:30:0) after 1m0s
[23:00:11.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:30:0)
[23:00:11.645] Timer tick, asking Buildbucket for the build status
[23:00:12.042] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:12.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:31:0) after 1m0s
[23:01:12.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:31:0)
[23:01:12.245] Timer tick, asking Buildbucket for the build status
[23:01:12.842] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:12.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:32:0) after 1m0s
[23:02:12.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:32:0)
[23:02:12.892] Timer tick, asking Buildbucket for the build status
[23:02:13.046] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:13.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:33:0) after 1m0s
[23:03:13.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:33:0)
[23:03:13.183] Timer tick, asking Buildbucket for the build status
[23:03:13.835] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:13.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:34:0) after 1m0s
[23:04:13.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:34:0)
[23:04:13.859] Timer tick, asking Buildbucket for the build status
[23:04:14.080] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:14.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:35:0) after 1m0s
[23:05:14.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:35:0)
[23:05:14.219] Timer tick, asking Buildbucket for the build status
[23:05:14.422] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:14.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:36:0) after 1m0s
[23:06:14.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:36:0)
[23:06:14.581] Timer tick, asking Buildbucket for the build status
[23:06:15.565] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:15.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:37:0) after 1m0s
[23:07:15.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:37:0)
[23:07:15.669] Timer tick, asking Buildbucket for the build status
[23:07:15.899] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:15.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:38:0) after 1m0s
[23:08:15.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:38:0)
[23:08:15.948] Timer tick, asking Buildbucket for the build status
[23:08:16.164] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:16.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:39:0) after 1m0s
[23:09:16.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:39:0)
[23:09:16.256] Timer tick, asking Buildbucket for the build status
[23:09:16.567] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:16.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:40:0) after 1m0s
[23:10:16.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:40:0)
[23:10:16.758] Timer tick, asking Buildbucket for the build status
[23:10:16.966] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:41:0) after 1m0s
[23:11:16.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:41:0)
[23:11:16.997] Timer tick, asking Buildbucket for the build status
[23:11:18.300] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:42:0) after 1m0s
[23:12:18.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:42:0)
[23:12:18.339] Timer tick, asking Buildbucket for the build status
[23:12:18.692] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:18.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:43:0) after 1m0s
[23:13:18.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:43:0)
[23:13:18.766] Timer tick, asking Buildbucket for the build status
[23:13:18.906] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:18.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:44:0) after 1m0s
[23:14:19.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:44:0)
[23:14:19.006] Timer tick, asking Buildbucket for the build status
[23:14:19.165] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:19.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:45:0) after 1m0s
[23:15:19.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:45:0)
[23:15:19.189] Timer tick, asking Buildbucket for the build status
[23:15:19.369] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:19.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:46:0) after 1m0s
[23:16:19.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:46:0)
[23:16:19.485] Timer tick, asking Buildbucket for the build status
[23:16:19.987] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:19.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:47:0) after 1m0s
[23:17:20.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:47:0)
[23:17:20.087] Timer tick, asking Buildbucket for the build status
[23:17:20.273] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:20.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:48:0) after 1m0s
[23:18:20.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:48:0)
[23:18:20.299] Timer tick, asking Buildbucket for the build status
[23:18:20.423] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:20.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:49:0) after 1m0s
[23:19:20.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:49:0)
[23:19:20.468] Timer tick, asking Buildbucket for the build status
[23:19:20.624] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:20.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:50:0) after 1m0s
[23:20:20.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:50:0)
[23:20:20.772] Timer tick, asking Buildbucket for the build status
[23:20:20.914] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:20.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:51:0) after 1m0s
[23:21:20.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:51:0)
[23:21:20.977] Timer tick, asking Buildbucket for the build status
[23:21:21.577] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:52:0) after 1m0s
[23:22:21.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:52:0)
[23:22:21.635] Timer tick, asking Buildbucket for the build status
[23:22:22.888] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:22.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:53:0) after 1m0s
[23:23:22.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:53:0)
[23:23:22.973] Timer tick, asking Buildbucket for the build status
[23:23:23.254] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:23.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:54:0) after 1m0s
[23:24:23.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:54:0)
[23:24:23.310] Timer tick, asking Buildbucket for the build status
[23:24:23.678] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:23.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:55:0) after 1m0s
[23:25:23.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:55:0)
[23:25:23.744] Timer tick, asking Buildbucket for the build status
[23:25:23.951] Build 8894782382039970304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:23.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9060212445075452688:56:0) after 1m0s
[23:26:16.451] Received PubSub notification, asking Buildbucket for the build status
[23:26:17.302] Build 8894782382039970304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:26:17.302] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1575674774370056",
"created_by": "project:chromium",
"created_ts": "1575671514433994",
"id": "8894782382039970304",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcc58ae0aa332072c165512113a07c4e9bf01938\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-30-jymp\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165753, \"got_angle_revision\": \"92183a268319bc625743e375b76fe5c7c31d27c7\", \"got_dawn_revision\": \"dc3317da6cd5242e525d3230516cfd2bbff25fe9\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"dcc58ae0aa332072c165512113a07c4e9bf01938\", \"got_revision_cp\": \"refs/heads/master@{#722647}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"629b743dd803aae2c018eb9d6044f5bc6079f0ba\", \"got_v8_revision\": \"69827db645fcece065bf16a795a4ec8d3a51057f\", \"got_v8_revision_cp\": \"refs/heads/8.0.426@{#2}\", \"got_webrtc_revision\": \"80bc1acb9c2b9d8096f356c748ec9b57e02759c9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30031}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcc58ae0aa332072c165512113a07c4e9bf01938\", \"swarm_hashes_refs/heads/master(at){#722647}_without_patch\": {\"accessibility_unittests\": \"9ae5ca2caac8b481325ae3179fd7d3f8a20e48a6\", \"angle_unittests\": \"eb4215a596df99e2a5dc9a6593d1a13b48213f2b\", \"app_shell_unittests\": \"553d3d0cc1972d5386bb0a221335eeefa84bbed2\", \"aura_unittests\": \"f95303c3c399407981ca1c979265ace614cb6c96\", \"base_unittests\": \"7353006f465ab6d5e845a2945332c03e9e00c953\", \"base_util_unittests\": \"d7a126045d3d0cfb52a5d9d6c5832ae4a155ac4c\", \"blink_common_unittests\": \"dee9b3efc81ec67b97f00573add8fed20013ae81\", \"blink_fuzzer_unittests\": \"56dadad27fa351e7a66d523a8145e1713fdc9040\", \"blink_heap_unittests\": \"6b509b35ae0def3dc8024d927586ad4527f3d27a\", \"blink_platform_unittests\": \"8ead7d42e013bc4e7de1bc1da98aefc8302e38b3\", \"blink_python_tests\": \"53278d693ea7d210e45deb982c4fb34b0e3d323f\", \"blink_unittests\": \"c70f86ef1dcb156d8e9f8a46aa8d50ea7eb42266\", \"blink_web_tests\": \"1d3961e3fd46045dfb34b94057d37d94c42b3221\", \"boringssl_crypto_tests\": \"f3ff9cb349f0464c3547b11599a8cbc2a9c06cd6\", \"boringssl_ssl_tests\": \"13f8b1e7781dc2d021deed4e9b84a1ebff7217aa\", \"browser_tests\": \"b0a4097ea6a1a2c3033f3c132ad89843128f1c01\", \"cacheinvalidation_unittests\": \"3dd12f4736547dc0c9ada209f6a1d50d76caf179\", \"capture_unittests\": \"8a3b22b295c0f5c849073628a04979afd034669d\", \"cast_unittests\": \"2aefeb3d359a67684ec86bce0cd6e716fded6c03\", \"cc_unittests\": \"238955b00497d33b90ce080eb4dff11d1b8c0f3a\", \"chrome_app_unittests\": \"7f4f17e14a198267d7fc4df0370bc2f61dc79cdf\", \"chromedriver_unittests\": \"f3dfbd92daf80167124e933b18b695be6dcafa23\", \"components_browsertests\": \"7e55efd0b5194b86427281401c8f0bb591ca9489\", \"components_unittests\": \"bc836a68e0d772abd3bd6a2cbd466631d49f2f01\", \"compositor_unittests\": \"6c866475fe58174eae06856f67f4d2c64d5392ec\", \"content_browsertests\": \"b55a9df73e0229782ce363d2cda6f4e5223bbe42\", \"content_shell_crash_test\": \"ee3acb0b30d459a89fc7dc2dc4f02cd0e8cc1928\", \"content_unittests\": \"e442d48a2d2fa7766e06dbc1b5906bca8e1a639c\", \"crashpad_tests\": \"2ec99c6a864a9fa4eed2235210f6baeabf24e262\", \"cronet_tests\": \"eb9d9e1f26b292f14a64d921eaba363ef078179d\", \"cronet_unittests\": \"cca4afb4a617a67f25e7dec416941f233828e8b4\", \"crypto_unittests\": \"0db077a0641f889c5ea670f0cd8e0d41f25dc5c7\", \"dbus_unittests\": \"7afbfc6d95df02c863397eccad44c4630b8f4d2d\", \"device_unittests\": \"a05d05421770fc7f399c3dae0a01647832520b39\", \"devtools_lint_check\": \"cee3b6317c5e5a95f43a55c2d9ead018cb714a77\", \"devtools_type_check\": \"60cc08099beebd9919b0c159da5dad3b74dcc4b9\", \"display_unittests\": \"5267224b7e22f1476ff5d2a5442b4fa0c7db50d1\", \"events_unittests\": \"3a71711e1ec984661042107d5d1b8312d7dbb786\", \"extensions_browsertests\": \"2a6577fb0b47978cf8ad40311b5a4b398ec81f67\", \"extensions_unittests\": \"c252d58c30613b607325f29ed014c7bd3c21d558\", \"filesystem_service_unittests\": \"97f968c9d996ae1701617b2920d89bd87af55ca6\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"f25ba759fcbc87d0fd87e0cbb2952b521151b231\", \"gfx_unittests\": \"e58e45846d4fe79568cd181bab2503d8a46e3516\", \"gin_unittests\": \"39400518c895e276a8459b90512e34e7e68f6ceb\", \"google_apis_unittests\": \"cb43bb045f0d6fe9ee7b51e68dd12008060aa64b\", \"gpu_unittests\": \"f56b6ea424f9dfa27a4d03a5fe4b34baf5d6bea7\", \"grit_python_unittests\": \"39ccc3e125dd92f815220570a45fb6e712a6dfb4\", \"gwp_asan_unittests\": \"77da4ef856b75ac5867c278206b1bc477dbb0853\", \"headless_browsertests\": \"4f78240e9c94d1905b0f3be75c6734eeedd35f9a\", \"headless_unittests\": \"37daea75358f1e3f5db7f0f26b7f10d0d7eb717e\", \"interactive_ui_tests\": \"6f672c3fc77a50ee8d102d60c4bb8d242b34e277\", \"ipc_tests\": \"5e90f9758ab6362151c7c2f8a576bf2485a929fc\", \"jingle_unittests\": \"603d237eb77e1bfe21c7c5029e1431517dbf642a\", \"latency_unittests\": \"35de4068b2ccd081b40b18717e66cbe6cb2ef239\", \"libjingle_xmpp_unittests\": \"952a283154cb6c3234f21ffe8b2f0ef07a62c723\", \"media_blink_unittests\": \"6fb0b97fcdb632cbf1b13ddcba55edca332d576b\", \"media_service_unittests\": \"74b0167ed352fdb3acc6f55c192df7b6b54de63b\", \"media_unittests\": \"30f29d7d2124ab1cecc3938567c9b8409de46bb8\", \"message_center_unittests\": \"9fc9cbcea05b64425a960159c8629e004aacc14f\", \"metrics_python_tests\": \"c668c32fbb51b3b68187fabc52b3ba41891bb502\", \"midi_unittests\": \"2936910366a5822b4affc979385bbcc8b6883068\", \"mojo_core_unittests\": \"231c70d37e613cc850b8fa290eb04bf8a154025b\", \"mojo_unittests\": \"29e0f171e3813e95dd4a9469109d46ca99154b6e\", \"nacl_helper_nonsfi_unittests\": \"01cafa1d395d2dbbfc27633e185cfc7d49c63da2\", \"nacl_loader_unittests\": \"07f537f442e04b0c2e76311d7bdedc658ba15203\", \"native_theme_unittests\": \"243455743950a5f4af0d7536f79b57e4b05ffe6c\", \"net_unittests\": \"58767d2cb96f236a6c9b0e76446687cfe772b0f7\", \"pdf_unittests\": \"41b4144976a4c497202463d377f009e114a7706b\", \"perfetto_unittests\": \"57d09bfb093aa0884969dc956bcdc3f15ecfe038\", \"ppapi_unittests\": \"39aff3fbeab278c6a3a3bd1d2ba44f0543cc88fc\", \"printing_unittests\": \"3c1a00558b22d131d76640b7b0c0aea1ae5c57ba\", \"remoting_unittests\": \"797f2d145375fde8af27b6d518665294ac0d958f\", \"sandbox_linux_unittests\": \"e818516b4853c67501ca5ec9b8c7075170d14634\", \"service_manager_unittests\": \"2e4f3559a8d8859a78190278cb841976aa95b9ff\", \"services_unittests\": \"03c776d6a27a0d6ed497ed0ad2067da254c97ec2\", \"shell_dialogs_unittests\": \"cfcbb334b62b3ba3fbd6ba039e1dc40d38062577\", \"skia_unittests\": \"f9940eab9405858d0b5a43939e932eb1e98b647a\", \"snapshot_unittests\": \"c8f368652ed8ff2b2be8879e7754ee9543852e6d\", \"sql_unittests\": \"1b4c39dcc91dd3eac5a42b7209e02d59c9ff79ff\", \"storage_unittests\": \"b02def5182dbf145fc0a191c67658b0de2d80648\", \"sync_integration_tests\": \"5e67693b9cef1abd0c35194b7cd7461e55a39a98\", \"telemetry_gpu_unittests\": \"657fa25b175a640c99edae41ac2d8b7a47c7ba1e\", \"telemetry_perf_unittests\": \"25c7b4711299260555ce716b7bb8ecc61bb6070f\", \"telemetry_unittests\": \"e2aaf7391d34c4a406899cf8faa7b6d8b42e3ee1\", \"traffic_annotation_auditor_unittests\": \"1eb3e32cd583e2e98c943ee48540c6ff04203acc\", \"ui_base_unittests\": \"bd394ea1bab40bcf2854c050bd0a493c744dadf2\", \"ui_touch_selection_unittests\": \"6d07cd041b4635b1fe456a1879bf411957d953aa\", \"unit_tests\": \"ffdfcb2ab03c98cbc50e588ff02dba586fb224a0\", \"url_unittests\": \"722e08ef1dd0c14dc1d3affb56a9b4cd73af0736\", \"views_perftests\": \"4d6cd276ff73e71ffdc3332b8fec5c50b030c19d\", \"views_unittests\": \"38747f17a586fbbf281ebaa7d529cbd91d806fe9\", \"viz_unittests\": \"ac40ee92b2b1f78c801d354a7332240fada44ddd\", \"vr_common_unittests\": \"2e1336004663fd9f371a5331a1b0992a988ee357\", \"vr_pixeltests\": \"201ff1d6d97fc74af645f2e245f46030a1b5da9a\", \"webdriver_wpt_tests\": \"9664a8c4f7646b03312e860a99e3f6c81c72fb06\", \"weblayer_browsertests\": \"27ade8bcba636921e610863282fe342263f541b6\", \"wm_unittests\": \"3f9c7c686ebdecdfc1e758d3b1fcdb97d0bce673\", \"wtf_unittests\": \"5f24f4292c0422b66bc1319028271244c94e7247\", \"xr_browser_tests\": \"966d8f9c3036d851fa356fbeff10dc7bc4360690\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-30-jymp\"], \"image\": [\"chrome-xenial-19112404-1fe53dcaaf2\"], \"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\": [\"4702-f6d98f9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1575671825004327",
"status": "COMPLETED",
"status_changed_ts": "1575674775729595",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165753",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dcc58ae0aa332072c165512113a07c4e9bf01938",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcc58ae0aa332072c165512113a07c4e9bf01938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060212445075452688",
"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/8894782382039970304/+/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:48f628e75d92b910",
"user_agent:luci-scheduler"
],
"updated_ts": "1575674775798066",
"url": "https://ci.chromium.org/b/8894782382039970304",
"utcnow_ts": "1575674777270589"
}
}
[23:26:17.302] Invocation finished in 54m25.147625832s with status SUCCEEDED