Unable to log in to 4.7 from Windows

[djmsalem]

Problem Description: I recently reinstalled Windows 7 after trying Windows 8, on a 64-bit machine. I unable to get past the login screen, receiving the following exception: org.springframework.jdbc.BadSqlGrammarException

Troubleshooting Steps: I uninstalled Windows 8 and reinstalled Windows 7 in a clean install. I loaded Java 6, in both 32- and 64-bit versions, then Subsonic 4.7. When that didn't work I tried the 32- and 64-bit versions of Java 7, uninstalling and reinstalling Subsonic. Port forwarding of 4040 is enabled and the service starts properly and I have the green icon in the taskbar. Javascript is enabled.

Playback Application and version: I haven't gotten to set up the playback application.

Subsonic Version: Can't get to the version from the help interface, but it is the build of 4.7 available for download December 16, 2012
Server Version: jetty-6.1.x
Hardware Platform: Windows 7, 64-bit, V 6.1
Java Memory Limit: 256 MB
Problem Filename: (If you are having difficulty with a particular file, please include it's name)
Output from ffmpeg -i: Returns the error: "ffmpeg is not recognized as an internal or external command, operable program or batch file."
Last ten log file lines: (The last ten or so lines copied and pasted from the About screen in the Subsonic Web Interface. Ideally these will be in a CODE block as well.) I can't get to the Web interface. But the entire error message I receive is this:

Exception org.springframework.jdbc.BadSqlGrammarException
Message PreparedStatementCallback; bad SQL grammar [select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_track_number, main_artist, main_album, main_genre, main_year, main_bit_rate, main_duration, main_format, main_file_size, playlist_caption_cutoff, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_chat from user_settings where username=?]; nested exception is java.sql.SQLException: Column not found: FINAL_VERSION_NOTIFICATION in statement [select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_track_number, main_artist, main_album, main_genre, main_year, main_bit_rate, main_duration, main_format, main_file_size, playlist_caption_cutoff, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_chat from user_settings where username=?]
Java version Oracle Corporation 1.7.0_09
Operating system Windows 7 6.1
Server jetty-6.1.x
Memory Used 21 of 48 MB
Stack trace org.springframework.jdbc.BadSqlGrammarException: PreparedStatementCallback; bad SQL grammar [select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_track_number, main_artist, main_album, main_genre, main_year, main_bit_rate, main_duration, main_format, main_file_size, playlist_caption_cutoff, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_chat from user_settings where username=?]; nested exception is java.sql.SQLException: Column not found: FINAL_VERSION_NOTIFICATION in statement [select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_track_number, main_artist, main_album, main_genre, main_year, main_bit_rate, main_duration, main_format, main_file_size, playlist_caption_cutoff, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_chat from user_settings where username=?]
at org.springframework.jdbc.support.SQLErrorCodeSQLExceptionTranslator.doTranslate(SQLErrorCodeSQLExceptionTranslator.java:220)
at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:72)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:607)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:641)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:670)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:678)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:710)
at net.sourceforge.subsonic.dao.AbstractDao.query(AbstractDao.java:83)
at net.sourceforge.subsonic.dao.AbstractDao.queryOne(AbstractDao.java:120)
at net.sourceforge.subsonic.dao.UserDao.getUserSettings(UserDao.java:166)
at net.sourceforge.subsonic.service.SettingsService.getUserSettings(SettingsService.java:1123)
at net.sourceforge.subsonic.controller.MultiController.index(MultiController.java:184)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at org.springframework.web.servlet.mvc.multiaction.MultiActionController.invokeNamedMethod(MultiActionController.java:473)
at org.springframework.web.servlet.mvc.multiaction.MultiActionController.handleRequestInternal(MultiActionController.java:410)
at org.springframework.web.servlet.mvc.AbstractController.handleRequest(AbstractController.java:153)
at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:875)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:807)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:571)
at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:501)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1093)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:265)
at org.acegisecurity.intercept.web.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:107)
at org.acegisecurity.intercept.web.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:72)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.ui.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:166)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.providers.anonymous.AnonymousProcessingFilter.doFilter(AnonymousProcessingFilter.java:125)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.ui.rememberme.RememberMeProcessingFilter.doFilter(RememberMeProcessingFilter.java:142)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.wrapper.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:81)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.ui.basicauth.BasicProcessingFilter.doFilter(BasicProcessingFilter.java:173)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.ui.AbstractProcessingFilter.doFilter(AbstractProcessingFilter.java:271)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.ui.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.context.HttpSessionContextIntegrationFilter.doFilter(HttpSessionContextIntegrationFilter.java:249)
at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
at org.acegisecurity.util.FilterChainProxy.doFilter(FilterChainProxy.java:149)
at org.acegisecurity.util.FilterToBeanProxy.doFilter(FilterToBeanProxy.java:98)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
at org.mortbay.jetty.Server.handle(Server.java:313)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
Caused by: java.sql.SQLException: Column not found: FINAL_VERSION_NOTIFICATION in statement [select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_track_number, main_artist, main_album, main_genre, main_year, main_bit_rate, main_duration, main_format, main_file_size, playlist_caption_cutoff, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_chat from user_settings where username=?]
at org.hsqldb.jdbc.Util.throwError(Unknown Source)
at org.hsqldb.jdbc.jdbcPreparedStatement.(Unknown Source)
at org.hsqldb.jdbc.jdbcConnection.prepareStatement(Unknown Source)
at org.springframework.jdbc.core.JdbcTemplate$SimplePreparedStatementCreator.createPreparedStatement(JdbcTemplate.java:1322)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:585)
... 68 more

I hope this is enough information, presented in the right way, to allow someone here to help. I had used Subsonic for well over a year without problem until recently.

Thank you very much.

David

[hakko]

My best guess would be that your database somehow got corrupted (it does happen pretty frequently because of a choice of poor software). Your best shot is probably to rename C:/Subsonic to C:/Subsonic_backup (or something similar). Stop/start your service and hopefully you'll be able to use it again. Then read this topic about recovering settings from a broken database: viewtopic.php?f=6&t=10338

[djmsalem]

This worked perfectly. I had changed the name of the .db file but had not changed the entire directory.

Many, many thanks for your help!

David

[hakko]

Great! Thanks for a well elaborated error report.