====== summary ====== hardware: Raspberry Pi Zero W Rev 1.1 OS: Raspbian GNU/Linux 10 (buster) CIFS archiving selected lun0 connected, from file /backingfiles/cam_disk.bin lun1 connected, from file /backingfiles/music_disk.bin 2 snapshots mounted Gadget state: configured ====== disk / images ====== Model: SD GD4QT (sd/mmc) Disk /dev/mmcblk0: 128GB Sector size (logical/physical): 512B/512B Partition Table: msdos Disk Flags: Number Start End Size Type File system Flags 1 4194kB 266MB 262MB primary fat32 boot, lba 2 266MB 2414MB 2147MB primary ext4 3 2414MB 128GB 126GB primary xfs 4 128GB 128GB 105MB primary ext4 cam disk image has 145 extents Model: (file) Disk /backingfiles/cam_disk.bin: 64.4GB Sector size (logical/physical): 512B/512B Partition Table: msdos Disk Flags: Number Start End Size Type File system Flags 1 1049kB 64.4GB 64.4GB primary fat32 lba music disk image has 2 extents Model: (file) Disk /backingfiles/music_disk.bin: 32.2GB Sector size (logical/physical): 512B/512B Partition Table: msdos Disk Flags: Number Start End Size Type File system Flags 1 1049kB 32.2GB 32.2GB primary fat32 lba no boombox disk image found Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p1 247M 49M 199M 20% /boot /dev/root 2.0G 1.3G 569M 70% / /dev/mmcblk0p3 117G 28G 90G 24% /backingfiles /dev/mmcblk0p4 95M 7.9M 80M 10% /mutable ====== network ====== ap0: flags=4163 mtu 1500 inet 192.168.66.1 netmask 255.255.255.0 broadcast 192.168.66.255 inet6 fe80::ba27:ebff:fe87:343a prefixlen 64 scopeid 0x20 ether b8:27:eb:87:34:3a txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 97 bytes 17635 (17.2 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 inet6 ::1 prefixlen 128 scopeid 0x10 loop txqueuelen 1000 (Local Loopback) RX packets 109 bytes 8878 (8.6 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 109 bytes 8878 (8.6 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.1.246 netmask 255.255.255.0 broadcast 192.168.1.255 inet6 fe80::ba27:ebff:fe87:343a prefixlen 64 scopeid 0x20 ether b8:27:eb:87:34:3a txqueuelen 1000 (Ethernet) RX packets 13743 bytes 831410 (811.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 31667 bytes 47716163 (45.5 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 Link Quality=48/70 Signal level=-62 dBm ====== fstab ====== proc /proc proc defaults 0 0 PARTUUID=8b3aede6-01 /boot vfat defaults,ro 0 2 PARTUUID=8b3aede6-02 / ext4 defaults,noatime,ro 0 1 # a swapfile is not a swap partition, no line here # use dphys-swapfile swap[on|off] for that LABEL=backingfiles /backingfiles xfs auto,rw,noatime 0 2 LABEL=mutable /mutable ext4 auto,rw 0 2 /backingfiles/cam_disk.bin /mnt/cam teslausb noauto 0 0 /backingfiles/music_disk.bin /mnt/music teslausb noauto 0 0 //192.168.1.3/TeslaCam /mnt/archive cifs noauto,credentials=/root/.teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default, 0 //192.168.1.3/TeslaMusic /mnt/musicarchive cifs noauto,credentials=/root/.teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default, 0 tmpfs /var/run/samba tmpfs nodev,nosuid 0 0 tmpfs /var/cache/samba tmpfs nodev,nosuid 0 0 tmpfs /var/log/nginx tmpfs nodev,nosuid 0 0 tmpfs /var/lib/nginx tmpfs nodev,nosuid 0 0 mount.ctts#/mutable/TeslaCam /var/www/html/TeslaCam fuse defaults,nofail,x-systemd.requires=/mutable 0 0 tmpfs /var/tmp tmpfs nodev,nosuid 0 0 tmpfs /tmp tmpfs nodev,nosuid 0 0 tmpfs /var/spool tmpfs nodev,nosuid 0 0 tmpfs /var/lib/ntp tmpfs nodev,nosuid 0 0 ====== initial setup boot log ====== no boot log found ====== rc.local log ====== -- Logs begin at Thu 2019-02-14 05:12:03 EST, end at Mon 2023-08-07 14:59:10 EDT. -- Aug 07 14:44:26 teslausb systemd[1]: Starting /etc/rc.local Compatibility... Aug 07 14:44:27 teslausb rc.local[476]: /etc/rc.local: line 25: /boot/teslausb-headless-setup.log: Read-only file system Aug 07 14:44:28 teslausb systemd[1]: Started /etc/rc.local Compatibility. ====== setup log ====== Sat 6 Nov 17:45:22 GMT 2021 : Detecting whether to update wpa_supplicant.conf Sat 6 Nov 17:45:23 GMT 2021 : Wifi variables specified, and no /boot/WIFI_ENABLED. Building wpa_supplicant.conf. Sat 6 Nov 17:45:23 GMT 2021 : Rebooting... Sat 6 Nov 17:46:06 GMT 2021 : Detecting whether to update wpa_supplicant.conf Sat 6 Nov 17:46:06 GMT 2021 : Grabbing main setup file. Sat 6 Nov 17:46:08 GMT 2021 : get_script failed, retrying Sun 6 Aug 03:54:30 BST 2023 : Starting setup. Sun 6 Aug 03:54:30 BST 2023 : curl -L https://github.com/marcone/teslausb/archive/main-dev.tar.gz Sun 6 Aug 03:54:33 BST 2023 : Downloaded /root/bin/envsetup.sh ... Sun 6 Aug 03:54:34 BST 2023 : Configuring the hostname... Sun 6 Aug 03:54:40 BST 2023 : Configured hostname: teslausb Sun 6 Aug 03:54:41 BST 2023 : Downloaded /tmp/483/setup-teslausb ... Sun 6 Aug 03:54:41 BST 2023 : /root/bin/setup-teslausb is up to date Sun 6 Aug 03:54:41 BST 2023 : Downloaded /tmp/483/rc.local ... Sun 6 Aug 03:54:41 BST 2023 : rc.local is up to date Sun 6 Aug 03:54:41 BST 2023 : Updating package index files... Sat 5 Aug 22:56:37 EDT 2023 : Verifying that the requested configuration is valid... Sat 5 Aug 22:56:37 EDT 2023 : Downloaded /tmp/verify-configuration.sh ... Sat 5 Aug 22:56:37 EDT 2023 : Checking XFS support Sat 5 Aug 22:56:41 EDT 2023 : XFS supported Sat 5 Aug 22:56:41 EDT 2023 : DATA_DRIVE is not set. SD card will be used. Sat 5 Aug 22:56:41 EDT 2023 : Verifying that there is sufficient space available on the MicroSD card... Sat 5 Aug 22:56:41 EDT 2023 : There is sufficient space available. Sat 5 Aug 22:56:45 EDT 2023 : Downloading common runtime scripts. Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/remountfs_rw ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/make_snapshot.sh ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/release_snapshot.sh ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/force_sync.sh ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/mountoptsforimage ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/mountimage ... Sat 5 Aug 22:56:45 EDT 2023 : Downloaded /root/bin/enable_gadget.sh ... Sat 5 Aug 22:56:46 EDT 2023 : Downloaded /root/bin/disable_gadget.sh ... Sat 5 Aug 22:56:46 EDT 2023 : /boot/cmdline.txt is up to date Sat 5 Aug 22:56:51 EDT 2023 : Downloaded /tmp/create-backingfiles-partition.sh ... Sat 5 Aug 22:56:51 EDT 2023 : create-backingfiles-partition: Checking existing partitions... Sat 5 Aug 22:56:51 EDT 2023 : create-backingfiles-partition: Modifying partition table for backing files partition... Sat 5 Aug 22:56:52 EDT 2023 : create-backingfiles-partition: Modifying partition table for mutable (writable) partition for script usage... Sat 5 Aug 22:56:53 EDT 2023 : create-backingfiles-partition: Writing updated partitions to fstab and /boot/cmdline.txt Sat 5 Aug 22:56:54 EDT 2023 : create-backingfiles-partition: Formatting new partitions... Sat 5 Aug 22:57:02 EDT 2023 : Mounting the partition for the backing files... Sat 5 Aug 22:57:03 EDT 2023 : Mounted the partition for the backing files. Sat 5 Aug 22:57:03 EDT 2023 : Mounting the mutable partition... Sat 5 Aug 22:57:03 EDT 2023 : Mounted the mutable partition. Sat 5 Aug 22:57:03 EDT 2023 : Creating backing disk files. Sat 5 Aug 22:57:03 EDT 2023 : Downloaded /tmp/create-backingfiles.sh ... Sat 5 Aug 22:57:03 EDT 2023 : create-backingfiles: starting Sat 5 Aug 22:57:03 EDT 2023 : create-backingfiles: cam: 60G, music: 30G, boombox: 0 mountpoint: /backingfiles, exfat: false Sat 5 Aug 22:57:04 EDT 2023 : create-backingfiles: Allocating 62914560K for /backingfiles/cam_disk.bin... Sat 5 Aug 22:57:05 EDT 2023 : create-backingfiles: Creating filesystem with label 'CAM' Sat 5 Aug 22:57:08 EDT 2023 : create-backingfiles: created camera backing file Sat 5 Aug 22:57:08 EDT 2023 : create-backingfiles: Allocating 31457280K for /backingfiles/music_disk.bin... Sat 5 Aug 22:57:10 EDT 2023 : create-backingfiles: Creating filesystem with label 'MUSIC' Sat 5 Aug 22:57:13 EDT 2023 : create-backingfiles: created music backing file Sat 5 Aug 22:57:13 EDT 2023 : create-backingfiles: done Sat 5 Aug 22:57:13 EDT 2023 : updated /etc/fstab for /mnt/cam Sat 5 Aug 22:57:13 EDT 2023 : updated /etc/fstab for /mnt/music Sat 5 Aug 22:57:18 EDT 2023 : calling configure.sh Sat 5 Aug 22:57:18 EDT 2023 : Downloaded /tmp/configure.sh ... Sat 5 Aug 22:57:18 EDT 2023 : configure: Pushover not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: Gotify not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: IFTTT not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: Discord not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: Webhook not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: Slack not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: Matrix not configured. Sat 5 Aug 22:57:18 EDT 2023 : configure: Telegram not enabled. Sat 5 Aug 22:57:18 EDT 2023 : configure: SNS not configured. Sat 5 Aug 22:57:18 EDT 2023 : Downloaded /root/bin/send-push-message ... Sat 5 Aug 22:57:18 EDT 2023 : Downloaded /root/bin/send_sns.py ... Sat 5 Aug 22:57:19 EDT 2023 : Downloaded /root/bin/send_matrix.py ... Sat 5 Aug 22:57:19 EDT 2023 : configure: Checking archive configs: Sat 5 Aug 22:57:19 EDT 2023 : configure: default rsync doesn't work, installing prebuilt 3.2.3 Sat 5 Aug 22:58:06 EDT 2023 : configure: rsync works OK now Sat 5 Aug 22:58:06 EDT 2023 : configure: done Sat 5 Aug 22:58:06 EDT 2023 : configure: Using archive module: run/cifs_archive Sat 5 Aug 22:58:06 EDT 2023 : configure: Installing base archive scripts into /root/bin Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/envsetup.sh ... Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/archiveloop ... Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/waitforidle ... Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/remountfs_rw ... Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/awake_start ... Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /root/bin/awake_stop ... Sat 5 Aug 22:58:06 EDT 2023 : configure: Skipping tesla_api.py install because no credentials were provided Sat 5 Aug 22:58:06 EDT 2023 : configure: Installing archive module scripts Sat 5 Aug 22:58:06 EDT 2023 : Downloaded /tmp/verify-and-configure-archive.sh ... Sat 5 Aug 22:58:07 EDT 2023 : Downloaded /root/bin/archive-clips.sh ... Sat 5 Aug 22:58:07 EDT 2023 : Downloaded /root/bin/connect-archive.sh ... Sat 5 Aug 22:58:07 EDT 2023 : Downloaded /root/bin/disconnect-archive.sh ... Sat 5 Aug 22:58:07 EDT 2023 : Downloaded /root/bin/archive-is-reachable.sh ... Sat 5 Aug 22:58:07 EDT 2023 : Downloaded /root/bin/copy-music.sh ... Sat 5 Aug 22:58:07 EDT 2023 : verify-and-configure-archive: Installing/updating required packages if needed Sat 5 Aug 22:58:35 EDT 2023 : verify-and-configure-archive: Done Sat 5 Aug 22:58:35 EDT 2023 : verify-and-configure-archive: Verifying that the archive server 192.168.1.3 is reachable... Sat 5 Aug 22:58:36 EDT 2023 : verify-and-configure-archive: The archive server is reachable. Sat 5 Aug 22:58:36 EDT 2023 : verify-and-configure-archive: Verifying that the archive share is mountable... Sat 5 Aug 22:58:36 EDT 2023 : verify-and-configure-archive: Trying mount command-line: Sat 5 Aug 22:58:36 EDT 2023 : verify-and-configure-archive: mount -t cifs '//192.168.1.3/TeslaCam' '/tmp/archivetestmount' -o 'credentials=/tmp/teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default,' Sat 5 Aug 22:58:37 EDT 2023 : verify-and-configure-archive: The archive share is mountable using: mount -t cifs '//192.168.1.3/TeslaCam' '/tmp/archivetestmount' -o 'credentials=/tmp/teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default,' Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: Verifying that the archive share is mountable... Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: Trying mount command-line: Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: mount -t cifs '//192.168.1.3/TeslaMusic' '/tmp/archivetestmount' -o 'credentials=/tmp/teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default,' Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: The archive share is mountable using: mount -t cifs '//192.168.1.3/TeslaMusic' '/tmp/archivetestmount' -o 'credentials=/tmp/teslaCamArchiveCredentials,iocharset=utf8,file_mode=0777,dir_mode=0777,vers=default,' Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: Configuring the archive... Sat 5 Aug 22:58:38 EDT 2023 : verify-and-configure-archive: Configured the archive. Sat 5 Aug 22:58:44 EDT 2023 : Downloaded /tmp/configure-samba.sh ... Sat 5 Aug 22:58:44 EDT 2023 : configure-samba: Installing samba and dependencies... Sat 5 Aug 23:04:21 EDT 2023 : configure-samba: Done. Sat 5 Aug 23:04:21 EDT 2023 : Downloaded /tmp/configure-ap.sh ... Sat 5 Aug 23:04:21 EDT 2023 : configure-ap: installing dnsmasq and hostapd Sat 5 Aug 23:05:52 EDT 2023 : configure-ap: configuring AP 'TESLAUSB WIFI' with IP 192.168.66.1 Sat 5 Aug 23:05:52 EDT 2023 : Downloaded /tmp/configure-automount.sh ... Sat 5 Aug 23:06:00 EDT 2023 : Downloaded /root/bin/auto.teslausb ... Sat 5 Aug 23:06:00 EDT 2023 : configure-automount: converting snapshot mountpoints to links Sat 5 Aug 23:06:00 EDT 2023 : configure-automount: configured automount Sat 5 Aug 23:06:00 EDT 2023 : Downloaded /tmp/configure-web.sh ... Sat 5 Aug 23:06:00 EDT 2023 : configuring nginx Sat 5 Aug 23:07:55 EDT 2023 : curl -L -o /tmp/webui.tgz https://github.com/marcone/teslausb-webui/releases/latest/download/teslausb-ui.tgz Sat 5 Aug 23:07:56 EDT 2023 : done configuring nginx Sat 5 Aug 23:07:56 EDT 2023 : Downloaded /tmp/configure-ssh.sh ... Sat 5 Aug 23:07:56 EDT 2023 : configuring ssh Sat 5 Aug 23:07:56 EDT 2023 : done configuring ssh Sat 5 Aug 23:07:57 EDT 2023 : Downloaded /tmp/make-root-fs-readonly.sh ... Sat 5 Aug 23:07:57 EDT 2023 : make-root-fs-readonly: start Sat 5 Aug 23:07:57 EDT 2023 : make-root-fs-readonly: Disabling unnecessary service... Sat 5 Aug 23:08:07 EDT 2023 : make-root-fs-readonly: Removing unwanted packages... Sat 5 Aug 23:10:06 EDT 2023 : make-root-fs-readonly: Installing ntp and busybox-syslogd... Sat 5 Aug 23:12:16 EDT 2023 : make-root-fs-readonly: Configuring system... Sat 5 Aug 23:12:16 EDT 2023 : make-root-fs-readonly: Moving fake-hwclock data Sat 5 Aug 23:12:17 EDT 2023 : make-root-fs-readonly: done Sat 5 Aug 23:12:17 EDT 2023 : Skipping package upgrade. Sat 5 Aug 23:12:24 EDT 2023 : All done. ====== archiveloop log ====== ============================================== Sat 6 Nov 13:45:31 EDT 2021: Starting archiveloop at 37.09 seconds uptime... Sat 6 Nov 13:45:31 EDT 2021: Running fsck on /backingfiles/cam_disk.bin... Sat 6 Nov 13:45:32 EDT 2021: | fsck from util-linux 2.33.1 Sat 6 Nov 13:45:36 EDT 2021: | fsck.fat 4.1 (2017-01-24) Sat 6 Nov 13:45:36 EDT 2021: | /dev/loop0p1: 4 files, 3/1965567 clusters Sat 6 Nov 13:45:37 EDT 2021: Finished fsck on /backingfiles/cam_disk.bin. Sat 6 Nov 13:45:37 EDT 2021: Running fsck on /backingfiles/music_disk.bin... Sat 6 Nov 13:45:37 EDT 2021: | fsck from util-linux 2.33.1 Sat 6 Nov 13:45:39 EDT 2021: | fsck.fat 4.1 (2017-01-24) Sat 6 Nov 13:45:39 EDT 2021: | /dev/loop0p1: 2 files, 1/1965055 clusters Sat 6 Nov 13:45:39 EDT 2021: Finished fsck on /backingfiles/music_disk.bin. Sat 6 Nov 13:45:41 EDT 2021: taking snapshot of cam disk in /backingfiles/snapshots/snap-000000 Sat 6 Nov 13:45:42 EDT 2021: took snapshot Sat 6 Nov 13:45:46 EDT 2021: comparing new snapshot with /backingfiles/snapshots/snap--00001/snap.bin Sat 6 Nov 13:45:46 EDT 2021: making links for /tmp/snapshots/snap-000000, retargeted to /backingfiles/snapshots/snap-000000/mnt Sat 6 Nov 13:45:46 EDT 2021: made all links for /tmp/snapshots/snap-000000 Sat 6 Nov 13:45:47 EDT 2021: Trying to set time... Sat 5 Aug 23:13:30 EDT 2023: Time adjusted by 55070863.146639 seconds after 0.440000 seconds Sat 5 Aug 23:13:31 EDT 2023: not keeping car awake. Sat 5 Aug 23:13:31 EDT 2023: Archiving... Sat 5 Aug 23:13:31 EDT 2023: Ensuring cam archive is mounted... Sat 5 Aug 23:13:31 EDT 2023: Mounting /mnt/archive... Sat 5 Aug 23:13:32 EDT 2023: Mounted /mnt/archive. Sat 5 Aug 23:13:33 EDT 2023: Ensured cam archive is mounted. Sat 5 Aug 23:13:33 EDT 2023: Ensuring music archive is mounted... Sat 5 Aug 23:13:33 EDT 2023: Mounting /mnt/musicarchive... Sat 5 Aug 23:13:33 EDT 2023: Mounted /mnt/musicarchive. Sat 5 Aug 23:13:33 EDT 2023: Ensured music archive is mounted. Sat 5 Aug 23:13:33 EDT 2023: Checking saved folder count... Sat 5 Aug 23:13:34 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sat 5 Aug 23:13:34 EDT 2023: Ensuring cam file is mounted... Sat 5 Aug 23:13:34 EDT 2023: Disconnecting usb from host... Sat 5 Aug 23:13:34 EDT 2023: Disconnected usb from host. Sat 5 Aug 23:13:34 EDT 2023: Mounting /mnt/cam... Sat 5 Aug 23:13:34 EDT 2023: Mounted /mnt/cam. Sat 5 Aug 23:13:34 EDT 2023: Ensured cam file is mounted. Sat 5 Aug 23:13:34 EDT 2023: cleaning cam mount Sat 5 Aug 23:13:35 EDT 2023: done cleaning cam mount Sat 5 Aug 23:13:35 EDT 2023: Trimming free space in /mnt/cam, which has 3 extents Sat 5 Aug 23:13:37 EDT 2023: Trim complete, image now has 1 extents Sat 5 Aug 23:13:37 EDT 2023: Unmounting /mnt/cam... Sat 5 Aug 23:13:37 EDT 2023: Unmounted /mnt/cam. Sat 5 Aug 23:13:37 EDT 2023: Finished archiving. Sat 5 Aug 23:13:37 EDT 2023: Copying music... Sat 5 Aug 23:13:37 EDT 2023: Starting music sync... Sat 5 Aug 23:13:37 EDT 2023: Ensuring music backing file is mounted... Sat 5 Aug 23:13:37 EDT 2023: Disconnecting usb from host... Sat 5 Aug 23:13:37 EDT 2023: Disconnected usb from host. Sat 5 Aug 23:13:37 EDT 2023: Mounting /mnt/music... Sat 5 Aug 23:13:37 EDT 2023: Mounted /mnt/music. Sat 5 Aug 23:13:37 EDT 2023: Ensured music drive is mounted. Sat 5 Aug 23:13:38 EDT 2023: Syncing music from archive... Sat 5 Aug 23:22:33 EDT 2023: Copied 84 music file(s), deleted 0, skipped 0 previously-copied files, and encountered 0 errors. Sat 5 Aug 23:22:34 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Sat 5 Aug 23:22:35 EDT 2023: Trim complete, image now has 1 extents Sat 5 Aug 23:22:35 EDT 2023: Unmounting /mnt/music... Sat 5 Aug 23:22:36 EDT 2023: Unmounted /mnt/music. Sat 5 Aug 23:22:36 EDT 2023: Finished copying music. Sat 5 Aug 23:22:36 EDT 2023: unmounting /mnt/archive Sat 5 Aug 23:22:36 EDT 2023: Connecting usb to host... Sat 5 Aug 23:22:36 EDT 2023: unmounting /mnt/musicarchive Sat 5 Aug 23:22:37 EDT 2023: Connected usb to host. Sat 5 Aug 23:22:42 EDT 2023: Waiting for archive to be unreachable... ============================================== Sat 5 Aug 23:28:32 EDT 2023: Starting archiveloop at 27.21 seconds uptime... Sat 5 Aug 23:28:32 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sat 5 Aug 23:28:33 EDT 2023: | fsck from util-linux 2.33.1 Sat 5 Aug 23:28:37 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sat 5 Aug 23:28:37 EDT 2023: | Free cluster summary wrong (1965562 vs. really 1965561) Sat 5 Aug 23:28:37 EDT 2023: | Auto-correcting. Sat 5 Aug 23:28:37 EDT 2023: | Performing changes. Sat 5 Aug 23:28:37 EDT 2023: | /dev/loop0p1: 7 files, 6/1965567 clusters Sat 5 Aug 23:28:37 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sat 5 Aug 23:28:37 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sat 5 Aug 23:28:38 EDT 2023: | fsck from util-linux 2.33.1 Sat 5 Aug 23:28:43 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sat 5 Aug 23:28:43 EDT 2023: | Free cluster summary wrong (1883010 vs. really 1883008) Sat 5 Aug 23:28:43 EDT 2023: | Auto-correcting. Sat 5 Aug 23:28:43 EDT 2023: | Performing changes. Sat 5 Aug 23:28:43 EDT 2023: | /dev/loop0p1: 97 files, 82047/1965055 clusters Sat 5 Aug 23:28:43 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sat 5 Aug 23:28:44 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000001 Sat 5 Aug 23:28:45 EDT 2023: took snapshot Sat 5 Aug 23:28:48 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000000/snap.bin Sat 5 Aug 23:28:48 EDT 2023: making links for /tmp/snapshots/snap-000001, retargeted to /backingfiles/snapshots/snap-000001/mnt Sat 5 Aug 23:28:48 EDT 2023: made all links for /tmp/snapshots/snap-000001 Sat 5 Aug 23:28:49 EDT 2023: Trying to set time... Sat 5 Aug 23:30:50 EDT 2023: Time adjusted by 121.247717 seconds after 0.250000 seconds Sat 5 Aug 23:30:50 EDT 2023: not keeping car awake. Sat 5 Aug 23:30:50 EDT 2023: Archiving... Sat 5 Aug 23:30:50 EDT 2023: Ensuring cam archive is mounted... Sat 5 Aug 23:30:51 EDT 2023: Mounting /mnt/archive... Sat 5 Aug 23:30:51 EDT 2023: Mounted /mnt/archive. Sat 5 Aug 23:30:51 EDT 2023: Ensured cam archive is mounted. Sat 5 Aug 23:30:51 EDT 2023: Ensuring music archive is mounted... Sat 5 Aug 23:30:52 EDT 2023: Mounting /mnt/musicarchive... Sat 5 Aug 23:30:52 EDT 2023: Mounted /mnt/musicarchive. Sat 5 Aug 23:30:52 EDT 2023: Ensured music archive is mounted. Sat 5 Aug 23:30:52 EDT 2023: Checking saved folder count... Sat 5 Aug 23:30:52 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sat 5 Aug 23:30:53 EDT 2023: Ensuring cam file is mounted... Sat 5 Aug 23:30:53 EDT 2023: Disconnecting usb from host... Sat 5 Aug 23:30:53 EDT 2023: Disconnected usb from host. Sat 5 Aug 23:30:53 EDT 2023: Mounting /mnt/cam... Sat 5 Aug 23:30:53 EDT 2023: Mounted /mnt/cam. Sat 5 Aug 23:30:53 EDT 2023: Ensured cam file is mounted. Sat 5 Aug 23:30:53 EDT 2023: cleaning cam mount Sat 5 Aug 23:30:54 EDT 2023: done cleaning cam mount Sat 5 Aug 23:30:54 EDT 2023: Trimming free space in /mnt/cam, which has 12 extents Sat 5 Aug 23:30:55 EDT 2023: Trim complete, image now has 12 extents Sat 5 Aug 23:30:55 EDT 2023: Unmounting /mnt/cam... Sat 5 Aug 23:30:55 EDT 2023: Unmounted /mnt/cam. Sat 5 Aug 23:30:55 EDT 2023: Finished archiving. Sat 5 Aug 23:30:55 EDT 2023: Copying music... Sat 5 Aug 23:30:55 EDT 2023: Starting music sync... Sat 5 Aug 23:30:55 EDT 2023: Ensuring music backing file is mounted... Sat 5 Aug 23:30:55 EDT 2023: Disconnecting usb from host... Sat 5 Aug 23:30:55 EDT 2023: Disconnected usb from host. Sat 5 Aug 23:30:55 EDT 2023: Mounting /mnt/music... Sat 5 Aug 23:30:55 EDT 2023: Mounted /mnt/music. Sat 5 Aug 23:30:55 EDT 2023: Ensured music drive is mounted. Sat 5 Aug 23:30:55 EDT 2023: Syncing music from archive... Sat 5 Aug 23:30:57 EDT 2023: Copied 0 music file(s), deleted 0, skipped 84 previously-copied files, and encountered 0 errors. Sat 5 Aug 23:30:57 EDT 2023: Trimming free space in /mnt/music, which has 1 extents Sat 5 Aug 23:30:57 EDT 2023: Trim complete, image now has 1 extents Sat 5 Aug 23:30:57 EDT 2023: Unmounting /mnt/music... Sat 5 Aug 23:30:58 EDT 2023: Unmounted /mnt/music. Sat 5 Aug 23:30:58 EDT 2023: Finished copying music. Sat 5 Aug 23:30:58 EDT 2023: unmounting /mnt/archive Sat 5 Aug 23:30:58 EDT 2023: Connecting usb to host... Sat 5 Aug 23:30:58 EDT 2023: unmounting /mnt/musicarchive Sat 5 Aug 23:30:58 EDT 2023: Connected usb to host. Sat 5 Aug 23:31:04 EDT 2023: Waiting for archive to be unreachable... Sun 6 Aug 00:28:33 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 00:30:15 EDT 2023: couldn't determine idle interval Sun 6 Aug 00:30:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000003 Sun 6 Aug 00:30:18 EDT 2023: took snapshot Sun 6 Aug 00:30:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000002/snap.bin Sun 6 Aug 00:30:20 EDT 2023: making links for /tmp/snapshots/snap-000003, retargeted to /backingfiles/snapshots/snap-000003/mnt Sun 6 Aug 00:30:23 EDT 2023: made all links for /tmp/snapshots/snap-000003 Sun 6 Aug 01:28:24 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 01:30:11 EDT 2023: couldn't determine idle interval Sun 6 Aug 01:30:11 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000006 Sun 6 Aug 01:30:13 EDT 2023: took snapshot Sun 6 Aug 01:30:15 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000005/snap.bin Sun 6 Aug 01:30:15 EDT 2023: making links for /tmp/snapshots/snap-000006, retargeted to /backingfiles/snapshots/snap-000006/mnt Sun 6 Aug 01:30:18 EDT 2023: made all links for /tmp/snapshots/snap-000006 Sun 6 Aug 02:28:19 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 02:30:01 EDT 2023: couldn't determine idle interval Sun 6 Aug 02:30:01 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000007 Sun 6 Aug 02:30:04 EDT 2023: took snapshot Sun 6 Aug 02:30:05 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000006/snap.bin Sun 6 Aug 02:30:05 EDT 2023: making links for /tmp/snapshots/snap-000007, retargeted to /backingfiles/snapshots/snap-000007/mnt Sun 6 Aug 02:30:09 EDT 2023: made all links for /tmp/snapshots/snap-000007 Sun 6 Aug 03:28:09 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 03:29:51 EDT 2023: couldn't determine idle interval Sun 6 Aug 03:29:51 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000008 Sun 6 Aug 03:29:54 EDT 2023: took snapshot Sun 6 Aug 03:29:55 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000007/snap.bin Sun 6 Aug 03:29:55 EDT 2023: making links for /tmp/snapshots/snap-000008, retargeted to /backingfiles/snapshots/snap-000008/mnt Sun 6 Aug 03:29:59 EDT 2023: made all links for /tmp/snapshots/snap-000008 Sun 6 Aug 04:27:59 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 04:29:41 EDT 2023: couldn't determine idle interval Sun 6 Aug 04:29:41 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000009 Sun 6 Aug 04:29:44 EDT 2023: took snapshot Sun 6 Aug 04:29:45 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000008/snap.bin Sun 6 Aug 04:29:46 EDT 2023: making links for /tmp/snapshots/snap-000009, retargeted to /backingfiles/snapshots/snap-000009/mnt Sun 6 Aug 04:29:49 EDT 2023: made all links for /tmp/snapshots/snap-000009 Sun 6 Aug 05:27:49 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 05:29:31 EDT 2023: couldn't determine idle interval Sun 6 Aug 05:29:32 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000010 Sun 6 Aug 05:29:35 EDT 2023: took snapshot Sun 6 Aug 05:29:36 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000009/snap.bin Sun 6 Aug 05:29:36 EDT 2023: making links for /tmp/snapshots/snap-000010, retargeted to /backingfiles/snapshots/snap-000010/mnt Sun 6 Aug 05:29:39 EDT 2023: made all links for /tmp/snapshots/snap-000010 Sun 6 Aug 06:27:40 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 06:29:22 EDT 2023: couldn't determine idle interval Sun 6 Aug 06:29:22 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000011 Sun 6 Aug 06:29:25 EDT 2023: took snapshot Sun 6 Aug 06:29:27 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000010/snap.bin Sun 6 Aug 06:29:27 EDT 2023: making links for /tmp/snapshots/snap-000011, retargeted to /backingfiles/snapshots/snap-000011/mnt Sun 6 Aug 06:29:30 EDT 2023: made all links for /tmp/snapshots/snap-000011 Sun 6 Aug 07:27:30 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 07:29:13 EDT 2023: couldn't determine idle interval Sun 6 Aug 07:29:13 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000012 Sun 6 Aug 07:29:16 EDT 2023: took snapshot Sun 6 Aug 07:29:17 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000011/snap.bin Sun 6 Aug 07:29:17 EDT 2023: making links for /tmp/snapshots/snap-000012, retargeted to /backingfiles/snapshots/snap-000012/mnt Sun 6 Aug 07:29:21 EDT 2023: made all links for /tmp/snapshots/snap-000012 Sun 6 Aug 08:27:21 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 08:29:03 EDT 2023: couldn't determine idle interval Sun 6 Aug 08:29:03 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000013 Sun 6 Aug 08:29:07 EDT 2023: took snapshot Sun 6 Aug 08:29:08 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000012/snap.bin Sun 6 Aug 08:29:09 EDT 2023: making links for /tmp/snapshots/snap-000013, retargeted to /backingfiles/snapshots/snap-000013/mnt Sun 6 Aug 08:29:12 EDT 2023: made all links for /tmp/snapshots/snap-000013 Sun 6 Aug 09:27:12 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 09:28:54 EDT 2023: couldn't determine idle interval Sun 6 Aug 09:28:55 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000014 Sun 6 Aug 09:28:58 EDT 2023: took snapshot Sun 6 Aug 09:28:59 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000013/snap.bin Sun 6 Aug 09:28:59 EDT 2023: making links for /tmp/snapshots/snap-000014, retargeted to /backingfiles/snapshots/snap-000014/mnt Sun 6 Aug 09:29:03 EDT 2023: made all links for /tmp/snapshots/snap-000014 ============================================== Sun 6 Aug 09:17:02 EDT 2023: Starting archiveloop at 28.30 seconds uptime... Sun 6 Aug 09:17:03 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 09:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:08 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:08 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sun 6 Aug 09:17:08 EDT 2023: | Automatically removing dirty bit. Sun 6 Aug 09:17:08 EDT 2023: | Performing changes. Sun 6 Aug 09:17:08 EDT 2023: | /dev/loop0p1: 248 files, 11700/1965567 clusters Sun 6 Aug 09:17:08 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 09:17:08 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 09:17:09 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:14 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:14 EDT 2023: | /dev/loop0p1: 97 files, 82047/1965055 clusters Sun 6 Aug 09:17:14 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 09:17:15 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000015 Sun 6 Aug 09:17:18 EDT 2023: took snapshot Sun 6 Aug 09:17:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000014/snap.bin Sun 6 Aug 09:17:21 EDT 2023: making links for /tmp/snapshots/snap-000015, retargeted to /backingfiles/snapshots/snap-000015/mnt Sun 6 Aug 09:17:24 EDT 2023: made all links for /tmp/snapshots/snap-000015 Sun 6 Aug 09:17:24 EDT 2023: Trying to set time... Sun 6 Aug 10:02:39 EDT 2023: Time adjusted by 2714.949910 seconds after 0.190000 seconds Sun 6 Aug 10:02:39 EDT 2023: not keeping car awake. Sun 6 Aug 10:02:39 EDT 2023: Archiving... Sun 6 Aug 10:02:39 EDT 2023: Ensuring cam archive is mounted... Sun 6 Aug 10:02:39 EDT 2023: Mounting /mnt/archive... Sun 6 Aug 10:02:40 EDT 2023: Mounted /mnt/archive. Sun 6 Aug 10:02:40 EDT 2023: Ensured cam archive is mounted. Sun 6 Aug 10:02:40 EDT 2023: Ensuring music archive is mounted... Sun 6 Aug 10:02:40 EDT 2023: Mounting /mnt/musicarchive... Sun 6 Aug 10:02:41 EDT 2023: Mounted /mnt/musicarchive. Sun 6 Aug 10:02:41 EDT 2023: Ensured music archive is mounted. Sun 6 Aug 10:02:41 EDT 2023: Checking saved folder count... Sun 6 Aug 10:02:42 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sun 6 Aug 10:02:42 EDT 2023: Ensuring cam file is mounted... Sun 6 Aug 10:02:42 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:02:42 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:02:42 EDT 2023: Mounting /mnt/cam... Sun 6 Aug 10:02:42 EDT 2023: Mounted /mnt/cam. Sun 6 Aug 10:02:42 EDT 2023: Ensured cam file is mounted. Sun 6 Aug 10:02:42 EDT 2023: cleaning cam mount Sun 6 Aug 10:02:44 EDT 2023: done cleaning cam mount Sun 6 Aug 10:02:44 EDT 2023: Trimming free space in /mnt/cam, which has 66 extents Sun 6 Aug 10:02:44 EDT 2023: Trim complete, image now has 36 extents Sun 6 Aug 10:02:45 EDT 2023: Unmounting /mnt/cam... Sun 6 Aug 10:02:45 EDT 2023: Unmounted /mnt/cam. Sun 6 Aug 10:02:45 EDT 2023: Finished archiving. Sun 6 Aug 10:02:45 EDT 2023: Copying music... Sun 6 Aug 10:02:45 EDT 2023: Starting music sync... Sun 6 Aug 10:02:45 EDT 2023: Ensuring music backing file is mounted... Sun 6 Aug 10:02:45 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:02:45 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:02:45 EDT 2023: Mounting /mnt/music... Sun 6 Aug 10:02:45 EDT 2023: Mounted /mnt/music. Sun 6 Aug 10:02:45 EDT 2023: Ensured music drive is mounted. Sun 6 Aug 10:02:45 EDT 2023: Syncing music from archive... ============================================== Sun 6 Aug 09:17:02 EDT 2023: Starting archiveloop at 28.29 seconds uptime... Sun 6 Aug 09:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 09:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:07 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:08 EDT 2023: | /dev/loop0p1: 248 files, 11700/1965567 clusters Sun 6 Aug 09:17:08 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 09:17:08 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 09:17:08 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:14 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:14 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sun 6 Aug 09:17:14 EDT 2023: | Automatically removing dirty bit. Sun 6 Aug 09:17:14 EDT 2023: | Performing changes. Sun 6 Aug 09:17:14 EDT 2023: | /dev/loop0p1: 79 files, 66305/1965055 clusters Sun 6 Aug 09:17:14 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 09:17:15 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000016 Sun 6 Aug 09:17:16 EDT 2023: took snapshot Sun 6 Aug 09:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000015/snap.bin Sun 6 Aug 09:17:19 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 09:17:23 EDT 2023: Trying to set time... Sun 6 Aug 10:06:36 EDT 2023: Time adjusted by 2953.264238 seconds after 0.170000 seconds Sun 6 Aug 10:06:36 EDT 2023: not keeping car awake. Sun 6 Aug 10:06:36 EDT 2023: Archiving... Sun 6 Aug 10:06:36 EDT 2023: Ensuring cam archive is mounted... Sun 6 Aug 10:06:36 EDT 2023: Mounting /mnt/archive... Sun 6 Aug 10:06:37 EDT 2023: Mounted /mnt/archive. Sun 6 Aug 10:06:37 EDT 2023: Ensured cam archive is mounted. Sun 6 Aug 10:06:37 EDT 2023: Ensuring music archive is mounted... Sun 6 Aug 10:06:37 EDT 2023: Mounting /mnt/musicarchive... Sun 6 Aug 10:06:37 EDT 2023: Mounted /mnt/musicarchive. Sun 6 Aug 10:06:37 EDT 2023: Ensured music archive is mounted. Sun 6 Aug 10:06:37 EDT 2023: Checking saved folder count... Sun 6 Aug 10:06:38 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sun 6 Aug 10:06:38 EDT 2023: Ensuring cam file is mounted... Sun 6 Aug 10:06:38 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:06:38 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:06:38 EDT 2023: Mounting /mnt/cam... Sun 6 Aug 10:06:39 EDT 2023: Mounted /mnt/cam. Sun 6 Aug 10:06:39 EDT 2023: Ensured cam file is mounted. Sun 6 Aug 10:06:39 EDT 2023: cleaning cam mount Sun 6 Aug 10:06:40 EDT 2023: done cleaning cam mount Sun 6 Aug 10:06:40 EDT 2023: Trimming free space in /mnt/cam, which has 36 extents Sun 6 Aug 10:06:41 EDT 2023: Trim complete, image now has 36 extents Sun 6 Aug 10:06:41 EDT 2023: Unmounting /mnt/cam... Sun 6 Aug 10:06:41 EDT 2023: Unmounted /mnt/cam. Sun 6 Aug 10:06:41 EDT 2023: Finished archiving. Sun 6 Aug 10:06:41 EDT 2023: Copying music... Sun 6 Aug 10:06:41 EDT 2023: Starting music sync... Sun 6 Aug 10:06:41 EDT 2023: Ensuring music backing file is mounted... Sun 6 Aug 10:06:41 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:06:41 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:06:41 EDT 2023: Mounting /mnt/music... Sun 6 Aug 10:06:41 EDT 2023: Mounted /mnt/music. Sun 6 Aug 10:06:41 EDT 2023: Ensured music drive is mounted. Sun 6 Aug 10:06:41 EDT 2023: Syncing music from archive... ============================================== Sun 6 Aug 09:17:02 EDT 2023: Starting archiveloop at 28.23 seconds uptime... Sun 6 Aug 09:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 09:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:08 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:08 EDT 2023: | /dev/loop0p1: 248 files, 11700/1965567 clusters Sun 6 Aug 09:17:08 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 09:17:08 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 09:17:08 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 09:17:13 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 09:17:13 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sun 6 Aug 09:17:14 EDT 2023: | Automatically removing dirty bit. Sun 6 Aug 09:17:14 EDT 2023: | Performing changes. Sun 6 Aug 09:17:14 EDT 2023: | /dev/loop0p1: 79 files, 65441/1965055 clusters Sun 6 Aug 09:17:14 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 09:17:15 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000016 Sun 6 Aug 09:17:17 EDT 2023: took snapshot Sun 6 Aug 09:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000015/snap.bin Sun 6 Aug 09:17:20 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 09:17:23 EDT 2023: Trying to set time... Sun 6 Aug 10:07:48 EDT 2023: Time adjusted by 3025.472624 seconds after 2.230000 seconds Sun 6 Aug 10:07:49 EDT 2023: not keeping car awake. Sun 6 Aug 10:07:49 EDT 2023: Archiving... Sun 6 Aug 10:07:49 EDT 2023: Ensuring cam archive is mounted... Sun 6 Aug 10:07:49 EDT 2023: Mounting /mnt/archive... Sun 6 Aug 10:07:49 EDT 2023: Mounted /mnt/archive. Sun 6 Aug 10:07:49 EDT 2023: Ensured cam archive is mounted. Sun 6 Aug 10:07:50 EDT 2023: Ensuring music archive is mounted... Sun 6 Aug 10:07:50 EDT 2023: Mounting /mnt/musicarchive... Sun 6 Aug 10:07:50 EDT 2023: Mounted /mnt/musicarchive. Sun 6 Aug 10:07:50 EDT 2023: Ensured music archive is mounted. Sun 6 Aug 10:07:50 EDT 2023: Checking saved folder count... Sun 6 Aug 10:07:51 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sun 6 Aug 10:07:51 EDT 2023: Ensuring cam file is mounted... Sun 6 Aug 10:07:51 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:07:51 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:07:51 EDT 2023: Mounting /mnt/cam... Sun 6 Aug 10:07:51 EDT 2023: Mounted /mnt/cam. Sun 6 Aug 10:07:51 EDT 2023: Ensured cam file is mounted. Sun 6 Aug 10:07:51 EDT 2023: cleaning cam mount Sun 6 Aug 10:07:52 EDT 2023: done cleaning cam mount Sun 6 Aug 10:07:52 EDT 2023: Trimming free space in /mnt/cam, which has 36 extents Sun 6 Aug 10:07:53 EDT 2023: Trim complete, image now has 36 extents Sun 6 Aug 10:07:53 EDT 2023: Unmounting /mnt/cam... Sun 6 Aug 10:07:53 EDT 2023: Unmounted /mnt/cam. Sun 6 Aug 10:07:53 EDT 2023: Finished archiving. Sun 6 Aug 10:07:53 EDT 2023: Copying music... Sun 6 Aug 10:07:53 EDT 2023: Starting music sync... Sun 6 Aug 10:07:53 EDT 2023: Ensuring music backing file is mounted... Sun 6 Aug 10:07:53 EDT 2023: Disconnecting usb from host... Sun 6 Aug 10:07:53 EDT 2023: Disconnected usb from host. Sun 6 Aug 10:07:53 EDT 2023: Mounting /mnt/music... Sun 6 Aug 10:07:54 EDT 2023: Mounted /mnt/music. Sun 6 Aug 10:07:54 EDT 2023: Ensured music drive is mounted. Sun 6 Aug 10:07:54 EDT 2023: Syncing music from archive... Sun 6 Aug 10:10:16 EDT 2023: connection dead, killing copy-music Sun 6 Aug 10:10:18 EDT 2023: Trimming free space in /mnt/music, which has 1 extents Sun 6 Aug 10:10:19 EDT 2023: Trim complete, image now has 2 extents Sun 6 Aug 10:10:19 EDT 2023: Unmounting /mnt/music... Sun 6 Aug 10:10:20 EDT 2023: Unmounted /mnt/music. Sun 6 Aug 10:10:20 EDT 2023: Finished copying music. Sun 6 Aug 10:10:20 EDT 2023: unmounting /mnt/archive Sun 6 Aug 10:10:20 EDT 2023: Connecting usb to host... Sun 6 Aug 10:10:20 EDT 2023: Connected usb to host. Sun 6 Aug 10:10:25 EDT 2023: Waiting for archive to be unreachable... Sun 6 Aug 10:10:26 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:27 EDT 2023: Retrying (1) 'archive_is_reachable' ... Sun 6 Aug 10:10:27 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:28 EDT 2023: Retrying (2) 'archive_is_reachable' ... Sun 6 Aug 10:10:28 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:29 EDT 2023: Retrying (3) 'archive_is_reachable' ... Sun 6 Aug 10:10:29 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:30 EDT 2023: Retrying (4) 'archive_is_reachable' ... Sun 6 Aug 10:10:30 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:31 EDT 2023: Retrying (5) 'archive_is_reachable' ... Sun 6 Aug 10:10:33 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:34 EDT 2023: Retrying (6) 'archive_is_reachable' ... Sun 6 Aug 10:10:34 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:35 EDT 2023: Retrying (7) 'archive_is_reachable' ... Sun 6 Aug 10:10:35 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:36 EDT 2023: Retrying (8) 'archive_is_reachable' ... Sun 6 Aug 10:10:36 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:39 EDT 2023: Retrying (9) 'archive_is_reachable' ... Sun 6 Aug 10:10:39 EDT 2023: Sleeping before retry ... Sun 6 Aug 10:10:40 EDT 2023: Retrying (10) 'archive_is_reachable' ... Sun 6 Aug 10:10:40 EDT 2023: Attempts exhausted. Sun 6 Aug 10:10:40 EDT 2023: Archive is unreachable. Sun 6 Aug 10:10:40 EDT 2023: Waiting for archive to be reachable... Sun 6 Aug 11:05:25 EDT 2023: waiting up to 90 seconds for idle interval Sun 6 Aug 11:05:28 EDT 2023: write in progress Sun 6 Aug 11:05:30 EDT 2023: no longer writing, wrote 28614656 Sun 6 Aug 11:05:36 EDT 2023: no writes seen in the last 5 seconds Sun 6 Aug 11:05:37 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000016 Sun 6 Aug 11:05:40 EDT 2023: took snapshot Sun 6 Aug 11:05:41 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000015/snap.bin Sun 6 Aug 11:05:41 EDT 2023: making links for /tmp/snapshots/snap-000016, retargeted to /backingfiles/snapshots/snap-000016/mnt Sun 6 Aug 11:05:46 EDT 2023: made all links for /tmp/snapshots/snap-000016 Sun 6 Aug 11:22:03 EDT 2023: Archive is reachable. Sun 6 Aug 11:22:03 EDT 2023: Trying to set time... Sun 6 Aug 11:22:03 EDT 2023: Time adjusted by 0.092408 seconds after 0.180000 seconds Sun 6 Aug 11:22:03 EDT 2023: not keeping car awake. Sun 6 Aug 11:22:23 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000017 Sun 6 Aug 11:22:26 EDT 2023: took snapshot Sun 6 Aug 11:22:28 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000016/snap.bin Sun 6 Aug 11:22:28 EDT 2023: making links for /tmp/snapshots/snap-000017, retargeted to /backingfiles/snapshots/snap-000017/mnt Sun 6 Aug 11:22:33 EDT 2023: made all links for /tmp/snapshots/snap-000017 Sun 6 Aug 11:22:33 EDT 2023: Archiving... Sun 6 Aug 11:22:34 EDT 2023: Ensuring cam archive is mounted... Sun 6 Aug 11:22:34 EDT 2023: Mounting /mnt/archive... Sun 6 Aug 11:22:34 EDT 2023: Mounted /mnt/archive. Sun 6 Aug 11:22:34 EDT 2023: Ensured cam archive is mounted. Sun 6 Aug 11:22:34 EDT 2023: Ensuring music archive is mounted... Sun 6 Aug 11:22:34 EDT 2023: /mnt/musicarchive is already mounted. Sun 6 Aug 11:22:34 EDT 2023: Ensured music archive is mounted. Sun 6 Aug 11:22:34 EDT 2023: Checking saved folder count... Sun 6 Aug 11:22:35 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sun 6 Aug 11:22:35 EDT 2023: Starting recording archiving Sun 6 Aug 11:22:35 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Sun 6 Aug 11:22:35 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 28.71 seconds uptime... Sun 6 Aug 11:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:10 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:10 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sun 6 Aug 11:17:10 EDT 2023: | Automatically removing dirty bit. Sun 6 Aug 11:17:10 EDT 2023: | Performing changes. Sun 6 Aug 11:17:10 EDT 2023: | /dev/loop0p1: 339 files, 179623/1965567 clusters Sun 6 Aug 11:17:10 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:10 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:11 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:15 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:15 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:15 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000018 Sun 6 Aug 11:17:17 EDT 2023: took snapshot Sun 6 Aug 11:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000017/snap.bin Sun 6 Aug 11:17:19 EDT 2023: making links for /tmp/snapshots/snap-000018, retargeted to /backingfiles/snapshots/snap-000018/mnt Sun 6 Aug 11:17:27 EDT 2023: made all links for /tmp/snapshots/snap-000018 Sun 6 Aug 11:17:29 EDT 2023: Connecting usb to host... Sun 6 Aug 11:17:30 EDT 2023: Connected usb to host. Sun 6 Aug 11:17:35 EDT 2023: Waiting for archive to be reachable... Sun 6 Aug 11:17:36 EDT 2023: Archive is reachable. Sun 6 Aug 11:17:36 EDT 2023: Trying to set time... Sun 6 Aug 22:20:14 EDT 2023: Time adjusted by 39757.303448 seconds after 0.270000 seconds Sun 6 Aug 22:20:14 EDT 2023: not keeping car awake. Sun 6 Aug 22:20:34 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000019 Sun 6 Aug 22:20:37 EDT 2023: took snapshot Sun 6 Aug 22:20:38 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000018/snap.bin Sun 6 Aug 22:20:38 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 22:20:41 EDT 2023: Archiving... Sun 6 Aug 22:20:41 EDT 2023: Ensuring cam archive is mounted... Sun 6 Aug 22:20:41 EDT 2023: Mounting /mnt/archive... Sun 6 Aug 22:20:42 EDT 2023: Mounted /mnt/archive. Sun 6 Aug 22:20:42 EDT 2023: Ensured cam archive is mounted. Sun 6 Aug 22:20:42 EDT 2023: Ensuring music archive is mounted... Sun 6 Aug 22:20:42 EDT 2023: Mounting /mnt/musicarchive... Sun 6 Aug 22:20:43 EDT 2023: Mounted /mnt/musicarchive. Sun 6 Aug 22:20:43 EDT 2023: Ensured music archive is mounted. Sun 6 Aug 22:20:43 EDT 2023: Checking saved folder count... Sun 6 Aug 22:20:44 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Sun 6 Aug 22:20:44 EDT 2023: Starting recording archiving Sun 6 Aug 22:20:44 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Sun 6 Aug 22:20:44 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 29.22 seconds uptime... Sun 6 Aug 11:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:10 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:10 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sun 6 Aug 11:17:10 EDT 2023: | Automatically removing dirty bit. Sun 6 Aug 11:17:10 EDT 2023: | Performing changes. Sun 6 Aug 11:17:10 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Sun 6 Aug 11:17:10 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:10 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:11 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:16 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:16 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:16 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:17 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000019 Sun 6 Aug 11:17:18 EDT 2023: took snapshot Sun 6 Aug 11:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000018/snap.bin Sun 6 Aug 11:17:20 EDT 2023: making links for /tmp/snapshots/snap-000019, retargeted to /backingfiles/snapshots/snap-000019/mnt Sun 6 Aug 11:17:24 EDT 2023: made all links for /tmp/snapshots/snap-000019 Sun 6 Aug 11:17:24 EDT 2023: Trying to set time... Mon 7 Aug 08:29:14 EDT 2023: Time adjusted by 76310.479199 seconds after 0.190000 seconds Mon 7 Aug 08:29:14 EDT 2023: not keeping car awake. Mon 7 Aug 08:29:15 EDT 2023: Archiving... Mon 7 Aug 08:29:15 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 08:29:15 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 08:29:15 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 08:29:16 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 08:29:16 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 08:29:16 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 08:29:16 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 08:29:16 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 08:29:16 EDT 2023: Checking saved folder count... Mon 7 Aug 08:29:17 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 08:29:17 EDT 2023: Starting recording archiving Mon 7 Aug 08:29:17 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Mon 7 Aug 08:29:17 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 28.99 seconds uptime... Sun 6 Aug 11:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:10 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:10 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Sun 6 Aug 11:17:10 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:10 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:10 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:15 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:16 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:16 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Sun 6 Aug 11:17:17 EDT 2023: took snapshot Sun 6 Aug 11:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Sun 6 Aug 11:17:19 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 11:17:23 EDT 2023: Trying to set time... Mon 7 Aug 08:34:56 EDT 2023: Time adjusted by 76653.646865 seconds after 0.160000 seconds Mon 7 Aug 08:34:57 EDT 2023: not keeping car awake. Mon 7 Aug 08:34:57 EDT 2023: Archiving... Mon 7 Aug 08:34:57 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 08:34:57 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 08:34:58 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 08:34:58 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 08:34:58 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 08:34:58 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 08:34:58 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 08:34:58 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 08:34:58 EDT 2023: Checking saved folder count... Mon 7 Aug 08:35:00 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 08:35:00 EDT 2023: Starting recording archiving Mon 7 Aug 08:35:00 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Mon 7 Aug 08:35:00 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 29.16 seconds uptime... Sun 6 Aug 11:17:03 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:04 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:11 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:11 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Sun 6 Aug 11:17:11 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:11 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:11 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:15 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:15 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:16 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Sun 6 Aug 11:17:17 EDT 2023: took snapshot Sun 6 Aug 11:17:19 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Sun 6 Aug 11:17:19 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 11:17:23 EDT 2023: Trying to set time... Mon 7 Aug 08:54:38 EDT 2023: Time adjusted by 77834.920439 seconds after 0.180000 seconds Mon 7 Aug 08:54:38 EDT 2023: not keeping car awake. Mon 7 Aug 08:54:38 EDT 2023: Archiving... Mon 7 Aug 08:54:38 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 08:54:38 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 08:54:39 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 08:54:39 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 08:54:39 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 08:54:39 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 08:54:40 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 08:54:40 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 08:54:40 EDT 2023: Checking saved folder count... Mon 7 Aug 08:54:42 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 08:54:42 EDT 2023: Starting recording archiving Mon 7 Aug 08:54:42 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Mon 7 Aug 08:54:42 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 28.21 seconds uptime... Sun 6 Aug 11:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:08 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:08 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Sun 6 Aug 11:17:08 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:08 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:09 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:14 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:14 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:14 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:15 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Sun 6 Aug 11:17:17 EDT 2023: took snapshot Sun 6 Aug 11:17:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Sun 6 Aug 11:17:20 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 11:17:24 EDT 2023: Trying to set time... Mon 7 Aug 08:56:39 EDT 2023: Time adjusted by 77954.354556 seconds after 0.190000 seconds Mon 7 Aug 08:56:39 EDT 2023: not keeping car awake. Mon 7 Aug 08:56:39 EDT 2023: Archiving... Mon 7 Aug 08:56:39 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 08:56:39 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 08:56:40 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 08:56:40 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 08:56:40 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 08:56:40 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 08:56:40 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 08:56:40 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 08:56:40 EDT 2023: Checking saved folder count... Mon 7 Aug 08:56:42 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 08:56:42 EDT 2023: Starting recording archiving Mon 7 Aug 08:56:42 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Mon 7 Aug 08:56:42 EDT 2023 ============================================== Sun 6 Aug 11:17:02 EDT 2023: Starting archiveloop at 28.81 seconds uptime... Sun 6 Aug 11:17:02 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Sun 6 Aug 11:17:03 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:10 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:10 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Sun 6 Aug 11:17:10 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Sun 6 Aug 11:17:10 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Sun 6 Aug 11:17:10 EDT 2023: | fsck from util-linux 2.33.1 Sun 6 Aug 11:17:16 EDT 2023: | fsck.fat 4.1 (2017-01-24) Sun 6 Aug 11:17:16 EDT 2023: | /dev/loop0p1: 89 files, 75400/1965055 clusters Sun 6 Aug 11:17:16 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Sun 6 Aug 11:17:17 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Sun 6 Aug 11:17:18 EDT 2023: took snapshot Sun 6 Aug 11:17:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Sun 6 Aug 11:17:20 EDT 2023: new snapshot is identical to previous one, discarding Sun 6 Aug 11:17:25 EDT 2023: Trying to set time... Mon 7 Aug 08:59:20 EDT 2023: Time adjusted by 78115.149739 seconds after 0.190000 seconds Mon 7 Aug 08:59:20 EDT 2023: not keeping car awake. Mon 7 Aug 08:59:20 EDT 2023: Archiving... Mon 7 Aug 08:59:20 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 08:59:20 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 08:59:21 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 08:59:21 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 08:59:21 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 08:59:21 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 08:59:22 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 08:59:22 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 08:59:22 EDT 2023: Checking saved folder count... Mon 7 Aug 08:59:23 EDT 2023: There are 4 event folder(s) with 128 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 08:59:23 EDT 2023: Starting recording archiving Mon 7 Aug 08:59:23 EDT 2023: Archiving 128 file(s) including 4 event folder(s) starting at Mon 7 Aug 08:59:23 EDT 2023 Mon 7 Aug 08:59:55 EDT 2023: Archiving completed successfully. Archived 128 files in 32s Mon 7 Aug 08:59:55 EDT 2023: Ensuring cam file is mounted... Mon 7 Aug 08:59:55 EDT 2023: Disconnecting usb from host... Mon 7 Aug 08:59:55 EDT 2023: Disconnected usb from host. Mon 7 Aug 08:59:55 EDT 2023: Mounting /mnt/cam... Mon 7 Aug 08:59:55 EDT 2023: Mounted /mnt/cam. Mon 7 Aug 08:59:55 EDT 2023: Ensured cam file is mounted. Mon 7 Aug 08:59:55 EDT 2023: cleaning cam mount Mon 7 Aug 08:59:56 EDT 2023: done cleaning cam mount Mon 7 Aug 08:59:57 EDT 2023: Trimming free space in /mnt/cam, which has 113 extents Mon 7 Aug 08:59:57 EDT 2023: Trim complete, image now has 94 extents Mon 7 Aug 08:59:57 EDT 2023: Unmounting /mnt/cam... Mon 7 Aug 08:59:57 EDT 2023: Unmounted /mnt/cam. Mon 7 Aug 08:59:57 EDT 2023: Finished archiving. Mon 7 Aug 08:59:57 EDT 2023: Copying music... Mon 7 Aug 08:59:57 EDT 2023: Starting music sync... Mon 7 Aug 08:59:57 EDT 2023: Ensuring music backing file is mounted... Mon 7 Aug 08:59:57 EDT 2023: Disconnecting usb from host... Mon 7 Aug 08:59:58 EDT 2023: Disconnected usb from host. Mon 7 Aug 08:59:58 EDT 2023: Mounting /mnt/music... Mon 7 Aug 08:59:58 EDT 2023: Mounted /mnt/music. Mon 7 Aug 08:59:58 EDT 2023: Ensured music drive is mounted. Mon 7 Aug 08:59:58 EDT 2023: Syncing music from archive... Mon 7 Aug 09:01:24 EDT 2023: Copied 31 music file(s), deleted 0, skipped 72 previously-copied files, and encountered 0 errors. Mon 7 Aug 09:01:24 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Mon 7 Aug 09:01:24 EDT 2023: Trim complete, image now has 2 extents Mon 7 Aug 09:01:24 EDT 2023: Unmounting /mnt/music... Mon 7 Aug 09:01:25 EDT 2023: Unmounted /mnt/music. Mon 7 Aug 09:01:25 EDT 2023: Finished copying music. Mon 7 Aug 09:01:25 EDT 2023: unmounting /mnt/archive Mon 7 Aug 09:01:25 EDT 2023: Connecting usb to host... Mon 7 Aug 09:01:25 EDT 2023: unmounting /mnt/musicarchive Mon 7 Aug 09:01:26 EDT 2023: Connected usb to host. Mon 7 Aug 09:01:31 EDT 2023: Waiting for archive to be unreachable... Mon 7 Aug 09:56:57 EDT 2023: waiting up to 90 seconds for idle interval Mon 7 Aug 09:58:39 EDT 2023: couldn't determine idle interval Mon 7 Aug 09:58:40 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Mon 7 Aug 09:58:42 EDT 2023: took snapshot Mon 7 Aug 09:58:44 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Mon 7 Aug 09:58:44 EDT 2023: new snapshot is identical to previous one, discarding ============================================== Mon 7 Aug 09:17:03 EDT 2023: Starting archiveloop at 28.17 seconds uptime... Mon 7 Aug 09:17:03 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 09:17:04 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 09:17:09 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 09:17:09 EDT 2023: | /dev/loop0p1: 182 files, 116132/1965567 clusters Mon 7 Aug 09:17:09 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 09:17:09 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 09:17:09 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 09:17:15 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 09:17:15 EDT 2023: | /dev/loop0p1: 120 files, 87687/1965055 clusters Mon 7 Aug 09:17:15 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 09:17:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Mon 7 Aug 09:17:18 EDT 2023: took snapshot Mon 7 Aug 09:17:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Mon 7 Aug 09:17:21 EDT 2023: new snapshot is identical to previous one, discarding Mon 7 Aug 09:17:25 EDT 2023: Trying to set time... Mon 7 Aug 10:03:11 EDT 2023: Time adjusted by 2745.864562 seconds after 2.670000 seconds Mon 7 Aug 10:03:11 EDT 2023: not keeping car awake. Mon 7 Aug 10:03:11 EDT 2023: Archiving... Mon 7 Aug 10:03:11 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 10:03:11 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 10:03:11 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 10:03:12 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 10:03:12 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 10:03:12 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 10:03:12 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 10:03:12 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 10:03:12 EDT 2023: Checking saved folder count... Mon 7 Aug 10:03:13 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 10:03:13 EDT 2023: Ensuring cam file is mounted... Mon 7 Aug 10:03:13 EDT 2023: Disconnecting usb from host... Mon 7 Aug 10:03:13 EDT 2023: Disconnected usb from host. Mon 7 Aug 10:03:13 EDT 2023: Mounting /mnt/cam... Mon 7 Aug 10:03:13 EDT 2023: Mounted /mnt/cam. Mon 7 Aug 10:03:13 EDT 2023: Ensured cam file is mounted. Mon 7 Aug 10:03:13 EDT 2023: cleaning cam mount Mon 7 Aug 10:03:15 EDT 2023: done cleaning cam mount Mon 7 Aug 10:03:15 EDT 2023: Trimming free space in /mnt/cam, which has 94 extents Mon 7 Aug 10:03:15 EDT 2023: Trim complete, image now has 94 extents Mon 7 Aug 10:03:15 EDT 2023: Unmounting /mnt/cam... Mon 7 Aug 10:03:15 EDT 2023: Unmounted /mnt/cam. Mon 7 Aug 10:03:15 EDT 2023: Finished archiving. Mon 7 Aug 10:03:15 EDT 2023: Copying music... Mon 7 Aug 10:03:15 EDT 2023: Starting music sync... Mon 7 Aug 10:03:15 EDT 2023: Ensuring music backing file is mounted... Mon 7 Aug 10:03:15 EDT 2023: Disconnecting usb from host... Mon 7 Aug 10:03:15 EDT 2023: Disconnected usb from host. Mon 7 Aug 10:03:15 EDT 2023: Mounting /mnt/music... Mon 7 Aug 10:03:16 EDT 2023: Mounted /mnt/music. Mon 7 Aug 10:03:16 EDT 2023: Ensured music drive is mounted. Mon 7 Aug 10:03:16 EDT 2023: Syncing music from archive... Mon 7 Aug 10:03:18 EDT 2023: Copied 0 music file(s), deleted 0, skipped 103 previously-copied files, and encountered 0 errors. Mon 7 Aug 10:03:18 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Mon 7 Aug 10:03:18 EDT 2023: Trim complete, image now has 2 extents Mon 7 Aug 10:03:18 EDT 2023: Unmounting /mnt/music... Mon 7 Aug 10:03:18 EDT 2023: Unmounted /mnt/music. Mon 7 Aug 10:03:18 EDT 2023: Finished copying music. Mon 7 Aug 10:03:18 EDT 2023: unmounting /mnt/archive Mon 7 Aug 10:03:18 EDT 2023: Connecting usb to host... Mon 7 Aug 10:03:19 EDT 2023: unmounting /mnt/musicarchive Mon 7 Aug 10:03:19 EDT 2023: Connected usb to host. Mon 7 Aug 10:03:24 EDT 2023: Waiting for archive to be unreachable... ============================================== Mon 7 Aug 10:17:03 EDT 2023: Starting archiveloop at 28.07 seconds uptime... Mon 7 Aug 10:17:03 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 10:17:04 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 10:17:09 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 10:17:09 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mon 7 Aug 10:17:09 EDT 2023: | Automatically removing dirty bit. Mon 7 Aug 10:17:09 EDT 2023: | Performing changes. Mon 7 Aug 10:17:09 EDT 2023: | /dev/loop0p1: 278 files, 136727/1965567 clusters Mon 7 Aug 10:17:09 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 10:17:09 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 10:17:09 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 10:17:15 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 10:17:15 EDT 2023: | /dev/loop0p1: 120 files, 87687/1965055 clusters Mon 7 Aug 10:17:15 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 10:17:16 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000020 Mon 7 Aug 10:17:18 EDT 2023: took snapshot Mon 7 Aug 10:17:20 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000019/snap.bin Mon 7 Aug 10:17:20 EDT 2023: making links for /tmp/snapshots/snap-000020, retargeted to /backingfiles/snapshots/snap-000020/mnt Mon 7 Aug 10:17:26 EDT 2023: made all links for /tmp/snapshots/snap-000020 Mon 7 Aug 10:17:26 EDT 2023: Trying to set time... Mon 7 Aug 10:45:23 EDT 2023: Time adjusted by 1676.797804 seconds after 0.510000 seconds Mon 7 Aug 10:45:23 EDT 2023: not keeping car awake. Mon 7 Aug 10:45:23 EDT 2023: Archiving... Mon 7 Aug 10:45:23 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 10:45:23 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 10:45:24 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 10:45:24 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 10:45:24 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 10:45:24 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 10:45:25 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 10:45:25 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 10:45:25 EDT 2023: Checking saved folder count... Mon 7 Aug 10:45:26 EDT 2023: There are 0 event folder(s) with 0 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 10:45:26 EDT 2023: Ensuring cam file is mounted... Mon 7 Aug 10:45:26 EDT 2023: Disconnecting usb from host... Mon 7 Aug 10:45:26 EDT 2023: Disconnected usb from host. Mon 7 Aug 10:45:26 EDT 2023: Mounting /mnt/cam... Mon 7 Aug 10:45:26 EDT 2023: Mounted /mnt/cam. Mon 7 Aug 10:45:26 EDT 2023: Ensured cam file is mounted. Mon 7 Aug 10:45:26 EDT 2023: cleaning cam mount Mon 7 Aug 10:45:28 EDT 2023: done cleaning cam mount Mon 7 Aug 10:45:28 EDT 2023: Trimming free space in /mnt/cam, which has 101 extents Mon 7 Aug 10:45:28 EDT 2023: Trim complete, image now has 101 extents Mon 7 Aug 10:45:28 EDT 2023: Unmounting /mnt/cam... Mon 7 Aug 10:45:28 EDT 2023: Unmounted /mnt/cam. Mon 7 Aug 10:45:28 EDT 2023: Finished archiving. Mon 7 Aug 10:45:29 EDT 2023: Copying music... Mon 7 Aug 10:45:29 EDT 2023: Starting music sync... Mon 7 Aug 10:45:29 EDT 2023: Ensuring music backing file is mounted... Mon 7 Aug 10:45:29 EDT 2023: Disconnecting usb from host... Mon 7 Aug 10:45:29 EDT 2023: Disconnected usb from host. Mon 7 Aug 10:45:29 EDT 2023: Mounting /mnt/music... Mon 7 Aug 10:45:29 EDT 2023: Mounted /mnt/music. Mon 7 Aug 10:45:29 EDT 2023: Ensured music drive is mounted. Mon 7 Aug 10:45:30 EDT 2023: Syncing music from archive... Mon 7 Aug 10:45:33 EDT 2023: Copied 0 music file(s), deleted 55, skipped 48 previously-copied files, and encountered 0 errors. Mon 7 Aug 10:45:34 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Mon 7 Aug 10:45:34 EDT 2023: Trim complete, image now has 2 extents Mon 7 Aug 10:45:34 EDT 2023: Unmounting /mnt/music... Mon 7 Aug 10:45:34 EDT 2023: Unmounted /mnt/music. Mon 7 Aug 10:45:34 EDT 2023: Finished copying music. Mon 7 Aug 10:45:34 EDT 2023: unmounting /mnt/archive Mon 7 Aug 10:45:34 EDT 2023: Connecting usb to host... Mon 7 Aug 10:45:35 EDT 2023: unmounting /mnt/musicarchive Mon 7 Aug 10:45:35 EDT 2023: Connected usb to host. Mon 7 Aug 10:45:40 EDT 2023: Waiting for archive to be unreachable... Mon 7 Aug 10:47:26 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:27 EDT 2023: Retrying (1) 'archive_is_reachable' ... Mon 7 Aug 10:47:33 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:34 EDT 2023: Retrying (2) 'archive_is_reachable' ... Mon 7 Aug 10:47:39 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:40 EDT 2023: Retrying (3) 'archive_is_reachable' ... Mon 7 Aug 10:47:40 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:41 EDT 2023: Retrying (4) 'archive_is_reachable' ... Mon 7 Aug 10:47:41 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:42 EDT 2023: Retrying (5) 'archive_is_reachable' ... Mon 7 Aug 10:47:42 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:44 EDT 2023: Retrying (6) 'archive_is_reachable' ... Mon 7 Aug 10:47:44 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:45 EDT 2023: Retrying (7) 'archive_is_reachable' ... Mon 7 Aug 10:47:45 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:46 EDT 2023: Retrying (8) 'archive_is_reachable' ... Mon 7 Aug 10:47:46 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:47 EDT 2023: Retrying (9) 'archive_is_reachable' ... Mon 7 Aug 10:47:47 EDT 2023: Sleeping before retry ... Mon 7 Aug 10:47:48 EDT 2023: Retrying (10) 'archive_is_reachable' ... Mon 7 Aug 10:47:48 EDT 2023: Attempts exhausted. Mon 7 Aug 10:47:48 EDT 2023: Archive is unreachable. Mon 7 Aug 10:47:48 EDT 2023: Waiting for archive to be reachable... Mon 7 Aug 11:42:59 EDT 2023: waiting up to 90 seconds for idle interval Mon 7 Aug 11:43:30 EDT 2023: write in progress Mon 7 Aug 11:43:38 EDT 2023: no longer writing, wrote 132562944 Mon 7 Aug 11:43:45 EDT 2023: no writes seen in the last 5 seconds Mon 7 Aug 11:43:45 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000021 Mon 7 Aug 11:43:48 EDT 2023: took snapshot Mon 7 Aug 11:43:50 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000020/snap.bin Mon 7 Aug 11:43:50 EDT 2023: making links for /tmp/snapshots/snap-000021, retargeted to /backingfiles/snapshots/snap-000021/mnt Mon 7 Aug 11:43:57 EDT 2023: made all links for /tmp/snapshots/snap-000021 Mon 7 Aug 12:14:49 EDT 2023: Archive is reachable. Mon 7 Aug 12:14:49 EDT 2023: Trying to set time... Mon 7 Aug 12:14:49 EDT 2023: Time adjusted by 0.255640 seconds after 0.340000 seconds Mon 7 Aug 12:14:49 EDT 2023: not keeping car awake. Mon 7 Aug 12:15:09 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000022 Mon 7 Aug 12:15:13 EDT 2023: took snapshot Mon 7 Aug 12:15:14 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000021/snap.bin Mon 7 Aug 12:15:14 EDT 2023: making links for /tmp/snapshots/snap-000022, retargeted to /backingfiles/snapshots/snap-000022/mnt Mon 7 Aug 12:15:22 EDT 2023: made all links for /tmp/snapshots/snap-000022 Mon 7 Aug 12:15:22 EDT 2023: Archiving... Mon 7 Aug 12:15:22 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 12:15:22 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 12:15:22 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 12:15:22 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 12:15:22 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 12:15:23 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 12:15:23 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 12:15:23 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 12:15:23 EDT 2023: Checking saved folder count... Mon 7 Aug 12:15:24 EDT 2023: There are 1 event folder(s) with 46 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 12:15:24 EDT 2023: Starting recording archiving Mon 7 Aug 12:15:24 EDT 2023: Archiving 46 file(s) including 1 event folder(s) starting at Mon 7 Aug 12:15:24 EDT 2023 Mon 7 Aug 12:41:57 EDT 2023: waiting up to 90 seconds for idle interval Mon 7 Aug 12:43:41 EDT 2023: couldn't determine idle interval Mon 7 Aug 12:43:41 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000023 Mon 7 Aug 12:43:44 EDT 2023: took snapshot Mon 7 Aug 12:43:46 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000022/snap.bin Mon 7 Aug 12:43:46 EDT 2023: making links for /tmp/snapshots/snap-000023, retargeted to /backingfiles/snapshots/snap-000023/mnt Mon 7 Aug 12:43:54 EDT 2023: made all links for /tmp/snapshots/snap-000023 Mon 7 Aug 12:45:52 EDT 2023: Error during archiving. Archived 14 files in 30m28s 2023/08/07 12:15:24 [18553] building file list 2023/08/07 12:15:24 [18553] done 2023/08/07 12:15:24 [18553] cd+++++++++ SavedClips/2023-08-07_12-09-54/ 2023/08/07 12:15:27 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-back.mp4 2023/08/07 12:17:13 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-front.mp4 2023/08/07 12:20:09 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-left_repeater.mp4 2023/08/07 12:21:41 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-right_repeater.mp4 2023/08/07 12:23:04 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-back.mp4 2023/08/07 12:24:46 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-front.mp4 2023/08/07 12:27:53 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-left_repeater.mp4 2023/08/07 12:29:33 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-right_repeater.mp4 2023/08/07 12:31:33 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-back.mp4 2023/08/07 12:33:49 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-front.mp4 2023/08/07 12:36:05 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-left_repeater.mp4 2023/08/07 12:37:48 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-right_repeater.mp4 2023/08/07 12:39:45 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-back.mp4 2023/08/07 12:41:15 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-front.mp4 2023/08/07 12:43:57 [18553] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-left_repeater.mp4 2023/08/07 12:45:52 [18553] rsync: [sender] write error: Broken pipe (32) building file list ... done SavedClips/2023-08-07_12-09-54/ SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_11-59-32-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-28-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-01-32-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-right_repeater.mp4 rsync: [receiver] close failed on "/mnt/archive/.teslausbtmp/2023-08-07_12-02-32-left_repeater.mp4.jKkbIf": Input/output error (5) rsync error: error in file IO (code 11) at receiver.c(868) [receiver=v3.2.3] rsync: [sender] write error: Broken pipe (32) Mon 7 Aug 12:45:52 EDT 2023: Ensuring cam file is mounted... Mon 7 Aug 12:45:52 EDT 2023: Disconnecting usb from host... Mon 7 Aug 12:45:53 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 12:45:53 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 12:45:55 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 12:45:55 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mon 7 Aug 12:45:55 EDT 2023: | Automatically removing dirty bit. Mon 7 Aug 12:45:55 EDT 2023: | Performing changes. Mon 7 Aug 12:45:55 EDT 2023: | /dev/loop2p1: 389 files, 270966/1965567 clusters Mon 7 Aug 12:45:55 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 12:45:55 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 12:45:56 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 12:45:57 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 12:45:57 EDT 2023: | /dev/loop2p1: 59 files, 50561/1965055 clusters Mon 7 Aug 12:45:58 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 12:45:58 EDT 2023: Disconnected usb from host. Mon 7 Aug 12:45:58 EDT 2023: Mounting /mnt/cam... Mon 7 Aug 12:45:58 EDT 2023: Mounted /mnt/cam. Mon 7 Aug 12:45:58 EDT 2023: Ensured cam file is mounted. Mon 7 Aug 12:45:58 EDT 2023: cleaning cam mount Mon 7 Aug 12:45:59 EDT 2023: done cleaning cam mount Mon 7 Aug 12:45:59 EDT 2023: Trimming free space in /mnt/cam, which has 174 extents Mon 7 Aug 12:46:00 EDT 2023: Trim complete, image now has 160 extents Mon 7 Aug 12:46:00 EDT 2023: Unmounting /mnt/cam... Mon 7 Aug 12:46:00 EDT 2023: Unmounted /mnt/cam. Mon 7 Aug 12:46:00 EDT 2023: Finished archiving. Mon 7 Aug 12:46:00 EDT 2023: Copying music... Mon 7 Aug 12:46:00 EDT 2023: Starting music sync... Mon 7 Aug 12:46:00 EDT 2023: Ensuring music backing file is mounted... Mon 7 Aug 12:46:00 EDT 2023: Disconnecting usb from host... Mon 7 Aug 12:46:00 EDT 2023: Disconnected usb from host. Mon 7 Aug 12:46:00 EDT 2023: Mounting /mnt/music... Mon 7 Aug 12:46:01 EDT 2023: Mounted /mnt/music. Mon 7 Aug 12:46:01 EDT 2023: Ensured music drive is mounted. Mon 7 Aug 12:46:01 EDT 2023: Syncing music from archive... Mon 7 Aug 12:46:02 EDT 2023: Copied 0 music file(s), deleted 0, skipped 48 previously-copied files, and encountered 0 errors. Mon 7 Aug 12:46:02 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Mon 7 Aug 12:46:03 EDT 2023: Trim complete, image now has 2 extents Mon 7 Aug 12:46:03 EDT 2023: Unmounting /mnt/music... Mon 7 Aug 12:46:03 EDT 2023: Unmounted /mnt/music. Mon 7 Aug 12:46:03 EDT 2023: Finished copying music. Mon 7 Aug 12:46:03 EDT 2023: unmounting /mnt/archive Mon 7 Aug 12:46:03 EDT 2023: Connecting usb to host... Mon 7 Aug 12:46:03 EDT 2023: unmounting /mnt/musicarchive Mon 7 Aug 12:46:04 EDT 2023: Connected usb to host. Mon 7 Aug 12:46:09 EDT 2023: Waiting for archive to be unreachable... ============================================== Mon 7 Aug 12:17:05 EDT 2023: Starting archiveloop at 28.35 seconds uptime... Mon 7 Aug 12:17:05 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 12:17:06 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 12:17:11 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 12:17:11 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mon 7 Aug 12:17:11 EDT 2023: | Automatically removing dirty bit. Mon 7 Aug 12:17:11 EDT 2023: | Long filename fragment "2023-08-07_13" found outside a LFN sequence. Mon 7 Aug 12:17:11 EDT 2023: | (Maybe the start bit is missing on the last fragment) Mon 7 Aug 12:17:11 EDT 2023: | Not auto-correcting this. Mon 7 Aug 12:17:11 EDT 2023: | Free cluster summary wrong (1755158 vs. really 1755154) Mon 7 Aug 12:17:11 EDT 2023: | Auto-correcting. Mon 7 Aug 12:17:11 EDT 2023: | Performing changes. Mon 7 Aug 12:17:11 EDT 2023: | /dev/loop0p1: 425 files, 210413/1965567 clusters Mon 7 Aug 12:17:11 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 12:17:11 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 12:17:12 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 12:17:17 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 12:17:18 EDT 2023: | /dev/loop0p1: 59 files, 50561/1965055 clusters Mon 7 Aug 12:17:18 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 12:17:19 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000024 Mon 7 Aug 12:17:21 EDT 2023: took snapshot Mon 7 Aug 12:17:23 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000023/snap.bin Mon 7 Aug 12:17:23 EDT 2023: making links for /tmp/snapshots/snap-000024, retargeted to /backingfiles/snapshots/snap-000024/mnt Mon 7 Aug 12:17:32 EDT 2023: made all links for /tmp/snapshots/snap-000024 Mon 7 Aug 12:17:32 EDT 2023: Trying to set time... Mon 7 Aug 14:16:42 EDT 2023: Time adjusted by 7149.890926 seconds after 0.180000 seconds Mon 7 Aug 14:16:42 EDT 2023: not keeping car awake. Mon 7 Aug 14:16:42 EDT 2023: Archiving... Mon 7 Aug 14:16:42 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 14:16:42 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 14:16:43 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 14:16:43 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 14:16:43 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 14:16:43 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 14:16:43 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 14:16:43 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 14:16:43 EDT 2023: Checking saved folder count... Mon 7 Aug 14:16:44 EDT 2023: There are 1 event folder(s) with 32 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 14:16:44 EDT 2023: Starting recording archiving Mon 7 Aug 14:16:45 EDT 2023: Archiving 32 file(s) including 1 event folder(s) starting at Mon 7 Aug 14:16:44 EDT 2023 Mon 7 Aug 14:44:07 EDT 2023: Error during archiving. Archived 9 files in 27m23s 2023/08/07 14:16:45 [1473] building file list 2023/08/07 14:16:45 [1473] done 2023/08/07 14:16:47 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-left_repeater.mp4 2023/08/07 14:18:48 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-right_repeater.mp4 2023/08/07 14:20:09 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-back.mp4 2023/08/07 14:21:56 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-front.mp4 2023/08/07 14:25:58 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-left_repeater.mp4 2023/08/07 14:28:16 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-right_repeater.mp4 2023/08/07 14:29:48 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-back.mp4 2023/08/07 14:34:18 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-front.mp4 2023/08/07 14:38:34 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-left_repeater.mp4 2023/08/07 14:41:09 [1473] >f+++++++++ SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-right_repeater.mp4 2023/08/07 14:44:07 [1473] rsync: [sender] write error: Broken pipe (32) building file list ... done SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-02-32-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-03-32-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-back.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-front.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-left_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-04-32-right_repeater.mp4 SavedClips/2023-08-07_12-09-54/2023-08-07_12-05-33-back.mp4 rsync: [receiver] close failed on "/mnt/archive/.teslausbtmp/2023-08-07_12-04-32-right_repeater.mp4.bEtxPL": Input/output error (5) rsync error: error in file IO (code 11) at receiver.c(868) [receiver=v3.2.3] rsync: [sender] write error: Broken pipe (32) Mon 7 Aug 14:44:07 EDT 2023: Ensuring cam file is mounted... Mon 7 Aug 14:44:07 EDT 2023: Disconnecting usb from host... Mon 7 Aug 14:44:08 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 14:44:08 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:10 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:10 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mon 7 Aug 14:44:10 EDT 2023: | Automatically removing dirty bit. Mon 7 Aug 14:44:10 EDT 2023: | Long filename fragment "2023-08-07_13" found outside a LFN sequence. Mon 7 Aug 14:44:10 EDT 2023: | (Maybe the start bit is missing on the last fragment) Mon 7 Aug 14:44:10 EDT 2023: | Not auto-correcting this. Mon 7 Aug 14:44:10 EDT 2023: | Performing changes. Mon 7 Aug 14:44:10 EDT 2023: | /dev/loop1p1: 297 files, 175424/1965567 clusters Mon 7 Aug 14:44:10 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 14:44:10 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 14:44:11 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:14 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:14 EDT 2023: | /dev/loop1p1: 59 files, 50561/1965055 clusters Mon 7 Aug 14:44:14 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 14:44:14 EDT 2023: Disconnected usb from host. Mon 7 Aug 14:44:14 EDT 2023: Mounting /mnt/cam... Mon 7 Aug 14:44:15 EDT 2023: Mounted /mnt/cam. Mon 7 Aug 14:44:15 EDT 2023: Ensured cam file is mounted. Mon 7 Aug 14:44:15 EDT 2023: cleaning cam mount Mon 7 Aug 14:44:18 EDT 2023: done cleaning cam mount Mon 7 Aug 14:44:19 EDT 2023: Trimming free space in /mnt/cam, which has 167 extents Mon 7 Aug 14:44:21 EDT 2023: Trim complete, image now has 145 extents Mon 7 Aug 14:44:21 EDT 2023: Unmounting /mnt/cam... Mon 7 Aug 14:44:21 EDT 2023: Unmounted /mnt/cam. Mon 7 Aug 14:44:21 EDT 2023: Finished archiving. Mon 7 Aug 14:44:21 EDT 2023: Copying music... Mon 7 Aug 14:44:22 EDT 2023: Starting music sync... Mon 7 Aug 14:44:22 EDT 2023: Ensuring music backing file is mounted... Mon 7 Aug 14:44:22 EDT 2023: Disconnecting usb from host... Mon 7 Aug 14:44:22 EDT 2023: Disconnected usb from host. Mon 7 Aug 14:44:22 EDT 2023: Mounting /mnt/music... Mon 7 Aug 14:44:23 EDT 2023: Mounted /mnt/music. Mon 7 Aug 14:44:23 EDT 2023: Ensured music drive is mounted. Mon 7 Aug 14:44:23 EDT 2023: Syncing music from archive... Mon 7 Aug 14:44:23 EDT 2023: Error: /mnt/musicarchive not mounted Mon 7 Aug 14:44:23 EDT 2023: Trimming free space in /mnt/music, which has 2 extents Mon 7 Aug 14:44:24 EDT 2023: Trim complete, image now has 2 extents Mon 7 Aug 14:44:24 EDT 2023: Unmounting /mnt/music... Mon 7 Aug 14:44:25 EDT 2023: Unmounted /mnt/music. Mon 7 Aug 14:44:25 EDT 2023: Finished copying music. Mon 7 Aug 14:44:25 EDT 2023: unmounting /mnt/archive Mon 7 Aug 14:44:25 EDT 2023: unmount failed Mon 7 Aug 14:44:25 EDT 2023: unmounting /mnt/musicarchive Mon 7 Aug 14:44:25 EDT 2023: Connecting usb to host... Mon 7 Aug 14:44:25 EDT 2023: unmount failed Mon 7 Aug 14:44:26 EDT 2023: Connected usb to host. ============================================== Mon 7 Aug 14:44:21 EDT 2023: Starting archiveloop at 29.00 seconds uptime... Mon 7 Aug 14:44:21 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 14:44:22 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:29 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:29 EDT 2023: | 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mon 7 Aug 14:44:29 EDT 2023: | Automatically removing dirty bit. Mon 7 Aug 14:44:29 EDT 2023: | Long filename fragment "2023-08-07_13" found outside a LFN sequence. Mon 7 Aug 14:44:29 EDT 2023: | (Maybe the start bit is missing on the last fragment) Mon 7 Aug 14:44:29 EDT 2023: | Not auto-correcting this. Mon 7 Aug 14:44:29 EDT 2023: | Performing changes. Mon 7 Aug 14:44:29 EDT 2023: | /dev/loop0p1: 297 files, 175424/1965567 clusters Mon 7 Aug 14:44:29 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 14:44:29 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 14:44:30 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:35 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:35 EDT 2023: | /dev/loop0p1: 59 files, 50561/1965055 clusters Mon 7 Aug 14:44:35 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 14:44:36 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000025 Mon 7 Aug 14:44:37 EDT 2023: took snapshot Mon 7 Aug 14:44:39 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000024/snap.bin Mon 7 Aug 14:44:39 EDT 2023: making links for /tmp/snapshots/snap-000025, retargeted to /backingfiles/snapshots/snap-000025/mnt Mon 7 Aug 14:44:49 EDT 2023: made all links for /tmp/snapshots/snap-000025 Mon 7 Aug 14:44:49 EDT 2023: Trying to set time... Mon 7 Aug 14:45:32 EDT 2023: Time adjusted by 43.416353 seconds after 0.190000 seconds Mon 7 Aug 14:45:32 EDT 2023: not keeping car awake. Mon 7 Aug 14:45:32 EDT 2023: Archiving... Mon 7 Aug 14:45:33 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 14:45:33 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 14:45:33 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 14:45:33 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 14:45:33 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 14:45:34 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 14:45:34 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 14:45:34 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 14:45:34 EDT 2023: Checking saved folder count... Mon 7 Aug 14:45:37 EDT 2023: There are 1 event folder(s) with 23 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 14:45:37 EDT 2023: Starting recording archiving Mon 7 Aug 14:45:37 EDT 2023: Archiving 23 file(s) including 1 event folder(s) starting at Mon 7 Aug 14:45:37 EDT 2023 ============================================== Mon 7 Aug 14:44:21 EDT 2023: Starting archiveloop at 32.80 seconds uptime... Mon 7 Aug 14:44:21 EDT 2023: Running fsck on /backingfiles/cam_disk.bin... Mon 7 Aug 14:44:23 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:29 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:30 EDT 2023: | Long filename fragment "2023-08-07_13" found outside a LFN sequence. Mon 7 Aug 14:44:30 EDT 2023: | (Maybe the start bit is missing on the last fragment) Mon 7 Aug 14:44:30 EDT 2023: | Not auto-correcting this. Mon 7 Aug 14:44:30 EDT 2023: | /dev/loop0p1: 297 files, 175424/1965567 clusters Mon 7 Aug 14:44:30 EDT 2023: Finished fsck on /backingfiles/cam_disk.bin. Mon 7 Aug 14:44:30 EDT 2023: Running fsck on /backingfiles/music_disk.bin... Mon 7 Aug 14:44:30 EDT 2023: | fsck from util-linux 2.33.1 Mon 7 Aug 14:44:34 EDT 2023: | fsck.fat 4.1 (2017-01-24) Mon 7 Aug 14:44:34 EDT 2023: | /dev/loop0p1: 59 files, 50561/1965055 clusters Mon 7 Aug 14:44:35 EDT 2023: Finished fsck on /backingfiles/music_disk.bin. Mon 7 Aug 14:44:35 EDT 2023: taking snapshot of cam disk in /backingfiles/snapshots/snap-000026 Mon 7 Aug 14:44:36 EDT 2023: took snapshot Mon 7 Aug 14:44:39 EDT 2023: comparing new snapshot with /backingfiles/snapshots/snap-000025/snap.bin Mon 7 Aug 14:44:40 EDT 2023: new snapshot is identical to previous one, discarding Mon 7 Aug 14:44:47 EDT 2023: Trying to set time... Mon 7 Aug 14:55:04 EDT 2023: Time adjusted by 616.392387 seconds after 0.630000 seconds Mon 7 Aug 14:55:04 EDT 2023: not keeping car awake. Mon 7 Aug 14:55:04 EDT 2023: Archiving... Mon 7 Aug 14:55:04 EDT 2023: Ensuring cam archive is mounted... Mon 7 Aug 14:55:04 EDT 2023: Mounting /mnt/archive... Mon 7 Aug 14:55:10 EDT 2023: Mounted /mnt/archive. Mon 7 Aug 14:55:10 EDT 2023: Ensured cam archive is mounted. Mon 7 Aug 14:55:10 EDT 2023: Ensuring music archive is mounted... Mon 7 Aug 14:55:10 EDT 2023: Mounting /mnt/musicarchive... Mon 7 Aug 14:55:15 EDT 2023: Mounted /mnt/musicarchive. Mon 7 Aug 14:55:15 EDT 2023: Ensured music archive is mounted. Mon 7 Aug 14:55:15 EDT 2023: Checking saved folder count... Mon 7 Aug 14:55:16 EDT 2023: There are 1 event folder(s) with 23 file(s) and 0 track mode file(s) to move. 0 short recording(s) will be skipped. Mon 7 Aug 14:55:16 EDT 2023: Starting recording archiving Mon 7 Aug 14:55:16 EDT 2023: Archiving 23 file(s) including 1 event folder(s) starting at Mon 7 Aug 14:55:16 EDT 2023 ====== system log ====== Aug 7 14:44:24 teslausb syslog.info syslogd started: BusyBox v1.30.1 Aug 7 14:44:26 teslausb daemon.notice ntpd[468]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 7 14:44:26 teslausb daemon.info ntpd[468]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 110:116 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: proto: precision = 2.000 usec (-19) Aug 7 14:44:26 teslausb daemon.notice ntpd[479]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 7 14:44:26 teslausb daemon.notice ntpd[479]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 7 14:44:26 teslausb daemon.err ntpd[479]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 406 days ago Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen and drop on 0 v6wildcard [::]:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen normally on 2 lo 127.0.0.1:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen normally on 3 ap0 192.168.66.1:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen normally on 4 lo [::1]:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listen normally on 5 ap0 [fe80::ba27:ebff:fe87:343a%4]:123 Aug 7 14:44:26 teslausb daemon.info ntpd[479]: Listening on routing socket on fd #22 for interface updates Aug 7 14:44:26 teslausb daemon.info ntpd[479]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 7 14:44:26 teslausb daemon.info ntpd[479]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 7 14:44:27 teslausb user.notice wpa_action: WPA_IFACE=wlan0 WPA_ACTION=CONNECTED Aug 7 14:44:27 teslausb user.notice wpa_action: WPA_ID=0 WPA_ID_STR=AP1 WPA_CTRL_DIR=/var/run/wpa_supplicant Aug 7 14:44:27 teslausb daemon.err ntpd[479]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3) Aug 7 14:44:27 teslausb user.notice wpa_action: ifup wlan0=AP1 Aug 7 14:44:28 teslausb daemon.err ntpd[479]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3) Aug 7 14:44:28 teslausb auth.info sshd[511]: Server listening on 0.0.0.0 port 22. Aug 7 14:44:28 teslausb auth.info sshd[511]: Server listening on :: port 22. Aug 7 14:44:28 teslausb daemon.info dhclient[517]: Internet Systems Consortium DHCP Client 4.4.1 Aug 7 14:44:28 teslausb daemon.info dhclient[517]: Copyright 2004-2018 Internet Systems Consortium. Aug 7 14:44:28 teslausb daemon.info dhclient[517]: All rights reserved. Aug 7 14:44:28 teslausb daemon.info dhclient[517]: For info, please visit https://www.isc.org/software/dhcp/ Aug 7 14:44:28 teslausb daemon.info dhclient[517]: Aug 7 14:44:28 teslausb daemon.err dhclient[517]: can't create /var/lib/dhcp/dhclient.wlan0.leases: Read-only file system Aug 7 14:44:29 teslausb daemon.info dnsmasq[527]: started, version 2.80 cachesize 150 Aug 7 14:44:29 teslausb daemon.info dnsmasq[527]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Aug 7 14:44:29 teslausb daemon.info dnsmasq-dhcp[527]: DHCP, IP range 192.168.66.100 -- 192.168.66.150, lease time 12h Aug 7 14:44:29 teslausb daemon.info dnsmasq-dhcp[527]: DHCP, sockets bound exclusively to interface ap0 Aug 7 14:44:29 teslausb daemon.info dnsmasq[527]: read /etc/hosts - 5 addresses Aug 7 14:44:29 teslausb daemon.info dhclient[517]: Listening on LPF/wlan0/b8:27:eb:87:34:3a Aug 7 14:44:29 teslausb daemon.info dhclient[517]: Sending on LPF/wlan0/b8:27:eb:87:34:3a Aug 7 14:44:29 teslausb daemon.info dhclient[517]: Sending on Socket/fallback Aug 7 14:44:29 teslausb daemon.info dhclient[517]: Created duid "\000\001\000\001,c\370\015\270'\353\2074:". Aug 7 14:44:29 teslausb daemon.err dhclient[517]: can't create /var/lib/dhcp/dhclient.wlan0.leases: Read-only file system Aug 7 14:44:29 teslausb daemon.info dhclient[517]: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 4 Aug 7 14:44:29 teslausb daemon.info dhclient[517]: DHCPOFFER of 192.168.1.246 from 192.168.1.1 Aug 7 14:44:29 teslausb daemon.info dhclient[517]: DHCPREQUEST for 192.168.1.246 on wlan0 to 255.255.255.255 port 67 Aug 7 14:44:29 teslausb daemon.info dhclient[517]: DHCPACK of 192.168.1.246 from 192.168.1.1 Aug 7 14:44:29 teslausb daemon.info ntpd[479]: Listen normally on 6 wlan0 [fe80::ba27:ebff:fe87:343a%3]:123 Aug 7 14:44:29 teslausb daemon.debug ntpd[479]: new interface(s) found: waking up resolver Aug 7 14:44:29 teslausb daemon.err ntpd[479]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3) Aug 7 14:44:30 teslausb daemon.warn dnsmasq[527]: failed to access /run/dnsmasq/resolv.conf: No such file or directory Aug 7 14:44:30 teslausb daemon.warn dnsmasq[527]: no servers found in /run/dnsmasq/resolv.conf, will retry Aug 7 14:44:30 teslausb daemon.err ntpd[479]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3) Aug 7 14:44:31 teslausb daemon.info ntpd[479]: Listen normally on 7 wlan0 192.168.1.246:123 Aug 7 14:44:31 teslausb daemon.debug ntpd[479]: new interface(s) found: waking up resolver Aug 7 14:44:32 teslausb daemon.info dnsmasq[527]: reading /run/dnsmasq/resolv.conf Aug 7 14:44:32 teslausb daemon.info dnsmasq[527]: using nameserver 192.168.1.5#53 Aug 7 14:44:32 teslausb daemon.err dhclient[517]: can't create /var/lib/dhcp/dhclient.wlan0.leases: Read-only file system Aug 7 14:44:32 teslausb daemon.info dhclient[517]: bound to 192.168.1.246 -- renewal in 2840 seconds. Aug 7 14:44:33 teslausb user.notice wpa_action: creating sendsigs omission pidfile: /run/sendsigs.omit.d/wpasupplicant.wpa_supplicant.wlan0.pid Aug 7 14:44:33 teslausb user.notice wpa_action: bssid=00:31:92:15:4b:f6 Aug 7 14:44:33 teslausb user.notice wpa_action: freq=2452 Aug 7 14:44:33 teslausb user.notice wpa_action: ssid=netgearGR Aug 7 14:44:33 teslausb user.notice wpa_action: id=0 Aug 7 14:44:33 teslausb user.notice wpa_action: id_str=AP1 Aug 7 14:44:33 teslausb user.notice wpa_action: mode=station Aug 7 14:44:33 teslausb user.notice wpa_action: pairwise_cipher=CCMP Aug 7 14:44:33 teslausb user.notice wpa_action: group_cipher=CCMP Aug 7 14:44:33 teslausb user.notice wpa_action: key_mgmt=WPA2-PSK Aug 7 14:44:33 teslausb user.notice wpa_action: wpa_state=COMPLETED Aug 7 14:44:33 teslausb user.notice wpa_action: ip_address=192.168.1.246 Aug 7 14:44:33 teslausb user.notice wpa_action: p2p_device_address=ba:27:eb:87:34:3a Aug 7 14:44:33 teslausb user.notice wpa_action: address=b8:27:eb:87:34:3a Aug 7 14:44:33 teslausb user.notice wpa_action: uuid=9390d13b-e291-5cc8-91f4-cd64fce358e0 Aug 7 14:44:39 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:44:39 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:44:47 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:44:47 teslausb user.info sntp[895]: sntp 4.2.8p12@1.3728-o (1) Aug 7 14:44:47 teslausb user.warn sntp[895]: Can't open KOD db file /var/lib/sntp/kod for writing: Read-only file system Aug 7 14:44:48 teslausb user.err sntp[895]: Send to [2001:4860:4806:c::]:123 failed, Network is unreachable Aug 7 14:44:48 teslausb user.info sntp[895]: 2023-08-07 14:44:48.297103 (+0500) +615.777131 +/- 410.518209 time.google.com 216.239.35.8 s1 no-leap Aug 7 14:55:18 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:55:18 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:55:22 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:55:38 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:55:38 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:55:41 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:55:51 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:55:51 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:55:54 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:55:57 teslausb daemon.err ntpd[479]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3) Aug 7 14:56:02 teslausb daemon.info ntpd[479]: Soliciting pool server 62.72.0.70 Aug 7 14:56:02 teslausb daemon.info ntpd[479]: Soliciting pool server 72.30.35.88 Aug 7 14:56:03 teslausb daemon.info ntpd[479]: Soliciting pool server 137.190.2.4 Aug 7 14:56:03 teslausb daemon.info ntpd[479]: Soliciting pool server 66.118.230.14 Aug 7 14:56:03 teslausb daemon.info ntpd[479]: Soliciting pool server 38.17.55.196 Aug 7 14:56:04 teslausb daemon.info ntpd[479]: Soliciting pool server 142.147.88.111 Aug 7 14:56:54 teslausb daemon.info ntpd[479]: Soliciting pool server 159.203.82.102 Aug 7 14:56:55 teslausb daemon.info ntpd[479]: Soliciting pool server 129.146.193.200 Aug 7 14:56:56 teslausb daemon.info ntpd[479]: Soliciting pool server 194.116.227.255 Aug 7 14:56:58 teslausb daemon.info ntpd[479]: Soliciting pool server 45.83.234.123 Aug 7 14:56:58 teslausb daemon.info ntpd[479]: Soliciting pool server 62.72.0.70 Aug 7 14:56:59 teslausb daemon.info ntpd[479]: Soliciting pool server 38.17.55.111 Aug 7 14:57:00 teslausb daemon.info ntpd[479]: Soliciting pool server 108.61.73.244 Aug 7 14:57:01 teslausb daemon.info ntpd[479]: Soliciting pool server 5.161.186.39 Aug 7 14:57:07 teslausb daemon.info ntpd[479]: receive: Unexpected origin timestamp 0xe87bbd01.8b2885d4 does not match aorg 0xe87bbd03.8b243540 from server@38.17.55.111 xmt 0xe87bbd03.a5184465 Aug 7 14:57:39 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:57:39 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:57:43 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:58:25 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:58:25 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:58:28 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:58:57 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/enable_gadget.sh Aug 7 14:58:57 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 7 14:58:57 teslausb authpriv.info sudo: pam_unix(sudo:session): session closed for user root Aug 7 14:59:06 teslausb authpriv.notice sudo: www-data : TTY=unknown ; PWD=/var/www/html/cgi-bin ; USER=root ; COMMAND=/root/bin/setup-teslausb diagnose Aug 7 14:59:06 teslausb authpriv.info sudo: pam_unix(sudo:session): session opened for user root by (uid=0) ====== dmesg ====== [Mon Aug 7 14:54:04 2023] Booting Linux on physical CPU 0x0 [Mon Aug 7 14:54:04 2023] Linux version 5.10.63+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1459 Wed Oct 6 16:40:27 BST 2021 [Mon Aug 7 14:54:04 2023] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d [Mon Aug 7 14:54:04 2023] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache [Mon Aug 7 14:54:04 2023] OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1 [Mon Aug 7 14:54:04 2023] random: fast init done [Mon Aug 7 14:54:04 2023] Memory policy: Data cache writeback [Mon Aug 7 14:54:04 2023] Reserved memory: created CMA memory pool at 0x17c00000, size 64 MiB [Mon Aug 7 14:54:04 2023] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [Mon Aug 7 14:54:04 2023] Zone ranges: [Mon Aug 7 14:54:04 2023] Normal [mem 0x0000000000000000-0x000000001bffffff] [Mon Aug 7 14:54:04 2023] Movable zone start for each node [Mon Aug 7 14:54:04 2023] Early memory node ranges [Mon Aug 7 14:54:04 2023] node 0: [mem 0x0000000000000000-0x000000001bffffff] [Mon Aug 7 14:54:04 2023] Initmem setup node 0 [mem 0x0000000000000000-0x000000001bffffff] [Mon Aug 7 14:54:04 2023] On node 0 totalpages: 114688 [Mon Aug 7 14:54:04 2023] Normal zone: 1008 pages used for memmap [Mon Aug 7 14:54:04 2023] Normal zone: 0 pages reserved [Mon Aug 7 14:54:04 2023] Normal zone: 114688 pages, LIFO batch:31 [Mon Aug 7 14:54:04 2023] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [Mon Aug 7 14:54:04 2023] pcpu-alloc: [0] 0 [Mon Aug 7 14:54:04 2023] Built 1 zonelists, mobility grouping on. Total pages: 113680 [Mon Aug 7 14:54:04 2023] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:D2:61:6F vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=8b3aede6-02 rootfstype=ext4 fsck.repair=yes rootwait modules-load=dwc2,g_ether quiet fsck.mode=auto noswap ro [Mon Aug 7 14:54:04 2023] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) [Mon Aug 7 14:54:04 2023] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) [Mon Aug 7 14:54:04 2023] mem auto-init: stack:off, heap alloc:off, heap free:off [Mon Aug 7 14:54:04 2023] Memory: 374400K/458752K available (8683K kernel code, 1324K rwdata, 2824K rodata, 420K init, 837K bss, 18816K reserved, 65536K cma-reserved) [Mon Aug 7 14:54:04 2023] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [Mon Aug 7 14:54:04 2023] ftrace: allocating 30717 entries in 60 pages [Mon Aug 7 14:54:04 2023] ftrace: allocated 60 pages with 4 groups [Mon Aug 7 14:54:04 2023] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [Mon Aug 7 14:54:04 2023] random: get_random_bytes called from start_kernel+0x324/0x560 with crng_init=1 [Mon Aug 7 14:54:04 2023] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns [Mon Aug 7 14:54:04 2023] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns [Mon Aug 7 14:54:04 2023] bcm2835: system timer (irq = 27) [Mon Aug 7 14:54:04 2023] Console: colour dummy device 80x30 [Mon Aug 7 14:54:04 2023] printk: console [tty1] enabled [Mon Aug 7 14:54:04 2023] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792) [Mon Aug 7 14:54:04 2023] pid_max: default: 32768 minimum: 301 [Mon Aug 7 14:54:04 2023] LSM: Security Framework initializing [Mon Aug 7 14:54:04 2023] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [Mon Aug 7 14:54:04 2023] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) [Mon Aug 7 14:54:04 2023] cgroup: Disabling memory control group subsystem [Mon Aug 7 14:54:04 2023] CPU: Testing write buffer coherency: ok [Mon Aug 7 14:54:04 2023] Setting up static identity map for 0x8200 - 0x8238 [Mon Aug 7 14:54:04 2023] devtmpfs: initialized [Mon Aug 7 14:54:04 2023] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5 [Mon Aug 7 14:54:04 2023] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [Mon Aug 7 14:54:04 2023] futex hash table entries: 256 (order: -1, 3072 bytes, linear) [Mon Aug 7 14:54:04 2023] pinctrl core: initialized pinctrl subsystem [Mon Aug 7 14:54:04 2023] NET: Registered protocol family 16 [Mon Aug 7 14:54:04 2023] DMA: preallocated 1024 KiB pool for atomic coherent allocations [Mon Aug 7 14:54:04 2023] audit: initializing netlink subsys (disabled) [Mon Aug 7 14:54:04 2023] thermal_sys: Registered thermal governor 'step_wise' [Mon Aug 7 14:54:04 2023] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. [Mon Aug 7 14:54:04 2023] hw-breakpoint: maximum watchpoint size is 4 bytes. [Mon Aug 7 14:54:04 2023] Serial: AMBA PL011 UART driver [Mon Aug 7 14:54:04 2023] audit: type=2000 audit(0.100:1): state=initialized audit_enabled=0 res=1 [Mon Aug 7 14:54:04 2023] bcm2835-mbox 2000b880.mailbox: mailbox enabled [Mon Aug 7 14:54:04 2023] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-10-29T10:49:08, variant start [Mon Aug 7 14:54:04 2023] raspberrypi-firmware soc:firmware: Firmware hash is b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13 [Mon Aug 7 14:54:04 2023] Kprobes globally optimized [Mon Aug 7 14:54:04 2023] bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1 [Mon Aug 7 14:54:04 2023] SCSI subsystem initialized [Mon Aug 7 14:54:04 2023] usbcore: registered new interface driver usbfs [Mon Aug 7 14:54:04 2023] usbcore: registered new interface driver hub [Mon Aug 7 14:54:04 2023] usbcore: registered new device driver usb [Mon Aug 7 14:54:04 2023] clocksource: Switched to clocksource timer [Mon Aug 7 14:54:06 2023] VFS: Disk quotas dquot_6.6.0 [Mon Aug 7 14:54:06 2023] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [Mon Aug 7 14:54:06 2023] FS-Cache: Loaded [Mon Aug 7 14:54:06 2023] CacheFiles: Loaded [Mon Aug 7 14:54:06 2023] NET: Registered protocol family 2 [Mon Aug 7 14:54:06 2023] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) [Mon Aug 7 14:54:06 2023] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) [Mon Aug 7 14:54:06 2023] TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) [Mon Aug 7 14:54:06 2023] TCP bind hash table entries: 4096 (order: 2, 16384 bytes, linear) [Mon Aug 7 14:54:06 2023] TCP: Hash tables configured (established 4096 bind 4096) [Mon Aug 7 14:54:06 2023] UDP hash table entries: 256 (order: 0, 4096 bytes, linear) [Mon Aug 7 14:54:06 2023] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear) [Mon Aug 7 14:54:06 2023] NET: Registered protocol family 1 [Mon Aug 7 14:54:06 2023] RPC: Registered named UNIX socket transport module. [Mon Aug 7 14:54:06 2023] RPC: Registered udp transport module. [Mon Aug 7 14:54:06 2023] RPC: Registered tcp transport module. [Mon Aug 7 14:54:06 2023] RPC: Registered tcp NFSv4.1 backchannel transport module. [Mon Aug 7 14:54:06 2023] hw perfevents: no irqs for PMU, sampling events not supported [Mon Aug 7 14:54:06 2023] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available [Mon Aug 7 14:54:06 2023] Initialise system trusted keyrings [Mon Aug 7 14:54:06 2023] workingset: timestamp_bits=14 max_order=17 bucket_order=3 [Mon Aug 7 14:54:06 2023] zbud: loaded [Mon Aug 7 14:54:06 2023] FS-Cache: Netfs 'nfs' registered for caching [Mon Aug 7 14:54:06 2023] NFS: Registering the id_resolver key type [Mon Aug 7 14:54:06 2023] Key type id_resolver registered [Mon Aug 7 14:54:06 2023] Key type id_legacy registered [Mon Aug 7 14:54:06 2023] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [Mon Aug 7 14:54:06 2023] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [Mon Aug 7 14:54:06 2023] Key type asymmetric registered [Mon Aug 7 14:54:06 2023] Asymmetric key parser 'x509' registered [Mon Aug 7 14:54:06 2023] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [Mon Aug 7 14:54:06 2023] io scheduler mq-deadline registered [Mon Aug 7 14:54:06 2023] io scheduler kyber registered [Mon Aug 7 14:54:06 2023] bcm2708_fb soc:fb: FB found 1 display(s) [Mon Aug 7 14:54:06 2023] Console: switching to colour frame buffer device 82x26 [Mon Aug 7 14:54:06 2023] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416 [Mon Aug 7 14:54:06 2023] bcm2835-rng 20104000.rng: hwrng registered [Mon Aug 7 14:54:06 2023] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB) [Mon Aug 7 14:54:06 2023] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000 [Mon Aug 7 14:54:06 2023] brd: module loaded [Mon Aug 7 14:54:06 2023] loop: module loaded [Mon Aug 7 14:54:06 2023] Loading iSCSI transport class v2.0-870. [Mon Aug 7 14:54:06 2023] libphy: Fixed MDIO Bus: probed [Mon Aug 7 14:54:06 2023] usbcore: registered new interface driver smsc95xx [Mon Aug 7 14:54:06 2023] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [Mon Aug 7 14:54:06 2023] dwc_otg: FIQ enabled [Mon Aug 7 14:54:06 2023] dwc_otg: NAK holdoff enabled [Mon Aug 7 14:54:06 2023] dwc_otg: FIQ split-transaction FSM enabled [Mon Aug 7 14:54:06 2023] Module dwc_common_port init [Mon Aug 7 14:54:06 2023] usbcore: registered new interface driver usb-storage [Mon Aug 7 14:54:06 2023] mousedev: PS/2 mouse device common for all mice [Mon Aug 7 14:54:06 2023] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [Mon Aug 7 14:54:06 2023] sdhci: Secure Digital Host Controller Interface driver [Mon Aug 7 14:54:06 2023] sdhci: Copyright(c) Pierre Ossman [Mon Aug 7 14:54:06 2023] mmc-bcm2835 20300000.mmcnr: could not get clk, deferring probe [Mon Aug 7 14:54:06 2023] sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe [Mon Aug 7 14:54:06 2023] sdhci-pltfm: SDHCI platform and OF driver helper [Mon Aug 7 14:54:06 2023] ledtrig-cpu: registered to indicate activity on CPUs [Mon Aug 7 14:54:06 2023] hid: raw HID events driver (C) Jiri Kosina [Mon Aug 7 14:54:06 2023] usbcore: registered new interface driver usbhid [Mon Aug 7 14:54:06 2023] usbhid: USB HID core driver [Mon Aug 7 14:54:06 2023] Initializing XFRM netlink socket [Mon Aug 7 14:54:06 2023] NET: Registered protocol family 17 [Mon Aug 7 14:54:06 2023] Key type dns_resolver registered [Mon Aug 7 14:54:06 2023] registered taskstats version 1 [Mon Aug 7 14:54:06 2023] Loading compiled-in X.509 certificates [Mon Aug 7 14:54:06 2023] uart-pl011 20201000.serial: cts_event_workaround enabled [Mon Aug 7 14:54:06 2023] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2 [Mon Aug 7 14:54:06 2023] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [Mon Aug 7 14:54:06 2023] mmc-bcm2835 20300000.mmcnr: mmc_debug:0 mmc_debug2:0 [Mon Aug 7 14:54:06 2023] mmc-bcm2835 20300000.mmcnr: DMA channel allocated [Mon Aug 7 14:54:06 2023] sdhost: log_buf @ (ptrval) (97d10000) [Mon Aug 7 14:54:06 2023] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [Mon Aug 7 14:54:06 2023] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [Mon Aug 7 14:54:06 2023] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [Mon Aug 7 14:54:06 2023] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [Mon Aug 7 14:54:06 2023] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [Mon Aug 7 14:54:06 2023] of_cfs_init [Mon Aug 7 14:54:06 2023] of_cfs_init: OK [Mon Aug 7 14:54:06 2023] Waiting for root device PARTUUID=8b3aede6-02... [Mon Aug 7 14:54:06 2023] mmc0: host does not support reading read-only switch, assuming write-enable [Mon Aug 7 14:54:06 2023] mmc0: new high speed SDXC card at address 0001 [Mon Aug 7 14:54:06 2023] mmcblk0: mmc0:0001 GD4QT 119 GiB [Mon Aug 7 14:54:06 2023] mmcblk0: p1 p2 p3 p4 [Mon Aug 7 14:54:06 2023] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [Mon Aug 7 14:54:06 2023] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [Mon Aug 7 14:54:06 2023] devtmpfs: mounted [Mon Aug 7 14:54:06 2023] Freeing unused kernel memory: 420K [Mon Aug 7 14:54:06 2023] Kernel memory protection not selected by kernel config. [Mon Aug 7 14:54:06 2023] Run /sbin/init as init process [Mon Aug 7 14:54:06 2023] with arguments: [Mon Aug 7 14:54:06 2023] /sbin/init [Mon Aug 7 14:54:06 2023] noswap [Mon Aug 7 14:54:06 2023] with environment: [Mon Aug 7 14:54:06 2023] HOME=/ [Mon Aug 7 14:54:06 2023] TERM=linux [Mon Aug 7 14:54:06 2023] modules-load=dwc2,g_ether [Mon Aug 7 14:54:06 2023] mmc1: new high speed SDIO card at address 0001 [Mon Aug 7 14:54:07 2023] systemd[1]: System time before build time, advancing clock. [Mon Aug 7 14:54:07 2023] NET: Registered protocol family 10 [Mon Aug 7 14:54:07 2023] Segment Routing with IPv6 [Mon Aug 7 14:54:07 2023] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) [Mon Aug 7 14:54:07 2023] systemd[1]: Detected architecture arm. [Mon Aug 7 14:54:07 2023] systemd[1]: Set hostname to . [Mon Aug 7 14:54:10 2023] random: systemd: uninitialized urandom read (16 bytes read) [Mon Aug 7 14:54:10 2023] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. [Mon Aug 7 14:54:10 2023] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. [Mon Aug 7 14:54:10 2023] systemd[1]: /lib/systemd/system/autofs.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/autofs.pid → /run/autofs.pid; please update the unit file accordingly. [Mon Aug 7 14:54:10 2023] random: systemd: uninitialized urandom read (16 bytes read) [Mon Aug 7 14:54:10 2023] systemd[1]: Listening on initctl Compatibility Named Pipe. [Mon Aug 7 14:54:10 2023] random: systemd: uninitialized urandom read (16 bytes read) [Mon Aug 7 14:54:10 2023] systemd[1]: Listening on Journal Audit Socket. [Mon Aug 7 14:54:10 2023] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [Mon Aug 7 14:54:10 2023] systemd[1]: Listening on fsck to fsckd communication Socket. [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: supply vusb_d not found, using dummy regulator [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: supply vusb_a not found, using dummy regulator [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: DWC OTG Controller [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: new USB bus registered, assigned bus number 1 [Mon Aug 7 14:54:12 2023] dwc2 20980000.usb: irq 33, io mem 0x20980000 [Mon Aug 7 14:54:12 2023] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [Mon Aug 7 14:54:12 2023] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [Mon Aug 7 14:54:12 2023] usb usb1: Product: DWC OTG Controller [Mon Aug 7 14:54:12 2023] usb usb1: Manufacturer: Linux 5.10.63+ dwc2_hsotg [Mon Aug 7 14:54:12 2023] usb usb1: SerialNumber: 20980000.usb [Mon Aug 7 14:54:12 2023] hub 1-0:1.0: USB hub found [Mon Aug 7 14:54:12 2023] hub 1-0:1.0: 1 port detected [Mon Aug 7 14:54:13 2023] using random self ethernet address [Mon Aug 7 14:54:13 2023] using random host ethernet address [Mon Aug 7 14:54:13 2023] usb0: HOST MAC 12:98:de:59:c1:80 [Mon Aug 7 14:54:13 2023] usb0: MAC 36:61:fb:37:34:4a [Mon Aug 7 14:54:13 2023] using random self ethernet address [Mon Aug 7 14:54:13 2023] using random host ethernet address [Mon Aug 7 14:54:13 2023] g_ether gadget: Ethernet Gadget, version: Memorial Day 2008 [Mon Aug 7 14:54:13 2023] g_ether gadget: g_ether ready [Mon Aug 7 14:54:13 2023] dwc2 20980000.usb: bound driver g_ether [Mon Aug 7 14:54:13 2023] systemd[1]: Started Load Kernel Modules. [Mon Aug 7 14:54:13 2023] dwc2 20980000.usb: new device is high-speed [Mon Aug 7 14:54:13 2023] systemd[1]: Starting Apply Kernel Variables... [Mon Aug 7 14:54:13 2023] systemd[1]: Condition check resulted in FUSE Control File System being skipped. [Mon Aug 7 14:54:13 2023] systemd[1]: Mounting Kernel Configuration File System... [Mon Aug 7 14:54:13 2023] dwc2 20980000.usb: new device is high-speed [Mon Aug 7 14:54:13 2023] dwc2 20980000.usb: new device is high-speed [Mon Aug 7 14:54:13 2023] dwc2 20980000.usb: new address 2 [Mon Aug 7 14:54:13 2023] systemd[1]: Mounted Kernel Configuration File System. [Mon Aug 7 14:54:13 2023] systemd[1]: Started Journal Service. [Mon Aug 7 14:54:14 2023] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) [Mon Aug 7 14:54:15 2023] systemd-journald[90]: Received request to flush runtime journal from PID 1 [Mon Aug 7 14:54:20 2023] mc: Linux media interface: v0.10 [Mon Aug 7 14:54:20 2023] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [Mon Aug 7 14:54:20 2023] [vc_sm_connected_init]: start [Mon Aug 7 14:54:20 2023] [vc_sm_connected_init]: installed successfully [Mon Aug 7 14:54:20 2023] videodev: Linux video capture interface: v2.00 [Mon Aug 7 14:54:20 2023] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:20 2023] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [Mon Aug 7 14:54:20 2023] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [Mon Aug 7 14:54:20 2023] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [Mon Aug 7 14:54:21 2023] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [Mon Aug 7 14:54:21 2023] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [Mon Aug 7 14:54:21 2023] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [Mon Aug 7 14:54:21 2023] bcm2835_audio bcm2835_audio: there is not valid maps for state default [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Device registered as /dev/video18 [Mon Aug 7 14:54:21 2023] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx [Mon Aug 7 14:54:23 2023] cfg80211: Loading compiled-in X.509 certificates for regulatory database [Mon Aug 7 14:54:23 2023] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [Mon Aug 7 14:54:24 2023] brcmfmac: F1 signature read @0x18000000=0x1541a9a6 [Mon Aug 7 14:54:24 2023] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [Mon Aug 7 14:54:24 2023] usbcore: registered new interface driver brcmfmac [Mon Aug 7 14:54:24 2023] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [Mon Aug 7 14:54:24 2023] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [Mon Aug 7 14:54:24 2023] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available [Mon Aug 7 14:54:24 2023] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd [Mon Aug 7 14:54:27 2023] SGI XFS with ACLs, security attributes, realtime, quota, no debug enabled [Mon Aug 7 14:54:29 2023] XFS (mmcblk0p3): Mounting V5 Filesystem [Mon Aug 7 14:54:30 2023] XFS (mmcblk0p3): Starting recovery (logdev: internal) [Mon Aug 7 14:54:30 2023] XFS (mmcblk0p3): Ending recovery (logdev: internal) [Mon Aug 7 14:54:30 2023] xfs filesystem being mounted at /backingfiles supports timestamps until 2038 (0x7fffffff) [Mon Aug 7 14:54:33 2023] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: (null) [Mon Aug 7 14:54:33 2023] ext4 filesystem being mounted at /mutable supports timestamps until 2038 (0x7fffffff) [Mon Aug 7 14:54:35 2023] IPv6: ADDRCONF(NETDEV_CHANGE): ap0: link becomes ready [Mon Aug 7 14:54:35 2023] fuse: init (API version 7.32) [Mon Aug 7 14:54:36 2023] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [Mon Aug 7 14:54:37 2023] loop0: p1 [Mon Aug 7 14:54:37 2023] random: crng init done [Mon Aug 7 14:54:37 2023] random: 7 urandom warning(s) missed due to ratelimiting [Mon Aug 7 14:54:42 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [Mon Aug 7 14:54:45 2023] loop0: p1 [Mon Aug 7 14:54:51 2023] loop0: p1 [Mon Aug 7 14:55:03 2023] FS-Cache: Netfs 'cifs' registered for caching [Mon Aug 7 14:55:03 2023] Key type cifs.spnego registered [Mon Aug 7 14:55:03 2023] Key type cifs.idmap registered [Mon Aug 7 14:55:03 2023] CIFS: Attempting to mount //192.168.1.3/TeslaCam [Mon Aug 7 14:55:09 2023] CIFS: Attempting to mount //192.168.1.3/TeslaMusic [Mon Aug 7 14:58:56 2023] Mass Storage Function, version: 2009/09/11 [Mon Aug 7 14:58:56 2023] LUN: removable file: (no medium) [Mon Aug 7 14:58:57 2023] LUN: removable file: (no medium) [Mon Aug 7 14:58:57 2023] dwc2 20980000.usb: bound driver configfs-gadget [Mon Aug 7 14:58:57 2023] dwc2 20980000.usb: new device is high-speed [Mon Aug 7 14:58:57 2023] dwc2 20980000.usb: new device is high-speed [Mon Aug 7 14:58:57 2023] dwc2 20980000.usb: new address 2 ====== process list and uptime ====== UID PID PPID C STIME TTY TIME CMD root 1 0 3 14:54 ? 00:00:11 /sbin/init noswap root 2 0 0 14:54 ? 00:00:00 [kthreadd] root 5 2 0 14:54 ? 00:00:01 [kworker/u2:0-events_unbound] root 6 2 0 14:54 ? 00:00:00 [mm_percpu_wq] root 7 2 0 14:54 ? 00:00:00 [rcu_tasks_rude_] root 8 2 0 14:54 ? 00:00:00 [rcu_tasks_trace] root 9 2 0 14:54 ? 00:00:02 [ksoftirqd/0] root 10 2 0 14:54 ? 00:00:00 [kdevtmpfs] root 11 2 0 14:54 ? 00:00:00 [netns] root 13 2 0 14:54 ? 00:00:00 [kauditd] root 14 2 0 14:54 ? 00:00:02 [kworker/0:2-events] root 15 2 0 14:54 ? 00:00:00 [khungtaskd] root 16 2 0 14:54 ? 00:00:00 [oom_reaper] root 17 2 0 14:54 ? 00:00:00 [writeback] root 18 2 0 14:54 ? 00:00:00 [kcompactd0] root 33 2 0 14:54 ? 00:00:00 [kblockd] root 34 2 0 14:54 ? 00:00:00 [blkcg_punt_bio] root 35 2 0 14:54 ? 00:00:00 [watchdogd] root 36 2 0 14:54 ? 00:00:00 [rpciod] root 37 2 0 14:54 ? 00:00:00 [kworker/u3:0] root 38 2 0 14:54 ? 00:00:00 [xprtiod] root 39 2 0 14:54 ? 00:00:00 [kswapd0] root 40 2 0 14:54 ? 00:00:00 [nfsiod] root 41 2 0 14:54 ? 00:00:00 [iscsi_eh] root 42 2 0 14:54 ? 00:00:00 [iscsi_destroy] root 43 2 0 14:54 ? 00:00:00 [DWC Notificatio] root 44 2 0 14:54 ? 00:00:00 [vchiq-slot/0] root 45 2 0 14:54 ? 00:00:00 [vchiq-recy/0] root 46 2 0 14:54 ? 00:00:00 [vchiq-sync/0] root 47 2 0 14:54 ? 00:00:00 [zswap-shrink] root 49 2 0 14:54 ? 00:00:00 [kworker/0:4-events_power_efficient] root 50 2 0 14:54 ? 00:00:01 [kworker/0:5-events] root 51 2 0 14:54 ? 00:00:00 [kworker/0:6-events_power_efficient] root 52 2 0 14:54 ? 00:00:00 [mmc_complete] root 53 2 0 14:54 ? 00:00:01 [kworker/0:1H-kblockd] root 54 2 0 14:54 ? 00:00:00 [jbd2/mmcblk0p2-] root 55 2 0 14:54 ? 00:00:00 [ext4-rsv-conver] root 56 2 0 14:54 ? 00:00:01 [kworker/0:2H-mmc_complete] root 57 2 0 14:54 ? 00:00:00 [ipv6_addrconf] root 58 2 2 14:54 ? 00:00:06 [kworker/u2:1-brcmf_wq/mmc1:0001:1] root 90 1 0 14:54 ? 00:00:01 /lib/systemd/systemd-journald root 105 2 0 14:54 ? 00:00:01 [kworker/u2:2-events_unbound] root 117 1 0 14:54 ? 00:00:02 /lib/systemd/systemd-udevd root 134 2 0 14:54 ? 00:00:00 [vchiq-keep/0] root 135 2 0 14:54 ? 00:00:00 [SMIO] root 142 2 0 14:54 ? 00:00:00 [mmal-vchiq] root 145 2 0 14:54 ? 00:00:00 [mmal-vchiq] root 147 2 0 14:54 ? 00:00:00 [mmal-vchiq] root 148 2 0 14:54 ? 00:00:00 [mmal-vchiq] root 150 2 0 14:54 ? 00:00:00 [mmal-vchiq] root 182 2 0 14:54 ? 00:00:00 [cfg80211] root 183 2 0 14:54 ? 00:00:00 [brcmf_wq/mmc1:0] root 184 2 0 14:54 ? 00:00:00 [brcmf_wdog/mmc1] root 204 2 0 14:54 ? 00:00:00 [xfsalloc] root 205 2 0 14:54 ? 00:00:00 [xfs_mru_cache] root 206 2 0 14:54 ? 00:00:00 [xfs-buf/mmcblk0] root 207 2 0 14:54 ? 00:00:00 [xfs-conv/mmcblk] root 209 2 0 14:54 ? 00:00:00 [xfs-cil/mmcblk0] root 210 2 0 14:54 ? 00:00:00 [xfs-reclaim/mmc] root 211 2 0 14:54 ? 00:00:00 [xfs-eofblocks/m] root 222 2 0 14:54 ? 00:00:00 [xfs-log/mmcblk0] root 223 2 0 14:54 ? 00:00:00 [xfsaild/mmcblk0] root 242 2 0 14:54 ? 00:00:00 [jbd2/mmcblk0p4-] root 243 2 0 14:54 ? 00:00:00 [ext4-rsv-conver] root 278 1 0 14:54 ? 00:00:00 /bin/bash /root/bin/archiveloop root 295 278 0 14:54 ? 00:00:00 flock -en -E 99 /root/bin/archiveloop /root/bin/archiveloop root 301 295 0 14:54 ? 00:00:00 /bin/bash -eu /root/bin/archiveloop root 316 1 0 14:54 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.ap0.pid /etc/hostapd/hostapd.conf root 325 1 0 14:54 ? 00:00:00 /root/cttseraser /mutable/TeslaCam /var/www/html/TeslaCam -o rw,dev,suid -o allow_other root 339 301 0 14:54 ? 00:00:00 /bin/bash -eu /root/bin/archiveloop root 340 301 0 14:54 ? 00:00:00 /bin/bash -eu /root/bin/archiveloop root 341 301 0 14:54 ? 00:00:00 /bin/bash -eu /root/bin/archiveloop root 345 341 0 14:54 ? 00:00:00 dmesg -w root 346 341 0 14:54 ? 00:00:00 /bin/bash -eu /root/bin/archiveloop root 348 339 0 14:54 ? 00:00:00 sleep 3480 avahi 352 1 0 14:54 ? 00:00:00 avahi-daemon: running [teslausb.local] root 355 1 0 14:54 ? 00:00:00 /usr/sbin/cron -f avahi 361 352 0 14:54 ? 00:00:00 avahi-daemon: chroot helper root 376 1 0 14:54 ? 00:00:00 /usr/sbin/rngd -r /dev/hwrng root 383 1 0 14:54 ? 00:00:01 /lib/systemd/systemd-logind message+ 393 1 1 14:54 ? 00:00:02 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 397 340 0 14:54 ? 00:00:00 sleep 1800 root 401 1 0 14:54 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 407 1 0 14:54 ? 00:00:00 /sbin/wpa_supplicant -s -B -P /run/wpa_supplicant.wlan0.pid -i wlan0 -W -D nl80211,wext -c /etc/wpa_supplicant/wpa_supplicant.conf root 414 1 0 14:54 ? 00:00:00 /sbin/syslogd -C128 root 446 1 0 14:54 ? 00:00:00 /sbin/wpa_cli -B -P /run/wpa_action.wlan0.pid -i wlan0 -p /var/run/wpa_supplicant -a /sbin/wpa_action root 478 1 0 14:54 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group ntp 479 1 0 14:54 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 110:116 root 482 1 0 14:54 ? 00:00:00 /usr/sbin/automount --pid-file /var/run/autofs.pid root 511 1 0 14:54 ? 00:00:00 /usr/sbin/sshd -D root 517 1 0 14:54 ? 00:00:00 /sbin/dhclient -4 -v -i -pf /run/dhclient.wlan0.pid -lf /var/lib/dhcp/dhclient.wlan0.leases -I -df /var/lib/dhcp/dhclient6.wlan0.leases wlan0 root 518 1 0 14:54 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux dnsmasq 527 1 0 14:54 ? 00:00:00 /usr/sbin/dnsmasq -x /run/dnsmasq/dnsmasq.pid -u dnsmasq -r /run/dnsmasq/resolv.conf -7 /etc/dnsmasq.d,.dpkg-dist,.dpkg-old,.dpkg-new --local-service --trust-anchor=.,20326,8,2,e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d root 619 1 0 14:54 ? 00:00:01 /usr/sbin/smbd --foreground --no-process-group root 680 1 0 14:54 ? 00:00:00 nginx: master process /usr/sbin/nginx -g daemon on; master_process on; www-data 681 680 0 14:54 ? 00:00:00 nginx: worker process www-data 702 1 0 14:54 ? 00:00:00 /usr/sbin/fcgiwrap -c 4 -f root 706 619 0 14:54 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 707 619 0 14:54 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group www-data 710 702 0 14:54 ? 00:00:00 /usr/sbin/fcgiwrap -c 4 -f www-data 711 702 0 14:54 ? 00:00:00 /usr/sbin/fcgiwrap -c 4 -f www-data 712 702 0 14:54 ? 00:00:00 /usr/sbin/fcgiwrap -c 4 -f www-data 713 702 0 14:54 ? 00:00:00 /usr/sbin/fcgiwrap -c 4 -f root 721 619 0 14:54 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 745 2 0 14:54 ? 00:00:00 [loop1] root 917 2 0 14:55 ? 00:00:00 [cifsiod] root 918 2 0 14:55 ? 00:00:00 [smb3decryptd] root 919 2 0 14:55 ? 00:00:00 [cifsfileinfoput] root 920 2 0 14:55 ? 00:00:00 [cifsoplockd] root 921 2 0 14:55 ? 00:00:00 [cifs-dfscache] root 923 2 0 14:55 ? 00:00:00 [cifsd] root 1030 301 0 14:55 ? 00:00:00 /bin/bash -eu /root/bin/archive-clips.sh /tmp/cam/merged /tmp/sentry_files /tmp/triggers /tmp/triggers.txt root 1031 1030 0 14:55 ? 00:00:00 /bin/bash -eu /root/bin/archive-clips.sh /tmp/cam/merged /tmp/sentry_files /tmp/triggers /tmp/triggers.txt root 1145 1030 0 14:55 ? 00:00:00 /bin/bash -eu /root/bin/archive-clips.sh /tmp/cam/merged /tmp/sentry_files /tmp/triggers /tmp/triggers.txt root 1147 1145 0 14:55 ? 00:00:00 rsync -avhRL --remove-source-files --temp-dir=.teslausbtmp --no-perms --omit-dir-times --stats --log-file=/tmp/archive-rsync-cmd.log --ignore-missing-args --files-from=/tmp/sentry_files /tmp/cam/merged/ /mnt/archive root 1150 1147 0 14:55 ? 00:00:00 rsync -avhRL --remove-source-files --temp-dir=.teslausbtmp --no-perms --omit-dir-times --stats --log-file=/tmp/archive-rsync-cmd.log --ignore-missing-args --files-from=/tmp/sentry_files /tmp/cam/merged/ /mnt/archive root 1155 1150 0 14:55 ? 00:00:01 rsync -avhRL --remove-source-files --temp-dir=.teslausbtmp --no-perms --omit-dir-times --stats --log-file=/tmp/archive-rsync-cmd.log --ignore-missing-args --files-from=/tmp/sentry_files /tmp/cam/merged/ /mnt/archive root 1356 2 1 14:55 ? 00:00:02 [kworker/u2:3-events_unbound] root 2426 2 4 14:58 ? 00:00:00 [file-storage] root 2458 1031 0 14:59 ? 00:00:00 sleep 5 www-data 2459 710 0 14:59 ? 00:00:00 /bin/bash /var/www/html/cgi-bin/diagnose.sh root 2460 2459 1 14:59 ? 00:00:00 sudo /root/bin/setup-teslausb diagnose root 2461 2460 0 14:59 ? 00:00:00 /bin/bash -eu /root/bin/setup-teslausb diagnose root 2463 2461 0 14:59 ? 00:00:00 flock -en -E 99 /root/bin/setup-teslausb /root/bin/setup-teslausb diagnose root 2464 2463 4 14:59 ? 00:00:00 /bin/bash -eu /root/bin/setup-teslausb diagnose root 2479 2464 2 14:59 ? 00:00:00 /bin/bash -eu /root/bin/setup-teslausb diagnose root 2480 2464 0 14:59 ? 00:00:00 tr \r \n root 2482 2464 0 14:59 ? 00:00:00 sed /^ *$/d root 2483 2464 0 14:59 ? 00:00:00 grep -a -v ^Reading package lists root 2484 2464 0 14:59 ? 00:00:00 grep -a -v ^(Reading database root 2485 2464 0 14:59 ? 00:00:00 grep -a -v ^Adding 'diversion of root 2486 2464 0 14:59 ? 00:00:00 grep -a -v ^Removing 'diversion of root 2487 2464 0 14:59 ? 00:00:00 sed -E s/\o033\[0;32m// root 2488 2464 0 14:59 ? 00:00:00 sed -E s/\o033\[0m// root 2545 117 5 14:59 ? 00:00:00 /lib/systemd/systemd-udevd root 2546 117 3 14:59 ? 00:00:00 /lib/systemd/systemd-udevd root 2547 117 6 14:59 ? 00:00:00 /lib/systemd/systemd-udevd root 2548 117 4 14:59 ? 00:00:00 /lib/systemd/systemd-udevd root 2571 2479 0 14:59 ? 00:00:00 ps -eaf teslausb has been up 5 minutes. System time is Mon 7 Aug 14:59:10 EDT 2023 ====== end of diagnostics ======