86uDLrrn

12019-11-04T10:33:20 bixicon.service INFO init_netifaces:303[718] LTE device power init
22019-11-04T10:33:20 netconnect.service INFO connection.py:reconnect:51 Set new configuration for LAN: {'name': 'eth0', 'lan': {}, 'ipv4': {'dhcp': False, 'ip': '192.168.1.111', 'netmask': '255.255.255.0', 'gw': '192.168.1.1', 'dns': ['8.8.8.8', '8.8.4.4']}}
32019-11-04T10:33:20 netconnect.service INFO lte.py:_loop:136 Error: NO_DEVICE_DETECTED
42019-11-04T10:33:20 netconnect.service INFO connection.py:reconnect:55 Disconnecting WifiClient
52019-11-04T10:33:20 netconnect.service INFO connection.py:reconnect:51 Set new configuration for Lte: {'name': 'ppp0', 'metric': 300, 'lte': {'apn': 'free', 'number': '*99#', 'user': None, 'password': None}}
62019-11-04T10:33:20 bixicon.service INFO init:163[466] Local time: 2019-11-04 11:33:20 (UTC offset 60 min.)
72019-11-04T10:33:20 bixicon.service }
82019-11-04T10:33:20 bixicon.service }
92019-11-04T10:33:20 bixicon.service "ssid": ""
102019-11-04T10:33:20 bixicon.service "netmask": "255.255.255.0",
112019-11-04T10:33:20 bixicon.service "key": "",
122019-11-04T10:33:20 bixicon.service "ip": "192.168.4.4",
132019-11-04T10:33:20 bixicon.service "gw": "192.168.4.1",
142019-11-04T10:33:20 bixicon.service "enable": false,
152019-11-04T10:33:20 bixicon.service "dns2": "1.1.1.2",
162019-11-04T10:33:20 bixicon.service "dns1": "1.1.1.1",
172019-11-04T10:33:20 bixicon.service "dhcp": true,
182019-11-04T10:33:20 bixicon.service "wifi": {
192019-11-04T10:33:20 bixicon.service },
202019-11-04T10:33:20 bixicon.service "timezone": "Europe/Paris"
212019-11-04T10:33:20 bixicon.service "pwr_mode": "normal",
222019-11-04T10:33:20 bixicon.service "ping_server": "www.google.com",
232019-11-04T10:33:20 bixicon.service "ntp": "pool.ntp.org",
242019-11-04T10:33:20 bixicon.service "name": "Propulsion01",
252019-11-04T10:33:20 bixicon.service "fail_shot_indi": false,
262019-11-04T10:33:20 bixicon.service ],
272019-11-04T10:33:20 bixicon.service }
282019-11-04T10:33:20 bixicon.service "upload": true
292019-11-04T10:33:20 bixicon.service "time": 3600,
302019-11-04T10:33:20 bixicon.service {
312019-11-04T10:33:20 bixicon.service "cfg_update": [
322019-11-04T10:33:20 bixicon.service "cfg_timestamp": 1572799070,
332019-11-04T10:33:20 bixicon.service "bixion_server": "true",
342019-11-04T10:33:20 bixicon.service "unit": {
352019-11-04T10:33:20 bixicon.service },
362019-11-04T10:33:20 bixicon.service ]
372019-11-04T10:33:20 bixicon.service }
382019-11-04T10:33:20 bixicon.service ]
392019-11-04T10:33:20 bixicon.service "Sun"
402019-11-04T10:33:20 bixicon.service "Sat",
412019-11-04T10:33:20 bixicon.service "Fri",
422019-11-04T10:33:20 bixicon.service "Thu",
432019-11-04T10:33:20 bixicon.service "Wed",
442019-11-04T10:33:20 bixicon.service "Tue",
452019-11-04T10:33:20 bixicon.service "Mon",
462019-11-04T10:33:20 bixicon.service "wdays": [
472019-11-04T10:33:20 bixicon.service "period": 300,
482019-11-04T10:33:20 bixicon.service "ito": 86340,
492019-11-04T10:33:20 bixicon.service "ifrom": 0,
502019-11-04T10:33:20 bixicon.service {
512019-11-04T10:33:20 bixicon.service "intervals": [
522019-11-04T10:33:20 bixicon.service "time_lapse": {
532019-11-04T10:33:20 bixicon.service },
542019-11-04T10:33:20 bixicon.service "user": "JChesneau"
552019-11-04T10:33:20 bixicon.service "type": "ftp",
562019-11-04T10:33:20 bixicon.service "token": "",
572019-11-04T10:33:20 bixicon.service "target_filename": "TOT-FROZEN",
582019-11-04T10:33:20 bixicon.service "skip_raw_upload": false,
592019-11-04T10:33:20 bixicon.service "server": "90.63.157.103:21",
602019-11-04T10:33:20 bixicon.service "reupload_limit": 0,
612019-11-04T10:33:20 bixicon.service "reupload": true,
622019-11-04T10:33:20 bixicon.service "passwd": "AdminJean1234",
632019-11-04T10:33:20 bixicon.service "partial_upload": 0,
642019-11-04T10:33:20 bixicon.service "local_storage": "all",
652019-11-04T10:33:20 bixicon.service "folder": "PHOTOS-TEST",
662019-11-04T10:33:20 bixicon.service "file_format": "format3",
672019-11-04T10:33:20 bixicon.service "exif_date": false,
682019-11-04T10:33:20 bixicon.service "storage": {
692019-11-04T10:33:20 bixicon.service },
702019-11-04T10:33:20 bixicon.service "username": "Propulsion01"
712019-11-04T10:33:20 bixicon.service "ssl": false,
722019-11-04T10:33:20 bixicon.service "smtptype": "bixion",
732019-11-04T10:33:20 bixicon.service "server": "smtp.example.com",
742019-11-04T10:33:20 bixicon.service "port": 25,
752019-11-04T10:33:20 bixicon.service "password": "timelapse",
762019-11-04T10:33:20 bixicon.service "mailto": "jean.chesneau@propulsion.biz",
772019-11-04T10:33:20 bixicon.service "dailyreports": true,
782019-11-04T10:33:20 bixicon.service "mail": {
792019-11-04T10:33:20 bixicon.service },
802019-11-04T10:33:20 bixicon.service "user": null
812019-11-04T10:33:20 bixicon.service "passwd": null,
822019-11-04T10:33:20 bixicon.service "number": "*99#",
832019-11-04T10:33:20 bixicon.service "enable": true,
842019-11-04T10:33:20 bixicon.service "apn": "free",
852019-11-04T10:33:20 bixicon.service "lte": {
862019-11-04T10:33:20 bixicon.service },
872019-11-04T10:33:20 bixicon.service "netmask": "255.255.255.0"
882019-11-04T10:33:20 bixicon.service "ip": "192.168.1.111",
892019-11-04T10:33:20 bixicon.service "gw": "192.168.1.1",
902019-11-04T10:33:20 bixicon.service "dns2": "8.8.4.4",
912019-11-04T10:33:20 bixicon.service "dns1": "8.8.8.8",
922019-11-04T10:33:20 bixicon.service "dhcp": false,
932019-11-04T10:33:20 bixicon.service "lan": {
942019-11-04T10:33:20 bixicon.service },
952019-11-04T10:33:20 bixicon.service "unit_power": true
962019-11-04T10:33:20 bixicon.service "trigger": "shutter_release",
972019-11-04T10:33:20 bixicon.service "off_between_shots": false,
982019-11-04T10:33:20 bixicon.service "max_exposure": 30,
992019-11-04T10:33:20 bixicon.service "keep_imgs": true,
1002019-11-04T10:33:20 bixicon.service "camera": {
1012019-11-04T10:33:20 bixicon.service INFO init:162[466] Configuration: {
1022019-11-04T10:33:20 bixicon.service }
1032019-11-04T10:33:20 bixicon.service "sw": "3.0.51"
1042019-11-04T10:33:20 bixicon.service "hw": "3.0",
1052019-11-04T10:33:20 bixicon.service "fw": 18,
1062019-11-04T10:33:20 bixicon.service "built_at": "2019-09-21 20:49:12",
1072019-11-04T10:33:20 bixicon.service "board": "NanoPI NEO Core",
1082019-11-04T10:33:20 bixicon.service INFO init:161[466] Version {
1092019-11-04T10:33:20 bixicon.service INFO init:160[466] BIXICON main aplication started [466]
1102019-11-04T10:33:14 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
1112019-11-04T10:33:09 logger.service INFO logger.py::261 Unit status data: {"sw_ver": "3.0.51", "fw_ver": "18", "hw_ver": "3.0", "v_in": 12.26, "v_ref": 3.3, "temperature": 26.8, "humidity": 27, "system_load": "(0.74, 0.16, 0.05)", "uptime": 18, "mem_total": 493, "mem_free": 216, "uploads_ok": 1006, "uploads_err": 5, "shots_ok": 508, "shots_err": 32, "timezone": "Europe/Paris", "unit_name": "Propulsion01", "unit_mode": "normal", "rssi_lte": null, "rssi_wifi": null, "rev_lte_modem": null, "image_cache_usage": 0.0, "cpu_temp": 47.486, "ls_total": null, "ls_free": null, "ts": 1572863589.8887217}
1122019-11-04T10:33:09 netconnect.service INFO netconnect.py:write_dns:195 New nameservers set in /run/netconnect/resolv.conf: ['8.8.8.8', '8.8.4.4']
1132019-11-04T10:31:28 bixicon.service volume was not unmounted cleanly
1142019-11-04T10:31:28 bixicon.service DEBUG __timer:53[1781] Transfer started
1152019-11-04T10:31:28 bixicon.service DEBUG upload_one:197[1781] Starting upload: /tmp/bixicon_images/2019_1104_112525.JPG 5936495 bytes
1162019-11-04T10:31:28 bixicon.service INFO store_worker:114[3182] Processing (all): 2019_1104_112524.CR2
1172019-11-04T10:31:27 bixicon.service DEBUG upload_one:182[1781] Connecting to ftp://JChesneau@90.63.157.103/PHOTOS-TEST
1182019-11-04T10:31:27 bixicon.service DEBUG upload_one:168[1781] Uploading one_file() /tmp/bixicon_images/2019_1104_112525.JPG
1192019-11-04T10:31:27 bixicon.service DEBUG upload_one:221[1779] Upload finished: successfully /tmp/bixicon_images/2019_1104_112524.CR2
1202019-11-04T10:31:25 bixicon.service DEBUG __timer:56[1779] Transfered 4497408 bytes, speed 224 kB/s
1212019-11-04T10:31:05 bixicon.service DEBUG __timer:56[1779] Transfered 3899392 bytes, speed 194 kB/s
1222019-11-04T10:30:45 bixicon.service DEBUG __timer:56[1779] Transfered 4620288 bytes, speed 231 kB/s
1232019-11-04T10:30:34 bixicon.service INFO upload_worker:161[2931] Processing 1O9A9018.JPG => 2019_1104_113034.JPG (seq 508)
1242019-11-04T10:30:34 bixicon.service INFO upload_worker:161[2929] Processing 1O9A9018.CR2 => 2019_1104_113034.CR2 (seq 508)
1252019-11-04T10:30:25 bixicon.service DEBUG __timer:56[1779] Transfered 4120576 bytes, speed 206 kB/s
1262019-11-04T10:30:09 bixicon.service DEBUG shot_and_download:170[2757] Shot command: /opt/app/bixicon_3.0.51/gphoto2/bin/gphoto2 --port usb:003,002 --set-config capturetarget=1 --set-config capture=on --force-overwrite --set-config autofocusdrive=0 --set-config cancelautofocus=1 --keep --wait-event-and-download=FILEADDED --wait-event-and-download=2s
1272019-11-04T10:30:09 UNKNOWN usb 3-1: reset high-speed USB device number 2 using ehci-platform
1282019-11-04T10:30:05 bixicon.service DEBUG __timer:56[1779] Transfered 3694592 bytes, speed 184 kB/s
1292019-11-04T10:30:05 bixicon.service INFO shot_worker:254[2757] Camera detected {'model': 'Canon EOS 5DS', 'bus': '003', 'dev': '002'}
1302019-11-04T10:30:02 bixicon.service DEBUG wait_for_ready:57[2757] Waiting for camera ready 5 sec.
1312019-11-04T10:30:01 bixicon.service DEBUG cam_detect:190[2757] Waking camera up via SR
1322019-11-04T10:30:00 bixicon.service INFO normal_mode:404[458] Next action ['SHOT'] in 0:05:00 seconds
1332019-11-04T10:30:00 bixicon.service INFO shot_worker:236[2757] Running shot via shutter_release
1342019-11-04T10:29:45 bixicon.service DEBUG __timer:56[1779] Transfered 3252224 bytes, speed 162 kB/s
1352019-11-04T10:29:25 bixicon.service DEBUG __timer:56[1779] Transfered 3284992 bytes, speed 164 kB/s
1362019-11-04T10:29:14 logger.service INFO logger.py::261 Unit status data: {"sw_ver": "3.0.51", "fw_ver": "18", "hw_ver": "3.0", "v_in": 12.29, "v_ref": 3.29, "temperature": 26.0, "humidity": 27, "system_load": "(0.24, 0.32, 0.18)", "uptime": 319, "mem_total": 493, "mem_free": 19, "uploads_ok": 1005, "uploads_err": 5, "shots_ok": 507, "shots_err": 32, "timezone": "Europe/Paris", "unit_name": "Propulsion01", "unit_mode": "normal", "rssi_lte": -113, "rssi_wifi": null, "rev_lte_modem": "EG25GGBR07A07M2G", "image_cache_usage": 68.89373588562012, "cpu_temp": 31.492, "ls_total": null, "ls_free": null, "ts": 1572863354.738433}
1372019-11-04T10:29:05 bixicon.service DEBUG __timer:56[1779] Transfered 3530752 bytes, speed 176 kB/s
1382019-11-04T10:28:45 bixicon.service DEBUG __timer:56[1779] Transfered 3842048 bytes, speed 192 kB/s
1392019-11-04T10:28:25 bixicon.service DEBUG __timer:56[1779] Transfered 2105344 bytes, speed 105 kB/s
1402019-11-04T10:28:05 bixicon.service DEBUG __timer:56[1779] Transfered 3612672 bytes, speed 180 kB/s
1412019-11-04T10:27:45 bixicon.service DEBUG __timer:56[1779] Transfered 2949120 bytes, speed 147 kB/s
1422019-11-04T10:27:25 bixicon.service DEBUG __timer:56[1779] Transfered 2932736 bytes, speed 146 kB/s
1432019-11-04T10:27:05 bixicon.service DEBUG __timer:56[1779] Transfered 3883008 bytes, speed 194 kB/s
1442019-11-04T10:26:45 bixicon.service DEBUG __timer:56[1779] Transfered 3817472 bytes, speed 190 kB/s
1452019-11-04T10:26:25 bixicon.service DEBUG __timer:56[1779] Transfered 3751936 bytes, speed 187 kB/s
1462019-11-04T10:26:05 bixicon.service DEBUG __timer:56[1779] Transfered 3956736 bytes, speed 197 kB/s
1472019-11-04T10:25:45 bixicon.service DEBUG __timer:56[1779] Transfered 3932160 bytes, speed 196 kB/s
1482019-11-04T10:25:25 bixicon.service DEBUG __timer:53[1779] Transfer started
1492019-11-04T10:25:25 bixicon.service DEBUG upload_one:197[1779] Starting upload: /tmp/bixicon_images/2019_1104_112524.CR2 66303823 bytes
1502019-11-04T10:25:25 bixicon.service INFO upload_worker:161[1781] Processing 1O9A9017.JPG => 2019_1104_112525.JPG (seq 507)
1512019-11-04T10:25:25 bixicon.service DEBUG upload_one:182[1779] Connecting to ftp://JChesneau@90.63.157.103/PHOTOS-TEST
1522019-11-04T10:25:25 bixicon.service DEBUG upload_one:168[1779] Uploading one_file() /tmp/bixicon_images/2019_1104_112524.CR2
1532019-11-04T10:25:25 bixicon.service INFO upload_worker:161[1779] Processing 1O9A9017.CR2 => 2019_1104_112524.CR2 (seq 507)
1542019-11-04T10:25:07 systemd-networkd.service tap0: Gained IPv6LL
1552019-11-04T10:25:07 bixicon.service DEBUG shot_and_download:170[1566] Shot command: /opt/app/bixicon_3.0.51/gphoto2/bin/gphoto2 --port usb:003,002 --set-config capturetarget=1 --set-config capture=on --force-overwrite --set-config autofocusdrive=0 --set-config cancelautofocus=1 --keep --wait-event-and-download=FILEADDED --wait-event-and-download=2s
1562019-11-04T10:25:07 UNKNOWN usb 3-1: reset high-speed USB device number 2 using ehci-platform
1572019-11-04T10:25:06 openvpn@bixi-vpn.service Initialization Sequence Completed
1582019-11-04T10:25:06 openvpn@bixi-vpn.service /sbin/ip addr add dev tap0 198.18.2.162/20 broadcast 198.18.15.255
1592019-11-04T10:25:06 systemd-networkd.service tap0: Gained carrier
1602019-11-04T10:25:06 systemd-udevd.service Using default interface naming scheme 'v240'.
1612019-11-04T10:25:06 openvpn@bixi-vpn.service /sbin/ip link set dev tap0 up mtu 1500
1622019-11-04T10:25:06 openvpn@bixi-vpn.service do_ifconfig, tt->did_ifconfig_ipv6_setup=0
1632019-11-04T10:25:06 openvpn@bixi-vpn.service TUN/TAP TX queue length set to 100
1642019-11-04T10:25:06 openvpn@bixi-vpn.service TUN/TAP device tap0 opened
1652019-11-04T10:25:06 openvpn@bixi-vpn.service Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
1662019-11-04T10:25:06 openvpn@bixi-vpn.service Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
1672019-11-04T10:25:06 openvpn@bixi-vpn.service Data Channel: using negotiated cipher 'AES-256-GCM'
1682019-11-04T10:25:06 openvpn@bixi-vpn.service OPTIONS IMPORT: data channel crypto options modified
1692019-11-04T10:25:06 openvpn@bixi-vpn.service OPTIONS IMPORT: adjusting link_mtu to 1657
1702019-11-04T10:25:06 openvpn@bixi-vpn.service OPTIONS IMPORT: peer-id set
1712019-11-04T10:25:06 openvpn@bixi-vpn.service OPTIONS IMPORT: --ifconfig/up options modified
1722019-11-04T10:25:06 openvpn@bixi-vpn.service OPTIONS IMPORT: timers and/or timeouts modified
1732019-11-04T10:25:06 openvpn@bixi-vpn.service PUSH: Received control message: 'PUSH_REPLY,ping 10,ping-restart 60,ifconfig 198.18.2.162 255.255.240.0,peer-id 6,cipher AES-256-GCM'
1742019-11-04T10:25:06 openvpn@bixi-vpn.service SENT CONTROL [vpn.bixion.com]: 'PUSH_REQUEST' (status=1)
1752019-11-04T10:25:05 openvpn@bixi-vpn.service [vpn.bixion.com] Peer Connection Initiated with [AF_INET]188.166.95.100:5003
1762019-11-04T10:25:05 openvpn@bixi-vpn.service Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
1772019-11-04T10:25:05 openvpn@bixi-vpn.service VERIFY OK: depth=0, C=CZ, ST=Czech Republic, L=Brno, O=BIXION s.r.o., CN=vpn.bixion.com, emailAddress=info@bixion.com
1782019-11-04T10:25:05 openvpn@bixi-vpn.service VERIFY OK: nsCertType=SERVER
1792019-11-04T10:25:05 openvpn@bixi-vpn.service VERIFY OK: depth=1, C=CZ, ST=Czech Republic, L=Brno, O=BIXION s.r.o, CN=BIXION Certificate authority, emailAddress=info@bixion.com
1802019-11-04T10:25:05 openvpn@bixi-vpn.service TLS: Initial packet from [AF_INET]188.166.95.100:5003, sid=ff72347c 30867951
1812019-11-04T10:25:05 openvpn@bixi-vpn.service UDP link remote: [AF_INET]188.166.95.100:5003
1822019-11-04T10:25:05 openvpn@bixi-vpn.service UDP link local (bound): [AF_INET][undef]:5003
1832019-11-04T10:25:05 openvpn@bixi-vpn.service Socket Buffers: R=[163840->163840] S=[163840->163840]
1842019-11-04T10:25:05 openvpn@bixi-vpn.service TCP/UDP: Preserving recently used remote address: [AF_INET]188.166.95.100:5003
1852019-11-04T10:25:05 bixicon.service INFO init_netifaces:326[711] Network device ready
1862019-11-04T10:25:05 openvpn@bixi-vpn.service WARNING: --ns-cert-type is DEPRECATED.  Use --remote-cert-tls instead.
1872019-11-04T10:25:04 bixicon.service INFO shot_worker:254[1566] Camera detected {'model': 'Canon EOS 5DS', 'bus': '003', 'dev': '002'}
1882019-11-04T10:25:01 bixicon.service DEBUG wait_for_ready:57[1566] Waiting for camera ready 5 sec.
1892019-11-04T10:25:00 bixicon.service INFO normal_mode:404[458] Next action ['SHOT'] in 0:05:00 seconds
1902019-11-04T10:25:00 bixicon.service WARNING normal_mode:417[458] Shot process already in progress
1912019-11-04T10:24:59 bixicon.service DEBUG cam_detect:190[1566] Waking camera up via SR
1922019-11-04T10:24:59 bixicon.service INFO normal_mode:404[458] Next action ['SHOT'] in 0:00:01 seconds
1932019-11-04T10:24:59 bixicon.service INFO shot_worker:236[1566] Running shot via shutter_release
1942019-11-04T10:24:59 base_timesync.service INFO process_offset():84 Making timestep of -1.072959e+00 s
1952019-11-04T10:24:55 netconnect.service INFO netconnect.py:_loop:133 Online
1962019-11-04T10:24:55 gwtran.service DEBUG on_subscribe():92 Subscribed with qos (0,)
1972019-11-04T10:24:55 gwtran.service INFO on_connect():85 Connected
1982019-11-04T10:24:54 netconnect.service INFO lte.py:_loop:141 Signal: {'rssi': -113, 'ber': '99', 'level': (0, 3)}
1992019-11-04T10:24:54 netconnect.service INFO lte.py:_loop:140 Network info: {'registered': True, 'sim_ready': True, 'access_technology': 'FDD LTE', 'operator': '20815', 'band': 'LTE BAND 28', 'channel': '3350'}
2002019-11-04T10:24:54 netconnect.service INFO netconnect.py:_loop:133 Online
2012019-11-04T10:24:54 netconnect.service INFO lte.py:_loop:111 Signal: {'rssi': -113, 'ber': '99', 'level': (0, 3)}
2022019-11-04T10:24:54 netconnect.service INFO lte.py:_loop:110 Network info: {'registered': True, 'sim_ready': True, 'access_technology': 'FDD LTE', 'operator': '20815', 'band': 'LTE BAND 28', 'channel': '3350'}
2032019-11-04T10:24:54 netconnect.service INFO lte.py:_loop:109 Link layer of LTE connected
2042019-11-04T10:24:53 netconnect.service INFO netconnect.py:write_dns:195 New nameservers set in /run/netconnect/resolv.conf: ['212.27.40.240', '212.27.40.241']
2052019-11-04T10:24:53 netconnect.service secondary DNS address 212.27.40.241
2062019-11-04T10:24:53 netconnect.service primary   DNS address 212.27.40.240
2072019-11-04T10:24:53 netconnect.service remote IP address 10.64.64.64
2082019-11-04T10:24:53 netconnect.service local  IP address 10.19.31.32
2092019-11-04T10:24:53 netconnect.service Could not determine remote IP address: defaulting to 10.64.64.64
2102019-11-04T10:24:53 systemd-networkd.service ppp0: Gained carrier
2112019-11-04T10:24:53 UNKNOWN PPP Deflate Compression module registered
2122019-11-04T10:24:53 UNKNOWN PPP BSD Compression module registered
2132019-11-04T10:24:53 netconnect.service PAP authentication succeeded
2142019-11-04T10:24:52 netconnect.service Connect: ppp0 <--> /dev/ttyUSB2
2152019-11-04T10:24:52 netconnect.service Using interface ppp0
2162019-11-04T10:24:52 netconnect.service Serial connection established.
2172019-11-04T10:24:52 netconnect.service send (^M)
2182019-11-04T10:24:52 netconnect.service -- got it
2192019-11-04T10:24:52 netconnect.service CONNECT
2202019-11-04T10:24:52 netconnect.service ATDT*99#^M^M
2212019-11-04T10:24:52 netconnect.service ^M
2222019-11-04T10:24:52 netconnect.service expect (CONNECT)
2232019-11-04T10:24:52 netconnect.service timeout set to 30 seconds
2242019-11-04T10:24:52 netconnect.service send (ATDT*99#^M)
2252019-11-04T10:24:52 netconnect.service -- got it
2262019-11-04T10:24:52 netconnect.service OK
2272019-11-04T10:24:52 netconnect.service AT+CGDCONT=1,"IP","free"^M^M
2282019-11-04T10:24:52 netconnect.service ^M
2292019-11-04T10:24:52 netconnect.service expect (OK)
2302019-11-04T10:24:51 netconnect.service send (AT+CGDCONT=1,"IP","free"^M)
2312019-11-04T10:24:51 netconnect.service -- got it
2322019-11-04T10:24:51 netconnect.service OK
2332019-11-04T10:24:51 netconnect.service ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0^M^M
2342019-11-04T10:24:51 netconnect.service ^M
2352019-11-04T10:24:51 netconnect.service expect (OK)
2362019-11-04T10:24:51 netconnect.service send (ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0^M)
2372019-11-04T10:24:51 netconnect.service -- got it
2382019-11-04T10:24:51 netconnect.service OK
2392019-11-04T10:24:51 netconnect.service AT+CFUN=1^M^M
2402019-11-04T10:24:51 netconnect.service ^M
2412019-11-04T10:24:51 netconnect.service expect (OK)
2422019-11-04T10:24:51 netconnect.service send (AT+CFUN=1^M)
2432019-11-04T10:24:51 netconnect.service -- got it
2442019-11-04T10:24:51 netconnect.service OK
2452019-11-04T10:24:51 netconnect.service ATZ^M^M
2462019-11-04T10:24:51 netconnect.service ^M
2472019-11-04T10:24:51 netconnect.service expect (OK)
2482019-11-04T10:24:51 netconnect.service send (ATZ^M)
2492019-11-04T10:24:51 netconnect.service -- got it
2502019-11-04T10:24:51 netconnect.service OK
2512019-11-04T10:24:51 netconnect.service ^M
2522019-11-04T10:24:51 netconnect.service Revision: EG25GGBR07A07M2G^M
2532019-11-04T10:24:51 netconnect.service EG25^M
2542019-11-04T10:24:51 netconnect.service Quectel^M
2552019-11-04T10:24:51 netconnect.service ATI^M^M
2562019-11-04T10:24:51 netconnect.service ^M
2572019-11-04T10:24:51 netconnect.service expect (OK)
2582019-11-04T10:24:51 netconnect.service send (ATI^M)
2592019-11-04T10:24:51 netconnect.service -- got it
2602019-11-04T10:24:51 netconnect.service OK
2612019-11-04T10:24:51 netconnect.service AT^M^M
2622019-11-04T10:24:51 netconnect.service expect (OK)
2632019-11-04T10:24:51 netconnect.service send (AT^M)
2642019-11-04T10:24:51 netconnect.service alarm
2652019-11-04T10:24:49 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
2662019-11-04T10:24:48 netconnect.service OK^M
2672019-11-04T10:24:48 netconnect.service ATZ^M^M
2682019-11-04T10:24:48 netconnect.service ^M
2692019-11-04T10:24:48 netconnect.service expect (OKd)
2702019-11-04T10:24:48 netconnect.service timeout set to 3 seconds
2712019-11-04T10:24:48 netconnect.service send (ATZ^M)
2722019-11-04T10:24:48 netconnect.service -- got it
2732019-11-04T10:24:48 netconnect.service OK
2742019-11-04T10:24:48 netconnect.service ATQ0^M^M
2752019-11-04T10:24:48 netconnect.service expect (OK)
2762019-11-04T10:24:48 netconnect.service send (ATQ0^M)
2772019-11-04T10:24:48 netconnect.service timeout set to 6 seconds
2782019-11-04T10:24:48 netconnect.service report (CONNECT)
2792019-11-04T10:24:48 netconnect.service abort on (DELAYED)
2802019-11-04T10:24:48 netconnect.service abort on (NO ANSWER)
2812019-11-04T10:24:48 netconnect.service abort on (NO DIAL TONE)
2822019-11-04T10:24:48 netconnect.service abort on (NO DIALTONE)
2832019-11-04T10:24:48 netconnect.service abort on (VOICE)
2842019-11-04T10:24:48 netconnect.service abort on (NO CARRIER)
2852019-11-04T10:24:48 netconnect.service abort on (BUSY)
2862019-11-04T10:24:47 netconnect.service pppd 2.4.7 started by root, uid 0
2872019-11-04T10:24:47 UNKNOWN PPP generic driver version 2.4.2
2882019-11-04T10:24:39 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
2892019-11-04T10:24:38 systemd-udevd.service Using default interface naming scheme 'v240'.
2902019-11-04T10:24:38 UNKNOWN usb 4-1: GSM modem (1-port) converter now attached to ttyUSB3
2912019-11-04T10:24:38 UNKNOWN option 4-1:1.3: GSM modem (1-port) converter detected
2922019-11-04T10:24:38 UNKNOWN usb 4-1: GSM modem (1-port) converter now attached to ttyUSB2
2932019-11-04T10:24:38 UNKNOWN option 4-1:1.2: GSM modem (1-port) converter detected
2942019-11-04T10:24:38 UNKNOWN usb 4-1: GSM modem (1-port) converter now attached to ttyUSB1
2952019-11-04T10:24:38 UNKNOWN option 4-1:1.1: GSM modem (1-port) converter detected
2962019-11-04T10:24:37 UNKNOWN usb 4-1: GSM modem (1-port) converter now attached to ttyUSB0
2972019-11-04T10:24:37 UNKNOWN option 4-1:1.0: GSM modem (1-port) converter detected
2982019-11-04T10:24:37 UNKNOWN usbcore: registered new interface driver qmi_wwan
2992019-11-04T10:24:37 UNKNOWN qmi_wwan 4-1:1.4 wwan0: register 'qmi_wwan' at usb-1c1c000.usb-1, WWAN/QMI device, fe:cf:40:f5:9d:9b
3002019-11-04T10:24:37 UNKNOWN qmi_wwan 4-1:1.4: cdc-wdm0: USB WDM device
3012019-11-04T10:24:37 UNKNOWN usbserial: USB Serial support registered for GSM modem (1-port)
3022019-11-04T10:24:37 UNKNOWN usbcore: registered new interface driver option
3032019-11-04T10:24:37 UNKNOWN usb 4-1: Manufacturer: Quectel
3042019-11-04T10:24:37 UNKNOWN usb 4-1: Product: EG25-G
3052019-11-04T10:24:37 UNKNOWN usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
3062019-11-04T10:24:37 UNKNOWN usb 4-1: New USB device found, idVendor=2c7c, idProduct=0125
3072019-11-04T10:24:37 UNKNOWN usb 4-1: new high-speed USB device number 2 using ehci-platform
3082019-11-04T10:24:36 openvpn@bixi-vpn.service Restart pause, 30 second(s)
3092019-11-04T10:24:36 openvpn@bixi-vpn.service SIGUSR1[soft,init_instance] received, process restarting
3102019-11-04T10:24:36 openvpn@bixi-vpn.service Could not determine IPv4/IPv6 protocol
3112019-11-04T10:24:36 openvpn@bixi-vpn.service RESOLVE: Cannot resolve host address: vpn.bixion.com:5003 (Temporary failure in name resolution)
3122019-11-04T10:24:36 openvpn@bixi-vpn.service RESOLVE: Cannot resolve host address: vpn.bixion.com:5003 (Temporary failure in name resolution)
3132019-11-04T10:24:36 openvpn@bixi-vpn.service WARNING: --ns-cert-type is DEPRECATED.  Use --remote-cert-tls instead.
3142019-11-04T10:24:35 bixicon.service INFO normal_mode:404[458] Next action ['SHOT'] in 0:00:25 seconds
3152019-11-04T10:24:35 bixicon.service INFO normal_mode:381[458] Startup setup None
3162019-11-04T10:24:34 systemd-fsckd.service systemd-fsckd.service: Succeeded.
3172019-11-04T10:24:32 api.service INFO _log:122[453]  * Running on http://0.0.0.0:80/ (Press CTRL+C to quit)
3182019-11-04T10:24:32 api.service INFO _log:122[453]  * Running on http://0.0.0.0:80/ (Press CTRL+C to quit)
3192019-11-04T10:24:32 api.service * Debug mode: off
3202019-11-04T10:24:32 api.service Use a production WSGI server instead.
3212019-11-04T10:24:32 api.service WARNING: This is a development server. Do not use it in a production deployment.
3222019-11-04T10:24:32 api.service * Environment: production
3232019-11-04T10:24:32 api.service * Serving Flask app "api" (lazy loading)
3242019-11-04T10:24:29 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
3252019-11-04T10:24:26 UNKNOWN IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
3262019-11-04T10:24:26 UNKNOWN dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW
3272019-11-04T10:24:26 UNKNOWN dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available
3282019-11-04T10:24:26 UNKNOWN Generic PHY stmmac-0:01: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:01, irq=POLL)
3292019-11-04T10:24:26 netconnect.service INFO lan.py:_loop:34 Created systemd-networkd configuration for eth0 (02:81:3f:1e:6e:80)
3302019-11-04T10:24:26 systemd-networkd.service Started Network Service.
3312019-11-04T10:24:26 systemd-networkd.service Enumeration completed
3322019-11-04T10:24:26 systemd-networkd.service /run/systemd/network/netconnect_LAN.network:5: Unknown lvalue 'Metric' in section 'Network'
3332019-11-04T10:24:26 systemd-networkd.service Starting Network Service...
3342019-11-04T10:24:25 bixicon.service INFO init_netifaces:303[711] LTE device power init
3352019-11-04T10:24:25 netconnect.service INFO connection.py:reconnect:51 Set new configuration for LAN: {'name': 'eth0', 'lan': {}, 'ipv4': {'dhcp': False, 'ip': '192.168.1.111', 'netmask': '255.255.255.0', 'gw': '192.168.1.1', 'dns': ['8.8.8.8', '8.8.4.4']}}
3362019-11-04T10:24:25 netconnect.service INFO lte.py:_loop:136 Error: NO_DEVICE_DETECTED
3372019-11-04T10:24:25 netconnect.service INFO connection.py:reconnect:55 Disconnecting WifiClient
3382019-11-04T10:24:25 netconnect.service INFO connection.py:reconnect:51 Set new configuration for Lte: {'name': 'ppp0', 'metric': 300, 'lte': {'apn': 'free', 'number': '*99#', 'user': None, 'password': None}}
3392019-11-04T10:24:25 bixicon.service INFO init:163[458] Local time: 2019-11-04 11:24:25 (UTC offset 60 min.)
3402019-11-04T10:24:25 bixicon.service }
3412019-11-04T10:24:25 bixicon.service }
3422019-11-04T10:24:25 bixicon.service "ssid": ""
3432019-11-04T10:24:25 bixicon.service "netmask": "255.255.255.0",
3442019-11-04T10:24:25 bixicon.service "key": "",
3452019-11-04T10:24:25 bixicon.service "ip": "192.168.4.4",
3462019-11-04T10:24:25 bixicon.service "gw": "192.168.4.1",
3472019-11-04T10:24:25 bixicon.service "enable": false,
3482019-11-04T10:24:25 bixicon.service "dns2": "1.1.1.2",
3492019-11-04T10:24:25 bixicon.service "dns1": "1.1.1.1",
3502019-11-04T10:24:25 bixicon.service "dhcp": true,
3512019-11-04T10:24:25 bixicon.service "wifi": {
3522019-11-04T10:24:25 bixicon.service },
3532019-11-04T10:24:25 bixicon.service "timezone": "Europe/Paris"
3542019-11-04T10:24:25 bixicon.service "pwr_mode": "normal",
3552019-11-04T10:24:25 bixicon.service "ping_server": "www.google.com",
3562019-11-04T10:24:25 bixicon.service "ntp": "pool.ntp.org",
3572019-11-04T10:24:25 bixicon.service "name": "Propulsion01",
3582019-11-04T10:24:25 bixicon.service "fail_shot_indi": false,
3592019-11-04T10:24:25 bixicon.service ],
3602019-11-04T10:24:25 bixicon.service }
3612019-11-04T10:24:25 bixicon.service "upload": true
3622019-11-04T10:24:25 bixicon.service "time": 3600,
3632019-11-04T10:24:25 bixicon.service {
3642019-11-04T10:24:25 bixicon.service "cfg_update": [
3652019-11-04T10:24:25 bixicon.service "cfg_timestamp": 1572799070,
3662019-11-04T10:24:25 bixicon.service "bixion_server": "true",
3672019-11-04T10:24:25 bixicon.service "unit": {
3682019-11-04T10:24:25 bixicon.service },
3692019-11-04T10:24:25 bixicon.service ]
3702019-11-04T10:24:25 bixicon.service }
3712019-11-04T10:24:25 bixicon.service ]
3722019-11-04T10:24:25 bixicon.service "Sun"
3732019-11-04T10:24:25 bixicon.service "Sat",
3742019-11-04T10:24:25 bixicon.service "Fri",
3752019-11-04T10:24:25 bixicon.service "Thu",
3762019-11-04T10:24:25 bixicon.service "Wed",
3772019-11-04T10:24:25 bixicon.service "Tue",
3782019-11-04T10:24:25 bixicon.service "Mon",
3792019-11-04T10:24:25 bixicon.service "wdays": [
3802019-11-04T10:24:25 bixicon.service "period": 300,
3812019-11-04T10:24:25 bixicon.service "ito": 86340,
3822019-11-04T10:24:25 bixicon.service "ifrom": 0,
3832019-11-04T10:24:25 bixicon.service {
3842019-11-04T10:24:25 bixicon.service "intervals": [
3852019-11-04T10:24:25 bixicon.service "time_lapse": {
3862019-11-04T10:24:25 bixicon.service },
3872019-11-04T10:24:25 bixicon.service "user": "JChesneau"
3882019-11-04T10:24:25 bixicon.service "type": "ftp",
3892019-11-04T10:24:25 bixicon.service "token": "",
3902019-11-04T10:24:25 bixicon.service "target_filename": "TOT-FROZEN",
3912019-11-04T10:24:25 bixicon.service "skip_raw_upload": false,
3922019-11-04T10:24:25 bixicon.service "server": "90.63.157.103:21",
3932019-11-04T10:24:25 bixicon.service "reupload_limit": 0,
3942019-11-04T10:24:25 bixicon.service "reupload": true,
3952019-11-04T10:24:25 bixicon.service "passwd": "AdminJean1234",
3962019-11-04T10:24:25 bixicon.service "partial_upload": 0,
3972019-11-04T10:24:25 bixicon.service "local_storage": "all",
3982019-11-04T10:24:25 bixicon.service "folder": "PHOTOS-TEST",
3992019-11-04T10:24:25 bixicon.service "file_format": "format3",
4002019-11-04T10:24:25 bixicon.service "exif_date": false,
4012019-11-04T10:24:25 bixicon.service "storage": {
4022019-11-04T10:24:25 bixicon.service },
4032019-11-04T10:24:25 bixicon.service "username": "Propulsion01"
4042019-11-04T10:24:25 bixicon.service "ssl": false,
4052019-11-04T10:24:25 bixicon.service "smtptype": "bixion",
4062019-11-04T10:24:25 bixicon.service "server": "smtp.example.com",
4072019-11-04T10:24:25 bixicon.service "port": 25,
4082019-11-04T10:24:25 bixicon.service "password": "timelapse",
4092019-11-04T10:24:25 bixicon.service "mailto": "jean.chesneau@propulsion.biz",
4102019-11-04T10:24:25 bixicon.service "dailyreports": true,
4112019-11-04T10:24:25 bixicon.service "mail": {
4122019-11-04T10:24:25 bixicon.service },
4132019-11-04T10:24:25 bixicon.service "user": null
4142019-11-04T10:24:25 bixicon.service "passwd": null,
4152019-11-04T10:24:25 bixicon.service "number": "*99#",
4162019-11-04T10:24:25 bixicon.service "enable": true,
4172019-11-04T10:24:25 bixicon.service "apn": "free",
4182019-11-04T10:24:25 bixicon.service "lte": {
4192019-11-04T10:24:25 bixicon.service },
4202019-11-04T10:24:25 bixicon.service "netmask": "255.255.255.0"
4212019-11-04T10:24:25 bixicon.service "ip": "192.168.1.111",
4222019-11-04T10:24:25 bixicon.service "gw": "192.168.1.1",
4232019-11-04T10:24:25 bixicon.service "dns2": "8.8.4.4",
4242019-11-04T10:24:25 bixicon.service "dns1": "8.8.8.8",
4252019-11-04T10:24:25 bixicon.service "dhcp": false,
4262019-11-04T10:24:25 bixicon.service "lan": {
4272019-11-04T10:24:25 bixicon.service },
4282019-11-04T10:24:25 bixicon.service "unit_power": true
4292019-11-04T10:24:25 bixicon.service "trigger": "shutter_release",
4302019-11-04T10:24:25 bixicon.service "off_between_shots": false,
4312019-11-04T10:24:25 bixicon.service "max_exposure": 30,
4322019-11-04T10:24:25 bixicon.service "keep_imgs": true,
4332019-11-04T10:24:25 bixicon.service "camera": {
4342019-11-04T10:24:25 bixicon.service INFO init:162[458] Configuration: {
4352019-11-04T10:24:25 bixicon.service }
4362019-11-04T10:24:25 bixicon.service "sw": "3.0.51"
4372019-11-04T10:24:25 bixicon.service "hw": "3.0",
4382019-11-04T10:24:25 bixicon.service "fw": 18,
4392019-11-04T10:24:25 bixicon.service "built_at": "2019-09-21 20:49:12",
4402019-11-04T10:24:25 bixicon.service "board": "NanoPI NEO Core",
4412019-11-04T10:24:25 bixicon.service INFO init:161[458] Version {
4422019-11-04T10:24:25 bixicon.service INFO init:160[458] BIXICON main aplication started [458]
4432019-11-04T10:24:19 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
4442019-11-04T10:24:15 netconnect.service INFO netconnect.py:write_dns:195 New nameservers set in /run/netconnect/resolv.conf: ['8.8.8.8', '8.8.4.4']
4452019-11-04T10:24:14 logger.service INFO logger.py::261 Unit status data: {"sw_ver": "3.0.51", "fw_ver": "18", "hw_ver": "3.0", "v_in": 12.26, "v_ref": 3.3, "temperature": 25.9, "humidity": 28, "system_load": "(0.87, 0.2, 0.06)", "uptime": 18, "mem_total": 493, "mem_free": 223, "uploads_ok": 1005, "uploads_err": 5, "shots_ok": 506, "shots_err": 32, "timezone": "Europe/Paris", "unit_name": "Propulsion01", "unit_mode": "normal", "rssi_lte": null, "rssi_wifi": null, "rev_lte_modem": null, "image_cache_usage": 0.0, "cpu_temp": 46.154, "ls_total": null, "ls_free": null, "ts": 1572863054.9542665}
4462019-11-04T10:22:30 bixicon.service volume was not unmounted cleanly
4472019-11-04T10:22:30 bixicon.service INFO store_worker:114[3918] Processing (all): 2019_1104_111528.CR2
4482019-11-04T10:22:29 bixicon.service DEBUG upload_one:182[2253] Connecting to ftp://JChesneau@90.63.157.103/PHOTOS-TEST
4492019-11-04T10:22:29 bixicon.service DEBUG upload_one:168[2253] Uploading one_file() /tmp/bixicon_images/2019_1104_111528.JPG
4502019-11-04T10:22:29 bixicon.service DEBUG upload_one:221[2251] Upload finished: successfully /tmp/bixicon_images/2019_1104_111528.CR2
4512019-11-04T10:22:28 bixicon.service DEBUG __timer:56[2251] Transfered 3014656 bytes, speed 150 kB/s
4522019-11-04T10:22:08 bixicon.service DEBUG __timer:56[2251] Transfered 4153344 bytes, speed 207 kB/s
4532019-11-04T10:21:48 bixicon.service DEBUG __timer:56[2251] Transfered 4276224 bytes, speed 213 kB/s
4542019-11-04T10:21:43 base_timesync.service DEBUG process_offset():88 Offset: -1.069939e-01
4552019-11-04T10:21:28 bixicon.service DEBUG __timer:56[2251] Transfered 3342336 bytes, speed 167 kB/s
4562019-11-04T10:21:20 logger.service INFO logger.py::261 Unit status data: {"sw_ver": "3.0.51", "fw_ver": "18", "hw_ver": "3.0", "v_in": 12.29, "v_ref": 3.29, "temperature": 25.0, "humidity": 28, "system_load": "(0.41, 0.32, 0.19)", "uptime": 620, "mem_total": 493, "mem_free": 15, "uploads_ok": 1004, "uploads_err": 5, "shots_ok": 506, "shots_err": 32, "timezone": "Europe/Paris", "unit_name": "Propulsion01", "unit_mode": "normal", "rssi_lte": -53, "rssi_wifi": null, "rev_lte_modem": "EG25GGBR07A07M2G", "image_cache_usage": 129.62292098999023, "cpu_temp": 32.098, "ls_total": null, "ls_free": null, "ts": 1572862880.6076388}
4572019-11-04T10:21:08 bixicon.service DEBUG __timer:56[2251] Transfered 4456448 bytes, speed 222 kB/s
4582019-11-04T10:20:48 bixicon.service DEBUG __timer:56[2251] Transfered 4227072 bytes, speed 211 kB/s
4592019-11-04T10:20:34 bixicon.service INFO upload_worker:161[3417] Processing 1O9A9016.JPG => 2019_1104_112034.JPG (seq 506)
4602019-11-04T10:20:34 bixicon.service INFO upload_worker:161[3415] Processing 1O9A9016.CR2 => 2019_1104_112034.CR2 (seq 506)
4612019-11-04T10:20:28 bixicon.service DEBUG __timer:56[2251] Transfered 3809280 bytes, speed 190 kB/s
4622019-11-04T10:20:08 bixicon.service DEBUG shot_and_download:170[3241] Shot command: /opt/app/bixicon_3.0.51/gphoto2/bin/gphoto2 --port usb:003,003 --set-config capturetarget=1 --set-config capture=on --force-overwrite --set-config autofocusdrive=0 --set-config cancelautofocus=1 --keep --wait-event-and-download=FILEADDED --wait-event-and-download=2s
4632019-11-04T10:20:08 bixicon.service DEBUG __timer:56[2251] Transfered 4235264 bytes, speed 211 kB/s
4642019-11-04T10:20:08 UNKNOWN usb 3-1: reset high-speed USB device number 3 using ehci-platform
4652019-11-04T10:20:04 bixicon.service INFO shot_worker:254[3241] Camera detected {'model': 'Canon EOS 5DS', 'bus': '003', 'dev': '003'}
4662019-11-04T10:20:01 bixicon.service DEBUG wait_for_ready:57[3241] Waiting for camera ready 5 sec.
4672019-11-04T10:20:00 bixicon.service DEBUG cam_detect:190[3241] Waking camera up via SR
4682019-11-04T10:20:00 bixicon.service INFO normal_mode:404[473] Next action ['SHOT'] in 0:05:00 seconds
4692019-11-04T10:20:00 bixicon.service INFO shot_worker:236[3241] Running shot via shutter_release
4702019-11-04T10:19:48 bixicon.service DEBUG __timer:56[2251] Transfered 4358144 bytes, speed 217 kB/s
4712019-11-04T10:19:28 bixicon.service DEBUG __timer:56[2251] Transfered 4136960 bytes, speed 206 kB/s
4722019-11-04T10:19:08 bixicon.service DEBUG __timer:56[2251] Transfered 4407296 bytes, speed 220 kB/s
4732019-11-04T10:18:48 bixicon.service DEBUG __timer:56[2251] Transfered 3932160 bytes, speed 196 kB/s
4742019-11-04T10:18:28 bixicon.service DEBUG __timer:56[2251] Transfered 4489216 bytes, speed 224 kB/s
4752019-11-04T10:18:08 bixicon.service DEBUG __timer:56[2251] Transfered 4997120 bytes, speed 249 kB/s
4762019-11-04T10:17:48 bixicon.service DEBUG __timer:56[2251] Transfered 4538368 bytes, speed 226 kB/s
4772019-11-04T10:17:28 bixicon.service DEBUG __timer:53[2251] Transfer started
4782019-11-04T10:17:28 bixicon.service DEBUG upload_one:197[2251] Starting upload: /tmp/bixicon_images/2019_1104_111528.CR2 62654065 bytes
4792019-11-04T10:17:27 bixicon.service DEBUG upload_one:182[2251] Connecting to ftp://JChesneau@90.63.157.103/PHOTOS-TEST
4802019-11-04T10:17:27 bixicon.service ERROR upload_one:215[2251] Cannot delete file from FTP: timed out Maybe deleted previously for slow data transfer
4812019-11-04T10:17:07 bixicon.service DEBUG upload_one:211[2251] Trying to delete corrupted file from FTP: PHOTOS-TEST/2019_1104_111528.CR2
4822019-11-04T10:17:07 bixicon.service ERROR upload_one:208[2251] Cannot upload: timed out
4832019-11-04T10:17:01 cron.service pam_unix(cron:session): session closed for user root
4842019-11-04T10:17:01 cron.service (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
4852019-11-04T10:17:01 cron.service pam_unix(cron:session): session opened for user root by (uid=0)
4862019-11-04T10:16:48 bixicon.service DEBUG __timer:56[2251] Transfered 11624448 bytes, speed 581 kB/s
4872019-11-04T10:16:28 bixicon.service DEBUG __timer:56[2251] Transfered 12099584 bytes, speed 604 kB/s
4882019-11-04T10:16:19 logger.service INFO logger.py::261 Unit status data: {"sw_ver": "3.0.51", "fw_ver": "18", "hw_ver": "3.0", "v_in": 12.31, "v_ref": 3.29, "temperature": 24.4, "humidity": 27, "system_load": "(0.55, 0.35, 0.17)", "uptime": 319, "mem_total": 493, "mem_free": 22, "uploads_ok": 1004, "uploads_err": 5, "shots_ok": 505, "shots_err": 32, "timezone": "Europe/Paris", "unit_name": "Propulsion01", "unit_mode": "normal", "rssi_lte": -113, "rssi_wifi": null, "rev_lte_modem": "EG25GGBR07A07M2G", "image_cache_usage": 64.47820568084717, "cpu_temp": 31.613, "ls_total": null, "ls_free": null, "ts": 1572862579.624507}
4892019-11-04T10:16:08 bixicon.service DEBUG __timer:56[2251] Transfered 12132352 bytes, speed 606 kB/s
4902019-11-04T10:15:48 bixicon.service DEBUG __timer:56[2251] Transfered 11665408 bytes, speed 583 kB/s
4912019-11-04T10:15:28 bixicon.service DEBUG __timer:53[2251] Transfer started
4922019-11-04T10:15:28 bixicon.service DEBUG upload_one:197[2251] Starting upload: /tmp/bixicon_images/2019_1104_111528.CR2 62654065 bytes
4932019-11-04T10:15:28 bixicon.service INFO upload_worker:161[2253] Processing 1O9A9015.JPG => 2019_1104_111528.JPG (seq 505)
4942019-11-04T10:15:28 bixicon.service DEBUG upload_one:182[2251] Connecting to ftp://JChesneau@90.63.157.103/PHOTOS-TEST
4952019-11-04T10:15:28 bixicon.service DEBUG upload_one:168[2251] Uploading one_file() /tmp/bixicon_images/2019_1104_111528.CR2
4962019-11-04T10:15:28 bixicon.service INFO upload_worker:161[2251] Processing 1O9A9015.CR2 => 2019_1104_111528.CR2 (seq 505)
4972019-11-04T10:15:11 bixicon.service DEBUG shot_and_download:170[2081] Shot command: /opt/app/bixicon_3.0.51/gphoto2/bin/gphoto2 --port usb:003,003 --set-config capturetarget=1 --set-config capture=on --force-overwrite --set-config autofocusdrive=0 --set-config cancelautofocus=1 --keep --wait-event-and-download=FILEADDED --wait-event-and-download=2s
4982019-11-04T10:15:11 UNKNOWN usb 3-1: reset high-speed USB device number 3 using ehci-platform
4992019-11-04T10:15:07 bixicon.service INFO shot_worker:254[2081] Camera detected {'model': 'Canon EOS 5DS', 'bus': '003', 'dev': '003'}
5002019-11-04T10:15:03 UNKNOWN usb 3-1: Manufacturer: Canon Inc.
5012019-11-04T10:15:03 UNKNOWN usb 3-1: Product: Canon Digital Camera
5022019-11-04T10:15:03 UNKNOWN usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
5032019-11-04T10:15:03 UNKNOWN usb 3-1: New USB device found, idVendor=04a9, idProduct=3295
5042019-11-04T10:15:03 api.service INFO _log:122[468] 198.18.0.1 - - [04/Nov/2019 10:15:03] "GET /static/js/main.2d076bec.chunk.js HTTP/1.1" 200 -
5052019-11-04T10:15:03 UNKNOWN usb 3-1: new high-speed USB device number 3 using ehci-platform
5062019-11-04T10:15:03 api.service INFO _log:122[468] 198.18.0.1 - - [04/Nov/2019 10:15:03] "GET /static/css/main.61a7d887.chunk.css HTTP/1.1" 200 -
5072019-11-04T10:15:03 api.service INFO _log:122[468] 198.18.0.1 - - [04/Nov/2019 10:15:03] "GET /static/css/2.152a8812.chunk.css HTTP/1.1" 200 -
5082019-11-04T10:15:03 api.service INFO _log:122[468] 198.18.0.1 - - [04/Nov/2019 10:15:03] "GET /static/css/2.152a8812.chunk.css HTTP/1.1" 200 -
5092019-11-04T10:15:02 api.service INFO _log:122[468] 198.18.0.1 - - [04/Nov/2019 10:15:02] "GET / HTTP/1.1" 200 -
5102019-11-04T10:15:02 bixicon.service DEBUG wait_for_ready:57[2081] Waiting for camera ready 5 sec.
5112019-11-04T10:15:01 bixicon.service DEBUG cam_detect:190[2081] Waking camera up via SR
5122019-11-04T10:15:01 bixicon.service INFO normal_mode:404[473] Next action ['SHOT'] in 0:04:59 seconds
5132019-11-04T10:15:01 bixicon.service INFO shot_worker:236[2081] Running shot via shutter_release
5142019-11-04T10:12:10 bixicon.service INFO init_netifaces:326[725] Network device ready
5152019-11-04T10:11:43 base_timesync.service DEBUG process_offset():88 Offset: -8.257120e-01
5162019-11-04T10:11:42 systemd-networkd.service tap0: Gained IPv6LL
5172019-11-04T10:11:41 openvpn@bixi-vpn.service Initialization Sequence Completed
5182019-11-04T10:11:41 openvpn@bixi-vpn.service /sbin/ip addr add dev tap0 198.18.2.162/20 broadcast 198.18.15.255
5192019-11-04T10:11:41 systemd-networkd.service tap0: Gained carrier
5202019-11-04T10:11:41 systemd-udevd.service Using default interface naming scheme 'v240'.
5212019-11-04T10:11:41 openvpn@bixi-vpn.service /sbin/ip link set dev tap0 up mtu 1500
5222019-11-04T10:11:41 openvpn@bixi-vpn.service do_ifconfig, tt->did_ifconfig_ipv6_setup=0
5232019-11-04T10:11:41 openvpn@bixi-vpn.service TUN/TAP TX queue length set to 100
5242019-11-04T10:11:41 openvpn@bixi-vpn.service TUN/TAP device tap0 opened
5252019-11-04T10:11:41 openvpn@bixi-vpn.service Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
5262019-11-04T10:11:41 openvpn@bixi-vpn.service Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
5272019-11-04T10:11:41 openvpn@bixi-vpn.service Data Channel: using negotiated cipher 'AES-256-GCM'
5282019-11-04T10:11:41 openvpn@bixi-vpn.service OPTIONS IMPORT: data channel crypto options modified
5292019-11-04T10:11:41 openvpn@bixi-vpn.service OPTIONS IMPORT: adjusting link_mtu to 1657
5302019-11-04T10:11:41 openvpn@bixi-vpn.service OPTIONS IMPORT: peer-id set
5312019-11-04T10:11:41 openvpn@bixi-vpn.service OPTIONS IMPORT: --ifconfig/up options modified
5322019-11-04T10:11:41 openvpn@bixi-vpn.service OPTIONS IMPORT: timers and/or timeouts modified
5332019-11-04T10:11:41 openvpn@bixi-vpn.service PUSH: Received control message: 'PUSH_REPLY,ping 10,ping-restart 60,ifconfig 198.18.2.162 255.255.240.0,peer-id 31,cipher AES-256-GCM'
5342019-11-04T10:11:41 openvpn@bixi-vpn.service SENT CONTROL [vpn.bixion.com]: 'PUSH_REQUEST' (status=1)
5352019-11-04T10:11:40 openvpn@bixi-vpn.service [vpn.bixion.com] Peer Connection Initiated with [AF_INET]188.166.95.100:5003
5362019-11-04T10:11:40 openvpn@bixi-vpn.service Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
5372019-11-04T10:11:40 openvpn@bixi-vpn.service VERIFY OK: depth=0, C=CZ, ST=Czech Republic, L=Brno, O=BIXION s.r.o., CN=vpn.bixion.com, emailAddress=info@bixion.com
5382019-11-04T10:11:40 openvpn@bixi-vpn.service VERIFY OK: nsCertType=SERVER
5392019-11-04T10:11:40 openvpn@bixi-vpn.service VERIFY OK: depth=1, C=CZ, ST=Czech Republic, L=Brno, O=BIXION s.r.o, CN=BIXION Certificate authority, emailAddress=info@bixion.com
5402019-11-04T10:11:40 openvpn@bixi-vpn.service TLS: Initial packet from [AF_INET]188.166.95.100:5003, sid=25161828 5607ce4b
5412019-11-04T10:11:40 openvpn@bixi-vpn.service UDP link remote: [AF_INET]188.166.95.100:5003
5422019-11-04T10:11:40 openvpn@bixi-vpn.service UDP link local (bound): [AF_INET][undef]:5003
5432019-11-04T10:11:40 openvpn@bixi-vpn.service Socket Buffers: R=[163840->163840] S=[163840->163840]
5442019-11-04T10:11:40 openvpn@bixi-vpn.service TCP/UDP: Preserving recently used remote address: [AF_INET]188.166.95.100:5003
5452019-11-04T10:11:40 openvpn@bixi-vpn.service WARNING: --ns-cert-type is DEPRECATED.  Use --remote-cert-tls instead.
5462019-11-04T10:11:39 bixicon.service INFO normal_mode:404[473] Next action ['SHOT'] in 0:03:21 seconds
5472019-11-04T10:11:39 bixicon.service INFO normal_mode:381[473] Startup setup None
5482019-11-04T10:11:39 gwtran.service DEBUG on_subscribe():92 Subscribed with qos (0,)
5492019-11-04T10:11:38 gwtran.service INFO on_connect():85 Connected
5502019-11-04T10:11:38 netconnect.service INFO netconnect.py:_loop:133 Online
5512019-11-04T10:11:38 netconnect.service INFO lte.py:_loop:111 Signal: {'rssi': -113, 'ber': '99', 'level': (0, 3)}
5522019-11-04T10:11:38 netconnect.service INFO lte.py:_loop:110 Network info: {'registered': True, 'sim_ready': True, 'access_technology': 'FDD LTE', 'operator': '20815', 'band': 'LTE BAND 7', 'channel': '3350'}
5532019-11-04T10:11:38 netconnect.service INFO lte.py:_loop:109 Link layer of LTE connected
5542019-11-04T10:11:38 systemd-fsckd.service systemd-fsckd.service: Succeeded.
5552019-11-04T10:11:38 netconnect.service INFO netconnect.py:write_dns:195 New nameservers set in /run/netconnect/resolv.conf: ['212.27.40.240', '212.27.40.241']
5562019-11-04T10:11:37 netconnect.service secondary DNS address 212.27.40.241
5572019-11-04T10:11:37 netconnect.service primary   DNS address 212.27.40.240
5582019-11-04T10:11:37 systemd-networkd.service ppp0: Gained carrier
5592019-11-04T10:11:37 netconnect.service remote IP address 10.64.64.64
5602019-11-04T10:11:37 netconnect.service local  IP address 10.80.120.94
5612019-11-04T10:11:37 netconnect.service Could not determine remote IP address: defaulting to 10.64.64.64
5622019-11-04T10:11:37 UNKNOWN PPP Deflate Compression module registered
5632019-11-04T10:11:37 UNKNOWN PPP BSD Compression module registered
5642019-11-04T10:11:37 netconnect.service PAP authentication succeeded
5652019-11-04T10:11:37 api.service INFO _log:122[468]  * Running on http://0.0.0.0:80/ (Press CTRL+C to quit)
5662019-11-04T10:11:37 api.service INFO _log:122[468]  * Running on http://0.0.0.0:80/ (Press CTRL+C to quit)
5672019-11-04T10:11:37 api.service * Debug mode: off
5682019-11-04T10:11:37 api.service Use a production WSGI server instead.
5692019-11-04T10:11:37 api.service WARNING: This is a development server. Do not use it in a production deployment.
5702019-11-04T10:11:37 api.service * Environment: production
5712019-11-04T10:11:37 api.service * Serving Flask app "api" (lazy loading)
5722019-11-04T10:11:36 netconnect.service Connect: ppp0 <--> /dev/ttyUSB2
5732019-11-04T10:11:36 netconnect.service Using interface ppp0
5742019-11-04T10:11:36 netconnect.service Serial connection established.
5752019-11-04T10:11:36 netconnect.service send (^M)
5762019-11-04T10:11:36 netconnect.service -- got it
5772019-11-04T10:11:36 netconnect.service CONNECT
5782019-11-04T10:11:36 netconnect.service ATDT*99#^M^M
5792019-11-04T10:11:36 netconnect.service ^M
5802019-11-04T10:11:36 netconnect.service expect (CONNECT)
5812019-11-04T10:11:36 netconnect.service timeout set to 30 seconds
5822019-11-04T10:11:36 netconnect.service send (ATDT*99#^M)
5832019-11-04T10:11:36 netconnect.service -- got it
5842019-11-04T10:11:36 netconnect.service OK
5852019-11-04T10:11:36 netconnect.service AT+CGDCONT=1,"IP","free"^M^M
5862019-11-04T10:11:36 netconnect.service ^M
5872019-11-04T10:11:36 netconnect.service expect (OK)
5882019-11-04T10:11:36 netconnect.service send (AT+CGDCONT=1,"IP","free"^M)
5892019-11-04T10:11:36 netconnect.service -- got it
5902019-11-04T10:11:36 netconnect.service OK
5912019-11-04T10:11:36 netconnect.service ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0^M^M
5922019-11-04T10:11:36 netconnect.service ^M
5932019-11-04T10:11:36 netconnect.service expect (OK)
5942019-11-04T10:11:35 netconnect.service send (ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0^M)
5952019-11-04T10:11:35 netconnect.service -- got it
5962019-11-04T10:11:35 netconnect.service OK
5972019-11-04T10:11:35 netconnect.service AT+CFUN=1^M^M
5982019-11-04T10:11:35 netconnect.service ^M
5992019-11-04T10:11:35 netconnect.service expect (OK)
6002019-11-04T10:11:35 netconnect.service send (AT+CFUN=1^M)
6012019-11-04T10:11:35 netconnect.service -- got it
6022019-11-04T10:11:35 netconnect.service OK
6032019-11-04T10:11:35 netconnect.service ATZ^M^M
6042019-11-04T10:11:35 netconnect.service ^M
6052019-11-04T10:11:35 netconnect.service expect (OK)
6062019-11-04T10:11:35 netconnect.service send (ATZ^M)
6072019-11-04T10:11:35 netconnect.service -- got it
6082019-11-04T10:11:35 netconnect.service OK
6092019-11-04T10:11:35 netconnect.service ^M
6102019-11-04T10:11:35 netconnect.service Revision: EG25GGBR07A07M2G^M
6112019-11-04T10:11:35 netconnect.service EG25^M
6122019-11-04T10:11:35 netconnect.service Quectel^M
6132019-11-04T10:11:35 netconnect.service ATI^M^M
6142019-11-04T10:11:35 netconnect.service ^M
6152019-11-04T10:11:35 netconnect.service expect (OK)
6162019-11-04T10:11:35 netconnect.service send (ATI^M)
6172019-11-04T10:11:35 netconnect.service -- got it
6182019-11-04T10:11:35 netconnect.service OK
6192019-11-04T10:11:35 netconnect.service AT^M^M
6202019-11-04T10:11:35 netconnect.service expect (OK)
6212019-11-04T10:11:35 netconnect.service send (AT^M)
6222019-11-04T10:11:35 netconnect.service alarm
6232019-11-04T10:11:33 base_timesync.service ERROR ntp2sys():63 Cannot sync with NTP: [Errno -3] Temporary failure in name resolution
6242019-11-04T10:11:32 netconnect.service OK^M
6252019-11-04T10:11:32 netconnect.service ATZ^M^M
6262019-11-04T10:11:32 netconnect.service ^M
6272019-11-04T10:11:32 netconnect.service expect (OKd)
6282019-11-04T10:11:32 netconnect.service timeout set to 3 seconds
6292019-11-04T10:11:32 netconnect.service send (ATZ^M)
6302019-11-04T10:11:32 netconnect.service -- got it
6312019-11-04T10:11:32 netconnect.service OK
6322019-11-04T10:11:32 netconnect.service ATQ0^M^M
6332019-11-04T10:11:32 netconnect.service expect (OK)
6342019-11-04T10:11:32 netconnect.service send (ATQ0^M)
6352019-11-04T10:11:32 netconnect.service timeout set to 6 seconds
6362019-11-04T10:11:32 netconnect.service report (CONNECT)
6372019-11-04T10:11:32 netconnect.service abort on (DELAYED)
6382019-11-04T10:11:32 netconnect.service abort on (NO ANSWER)
6392019-11-04T10:11:32 netconnect.service abort on (NO DIAL TONE)
6402019-11-04T10:11:32 netconnect.service abort on (NO DIALTONE)
6412019-11-04T10:11:32 netconnect.service abort on (VOICE)
6422019-11-04T10:11:32 netconnect.service abort on (NO CARRIER)
6432019-11-04T10:11:32 netconnect.service abort on (BUSY)
6442019-11-04T10:11:31 netconnect.service pppd 2.4.7 started by root, uid 0
6452019-11-04T10:11:31 UNKNOWN PPP generic driver version 2.4.2
6462019-11-04T10:11:30 UNKNOWN IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
6472019-11-04T10:11:30 UNKNOWN dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW
6482019-11-04T10:11:30 UNKNOWN dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available
6492019-11-04T10:11:30 UNKNOWN Generic PHY stmmac-0:01: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:01, irq=POLL)
6502019-11-04T10:11:30 netconnect.service INFO lan.py:_loop:34 Created systemd-networkd configuration for eth0 (02:81:3f:1e:6e:80)
6512019-11-04T10:11:30 systemd-networkd.service Started Network Service.
6522019-11-04T10:11:30 systemd-networkd.service Enumeration completed
6532019-11-04T10:11:30 systemd-networkd.service /run/systemd/network/netconnect_LAN.network:5: Unknown lvalue 'Metric' in section 'Network'
6542019-11-04T10:11:30 systemd-networkd.service Starting Network Service...
6552019-11-04T10:11:30 bixicon.service INFO init_netifaces:303[725] LTE device power init
6562019-11-04T10:11:30 netconnect.service INFO connection.py:reconnect:51 Set new configuration for LAN: {'name': 'eth0', 'lan': {}, 'ipv4': {'dhcp': False, 'ip': '192.168.1.111', 'netmask': '255.255.255.0', 'gw': '192.168.1.1', 'dns': ['8.8.8.8', '8.8.4.4']}}
6572019-11-04T10:11:29 netconnect.service INFO connection.py:reconnect:55 Disconnecting WifiClient
6582019-11-04T10:11:29 netconnect.service INFO connection.py:reconnect:51 Set new configuration for Lte: {'name': 'ppp0', 'metric': 300, 'lte': {'apn': 'free', 'number': '*99#', 'user': None, 'password': None}}
6592019-11-04T10:11:29 bixicon.service INFO init:163[473] Local time: 2019-11-04 11:11:29 (UTC offset 60 min.)
6602019-11-04T10:11:29 bixicon.service }
6612019-11-04T10:11:29 bixicon.service }
6622019-11-04T10:11:29 bixicon.service "ssid": ""
6632019-11-04T10:11:29 bixicon.service "netmask": "255.255.255.0",
6642019-11-04T10:11:29 bixicon.service "key": "",