[06:00:32.131] New invocation is queued and will start shortly
[06:00:32.131] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[06:00:33.203] Starting the invocation (attempt 1)
[06:00:33.256] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:00:33.256] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:00:33.256] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9082289877255382064",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-linux\",\"properties\":{\"root_solution_revision\":\"3acfc0621e0eacebb1bcf425ca734fee760e4361\",\"root_solution_revision_timestamp\":1554615016}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-linux",
"scheduler_invocation_id:9082289877255382064",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:8149",
"user_agent:recipe"
]
}
[06:00:34.659] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1554616833596241",
"id": "8916859815053407008",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"root_solution_revision\": \"3acfc0621e0eacebb1bcf425ca734fee760e4361\", \"root_solution_revision_timestamp\": 1554615016}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554616834506994",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/5902",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:8149",
"scheduler_invocation_id:9082289877255382064",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8916859815053407008/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1554616834507123",
"url": "https://ci.chromium.org/b/8916859815053407008",
"utcnow_ts": "1554616834641988"
}
}
[06:00:34.659] Task URL: https://ci.chromium.org/b/8916859815053407008
[06:00:34.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:2:0) after 1m0s
[06:01:12.545] Received PubSub notification, asking Buildbucket for the build status
[06:01:12.603] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:34.675] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:2:0)
[06:01:34.675] Timer tick, asking Buildbucket for the build status
[06:01:34.907] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:34.907] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:4:0) after 1m0s
[06:02:34.926] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:4:0)
[06:02:34.926] Timer tick, asking Buildbucket for the build status
[06:02:34.991] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:34.992] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:5:0) after 1m0s
[06:03:35.009] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:5:0)
[06:03:35.009] Timer tick, asking Buildbucket for the build status
[06:03:35.117] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:35.117] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:6:0) after 1m0s
[06:04:35.096] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:6:0)
[06:04:35.096] Timer tick, asking Buildbucket for the build status
[06:04:35.160] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:35.161] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:7:0) after 1m0s
[06:05:35.182] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:7:0)
[06:05:35.182] Timer tick, asking Buildbucket for the build status
[06:05:35.441] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:35.441] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:8:0) after 1m0s
[06:06:35.487] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:8:0)
[06:06:35.487] Timer tick, asking Buildbucket for the build status
[06:06:35.609] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:35.609] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:9:0) after 1m0s
[06:07:35.627] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:9:0)
[06:07:35.627] Timer tick, asking Buildbucket for the build status
[06:07:35.722] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:35.722] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:10:0) after 1m0s
[06:08:35.791] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:10:0)
[06:08:35.791] Timer tick, asking Buildbucket for the build status
[06:08:35.945] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:35.945] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:11:0) after 1m0s
[06:09:35.981] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:11:0)
[06:09:35.981] Timer tick, asking Buildbucket for the build status
[06:09:36.092] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:36.092] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:12:0) after 1m0s
[06:10:36.113] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:12:0)
[06:10:36.113] Timer tick, asking Buildbucket for the build status
[06:10:36.175] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:36.175] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:13:0) after 1m0s
[06:11:36.210] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:13:0)
[06:11:36.210] Timer tick, asking Buildbucket for the build status
[06:11:36.355] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:36.356] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:14:0) after 1m0s
[06:12:36.347] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:14:0)
[06:12:36.347] Timer tick, asking Buildbucket for the build status
[06:12:36.595] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:36.595] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:15:0) after 1m0s
[06:13:36.621] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:15:0)
[06:13:36.621] Timer tick, asking Buildbucket for the build status
[06:13:36.779] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:36.779] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:16:0) after 1m0s
[06:14:36.798] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:16:0)
[06:14:36.798] Timer tick, asking Buildbucket for the build status
[06:14:36.877] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:36.877] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:17:0) after 1m0s
[06:15:36.895] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:17:0)
[06:15:36.895] Timer tick, asking Buildbucket for the build status
[06:15:37.000] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:37.000] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:18:0) after 1m0s
[06:16:37.005] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:18:0)
[06:16:37.005] Timer tick, asking Buildbucket for the build status
[06:16:37.217] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:37.217] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:19:0) after 1m0s
[06:17:37.219] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:19:0)
[06:17:37.219] Timer tick, asking Buildbucket for the build status
[06:17:37.389] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:37.389] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:20:0) after 1m0s
[06:18:37.409] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:20:0)
[06:18:37.409] Timer tick, asking Buildbucket for the build status
[06:18:37.483] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:37.483] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:21:0) after 1m0s
[06:19:37.456] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:21:0)
[06:19:37.456] Timer tick, asking Buildbucket for the build status
[06:19:37.571] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:37.571] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:22:0) after 1m0s
[06:20:37.584] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:22:0)
[06:20:37.585] Timer tick, asking Buildbucket for the build status
[06:20:37.792] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:37.792] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:23:0) after 1m0s
[06:21:37.792] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:23:0)
[06:21:37.792] Timer tick, asking Buildbucket for the build status
[06:21:37.888] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:37.888] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:24:0) after 1m0s
[06:22:37.882] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:24:0)
[06:22:37.882] Timer tick, asking Buildbucket for the build status
[06:22:38.025] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:38.025] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:25:0) after 1m0s
[06:23:38.064] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:25:0)
[06:23:38.064] Timer tick, asking Buildbucket for the build status
[06:23:38.141] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:38.141] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:26:0) after 1m0s
[06:24:38.161] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:26:0)
[06:24:38.161] Timer tick, asking Buildbucket for the build status
[06:24:38.351] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:38.351] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:27:0) after 1m0s
[06:25:38.342] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:27:0)
[06:25:38.342] Timer tick, asking Buildbucket for the build status
[06:25:38.660] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:38.660] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:28:0) after 1m0s
[06:26:38.678] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:28:0)
[06:26:38.678] Timer tick, asking Buildbucket for the build status
[06:26:38.804] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:38.804] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:29:0) after 1m0s
[06:27:38.819] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:29:0)
[06:27:38.819] Timer tick, asking Buildbucket for the build status
[06:27:38.897] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:38.897] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:30:0) after 1m0s
[06:28:38.916] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:30:0)
[06:28:38.916] Timer tick, asking Buildbucket for the build status
[06:28:38.980] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:38.980] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:31:0) after 1m0s
[06:29:39.000] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:31:0)
[06:29:39.000] Timer tick, asking Buildbucket for the build status
[06:29:39.346] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:39.346] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:32:0) after 1m0s
[06:30:39.357] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:32:0)
[06:30:39.357] Timer tick, asking Buildbucket for the build status
[06:30:39.510] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:39.510] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:33:0) after 1m0s
[06:31:39.488] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:33:0)
[06:31:39.488] Timer tick, asking Buildbucket for the build status
[06:31:39.691] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:39.691] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:34:0) after 1m0s
[06:32:39.670] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:34:0)
[06:32:39.670] Timer tick, asking Buildbucket for the build status
[06:32:39.733] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:39.733] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:35:0) after 1m0s
[06:33:39.711] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:35:0)
[06:33:39.711] Timer tick, asking Buildbucket for the build status
[06:33:39.786] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:39.786] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:36:0) after 1m0s
[06:34:39.805] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:36:0)
[06:34:39.805] Timer tick, asking Buildbucket for the build status
[06:34:39.979] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:39.979] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:37:0) after 1m0s
[06:35:39.997] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:37:0)
[06:35:39.997] Timer tick, asking Buildbucket for the build status
[06:35:40.062] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.062] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:38:0) after 1m0s
[06:36:40.088] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:38:0)
[06:36:40.088] Timer tick, asking Buildbucket for the build status
[06:36:40.222] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.222] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:39:0) after 1m0s
[06:37:40.209] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:39:0)
[06:37:40.209] Timer tick, asking Buildbucket for the build status
[06:37:40.375] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:40.375] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:40:0) after 1m0s
[06:38:40.395] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:40:0)
[06:38:40.395] Timer tick, asking Buildbucket for the build status
[06:38:40.460] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:40.460] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:41:0) after 1m0s
[06:39:40.481] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:41:0)
[06:39:40.481] Timer tick, asking Buildbucket for the build status
[06:39:40.544] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:40.544] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:42:0) after 1m0s
[06:40:40.563] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:42:0)
[06:40:40.563] Timer tick, asking Buildbucket for the build status
[06:40:40.747] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:40.747] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:43:0) after 1m0s
[06:41:40.764] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:43:0)
[06:41:40.764] Timer tick, asking Buildbucket for the build status
[06:41:40.843] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:40.844] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:44:0) after 1m0s
[06:42:40.819] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:44:0)
[06:42:40.819] Timer tick, asking Buildbucket for the build status
[06:42:40.893] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:40.893] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:45:0) after 1m0s
[06:43:40.909] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:45:0)
[06:43:40.909] Timer tick, asking Buildbucket for the build status
[06:43:41.081] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:41.081] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:46:0) after 1m0s
[06:44:41.104] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:46:0)
[06:44:41.104] Timer tick, asking Buildbucket for the build status
[06:44:41.215] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:41.215] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:47:0) after 1m0s
[06:45:41.235] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:47:0)
[06:45:41.235] Timer tick, asking Buildbucket for the build status
[06:45:41.304] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:41.304] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:48:0) after 1m0s
[06:46:41.332] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:48:0)
[06:46:41.332] Timer tick, asking Buildbucket for the build status
[06:46:41.480] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:41.480] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:49:0) after 1m0s
[06:47:41.501] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:49:0)
[06:47:41.501] Timer tick, asking Buildbucket for the build status
[06:47:41.660] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:41.660] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:50:0) after 1m0s
[06:48:41.691] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:50:0)
[06:48:41.691] Timer tick, asking Buildbucket for the build status
[06:48:41.750] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:41.750] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:51:0) after 1m0s
[06:49:41.777] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:51:0)
[06:49:41.777] Timer tick, asking Buildbucket for the build status
[06:49:41.846] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:41.846] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:52:0) after 1m0s
[06:50:41.854] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:52:0)
[06:50:41.854] Timer tick, asking Buildbucket for the build status
[06:50:41.980] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:41.980] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:53:0) after 1m0s
[06:51:42.001] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:53:0)
[06:51:42.001] Timer tick, asking Buildbucket for the build status
[06:51:42.057] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:42.057] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:54:0) after 1m0s
[06:52:42.087] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:54:0)
[06:52:42.087] Timer tick, asking Buildbucket for the build status
[06:52:42.126] Build 8916859815053407008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:42.126] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9082289877255382064:55:0) after 1m0s
[06:53:10.924] Received PubSub notification, asking Buildbucket for the build status
[06:53:11.043] Build 8916859815053407008: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:53:11.043] Buildbucket build:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "AUTO",
"completed_ts": "1554619990639558",
"created_by": "project:infra",
"created_ts": "1554616833596241",
"failure_reason": "BUILD_FAILURE",
"id": "8916859815053407008",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"root_solution_revision\": \"3acfc0621e0eacebb1bcf425ca734fee760e4361\", \"root_solution_revision_timestamp\": 1554615016}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('add kythe metadata') failed with return_code 1\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1879-c4\", \"buildername\": \"codesearch-gen-chromium-linux\", \"buildnumber\": 5902, \"got_angle_revision\": \"90b1865e2a8b644bc3e200726de9e3a321ee5c18\", \"got_clang_revision\": \"357692-1\", \"got_dawn_revision\": \"20b0c33913e45cd3d553b42a43b75b27af817833\", \"got_nacl_revision\": \"067afa275dcb4b3c6eca979ea5e3fe75073c4080\", \"got_revision\": \"3acfc0621e0eacebb1bcf425ca734fee760e4361\", \"got_revision_cp\": \"refs/heads/master@{#648524}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"f19370068e0906ccff45aace0faac15ab3c5fc6a\", \"got_v8_revision_cp\": \"refs/heads/7.5.209@{#1}\", \"got_webrtc_revision\": \"59e875ce18bb24aa6d1ec5cdf128d7ef244fa37a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27463}\", \"mastername\": \"chromium.infra.codesearch\", \"path_config\": \"generic\", \"recipe\": \"chromium_codesearch\", \"root_solution_revision\": \"3acfc0621e0eacebb1bcf425ca734fee760e4361\", \"root_solution_revision_timestamp\": 1554615016.0}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"codesearch-gen-chromium-linux\"], \"caches\": [\"builder_14b4f73c8b95a6615516bba597f721e10c68093aeabbb388e9a5697d2f7f84cc_v2\", \"codesearch_git_genfiles_repo\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1879-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.codesearch\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step('add kythe metadata') failed with return_code 1\"}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554616872013139",
"status": "COMPLETED",
"status_changed_ts": "1554616872245547",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/5902",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:8149",
"scheduler_invocation_id:9082289877255382064",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8916859815053407008/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:440f33e28fdf0010",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1554619990763317",
"url": "https://ci.chromium.org/b/8916859815053407008",
"utcnow_ts": "1554619991025693"
}
}
[06:53:11.043] Invocation finished in 52m38.929892993s with status FAILED