Troubleshooting logs

Beta release announcements and discussion around them
Post Reply
silversword
Posts: 24
Joined: Sat Aug 27, 2016 8:53 pm

Thu Sep 08, 2016 9:28 pm

Is there somewhere that you can view logs? Only place I see right now is the java console, might be nice another item under settings on the left of the web interface.

Trying to troubleshoot Devices, and replacing/assigning new credentials to use for login/backup. Discovery doesn't seem to be working on 2nd test device.

David
User avatar
Tomas
Posts: 1206
Joined: Sat Jun 25, 2016 12:33 pm

Thu Sep 08, 2016 10:55 pm

When running in .exe mode, the only way to view logs is in the console.
The .exe is meant to only be used as a portable deployment.

UPDATE:
We have a Windows installer available, which will properly install Unimus as a service https://unimus.net/download.html. In this case, logs will be available on the file system, and you can also download the logs directly from Unimus GUI - in the "Zones" screen.
silversword
Posts: 24
Joined: Sat Aug 27, 2016 8:53 pm

Fri Sep 09, 2016 2:43 am

I get really burned out on many app installs every time a new server/installation is done.

Any time possible I can get an app to run in a "thumb drive" like mode: as in it's self-contained in it's own folder and I can backup/restore with straight file copy that's what I prefer and just re-launch app from it's directory (config, databases, and licensing all in it's own directory).

Just some feedback for your future plans :)

Thanks!
David
SPITwSPOTS
Posts: 10
Joined: Wed Sep 14, 2016 8:41 pm

Thu Sep 15, 2016 1:40 am

Is there somewhere I should be looking for error logs that would tell me why backups failed?

I have a few devices that I'm unable to get to back up successfully. I have other devices of the same make, model, and firmware that back up fine.

If your interested the devices I've noticed so far are a tough switch (I know I was surprised I still had some too) firmware version 1.3.2 and an edge router pro firmware version 1.9. I do have other identical equipment (also same credentials) that are backing up just fine.
SPITwSPOTS
Posts: 10
Joined: Wed Sep 14, 2016 8:41 pm

Thu Sep 15, 2016 3:01 am

Sorry I didn't realize you got some information if you just leave the terminal window open. Let me know if you would like me to start a new thread for this. But here is what I captured when I tried to download a back up from one of the devices that's giving me trouble. (sorry its not pretty)

2016-09-14 18:54:42.579 INFO 2505 --- [nio-8085-exec-2] n.unimus.business.aaa.DefaultAAAHandler : User 'admin' successfully logged in.
2016-09-14 18:55:09.939 INFO 2505 --- [nio-8085-exec-8] n.u.core.util.DuplicateRequestFilter : Checking backup request address duplicity 100.113.0.0
2016-09-14 18:55:09.939 INFO 2505 --- [nio-8085-exec-8] n.u.core.util.DuplicateRequestFilter : Not duplicated BackupRequest(context=JobContext(uuid=4b536984-7cff-4a56-9292-d7c1e536a693, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, connectorType=SSH)
2016-09-14 18:55:09.941 INFO 2505 --- [nio-8085-exec-8] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@2b5cb9b2]'.
2016-09-14 18:55:31.186 WARN 2505 --- [ backup-1] n.u.c.d.ubnt.UbntEdgerouterBackupDriver : Unable to download backup - 'Expect operation fails (timeout: 20000 ms) for matcher: anyOf(regexp('.+@.+:~\$ $'),regexp(':\r'))'!
2016-09-14 18:55:31.187 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Removing backup request address 100.113.0.0
2016-09-14 18:55:31.188 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-14 18:55:31.194 INFO 2505 --- [ backup-1] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@2b5cb9b2]'.
2016-09-14 18:55:31.195 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Checking discovery request address duplicity 100.113.0.0
2016-09-14 18:55:31.195 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Not duplicated DiscoveryRequest(context=JobContext(uuid=6ee19583-bf67-47a2-8b7b-065a38ac0f65, deviceId=11, groupId=1), address=100.113.0.0)
2016-09-14 18:55:31.195 INFO 2505 --- [ backup-1] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@54e7391d]'.
2016-09-14 18:55:31.196 INFO 2505 --- [ backup-1] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=BackupResult(context=JobContext(uuid=6ee19583-bf67-47a2-8b7b-065a38ac0f65, deviceId=11, groupId=1), address=100.113.0.0, backupType=TEXT, timestamp=null, duration=0, backupError=SSH_BACKUP_DOWNLOAD_FAILED), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=8eb75999-0ccd-b3a1-fd4e-2ca29b61d0bc, unimus-request-processing-duration=PT21.245S, timestamp=1473908131196}]
2016-09-14 18:55:38.113 INFO 2505 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Removing discovery request address 100.113.0.0
2016-09-14 18:55:38.113 INFO 2505 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-14 18:55:38.128 INFO 2505 --- [ discovery-7] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@2b5cb9b2]'.
2016-09-14 18:55:38.129 INFO 2505 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Checking backup request address duplicity 100.113.0.0
2016-09-14 18:55:38.129 INFO 2505 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Not duplicated BackupRequest(context=JobContext(uuid=f46870eb-c2ed-486a-a2b0-152435ec5d2d, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, connectorType=SSH)
2016-09-14 18:55:38.129 INFO 2505 --- [ discovery-7] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@54e7391d]'.
2016-09-14 18:55:38.130 INFO 2505 --- [ discovery-7] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=DiscoveryResult(context=JobContext(uuid=f46870eb-c2ed-486a-a2b0-152435ec5d2d, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, credentials=DeviceCredential{username='admin', password = '9 character(s)'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, model=EdgeRouter Pro 8-Port, connectorType=SSH, discoveryLog=null, successful=true), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=17d05bb1-57e8-a8d2-a1bd-f5c6581f5bff, unimus-request-processing-duration=PT6.916S, timestamp=1473908138130}]
2016-09-14 18:55:59.029 WARN 2505 --- [ backup-1] n.u.c.d.ubnt.UbntEdgerouterBackupDriver : Unable to download backup - 'Expect operation fails (timeout: 20000 ms) for matcher: anyOf(regexp('.+@.+:~\$ $'),regexp(':\r'))'!
2016-09-14 18:55:59.030 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Removing backup request address 100.113.0.0
2016-09-14 18:55:59.030 INFO 2505 --- [ backup-1] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-14 18:55:59.034 INFO 2505 --- [ backup-1] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@54e7391d]'.
2016-09-14 18:55:59.034 INFO 2505 --- [ backup-1] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=BackupResult(context=JobContext(uuid=null, deviceId=11, groupId=1), address=100.113.0.0, backupType=TEXT, timestamp=null, duration=0, backupError=SSH_BACKUP_DOWNLOAD_FAILED), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=25839f95-053e-0428-9ef8-5e411dfc7fb1, unimus-request-processing-duration=PT20.9S, timestamp=1473908159034}]
User avatar
Tomas
Posts: 1206
Joined: Sat Jun 25, 2016 12:33 pm

Thu Sep 15, 2016 9:27 am

From looking at the logs, it seems you are running 0.2.1.
The issues with EdgeRouter were fixed in 0.2.2, can you please try with 0.2.2?

As for THOUGHSwitch, its probably some inconsistenties between various versions of ThoughSwitch firmware that make it work on one version, but not on another. Altho the fact that it works for you on some devices of same model/firmware and not other is really interesting...

Is there any chance to get SSH access to the devices that dont work?
I will be glad to fix it, but it will require some debugging and testing that requires SSH access to the devices.
SPITwSPOTS
Posts: 10
Joined: Wed Sep 14, 2016 8:41 pm

Thu Sep 15, 2016 6:33 pm

Thanks for the response. Looks like I accidentally had both versions downloaded and ran the wrong one. So I deleted all the old ones and download 0.2.2. I'm still getting the same error. (it does work on some edgerouters)

2016-09-15 10:27:44.016 INFO 24535 --- [nio-8085-exec-5] n.u.core.util.DuplicateRequestFilter : Checking backup request address duplicity 100.113.0.0
2016-09-15 10:27:44.016 INFO 24535 --- [nio-8085-exec-5] n.u.core.util.DuplicateRequestFilter : Not duplicated BackupRequest(context=JobContext(uuid=fb07364b-f7ab-4f3a-8e45-e7b36037f248, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, connectorType=SSH)
2016-09-15 10:27:44.018 INFO 24535 --- [nio-8085-exec-5] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@158d255c]'.
2016-09-15 10:28:05.220 WARN 24535 --- [ backup-2] n.u.c.d.ubnt.UbntEdgerouterBackupDriver : Unable to download backup - 'Expect operation fails (timeout: 20000 ms) for matcher: anyOf(regexp('.+@.+:~\$ $'),regexp('(?m)^:$'))'!
2016-09-15 10:28:05.222 INFO 24535 --- [ backup-2] n.u.core.util.DuplicateRequestFilter : Removing backup request address 100.113.0.0
2016-09-15 10:28:05.222 INFO 24535 --- [ backup-2] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-15 10:28:05.236 INFO 24535 --- [ backup-2] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@158d255c]'.
2016-09-15 10:28:05.236 INFO 24535 --- [ backup-2] n.u.core.util.DuplicateRequestFilter : Checking discovery request address duplicity 100.113.0.0
2016-09-15 10:28:05.237 INFO 24535 --- [ backup-2] n.u.core.util.DuplicateRequestFilter : Not duplicated DiscoveryRequest(context=JobContext(uuid=22e12f69-d105-4a0e-aae1-962a6945a4c2, deviceId=11, groupId=1), address=100.113.0.0)
2016-09-15 10:28:05.237 INFO 24535 --- [ backup-2] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@6d1310f6]'.
2016-09-15 10:28:05.238 INFO 24535 --- [ backup-2] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=BackupResult(context=JobContext(uuid=22e12f69-d105-4a0e-aae1-962a6945a4c2, deviceId=11, groupId=1), address=100.113.0.0, backupType=TEXT, timestamp=null, duration=0, backupError=SSH_BACKUP_DOWNLOAD_FAILED), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=da68cc88-caa4-eff0-5734-32021570ad76, unimus-request-processing-duration=PT21.202S, timestamp=1473964085237}]
2016-09-15 10:28:11.922 INFO 24535 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Removing discovery request address 100.113.0.0
2016-09-15 10:28:11.923 INFO 24535 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-15 10:28:11.936 INFO 24535 --- [ discovery-7] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.CoreRequestBuilder@158d255c]'.
2016-09-15 10:28:11.937 INFO 24535 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Checking backup request address duplicity 100.113.0.0
2016-09-15 10:28:11.937 INFO 24535 --- [ discovery-7] n.u.core.util.DuplicateRequestFilter : Not duplicated BackupRequest(context=JobContext(uuid=d78a741e-b1f8-472b-9334-27943a909256, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, connectorType=SSH)
2016-09-15 10:28:11.937 INFO 24535 --- [ discovery-7] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@6d1310f6]'.
2016-09-15 10:28:11.938 INFO 24535 --- [ discovery-7] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=DiscoveryResult(context=JobContext(uuid=d78a741e-b1f8-472b-9334-27943a909256, deviceId=11, groupId=1), address=100.113.0.0, port=Port{id='3', createdTime='null', port='22'}, credentials=DeviceCredential{username='admin', password = '9 character(s)'}, deviceVendor=Ubiquiti, deviceType=EdgeRouter, model=EdgeRouter Pro 8-Port, connectorType=SSH, discoveryLog=null, successful=true), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=22741898-3a01-8c4d-0d88-165c047718d6, unimus-request-processing-duration=PT6.684S, timestamp=1473964091937}]
2016-09-15 10:28:33.129 WARN 24535 --- [ backup-3] n.u.c.d.ubnt.UbntEdgerouterBackupDriver : Unable to download backup - 'Expect operation fails (timeout: 20000 ms) for matcher: anyOf(regexp('.+@.+:~\$ $'),regexp('(?m)^:$'))'!
2016-09-15 10:28:33.130 INFO 24535 --- [ backup-3] n.u.core.util.DuplicateRequestFilter : Removing backup request address 100.113.0.0
2016-09-15 10:28:33.130 INFO 24535 --- [ backup-3] n.u.core.util.DuplicateRequestFilter : Address removed 100.113.0.0
2016-09-15 10:28:33.133 INFO 24535 --- [ backup-3] net.unimus.ui.core.AbstractTableWidget : New data set change event. Event = 'DataSetChangedEvent{, topic='/1/class net.unimus.schema.Device'} net.unimus.events.DataSetChangedEvent[source=net.unimus.business.backup.handler.ResultHandler@6d1310f6]'.
2016-09-15 10:28:33.133 INFO 24535 --- [ backup-3] net.unimus.core.util.MessageLogger : Logging message GenericMessage [payload=BackupResult(context=JobContext(uuid=null, deviceId=11, groupId=1), address=100.113.0.0, backupType=TEXT, timestamp=null, duration=0, backupError=SSH_BACKUP_DOWNLOAD_FAILED), headers={unimus-core-discovery-context=BackerContext(isDiscovered=false, sshConnectorConfig=SshConnectorConfig{id='null', createdTime='null', ports='[Port{id='3', createdTime='null', port='22'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpConnectorConfig=HttpConnectorConfig{id='6', createdTime='1473891270', ports='[Port{id='5', createdTime='null', port='80'}]', connectTimeout='20000', expectTimeout='20000', backupTimeout='20000'}, httpsConnectorConfig=HttpsConnectorConfig{id='null', createdTime='null', ports='[]', connectTimeout='0', expectTimeout='0', backupTimeout='0'}), id=85c49c2a-8ecc-102e-5356-5e606f1ae7ca, unimus-request-processing-duration=PT21.192S, timestamp=1473964113133}]
SPITwSPOTS
Posts: 10
Joined: Wed Sep 14, 2016 8:41 pm

Thu Sep 15, 2016 6:35 pm

Tomas wrote: Is there any chance to get SSH access to the devices that dont work?
I will be glad to fix it, but it will require some debugging and testing that requires SSH access to the devices.
Sure I can get you SSH access. How do you prefer to communicate? Email, PM, Chat...
User avatar
Tomas
Posts: 1206
Joined: Sat Jun 25, 2016 12:33 pm

Thu Sep 15, 2016 7:44 pm

SPITwSPOTS wrote: Sure I can get you SSH access. How do you prefer to communicate? Email, PM, Chat...
PM would be best :)
Post Reply