App got stuck after adding new person

[damithc]

Got stuck after adding a second person (stuck during grace period).
addressbook.log.txt

[damithc]

Note that I'm running from the releases version V1.4.1ea

[damithc]

Got stuck again at startup. UI non responsive.

[JavaFX-Launcher] I 14:37:37.890 (MainApp) Initializing app ...
[JavaFX-Launcher] I 14:37:37.894 (DependencyChecker) Verifying dependencies
[JavaFX-Launcher] I 14:37:37.894 (DependencyChecker) Verifying java version dependency
[JavaFX-Launcher] I 14:37:37.894 (DependencyChecker) Verifying dependency libraries are present
[JavaFX-Launcher] I 14:37:37.987 (DependencyChecker) All dependencies present
[JavaFX-Launcher] I 14:37:37.989 (StorageManager) Config file config.json found, attempting to read.
[JavaFX-Launcher] I 14:37:38.032 (LoggerManager) currentLogLevel: INFO
[JavaFX-Launcher] I 14:37:38.033 (LoggerManager) specialLogLevels: {}
[JavaFX-Launcher] I 14:37:38.269 (SyncManager) Active addressbook set to MyAddressBook
[JavaFX Application Thread] I 14:37:38.345 (MainApp) Starting application: V1.4.1ea
[JavaFX Application Thread] I 14:37:38.346 (MainController) Starting main controller.
[JavaFX Application Thread] I 14:37:38.346 (BrowserManager) Initializing browser with 3 pages
[JavaFX Application Thread] I 14:37:39.618 (StorageManager) Starting storage manager.
[JavaFX Application Thread] I 14:37:39.706 (SyncManager) Starting sync manager.
[pool-6-thread-1] I 14:37:39.710 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 14:37:39.711 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 14:37:39.718 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 14:37:39.718 (CloudSimulator) Current quota left: 5000
[pool-6-thread-1] I 14:37:39.768 (RemoteManager) 4 updated persons.
[pool-6-thread-1] I 14:37:39.769 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 14:37:39.769 (CloudSimulator) Current quota left: 4999
[pool-6-thread-1] I 14:37:39.866 (EventManager) SyncCompletedEvent: 4 updatedPersons and 0 latest tags
[pool-6-thread-1] I 14:37:39.868 (EventManager) LocalModelChangedEvent: number of persons 4, number of tags 0
[pool-6-thread-1] I 14:37:39.870 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 14:37:49.885 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 14:37:49.885 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 14:37:49.885 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 14:37:49.885 (CloudSimulator) Current quota left: 4998
[pool-6-thread-1] I 14:37:49.907 (RemoteManager) 4 updated persons.
[pool-6-thread-1] I 14:37:49.907 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 14:37:49.907 (CloudSimulator) Current quota left: 4997
[pool-6-thread-1] I 14:37:49.926 (EventManager) SyncCompletedEvent: 4 updatedPersons and no updates to tags
[pool-6-thread-1] I 14:37:49.926 (EventManager) LocalModelChangedEvent: number of persons 4, number of tags 0
[pool-6-thread-1] I 14:37:49.926 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 14:37:59.934 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 14:37:59.934 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 14:37:59.934 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 14:37:59.935 (CloudSimulator) Current quota left: 4997
[pool-6-thread-1] I 14:37:59.956 (RemoteManager) 4 updated persons.
[pool-6-thread-1] I 14:37:59.957 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 14:37:59.957 (CloudSimulator) Current quota left: 4996
[pool-6-thread-1] I 14:37:59.973 (EventManager) SyncCompletedEvent: 4 updatedPersons and no updates to tags
[pool-6-thread-1] I 14:37:59.974 (EventManager) LocalModelChangedEvent: number of persons 4, number of tags 0
[pool-6-thread-1] I 14:37:59.974 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 14:38:09.981 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 14:38:09.981 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 14:38:09.981 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 14:38:09.981 (CloudSimulator) Current quota left: 4996
[pool-6-thread-1] I 14:38:10.011 (RemoteManager) 4 updated persons.
[pool-6-thread-1] I 14:38:10.012 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 14:38:10.012 (CloudSimulator) Current quota left: 4995
[pool-6-thread-1] I 14:38:10.040 (EventManager) SyncCompletedEvent: 4 updatedPersons and no updates to tags
[pool-6-thread-1] I 14:38:10.040 (EventManager) LocalModelChangedEvent: number of persons 4, number of tags 0
[pool-6-thread-1] I 14:38:10.041 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 14:38:20.050 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 14:38:20.050 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 14:38:20.050 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 14:38:20.050 (CloudSimulator) Current quota left: 4995
[pool-6-thread-1] I 14:38:20.067 (RemoteManager) 4 updated persons.
[pool-6-thread-1] I 14:38:20.067 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 14:38:20.067 (CloudSimulator) Current quota left: 4994
[pool-6-thread-1] I 14:38:20.082 (EventManager) SyncCompletedEvent: 4 updatedPersons and no updates to tags
[pool-6-thread-1] I 14:38:20.082 (EventManager) LocalModelChangedEvent: number of persons 4, number of tags 0
[pool-6-thread-1] I 14:38:20.083 (StorageManager) Local data changed, saving to primary data file
[JavaFX Application Thread] I 14:38:24.014 (MainApp) Stopping application.
[JavaFX Application Thread] I 14:38:24.054 (SyncManager) Stopping sync manager.

[damithc]

I'm curious as to why there are 'local model changed' events going on at start up.

[damithc]

The error is random by the way, not able to reproduce reliably.

[damithc]

Managed to reproduce twice using these steps.

1. Install new version.
2. Run new version.
3. Add one person.
4. Add another person.
   App gets stuck.

[JavaFX-Launcher] I 15:17:36.781 (MainApp) Initializing app ...
[JavaFX-Launcher] I 15:17:36.818 (DependencyChecker) Verifying dependencies
[JavaFX-Launcher] I 15:17:36.818 (DependencyChecker) Verifying java version dependency
[JavaFX-Launcher] I 15:17:36.819 (DependencyChecker) Verifying dependency libraries are present
[JavaFX-Launcher] I 15:17:36.910 (DependencyChecker) All dependencies present
[JavaFX-Launcher] I 15:17:36.938 (StorageManager) Config file config.json not found, using default config.
[JavaFX-Launcher] I 15:17:37.043 (LoggerManager) currentLogLevel: INFO
[JavaFX-Launcher] I 15:17:37.043 (LoggerManager) specialLogLevels: {}
[JavaFX-Launcher] I 15:17:37.452 (CloudFileHandler) Writing to cloud file 'MyAddressBook'.
[JavaFX-Launcher] I 15:17:37.555 (SyncManager) Active addressbook set to MyAddressBook
[JavaFX Application Thread] I 15:17:38.154 (MainApp) Starting application: V1.4.1ea
[JavaFX Application Thread] I 15:17:38.154 (MainController) Starting main controller.
[JavaFX Application Thread] I 15:17:38.154 (BrowserManager) Initializing browser with 3 pages
[JavaFX Application Thread] I 15:17:41.787 (StorageManager) Starting storage manager.
[JavaFX Application Thread] I 15:17:41.810 (SyncManager) Starting sync manager.
[pool-6-thread-1] I 15:17:41.813 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:17:41.814 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:17:41.838 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:17:41.839 (CloudSimulator) Current quota left: 5000
[pool-6-thread-1] I 15:17:41.971 (RemoteManager) 0 updated persons.
[pool-6-thread-1] I 15:17:41.972 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:17:41.972 (CloudSimulator) Current quota left: 4999
[pool-6-thread-1] I 15:17:42.000 (EventManager) SyncCompletedEvent: 0 updatedPersons and 0 latest tags
[pool-6-thread-1] I 15:17:42.002 (EventManager) LocalModelChangedEvent: number of persons 0, number of tags 0
[pool-6-thread-1] I 15:17:42.004 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 15:17:52.018 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:17:52.018 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:17:52.018 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:17:52.019 (CloudSimulator) Current quota left: 4998
[pool-6-thread-1] I 15:17:52.037 (RemoteManager) 0 updated persons.
[pool-6-thread-1] I 15:17:52.038 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:17:52.038 (CloudSimulator) Current quota left: 4997
[pool-6-thread-1] I 15:17:52.054 (EventManager) SyncCompletedEvent: 0 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:17:52.055 (EventManager) LocalModelChangedEvent: number of persons 0, number of tags 0
[pool-6-thread-1] I 15:17:52.055 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 15:18:02.061 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:02.061 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:02.061 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:02.061 (CloudSimulator) Current quota left: 4997
[pool-6-thread-1] I 15:18:02.077 (RemoteManager) 0 updated persons.
[pool-6-thread-1] I 15:18:02.077 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:02.077 (CloudSimulator) Current quota left: 4996
[pool-6-thread-1] I 15:18:02.093 (EventManager) SyncCompletedEvent: 0 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:02.094 (EventManager) LocalModelChangedEvent: number of persons 0, number of tags 0
[pool-6-thread-1] I 15:18:02.094 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 15:18:12.103 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:12.103 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:12.103 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:12.103 (CloudSimulator) Current quota left: 4996
[pool-6-thread-1] I 15:18:12.120 (RemoteManager) 0 updated persons.
[pool-6-thread-1] I 15:18:12.120 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:12.120 (CloudSimulator) Current quota left: 4995
[pool-6-thread-1] I 15:18:12.133 (EventManager) SyncCompletedEvent: 0 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:12.134 (EventManager) LocalModelChangedEvent: number of persons 0, number of tags 0
[pool-6-thread-1] I 15:18:12.134 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 15:18:22.140 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:22.140 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:22.140 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:22.140 (CloudSimulator) Current quota left: 4995
[pool-6-thread-1] I 15:18:22.155 (RemoteManager) 0 updated persons.
[pool-6-thread-1] I 15:18:22.155 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:22.155 (CloudSimulator) Current quota left: 4994
[pool-6-thread-1] I 15:18:22.169 (EventManager) SyncCompletedEvent: 0 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:22.169 (EventManager) LocalModelChangedEvent: number of persons 0, number of tags 0
[pool-6-thread-1] I 15:18:22.170 (StorageManager) Local data changed, saving to primary data file
[pool-3-thread-1] I 15:18:23.654 (EventManager) CreatePersonOnRemoteRequestEvent: Request to create person on remote MyAddressBook: Person [#TBD][Damith R][Street:][City:][Postal code:][Birthday:][GitHub Username:damithc][]
[pool-5-thread-1] I 15:18:23.655 (CreatePersonOnRemoteTask) Creating Person [#TBD][Damith R][Street:][City:][Postal code:][Birthday:][GitHub Username:damithc][] in MyAddressBook on remote
[pool-5-thread-1] I 15:18:23.656 (CloudSimulator) Current quota left: 4994
[pool-5-thread-1] I 15:18:23.671 (CloudFileHandler) Writing to cloud file 'MyAddressBook'.
[pool-3-thread-1] I 15:18:23.694 (EventManager) CommandFinishedEvent: User command #1 finished execution (Successful): Target [Person #1]
[pool-6-thread-1] I 15:18:32.177 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:32.177 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:32.177 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:32.177 (CloudSimulator) Current quota left: 4993
[pool-6-thread-1] I 15:18:32.196 (RemoteManager) 1 updated persons.
[pool-6-thread-1] I 15:18:32.196 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:32.197 (CloudSimulator) Current quota left: 4992
[pool-6-thread-1] I 15:18:32.216 (EventManager) SyncCompletedEvent: 1 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:32.217 (EventManager) LocalModelChangedEvent: number of persons 1, number of tags 0
[pool-6-thread-1] I 15:18:32.217 (StorageManager) Local data changed, saving to primary data file
[pool-3-thread-1] I 15:18:34.831 (EventManager) CreatePersonOnRemoteRequestEvent: Request to create person on remote MyAddressBook: Person [#TBD][You Liang][Street:][City:][Postal code:][Birthday:][GitHub Username:yl-coder][]
[pool-5-thread-2] I 15:18:34.831 (CreatePersonOnRemoteTask) Creating Person [#TBD][You Liang][Street:][City:][Postal code:][Birthday:][GitHub Username:yl-coder][] in MyAddressBook on remote
[pool-5-thread-2] I 15:18:34.832 (CloudSimulator) Current quota left: 4992
[pool-5-thread-2] I 15:18:34.853 (CloudFileHandler) Writing to cloud file 'MyAddressBook'.
[pool-3-thread-1] I 15:18:34.869 (EventManager) CommandFinishedEvent: User command #2 finished execution (Successful): Target [Person #2]
[pool-6-thread-1] I 15:18:42.224 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:42.224 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:42.224 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:42.224 (CloudSimulator) Current quota left: 4991
[pool-6-thread-1] I 15:18:42.240 (RemoteManager) 2 updated persons.
[pool-6-thread-1] I 15:18:42.240 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:42.240 (CloudSimulator) Current quota left: 4990
[pool-6-thread-1] I 15:18:42.255 (EventManager) SyncCompletedEvent: 2 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:42.255 (EventManager) LocalModelChangedEvent: number of persons 2, number of tags 0
[pool-6-thread-1] I 15:18:42.255 (StorageManager) Local data changed, saving to primary data file
[pool-6-thread-1] I 15:18:52.263 (GetUpdatesFromRemoteTask) Attempting to run periodic update.
[pool-6-thread-1] I 15:18:52.263 (EventManager) SyncStartedEvent: Synchronization with server has started.
[pool-6-thread-1] I 15:18:52.263 (RemoteManager) Getting updated persons from remote.
[pool-6-thread-1] I 15:18:52.263 (CloudSimulator) Current quota left: 4990
[pool-6-thread-1] I 15:18:52.278 (RemoteManager) 2 updated persons.
[pool-6-thread-1] I 15:18:52.279 (RemoteManager) Getting tags list from remote.
[pool-6-thread-1] I 15:18:52.279 (CloudSimulator) Current quota left: 4989
[pool-6-thread-1] I 15:18:52.292 (EventManager) SyncCompletedEvent: 2 updatedPersons and no updates to tags
[pool-6-thread-1] I 15:18:52.292 (EventManager) LocalModelChangedEvent: number of persons 2, number of tags 0
[pool-6-thread-1] I 15:18:52.292 (StorageManager) Local data changed, saving to primary data file

[m133225]

Hmm, the V1.4.1ea published earlier might not work properly as the dependencies may be not in order. Can't seem to reproduce this in the latest version.

[damithc]

Reproduced using the latest version V1.4.1ea (I installed it again just now).