10:25:03 *** MyError.report ***
10:25:03 Stack Traceback
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/svc/svc.py', 637, '_walk', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 210, 'stop', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 194, 'cb0', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/net/net.py', 36, 'cancel', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/util/simplefile.py', 11, 'write_string_to_file', None)
10:25:03 [Errno 2] No such file or directory: '/proc/sys/net/ipv4/netfilter/ip_conntrack_tcp_be_liberal': svc/svc:637,sagent/ipts:210,sagent/ipts:194,net/net:36,util/simplefile:11 (exceptions.IOError)
10:25:03 *** MyError.report ***
10:25:03 Stack Traceback
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/defer.py', 323, '_runCallbacks', 'self.result = callback(self.result, *args, **kw)')
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 90, 'cb2', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 46, 'process_cmd_result', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/sagent_entry.py', 11, 'run_server_agent', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/util/daemon.py', 28, 'twistd_with_reactor', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/util/daemon.py', 69, 'twistd', None)
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/application/app.py', 423, 'run', 'self.postApplication()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/scripts/_twistd_unix.py', 202, 'postApplication', 'self.startReactor(None, self.oldstdout, self.oldstderr)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/application/app.py', 445, 'startReactor', 'self.config, oldstdout, oldstderr, self.profiler, reactor)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/application/app.py', 348, 'runReactorWithLogging', 'reactor.run()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/base.py', 1166, 'run', 'self.mainLoop()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/base.py', 1178, 'mainLoop', 'self.doIteration(t)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/epollreactor.py', 194, 'doPoll', 'log.callWithLogger(selectable, _drdw, selectable, fd, event)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/python/log.py', 85, 'callWithLogger', 'return callWithContext({"system": lp}, func, *args, **kw)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/python/log.py', 70, 'callWithContext', 'return context.call({ILogContext: newCtx}, func, *args, **kw)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/python/context.py', 59, 'callWithContext', 'return self.currentContext().callWithContext(ctx, func, *args, **kw)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/python/context.py', 37, 'callWithContext', 'return func(*args,**kw)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/epollreactor.py', 223, '_doReadOrWrite', 'self._disconnectSelectable(selectable, why, inRead)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/posixbase.py', 191, '_disconnectSelectable', 'selectable.connectionLost(f)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/process.py', 260, 'connectionLost', 'self.proc.childConnectionLost(self.name, reason)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/process.py', 762, 'childConnectionLost', 'self.maybeCallProcessEnded()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/process.py', 773, 'maybeCallProcessEnded', 'self.reapProcess()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/process.py', 306, 'reapProcess', 'self.processEnded(status)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/_baseprocess.py', 48, 'processEnded', 'self.maybeCallProcessEnded()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/process.py', 775, 'maybeCallProcessEnded', '_BaseProcess.maybeCallProcessEnded(self)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/_baseprocess.py', 60, 'maybeCallProcessEnded', 'proto.processEnded(Failure(reason))')
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/svc/pp.py', 117, 'processEnded', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/svc/svcnotify.py', 32, 'notify_change_state', None)
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/defer.py', 238, 'callback', 'self._startRunCallbacks(result)')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/defer.py', 307, '_startRunCallbacks', 'self._runCallbacks()')
10:25:03 ('/usr/local/openvpn_as/lib/python2.6/site-packages/Twisted-9.0.0-py2.6-linux-x86_64.egg/twisted/internet/defer.py', 323, '_runCallbacks', 'self.result = callback(self.result, *args, **kw)')
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 90, 'cb2', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/sagent/ipts.py', 46, 'process_cmd_result', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/util/error.py', 61, '__init__', None)
10:25:03 ('build/bdist.linux-x86_64/egg/pyovpn/util/error.py', 44, '__init__', None)
10:25:03 Service deferred error: IPTablesServiceBase: failed to run iptables-save [status=1]: []: internet/defer:323,sagent/ipts:90,sagent/ipts:46,sagent/sagent_entry:11,util/daemon:28,util/daemon:69,application/app:423,scripts/_twistd_unix:202,application/app:445,application/app:348,internet/base:1166,internet/base:1178,internet/epollreactor:194,python/log:85,python/log:70,python/context:59,python/context:37,internet/epollreactor:223,internet/posixbase:191,internet/process:260,internet/process:762,internet/process:773,internet/process:306,internet/_baseprocess:48,internet/process:775,internet/_baseprocess:60,svc/pp:117,svc/svcnotify:32,internet/defer:238,internet/defer:307,internet/defer:323,sagent/ipts:90,sagent/ipts:46,util/error:61,util/error:44
10:25:03 Server Agent shutting down, stop status: {'errors': {'iptables_openvpn': [('error', "[Errno 2] No such file or directory: '/proc/sys/net/ipv4/netfilter/ip_conntrack_tcp_be_liberal': svc/svc:637,sagent/ipts:210,sagent/ipts:194,net/net:36,util/simplefile:11 (exceptions.IOError)")], 'iptables_web': [('error', 'Service deferred error: IPTablesServiceBase: failed to run iptables-save [status=1]: []: internet/defer:323,sagent/ipts:90,sagent/ipts:46,sagent/sagent_entry:11,util/daemon:28,util/daemon:69,application/app:423,scripts/_twistd_unix:202,application/app:445,application/app:348,internet/base:1166,internet/base:1178,internet/epollreactor:194,python/log:85,python/log:70,python/context:59,python/context:37,internet/epollreactor:223,internet/posixbase:191,internet/process:260,internet/process:762,internet/process:773,internet/process:306,internet/_baseprocess:48,internet/process:775,internet/_baseprocess:60,svc/pp:117,svc/svcnotify:32,internet/defer:238,internet/defer:307,internet/defer:323,sagent/ipts:90,sagent/ipts:46,util/error:61,util/error:44')]}, 'service_status': {'bridge': 'off', 'openvpn_4': 'off', 'openvpn_5': 'off', 'openvpn_6': 'off', 'api': 'off', 'openvpn_0': 'off', 'openvpn_1': 'off', 'openvpn_2': 'off', 'openvpn_3': 'off', 'openvpn_8': 'off', 'openvpn_9': 'off', 'web': 'off', 'log': 'off', 'iptables_web': 'on', 'iptables_openvpn': 'on', 'iptables_live': 'off', 'db_push': 'off', 'auth': 'off', 'client_query': 'off', 'user': 'off', 'license': 'off', 'openvpn_14': 'off', 'openvpn_15': 'off', 'openvpn_12': 'off', 'openvpn_13': 'off', 'openvpn_10': 'off', 'openvpn_11': 'off', 'openvpn_7': 'off', 'crl': 'off'}, 'active_profile': 'Default'}
10:25:03 (Port None Closed)
10:25:03 (Port None Closed)
10:25:04 (Port None Closed)
10:25:04 Received SIGTERM, shutting down.
10:25:04 Main loop terminated.
10:25:04 Server Shut Down.
10:26:06 Log opened.
10:26:06 twistd 9.0.0 (/usr/local/openvpn_as/bin/python 2.6.6) starting up.
10:26:06 reactor class: twisted.internet.epollreactor.EPollReactor.
10:26:06 rmdir /usr/local/openvpn_as/etc/db_push
10:26:08 ACCESS SERVER starting, version=1.8.5
10:26:08 Max open files set to (4096, 4096)
10:26:08 /etc/resolv.conf changed, reparsing
10:26:08 Resolver added ('8.8.8.8', 53) to server list
10:26:08 Resolver added ('8.8.4.4', 53) to server list
10:26:11 PortPool: port 953: [Errno 98] Address already in use: util/sock:190,<string>:1 (socket.error)
10:26:12 twisted.web.server.Site starting on "u'/usr/local/openvpn_as/etc/sock/sagent'"
10:26:12 twisted.web.server.Site starting on "u'/usr/local/openvpn_as/etc/sock/sagent.localroot'"
10:26:12 twisted.web.server.Site starting on "u'/usr/local/openvpn_as/etc/sock/sagent.api'"
10:26:13 OpenVPNDataDir: using shared dir: '/dev/shm/openvpn_as/pso'
10:26:15 WEB OUT: 'WSERV admin <server-ip-addr> 943'
10:26:15 WEB OUT: 'WSERV client+xmlrpc <server-ip-addr> 443'
10:26:15 WEB OUT: 'WSERV admin+client 127.0.0.1 904'
10:26:15 WEB OUT: 'WSERV admin 127.0.0.1 905'
10:26:15 WEB OUT: 'WSERV client 127.0.0.1 906'
10:26:15 WEB OUT: 'WSERV xmlrpc 127.0.0.1 907'
10:26:15 WEB OUT: 'WSERV admin+client+xmlrpc 127.0.0.1 908'
10:26:15 WEB OUT: 'WSERV client+xmlrpc 127.0.0.1 909'
10:26:15 WEB OUT: ' 10:26:15 Log opened.'
10:26:15 WEB OUT: ' 10:26:15 twistd 9.0.0 (/usr/local/openvpn_as/bin/python 2.6.6) starting up.'
10:26:15 WEB OUT: ' 10:26:15 reactor class: twisted.internet.epollreactor.EPollReactor.'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 943'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 443'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 904'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 905'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 906'
10:26:15 WEB OUT: ' 10:26:15 twisted.web.server.Site starting on 907'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 908'
10:26:15 WEB OUT: ' 10:26:15 pyovpn.web.webbase.MySiteBase starting on 909'
10:26:15 WEB OUT: ' 10:26:15 set uid/gid 507/508'
10:26:15 WEB OUT: ' 10:26:15 Web server running as UID 507'
10:26:17 OpenVPNDataDir: using shared dir: '/dev/shm/openvpn_as/memstats'
10:26:17 License Info {'concurrent_connections': 2}
10:26:17 <class 'pyovpn.omi.omiauth.OMIServerAuthFactory'> starting on "u'/usr/local/openvpn_as/etc/sock/omisock-xIkJWcuTbPv8'"
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 OpenVPN 2.1.23 x86_64-unknown-linux-gnu [SSL] [LZO2] [SNAPPY] [EPOLL] built on May 2 2013'
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 MANAGEMENT: client_uid=0'
10:26:17 (Port None Closed)
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 MANAGEMENT: Connected to management server at /usr/local/openvpn_as/etc/sock/omisock-xIkJWcuTbPv8'
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'state on'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'echo on'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'bytecount 300'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'env-filter 1'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'hold off'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 MANAGEMENT: CMD 'hold release'"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 NOTE: OpenVPN 2.1 requires '--script-security 2' or higher to call user-defined scripts or executables"
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 NOTE: --fast-io is disabled since we are not using UDP'
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 Initializing OpenSSL auto engine support'
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 Diffie-Hellman initialized with 1024 bit key'
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 Control Channel Authentication: tls-auth using INLINE static key file'
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication"
10:26:17 OVPN 0 OUT: "Tue Nov 12 10:26:17 2013 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication"
10:26:17 OVPN 0 OUT: 'Tue Nov 12 10:26:17 2013 Socket Buffers: R=[87380->200000] S=[16384->200000]'
10:26:18 OVPN 0 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t0 opened'
10:26:18 OVPN 1 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t1 opened'
10:26:18 OVPN 2 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t2 opened'
10:26:18 OVPN 3 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t3 opened'
10:26:18 OVPN 4 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t4 opened'
10:26:18 OVPN 5 OUT: 'Tue Nov 12 10:26:18 2013 TUN/TAP device as0t5 opened'
10:26:19 OVPN 6 OUT: 'Tue Nov 12 10:26:19 2013 TUN/TAP device as0t6 opened'
10:26:19 OVPN 7 OUT: 'Tue Nov 12 10:26:19 2013 TUN/TAP device as0t7 opened'
10:26:19 OVPN 8 OUT: 'Tue Nov 12 10:26:19 2013 TUN/TAP device as0t8 opened'
10:26:19 OVPN 9 OUT: 'Tue Nov 12 10:26:19 2013 TUN/TAP device as0t9 opened'
10:26:20 OVPN 10 OUT: 'Tue Nov 12 10:26:20 2013 TUN/TAP device as0t10 opened'
10:26:20 OVPN 11 OUT: 'Tue Nov 12 10:26:20 2013 TUN/TAP device as0t11 opened'
10:26:20 OVPN 12 OUT: 'Tue Nov 12 10:26:20 2013 TUN/TAP device as0t12 opened'
10:26:20 OVPN 13 OUT: 'Tue Nov 12 10:26:20 2013 TUN/TAP device as0t13 opened'
10:26:21 OVPN 14 OUT: 'Tue Nov 12 10:26:21 2013 TUN/TAP device as0t14 opened'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 TUN/TAP device as0t15 opened'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 TUN/TAP TX queue length set to 200'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 MANAGEMENT: >STATE:1384251981,ASSIGN_IP,,10.0.3.241,'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 /sbin/ifconfig as0t15 10.0.3.241 netmask 255.255.255.240 mtu 1500 broadcast 10.0.3.255'
10:26:21 [OMIClientAuth,0,] Server Agent initialization status: {'errors': {'openvpn_4': [('info', u'process started successfully on 10.0.3.65')], 'openvpn_5': [('info', u'process started successfully on 10.0.3.81')], 'openvpn_6': [('info', u'process started successfully on 10.0.3.97')], 'openvpn_7': [('info', u'process started successfully on 10.0.3.113')], 'openvpn_0': [('info', u'process started successfully on 10.0.3.1')], 'openvpn_1': [('info', u'process started successfully on 10.0.3.17')], 'openvpn_2': [('info', u'process started successfully on 10.0.3.33')], 'openvpn_3': [('info', u'process started successfully on 10.0.3.49')], 'openvpn_8': [('info', u'process started successfully on 10.0.3.129')], 'openvpn_9': [('info', u'process started successfully on 10.0.3.145')], 'openvpn_14': [('info', u'process started successfully on 10.0.3.225')], 'openvpn_15': [('info', u'process started successfully on 10.0.3.241')], 'openvpn_12': [('info', u'process started successfully on 10.0.3.193')], 'openvpn_13': [('info', u'process started successfully on 10.0.3.209')], 'openvpn_10': [('info', u'process started successfully on 10.0.3.161')], 'openvpn_11': [('info', u'process started successfully on 10.0.3.177')]}, 'service_status': {'bridge': 'started', 'openvpn_4': 'started', 'openvpn_5': 'started', 'openvpn_6': 'started', 'api': 'started', 'openvpn_0': 'started', 'openvpn_1': 'started', 'openvpn_2': 'started', 'openvpn_3': 'started', 'openvpn_8': 'started', 'openvpn_9': 'started', 'web': 'started', 'log': 'started', 'iptables_web': 'started', 'iptables_openvpn': 'started', 'iptables_live': 'started', 'db_push': 'started', 'auth': 'started', 'client_query': 'started', 'user': 'started', 'license': 'started', 'openvpn_14': 'started', 'openvpn_15': 'started', 'openvpn_12': 'started', 'openvpn_13': 'started', 'openvpn_10': 'started', 'openvpn_11': 'started', 'openvpn_7': 'started', 'crl': 'started'}}
10:26:21 [OMIClientAuth,0,] Server Agent started
10:26:21 OVPN 15 OUT: "Tue Nov 12 10:26:21 2013 chroot to '/dev/shm/openvpn_as' and cd to '/' succeeded"
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 GID set to openvpn_as'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 UID set to openvpn_as'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 memstats data will be written to /memstats/memstat-jLU61gxmqbPB.dat'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 UDPv4 link local (bound): <server-ip-addr>:929'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 UDPv4 link remote: [undef]'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 MULTI: multi_init called, r=4096 v=4096'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 Initialization Sequence Completed'
10:26:21 OVPN 15 OUT: 'Tue Nov 12 10:26:21 2013 MANAGEMENT: >STATE:1384251981,CONNECTED,SUCCESS,10.0.3.241,'
10:26:21 OVPN 10 OUT: 'Tue Nov 12 10:26:21 2013 <client-ip-addr>:53589 VERIFY OK: depth=1, /CN=OpenVPN CA'
10:26:21 OVPN 10 OUT: 'Tue Nov 12 10:26:21 2013 <client-ip-addr>:53589 VERIFY OK: nsCertType=CLIENT'
10:26:21 OVPN 10 OUT: 'Tue Nov 12 10:26:21 2013 <client-ip-addr>:53589 VERIFY OK: depth=0, /CN=user5_AUTOLOGIN'
10:26:22 AUTH SUCCESS {'status': 0, 'reason': 'AuthTrue: autologin certificate auth succeeded', 'serial_list': [], 'user': u'user5', 'proplist': {u'prop_autologin': u'true', u'type': u'user_connect', u'prop_autogenerate': u'true'}, 'common_name': u'user5_AUTOLOGIN', 'serial': u'3'} cli=''/''
10:26:22 OVPN 10 OUT: "Tue Nov 12 10:26:22 2013 MANAGEMENT: CMD 'client-auth 0 0'"
10:26:22 OVPN 10 OUT: 'Tue Nov 12 10:26:22 2013 <client-ip-addr>:53589 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA'
10:26:22 OVPN 10 OUT: 'Tue Nov 12 10:26:22 2013 <client-ip-addr>:53589 [user5_AUTOLOGIN] Peer Connection Initiated with <client-ip-addr>:53589'
10:26:22 OVPN 10 OUT: 'Tue Nov 12 10:26:22 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 OPTIONS IMPORT: compression parms modified'
10:26:22 OVPN 10 OUT: 'Tue Nov 12 10:26:22 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 MULTI: Learn: 10.0.3.162 -> user5_AUTOLOGIN/<client-ip-addr>:53589'
10:26:22 OVPN 10 OUT: 'Tue Nov 12 10:26:22 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 MULTI: primary virtual IP for user5_AUTOLOGIN/<client-ip-addr>:53589: 10.0.3.162'
10:26:22 License Info {'concurrent_connections': 2}
10:26:24 OVPN 10 OUT: "Tue Nov 12 10:26:24 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 SENT CONTROL [user5_AUTOLOGIN]: 'PUSH_REPLY,explicit-exit-notify,topology subnet,route-delay 5 30,dhcp-pre-release,dhcp-renew,dhcp-release,route-metric 101,explicit-exit-notify 3,ping 12,ping-restart 50,redirect-gateway def1,redirect-gateway bypass-dhcp,redirect-gateway autolocal,route-gateway 10.0.3.161,dhcp-option DNS 208.67.222.222,dhcp-option DNS 208.67.220.220,register-dns,comp-lzo no,ifconfig 10.0.3.162 255.255.255.240' (status=1)"
10:28:37 WEB OUT: ' 10:28:37 [UDSProxyQueryProtocol,client] Logout of portal <twisted.cred.portal.Portal instance at 0x7124518>'
10:28:37 [HTTPChannel,9,] License Info {'concurrent_connections': 2}
10:28:51 [HTTPChannel,18,] License Info {'concurrent_connections': 2}
10:28:56 [HTTPChannel,28,] License Info {'concurrent_connections': 2}
10:30:55 OVPN 11 OUT: 'Tue Nov 12 10:30:55 2013 <client-ip-addr>:40533 TLS: Initial packet from <client-ip-addr>:40533, sid=fb1ba789 40520667'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 <client-ip-addr>:40533 VERIFY OK: depth=1, /CN=OpenVPN CA'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 <client-ip-addr>:40533 VERIFY OK: nsCertType=CLIENT'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 <client-ip-addr>:40533 VERIFY OK: depth=0, /CN=user5_AUTOLOGIN'
10:30:56 AUTH SUCCESS {'status': 0, 'reason': 'AuthTrue: autologin certificate auth succeeded', 'serial_list': [], 'user': u'user5', 'proplist': {u'prop_autologin': u'true', u'type': u'user_connect', u'prop_autogenerate': u'true'}, 'common_name': u'user5_AUTOLOGIN', 'serial': u'3'} cli=''/''
10:30:56 OVPN 11 OUT: "Tue Nov 12 10:30:56 2013 MANAGEMENT: CMD 'client-auth 0 0'"
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 <client-ip-addr>:40533 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 <client-ip-addr>:40533 [user5_AUTOLOGIN] Peer Connection Initiated with <client-ip-addr>:40533'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 user5_AUTOLOGIN/<client-ip-addr>:40533 OPTIONS IMPORT: compression parms modified'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 user5_AUTOLOGIN/<client-ip-addr>:40533 MULTI: Learn: 10.0.3.178 -> user5_AUTOLOGIN/<client-ip-addr>:40533'
10:30:56 OVPN 11 OUT: 'Tue Nov 12 10:30:56 2013 user5_AUTOLOGIN/<client-ip-addr>:40533 MULTI: primary virtual IP for user5_AUTOLOGIN/<client-ip-addr>:40533: 10.0.3.178'
10:30:58 OVPN 11 OUT: "Tue Nov 12 10:30:58 2013 user5_AUTOLOGIN/<client-ip-addr>:40533 SENT CONTROL [user5_AUTOLOGIN]: 'PUSH_REPLY,explicit-exit-notify,topology subnet,route-delay 5 30,dhcp-pre-release,dhcp-renew,dhcp-release,route-metric 101,explicit-exit-notify 3,ping 12,ping-restart 50,redirect-gateway def1,redirect-gateway bypass-dhcp,redirect-gateway autolocal,route-gateway 10.0.3.177,dhcp-option DNS 208.67.222.222,dhcp-option DNS 208.67.220.220,register-dns,comp-lzo no,ifconfig 10.0.3.178 255.255.255.240' (status=1)"
10:30:59 OVPN 10 OUT: 'Tue Nov 12 10:30:59 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:31:49 OVPN 10 OUT: 'Tue Nov 12 10:31:49 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:31:50 [HTTPChannel,35,] License Info {'concurrent_connections': 2}
10:32:01 OVPN 10 OUT: 'Tue Nov 12 10:32:01 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:32:02 OVPN 10 OUT: 'Tue Nov 12 10:32:02 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:32:11 OVPN 10 OUT: 'Tue Nov 12 10:32:11 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:32:14 OVPN 10 OUT: 'Tue Nov 12 10:32:14 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:32:19 [HTTPChannel,42,] License Info {'concurrent_connections': 2}
10:32:26 OVPN 10 OUT: 'Tue Nov 12 10:32:26 2013 read UDPv4 [ECONNREFUSED]: Connection refused (code=111)'
10:32:32 OVPN 10 OUT: 'Tue Nov 12 10:32:32 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 [user5_AUTOLOGIN] Inactivity timeout (--ping-restart), restarting'
10:32:32 OVPN 10 OUT: 'Tue Nov 12 10:32:32 2013 user5_AUTOLOGIN/<client-ip-addr>:53589 SIGUSR1[soft,ping-restart] received, client-instance restarting'
10:32:49 [HTTPChannel,50,] License Info {'concurrent_connections': 2}
10:32:49 OVPN 12 OUT: "Tue Nov 12 10:32:49 2013 MANAGEMENT: CMD 'status 3'"
10:32:49 OVPN 14 OUT: "Tue Nov 12 10:32:49 2013 MANAGEMENT: CMD 'status 3'"
10:32:49 OVPN 15 OUT: "Tue Nov 12 10:32:49 2013 MANAGEMENT: CMD 'status 3'"