Error after each package update

jimp · Jan 7, 2011, 11:20 PM

Yeah that one definitely wouldn't have all the commits. The last one hit the repo about 20 minutes ago.

So the next one after that which starts up should be OK.

jlepthien · Jan 8, 2011, 12:46 PM

Tried with snap from 1/8 and at least my PPPoE came up by itself. After the package resync there were only Shellcmd and Unbound installed. So I had to manually install avahi, backup and nmap again. At least I did not need any more reboots, so I guess we're gettin' closer now ;-)

Thanks jimp…

Edit: Now my iPhone on WLAN can't connect to my iTunes on LAN anymore. Is avahi working for you jimp?

jimp · Jan 8, 2011, 3:21 PM

I didn't try to run/configure avahi, I just had it installed.

It is a different version of Avahi, before it was downloading and installing Avahi from FreeBSD 7. Now it's really using the version compiled for FreeBSD 8. Any logs from Avahi that are interesting?

jlepthien · Jan 8, 2011, 3:40 PM

Hi jimp,

here is what a 'grep -i avahi' in system.log gives me:

Jan  8 16:15:50 voldemort avahi-daemon[25207]: Found user 'avahi' (UID 558) and group 'avahi' (GID 558).
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Successfully dropped root privileges.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: avahi-daemon 0.6.25 starting up.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Jan  8 16:15:50 voldemort avahi-daemon[25207]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Loading service file /usr/local/etc/avahi/services/sftp-ssh.service.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Loading service file /usr/local/etc/avahi/services/ssh.service.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Joining mDNS multicast group on interface ath0_wlan1.IPv4 with address 192.168.100.254.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: New relevant interface ath0_wlan1.IPv4 for mDNS.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Joining mDNS multicast group on interface ath0_wlan0.IPv4 with address 172.16.100.254.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: New relevant interface ath0_wlan0.IPv4 for mDNS.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Joining mDNS multicast group on interface vr0.IPv4 with address 10.0.100.254.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: New relevant interface vr0.IPv4 for mDNS.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Network interface enumeration completed.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for fe80::480:48ff:fe62:fa57 on ath0_wlan1.*.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for 192.168.100.254 on ath0_wlan1.IPv4.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for fe80::280:48ff:fe62:fa57 on ath0_wlan0.*.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for 172.16.100.254 on ath0_wlan0.IPv4.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for fe80::20d:b9ff:fe13:54a8 on vr0.*.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering new address record for 10.0.100.254 on vr0.IPv4.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Registering HINFO record with values 'I386'/'FREEBSD'.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Short read() on signal pipe.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: Short read() on signal pipe.
Jan  8 16:15:50 voldemort avahi-daemon[25207]: daemon_signal_next() failed
Jan  8 16:15:50 voldemort avahi-daemon[25207]: daemon_signal_next() failed
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: iface.c: avahi_server_add_service() failed: Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: Server startup complete. Host name is voldemort.hogwarts.lan. Local service cookie is 220947134.
Jan  8 16:15:51 voldemort avahi-daemon[25207]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supported
Jan  8 16:15:51 voldemort avahi-daemon[25207]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supported

jimp · Jan 8, 2011, 3:53 PM

I may have to experiment with it on Monday, my brain is taking the weekend off. :-)

jlepthien · Jan 10, 2011, 4:03 PM

Thanks jimp. Suddenly it started to work again. Mysteriously…
Did nothing besides wait. Will have an eye on that...

The only thing now is that my PPPoE does take too long to come up after a upgrade so the packages sync doesn't work that well. Today it only installed one of six packages (nmap) after upgrade to 1/10 but that's another story...

Edit: Is there a reason why not all packages are listed when I run a pkg_info on console? Have avahi, backup, shellcmd, unbound, rrd summary and nmap installed, but the output only shows the following:

expat-2.0.1_1       XML 1.0 parser written in C
libevent-1.4.14b_1  Provides an API to execute callback functions on certain ev
lua-5.1.4_5         Small, compilable scripting language providing easy access 
nmap-5.35.d1        Port scanning utility for large networks
openssl-1.0.0_4     SSL and crypto library
pcre-8.10           Perl Compatible Regular Expressions library
pkg-config-0.25_1   A utility to retrieve information about installed libraries
unbound-1.4.7       A validating, recursive, and caching DNS resolver

jimp · Jan 10, 2011, 4:12 PM

Avahi is installed via a tgz archive, not a proper "package" file, and the other missing ones are only found in the pfSense GUI, not OS packages.

pkg_info only lists properly registered FreeBSD packages.

jlepthien · Jan 10, 2011, 4:13 PM

Thanks for clearing that up, jimp ;)

jlepthien · Jan 10, 2011, 10:55 PM

Now with a working PPPoE connection on startup almost everything worked fine except the nmap package wasn't installed. All other five package were. Can you confirm that? Or should I open a new ticket/thread for nmap?

jimp · Jan 10, 2011, 11:01 PM

I haven't tried that one yet. Not sure what its deal might be.

Try to reinstall it manually one more time and see if it survives the next firmware update again.

jlepthien · Jan 11, 2011, 11:44 AM

All packages got reinstalled now after my latest upgrade. nmap shows up as installed but does not work. On console I get:

[2.0-BETA5][admin@voldemort.hogwarts.lan]/root(9): nmap 
nmap: Command not found.

Also reinstalling the package did not work. Even completely removing and adding the package doesn't change this…

jimp · Jan 11, 2011, 1:41 PM

Does pkg_info still show nmap installed? What happens if you pkg_delete nmap-5.35.d1 and then reinstall?

jlepthien · Jan 11, 2011, 1:50 PM

@jimp:

Does pkg_info still show nmap installed? What happens if you pkg_delete nmap-5.35.d1 and then reinstall?

Yes, pkg_info shows nmap installed. When I remove it and reinstall or completely delete and reinstall I now get the following error:

/libexec/ld-elf.so.1: Shared object "libpcap.so.1" not found, required by "nmap"

pac · Jan 12, 2011, 12:55 AM

I experienced the same issues with my ALIX board under 2.0-BETA4; had to revert back to 1.2.3-RELEASE because the system was too unstable.

jlepthien, would you say most of the packaging and WebGUI freezes have been fixed ? I could install 2.0-BETA5 01/08 and provide some additional feedback.

__.
pac

romainp · Jan 12, 2011, 2:06 AM

Juste to let know know:
with 2.0-BETA5 (i386) built on Tue Jan 11 14:19:18 EST 2011

I got this message at boot time:

One moment please, reinstalling packages...
pkg_info: no packages match pattern(s)

Don't really know what it means…

Also, all my packages have been deinstalled but I don't know my just after the upgrade...

System log show this:

php: /pkg_mgr_install.php: The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '127', the output was '/usr/local/etc/rc.d/postfix.sh: /usr/local/sbin/postfix: not found'

It's not the first time that even if a package have been removed the init scripts still alive.

jimp · Jan 12, 2011, 2:12 AM

@romainp:

One moment please, reinstalling packages...
pkg_info: no packages match pattern(s)

Don't really know what it means…

That's harmless but we've been trying to track it down.

@romainp:

Also, all my packages have been deinstalled but I don't know my just after the upgrade…

Someone else had that happen but we weren't able to replicate it. Near as I could tell, it was due to an upgrade that was done the last time that wasn't quite right, but the next one was OK and hasn't broken since. If someone can reliably replicate it on current snapshots that might help.

@romainp:

System log show this:
php: /pkg_mgr_install.php: The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '127', the output was '/usr/local/etc/rc.d/postfix.sh: /usr/local/sbin/postfix: not found'
It's not the first time that even if a package have been removed the init scripts still alive.

That's up to the individual package maintainer to remove the script on deinstall. Not much to be done about that by the package system in general.

romainp · Jan 12, 2011, 2:29 AM

Thanks for those infos.

Maybe the package automatic deinstall was because my pppoe connection was down at boot time? It happen in earlier release that after a release upgrade, packages are deinstalled and then reinstalled automatically. I think yesterday it happens. Today, just de deinstall. I am impatient to see what will happen tomorrow! :-)
Just joking. I love pfSense and you all guys do a fantastic works.
Thanks

jimp · Jan 12, 2011, 2:46 AM

Lack of connectivity would explain it, I suppose. Might need some extra logic there to prevent that from happening.

jimp · Jan 12, 2011, 9:53 PM

I added some code to bail on the package update if there is no connectivity when the function is called. It won't be in the next new snapshot, but the one after.

jlepthien · Jan 13, 2011, 5:39 PM

Oh boy. Now all packages except avahi have been automatically reinstalled…

Still, nmap gives me an error:

/libexec/ld-elf.so.1: Shared object "libpcap.so.1" not found, required by "nmap"