Guest User!

You are not Sophos Staff.

Thread Info
  • State Not Answered
  • Locked Locked
  • Replies 9 replies
  • Subscribers 1 subscriber
  • Views 2593 views
  • Users 0 members are here
Options
Suggested
This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

HTML5VPN dropping session due to config change

kloana
Hi,

i have a Home-HA system with two UTM's. Both HTML5VPN session's to a SSH-Server and to RDP-Server behind UTM are terminating unexpectly with message "Session closed due to configuration change". But nothing was changed in webadmin and utm configuration!

So i did some research and could find out, everytime when sessions are closed configuration was updated.

Attached you can find the relevant line of both log's (html5 and confd). 


2015:01:21-06:41:26 primary-1 screenmgr[4890]: Session[1]: Service has stopped with an error: 2

2015:01:21-06:41:27 primary-1 screenmgr[4890]: Session[2]: Service has stopped with an error: 2

2015:01:21-06:41:26 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 57"

2015:01:21-06:41:27 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 58"



2015:01:21-07:11:29 primary-1 screenmgr[4890]: Session[4]: Service has stopped with an error: 2

2015:01:21-07:11:30 primary-1 screenmgr[4890]: Session[5]: Service has stopped with an error: 2

2015:01:21-07:11:29 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 73"

2015:01:21-07:11:30 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 75"

2015:01:21-07:11:30 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version unchanged: 75"



2015:01:21-07:41:28 primary-1 screenmgr[4890]: Session[7]: Service has stopped with an error: 2

2015:01:21-07:41:29 primary-1 screenmgr[4890]: Session[6]: Service has stopped with an error: 2

2015:01:21-07:41:28 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 83"

2015:01:21-07:41:28 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 84"

2015:01:21-07:41:29 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 85"



2015:01:21-07:55:00 primary-1 screenmgr[4890]: Session[8]: Service has stopped with an error: 2

2015:01:21-07:55:00 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 90"



2015:01:21-07:56:12 primary-1 screenmgr[4890]: Session[9]: Service has stopped with an error: 2

2015:01:21-07:56:12 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 93"



2015:01:21-08:26:32 primary-1 screenmgr[4890]: Session[11]: Service has stopped with an error: 2

2015:01:21-08:26:32 primary-1 screenmgr[4890]: Session[12]: Service has stopped with an error: 2

2015:01:21-08:26:33 primary-1 screenmgr[4890]: Session[10]: Service has stopped with an error: 2

2015:01:21-08:26:31 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 103"

2015:01:21-08:26:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 104"

2015:01:21-08:26:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 105"

2015:01:21-08:26:34 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 106"



2015:01:21-08:56:32 primary-1 screenmgr[4890]: Session[13]: Service has stopped with an error: 2

2015:01:21-08:56:30 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 114"

2015:01:21-08:56:31 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 115"

2015:01:21-08:56:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 116"

2015:01:21-08:56:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 117"

2015:01:21-08:56:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 118"



2015:01:21-09:26:31 primary-1 screenmgr[4890]: Session[14]: Service has stopped with an error: 2

2015:01:21-09:26:32 primary-1 screenmgr[4890]: Session[15]: Service has stopped with an error: 2

2015:01:21-09:26:30 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 125"

2015:01:21-09:26:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 127"

2015:01:21-09:26:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version unchanged: 127"

2015:01:21-09:26:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 128"

2015:01:21-09:26:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 129"



2015:01:21-09:39:18 primary-1 screenmgr[4890]: Session[17]: Service has stopped with an error: 2

2015:01:21-09:39:18 primary-1 screenmgr[4890]: Session[16]: Service has stopped with an error: 2

2015:01:21-09:39:16 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 131"

2015:01:21-09:39:17 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 132"

2015:01:21-09:39:18 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 133"



2015:01:21-09:56:37 primary-1 screenmgr[4890]: Session[19]: Service has stopped with an error: 2

2015:01:21-09:56:37 primary-1 screenmgr[4890]: Session[18]: Service has stopped with an error: 2

2015:01:21-09:56:36 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 138"

2015:01:21-09:56:37 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 139"

2015:01:21-09:56:38 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 141"

2015:01:21-09:56:38 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version unchanged: 141"

2015:01:21-09:56:55 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 142"



2015:01:21-10:11:32 primary-1 screenmgr[4890]: Session[20]: Service has stopped with an error: 2

2015:01:21-10:11:30 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 144"

2015:01:21-10:11:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 145"

2015:01:21-10:11:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 146"

2015:01:21-10:11:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 147"

2015:01:21-10:11:34 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 148"



2015:01:21-10:26:32 primary-1 screenmgr[4890]: Session[21]: Service has stopped with an error: 2

2015:01:21-10:26:31 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 152"

2015:01:21-10:26:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 153"

2015:01:21-10:26:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 154"

2015:01:21-10:26:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 155"

2015:01:21-10:26:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 156"



2015:01:21-10:56:33 primary-1 screenmgr[4890]: Session[24]: Service has stopped with an error: 2

2015:01:21-10:56:33 primary-1 screenmgr[4890]: Session[22]: Service has stopped with an error: 2

2015:01:21-10:56:31 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 166"

2015:01:21-10:56:32 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 167"

2015:01:21-10:56:33 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 168"

2015:01:21-10:56:34 primary-1 confd-sync[6280]: id="3850" severity="info" sys="System" sub="ha" name="Version changed: 169"


Maybe someone has some suggestions?

Thanks in advance,
regards
Herbert


This thread was automatically locked due to age.
Parents
  • 0
    Herbert, do you see anything interesting in the System messages, Up2Date messages or High availability logs at 2015:01:21-10:56?

    Cheers - Bob
     
    Sophos UTM Community Moderator
    Sophos Certified Architect - UTM
    Sophos Certified Engineer - XG
    Gold Solution Partner since 2005
    MediaSoft, Inc. USA
  • 0 in reply to BAlfson
    Hi,

    here are the relevant logs.

    System.log: 

    2015:01:21-11:56:01 primary-2 /usr/sbin/cron[21215]: (httpproxy) CMD (/var/chroot-http/usr/bin/virus_feedback_uploader)

    2015:01:21-11:56:01 primary-1 /usr/sbin/cron[5148]: (root) CMD (/sbin/audld.plx --trigger)

    2015:01:21-11:56:11 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:12 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:13 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:28 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Changeset 194 empty!

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_DefaultSophosUTMSupportHost

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_LogMgmtProvisioningService

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_NetDnsSophoLivec

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_NtpPool

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: No change to REF_LogMgmtProvisioningService :: logmgmt.astaro.com

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: No change to REF_DefaultSophosUTMSupportHost :: dispatch.apu.sophos.com

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Updating REF_NtpPool :: pool.ntp.org

    2015:01:21-11:56:32 primary-1 ntpd[3116]: ntpd exiting on signal 15

    2015:01:21-11:56:32 primary-1 ntpd[5335]: ntpd 4.2.6p5@1.2349 Tue Feb  4 13:03:59 UTC 2014 (1)

    2015:01:21-11:56:32 primary-1 ntpd[5336]: proto: precision = 1.047 usec

    2015:01:21-11:56:32 primary-1 ntpd[5336]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen and drop on 1 v6wildcard :: UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 2 lo 127.0.0.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 3 eth3 198.19.250.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 4 eth1.100 10.10.0.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 5 eth1.108 10.10.8.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 6 eth1.115 10.10.15.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 7 eth1.109 10.10.9.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 8 eth1.110 10.10.10.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 9 ppp0 178.191.200.16 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 10 tun0 10.242.2.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 11 lo ::1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: peers refreshed

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listening on routing socket on fd #28 for interface updates

    2015:01:21-11:56:32 primary-2 ntpd[20438]: ntpd exiting on signal 15

    2015:01:21-11:56:32 primary-2 ntpd[21285]: ntpd 4.2.6p5@1.2349 Tue Feb  4 13:03:59 UTC 2014 (1)

    2015:01:21-11:56:32 primary-2 ntpd[21286]: proto: precision = 1.048 usec

    2015:01:21-11:56:32 primary-2 ntpd[21286]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen and drop on 1 v6wildcard :: UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 2 lo 127.0.0.1 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 3 eth3 198.19.250.2 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 4 eth1.100 10.10.0.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 5 eth1.108 10.10.8.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 6 eth1.115 10.10.15.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 7 eth1.109 10.10.9.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 8 eth1.110 10.10.10.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 9 ppp0 178.191.200.16 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 10 lo ::1 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: peers refreshed

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listening on routing socket on fd #27 for interface updates

    2015:01:21-11:56:32 primary-2 ntpd[21286]: bind(28) AF_INET 0.0.0.0#123 flags 0x29 failed: Address already in use

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #9 ppp0

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #8 eth1.110

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #7 eth1.109

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #6 eth1.115

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #5 eth1.108

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #4 eth1.100

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #3 eth3

    2015:01:21-11:56:38 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:42 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again


    up2date.log: 

    2015:01:21-10:56:01 primary-1 audld[29444]: running on HA master system or cluster node

    2015:01:21-10:56:01 primary-1 audld[29444]: Starting Up2Date Package Downloader

    2015:01:21-10:56:05 primary-1 audld[29444]: patch up2date possible

    2015:01:21-10:56:05 primary-1 audld[29444]: Using static update server list in HA mode

    2015:01:21-10:56:30 primary-1 audld[29444]: id="3701" severity="info" sys="system" sub="up2date" name="Authentication successful"

    2015:01:21-10:56:30 primary-1 audld[29444]: Using static download server list in HA mode

    2015:01:21-10:56:30 primary-1 audld[29444]: id="3707" severity="info" sys="system" sub="up2date" name="Successfully synchronized fileset" status="success" action="download" package="avira3"

    2015:01:21-10:56:31 primary-1 audld[29444]: id="3707" severity="info" sys="system" sub="up2date" name="Successfully synchronized fileset" status="success" action="download" package="sys"

    2015:01:21-10:56:32 primary-1 auisys[29564]: running on HA master system or cluster node

    2015:01:21-10:56:33 primary-1 auisys[29564]: not cleaning /var/up2date/sys-install in --nosys mode

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/aptp-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/avira3-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/clvbrowser-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/geoip-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/ipsbundle-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/man9-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/ohelp9-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/savi-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: Starting Up2Date Package Installer

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: Install u2d packages 

    2015:01:21-10:56:33 primary-1 auisys[29564]: Starting installing up2date packages for type 'avira3'

    2015:01:21-10:56:33 primary-1 auisys[29564]: Installing up2date package: /var/up2date/avira3/u2d-avira3-9.9206-9207.patch.tgz.gpg

    2015:01:21-10:56:33 primary-1 auisys[29564]: Verifying up2date package signature

    2015:01:21-10:56:33 primary-1 auisys[29564]: Unpacking installation instructions

    2015:01:21-10:56:33 primary-1 auisys[29564]: parsing installation instructions

    2015:01:21-10:56:33 primary-1 auisys[29564]: This is a patch. Setting required_version to 9.9206

    2015:01:21-10:56:33 primary-1 auisys[29564]: Unpacking up2date package container

    2015:01:21-10:56:33 primary-1 auisys[29564]: Running pre-installation checks

    2015:01:21-10:56:34 primary-1 auisys[29564]: Starting up2date package installation

    2015:01:21-10:56:49 primary-1 auisys[29564]: id="371Z" severity="info" sys="system" sub="up2date" name="Successfully installed Up2Date package" status="success" action="install" package_version="9.9207" package="avira3"

    2015:01:21-10:56:49 primary-1 auisys[29564]: [INFO-306] New Pattern Up2Dates installed

    2015:01:21-10:56:50 primary-1 auisys[29564]: Doing HA sync

    2015:01:21-10:56:50 primary-1 auisys[29564]: calling: 

    2015:01:21-10:56:50 primary-1 auisys[29564]: id="3720" severity="info" sys="system" sub="up2date" name="Successfully triggered up2date sync" status="success" action="sync"

    2015:01:21-10:56:50 primary-1 auisys[29564]: Up2Date Package Installer finished, exiting

    2015:01:21-10:56:50 primary-1 auisys[29564]: id="3716" severity="info" sys="system" sub="up2date" name="Up2Date Package Installer finished, exiting"


    And in high availability is nothing at the relevant time!

    Thanks,
    regards
    Herbert
Reply
  • 0 in reply to BAlfson
    Hi,

    here are the relevant logs.

    System.log: 

    2015:01:21-11:56:01 primary-2 /usr/sbin/cron[21215]: (httpproxy) CMD (/var/chroot-http/usr/bin/virus_feedback_uploader)

    2015:01:21-11:56:01 primary-1 /usr/sbin/cron[5148]: (root) CMD (/sbin/audld.plx --trigger)

    2015:01:21-11:56:11 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:12 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:13 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:28 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Changeset 194 empty!

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_DefaultSophosUTMSupportHost

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_LogMgmtProvisioningService

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_NetDnsSophoLivec

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Adding REF_NtpPool

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: No change to REF_LogMgmtProvisioningService :: logmgmt.astaro.com

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: No change to REF_DefaultSophosUTMSupportHost :: dispatch.apu.sophos.com

    2015:01:21-11:56:31 primary-1 dns-resolver[6639]: Updating REF_NtpPool :: pool.ntp.org

    2015:01:21-11:56:32 primary-1 ntpd[3116]: ntpd exiting on signal 15

    2015:01:21-11:56:32 primary-1 ntpd[5335]: ntpd 4.2.6p5@1.2349 Tue Feb  4 13:03:59 UTC 2014 (1)

    2015:01:21-11:56:32 primary-1 ntpd[5336]: proto: precision = 1.047 usec

    2015:01:21-11:56:32 primary-1 ntpd[5336]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen and drop on 1 v6wildcard :: UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 2 lo 127.0.0.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 3 eth3 198.19.250.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 4 eth1.100 10.10.0.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 5 eth1.108 10.10.8.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 6 eth1.115 10.10.15.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 7 eth1.109 10.10.9.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 8 eth1.110 10.10.10.61 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 9 ppp0 178.191.200.16 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 10 tun0 10.242.2.1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listen normally on 11 lo ::1 UDP 123

    2015:01:21-11:56:32 primary-1 ntpd[5336]: peers refreshed

    2015:01:21-11:56:32 primary-1 ntpd[5336]: Listening on routing socket on fd #28 for interface updates

    2015:01:21-11:56:32 primary-2 ntpd[20438]: ntpd exiting on signal 15

    2015:01:21-11:56:32 primary-2 ntpd[21285]: ntpd 4.2.6p5@1.2349 Tue Feb  4 13:03:59 UTC 2014 (1)

    2015:01:21-11:56:32 primary-2 ntpd[21286]: proto: precision = 1.048 usec

    2015:01:21-11:56:32 primary-2 ntpd[21286]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen and drop on 1 v6wildcard :: UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 2 lo 127.0.0.1 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 3 eth3 198.19.250.2 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 4 eth1.100 10.10.0.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 5 eth1.108 10.10.8.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 6 eth1.115 10.10.15.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 7 eth1.109 10.10.9.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 8 eth1.110 10.10.10.61 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 9 ppp0 178.191.200.16 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listen normally on 10 lo ::1 UDP 123

    2015:01:21-11:56:32 primary-2 ntpd[21286]: peers refreshed

    2015:01:21-11:56:32 primary-2 ntpd[21286]: Listening on routing socket on fd #27 for interface updates

    2015:01:21-11:56:32 primary-2 ntpd[21286]: bind(28) AF_INET 0.0.0.0#123 flags 0x29 failed: Address already in use

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #9 ppp0

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #8 eth1.110

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #7 eth1.109

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #6 eth1.115

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #5 eth1.108

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #4 eth1.100

    2015:01:21-11:56:32 primary-2 ntpd[21286]: io_setbclient: Opened broadcast client on interface #3 eth3

    2015:01:21-11:56:38 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again

    2015:01:21-11:56:42 primary-1 ulogd[6683]: ct1: nl_recvmsgs: Try again


    up2date.log: 

    2015:01:21-10:56:01 primary-1 audld[29444]: running on HA master system or cluster node

    2015:01:21-10:56:01 primary-1 audld[29444]: Starting Up2Date Package Downloader

    2015:01:21-10:56:05 primary-1 audld[29444]: patch up2date possible

    2015:01:21-10:56:05 primary-1 audld[29444]: Using static update server list in HA mode

    2015:01:21-10:56:30 primary-1 audld[29444]: id="3701" severity="info" sys="system" sub="up2date" name="Authentication successful"

    2015:01:21-10:56:30 primary-1 audld[29444]: Using static download server list in HA mode

    2015:01:21-10:56:30 primary-1 audld[29444]: id="3707" severity="info" sys="system" sub="up2date" name="Successfully synchronized fileset" status="success" action="download" package="avira3"

    2015:01:21-10:56:31 primary-1 audld[29444]: id="3707" severity="info" sys="system" sub="up2date" name="Successfully synchronized fileset" status="success" action="download" package="sys"

    2015:01:21-10:56:32 primary-1 auisys[29564]: running on HA master system or cluster node

    2015:01:21-10:56:33 primary-1 auisys[29564]: not cleaning /var/up2date/sys-install in --nosys mode

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/aptp-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/avira3-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/clvbrowser-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/geoip-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/ipsbundle-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/man9-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/ohelp9-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: removing '/var/up2date/savi-install'

    2015:01:21-10:56:33 primary-1 auisys[29564]: Starting Up2Date Package Installer

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: No suitable packages of type  found, skipping

    2015:01:21-10:56:33 primary-1 auisys[29564]: Install u2d packages 

    2015:01:21-10:56:33 primary-1 auisys[29564]: Starting installing up2date packages for type 'avira3'

    2015:01:21-10:56:33 primary-1 auisys[29564]: Installing up2date package: /var/up2date/avira3/u2d-avira3-9.9206-9207.patch.tgz.gpg

    2015:01:21-10:56:33 primary-1 auisys[29564]: Verifying up2date package signature

    2015:01:21-10:56:33 primary-1 auisys[29564]: Unpacking installation instructions

    2015:01:21-10:56:33 primary-1 auisys[29564]: parsing installation instructions

    2015:01:21-10:56:33 primary-1 auisys[29564]: This is a patch. Setting required_version to 9.9206

    2015:01:21-10:56:33 primary-1 auisys[29564]: Unpacking up2date package container

    2015:01:21-10:56:33 primary-1 auisys[29564]: Running pre-installation checks

    2015:01:21-10:56:34 primary-1 auisys[29564]: Starting up2date package installation

    2015:01:21-10:56:49 primary-1 auisys[29564]: id="371Z" severity="info" sys="system" sub="up2date" name="Successfully installed Up2Date package" status="success" action="install" package_version="9.9207" package="avira3"

    2015:01:21-10:56:49 primary-1 auisys[29564]: [INFO-306] New Pattern Up2Dates installed

    2015:01:21-10:56:50 primary-1 auisys[29564]: Doing HA sync

    2015:01:21-10:56:50 primary-1 auisys[29564]: calling: 

    2015:01:21-10:56:50 primary-1 auisys[29564]: id="3720" severity="info" sys="system" sub="up2date" name="Successfully triggered up2date sync" status="success" action="sync"

    2015:01:21-10:56:50 primary-1 auisys[29564]: Up2Date Package Installer finished, exiting

    2015:01:21-10:56:50 primary-1 auisys[29564]: id="3716" severity="info" sys="system" sub="up2date" name="Up2Date Package Installer finished, exiting"


    And in high availability is nothing at the relevant time!

    Thanks,
    regards
    Herbert
Children
No Data
Unfiltered HTML