[02:03:20.677] New invocation is queued and will start shortly
[02:03:21.921] Starting the invocation (attempt 1)
[02:03:21.983] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:03:21.983] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:03:21.983] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056031679763502432",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056031679763502432",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"gitiles_ref:refs/heads/master"
]
}
[02:03:23.309] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579658602796073",
"id": "8890601616872855600",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579658603156059",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167666",
"builder:Linux Builder (dbg)",
"buildset:commit/git/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056031679763502432",
"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/8890601616872855600/+/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": "1579658603156201",
"url": "https://ci.chromium.org/b/8890601616872855600",
"utcnow_ts": "1579658603297936"
}
}
[02:03:23.309] Task URL: https://ci.chromium.org/b/8890601616872855600
[02:03:23.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:2:0) after 1m0s
[02:04:23.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:2:0)
[02:04:23.337] Timer tick, asking Buildbucket for the build status
[02:04:23.604] Build 8890601616872855600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:23.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:3:0) after 1m0s
[02:05:23.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:3:0)
[02:05:23.801] Timer tick, asking Buildbucket for the build status
[02:05:24.119] Build 8890601616872855600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:4:0) after 1m0s
[02:06:24.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:4:0)
[02:06:24.268] Timer tick, asking Buildbucket for the build status
[02:06:24.468] Build 8890601616872855600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:24.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:5:0) after 1m0s
[02:07:24.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:5:0)
[02:07:24.533] Timer tick, asking Buildbucket for the build status
[02:07:24.731] Build 8890601616872855600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:24.731] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:6:0) after 1m0s
[02:08:08.957] Received PubSub notification, asking Buildbucket for the build status
[02:08:09.106] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:24.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:6:0)
[02:08:24.752] Timer tick, asking Buildbucket for the build status
[02:08:24.899] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:24.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:8:0) after 1m0s
[02:09:25.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:8:0)
[02:09:25.013] Timer tick, asking Buildbucket for the build status
[02:09:25.153] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:25.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:9:0) after 1m0s
[02:10:25.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:9:0)
[02:10:25.244] Timer tick, asking Buildbucket for the build status
[02:10:25.460] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:25.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:10:0) after 1m0s
[02:11:25.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:10:0)
[02:11:25.481] Timer tick, asking Buildbucket for the build status
[02:11:25.684] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:25.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:11:0) after 1m0s
[02:12:25.712] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:11:0)
[02:12:25.712] Timer tick, asking Buildbucket for the build status
[02:12:26.075] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:26.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:12:0) after 1m0s
[02:13:26.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:12:0)
[02:13:26.093] Timer tick, asking Buildbucket for the build status
[02:13:26.496] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:26.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:13:0) after 1m0s
[02:14:26.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:13:0)
[02:14:26.521] Timer tick, asking Buildbucket for the build status
[02:14:26.702] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:14:0) after 1m0s
[02:15:26.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:14:0)
[02:15:26.677] Timer tick, asking Buildbucket for the build status
[02:15:27.355] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:27.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:15:0) after 1m0s
[02:16:27.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:15:0)
[02:16:27.429] Timer tick, asking Buildbucket for the build status
[02:16:27.587] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:27.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:16:0) after 1m0s
[02:17:27.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:16:0)
[02:17:27.742] Timer tick, asking Buildbucket for the build status
[02:17:27.917] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:27.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:17:0) after 1m0s
[02:18:28.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:17:0)
[02:18:28.242] Timer tick, asking Buildbucket for the build status
[02:18:29.173] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:29.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:18:0) after 1m0s
[02:19:29.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:18:0)
[02:19:29.286] Timer tick, asking Buildbucket for the build status
[02:19:29.423] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:29.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:19:0) after 1m0s
[02:20:29.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:19:0)
[02:20:29.703] Timer tick, asking Buildbucket for the build status
[02:20:30.453] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:30.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:20:0) after 1m0s
[02:21:30.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:20:0)
[02:21:30.480] Timer tick, asking Buildbucket for the build status
[02:21:31.301] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:31.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:21:0) after 1m0s
[02:22:31.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:21:0)
[02:22:31.341] Timer tick, asking Buildbucket for the build status
[02:22:32.884] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:32.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:22:0) after 1m0s
[02:23:33.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:22:0)
[02:23:33.056] Timer tick, asking Buildbucket for the build status
[02:23:33.231] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:33.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:23:0) after 1m0s
[02:24:33.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:23:0)
[02:24:33.275] Timer tick, asking Buildbucket for the build status
[02:24:33.712] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:33.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:24:0) after 1m0s
[02:25:33.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:24:0)
[02:25:33.694] Timer tick, asking Buildbucket for the build status
[02:25:34.267] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:34.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:25:0) after 1m0s
[02:26:34.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:25:0)
[02:26:34.463] Timer tick, asking Buildbucket for the build status
[02:26:34.599] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:34.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:26:0) after 1m0s
[02:27:34.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:26:0)
[02:27:34.759] Timer tick, asking Buildbucket for the build status
[02:27:34.935] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:34.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:27:0) after 1m0s
[02:28:34.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:27:0)
[02:28:34.949] Timer tick, asking Buildbucket for the build status
[02:28:35.198] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:35.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:28:0) after 1m0s
[02:29:35.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:28:0)
[02:29:35.197] Timer tick, asking Buildbucket for the build status
[02:29:35.691] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:35.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:29:0) after 1m0s
[02:30:35.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:29:0)
[02:30:35.742] Timer tick, asking Buildbucket for the build status
[02:30:37.382] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:37.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:30:0) after 1m0s
[02:31:37.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:30:0)
[02:31:37.433] Timer tick, asking Buildbucket for the build status
[02:31:37.570] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:37.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:31:0) after 1m0s
[02:32:37.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:31:0)
[02:32:37.595] Timer tick, asking Buildbucket for the build status
[02:32:37.818] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:37.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:32:0) after 1m0s
[02:33:37.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:32:0)
[02:33:37.850] Timer tick, asking Buildbucket for the build status
[02:33:38.377] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:38.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:33:0) after 1m0s
[02:34:38.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:33:0)
[02:34:38.499] Timer tick, asking Buildbucket for the build status
[02:34:39.177] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:39.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:34:0) after 1m0s
[02:35:39.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:34:0)
[02:35:39.199] Timer tick, asking Buildbucket for the build status
[02:35:39.451] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:39.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:35:0) after 1m0s
[02:36:39.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:35:0)
[02:36:39.538] Timer tick, asking Buildbucket for the build status
[02:36:39.681] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:39.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:36:0) after 1m0s
[02:37:39.706] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:36:0)
[02:37:39.706] Timer tick, asking Buildbucket for the build status
[02:37:39.833] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:39.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:37:0) after 1m0s
[02:38:39.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:37:0)
[02:38:39.862] Timer tick, asking Buildbucket for the build status
[02:38:40.095] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:40.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:38:0) after 1m0s
[02:39:40.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:38:0)
[02:39:40.156] Timer tick, asking Buildbucket for the build status
[02:39:40.692] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:40.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:39:0) after 1m0s
[02:40:40.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:39:0)
[02:40:40.783] Timer tick, asking Buildbucket for the build status
[02:40:41.034] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:41.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:40:0) after 1m0s
[02:41:41.129] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:40:0)
[02:41:41.129] Timer tick, asking Buildbucket for the build status
[02:41:41.638] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:41.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:41:0) after 1m0s
[02:42:41.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:41:0)
[02:42:41.738] Timer tick, asking Buildbucket for the build status
[02:42:41.953] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:41.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:42:0) after 1m0s
[02:43:42.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:42:0)
[02:43:42.097] Timer tick, asking Buildbucket for the build status
[02:43:42.871] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:42.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:43:0) after 1m0s
[02:44:42.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:43:0)
[02:44:42.937] Timer tick, asking Buildbucket for the build status
[02:44:43.202] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:43.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:44:0) after 1m0s
[02:45:43.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:44:0)
[02:45:43.304] Timer tick, asking Buildbucket for the build status
[02:45:43.587] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:43.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:45:0) after 1m0s
[02:46:43.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:45:0)
[02:46:43.759] Timer tick, asking Buildbucket for the build status
[02:46:44.807] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:44.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:46:0) after 1m0s
[02:47:44.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:46:0)
[02:47:44.860] Timer tick, asking Buildbucket for the build status
[02:47:45.355] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:45.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:47:0) after 1m0s
[02:48:45.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:47:0)
[02:48:45.464] Timer tick, asking Buildbucket for the build status
[02:48:45.874] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:45.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:48:0) after 1m0s
[02:49:45.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:48:0)
[02:49:45.957] Timer tick, asking Buildbucket for the build status
[02:49:47.982] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:47.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:49:0) after 1m0s
[02:50:48.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:49:0)
[02:50:48.011] Timer tick, asking Buildbucket for the build status
[02:50:48.463] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:48.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:50:0) after 1m0s
[02:51:48.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:50:0)
[02:51:48.518] Timer tick, asking Buildbucket for the build status
[02:51:48.751] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:48.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:51:0) after 1m0s
[02:52:48.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:51:0)
[02:52:48.845] Timer tick, asking Buildbucket for the build status
[02:52:49.052] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:49.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:52:0) after 1m0s
[02:53:49.029] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:52:0)
[02:53:49.029] Timer tick, asking Buildbucket for the build status
[02:53:50.363] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:50.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:53:0) after 1m0s
[02:54:50.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:53:0)
[02:54:50.497] Timer tick, asking Buildbucket for the build status
[02:54:50.668] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:50.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:54:0) after 1m0s
[02:55:50.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:54:0)
[02:55:50.747] Timer tick, asking Buildbucket for the build status
[02:55:50.932] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:50.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:55:0) after 1m0s
[02:56:51.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:55:0)
[02:56:51.119] Timer tick, asking Buildbucket for the build status
[02:56:51.520] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:51.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:56:0) after 1m0s
[02:57:51.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:56:0)
[02:57:51.556] Timer tick, asking Buildbucket for the build status
[02:57:51.768] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:51.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:57:0) after 1m0s
[02:58:51.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:57:0)
[02:58:51.898] Timer tick, asking Buildbucket for the build status
[02:58:53.697] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:58:0) after 1m0s
[02:59:53.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:58:0)
[02:59:53.720] Timer tick, asking Buildbucket for the build status
[02:59:54.041] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:54.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:59:0) after 1m0s
[03:00:54.203] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:59:0)
[03:00:54.203] Timer tick, asking Buildbucket for the build status
[03:00:54.776] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:60:0) after 1m0s
[03:01:54.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:60:0)
[03:01:54.815] Timer tick, asking Buildbucket for the build status
[03:01:55.033] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:55.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:61:0) after 1m0s
[03:02:55.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:61:0)
[03:02:55.094] Timer tick, asking Buildbucket for the build status
[03:02:56.180] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:56.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:62:0) after 1m0s
[03:03:56.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:62:0)
[03:03:56.265] Timer tick, asking Buildbucket for the build status
[03:03:56.443] Build 8890601616872855600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:56.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056031679763502432:63:0) after 1m0s
[03:04:28.197] Received PubSub notification, asking Buildbucket for the build status
[03:04:28.632] Build 8890601616872855600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:04:28.632] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579662267046968",
"created_by": "project:chromium",
"created_ts": "1579658602796073",
"id": "8890601616872855600",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3\"}}",
"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-47-hzvq\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167666, \"got_angle_revision\": \"7f8b6e3fabbd579065944112095ffb69a869316b\", \"got_dawn_revision\": \"80880ee9985484d39355d2827ce5cb9343a07e91\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3\", \"got_revision_cp\": \"refs/heads/master@{#733841}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"b766e5e7fbf42e48be36afc80e2c448e339c599e\", \"got_v8_revision\": \"4cde67a85fd4c29f78551e5aebca1fc8912876e8\", \"got_v8_revision_cp\": \"refs/heads/8.1.244@{#1}\", \"got_webrtc_revision\": \"06df1e1c4655a1048bfeb30e090ab52005f1e841\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30339}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3\", \"swarm_hashes_refs/heads/master(at){#733841}_without_patch\": {\"accessibility_unittests\": \"383f4cd8c4064de3b637a55265083de46d34887b\", \"angle_unittests\": \"1155063806e9f2363812e3285cc33005552e5bab\", \"app_shell_unittests\": \"76a06ae1f6ed8f4311187c261267c92f44cc51d8\", \"aura_unittests\": \"e03a914152aa570e262dac48b197dadea51e9619\", \"base_unittests\": \"88008a631ed3aa35914e18e9af3d82e0758ab7d5\", \"base_util_unittests\": \"fc34962902235ac18ca84ca65a13ccd082ddbfa0\", \"blink_common_unittests\": \"84de5718dd2dd9df7030432a3e7353c88be88ef7\", \"blink_fuzzer_unittests\": \"90c934d41e80dabdf7460320fbf618d5f8526646\", \"blink_heap_unittests\": \"dee0fa3a1291d5692ebb3fdd6a556a830fdf35f3\", \"blink_platform_unittests\": \"638fa6632cad37c36d12e0c091e60182c8833d72\", \"blink_python_tests\": \"eed8cd515c9f5fe250430b44477a6bb0360c4390\", \"blink_unittests\": \"2fc681eca73626ab536acaa303169e48d65b1cf5\", \"blink_web_tests\": \"5eb142356f545667b08865144c08b5eedd6703d2\", \"boringssl_crypto_tests\": \"461cc6c0d56144de44c3e9ad408dc00cdb47e109\", \"boringssl_ssl_tests\": \"ea9939c1d3004f41cdb5840bd92fc55bee1f4886\", \"browser_tests\": \"f0affaca05028d2e7fe58d21a5ea7a144cd6b49d\", \"cacheinvalidation_unittests\": \"73497498e9f8824388407299e0394d3e5d330e1d\", \"capture_unittests\": \"e97ff02eaa04a82b8017bcea5a57634b83ecc794\", \"cast_unittests\": \"766bd54b1987bc3bbeddc7366e021b2eab0079a0\", \"cc_unittests\": \"d145f845adebd91d912accce561db5949f8e1a61\", \"chrome_app_unittests\": \"059f857085e13c4652f4716907779cd2f80ad0d5\", \"chromedriver_unittests\": \"e4576e9e0c3313db1c726b29e8283a1096fa1b2f\", \"components_browsertests\": \"de201bd3b3614b7b12e2d31501724cc820935dfc\", \"components_unittests\": \"6b91611f25b090b288bdc8a7f8b483371bbf1fca\", \"compositor_unittests\": \"ff002de4da4e79d9eece9b886154d3d6ce70c8bc\", \"content_browsertests\": \"50500da543de5033f4b35dd1e7760fb93459c087\", \"content_shell_crash_test\": \"fba0bb5fbb885b54540c6653df1844ad4bf9d5e5\", \"content_unittests\": \"ce1f5685e4a476384147c7c4e4c393835fd5744b\", \"crashpad_tests\": \"492e583cb2c6358320c5b7c7c975618419d753ab\", \"cronet_tests\": \"6b73587282f4f27bcf18f3b2419f51de399794ef\", \"cronet_unittests\": \"ce0fd8572ed38eda0fcab3b10f218092a8654947\", \"crypto_unittests\": \"0b90e875f383334d038c7319a9dc3dc419d1f6cf\", \"dbus_unittests\": \"4711535e2c5b550031b9829f1ae3aa821cddaf15\", \"device_unittests\": \"2d0768f31bfff5fbe5d0afebcbc4998ffa2ae332\", \"display_unittests\": \"6b54363499a86117920128cfdf343848cbf716ef\", \"events_unittests\": \"ca3f44b5343fdd41d7f2e2bfb25f5066fbe894c2\", \"extensions_browsertests\": \"c4fb33900de0f075170be85eb034dac79a4a11ff\", \"extensions_unittests\": \"895359df1e5c912f1bbdd19913eb74b35e84c17b\", \"filesystem_service_unittests\": \"c1ab4d98cc7f737ac4bd8d60c7d2dd8c797ecfe9\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"cc9beb1cc4176b08f064efb06f94f5f09f826bd1\", \"gfx_unittests\": \"27fe5dc099f96d1d6c473946248a595a76e393d6\", \"gin_unittests\": \"6c38c1895f0e0d9b5062b1f31b4fef91ad94c255\", \"google_apis_unittests\": \"71d79c319ae8828bcaf6db133c309713cf197cb9\", \"gpu_unittests\": \"24fef08c8eebd482bbf49cb177b33162fcc509a9\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"598b2b92ea3c87f570e8ad04201a6e1cac61e94d\", \"headless_browsertests\": \"cee11524f968b93cee3a358b5526e62c70d2a13d\", \"headless_unittests\": \"8acafa6b8831a5097dcb119547b546c7f0cbb321\", \"interactive_ui_tests\": \"7ce2dc51a2ddeab35a50182c190f738bf15f26ae\", \"ipc_tests\": \"96f1103615eb0fcb63e3fa26982c075286e0d92f\", \"jingle_unittests\": \"d21339f765619ef202cd2a887bff9030846b3374\", \"latency_unittests\": \"200d8aa2f89968c5ad26d6febd057c8f0484af2f\", \"libjingle_xmpp_unittests\": \"2a1b644b2a2df5d14f45d62e0e3e79902a98f33f\", \"media_blink_unittests\": \"8004ea0e9453082786d17b794cac84b0d9b62720\", \"media_service_unittests\": \"bcdeb883440a81f47327a09ba938f4b34cb2bfd7\", \"media_unittests\": \"2390fb9b09babd6f6d88db64bb7667b61ad65abc\", \"message_center_unittests\": \"566e0529dd55641f0bf092770583e20dac89129f\", \"metrics_python_tests\": \"3b713b3bcb60511f8697b065cc37168cd7ba9b6c\", \"midi_unittests\": \"e6417c666eb60910c1d8802ddf23740affb62f93\", \"mojo_core_unittests\": \"0e5bdec0e9514f453f948d6faf9185d07b4ca729\", \"mojo_unittests\": \"b3fefb6e8e7bb8cf5c5aafec10db69ac4b0db31d\", \"nacl_helper_nonsfi_unittests\": \"b9ac89d54d78249f4e710e2b79cb100c4d97f574\", \"nacl_loader_unittests\": \"416beb393262d14bfc7e602a41d42c66551705e1\", \"native_theme_unittests\": \"3aade7f6a21c9392bd55b99cfd84fb665b092665\", \"net_unittests\": \"5257866a2ddaf3f835692ee2a7c1d846e3fa2335\", \"pdf_unittests\": \"30b03e7aa508a9df989eea28e9b18d081507007b\", \"perfetto_unittests\": \"6ef1f342e5dde004d865bc1f0b7fdc517ded47c3\", \"ppapi_unittests\": \"7538150cf4e69c246d9a75da683315a8774c27a4\", \"printing_unittests\": \"3106403bed14d8fe5194800a8f98b9ff8febdb82\", \"remoting_unittests\": \"a765015726ecf317535b44b5e7bb0b315c6d7395\", \"sandbox_linux_unittests\": \"4136f03cd3bd84ff5fa008f0a92c50e568009892\", \"service_manager_unittests\": \"f837025b2e17d08e5f9c35b5d8bbd63bf4767262\", \"services_unittests\": \"f8c1cc4fcd8b6e72dce83173d54fc6c581b11af1\", \"shell_dialogs_unittests\": \"56b85a8125212f1eb3b350601d1f391d7bad53e1\", \"skia_unittests\": \"d111e637f8335cb32ac715fd6dbb29e601721edf\", \"snapshot_unittests\": \"f615cdc40d5415c7d31714090295e437b1c8180c\", \"sql_unittests\": \"7ea20f273dbf479ef9e4cb66aaee57b953df7350\", \"storage_unittests\": \"949924899427eb6c777763976612660faa930af3\", \"sync_integration_tests\": \"1f6708e6437a84e22b7b6ffe09a026e29dc34886\", \"telemetry_gpu_unittests\": \"eed8964c04d8f62411dbbec1e9c0efb32f34f556\", \"telemetry_perf_unittests\": \"62019a9868e8c0f54cac5ee06103529ecacd150c\", \"telemetry_unittests\": \"3318a7f43c5d60cb37322ffdd02cedfc9d0978d9\", \"traffic_annotation_auditor_unittests\": \"68be1c9b800f8b313b342f3d2699d8f2607eab5f\", \"ui_base_unittests\": \"6ba63585bb17512e87910c69a32c20bb2902499d\", \"ui_touch_selection_unittests\": \"ab624ce0330768b26d5b0636346f6940c2f5ab66\", \"unit_tests\": \"e1049e68ff9b8111080233b3172b0bf502d5b13f\", \"url_unittests\": \"bbdcb84d5eadbc0a2b38090fcb0e6d8660a7152b\", \"views_perftests\": \"4276369ced5accfa2b34f2c13934a3d5bd8ab97c\", \"views_unittests\": \"78cfb62324b40c0470966fb3ea02d3a7f074b057\", \"viz_unittests\": \"31b9502c4cdcc88646e19e5d9752051d1a1293ed\", \"vr_common_unittests\": \"0572a75f1fbff5607d4c5da44bbd160a1fe98d58\", \"vr_pixeltests\": \"6d5ecfa27e5de3cf3809e8786024d2ddbaf17c1c\", \"webdriver_wpt_tests\": \"4c4a95dc6f482e78f4c40b8786b27bd818d73d16\", \"weblayer_browsertests\": \"813b76f6e57676416139f71db3184a39a9e3d0d2\", \"wm_unittests\": \"774c64d5cd57a925bfb2b6f0fb2cb991b8bea30a\", \"wtf_unittests\": \"d978864b9c207cb14dc64612ac37c9f94c7f8087\", \"xr_browser_tests\": \"d7ae659f88e8aab41723a9f299ac0542ed2795cb\"}}, \"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-47-hzvq\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"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\": [\"4784-e3296b5\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579658888046564",
"status": "COMPLETED",
"status_changed_ts": "1579662267571109",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167666",
"builder:Linux Builder (dbg)",
"buildset:commit/git/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/28cbcb259ef103173e1dfc4d60f91d9cb5a30dd3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056031679763502432",
"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/8890601616872855600/+/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:49e3cef548ec5a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579662267574559",
"url": "https://ci.chromium.org/b/8890601616872855600",
"utcnow_ts": "1579662268619265"
}
}
[03:04:28.632] Invocation finished in 1h1m7.975934093s with status SUCCEEDED