[08:03:41.092] New invocation is queued and will start shortly
[08:03:41.092] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[08:03:42.347] Starting the invocation (attempt 1)
[08:03:42.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:03:42.427] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:03:42.427] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9047674088200462544",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\",\"codesearch_mirror_revision_timestamp\":1587628158,\"root_solution_revision\":\"f44571f45530035be9e3725ced5cdc88c9a10c78\",\"root_solution_revision_timestamp\":1587628082},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:10591\",\"user_agent:recipe\"]},\"id\":\"7b691ea6-e588-48e2-852c-a015dfa2f6ca\",\"title\":\"codesearch-gen-chromium-initiator/10591\"}]},\"codesearch_mirror_revision\":\"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\",\"codesearch_mirror_revision_timestamp\":1587628158,\"root_solution_revision\":\"f44571f45530035be9e3725ced5cdc88c9a10c78\",\"root_solution_revision_timestamp\":1587628082}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-win",
"scheduler_invocation_id:9047674088200462544",
"scheduler_job_id:infra/codesearch-gen-chromium-win",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:10591",
"user_agent:recipe"
]
}
[08:03:43.712] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1587629022635305",
"id": "8882244025920120048",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:10591\", \"user_agent:recipe\"]}, \"id\": \"7b691ea6-e588-48e2-852c-a015dfa2f6ca\", \"title\": \"codesearch-gen-chromium-initiator/10591\"}]}, \"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587629023485868",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-win/5695",
"builder:codesearch-gen-chromium-win",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:10591",
"scheduler_invocation_id:9047674088200462544",
"scheduler_job_id:infra/codesearch-gen-chromium-win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8882244025920120048/+/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": "1587629023486030",
"url": "https://ci.chromium.org/b/8882244025920120048",
"utcnow_ts": "1587629023695866"
}
}
[08:03:43.712] Task URL: https://ci.chromium.org/b/8882244025920120048
[08:03:43.712] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:2:0) after 1m0s
[08:04:40.688] Received PubSub notification, asking Buildbucket for the build status
[08:04:40.806] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:44.024] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:2:0)
[08:04:44.024] Timer tick, asking Buildbucket for the build status
[08:04:44.162] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:44.162] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:4:0) after 1m0s
[08:05:44.374] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:4:0)
[08:05:44.374] Timer tick, asking Buildbucket for the build status
[08:05:44.529] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:44.529] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:5:0) after 1m0s
[08:06:45.017] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:5:0)
[08:06:45.017] Timer tick, asking Buildbucket for the build status
[08:06:45.238] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:45.238] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:6:0) after 1m0s
[08:07:45.316] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:6:0)
[08:07:45.316] Timer tick, asking Buildbucket for the build status
[08:07:45.484] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:45.484] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:7:0) after 1m0s
[08:08:45.789] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:7:0)
[08:08:45.789] Timer tick, asking Buildbucket for the build status
[08:08:45.917] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:45.917] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:8:0) after 1m0s
[08:09:46.312] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:8:0)
[08:09:46.312] Timer tick, asking Buildbucket for the build status
[08:09:46.452] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:46.452] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:9:0) after 1m0s
[08:10:46.624] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:9:0)
[08:10:46.624] Timer tick, asking Buildbucket for the build status
[08:10:46.879] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:46.879] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:10:0) after 1m0s
[08:11:47.006] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:10:0)
[08:11:47.006] Timer tick, asking Buildbucket for the build status
[08:11:47.378] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:47.378] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:11:0) after 1m0s
[08:12:47.525] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:11:0)
[08:12:47.525] Timer tick, asking Buildbucket for the build status
[08:12:47.682] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:47.682] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:12:0) after 1m0s
[08:13:47.768] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:12:0)
[08:13:47.768] Timer tick, asking Buildbucket for the build status
[08:13:47.923] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:47.923] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:13:0) after 1m0s
[08:14:47.949] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:13:0)
[08:14:47.949] Timer tick, asking Buildbucket for the build status
[08:14:48.800] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:48.800] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:14:0) after 1m0s
[08:15:48.865] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:14:0)
[08:15:48.865] Timer tick, asking Buildbucket for the build status
[08:15:48.982] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:48.982] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:15:0) after 1m0s
[08:16:49.254] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:15:0)
[08:16:49.254] Timer tick, asking Buildbucket for the build status
[08:16:49.430] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:49.430] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:16:0) after 1m0s
[08:17:49.641] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:16:0)
[08:17:49.641] Timer tick, asking Buildbucket for the build status
[08:17:49.951] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:49.951] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:17:0) after 1m0s
[08:18:50.166] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:17:0)
[08:18:50.166] Timer tick, asking Buildbucket for the build status
[08:18:50.360] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:50.360] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:18:0) after 1m0s
[08:19:50.477] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:18:0)
[08:19:50.477] Timer tick, asking Buildbucket for the build status
[08:19:50.607] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:50.607] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:19:0) after 1m0s
[08:20:50.648] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:19:0)
[08:20:50.648] Timer tick, asking Buildbucket for the build status
[08:20:50.856] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:50.856] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:20:0) after 1m0s
[08:21:50.964] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:20:0)
[08:21:50.964] Timer tick, asking Buildbucket for the build status
[08:21:51.245] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:51.245] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:21:0) after 1m0s
[08:22:51.393] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:21:0)
[08:22:51.393] Timer tick, asking Buildbucket for the build status
[08:22:52.318] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:52.318] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:22:0) after 1m0s
[08:23:52.343] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:22:0)
[08:23:52.343] Timer tick, asking Buildbucket for the build status
[08:23:52.726] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:52.726] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:23:0) after 1m0s
[08:24:52.757] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:23:0)
[08:24:52.757] Timer tick, asking Buildbucket for the build status
[08:24:52.991] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:52.991] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:24:0) after 1m0s
[08:25:53.043] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:24:0)
[08:25:53.043] Timer tick, asking Buildbucket for the build status
[08:25:53.454] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:53.454] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:25:0) after 1m0s
[08:26:53.479] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:25:0)
[08:26:53.479] Timer tick, asking Buildbucket for the build status
[08:26:53.771] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:53.771] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:26:0) after 1m0s
[08:27:53.783] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:26:0)
[08:27:53.783] Timer tick, asking Buildbucket for the build status
[08:27:53.952] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:53.952] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:27:0) after 1m0s
[08:28:54.031] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:27:0)
[08:28:54.031] Timer tick, asking Buildbucket for the build status
[08:28:54.242] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:54.242] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:28:0) after 1m0s
[08:29:54.336] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:28:0)
[08:29:54.336] Timer tick, asking Buildbucket for the build status
[08:29:54.641] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:54.641] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:29:0) after 1m0s
[08:30:54.654] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:29:0)
[08:30:54.654] Timer tick, asking Buildbucket for the build status
[08:30:54.921] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:54.921] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:30:0) after 1m0s
[08:31:54.977] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:30:0)
[08:31:54.977] Timer tick, asking Buildbucket for the build status
[08:31:55.167] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:55.167] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:31:0) after 1m0s
[08:32:55.213] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:31:0)
[08:32:55.213] Timer tick, asking Buildbucket for the build status
[08:32:55.385] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:55.385] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:32:0) after 1m0s
[08:33:55.404] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:32:0)
[08:33:55.404] Timer tick, asking Buildbucket for the build status
[08:33:55.637] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:55.637] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:33:0) after 1m0s
[08:34:55.711] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:33:0)
[08:34:55.711] Timer tick, asking Buildbucket for the build status
[08:34:55.939] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:55.939] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:34:0) after 1m0s
[08:35:56.021] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:34:0)
[08:35:56.021] Timer tick, asking Buildbucket for the build status
[08:35:56.249] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:56.249] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:35:0) after 1m0s
[08:36:56.267] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:35:0)
[08:36:56.267] Timer tick, asking Buildbucket for the build status
[08:36:56.431] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:56.431] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:36:0) after 1m0s
[08:37:56.490] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:36:0)
[08:37:56.490] Timer tick, asking Buildbucket for the build status
[08:37:56.632] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:56.632] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:37:0) after 1m0s
[08:38:56.768] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:37:0)
[08:38:56.768] Timer tick, asking Buildbucket for the build status
[08:38:56.922] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:56.922] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:38:0) after 1m0s
[08:39:57.038] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:38:0)
[08:39:57.038] Timer tick, asking Buildbucket for the build status
[08:39:57.980] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:57.980] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:39:0) after 1m0s
[08:40:58.070] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:39:0)
[08:40:58.070] Timer tick, asking Buildbucket for the build status
[08:40:58.236] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:58.236] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:40:0) after 1m0s
[08:41:58.255] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:40:0)
[08:41:58.255] Timer tick, asking Buildbucket for the build status
[08:41:58.445] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:58.445] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:41:0) after 1m0s
[08:42:58.470] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:41:0)
[08:42:58.470] Timer tick, asking Buildbucket for the build status
[08:43:00.389] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:00.389] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:42:0) after 1m0s
[08:44:02.046] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:42:0)
[08:44:02.046] Timer tick, asking Buildbucket for the build status
[08:44:03.342] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:03.342] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:43:0) after 1m0s
[08:45:03.408] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:43:0)
[08:45:03.408] Timer tick, asking Buildbucket for the build status
[08:45:03.701] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:03.702] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:44:0) after 1m0s
[08:46:03.735] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:44:0)
[08:46:03.736] Timer tick, asking Buildbucket for the build status
[08:46:04.006] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:04.006] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:45:0) after 1m0s
[08:47:03.976] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:45:0)
[08:47:03.976] Timer tick, asking Buildbucket for the build status
[08:47:04.335] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:04.335] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:46:0) after 1m0s
[08:48:04.343] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:46:0)
[08:48:04.343] Timer tick, asking Buildbucket for the build status
[08:48:04.535] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:04.535] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:47:0) after 1m0s
[08:49:04.576] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:47:0)
[08:49:04.576] Timer tick, asking Buildbucket for the build status
[08:49:04.748] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:04.748] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:48:0) after 1m0s
[08:50:04.846] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:48:0)
[08:50:04.846] Timer tick, asking Buildbucket for the build status
[08:50:05.261] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:05.261] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:49:0) after 1m0s
[08:51:05.329] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:49:0)
[08:51:05.329] Timer tick, asking Buildbucket for the build status
[08:51:05.620] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:05.620] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:50:0) after 1m0s
[08:52:05.606] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:50:0)
[08:52:05.606] Timer tick, asking Buildbucket for the build status
[08:52:05.694] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:05.694] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:51:0) after 1m0s
[08:53:05.672] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:51:0)
[08:53:05.673] Timer tick, asking Buildbucket for the build status
[08:53:05.886] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:05.886] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:52:0) after 1m0s
[08:54:05.935] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:52:0)
[08:54:05.935] Timer tick, asking Buildbucket for the build status
[08:54:06.205] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:06.205] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:53:0) after 1m0s
[08:55:06.275] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:53:0)
[08:55:06.275] Timer tick, asking Buildbucket for the build status
[08:55:07.496] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:07.496] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:54:0) after 1m0s
[08:56:07.521] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:54:0)
[08:56:07.521] Timer tick, asking Buildbucket for the build status
[08:56:07.713] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:07.713] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:55:0) after 1m0s
[08:57:07.787] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:55:0)
[08:57:07.787] Timer tick, asking Buildbucket for the build status
[08:57:08.011] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:08.011] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:56:0) after 1m0s
[08:58:08.107] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:56:0)
[08:58:08.107] Timer tick, asking Buildbucket for the build status
[08:58:08.433] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:08.433] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:57:0) after 1m0s
[08:59:08.425] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:57:0)
[08:59:08.425] Timer tick, asking Buildbucket for the build status
[08:59:08.658] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:08.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:58:0) after 1m0s
[09:00:08.694] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:58:0)
[09:00:08.694] Timer tick, asking Buildbucket for the build status
[09:00:08.860] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:08.860] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:59:0) after 1m0s
[09:01:08.883] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:59:0)
[09:01:08.883] Timer tick, asking Buildbucket for the build status
[09:01:09.275] Build 8882244025920120048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:09.275] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9047674088200462544:60:0) after 1m0s
[09:01:29.297] Received PubSub notification, asking Buildbucket for the build status
[09:01:29.437] Build 8882244025920120048: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:01:29.437] Buildbucket build:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"completed_ts": "1587632487964967",
"created_by": "project:infra",
"created_ts": "1587629022635305",
"failure_reason": "BUILD_FAILURE",
"id": "8882244025920120048",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:10591\", \"user_agent:recipe\"]}, \"id\": \"7b691ea6-e588-48e2-852c-a015dfa2f6ca\", \"title\": \"codesearch-gen-chromium-initiator/10591\"}]}, \"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\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\": {\"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:10591\", \"user_agent:recipe\"]}, \"id\": \"7b691ea6-e588-48e2-852c-a015dfa2f6ca\", \"title\": \"codesearch-gen-chromium-initiator/10591\"}]}, \"bot_id\": \"luci-infra-codesearch-win-0-dujk\", \"buildername\": \"codesearch-gen-chromium-win\", \"buildnumber\": 5695, \"codesearch_mirror_revision\": \"f1049d13991ac2c7c680ad8d2bad4fc547dc7185\", \"codesearch_mirror_revision_timestamp\": 1587628158.0, \"got_angle_revision\": \"913f4f4213818da37660702e96365e25218d5f0e\", \"got_clang_revision\": \"n346557-4e0d9925-2\", \"got_dawn_revision\": \"d4302437c9f150d93d3db905bbcead6d94e3dadc\", \"got_nacl_revision\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"got_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"got_revision_cp\": \"refs/heads/master@{#761833}\", \"got_swarming_client_revision\": \"160b445a44e0daacf6f3f8570ca2707ec451f374\", \"got_swiftshader_revision\": \"5ab1f36a38aa987d25271a6a58523ef7866ea1c5\", \"got_v8_revision\": \"21aabe2d87e1350e9395f5af3026d9a185322754\", \"got_v8_revision_cp\": \"refs/heads/8.4.163@{#1}\", \"got_webrtc_revision\": \"f4ee036871f26e350a4f6e242a52fd802c74236d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31121}\", \"mastername\": \"chromium.infra.codesearch\", \"path_config\": \"generic\", \"recipe\": \"chromium_codesearch\", \"root_solution_revision\": \"f44571f45530035be9e3725ced5cdc88c9a10c78\", \"root_solution_revision_timestamp\": 1587628082.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_51272234846e6a2cd04aa26557e39158e034c42367eea2a83e543ed93811995b_v2\", \"codesearch_git_genfiles_repo\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-codesearch-win-0-dujk\"], \"image\": [\"chrome-win10-1703-20040700-021aac15b85\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.infra.codesearch\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5018-c7f912d\"], \"ssd\": [\"1\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-a\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\nFailing build because ninja reported work to do.\\nThis means that after completing a compile, another was run and\\nit resulted in still having work to do (that is, a no-op build\\nwasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\nlikely culprit.\\n```\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1587629079918968",
"status": "COMPLETED",
"status_changed_ts": "1587629080391575",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-win/5695",
"builder:codesearch-gen-chromium-win",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:10591",
"scheduler_invocation_id:9047674088200462544",
"scheduler_job_id:infra/codesearch-gen-chromium-win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8882244025920120048/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4bbee1eabc148010",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1587632488553792",
"url": "https://ci.chromium.org/b/8882244025920120048",
"utcnow_ts": "1587632489418393"
}
}
[09:01:29.437] Invocation finished in 57m48.360850497s with status FAILED