[00:59:26.564] New invocation is queued and will start shortly
[00:59:27.858] Starting the invocation (attempt 1)
[00:59:27.895] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:59:27.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:59:27.895] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053408388118029920",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"171396516faad1d5933e50a42c694aee2461171a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053408388118029920",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/171396516faad1d5933e50a42c694aee2461171a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/171396516faad1d5933e50a42c694aee2461171a",
"gitiles_ref:refs/heads/master"
]
}
[00:59:29.200] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582160368018408",
"id": "8887978325903585200",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"171396516faad1d5933e50a42c694aee2461171a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582160368885995",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168806",
"builder:Linux Builder (dbg)",
"buildset:commit/git/171396516faad1d5933e50a42c694aee2461171a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/171396516faad1d5933e50a42c694aee2461171a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053408388118029920",
"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/8887978325903585200/+/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": "1582160368886936",
"url": "https://ci.chromium.org/b/8887978325903585200",
"utcnow_ts": "1582160369143306"
}
}
[00:59:29.200] Task URL: https://ci.chromium.org/b/8887978325903585200
[00:59:29.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:2:0) after 1m0s
[01:00:29.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:2:0)
[01:00:29.332] Timer tick, asking Buildbucket for the build status
[01:00:29.527] Build 8887978325903585200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:29.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:3:0) after 1m0s
[01:01:29.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:3:0)
[01:01:29.611] Timer tick, asking Buildbucket for the build status
[01:01:29.724] Build 8887978325903585200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:29.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:4:0) after 1m0s
[01:02:29.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:4:0)
[01:02:29.853] Timer tick, asking Buildbucket for the build status
[01:02:30.011] Build 8887978325903585200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:30.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:5:0) after 1m0s
[01:03:30.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:5:0)
[01:03:30.138] Timer tick, asking Buildbucket for the build status
[01:03:30.337] Build 8887978325903585200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:30.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:6:0) after 1m0s
[01:04:07.609] Received PubSub notification, asking Buildbucket for the build status
[01:04:07.730] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:30.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:6:0)
[01:04:30.512] Timer tick, asking Buildbucket for the build status
[01:04:30.581] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:30.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:8:0) after 1m0s
[01:05:30.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:8:0)
[01:05:30.661] Timer tick, asking Buildbucket for the build status
[01:05:30.747] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:30.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:9:0) after 1m0s
[01:06:30.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:9:0)
[01:06:30.818] Timer tick, asking Buildbucket for the build status
[01:06:31.129] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:31.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:10:0) after 1m0s
[01:07:31.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:10:0)
[01:07:31.213] Timer tick, asking Buildbucket for the build status
[01:07:31.324] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:31.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:11:0) after 1m0s
[01:08:31.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:11:0)
[01:08:31.378] Timer tick, asking Buildbucket for the build status
[01:08:31.471] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:31.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:12:0) after 1m0s
[01:09:31.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:12:0)
[01:09:31.475] Timer tick, asking Buildbucket for the build status
[01:09:31.775] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:31.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:13:0) after 1m0s
[01:10:31.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:13:0)
[01:10:31.890] Timer tick, asking Buildbucket for the build status
[01:10:32.158] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:32.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:14:0) after 1m0s
[01:11:32.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:14:0)
[01:11:32.213] Timer tick, asking Buildbucket for the build status
[01:11:32.530] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:32.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:15:0) after 1m0s
[01:12:32.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:15:0)
[01:12:32.556] Timer tick, asking Buildbucket for the build status
[01:12:32.697] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:32.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:16:0) after 1m0s
[01:13:32.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:16:0)
[01:13:32.800] Timer tick, asking Buildbucket for the build status
[01:13:34.364] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:34.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:17:0) after 1m0s
[01:14:34.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:17:0)
[01:14:34.449] Timer tick, asking Buildbucket for the build status
[01:14:34.560] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:34.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:18:0) after 1m0s
[01:15:34.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:18:0)
[01:15:34.651] Timer tick, asking Buildbucket for the build status
[01:15:34.961] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:34.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:19:0) after 1m0s
[01:16:35.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:19:0)
[01:16:35.014] Timer tick, asking Buildbucket for the build status
[01:16:35.205] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:35.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:20:0) after 1m0s
[01:17:35.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:20:0)
[01:17:35.224] Timer tick, asking Buildbucket for the build status
[01:17:35.325] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:35.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:21:0) after 1m0s
[01:18:35.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:21:0)
[01:18:35.349] Timer tick, asking Buildbucket for the build status
[01:18:35.459] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:35.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:22:0) after 1m0s
[01:19:35.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:22:0)
[01:19:35.634] Timer tick, asking Buildbucket for the build status
[01:19:35.769] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:35.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:23:0) after 1m0s
[01:20:35.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:23:0)
[01:20:35.934] Timer tick, asking Buildbucket for the build status
[01:20:36.173] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:36.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:24:0) after 1m0s
[01:21:36.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:24:0)
[01:21:36.202] Timer tick, asking Buildbucket for the build status
[01:21:36.319] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:36.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:25:0) after 1m0s
[01:22:36.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:25:0)
[01:22:36.422] Timer tick, asking Buildbucket for the build status
[01:22:36.506] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:36.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:26:0) after 1m0s
[01:23:36.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:26:0)
[01:23:36.671] Timer tick, asking Buildbucket for the build status
[01:23:37.111] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:37.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:27:0) after 1m0s
[01:24:37.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:27:0)
[01:24:37.191] Timer tick, asking Buildbucket for the build status
[01:24:37.466] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:37.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:28:0) after 1m0s
[01:25:37.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:28:0)
[01:25:37.530] Timer tick, asking Buildbucket for the build status
[01:25:37.658] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:37.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:29:0) after 1m0s
[01:26:37.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:29:0)
[01:26:37.829] Timer tick, asking Buildbucket for the build status
[01:26:38.038] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:38.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:30:0) after 1m0s
[01:27:38.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:30:0)
[01:27:38.242] Timer tick, asking Buildbucket for the build status
[01:27:38.668] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:38.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:31:0) after 1m0s
[01:28:38.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:31:0)
[01:28:38.679] Timer tick, asking Buildbucket for the build status
[01:28:38.807] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:38.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:32:0) after 1m0s
[01:29:38.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:32:0)
[01:29:38.870] Timer tick, asking Buildbucket for the build status
[01:29:39.974] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:39.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:33:0) after 1m0s
[01:30:40.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:33:0)
[01:30:40.001] Timer tick, asking Buildbucket for the build status
[01:30:40.094] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:40.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:34:0) after 1m0s
[01:31:40.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:34:0)
[01:31:40.114] Timer tick, asking Buildbucket for the build status
[01:31:40.364] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:40.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:35:0) after 1m0s
[01:32:40.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:35:0)
[01:32:40.442] Timer tick, asking Buildbucket for the build status
[01:32:40.595] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:40.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:36:0) after 1m0s
[01:33:40.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:36:0)
[01:33:40.606] Timer tick, asking Buildbucket for the build status
[01:33:41.017] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:41.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:37:0) after 1m0s
[01:34:41.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:37:0)
[01:34:41.153] Timer tick, asking Buildbucket for the build status
[01:34:41.257] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:41.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:38:0) after 1m0s
[01:35:41.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:38:0)
[01:35:41.270] Timer tick, asking Buildbucket for the build status
[01:35:41.402] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:41.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:39:0) after 1m0s
[01:36:41.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:39:0)
[01:36:41.449] Timer tick, asking Buildbucket for the build status
[01:36:41.559] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:41.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:40:0) after 1m0s
[01:37:41.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:40:0)
[01:37:41.578] Timer tick, asking Buildbucket for the build status
[01:37:41.713] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:41.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:41:0) after 1m0s
[01:38:41.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:41:0)
[01:38:41.802] Timer tick, asking Buildbucket for the build status
[01:38:42.004] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:42.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:42:0) after 1m0s
[01:39:41.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:42:0)
[01:39:41.991] Timer tick, asking Buildbucket for the build status
[01:39:42.125] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:42.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:43:0) after 1m0s
[01:40:42.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:43:0)
[01:40:42.167] Timer tick, asking Buildbucket for the build status
[01:40:42.317] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:42.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:44:0) after 1m0s
[01:41:42.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:44:0)
[01:41:42.394] Timer tick, asking Buildbucket for the build status
[01:41:42.664] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:42.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:45:0) after 1m0s
[01:42:42.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:45:0)
[01:42:42.701] Timer tick, asking Buildbucket for the build status
[01:42:43.076] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:43.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:46:0) after 1m0s
[01:43:43.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:46:0)
[01:43:43.130] Timer tick, asking Buildbucket for the build status
[01:43:43.488] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:43.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:47:0) after 1m0s
[01:44:43.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:47:0)
[01:44:43.616] Timer tick, asking Buildbucket for the build status
[01:44:43.801] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:43.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:48:0) after 1m0s
[01:45:43.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:48:0)
[01:45:43.908] Timer tick, asking Buildbucket for the build status
[01:45:44.175] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:44.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:49:0) after 1m0s
[01:46:44.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:49:0)
[01:46:44.460] Timer tick, asking Buildbucket for the build status
[01:46:44.737] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:44.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:50:0) after 1m0s
[01:47:44.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:50:0)
[01:47:44.829] Timer tick, asking Buildbucket for the build status
[01:47:44.932] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:44.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:51:0) after 1m0s
[01:48:44.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:51:0)
[01:48:44.992] Timer tick, asking Buildbucket for the build status
[01:48:45.114] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:45.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:52:0) after 1m0s
[01:49:45.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:52:0)
[01:49:45.175] Timer tick, asking Buildbucket for the build status
[01:49:45.291] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:53:0) after 1m0s
[01:50:45.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:53:0)
[01:50:45.317] Timer tick, asking Buildbucket for the build status
[01:50:45.692] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:45.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:54:0) after 1m0s
[01:51:45.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:54:0)
[01:51:45.769] Timer tick, asking Buildbucket for the build status
[01:51:45.878] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:45.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:55:0) after 1m0s
[01:52:45.919] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:55:0)
[01:52:45.919] Timer tick, asking Buildbucket for the build status
[01:52:46.097] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:46.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:56:0) after 1m0s
[01:53:46.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:56:0)
[01:53:46.158] Timer tick, asking Buildbucket for the build status
[01:53:46.392] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:46.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:57:0) after 1m0s
[01:54:46.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:57:0)
[01:54:46.534] Timer tick, asking Buildbucket for the build status
[01:54:46.867] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:46.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:58:0) after 1m0s
[01:55:46.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:58:0)
[01:55:46.890] Timer tick, asking Buildbucket for the build status
[01:55:47.071] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:47.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:59:0) after 1m0s
[01:56:47.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:59:0)
[01:56:47.136] Timer tick, asking Buildbucket for the build status
[01:56:47.276] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:47.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:60:0) after 1m0s
[01:57:47.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:60:0)
[01:57:47.327] Timer tick, asking Buildbucket for the build status
[01:57:47.427] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:47.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:61:0) after 1m0s
[01:58:47.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:61:0)
[01:58:47.445] Timer tick, asking Buildbucket for the build status
[01:58:47.650] Build 8887978325903585200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:47.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053408388118029920:62:0) after 1m0s
[01:59:04.025] Received PubSub notification, asking Buildbucket for the build status
[01:59:04.108] Build 8887978325903585200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:59:04.108] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582163943078667",
"created_by": "project:chromium",
"created_ts": "1582160368018408",
"id": "8887978325903585200",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"171396516faad1d5933e50a42c694aee2461171a\"}}",
"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-13-j2ux\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168806, \"got_angle_revision\": \"49010904083f28e0890388ea8514a4053d1f35c5\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"171396516faad1d5933e50a42c694aee2461171a\", \"got_revision_cp\": \"refs/heads/master@{#742897}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8def9063c4fdefa8e644198d2254ef279952c88b\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"171396516faad1d5933e50a42c694aee2461171a\", \"swarm_hashes_refs/heads/master(at){#742897}_without_patch\": {\"accessibility_unittests\": \"76da19ff412f890540e08708eacd0fb9f20a6d22\", \"angle_unittests\": \"18ff6093a11859345ad191d18ed53f4282b3a76f\", \"app_shell_unittests\": \"154aa2a06135ee0bcfb3fd218d79f1b06215451f\", \"aura_unittests\": \"ccda5a3f9d44e8dcd8f3d3836eff3fb4df517736\", \"base_unittests\": \"edf8c88ce549a189bb11fa03ba8558c35d1d962a\", \"base_util_unittests\": \"774aa7bbcc06277cee27287eb6c31a57094fbfd8\", \"blink_common_unittests\": \"55a0b966314802da766616ae1ac994a71d7d3d63\", \"blink_fuzzer_unittests\": \"65514b175d3cebdde52df305cfe324940c821873\", \"blink_heap_unittests\": \"93b11bf4609d1ce67c4131b72400a44626fbfec2\", \"blink_platform_unittests\": \"772f96d1558ae1a87bd1255c806eb11b614a2017\", \"blink_python_tests\": \"eaf23057c19182bb72145a5d53671fd1eb4cf5bf\", \"blink_unittests\": \"e165608545373411456ab8f385d47d7cf700181d\", \"blink_web_tests\": \"35b14a451da08a198e88b2fa2867c9bb9a01d637\", \"boringssl_crypto_tests\": \"e134738b0b11b0c76b1f6ae86b8180418508349a\", \"boringssl_ssl_tests\": \"2f79b72cc9bea4cf1dfbeab03c32b34daa6e9708\", \"browser_tests\": \"ceb35c3b7d69c6cb8ba170eefd8d5b7b1ec76b1d\", \"cacheinvalidation_unittests\": \"58101dc47a4877ae1278842a018e0c70e368c9f7\", \"capture_unittests\": \"948619ac18283fbe6af0ef5ec260c39dca2f9c54\", \"cast_unittests\": \"8cac5c3bd35617a02a43c1af049511352ba01b6f\", \"cc_unittests\": \"084d4f334e73933bb1550049d093baf387cb04c6\", \"chrome_app_unittests\": \"1df2110d6f4df852089fcc9f1aa032a5e1d13578\", \"chromedriver_unittests\": \"7032896f4818e507662fccb7ad7ceced721f29f0\", \"components_browsertests\": \"3241d4367740f5fc455f6ffdbb44daa193c579da\", \"components_unittests\": \"ddbbd8f46ebe35f77bfe6422d5a9e1ad7e12e04a\", \"compositor_unittests\": \"369d9bf59d0716c1c4b6770a50a78d58c509395b\", \"content_browsertests\": \"f105da795791b8b474de18c8c56b7a1faa7821c8\", \"content_shell_crash_test\": \"9b047b19a37ff6593e173d87f6cd878cc7748ece\", \"content_unittests\": \"16710cd506e1cddabfa2471d5e11e3d19e9b9e13\", \"crashpad_tests\": \"50f14793322b6fe88b1bc4a19d554cfa98dbc2eb\", \"cronet_tests\": \"49011ffe70d08edebc1edc549232cfd22df6db1c\", \"cronet_unittests\": \"aa96f81216061390ff8bf6c526973ccef6abcd4c\", \"crypto_unittests\": \"1a3c1bb87b9adc899c71c6a674e750c4eda2e0f1\", \"dbus_unittests\": \"cf3f5200c3b9aa67fa6545afbcd65a15038bf01a\", \"device_unittests\": \"1c5f8aa552fc1f42f85a88c6db8f7035b218d7c9\", \"display_unittests\": \"718905cc1d207037ca268be7c59928f2e9459824\", \"events_unittests\": \"76b619271164fa16a5d7bda6010b6c3d7c718aec\", \"extensions_browsertests\": \"d6f79454b26d46ec32fef71b5d6ff0c91af593c6\", \"extensions_unittests\": \"bc158185faf94abcad4b25837d9ac9106cc1cd14\", \"filesystem_service_unittests\": \"611685285dd0c5ea034e6d9bda28416a596d6228\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"6ae02c3c377184ff00ce27b2e81ecdc33af13ff7\", \"gfx_unittests\": \"04376b1a455d590239d312d2ed05508143cd6e34\", \"gin_unittests\": \"8a7808d309805a8c18d91f4c68ff2c836b8daa7e\", \"google_apis_unittests\": \"06d7ba6bc95664e151c86fb88c1aa714df316d33\", \"gpu_unittests\": \"09f5ffad2dce8ac4fa2f2814cb3d86a1b706a11e\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"6542385fbcebfd1876b97303d131d873ed011186\", \"headless_browsertests\": \"8cae5622dd4e3688e207cc5fda778fc51242c591\", \"headless_unittests\": \"05c4735d82e4bc21b6706c29fad4b6654ff52dcc\", \"interactive_ui_tests\": \"f65c8dcb958f9cd4a36a3c1190f97539911d30f7\", \"ipc_tests\": \"1c511f7200e0d8bdc68a321eb3b8399bae89c1a3\", \"jingle_unittests\": \"01739ecc1fffeef0efb0f55cfd5489d8378f5ce9\", \"latency_unittests\": \"9cf7537d838b1aef8d91e89d9b10dfe57500b6b0\", \"libjingle_xmpp_unittests\": \"8228cfe0f5a80693ba55a4994d7826b3369d9333\", \"media_blink_unittests\": \"60939155b9e74d28c578845ce95df2581f3c887a\", \"media_service_unittests\": \"49d2a8c3854b123a29669a5b1a39d98a91dac5c9\", \"media_unittests\": \"2e98224605aab6d78213f4e6640024ea7744540c\", \"message_center_unittests\": \"86efa3d0df7ca1320d96d8ca735ed2ea397e3d63\", \"metrics_python_tests\": \"48ab4da2a2316608ad44d9a303fe7c885d6a62a6\", \"midi_unittests\": \"fb080403e4747e4eae73a5e77620f8e4bebb9486\", \"mojo_core_unittests\": \"c54fd327134f787dbcd0e014c81323c81985d8fc\", \"mojo_unittests\": \"e3424ab2a74e88434fee24d6c3717088ab515da3\", \"nacl_helper_nonsfi_unittests\": \"4c5a928605cc724131db967c95cbac313483ad4e\", \"nacl_loader_unittests\": \"782bf6dd86711d558e27b888059762a69607ebd5\", \"native_theme_unittests\": \"5778373498687e6946203e31586223689dec5244\", \"net_unittests\": \"b148f96881be413a4bd00693fa5823cf47cb209f\", \"pdf_unittests\": \"da5bedf420abc6e896f609ef6bcc87d809d577cb\", \"perfetto_unittests\": \"9ce53e1433e7675d0377e67507ac1fb542d70bd4\", \"ppapi_unittests\": \"f779b49c265184fc76feaf30322b5eb5f30b8442\", \"printing_unittests\": \"5c262394eba80bab91df95a52ea1071bc4992176\", \"remoting_unittests\": \"bb818e3d6b3f1dbfb7e2f76cc36f592af44faf8a\", \"sandbox_linux_unittests\": \"235180178d48bbdb5576d9f9634c38e2de9cb175\", \"service_manager_unittests\": \"e3419af74831799812d6e043b71895506c709d1e\", \"services_unittests\": \"e229ce10c74d9506f365b739bca407cf5c7b127a\", \"shell_dialogs_unittests\": \"dee24c5b2021dc7e26c2534f68011e35201ad9ce\", \"skia_unittests\": \"419695adfde72d9c61a37aac84b667caae99ddda\", \"snapshot_unittests\": \"a02673110dcc8d1c48f2461708c7deb35d6c06fd\", \"sql_unittests\": \"d2c42f946372cab6379d7672c3ee8a8f0c9433a4\", \"storage_unittests\": \"1a12cbaeb57983e0007e48c2b8db1c14025552d0\", \"sync_integration_tests\": \"f35d242f60390dd08815812aa1384c000193493b\", \"telemetry_gpu_unittests\": \"dda53d34f7bdd31d153fb412e8dadb5958f407b2\", \"telemetry_perf_unittests\": \"615a9a0faa6ea46c8714cef3cc0f99365d9bd22b\", \"telemetry_unittests\": \"62ec81b34c1731e14d76eed85565c63e15b55803\", \"traffic_annotation_auditor_unittests\": \"a05ced982179bafc2cfd127ff1613aa752832926\", \"ui_base_unittests\": \"b4c1fe1f43a19c6eaf5d0b5950941b1be87d1691\", \"ui_touch_selection_unittests\": \"f1861d66a7454ed77d2fe8de83e8765270d654dd\", \"unit_tests\": \"e3cb6cf3588378f42092c1963ddcc03a75333993\", \"url_unittests\": \"330114ef43948597a7d6c8a653324300518b38e4\", \"views_perftests\": \"b76ed6ba7736a4335f42324c8abca679f31b5866\", \"views_unittests\": \"2cf8a0895060af21e06c577afb26cf7a6073f7e1\", \"viz_unittests\": \"8deef30ca6328068772ca97666ccaca9ac04d51f\", \"vr_common_unittests\": \"ffebb0607b44716f4d3a5825f40e5574fb237e7a\", \"vr_pixeltests\": \"07fff9a650bdd3b02caa740409ee061422f6675b\", \"webdriver_wpt_tests\": \"d26a83a66106468a3683f198df0f7bbd27bf3759\", \"weblayer_browsertests\": \"386eb1fadbb8df1eed0db3a8c693d99a9bd87719\", \"wm_unittests\": \"229a338217821c74aaeff1a5527161d8c9b7d6cb\", \"wtf_unittests\": \"e9f599977c68623398966711202500e126c34be5\", \"xr_browser_tests\": \"52827dd7d58d5bd483638bea3aaa4c543860d0be\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_01a2bb7b9ec3691455d2ab60c15f7ad693c162c478afc3e31d48df8038bddadd_v2\", \"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_911c325d5064f233804a8a9f54da19e80424921a825d2040115ae4dfe5f29775_v2\", \"builder_a3249012ab2e105b4f1a10f0dc352b422f692fd701d889292e5c6170d198db4b_v2\", \"builder_ee0cba3c54f4de5afe190f5f1f18eea3c236437d0f634917582eff9316b81bdc_v2\", \"builder_eeb4aed1f5957a78d1f0fe1c00bdb778895591d08c1bb63c47ebb234324ef661_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-east1-d-8-13-j2ux\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582160646850111",
"status": "COMPLETED",
"status_changed_ts": "1582163943474101",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168806",
"builder:Linux Builder (dbg)",
"buildset:commit/git/171396516faad1d5933e50a42c694aee2461171a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/171396516faad1d5933e50a42c694aee2461171a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053408388118029920",
"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/8887978325903585200/+/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:4a78ecdf07963110",
"user_agent:luci-scheduler"
],
"updated_ts": "1582163943507732",
"url": "https://ci.chromium.org/b/8887978325903585200",
"utcnow_ts": "1582163944095487"
}
}
[01:59:04.108] Invocation finished in 59m37.586026124s with status SUCCEEDED