2018-10-19 06:01:56,714 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Telnet:'19.10.18 06:01:56;CALL;1;4;4XXXXXXX;0XXXXXXXXXX;SIP1;'
2018-10-19 06:01:56,714 [ Thread-4::140147691005696] [INFO ] [ call_monitor::run_logic ] Call (ID:1, ActiveCalls.:1, Caller:4XXXXXXX(ZuHause), DialedNumber:0XXXXXXXXXX(NoSuchUser), LinePort:SIP1)
2018-10-19 06:01:56,715 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] There is at least 1 active call. Send start_capture event to the CaptureMonitor.
2018-10-19 06:01:56,715 [ Thread-4::140147691005696] [DEBUG ] [ capture_monitor::start_capture ] start_capture called.
2018-10-19 06:01:56,715 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Wait for call monitor status change.
2018-10-19 06:01:56,715 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture wait() finished.
2018-10-19 06:01:56,715 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture release lock.
2018-10-19 06:01:56,716 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture release lock finished.
2018-10-19 06:01:56,716 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login attempt to the the FritzBox (box_name:192.168.178.1)
2018-10-19 06:01:56,716 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Call the challange token url (url:'http://192.168.178.1/login_sid.lua')
2018-10-19 06:01:57,148 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] SID HTTP result:200
2018-10-19 06:01:57,149 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Call the read seed token url (url:'http://192.168.178.1/login_sid.lua?username=someuser&response=bbc71146-05594c46ee8fc293e745e2466996fbc0', data:'login:command/response=bbc71146-05594c46ee8fc293e745e2466996fbc0&getpage=../html/login_sid.xml').
2018-10-19 06:01:57,428 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login HTTP result:200
2018-10-19 06:01:57,429 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login OK (SID: 2dc912c65c4614c5)
2018-10-19 06:01:57,429 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_start_capture ] data_map:{'dialed.numbername': '0XXXXXXXXXX(NoSuchUser)', 'callpartner.name': 'NoSuchUser', 'tcape': datetime.datetime(2018, 10, 18, 15, 1, 37, 827116), 'me.name': 'ZuHause', 'pbook_name.ZuHause': '4XXXXXXX', 'me.numbername': '4XXXXXXX(ZuHause)', 'tcaps': datetime.datetime(2018, 10, 19, 6, 1, 57, 429226), 'pbook_name.NoSuchUser': '0XXXXXXXXXX', 'dialed.number': '0XXXXXXXXXX', 'dialed.name': 'NoSuchUser', 'pbook_number.3096212': 'ZuHause', 'callpartner.numbername': '0XXXXXXXXXX(NoSuchUser)', 'tdisc': datetime.datetime(2018, 10, 18, 15, 1, 36, 37576), 'pbook_number.0XXXXXXXXXX': 'NoSuchUser', 'callpartner.number': '0XXXXXXXXXX', 'tcall': datetime.datetime(2018, 10, 19, 6, 1, 56, 713991), 'caller.number': '4XXXXXXX', 'tstart': datetime.datetime(2018, 10, 17, 11, 58, 26, 814034), 'caller.numbername': '4XXXXXXX(ZuHause)', 'caller.name': 'ZuHause', 'acalls.number': 1, 'todisc': time.struct_time(tm_year=2018, tm_mon=10, tm_mday=18, tm_hour=15, tm_min=1, tm_sec=36, tm_wday=3, tm_yday=291, tm_isdst=-1), 'tconn': datetime.datetime(2018, 10, 18, 13, 57, 26, 616368), 'tocall': time.struct_time(tm_year=2018, tm_mon=10, tm_mday=19, tm_hour=6, tm_min=1, tm_sec=56, tm_wday=4, tm_yday=292, tm_isdst=-1), 'callevent.name': 'CALL', 'pbook_number.4XXXXXXX': 'ZuHause', 'toconn': time.struct_time(tm_year=2018, tm_mon=10, tm_mday=18, tm_hour=13, tm_min=57, tm_sec=26, tm_wday=3, tm_yday=291, tm_isdst=-1), 'lineport.name': 'SIP1', 'me.number': '4XXXXXXX'}
2018-10-19 06:01:57,429 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_capture_file ] Initialize capture file (folder:captures/2018-10-19/060157/, file:capture_20181019060157.cap).
2018-10-19 06:01:57,429 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_capture_file ] Destination folder:'captures/2018-10-19/060157/' not exists. Create.
2018-10-19 06:01:57,430 [ Thread-3::140147682612992] [INFO ] [ capture_monitor::sub_start_capture ] Start capture (capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:01:57,430 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_start_capture ] Send capture start request to the box (url:'http://192.168.178.1/cgi-bin/capture_notimeout?start=1&start1=Start&ifaceorminor= &sid=2dc912c65c4614c5', capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:01:57,430 [ Thread-3::140147682612992] [DEBUG ] [exception_logging_thread::__init__ ] ExceptionLoggingThread().
2018-10-19 06:01:57,431 [ Thread-8::140147601438464] [DEBUG ] [ tracer::run_logic ] Trace started (url:'http://192.168.178.1/cgi-bin/capture_notimeout?start=1&start1=Start&ifaceorminor= &sid=2dc912c65c4614c5', filename:'captures/2018-10-19/060157/capture_20181019060157.cap')
2018-10-19 06:01:57,431 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_start_capture ] Send capture start request to the box finished (url:'http://192.168.178.1/cgi-bin/capture_notimeout?start=1&start1=Start&ifaceorminor= &sid=2dc912c65c4614c5', capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:01:57,431 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture acquire lock.
2018-10-19 06:01:57,431 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture acquire lock finished.
2018-10-19 06:01:57,431 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture wait().
2018-10-19 06:02:10,493 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Telnet:'19.10.18 06:02:10;CONNECT;1;4;0XXXXXXXXXX;'
2018-10-19 06:02:10,494 [ Thread-4::140147691005696] [INFO ] [ call_monitor::run_logic ] Connect (ID:1, ActiveCalls.:1, Caller:4XXXXXXX(ZuHause), DialedNumber:0XXXXXXXXXX(NoSuchUser), LinePort:SIP1)
2018-10-19 06:02:10,494 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Wait for call monitor status change.
2018-10-19 06:02:58,803 [ Thread-8::140147601438464] [DEBUG ] [ tracer::run_logic ] Could not open Trace (url:'http://192.168.178.1/cgi-bin/capture_notimeout?start=1&start1=Start&ifaceorminor= &sid=2dc912c65c4614c5', filename:'captures/2018-10-19/060157/capture_20181019060157.cap')
2018-10-19 06:09:53,840 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Telnet:'19.10.18 06:09:53;DISCONNECT;1;464;'
2018-10-19 06:09:53,840 [ Thread-4::140147691005696] [INFO ] [ call_monitor::run_logic ] Disconnect (ID:1, ActiveCalls.:0, Caller:4XXXXXXX(ZuHause), DialedNumber:0XXXXXXXXXX(NoSuchUser), LinePort:SIP1)
2018-10-19 06:09:53,840 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] There is no more active calls. Send stop_capture event to the CaptureMonitor.
2018-10-19 06:09:53,840 [ Thread-4::140147691005696] [DEBUG ] [ capture_monitor::stop_capture ] stop_capture called.
2018-10-19 06:09:53,840 [ Thread-4::140147691005696] [DEBUG ] [ call_monitor::run_logic ] Wait for call monitor status change.
2018-10-19 06:09:53,840 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture wait() finished.
2018-10-19 06:09:53,840 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture wait(0.999711).
2018-10-19 06:09:54,840 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture wait(0.999711) finished.
2018-10-19 06:09:54,841 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture release lock.
2018-10-19 06:09:54,841 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] post_capture release lock finished.
2018-10-19 06:09:54,841 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login attempt to the the FritzBox (box_name:192.168.178.1)
2018-10-19 06:09:54,841 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Call the challange token url (url:'http://192.168.178.1/login_sid.lua')
2018-10-19 06:09:55,116 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] SID HTTP result:200
2018-10-19 06:09:55,116 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Call the read seed token url (url:'http://192.168.178.1/login_sid.lua?username=someuser&response=58190781-747263f4b6693719103bdb187d2d1d03', data:'login:command/response=58190781-747263f4b6693719103bdb187d2d1d03&getpage=../html/login_sid.xml').
2018-10-19 06:09:55,386 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login HTTP result:200
2018-10-19 06:09:55,386 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::init_login ] Login OK (SID: aaef2fb7084b3cea)
2018-10-19 06:09:55,386 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_stop_capture ] Send capture stop request to the box (url:'http://192.168.178.1/cgi-bin/capture_notimeout?stop=1&stop1=Stop&ifaceorminor= &sid=aaef2fb7084b3cea', capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:09:55,507 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_stop_capture ] Send capture stop request to the box finished (url:'http://192.168.178.1/cgi-bin/capture_notimeout?stop=1&stop1=Stop&ifaceorminor= &sid=aaef2fb7084b3cea', capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:09:55,508 [ Thread-3::140147682612992] [INFO ] [ capture_monitor::sub_stop_capture ] Capture finished (capture_file:'captures/2018-10-19/060157/capture_20181019060157.cap').
2018-10-19 06:09:55,508 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::sub_stop_capture ] Add captured file 'captures/2018-10-19/060157/capture_20181019060157.cap' to the decoding work queue.
2018-10-19 06:09:55,508 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture acquire lock.
2018-10-19 06:09:55,508 [ Thread-1::140147593045760] [INFO ] [ capfile_worker::process ] Decode process started (worker_id:0, file:'captures/2018-10-19/060157/capture_20181019060157.cap')
2018-10-19 06:09:55,508 [ Thread-1::140147593045760] [ERROR ] [exception_logging_thread::run ] Exception in `run`
Traceback (most recent call last):
File "/home/fritzcap-master/core/exception_logging_thread.py", line 48, in run
self.run_logic()
File "/home/fritzcap-master/core/capfile_worker.py", line 69, in run_logic
self.process(filename)
File "/home/fritzcap-master/core/capfile_worker.py", line 79, in process
PcapParser(filename, g711.decode).parse()
File "/home/fritzcap-master/core/pcap_parse.py", line 46, in __init__
self.f = open(filename, 'rb')
IOError: [Errno 2] No such file or directory: 'captures/2018-10-19/060157/capture_20181019060157.cap'
2018-10-19 06:09:55,509 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture acquire lock finished.
2018-10-19 06:09:55,509 [ Thread-3::140147682612992] [DEBUG ] [ capture_monitor::run_logic ] pre_capture wait().