[08:23:04.457] New invocation is queued and will start shortly
[08:23:04.457] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[08:23:05.856] Starting the invocation (attempt 1)
[08:23:05.898] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:23:05.898] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:23:05.898] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9023574075261369808",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\",\"codesearch_mirror_revision_timestamp\":1610610589,\"root_solution_revision\":\"7452904e61bd63245b296f1e9160d6a987874b08\",\"root_solution_revision_timestamp\":1610610469},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:12188\",\"user_agent:recipe\"]},\"id\":\"b9574810-ba38-47c3-a562-a0f20b906cd9\",\"title\":\"codesearch-gen-chromium-initiator/12188\"}]},\"codesearch_mirror_revision\":\"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\",\"codesearch_mirror_revision_timestamp\":1610610589,\"root_solution_revision\":\"7452904e61bd63245b296f1e9160d6a987874b08\",\"root_solution_revision_timestamp\":1610610469}}",
"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:9023574075261369808",
"scheduler_job_id:infra/codesearch-gen-chromium-win",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12188",
"user_agent:recipe"
]
}
[08:23:06.648] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1610612585998603",
"id": "8858144012983105744",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\", \"codesearch_mirror_revision_timestamp\": 1610610589.0, \"root_solution_revision\": \"7452904e61bd63245b296f1e9160d6a987874b08\", \"root_solution_revision_timestamp\": 1610610469.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12188\", \"user_agent:recipe\"]}, \"id\": \"b9574810-ba38-47c3-a562-a0f20b906cd9\", \"title\": \"codesearch-gen-chromium-initiator/12188\"}]}, \"codesearch_mirror_revision\": \"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\", \"codesearch_mirror_revision_timestamp\": 1610610589.0, \"root_solution_revision\": \"7452904e61bd63245b296f1e9160d6a987874b08\", \"root_solution_revision_timestamp\": 1610610469.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610612586396897",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-win/6908",
"builder:codesearch-gen-chromium-win",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12188",
"scheduler_invocation_id:9023574075261369808",
"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/8858144012983105744/+/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": "1610612586397019",
"url": "https://ci.chromium.org/b/8858144012983105744",
"utcnow_ts": "1610612586625254"
}
}
[08:23:06.648] Task URL: https://ci.chromium.org/b/8858144012983105744
[08:23:06.648] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:2:0) after 1m0s
[08:24:06.791] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:2:0)
[08:24:06.791] Timer tick, asking Buildbucket for the build status
[08:24:06.884] Build 8858144012983105744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:06.884] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:3:0) after 1m0s
[08:24:27.706] Received PubSub notification, asking Buildbucket for the build status
[08:24:28.649] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:06.930] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:3:0)
[08:25:06.930] Timer tick, asking Buildbucket for the build status
[08:25:07.009] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:07.009] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:5:0) after 1m0s
[08:26:07.033] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:5:0)
[08:26:07.033] Timer tick, asking Buildbucket for the build status
[08:26:07.172] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:07.172] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:6:0) after 1m0s
[08:27:07.269] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:6:0)
[08:27:07.269] Timer tick, asking Buildbucket for the build status
[08:27:08.598] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:08.598] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:7:0) after 1m0s
[08:28:08.683] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:7:0)
[08:28:08.683] Timer tick, asking Buildbucket for the build status
[08:28:08.806] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:08.806] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:8:0) after 1m0s
[08:29:08.822] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:8:0)
[08:29:08.822] Timer tick, asking Buildbucket for the build status
[08:29:08.924] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:08.924] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:9:0) after 1m0s
[08:30:08.931] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:9:0)
[08:30:08.931] Timer tick, asking Buildbucket for the build status
[08:30:09.352] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:09.352] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:10:0) after 1m0s
[08:31:09.399] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:10:0)
[08:31:09.399] Timer tick, asking Buildbucket for the build status
[08:31:09.531] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:09.531] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:11:0) after 1m0s
[08:32:09.580] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:11:0)
[08:32:09.580] Timer tick, asking Buildbucket for the build status
[08:32:09.659] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:09.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:12:0) after 1m0s
[08:33:09.711] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:12:0)
[08:33:09.711] Timer tick, asking Buildbucket for the build status
[08:33:09.816] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:09.816] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:13:0) after 1m0s
[08:34:09.906] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:13:0)
[08:34:09.906] Timer tick, asking Buildbucket for the build status
[08:34:10.232] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:10.232] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:14:0) after 1m0s
[08:35:10.210] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:14:0)
[08:35:10.210] Timer tick, asking Buildbucket for the build status
[08:35:10.279] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:10.279] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:15:0) after 1m0s
[08:36:10.345] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:15:0)
[08:36:10.345] Timer tick, asking Buildbucket for the build status
[08:36:10.574] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:10.574] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:16:0) after 1m0s
[08:37:10.640] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:16:0)
[08:37:10.640] Timer tick, asking Buildbucket for the build status
[08:37:10.834] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:10.834] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:17:0) after 1m0s
[08:38:10.829] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:17:0)
[08:38:10.829] Timer tick, asking Buildbucket for the build status
[08:38:11.033] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:11.033] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:18:0) after 1m0s
[08:39:11.062] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:18:0)
[08:39:11.062] Timer tick, asking Buildbucket for the build status
[08:39:11.231] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:11.231] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:19:0) after 1m0s
[08:40:11.300] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:19:0)
[08:40:11.300] Timer tick, asking Buildbucket for the build status
[08:40:11.374] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:11.374] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:20:0) after 1m0s
[08:41:11.392] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:20:0)
[08:41:11.392] Timer tick, asking Buildbucket for the build status
[08:41:11.452] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:11.452] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:21:0) after 1m0s
[08:42:11.672] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:21:0)
[08:42:11.672] Timer tick, asking Buildbucket for the build status
[08:42:11.822] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:11.822] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:22:0) after 1m0s
[08:43:11.873] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:22:0)
[08:43:11.873] Timer tick, asking Buildbucket for the build status
[08:43:12.234] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:12.234] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:23:0) after 1m0s
[08:44:12.319] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:23:0)
[08:44:12.319] Timer tick, asking Buildbucket for the build status
[08:44:12.390] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:12.390] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:24:0) after 1m0s
[08:45:12.446] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:24:0)
[08:45:12.446] Timer tick, asking Buildbucket for the build status
[08:45:12.728] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:12.728] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:25:0) after 1m0s
[08:46:12.789] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:25:0)
[08:46:12.789] Timer tick, asking Buildbucket for the build status
[08:46:12.895] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:12.895] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:26:0) after 1m0s
[08:47:12.913] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:26:0)
[08:47:12.913] Timer tick, asking Buildbucket for the build status
[08:47:12.973] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:12.973] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:27:0) after 1m0s
[08:48:13.004] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:27:0)
[08:48:13.004] Timer tick, asking Buildbucket for the build status
[08:48:13.100] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:13.100] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:28:0) after 1m0s
[08:49:13.128] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:28:0)
[08:49:13.129] Timer tick, asking Buildbucket for the build status
[08:49:13.345] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:13.345] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:29:0) after 1m0s
[08:50:13.365] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:29:0)
[08:50:13.365] Timer tick, asking Buildbucket for the build status
[08:50:13.843] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:13.843] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:30:0) after 1m0s
[08:51:13.948] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:30:0)
[08:51:13.948] Timer tick, asking Buildbucket for the build status
[08:51:14.057] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.057] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:31:0) after 1m0s
[08:52:14.199] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:31:0)
[08:52:14.199] Timer tick, asking Buildbucket for the build status
[08:52:14.271] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:14.271] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:32:0) after 1m0s
[08:53:14.335] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:32:0)
[08:53:14.335] Timer tick, asking Buildbucket for the build status
[08:53:14.455] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:14.455] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:33:0) after 1m0s
[08:54:14.589] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:33:0)
[08:54:14.589] Timer tick, asking Buildbucket for the build status
[08:54:14.725] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:14.725] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:34:0) after 1m0s
[08:55:14.780] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:34:0)
[08:55:14.780] Timer tick, asking Buildbucket for the build status
[08:55:14.850] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:14.850] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:35:0) after 1m0s
[08:56:14.996] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:35:0)
[08:56:14.996] Timer tick, asking Buildbucket for the build status
[08:56:15.080] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.080] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:36:0) after 1m0s
[08:57:15.107] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:36:0)
[08:57:15.107] Timer tick, asking Buildbucket for the build status
[08:57:15.178] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:15.178] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:37:0) after 1m0s
[08:58:15.387] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:37:0)
[08:58:15.387] Timer tick, asking Buildbucket for the build status
[08:58:15.512] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:15.512] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:38:0) after 1m0s
[08:59:15.632] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:38:0)
[08:59:15.632] Timer tick, asking Buildbucket for the build status
[08:59:15.904] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:15.904] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:39:0) after 1m0s
[09:00:16.092] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:39:0)
[09:00:16.092] Timer tick, asking Buildbucket for the build status
[09:00:16.209] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.209] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:40:0) after 1m0s
[09:01:17.366] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:40:0)
[09:01:17.366] Timer tick, asking Buildbucket for the build status
[09:01:17.466] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:17.466] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:41:0) after 1m0s
[09:02:17.589] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:41:0)
[09:02:17.589] Timer tick, asking Buildbucket for the build status
[09:02:17.706] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:17.706] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:42:0) after 1m0s
[09:03:17.723] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:42:0)
[09:03:17.723] Timer tick, asking Buildbucket for the build status
[09:03:18.142] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.142] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:43:0) after 1m0s
[09:04:18.247] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:43:0)
[09:04:18.247] Timer tick, asking Buildbucket for the build status
[09:04:18.425] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:18.425] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:44:0) after 1m0s
[09:05:18.475] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:44:0)
[09:05:18.475] Timer tick, asking Buildbucket for the build status
[09:05:19.014] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:19.014] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:45:0) after 1m0s
[09:06:19.051] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:45:0)
[09:06:19.051] Timer tick, asking Buildbucket for the build status
[09:06:19.159] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:19.159] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:46:0) after 1m0s
[09:07:19.202] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:46:0)
[09:07:19.202] Timer tick, asking Buildbucket for the build status
[09:07:19.683] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.683] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:47:0) after 1m0s
[09:08:19.836] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:47:0)
[09:08:19.837] Timer tick, asking Buildbucket for the build status
[09:08:20.004] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:20.004] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:48:0) after 1m0s
[09:09:20.158] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:48:0)
[09:09:20.158] Timer tick, asking Buildbucket for the build status
[09:09:20.230] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:20.230] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:49:0) after 1m0s
[09:10:20.438] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:49:0)
[09:10:20.438] Timer tick, asking Buildbucket for the build status
[09:10:20.523] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:20.523] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:50:0) after 1m0s
[09:11:20.602] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:50:0)
[09:11:20.602] Timer tick, asking Buildbucket for the build status
[09:11:20.679] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:20.679] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:51:0) after 1m0s
[09:12:20.746] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:51:0)
[09:12:20.746] Timer tick, asking Buildbucket for the build status
[09:12:20.879] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:20.879] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:52:0) after 1m0s
[09:13:20.926] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:52:0)
[09:13:20.926] Timer tick, asking Buildbucket for the build status
[09:13:21.035] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:21.035] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:53:0) after 1m0s
[09:14:21.159] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:53:0)
[09:14:21.159] Timer tick, asking Buildbucket for the build status
[09:14:21.227] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:21.227] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:54:0) after 1m0s
[09:15:21.298] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:54:0)
[09:15:21.298] Timer tick, asking Buildbucket for the build status
[09:15:21.408] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:21.408] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:55:0) after 1m0s
[09:16:21.387] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:55:0)
[09:16:21.387] Timer tick, asking Buildbucket for the build status
[09:16:21.526] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:21.526] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:56:0) after 1m0s
[09:17:21.565] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:56:0)
[09:17:21.565] Timer tick, asking Buildbucket for the build status
[09:17:21.794] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:21.795] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:57:0) after 1m0s
[09:18:21.824] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:57:0)
[09:18:21.824] Timer tick, asking Buildbucket for the build status
[09:18:21.933] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.933] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:58:0) after 1m0s
[09:19:22.002] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:58:0)
[09:19:22.002] Timer tick, asking Buildbucket for the build status
[09:19:22.083] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:22.083] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:59:0) after 1m0s
[09:20:22.109] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:59:0)
[09:20:22.109] Timer tick, asking Buildbucket for the build status
[09:20:22.168] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:22.168] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:60:0) after 1m0s
[09:21:22.273] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:60:0)
[09:21:22.273] Timer tick, asking Buildbucket for the build status
[09:21:22.484] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:22.484] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:61:0) after 1m0s
[09:22:22.786] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:61:0)
[09:22:22.786] Timer tick, asking Buildbucket for the build status
[09:22:22.866] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.866] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:62:0) after 1m0s
[09:23:23.015] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:62:0)
[09:23:23.015] Timer tick, asking Buildbucket for the build status
[09:23:23.163] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:23.163] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:63:0) after 1m0s
[09:24:23.300] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:63:0)
[09:24:23.300] Timer tick, asking Buildbucket for the build status
[09:24:23.367] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:23.367] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:64:0) after 1m0s
[09:25:23.346] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:64:0)
[09:25:23.346] Timer tick, asking Buildbucket for the build status
[09:25:23.502] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:23.502] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:65:0) after 1m0s
[09:26:23.596] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:65:0)
[09:26:23.596] Timer tick, asking Buildbucket for the build status
[09:26:23.656] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:23.656] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:66:0) after 1m0s
[09:27:23.723] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:66:0)
[09:27:23.723] Timer tick, asking Buildbucket for the build status
[09:27:24.175] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:24.175] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:67:0) after 1m0s
[09:28:24.248] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:67:0)
[09:28:24.248] Timer tick, asking Buildbucket for the build status
[09:28:24.354] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:24.354] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:68:0) after 1m0s
[09:29:24.420] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:68:0)
[09:29:24.420] Timer tick, asking Buildbucket for the build status
[09:29:24.622] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:24.622] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:69:0) after 1m0s
[09:30:24.794] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:69:0)
[09:30:24.794] Timer tick, asking Buildbucket for the build status
[09:30:25.045] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:25.045] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:70:0) after 1m0s
[09:31:25.126] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:70:0)
[09:31:25.126] Timer tick, asking Buildbucket for the build status
[09:31:25.462] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:25.462] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:71:0) after 1m0s
[09:32:25.617] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:71:0)
[09:32:25.617] Timer tick, asking Buildbucket for the build status
[09:32:25.683] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:25.683] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:72:0) after 1m0s
[09:33:25.706] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:72:0)
[09:33:25.706] Timer tick, asking Buildbucket for the build status
[09:33:25.807] Build 8858144012983105744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:25.807] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9023574075261369808:73:0) after 1m0s
[09:34:05.630] Received PubSub notification, asking Buildbucket for the build status
[09:34:05.696] Build 8858144012983105744: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:34:05.696] Buildbucket build:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"completed_ts": "1610616845357952",
"created_by": "project:infra",
"created_ts": "1610612585998603",
"failure_reason": "BUILD_FAILURE",
"id": "8858144012983105744",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\", \"codesearch_mirror_revision_timestamp\": 1610610589.0, \"root_solution_revision\": \"7452904e61bd63245b296f1e9160d6a987874b08\", \"root_solution_revision_timestamp\": 1610610469.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12188\", \"user_agent:recipe\"]}, \"id\": \"b9574810-ba38-47c3-a562-a0f20b906cd9\", \"title\": \"codesearch-gen-chromium-initiator/12188\"}]}, \"codesearch_mirror_revision\": \"4f2937b071c5f42768c14ee60b463d3c8b7ec0c1\", \"codesearch_mirror_revision_timestamp\": 1610610589.0, \"root_solution_revision\": \"7452904e61bd63245b296f1e9160d6a987874b08\", \"root_solution_revision_timestamp\": 1610610469.0}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[36308/36341] LINK interactive_ui_tests.exe interactive_ui_tests.exe.pdb\\nFAILED: interactive_ui_tests.exe interactive_ui_tests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"198dc345383e1f17252945bdb4a6dd47f4345ad4\", \"got_dawn_revision\": \"6f833b7f78c92595d3ec74ee622dab1868fdf40b\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"7452904e61bd63245b296f1e9160d6a987874b08\", \"got_revision_cp\": \"refs/heads/master@{#843427}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"712b239be61d29c242052c85f927da5291648e41\", \"got_v8_revision_cp\": \"refs/heads/8.9.272@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_51272234846e6a2cd04aa26557e39158e034c42367eea2a83e543ed93811995b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-codesearch-win-0-d92o\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.infra.codesearch\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5661-c6f6250\"], \"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```\\n[36308/36341] LINK interactive_ui_tests.exe interactive_ui_tests.exe.pdb\\nFAILED: interactive_ui_tests.exe interactive_ui_tests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610612666493326",
"status": "COMPLETED",
"status_changed_ts": "1610612666941442",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-win/6908",
"builder:codesearch-gen-chromium-win",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12188",
"scheduler_invocation_id:9023574075261369808",
"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/8858144012983105744/+/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:5118cf432fa57a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1610616845437767",
"url": "https://ci.chromium.org/b/8858144012983105744",
"utcnow_ts": "1610616845684322"
}
}
[09:34:05.696] Invocation finished in 1h11m1.255619081s with status FAILED