[20:00:42.627] New invocation is queued and will start shortly
[20:00:42.627] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[20:00:44.116] Starting the invocation (attempt 1)
[20:00:44.198] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:00:44.198] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:00:44.199] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9067832100361611776",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-fuchsia\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"root_solution_revision\":\"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\",\"root_solution_revision_timestamp\":1568404444},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:9256\",\"user_agent:recipe\"]},\"id\":\"d8ac8696-0e85-4211-94b4-ade7ab2fe04f\",\"title\":\"codesearch-gen-chromium-initiator/9256\"}]},\"root_solution_revision\":\"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\",\"root_solution_revision_timestamp\":1568404444}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-fuchsia",
"scheduler_invocation_id:9067832100361611776",
"scheduler_job_id:infra/codesearch-gen-chromium-fuchsia",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:9256",
"user_agent:recipe"
]
}
[20:00:49.531] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1568404844509689",
"id": "8902402037722718480",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-fuchsia\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:9256\", \"user_agent:recipe\"]}, \"id\": \"d8ac8696-0e85-4211-94b4-ade7ab2fe04f\", \"title\": \"codesearch-gen-chromium-initiator/9256\"}]}, \"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568404848616487",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-fuchsia/2009",
"builder:codesearch-gen-chromium-fuchsia",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:9256",
"scheduler_invocation_id:9067832100361611776",
"scheduler_job_id:infra/codesearch-gen-chromium-fuchsia",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8902402037722718480/+/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": "1568404848616627",
"url": "https://ci.chromium.org/b/8902402037722718480",
"utcnow_ts": "1568404849431103"
}
}
[20:00:49.531] Task URL: https://ci.chromium.org/b/8902402037722718480
[20:00:49.531] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:2:0) after 1m0s
[20:01:48.916] Received PubSub notification, asking Buildbucket for the build status
[20:01:49.219] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.762] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:2:0)
[20:01:49.762] Timer tick, asking Buildbucket for the build status
[20:01:50.007] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:50.007] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:4:0) after 1m0s
[20:02:50.184] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:4:0)
[20:02:50.184] Timer tick, asking Buildbucket for the build status
[20:02:53.036] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:53.036] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:5:0) after 1m0s
[20:03:53.147] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:5:0)
[20:03:53.147] Timer tick, asking Buildbucket for the build status
[20:03:53.360] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:53.360] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:6:0) after 1m0s
[20:04:53.359] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:6:0)
[20:04:53.359] Timer tick, asking Buildbucket for the build status
[20:04:54.775] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:54.775] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:7:0) after 1m0s
[20:05:54.860] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:7:0)
[20:05:54.860] Timer tick, asking Buildbucket for the build status
[20:05:57.423] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:57.423] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:8:0) after 1m0s
[20:06:57.503] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:8:0)
[20:06:57.503] Timer tick, asking Buildbucket for the build status
[20:06:57.802] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:57.802] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:9:0) after 1m0s
[20:07:57.957] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:9:0)
[20:07:57.957] Timer tick, asking Buildbucket for the build status
[20:07:58.361] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:58.361] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:10:0) after 1m0s
[20:08:58.534] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:10:0)
[20:08:58.534] Timer tick, asking Buildbucket for the build status
[20:09:01.920] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:01.920] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:11:0) after 1m0s
[20:10:01.940] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:11:0)
[20:10:01.940] Timer tick, asking Buildbucket for the build status
[20:10:02.228] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:02.229] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:12:0) after 1m0s
[20:11:02.261] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:12:0)
[20:11:02.261] Timer tick, asking Buildbucket for the build status
[20:11:02.489] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:02.489] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:13:0) after 1m0s
[20:12:02.523] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:13:0)
[20:12:02.523] Timer tick, asking Buildbucket for the build status
[20:12:02.935] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:02.935] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:14:0) after 1m0s
[20:13:02.991] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:14:0)
[20:13:02.991] Timer tick, asking Buildbucket for the build status
[20:13:03.183] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:03.183] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:15:0) after 1m0s
[20:14:03.360] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:15:0)
[20:14:03.360] Timer tick, asking Buildbucket for the build status
[20:14:03.639] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:03.639] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:16:0) after 1m0s
[20:15:03.752] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:16:0)
[20:15:03.752] Timer tick, asking Buildbucket for the build status
[20:15:04.018] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:04.018] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:17:0) after 1m0s
[20:16:04.085] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:17:0)
[20:16:04.085] Timer tick, asking Buildbucket for the build status
[20:16:04.399] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:04.399] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:18:0) after 1m0s
[20:17:04.485] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:18:0)
[20:17:04.485] Timer tick, asking Buildbucket for the build status
[20:17:04.917] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:04.917] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:19:0) after 1m0s
[20:18:05.088] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:19:0)
[20:18:05.088] Timer tick, asking Buildbucket for the build status
[20:18:05.240] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:05.240] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:20:0) after 1m0s
[20:19:05.343] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:20:0)
[20:19:05.343] Timer tick, asking Buildbucket for the build status
[20:19:06.051] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:06.051] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:21:0) after 1m0s
[20:20:06.089] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:21:0)
[20:20:06.089] Timer tick, asking Buildbucket for the build status
[20:20:06.340] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:06.340] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:22:0) after 1m0s
[20:21:06.363] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:22:0)
[20:21:06.363] Timer tick, asking Buildbucket for the build status
[20:21:06.611] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:06.611] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:23:0) after 1m0s
[20:22:06.712] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:23:0)
[20:22:06.712] Timer tick, asking Buildbucket for the build status
[20:22:07.161] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:07.161] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:24:0) after 1m0s
[20:23:07.214] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:24:0)
[20:23:07.214] Timer tick, asking Buildbucket for the build status
[20:23:07.405] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:07.405] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:25:0) after 1m0s
[20:24:07.459] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:25:0)
[20:24:07.459] Timer tick, asking Buildbucket for the build status
[20:24:07.727] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:07.728] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:26:0) after 1m0s
[20:25:07.853] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:26:0)
[20:25:07.853] Timer tick, asking Buildbucket for the build status
[20:25:08.166] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:08.166] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:27:0) after 1m0s
[20:26:08.232] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:27:0)
[20:26:08.232] Timer tick, asking Buildbucket for the build status
[20:26:08.469] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:08.469] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:28:0) after 1m0s
[20:27:08.516] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:28:0)
[20:27:08.516] Timer tick, asking Buildbucket for the build status
[20:27:10.074] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:10.074] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:29:0) after 1m0s
[20:28:10.133] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:29:0)
[20:28:10.133] Timer tick, asking Buildbucket for the build status
[20:28:10.575] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:10.575] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:30:0) after 1m0s
[20:29:10.613] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:30:0)
[20:29:10.614] Timer tick, asking Buildbucket for the build status
[20:29:10.992] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:10.992] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:31:0) after 1m0s
[20:30:10.998] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:31:0)
[20:30:10.998] Timer tick, asking Buildbucket for the build status
[20:30:11.457] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:11.458] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:32:0) after 1m0s
[20:31:11.626] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:32:0)
[20:31:11.626] Timer tick, asking Buildbucket for the build status
[20:31:11.831] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:11.831] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:33:0) after 1m0s
[20:32:11.834] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:33:0)
[20:32:11.834] Timer tick, asking Buildbucket for the build status
[20:32:11.989] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:11.989] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:34:0) after 1m0s
[20:33:12.069] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:34:0)
[20:33:12.069] Timer tick, asking Buildbucket for the build status
[20:33:12.305] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:12.305] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:35:0) after 1m0s
[20:34:12.331] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:35:0)
[20:34:12.331] Timer tick, asking Buildbucket for the build status
[20:34:14.908] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:14.908] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:36:0) after 1m0s
[20:35:14.933] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:36:0)
[20:35:14.933] Timer tick, asking Buildbucket for the build status
[20:35:15.114] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:15.114] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:37:0) after 1m0s
[20:36:15.168] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:37:0)
[20:36:15.168] Timer tick, asking Buildbucket for the build status
[20:36:15.477] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:15.477] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:38:0) after 1m0s
[20:37:15.564] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:38:0)
[20:37:15.564] Timer tick, asking Buildbucket for the build status
[20:37:16.554] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:16.554] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:39:0) after 1m0s
[20:38:16.596] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:39:0)
[20:38:16.596] Timer tick, asking Buildbucket for the build status
[20:38:16.999] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:16.999] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:40:0) after 1m0s
[20:39:17.112] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:40:0)
[20:39:17.112] Timer tick, asking Buildbucket for the build status
[20:39:17.337] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:17.337] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:41:0) after 1m0s
[20:40:17.523] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:41:0)
[20:40:17.523] Timer tick, asking Buildbucket for the build status
[20:40:17.976] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:17.976] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:42:0) after 1m0s
[20:41:18.004] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:42:0)
[20:41:18.004] Timer tick, asking Buildbucket for the build status
[20:41:18.174] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:18.174] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:43:0) after 1m0s
[20:42:18.194] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:43:0)
[20:42:18.194] Timer tick, asking Buildbucket for the build status
[20:42:18.308] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:18.308] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:44:0) after 1m0s
[20:43:18.344] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:44:0)
[20:43:18.344] Timer tick, asking Buildbucket for the build status
[20:43:18.551] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:18.551] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:45:0) after 1m0s
[20:44:18.574] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:45:0)
[20:44:18.574] Timer tick, asking Buildbucket for the build status
[20:44:19.677] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:19.677] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:46:0) after 1m0s
[20:45:19.654] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:46:0)
[20:45:19.654] Timer tick, asking Buildbucket for the build status
[20:45:19.932] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:19.932] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:47:0) after 1m0s
[20:46:19.959] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:47:0)
[20:46:19.959] Timer tick, asking Buildbucket for the build status
[20:46:20.220] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:20.220] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:48:0) after 1m0s
[20:47:20.246] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:48:0)
[20:47:20.246] Timer tick, asking Buildbucket for the build status
[20:47:20.352] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:20.352] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:49:0) after 1m0s
[20:48:20.424] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:49:0)
[20:48:20.424] Timer tick, asking Buildbucket for the build status
[20:48:20.785] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:20.785] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:50:0) after 1m0s
[20:49:21.009] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:50:0)
[20:49:21.009] Timer tick, asking Buildbucket for the build status
[20:49:21.200] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:21.200] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:51:0) after 1m0s
[20:50:21.230] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:51:0)
[20:50:21.230] Timer tick, asking Buildbucket for the build status
[20:50:21.639] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:21.640] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:52:0) after 1m0s
[20:51:21.738] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:52:0)
[20:51:21.738] Timer tick, asking Buildbucket for the build status
[20:51:22.224] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:22.224] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:53:0) after 1m0s
[20:52:22.246] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:53:0)
[20:52:22.246] Timer tick, asking Buildbucket for the build status
[20:52:22.818] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:22.818] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:54:0) after 1m0s
[20:53:22.949] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:54:0)
[20:53:22.949] Timer tick, asking Buildbucket for the build status
[20:53:23.161] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:23.161] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:55:0) after 1m0s
[20:54:23.271] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:55:0)
[20:54:23.271] Timer tick, asking Buildbucket for the build status
[20:54:23.415] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:23.415] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:56:0) after 1m0s
[20:55:23.403] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:56:0)
[20:55:23.403] Timer tick, asking Buildbucket for the build status
[20:55:24.730] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:24.730] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:57:0) after 1m0s
[20:56:24.839] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:57:0)
[20:56:24.839] Timer tick, asking Buildbucket for the build status
[20:56:25.468] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:25.468] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:58:0) after 1m0s
[20:57:25.494] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:58:0)
[20:57:25.494] Timer tick, asking Buildbucket for the build status
[20:57:25.707] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:25.707] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:59:0) after 1m0s
[20:58:25.774] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:59:0)
[20:58:25.774] Timer tick, asking Buildbucket for the build status
[20:58:25.887] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:25.887] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:60:0) after 1m0s
[20:59:25.919] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:60:0)
[20:59:25.919] Timer tick, asking Buildbucket for the build status
[20:59:26.304] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:26.304] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:61:0) after 1m0s
[21:00:26.372] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:61:0)
[21:00:26.372] Timer tick, asking Buildbucket for the build status
[21:00:27.292] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:27.293] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:62:0) after 1m0s
[21:01:27.311] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:62:0)
[21:01:27.311] Timer tick, asking Buildbucket for the build status
[21:01:27.526] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:27.526] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:63:0) after 1m0s
[21:02:27.573] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:63:0)
[21:02:27.573] Timer tick, asking Buildbucket for the build status
[21:02:27.788] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:27.788] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:64:0) after 1m0s
[21:03:27.874] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:64:0)
[21:03:27.874] Timer tick, asking Buildbucket for the build status
[21:03:28.243] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:28.243] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:65:0) after 1m0s
[21:04:28.488] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:65:0)
[21:04:28.488] Timer tick, asking Buildbucket for the build status
[21:04:28.933] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:28.933] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:66:0) after 1m0s
[21:05:29.257] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:66:0)
[21:05:29.257] Timer tick, asking Buildbucket for the build status
[21:05:29.649] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:29.649] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:67:0) after 1m0s
[21:06:29.991] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:67:0)
[21:06:29.991] Timer tick, asking Buildbucket for the build status
[21:06:30.454] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:30.454] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:68:0) after 1m0s
[21:07:30.611] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:68:0)
[21:07:30.611] Timer tick, asking Buildbucket for the build status
[21:07:32.549] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:32.549] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:69:0) after 1m0s
[21:08:32.594] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:69:0)
[21:08:32.594] Timer tick, asking Buildbucket for the build status
[21:08:32.780] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:32.780] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:70:0) after 1m0s
[21:09:32.973] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:70:0)
[21:09:32.973] Timer tick, asking Buildbucket for the build status
[21:09:33.450] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:33.450] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:71:0) after 1m0s
[21:10:36.323] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:71:0)
[21:10:36.323] Timer tick, asking Buildbucket for the build status
[21:10:37.718] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:37.718] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:72:0) after 1m0s
[21:11:37.787] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:72:0)
[21:11:37.787] Timer tick, asking Buildbucket for the build status
[21:11:39.025] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:39.025] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:73:0) after 1m0s
[21:12:39.064] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:73:0)
[21:12:39.064] Timer tick, asking Buildbucket for the build status
[21:12:39.180] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:39.180] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:74:0) after 1m0s
[21:13:39.159] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:74:0)
[21:13:39.159] Timer tick, asking Buildbucket for the build status
[21:13:39.585] Build 8902402037722718480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:39.585] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-fuchsia:9067832100361611776:75:0) after 1m0s
[21:14:13.554] Received PubSub notification, asking Buildbucket for the build status
[21:14:13.646] Build 8902402037722718480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:14:13.646] Buildbucket build:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"completed_ts": "1568409251818268",
"created_by": "project:infra",
"created_ts": "1568404844509689",
"id": "8902402037722718480",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-fuchsia\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:9256\", \"user_agent:recipe\"]}, \"id\": \"d8ac8696-0e85-4211-94b4-ade7ab2fe04f\", \"title\": \"codesearch-gen-chromium-initiator/9256\"}]}, \"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:9256\", \"user_agent:recipe\"]}, \"id\": \"d8ac8696-0e85-4211-94b4-ade7ab2fe04f\", \"title\": \"codesearch-gen-chromium-initiator/9256\"}]}, \"bot_id\": \"luci-infra-codesearch-xenial-6-sar8\", \"buildername\": \"codesearch-gen-chromium-fuchsia\", \"buildnumber\": 2009, \"got_angle_revision\": \"c4b1fbd69b12c0af30a58d0810f88d1301b8dabd\", \"got_clang_revision\": \"371202-8455294f-2\", \"got_dawn_revision\": \"785ec66b66a3577e4f17306a10ab30b7f92c49ae\", \"got_nacl_revision\": \"a9e27d5c7f1b6edf7371edab79e104578d62c4f5\", \"got_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"got_revision_cp\": \"refs/heads/master@{#696490}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"34658ca36da5fa73dfbb288068d4f095c1830129\", \"got_v8_revision_cp\": \"refs/heads/7.9.36@{#1}\", \"got_webrtc_revision\": \"6a6eb61baa10e61282a010405a0af6c71c7283c3\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29185}\", \"mastername\": \"chromium.infra.codesearch\", \"path_config\": \"generic\", \"recipe\": \"chromium_codesearch\", \"root_solution_revision\": \"9004bcf36a8d84b1dc31fa6e15314ab81e2efb91\", \"root_solution_revision_timestamp\": 1568404444.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_b5ea7bb4091186e872937c4042f52c0c1d84281f1bc29c547e93e8efc0fd27bb_v2\", \"codesearch_update_submodules_mirror\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-codesearch-xenial-6-sar8\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.codesearch\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4436-e523514\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-a\"]}}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568404904785719",
"status": "COMPLETED",
"status_changed_ts": "1568409252412395",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-fuchsia/2009",
"builder:codesearch-gen-chromium-fuchsia",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:9256",
"scheduler_invocation_id:9067832100361611776",
"scheduler_job_id:infra/codesearch-gen-chromium-fuchsia",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8902402037722718480/+/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:4745086a378cd710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1568409252475192",
"url": "https://ci.chromium.org/b/8902402037722718480",
"utcnow_ts": "1568409253637295"
}
}
[21:14:13.646] Invocation finished in 1h13m31.038584093s with status SUCCEEDED