Failed Update?

piercedfreak · 1 February 2020 20:06

When I try to update via the gui it always fails. I get this via sudo journalctl -f while updating.

Feb 01 14:58:43 osmc systemd[1]: Stopped media center application.
Feb 01 14:58:44 osmc manual-update[21154]: open /dev/tty: No such device or address
Feb 01 14:58:44 osmc manual-update[21154]: debconf: whiptail output the above errors, giving up!
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in scalar chomp at /usr/share/perl5/Debconf/Client/ConfModule.pm line 132, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in split at /usr/share/perl5/Debconf/Client/ConfModule.pm line 133, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret[0] in string eq at /usr/share/perl5/Debconf/Client/ConfModule.pm line 134, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret[0] in string eq at /usr/bin/debconf-apt-progress line 384, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in scalar chomp at /usr/share/perl5/Debconf/Client/ConfModule.pm line 132, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in split at /usr/share/perl5/Debconf/Client/ConfModule.pm line 133, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret[0] in string eq at /usr/share/perl5/Debconf/Client/ConfModule.pm line 134, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret[0] in string eq at /usr/bin/debconf-apt-progress line 211, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in scalar chomp at /usr/share/perl5/Debconf/Client/ConfModule.pm line 132, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret in split at /usr/share/perl5/Debconf/Client/ConfModule.pm line 133, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: Use of uninitialized value $ret[0] in string eq at /usr/share/perl5/Debconf/Client/ConfModule.pm line 134, <STDIN> line 1.
Feb 01 14:58:44 osmc manual-update[21154]: basename: missing operand
Feb 01 14:58:44 osmc manual-update[21154]: Try 'basename --help' for more information.
Feb 01 14:59:06 osmc systemd[1]: Stopped Setup OSMC machine ID.
Feb 01 14:59:06 osmc systemd[1]: Stopped Timer to report anonymous OSMC install statistics.
Feb 01 14:59:14 osmc systemd[1]: Started media center application.

I can still upgrade via sudo apt-get dist upgrade, but I would like to fix this issue(without reinstalling if possible).

sam_nazarko · 1 February 2020 20:12

Please post the full logs so we can see what the issue is.
Is this a Raspberry Pi? It looks like there could be some SD corruption.

piercedfreak · 1 February 2020 22:18

Yes this is on a Raspberry Pi, but it is running from a thumb drive. It has been doing this for the past 3 updates, but I have just gotten time to actually look into it, instead of running ‘dist upgrade’. The RPI has been running for close to 5 months so the full log is quite large, so I will post from right before I start the manual upgrade from the gui, until it stops. I can find no other errors in the log.

Feb 01 17:07:21 osmc sudo[22135]:     osmc : TTY=pts/3 ; PWD=/home/osmc ; USER=root ; COMMAND=/bin/journalctl -f
Feb 01 17:07:21 osmc sudo[22135]: pam_unix(sudo:session): session opened for user root by osmc(uid=0)
Feb 01 17:07:27 osmc sudo[22135]: pam_unix(sudo:session): session closed for user root
Feb 01 17:07:34 osmc sudo[22139]:     osmc : TTY=pts/3 ; PWD=/home/osmc ; USER=root ; COMMAND=/bin/journalctl -f
Feb 01 17:07:34 osmc sudo[22139]: pam_unix(sudo:session): session opened for user root by osmc(uid=0)
Feb 01 17:07:50 osmc sudo[22153]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /var/tmp/config.txt /boot/config.txt
Feb 01 17:07:50 osmc sudo[22153]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:07:50 osmc mediacenter[21376]: mv: failed to preserve ownership for '/boot/config.txt': Operation not permitted
Feb 01 17:07:50 osmc sudo[22153]: pam_unix(sudo:session): session closed for user root
Feb 01 17:08:00 osmc sudo[22165]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /home/osmc/.factoryreset
Feb 01 17:08:00 osmc sudo[22165]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:08:00 osmc mediacenter[21376]: rm: cannot remove '/home/osmc/.factoryreset': No such file or directory
Feb 01 17:08:00 osmc sudo[22165]: pam_unix(sudo:session): session closed for user root
Feb 01 17:08:01 osmc sudo[22168]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/python /usr/share/kodi/addons/script.module.osmcsetting.updates/resources/lib/apt_cache_action.py update_manual
Feb 01 17:08:01 osmc sudo[22168]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:08:05 osmc mediacenter[21376]: ===================================================================
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:01.506732 apt_cache_action.py running
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:01.506833 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:01.507645 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: Pulse ===========================================
Feb 01 17:08:05 osmc mediacenter[21376]: current_items 11
Feb 01 17:08:05 osmc mediacenter[21376]: total_items 14
Feb 01 17:08:05 osmc mediacenter[21376]: total_bytes 95713
Feb 01 17:08:05 osmc mediacenter[21376]: fetched_bytes 4682
Feb 01 17:08:05 osmc mediacenter[21376]: current_bytes 88285
Feb 01 17:08:05 osmc mediacenter[21376]: current_cps 0
Feb 01 17:08:05 osmc mediacenter[21376]: Pulse ===========================================
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:04.131871 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: Pulse ===========================================
Feb 01 17:08:05 osmc mediacenter[21376]: current_items 29
Feb 01 17:08:05 osmc mediacenter[21376]: total_items 30
Feb 01 17:08:05 osmc mediacenter[21376]: total_bytes 122543
Feb 01 17:08:05 osmc mediacenter[21376]: fetched_bytes 122543
Feb 01 17:08:05 osmc mediacenter[21376]: current_bytes 120520
Feb 01 17:08:05 osmc mediacenter[21376]: current_cps 0
Feb 01 17:08:05 osmc mediacenter[21376]: Pulse ===========================================
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:05.233002 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:05.850769 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:05.851810 apt_cache_action.py sending response
Feb 01 17:08:05 osmc mediacenter[21376]: 2020-02-01 17:08:05.852038 apt_cache_action.py exiting
Feb 01 17:08:05 osmc mediacenter[21376]: ===================================================================
Feb 01 17:08:05 osmc sudo[22168]: pam_unix(sudo:session): session closed for user root
Feb 01 17:08:14 osmc mediacenter[21376]: [1.2K blob data]
Feb 01 17:08:15 osmc mediacenter[21376]: [188B blob data]
Feb 01 17:08:15 osmc mediacenter[21376]: [100B blob data]
Feb 01 17:08:28 osmc sudo[22441]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/python /usr/share/kodi/addons/script.module.osmcsetting.updates/resources/lib/apt_cache_action.py fetch
Feb 01 17:08:28 osmc sudo[22441]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:08:43 osmc mediacenter[21376]: ===================================================================
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:28.263974 apt_cache_action.py running
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.465245 apt_cache_action.py fetching all packages
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.465418 apt_cache_action.py sending response
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.574095 apt_cache_action.py sending response
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.574771 apt_cache_action.py sending response
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.575133 apt_cache_action.py sending response
Feb 01 17:08:43 osmc mediacenter[21376]: 2020-02-01 17:08:43.575383 apt_cache_action.py exiting
Feb 01 17:08:43 osmc mediacenter[21376]: ===================================================================
Feb 01 17:08:43 osmc sudo[22441]: pam_unix(sudo:session): session closed for user root
Feb 01 17:08:53 osmc sudo[22453]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start manual-update
Feb 01 17:08:53 osmc sudo[22453]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:08:53 osmc systemd[1]: Started Updates system outside of mediacenter.
Feb 01 17:08:53 osmc sudo[22453]: pam_unix(sudo:session): session closed for user root
Feb 01 17:08:53 osmc systemd[1]: Stopping media center application...
Feb 01 17:09:00 osmc systemd[1]: Starting Clean php session files...
Feb 01 17:09:00 osmc systemd[1]: Started Clean php session files.
Feb 01 17:09:01 osmc CRON[22508]: pam_unix(cron:session): session opened for user root by (uid=0)
Feb 01 17:09:01 osmc CRON[22509]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Feb 01 17:09:01 osmc CRON[22508]: pam_unix(cron:session): session closed for user root
Feb 01 17:09:02 osmc sudo[21393]: pam_unix(sudo:session): session closed for user osmc
Feb 01 17:09:02 osmc systemd[1]: Stopped media center application.
Feb 01 17:09:02 osmc kernel: Console: switching to colour frame buffer device 160x45
Feb 01 17:09:04 osmc systemd[1]: Reloading.
Feb 01 17:09:05 osmc systemd[1]: Reloading.
Feb 01 17:09:05 osmc systemd[1]: Reloading.
Feb 01 17:09:06 osmc systemd[1]: Reloading.
Feb 01 17:09:06 osmc systemd[1]: Starting Setup OSMC machine ID...
Feb 01 17:09:06 osmc systemd[1]: Started Setup OSMC machine ID.
Feb 01 17:09:06 osmc systemd[1]: Started Timer to report anonymous OSMC install statistics.
Feb 01 17:09:06 osmc systemd[1]: Starting Report anonymous OSMC install statistics...
Feb 01 17:09:08 osmc systemd[1]: Started Report anonymous OSMC install statistics.
Feb 01 17:09:23 osmc manual-update[22455]: open /dev/tty: No such device or address
Feb 01 17:09:23 osmc manual-update[22455]: debconf: whiptail output the above errors, giving up!
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret in scalar chomp at /usr/share/perl5/Debconf/Client/ConfModule.pm line 132, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret in split at /usr/share/perl5/Debconf/Client/ConfModule.pm line 133, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret[0] in string eq at /usr/share/perl5/Debconf/Client/ConfModule.pm line 134, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret[0] in string eq at /usr/bin/debconf-apt-progress line 384, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret in scalar chomp at /usr/share/perl5/Debconf/Client/ConfModule.pm line 132, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret in split at /usr/share/perl5/Debconf/Client/ConfModule.pm line 133, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret[0] in string eq at /usr/share/perl5/Debconf/Client/ConfModule.pm line 134, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: Use of uninitialized value $ret[0] in string eq at /usr/bin/debconf-apt-progress line 211, <STDIN> line 1.
Feb 01 17:09:23 osmc manual-update[22455]: basename: missing operand
Feb 01 17:09:23 osmc manual-update[22455]: Try 'basename --help' for more information.
Feb 01 17:09:50 osmc sudo[22139]: pam_unix(sudo:session): session closed for user root
Feb 01 17:09:53 osmc systemd[1]: Started media center application.
Feb 01 17:09:53 osmc mediacenter[22684]: Starting Kodi...
Feb 01 17:09:53 osmc sudo[22701]:     root : TTY=unknown ; PWD=/ ; USER=osmc ; ENV=LIRC_SOCKET_PATH=/var/run/lirc/lircd ; COMMAND=/usr/lib/kodi/kodi.bin --standalone -fs
Feb 01 17:09:53 osmc sudo[22701]: pam_unix(sudo:session): session opened for user osmc by (uid=0)
Feb 01 17:09:53 osmc sudo[22704]:     osmc : TTY=pts/3 ; PWD=/home/osmc ; USER=root ; COMMAND=/bin/journalctl
Feb 01 17:09:53 osmc sudo[22704]: pam_unix(sudo:session): session opened for user root by osmc(uid=0)
Feb 01 17:09:53 osmc sudo[22707]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /tmp/timezone /etc/timezone
Feb 01 17:09:53 osmc sudo[22707]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:09:53 osmc sudo[22707]: pam_unix(sudo:session): session closed for user root
Feb 01 17:09:54 osmc sudo[22711]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /usr/share/zoneinfo/America/New_York /etc/localtime.dpkg-new
Feb 01 17:09:54 osmc sudo[22711]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:09:54 osmc sudo[22711]: pam_unix(sudo:session): session closed for user root
Feb 01 17:09:54 osmc sudo[22714]:     osmc : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /etc/localtime.dpkg-new /etc/localtime
Feb 01 17:09:54 osmc sudo[22714]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 01 17:09:54 osmc sudo[22714]: pam_unix(sudo:session): session closed for user root
Feb 01 17:09:54 osmc kernel: Console: switching to colour dummy device 80x30

ooZee · 1 February 2020 22:53

If the log is too large, then please reboot twice and then post logs as advised.

fzinken · 2 February 2020 02:09

Well if the error is during GUI update it will be shown in the kodi.log not the kernel log.

Just upload all logs via MyOSMC or grab-logs -A

piercedfreak · 2 February 2020 19:14

https://paste.osmc.tv/meluhifuyi Is the log.

dillthedog · 2 February 2020 19:41

It should have been grab-logs -A (with a big A).

piercedfreak · 2 February 2020 19:54

My bad. Too early for me. https://paste.osmc.tv/difusoduwu

dillthedog · 2 February 2020 20:11

What’s the output if you run

sudo /usr/bin/python /usr/share/kodi/addons/script.module.osmcsetting.updates/resources/lib/apt_cache_action.py update

piercedfreak · 2 February 2020 20:15

osmc@osmc:~$ sudo /usr/bin/python /usr/share/kodi/addons/script.module.osmcsetting.updates/resources/lib/apt_cache_action.py update
===================================================================
2020-02-02 15:14:39.251186 apt_cache_action.py running
2020-02-02 15:14:39.251396 apt_cache_action.py sending response
2020-02-02 15:14:39.252290 apt_cache_action.py sending response
Pulse ===========================================
current_items 1
total_items 5
total_bytes 185379
fetched_bytes 185379
current_bytes 185379
current_cps 0
Pulse ===========================================
2020-02-02 15:14:43.172231 apt_cache_action.py sending response
Pulse ===========================================
current_items 24
total_items 26
total_bytes 185379
fetched_bytes 185379
current_bytes 185379
current_cps 0
Pulse ===========================================
2020-02-02 15:14:44.272035 apt_cache_action.py sending response
2020-02-02 15:14:45.337526 apt_cache_action.py sending response
2020-02-02 15:14:45.338169 apt_cache_action.py sending response
2020-02-02 15:14:45.338584 apt_cache_action.py sending response
2020-02-02 15:14:45.338860 apt_cache_action.py exiting
===================================================================
osmc@osmc:~$

dillthedog · 2 February 2020 21:01

I can’t put my finger on anything obvious. You seem to have had some kind of corruption with debconf that you fixed with a reinstall. Unfortunately, it’s possible – though by no means certain – that the corrupted debconf has left something in an incorrect state.

It still seems to be running from the SD card. The cmdline.txt file shows root=/dev/mmcblk0p2 and the disk space info says it’s an 8GB device:

====================== Diskspace =================== qZy25Yas
Filesystem      Size  Used Avail Use% Mounted on
devtmpfs        368M     0  368M   0% /dev
tmpfs           373M  9.9M  363M   3% /run
/dev/mmcblk0p2  7.1G  3.8G  3.0G  56% /
tmpfs           373M  432K  373M   1% /dev/shm
tmpfs           5.0M  4.0K  5.0M   1% /run/lock
tmpfs           373M     0  373M   0% /sys/fs/cgroup
/dev/mmcblk0p1  240M   36M  204M  15% /boot
/dev/sdb1        29G  3.6G   24G  14% /media/home
/dev/sda1       3.6T  2.7T  725G  80% /media/hd
tmpfs            75M     0   75M   0% /run/user/999
tmpfs            75M     0   75M   0% /run/user/1000

On the available information, the best I can suggest is that your SD card might be having problems. You’ve loaded more packages onto the system than is normal, so the card might be getting a good workout. They do eventually wear out.

piercedfreak · 2 February 2020 21:33

I think you are right about the SD card dying. I thought this one booting from USB, it must be the only one that is not USB booting in my collection. I will change that, and start over I guess. Thanks for the help.