Netgate Discussion Forum
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Search
    • Register
    • Login

    CRITICAL: postfix fails to start after upgrade to 2.03 release [solved]

    pfSense Packages
    3
    24
    4.8k
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • H
      hcoin
      last edited by

      I started with a long time working pfsense system, with postfix running normally.  i386.  I did the normal upgrade in the configurator.  After a reboot the system hung for 12 hours claiming it was reinstalling packages.  The dashboard showed all services running but postfix not installed.   I rebooted, postfix was not running.  I reinstalled the package, it didn't start.  I reinstalled the xml, it didn't start.   I manually went to the services screen, hit start, it started.   That same screen can't stop the package.  The only way to get postfix going is to manually start it upon reboot using the services menu.

      Here's the log showing a reboot cycle:

      May 8 09:10:31 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:31 syslogd: exiting on signal 15
      May 8 09:10:30 php: : Config sync not being done because of missing sync IP (normal on secondary systems).
      May 8 09:10:27 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:10:27 postfix/postfix-script[2398]: fatal: the Postfix mail system is not running
      May 8 09:10:27 php: : Stopping postfix
      May 8 09:10:26 php: : Writing rc_file
      May 8 09:10:24 php: : Writing out configuration
      May 8 09:10:24 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:10:24 postfix/postfix-script[19596]: fatal: the Postfix mail system is not running
      May 8 09:10:24 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:23 syslogd: exiting on signal 15
      May 8 09:10:23 check_reload_status: Syncing firewall
      May 8 09:10:21 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:10:21 postfix/postfix-script[15478]: fatal: the Postfix mail system is not running
      May 8 09:10:21 php: : Stopping postfix
      May 8 09:10:20 php: : Writing rc_file
      May 8 09:10:19 check_reload_status: Reloading filter
      May 8 09:10:18 sshlockout[9068]: sshlockout/webConfigurator v3.0 starting up
      May 8 09:10:18 login: login on ttyv0 as root
      May 8 09:10:17 php: : Writing out configuration
      May 8 09:10:17 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:10:17 postfix/postfix-script[7234]: fatal: the Postfix mail system is not running
      May 8 09:10:17 php: : IPSEC: One or more IPsec tunnel endpoints has changed its IP. Refreshing.
      May 8 09:10:16 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:16 syslogd: exiting on signal 15
      May 8 09:10:14 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
      May 8 09:10:14 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:10:14 postfix/postfix-script[61643]: fatal: the Postfix mail system is not running
      May 8 09:10:14 php: : Stopping postfix
      May 8 09:10:14 php: : Stopping postfix
      May 8 09:10:13 php: : Writing rc_file
      May 8 09:10:13 php: : Writing rc_file
      May 8 09:10:11 php: : Writing out configuration
      May 8 09:10:11 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:10:11 postfix/postfix-script[59283]: fatal: the Postfix mail system is not running
      May 8 09:10:10 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:10 syslogd: exiting on signal 15
      May 8 09:10:08 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:10:08 postfix/postfix-script[54181]: fatal: the Postfix mail system is not running
      May 8 09:10:06 php: : Stopping postfix
      May 8 09:10:06 php: : Writing out configuration
      May 8 09:10:06 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:10:06 postfix/postfix-script[50873]: fatal: the Postfix mail system is not running
      May 8 09:10:06 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:05 syslogd: exiting on signal 15
      May 8 09:10:03 php: : Writing out configuration
      May 8 09:10:03 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:10:03 postfix/postfix-script[44121]: fatal: the Postfix mail system is not running
      May 8 09:10:02 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:10:02 syslogd: exiting on signal 15
      May 8 09:10:00 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
      May 8 09:10:00 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:10:00 postfix/postfix-script[39052]: fatal: the Postfix mail system is not running
      May 8 09:10:00 php: : Stopping postfix
      May 8 09:10:00 php: : Stopping postfix
      May 8 09:09:59 php: : Writing rc_file
      May 8 09:09:59 php: : Writing rc_file
      May 8 09:09:57 php: : Writing out configuration
      May 8 09:09:57 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:57 postfix/postfix-script[35649]: fatal: the Postfix mail system is not running
      May 8 09:09:57 apinger: rrdtool respawning too fast, waiting 300s.
      May 8 09:09:57 apinger: Error while feeding rrdtool: Broken pipe
      May 8 09:09:56 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:56 syslogd: exiting on signal 15
      May 8 09:09:56 check_reload_status: Syncing firewall
      May 8 09:09:54 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:09:54 postfix/postfix-script[29314]: fatal: the Postfix mail system is not running
      May 8 09:09:53 php: : Stopping postfix
      May 8 09:09:52 php: : Writing out configuration
      May 8 09:09:52 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:52 postfix/postfix-script[27673]: fatal: the Postfix mail system is not running
      May 8 09:09:52 php: : Writing rc_file
      May 8 09:09:51 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:51 syslogd: exiting on signal 15
      May 8 09:09:49 php: : Writing out configuration
      May 8 09:09:49 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:49 postfix/postfix-script[14102]: fatal: the Postfix mail system is not running
      May 8 09:09:48 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:48 syslogd: exiting on signal 15
      May 8 09:09:46 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
      May 8 09:09:46 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:09:46 postfix/postfix-script[8825]: fatal: the Postfix mail system is not running
      May 8 09:09:46 php: : Stopping postfix
      May 8 09:09:46 php: : Stopping postfix
      May 8 09:09:45 php: : Writing rc_file
      May 8 09:09:45 php: : Writing rc_file
      May 8 09:09:43 php: : Writing out configuration
      May 8 09:09:43 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:43 postfix/postfix-script[6060]: fatal: the Postfix mail system is not running
      May 8 09:09:42 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:42 syslogd: exiting on signal 15
      May 8 09:09:40 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:09:39 php: : Stopping postfix
      May 8 09:09:38 php: : Writing rc_file
      May 8 09:09:36 php: : Writing out configuration
      May 8 09:09:36 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:36 postfix/postfix-script[51319]: fatal: the Postfix mail system is not running
      May 8 09:09:36 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:33 syslogd: exiting on signal 15
      May 8 09:09:31 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
      May 8 09:09:31 postfix/postfix-script[43276]: fatal: the Postfix mail system is not running
      May 8 09:09:31 php: : No pfBlocker action during boot process.
      May 8 09:09:31 php: : No pfBlocker action during boot process.
      May 8 09:09:31 php: : No pfBlocker action during boot process.
      May 8 09:09:31 php: : No pfBlocker action during boot process.
      May 8 09:09:31 php: : Stopping postfix
      May 8 09:09:30 php: : Writing rc_file
      May 8 09:09:29 upsmon[36667]: Startup successful
      May 8 09:09:28 php: : Writing out configuration
      May 8 09:09:28 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
      May 8 09:09:28 postfix/postfix-script[28277]: fatal: the Postfix mail system is not running
      May 8 09:09:26 syslogd: kernel boot file is /boot/kernel/kernel
      May 8 09:09:26 syslogd: exiting on signal 15
      May 8 09:09:26 check_reload_status: Syncing firewall
      May 8 09:09:22 php: : Restarting/Starting all packages.
      May 8 09:09:21 miniupnpd[39150]: Listening for NAT-PMP traffic on port 5351
      May 8 09:09:21 miniupnpd[39150]: Listening for NAT-PMP traffic on port 5351
      May 8 09:09:21 miniupnpd[39150]: HTTP listening on port 2189
      May 8 09:09:21 miniupnpd[39150]: HTTP listening on port 2189
      May 8 09:09:21 php: : miniupnpd: Starting service on interface: opt3, opt4
      May 8 09:09:21 php: : Creating rrd update script
      May 8 09:09:21 php: : No pfBlocker action during boot process.
      May 8 09:09:21 php: : No pfBlocker action during boot process.
      May 8 09:09:21 php: : No pfBlocker action during boot process.
      May 8 09:09:20 php: : No pfBlocker action during boot process.
      May 8 09:09:18 upsmon[27597]: Startup successful
      May 8 09:09:18 check_reload_status: Restarting ipsec tunnels
      May 8 09:09:15 php: : filter_generate_address: is not a valid source port.
      May 8 09:09:15 php: : filter_generate_address: is not a valid source port.
      May 8 09:09:11 php: : filter_generate_address: is not a valid source port.
      May 8 09:09:11 php: : filter_generate_address: is not a valid source port.
      May 8 09:09:08 php: : Restarting/Starting all packages.
      May 8 09:09:06 dnsmasq[8743]: read /etc/hosts - 56 addresses
      May 8 09:09:06 dnsmasq[8743]: using nameserver 192.168.50.1#53 for domain mamabosso.com
      May 8 09:09:06 dnsmasq[8743]: ignoring nameserver 127.0.0.1 - local interface
      May 8 09:09:06 dnsmasq[8743]: ignoring nameserver 127.0.0.1 - local interface
      May 8 09:09:06 dnsmasq[8743]: using nameserver 97.64.187.150#53
      May 8 09:09:06 dnsmasq[8743]: using nameserver 74.84.119.153#53
      May 8 09:09:06 dnsmasq[8743]: using nameserver 8.8.8.8#53
      May 8 09:09:06 dnsmasq[8743]: using nameserver 8.8.4.4#53
      May 8 09:09:06 dnsmasq[8743]: reading /etc/resolv.conf
      May 8 09:09:06 dnsmasq[8743]: using nameserver 192.168.50.1#53 for domain mamabosso.com
      May 8 09:09:06 dnsmasq[8743]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
      May 8 09:09:06 dnsmasq[8743]: started, version 2.65 cachesize 10000
      May 8 09:09:06 check_reload_status: Updating all dyndns
      May 8 09:09:06 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
      May 8 09:09:06 dhcpd: All rights reserved.
      May 8 09:09:06 dhcpd: Copyright 2004-2012 Internet Systems Consortium.
      May 8 09:09:06 dhcpd: Internet Systems Consortium DHCP Server 4.2.4-P2

      After using the start button at the services screen, normal operation commences:

      'May 8 09:15:29 postfix/smtpd[20436]: disconnect from unknown[173.224.218.62]
      May 8 09:15:29 postfix/smtpd[20436]: NOQUEUE: reject: RCPT from unknown[173.224.218.62]: 450 4.7.1 Client host rejected: cannot find your hostname, [173.224.218.62]; from= insidewallstreetreport@questioningdishtowel.infoto= harrycoin@n4comm.comproto=ESMTP helo= <mail.questioningdishtowel.info>May 8 09:15:29 postfix/smtpd[20436]: connect from unknown[173.224.218.62]
      May 8 09:15:29 postfix/smtpd[20436]: warning: 173.224.218.62: hostname unassigned.psychz.net verification failed: hostname nor servname provided, or not known
      May 8 09:15:29 postfix/postscreen[20433]: PASS OLD [173.224.218.62]:24386
      May 8 09:15:29 postfix/postscreen[20433]: CONNECT from [173.224.218.62]:24386
      May 8 09:15:26 postfix/master[19799]: daemon started – version 2.8.7, configuration /usr/local/etc/postfix
      May 8 09:15:26 postfix/postfix-script[19528]: starting the Postfix mail system</mail.questioningdishtowel.info>/harrycoin@n4comm.com/insidewallstreetreport@questioningdishtowel.info

      1 Reply Last reply Reply Quote 0
      • H
        hcoin
        last edited by

        Here's the answer to a likely question:

        $ ls -l /usr/local/etc/rc.d
        total 48
        -rwxr-xr-x  1 root  wheel  560 Feb  6  2012 clamd
        -rwxr-xr-x  1 root  wheel  269 Feb  7 22:17 cron.sh
        -rwxr-xr-x  1 havp  2000    449 Feb  6  2012 havp_avupdate
        -r-xr-xr-x  1 root  wheel  953 Sep  5  2012 nut
        -rwxr-xr-x  1 root  wheel  534 May  8 09:09 nut.sh
        -r-xr-xr-x  1 root  wheel  950 Sep  5  2012 nut_upslog
        -r-xr-xr-x  1 root  wheel  845 Sep  5  2012 nut_upsmon
        -r-xr-xr-x  1 root  wheel  1177 Jun 10  2012 postfix
        -rw-r–r--  1 root  wheel  462 May  8 09:10 postfix.sh
        -r-xr-xr-x  1 root  wheel  618 Jun  7  2010 rsyncd
        -r-xr-xr-x  1 root  wheel  1452 Sep  5  2012 snmpd
        -r-xr-xr-x  1 root  wheel  814 Sep  5  2012 snmptrapd

        1 Reply Last reply Reply Quote 0
        • marcellocM
          marcelloc
          last edited by

          Did you saved postfix config after upgrade?

          The startup script is postfix, postfix.sh is the default startup script from freebsd.

          Treinamentos de Elite: http://sys-squad.com

          Help a community developer! ;D

          1 Reply Last reply Reply Quote 0
          • H
            hcoin
            last edited by

            Thanks for the reply.  All I know for sure is I installed postfix through pfsense during the last release, and it worked well.  Then I did the normal 'upgrade' process through the pfsense configurator.  Then pfsense rebooted and the configurator displayed "reinstalling packages don't change anything via the GUI' for 12 hours.  All the services I had loaded but not postifix was there.  I rebooted.  Postfix was absent from the GUI services list.  I went to the packages under pfsense and there it said postfix was installed, so I 'reinstalled' it, and there were no errors in that process but postfix wasn't running.  So I reinstalled the xml, no reinstall errors and still not running.  Then I went to the services GUI screen and tried to start it there, and it started.    I rebooted and postfix didn't and still doesn't start.

            What should I do?  I need postfix to start by itself like all the other packages.

            1 Reply Last reply Reply Quote 0
            • marcellocM
              marcelloc
              last edited by

              @hcoin:

              What should I do?   I need postfix to start by itself like all the other packages.

              try to chmod +x /usr/local/etc/rc.d/postfix.sh on default startup script.

              Treinamentos de Elite: http://sys-squad.com

              Help a community developer! ;D

              1 Reply Last reply Reply Quote 0
              • H
                hcoin
                last edited by

                chmod +x /usr/local/etc/rc.d/postfix.sh  did not work.  Tried

                chmod a+x /usr/local/etc/rc.d/postfix.sh

                $ ls -l /usr/local/etc/rc.d
                total 48
                -rwxr-xr-x  1 root  wheel  560 Feb  6  2012 clamd
                -rwxr-xr-x  1 root  wheel  269 Feb  7 22:17 cron.sh
                -rwxr-xr-x  1 havp  2000    449 Feb  6  2012 havp_avupdate
                -r-xr-xr-x  1 root  wheel  953 Sep  5  2012 nut
                -rwxr-xr-x  1 root  wheel  534 May  8 17:36 nut.sh
                -r-xr-xr-x  1 root  wheel  950 Sep  5  2012 nut_upslog
                -r-xr-xr-x  1 root  wheel  845 Sep  5  2012 nut_upsmon
                -r-xr-xr-x  1 root  wheel  1177 Jun 10  2012 postfix
                -rw-r–r--  1 root  wheel  462 May  8 17:37 postfix.sh
                -r-xr-xr-x  1 root  wheel  618 Jun  7  2010 rsyncd
                -r-xr-xr-x  1 root  wheel  1452 Sep  5  2012 snmpd
                -r-xr-xr-x  1 root  wheel  814 Sep  5  2012 snmptrapd

                Waiting for 'bootup complete' beep sound...

                No joy.  Log:

                May 8 17:37:57 php: : filter_generate_address: is not a valid source port.
                May 8 17:37:57 php: : filter_generate_address: is not a valid source port.
                May 8 17:37:52 php: : Config sync not being done because of missing sync IP (normal on secondary systems).
                May 8 17:37:50 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:50 postfix/postfix-script[1661]: fatal: the Postfix mail system is not running
                May 8 17:37:50 php: : Stopping postfix
                May 8 17:37:49 php: : Writing rc_file
                May 8 17:37:47 php: : Writing out configuration
                May 8 17:37:47 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:47 postfix/postfix-script[33823]: fatal: the Postfix mail system is not running
                May 8 17:37:47 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:46 syslogd: exiting on signal 15
                May 8 17:37:46 check_reload_status: Syncing firewall
                May 8 17:37:45 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:45 postfix/postfix-script[29770]: fatal: the Postfix mail system is not running
                May 8 17:37:44 php: : Stopping postfix
                May 8 17:37:43 php: : Writing rc_file
                May 8 17:37:43 check_reload_status: Reloading filter
                May 8 17:37:41 sshlockout[23460]: sshlockout/webConfigurator v3.0 starting up
                May 8 17:37:41 login: login on ttyv0 as root
                May 8 17:37:40 php: : IPSEC: One or more IPsec tunnel endpoints has changed its IP. Refreshing.
                May 8 17:37:40 php: : Writing out configuration
                May 8 17:37:40 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:40 postfix/postfix-script[20289]: fatal: the Postfix mail system is not running
                May 8 17:37:39 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:39 syslogd: exiting on signal 15
                May 8 17:37:37 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
                May 8 17:37:37 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:37 postfix/postfix-script[12810]: fatal: the Postfix mail system is not running
                May 8 17:37:37 php: : Stopping postfix
                May 8 17:37:37 php: : Stopping postfix
                May 8 17:37:36 php: : Writing rc_file
                May 8 17:37:36 php: : Writing rc_file
                May 8 17:37:34 php: : Writing out configuration
                May 8 17:37:34 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:34 postfix/postfix-script[9899]: fatal: the Postfix mail system is not running
                May 8 17:37:33 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:33 syslogd: exiting on signal 15
                May 8 17:37:33 check_reload_status: Syncing firewall
                May 8 17:37:31 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:31 postfix/postfix-script[3929]: fatal: the Postfix mail system is not running
                May 8 17:37:30 php: : Stopping postfix
                May 8 17:37:29 php: : Writing out configuration
                May 8 17:37:29 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:29 postfix/postfix-script[1727]: fatal: the Postfix mail system is not running
                May 8 17:37:29 php: : Writing rc_file
                May 8 17:37:28 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:27 syslogd: exiting on signal 15
                May 8 17:37:27 php: : Writing out configuration
                May 8 17:37:27 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:27 postfix/postfix-script[60145]: fatal: the Postfix mail system is not running
                May 8 17:37:27 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:26 syslogd: exiting on signal 15
                May 8 17:37:25 apinger: rrdtool respawning too fast, waiting 300s.
                May 8 17:37:25 apinger: Error while feeding rrdtool: Broken pipe
                May 8 17:37:24 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
                May 8 17:37:24 php: : Stopping postfix
                May 8 17:37:23 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:23 postfix/postfix-script[54112]: fatal: the Postfix mail system is not running
                May 8 17:37:23 php: : Writing rc_file
                May 8 17:37:21 php: : Writing out configuration
                May 8 17:37:21 php: : Stopping postfix
                May 8 17:37:21 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:21 postfix/postfix-script[50770]: fatal: the Postfix mail system is not running
                May 8 17:37:20 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:20 syslogd: exiting on signal 15
                May 8 17:37:20 check_reload_status: Syncing firewall
                May 8 17:37:20 php: : Writing rc_file
                May 8 17:37:18 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:18 postfix/postfix-script[44808]: fatal: the Postfix mail system is not running
                May 8 17:37:17 php: : Stopping postfix
                May 8 17:37:16 php: : Writing out configuration
                May 8 17:37:16 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:16 postfix/postfix-script[43180]: fatal: the Postfix mail system is not running
                May 8 17:37:16 php: : Writing rc_file
                May 8 17:37:15 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:14 syslogd: exiting on signal 15
                May 8 17:37:14 php: : Writing out configuration
                May 8 17:37:14 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:14 postfix/postfix-script[37088]: fatal: the Postfix mail system is not running
                May 8 17:37:14 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:13 syslogd: exiting on signal 15
                May 8 17:37:11 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '126', the output was '/usr/local/etc/rc.d/postfix.sh: Permission denied'
                May 8 17:37:11 php: : Stopping postfix
                May 8 17:37:10 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:10 postfix/postfix-script[31338]: fatal: the Postfix mail system is not running
                May 8 17:37:10 php: : Writing rc_file
                May 8 17:37:09 php: : Stopping postfix
                May 8 17:37:08 php: : Writing out configuration
                May 8 17:37:08 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:08 postfix/postfix-script[28713]: fatal: the Postfix mail system is not running
                May 8 17:37:08 php: : Writing rc_file
                May 8 17:37:07 check_reload_status: Syncing firewall
                May 8 17:37:07 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:07 syslogd: exiting on signal 15
                May 8 17:37:05 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:37:05 postfix/postfix-script[23705]: fatal: the Postfix mail system is not running
                May 8 17:37:04 php: : Stopping postfix
                May 8 17:37:03 php: : Writing out configuration
                May 8 17:37:03 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:03 postfix/postfix-script[21024]: fatal: the Postfix mail system is not running
                May 8 17:37:03 php: : Writing rc_file
                May 8 17:37:01 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:01 syslogd: exiting on signal 15
                May 8 17:37:01 php: : Writing out configuration
                May 8 17:37:01 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:37:01 postfix/postfix-script[16259]: fatal: the Postfix mail system is not running
                May 8 17:37:00 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:37:00 syslogd: exiting on signal 15
                May 8 17:36:56 php: : The command '/usr/local/etc/rc.d/postfix.sh stop' returned exit code '1', the output was ''
                May 8 17:36:56 postfix/postfix-script[11735]: fatal: the Postfix mail system is not running
                May 8 17:36:56 php: : Stopping postfix
                May 8 17:36:55 php: : Writing rc_file
                May 8 17:36:55 php: : No pfBlocker action during boot process.
                May 8 17:36:55 php: : No pfBlocker action during boot process.
                May 8 17:36:55 php: : No pfBlocker action during boot process.
                May 8 17:36:55 php: : No pfBlocker action during boot process.
                May 8 17:36:53 php: : Writing out configuration
                May 8 17:36:53 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                May 8 17:36:53 postfix/postfix-script[8846]: fatal: the Postfix mail system is not running
                May 8 17:36:53 upsmon[5694]: Startup successful
                May 8 17:36:51 syslogd: kernel boot file is /boot/kernel/kernel
                May 8 17:36:51 syslogd: exiting on signal 15
                May 8 17:36:51 check_reload_status: Syncing firewall
                May 8 17:36:50 upsmon[8241]: upsmon parent: read
                May 8 17:36:50 upsmon[8241]: upsmon parent: read
                May 8 17:36:50 upsmon[8480]: Signal 15: exiting
                May 8 17:36:46 php: : No pfBlocker action during boot process.
                May 8 17:36:46 php: : Restarting/Starting all packages.

                1 Reply Last reply Reply Quote 0
                • H
                  hcoin
                  last edited by

                  Something is resetting the execute permissions on /etc/rc.d/postfix.sh.  After the chmod I see

                  $ ls -l /usr/local/etc/rc.d
                  total 48
                  -rwxr-xr-x  1 root  wheel   560 Feb  6  2012 clamd
                  -rwxr-xr-x  1 root  wheel   269 Feb  7 22:17 cron.sh
                  -rwxr-xr-x  1 havp  2000    449 Feb  6  2012 havp_avupdate
                  -r-xr-xr-x  1 root  wheel   953 Sep  5  2012 nut
                  -rwxr-xr-x  1 root  wheel   534 May  8 17:36 nut.sh
                  -r-xr-xr-x  1 root  wheel   950 Sep  5  2012 nut_upslog
                  -r-xr-xr-x  1 root  wheel   845 Sep  5  2012 nut_upsmon
                  -r-xr-xr-x  1 root  wheel  1177 Jun 10  2012 postfix
                  -rwxr-xr-x  1 root  wheel   462 May  8 17:37 postfix.sh
                  -r-xr-xr-x  1 root  wheel   618 Jun  7  2010 rsyncd
                  -r-xr-xr-x  1 root  wheel  1452 Sep  5  2012 snmpd
                  -r-xr-xr-x  1 root  wheel   814 Sep  5  2012 snmptrapd
                  

                  But after the reboot and nothing else….

                  $ ls -l /usr/local/etc/rc.d
                  total 48
                  -rwxr-xr-x  1 root  wheel   560 Feb  6  2012 clamd
                  -rwxr-xr-x  1 root  wheel   269 Feb  7 22:17 cron.sh
                  -rwxr-xr-x  1 havp  2000    449 Feb  6  2012 havp_avupdate
                  -r-xr-xr-x  1 root  wheel   953 Sep  5  2012 nut
                  -rwxr-xr-x  1 root  wheel   534 May  8 17:56 nut.sh
                  -r-xr-xr-x  1 root  wheel   950 Sep  5  2012 nut_upslog
                  -r-xr-xr-x  1 root  wheel   845 Sep  5  2012 nut_upsmon
                  -r-xr-xr-x  1 root  wheel  1177 Jun 10  2012 postfix
                  -rw-r--r--  1 root  wheel   462 May  8 17:56 postfix.sh
                  -r-xr-xr-x  1 root  wheel   618 Jun  7  2010 rsyncd
                  -r-xr-xr-x  1 root  wheel  1452 Sep  5  2012 snmpd
                  -r-xr-xr-x  1 root  wheel   814 Sep  5  2012 snmptrapd
                  
                  1 Reply Last reply Reply Quote 0
                  • marcellocM
                    marcelloc
                    last edited by

                    Can you check if postfix is enabled on config gui?

                    I'm checking the code and it removes exec permission only when enable postfix is unselectd.

                    Treinamentos de Elite: http://sys-squad.com

                    Help a community developer! ;D

                    1 Reply Last reply Reply Quote 0
                    • H
                      hcoin
                      last edited by

                      Not sure I'm answering what you asked precisely, but here's some data:

                      System: Package Manager  / Available Packages – postfix does not appear
                      System: Package Manager  / Installed Packages -- postfix appears
                      Postfix Forwarder Services Package Info 2.8.7,1 pkg v.2.3.4_1

                      Services: Postfix relay and antispam -- appears.   Full config gui present all populated with correct items and indeed fully operational handing email properly -- but only after choosing the 'start' button in the 'status/services' gui item 'postfix'.   Interestingly under 'description' of the postfix service it reports 'none available'.  Seems odd.  But it is running after manually starting.

                      Backup / restore.   Here's the xml for the portion of the backup/packages mentioning postfix:

                      - <package><name>Postfix Forwarder</name> <website>http://www.postfix.org/</website> 
                      - 
                      <category>Services</category>
                       <pkginfolink>http://forum.pfsense.org/index.php/topic,40622.0.html</pkginfolink> 
                      <config_file>http://www.pfsense.com/packages/config/postfix/postfix.xml</config_file> <depends_on_package_base_url>http://files.pfsense.org/packages/8/All/</depends_on_package_base_url> <depends_on_package>postfix-2.8.7,1.tbz</depends_on_package> 
                      <depends_on_package>perl-5.12.4_3.tbz</depends_on_package> 
                      <depends_on_package_pbi>postfix-2.9.4_2-i386.pbi</depends_on_package_pbi> 
                      <version>2.8.7,1 pkg v.2.3.4_1</version> 
                      <status>RC1</status> <required_version>2.0</required_version>
                       <configurationfile>postfix.xml</configurationfile>
                       <build_port_path>/usr/ports/mail/postfix</build_port_path>
                      <build_options>WITH_PCRE=true;WITH_SPF=true;WITH_SASL2=true;WITH_TLS=true</build_options></package> 
                      

                      …

                      • <service><name>postfix</name> <rcfile>postfix.sh</rcfile> <executable>master</executable></service>
                      1 Reply Last reply Reply Quote 0
                      • marcellocM
                        marcelloc
                        last edited by

                        This option.

                        postfix_enable.png
                        postfix_enable.png_thumb

                        Treinamentos de Elite: http://sys-squad.com

                        Help a community developer! ;D

                        1 Reply Last reply Reply Quote 0
                        • H
                          hcoin
                          last edited by

                          It was unchecked, though Postfix along with the rest of the box was running without change for a year, to include power cycles every few months.  It was certainly trading email just fine.   All worked normally preupgrade so the enable box must have been checked then.   Maybe the auto-upgrade of pfsense to the next pfsense release turned it off?   I wonder why the upgrade GUI hung on postfix?

                          I've done a reboot to see if the box stays checked… and.... box stays checked.

                          Anyhow, Thanks!  Seems to be working now.  Never would I have thought to see if upgrading disabled configured packages short of your hint!

                          1 Reply Last reply Reply Quote 0
                          • H
                            hcoin
                            last edited by

                            The aforementioned box (now working) was a backup in a primary / secondary system.  I've now done the 'upgrade' operation in the primary and the 'reinstall gui' is once again hung complaining of 'reinstalling packages in the background'.  Here's some data:

                            …
                            63159  ??  Ss    0:00.10 /usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroo
                              25  v0  Is+    0:00.04 sh /etc/rc autoboot
                              257  v0  I+    1:37.98 /usr/local/bin/php -f /etc/rc.bootup
                            14085  v0  IN+    0:00.32 /bin/sh /var/db/rrd/updaterrd.sh
                            20322  v0  I+    0:00.01 /bin/sh ./+INSTALL postfix-2.8.7,1 POST-INSTALL
                            28607  v0  S+    0:00.05 /usr/sbin/tcpdump -s 256 -v -S -l -n -e -ttt -i pflog
                            28821  v0  S+    0:00.05 logger -t pf -p local0.info
                            35930  v0  I+    0:00.15 /usr/sbin/pkg_add -fv /tmp/apkg_postfix-2.8.7,1.tbz
                            41620  v0  I+    0:00.00 /bin/sh ./+INSTALL postfix-2.8.7,1 POST-INSTALL
                            44770  v0  IN+    0:00.00 sleep 60

                            ...

                            1 Reply Last reply Reply Quote 0
                            • H
                              hcoin
                              last edited by

                              And a few minutes later:

                              …
                                159  ??  S      0:00.24 /usr/local/sbin/dnsmasq --local-ttl 1 --all-servers -
                                252  ??  INs    0:25.10 /usr/local/sbin/check_reload_status
                                254  ??  IN    0:00.00 check_reload_status: Monitoring daemon of check_reloa
                                264  ??  Is    0:00.02 /sbin/devd
                                431  ??  SNs    0:00.19 /usr/local/bin/ntpd -g -c /var/etc/ntpd.conf
                              2529  ??  SNs    0:00.03 /usr/sbin/cron -s
                              6839  ??  SN    0:00.03 smtp -t unix -u
                              7168  ??  IN    0:00.02 bounce -z -n defer -t unix -u
                              8601  ??  SN    0:00.70 /usr/local/bin/php -f /etc/rc.newipsecdns
                              9432  ??  Is    0:00.00 /usr/sbin/sshd
                              11306  ??  Ss    0:00.02 /usr/sbin/hostapd -B /var/etc/hostapd_ath0_wlan0.conf
                              16321  ??  Ss    0:00.04 /usr/local/sbin/miniupnpd -f /var/etc/miniupnpd.conf
                              19652  ??  I      0:00.02 rrdtool -
                              24038  ??  SNs    0:00.03 postscreen -l -n 127.0.0.1:25 -t inet -u -o user=post
                              24228  ??  IN    0:00.02 dnsblog -z -t unix -u
                              24342  ??  IN    0:00.02 tlsmgr -l -t unix -u
                              24428  ??  SN    0:00.06 smtpd -t pass -u -o stress=
                              24660  ??  SN    0:00.02 trivial-rewrite -n rewrite -t unix -u
                              25279  ??  Ss    0:00.20 /usr/local/sbin/openvpn --config /var/etc/openvpn/cli
                              27684  ??  SNs    0:00.10 /usr/local/libexec/postfix/master
                              27831  ??  Ss    0:00.02 /usr/local/sbin/openvpn --config /var/etc/openvpn/ser
                              29918  ??  S      0:29.49 /usr/local/freeswitch/bin/./freeswitch -nc
                              33505  ??  SNs    0:01.50 /usr/sbin/syslogd -s -c -c -l /var/dhcpd/var/run/log
                              33542  ??  Ss    0:00.29 /usr/local/sbin/apinger -c /var/etc/apinger.conf
                              42179  ??  SNs    0:00.36 /usr/local/libexec/nut/apcsmart -a gate1backups
                              43263  ??  SNs    0:00.06 /usr/local/sbin/upsd
                              43515  ??  INs    0:00.01 /usr/local/sbin/upsmon gate1backups@localhost
                              43566  ??  SN    0:00.07 /usr/local/sbin/upsmon gate1backups@localhost
                              47371  ??  S      0:15.92 /usr/local/sbin/lighttpd -f /var/etc/lighty-webConfig
                              47517  ??  Is    0:00.18 /usr/local/bin/php
                              48386  ??  Is    0:00.18 /usr/local/bin/php
                              52292  ??  I      0:00.42 /usr/local/bin/php
                              52328  ??  SN    0:00.02 cleanup -z -t unix -u
                              52404  ??  S      0:04.94 /usr/local/bin/php
                              52539  ??  SN    0:00.02 bounce -z -t unix -u
                              53363  ??  R      0:00.01 ps ax
                              54225  ??  IN    0:00.02 pickup -l -t fifo -u
                              54264  ??  SN    0:00.04 qmgr -l -t fifo -u
                              58353  ??  SN    0:00.02 anvil -l -t unix -u
                              63159  ??  Ss    0:00.27 /usr/local/sbin/dhcpd -user dhcpd -group _dhcp -chroo
                                25  v0  Is+    0:00.04 sh /etc/rc autoboot
                                257  v0  I+    1:37.98 /usr/local/bin/php -f /etc/rc.bootup
                              14085  v0  SN+    0:00.97 /bin/sh /var/db/rrd/updaterrd.sh
                              20322  v0  I+    0:00.01 /bin/sh ./+INSTALL postfix-2.8.7,1 POST-INSTALL
                              28607  v0  S+    0:00.11 /usr/sbin/tcpdump -s 256 -v -S -l -n -e -ttt -i pflog
                              28821  v0  S+    0:00.12 logger -t pf -p local0.info
                              35930  v0  I+    0:00.15 /usr/sbin/pkg_add -fv /tmp/apkg_postfix-2.8.7,1.tbz
                              41620  v0  I+    0:00.00 /bin/sh ./+INSTALL postfix-2.8.7,1 POST-INSTALL
                              52298  v0  SN+    0:00.00 sleep 60

                              I can hold it here, and not reboot if there is any diagnostic info you'd like?

                              1 Reply Last reply Reply Quote 0
                              • M
                                mschiek01
                                last edited by

                                I have seen this before on the upgrade what other packages do you have installed?

                                1 Reply Last reply Reply Quote 0
                                • H
                                  hcoin
                                  last edited by

                                  on the system I just got working (check the box fix): arping, cron, notes, nut, pfblocker, postfix forwarder.  services running include those plus miniupnpd, dhcpd and dnsmasq and openvpn.

                                  On the one still hung during upgrade:

                                  cron The cron utility is used to manage commands on a schedule.
                                  Running
                                  [Restart Service] [Stop Service]
                                  dhcpd DHCP Service
                                  Running
                                  [Restart Service] [Stop Service]
                                  dnsmasq DNS Forwarder
                                  Running
                                  [Restart Service] [Stop Service]
                                  miniupnpd UPnP Service
                                  Running
                                  [Restart Service] [Stop Service]
                                  ntpd NTP clock sync
                                  Running
                                  [Restart Service] [Stop Service]
                                  nut Network UPS Tools
                                  Running
                                  [Restart Service] [Stop Service]
                                  openvpn OpenVPN client: Mama Bosso VPN Site-Site
                                  Running
                                  [Restart Service] [Stop Service]
                                  openvpn OpenVPN server: QF Tunnel
                                  Running
                                  [Restart Service] [Stop Service]
                                  snort Snort is the most widely deployed IDS/IPS technology worldwide.
                                  Stopped
                                  [Start Service]
                                  tftp

                                  and it's still waiting on the postfix install processes, they appear to be hung.

                                  All packages install as normal except for postfix.  The one system is still hung though I have access to the GUI.  Any live diagnostic info you'd like before I reboot?

                                  May 9 14:53:39 syslogd: kernel boot file is /boot/kernel/kernel
                                  May 9 14:53:39 syslogd: exiting on signal 15
                                  May 9 14:53:38 check_reload_status: Syncing firewall
                                  May 9 13:39:30 SnortStartup[63836]: Snort START For netbs(12157_xl1)…
                                  May 9 13:39:30 snort[63737]: FATAL ERROR: /usr/local/etc/snort/snort_12157_xl1/rules/snort.rules(571) Please enable the HTTP Inspect preprocessor before using the http content modifiers
                                  May 9 13:39:30 snort[63737]: FATAL ERROR: /usr/local/etc/snort/snort_12157_xl1/rules/snort.rules(571) Please enable the HTTP Inspect preprocessor before using the http content modifiers
                                  May 9 13:39:27 SnortStartup[62411]: Snort START For mediacom(22941_xl0)…
                                  May 9 13:39:27 snort[62194]: FATAL ERROR: /usr/local/etc/snort/snort_22941_xl0/rules/snort.rules(571) Please enable the HTTP Inspect preprocessor before using the http content modifiers
                                  May 9 13:39:27 snort[62194]: FATAL ERROR: /usr/local/etc/snort/snort_22941_xl0/rules/snort.rules(571) Please enable the HTTP Inspect preprocessor before using the http content modifiers
                                  May 9 13:39:23 php: : The command '/usr/local/etc/rc.d/snort.sh stop' returned exit code '1', the output was ''
                                  May 9 13:39:21 SnortStartup[52738]: Snort STOP For netbs(12157_xl1)…
                                  May 9 13:39:19 SnortStartup[51918]: Snort STOP For mediacom(22941_xl0)…
                                  May 9 13:39:08 php: : Checking for and disabling any rules dependent upon disabled preprocessors for NETBS...
                                  May 9 13:38:45 kernel: s...
                                  May 9 13:38:42 kernel: for package installation...
                                  May 9 13:38:42 kernel: .
                                  May 9 13:38:34 php: : Beginning package installation for Postfix Forwarder.
                                  May 9 13:38:27 check_reload_status: Syncing firewall
                                  May 9 13:38:18 php: : Checking for and disabling any rules dependent upon disabled preprocessors for MEDIACOM...
                                  May 9 13:37:54 php: : Beginning package installation for TFTP.
                                  May 9 13:37:50 check_reload_status: Syncing firewall
                                  May 9 13:37:48 upsd[43263]: User monuser@127.0.0.1 logged into UPS [gate1backups]
                                  May 9 13:37:46 upsmon[43515]: Startup successful
                                  May 9 13:37:46 upsd[43263]: Startup successful
                                  May 9 13:37:45 upsd[42451]: Connected to UPS [gate1backups]: apcsmart-gate1backups
                                  May 9 13:37:45 upsd[42451]: listening on 127.0.0.1 port 3493
                                  May 9 13:37:45 upsd[42451]: listening on ::1 port 3493
                                  May 9 13:37:44 apcsmart[42179]: Startup successful
                                  May 9 13:37:38 check_reload_status: Syncing firewall
                                  May 9 13:37:34 php: : No pfBlocker action during boot process.
                                  May 9 13:37:34 php: : No pfBlocker action during boot process.
                                  May 9 13:37:32 kernel: package instructions…done.
                                  May 9 13:37:31 kernel: Package XML... done.
                                  May 9 13:37:31 kernel: >Cleaning up... Beginning package installation for arping...
                                  May 9 13:37:30 kernel: .
                                  May 9 13:37:30 kernel: .
                                  May 9 13:37:30 kernel: structions...
                                  May 9 13:37:25 php: : Beginning package installation for pfBlocker.
                                  May 9 13:37:22 php: : No pfBlocker action during boot process.
                                  May 9 13:37:19 check_reload_status: Syncing firewall
                                  May 9 13:37:11 php: : Postfix setup completed
                                  May 9 13:37:06 check_reload_status: Syncing firewall
                                  May 9 13:37:04 kernel: done.
                                  May 9 13:37:03 php: : Reloading/starting postfix
                                  May 9 13:37:02 php: : Writing rc_file
                                  May 9 13:37:00 php: : Beginning package installation for Notes.
                                  May 9 13:37:00 php: : Writing out configuration
                                  May 9 13:36:56 check_reload_status: Syncing firewall
                                  May 9 13:36:42 syslogd: kernel boot file is /boot/kernel/kernel
                                  May 9 13:36:39 syslogd: exiting on signal 15
                                  May 9 13:36:39 php: : Beginning package installation for OpenVPN tap Bridging Fix.
                                  May 9 13:36:31 check_reload_status: Syncing firewall
                                  May 9 13:36:28 php: : XML error: Not well-formed (invalid token) at line 1 in /usr/local/pkg/
                                  May 9 13:36:22 apinger: rrdtool respawning too fast, waiting 300s.
                                  May 9 13:36:22 apinger: Error while feeding rrdtool: Broken pipe
                                  May 9 13:36:21 php: : Message sent to room_qf_systems_status@quietfountain.com OK
                                  May 9 13:36:20 php: : Postfix setup completed
                                  May 9 13:36:20 postfix/postfix-script[11930]: fatal: the Postfix mail system is not running
                                  May 9 13:36:18 php: : Message sent to room_qf_systems_status@quietfountain.com OK
                                  May 9 13:36:17 php: : Could not send the message to room_qf_systems_status@quietfountain.com – Error: it was not possible to read line from the SMTP server: data access time out
                                  May 9 13:36:16 check_reload_status: Syncing firewall
                                  May 9 13:36:13 php: : Reloading/starting postfix
                                  May 9 13:36:12 php: : Writing rc_file
                                  May 9 13:36:10 php: : Writing out configuration
                                  May 9 13:36:10 php: : The command '/usr/local/sbin/postfix reload' returned exit code '1', the output was ''
                                  May 9 13:36:10 postfix/postfix-script[7778]: fatal: the Postfix mail system is not running
                                  May 9 13:36:07 syslogd: kernel boot file is /boot/kernel/kernel
                                  May 9 13:36:07 syslogd: exiting on signal 15
                                  May 9 13:36:02 check_reload_status: Syncing firewall
                                  May 9 13:35:48 miniupnpd[16321]: Listening for NAT-PMP traffic on port 5351
                                  May 9 13:35:48 miniupnpd[16321]: Listening for NAT-PMP traffic on port 5351
                                  May 9 13:35:48 miniupnpd[16321]: HTTP listening on port 2189
                                  May 9 13:35:48 miniupnpd[16321]: HTTP listening on port 2189
                                  May 9 13:35:48 php: : miniupnpd: Starting service on interface: opt3, opt4
                                  May 9 13:35:48 php: : Creating rrd update script
                                  May 9 13:35:44 check_reload_status: Restarting ipsec tunnels
                                  May 9 13:35:42 php: : No pfBlocker action during boot process.
                                  May 9 13:35:42 php: : No pfBlocker action during boot process.
                                  May 9 13:35:42 php: : No pfBlocker action during boot process.
                                  May 9 13:35:42 php: : No pfBlocker action during boot process.
                                  May 9 13:35:41 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:41 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:38 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:38 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:34 php: : XML error: Not well-formed (invalid token) at line 1 in /usr/local/pkg/
                                  May 9 13:35:34 php: : XML error: Not well-formed (invalid token) at line 1 in /usr/local/pkg/
                                  May 9 13:35:33 php: : Message sent to room_qf_systems_status@quietfountain.com OK
                                  May 9 13:35:32 php: : Restarting/Starting all packages.
                                  May 9 13:35:31 php: : pfSense package system has detected an ip change -> 192.168.55.6 … Restarting packages.
                                  May 9 13:35:31 ntpdate[60859]: step time server 216.171.120.36 offset 0.988073 sec
                                  May 9 13:35:30 dnsmasq[159]: read /etc/hosts - 55 addresses
                                  May 9 13:35:30 dnsmasq[159]: using nameserver 192.168.50.1#53 for domain XXXXXX.com
                                  May 9 13:35:30 dnsmasq[159]: ignoring nameserver 127.0.0.1 - local interface
                                  May 9 13:35:30 dnsmasq[159]: ignoring nameserver 127.0.0.1 - local interface
                                  May 9 13:35:30 dnsmasq[159]: using nameserver XXXXX0#53
                                  May 9 13:35:30 dnsmasq[159]: using nameserver xXXXXX3#53
                                  May 9 13:35:30 dnsmasq[159]: using nameserver 8.8.8.8#53
                                  May 9 13:35:30 dnsmasq[159]: using nameserver 8.8.4.4#53
                                  May 9 13:35:30 dnsmasq[159]: reading /etc/resolv.conf
                                  May 9 13:35:30 dnsmasq[159]: using nameserver 192.168.50.1#53 for domain mamabosso.com
                                  May 9 13:35:30 dnsmasq[159]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
                                  May 9 13:35:30 dnsmasq[159]: started, version 2.65 cachesize 10000
                                  May 9 13:35:30 check_reload_status: Updating all dyndns
                                  May 9 13:35:30 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
                                  May 9 13:35:30 dhcpd: All rights reserved.
                                  May 9 13:35:30 dhcpd: Copyright 2004-2012 Internet Systems Consortium.
                                  May 9 13:35:30 dhcpd: Internet Systems Consortium DHCP Server 4.2.4-P2
                                  May 9 13:35:27 php: : rc.newwanip: on (IP address: 192.168.55.6) (interface: ) (real interface: ovpnc3).
                                  May 9 13:35:27 php: : rc.newwanip: Informational is starting ovpnc3.
                                  May 9 13:35:27 php: : Gateways status could not be determined, considering all as up/active.
                                  May 9 13:35:26 check_reload_status: Starting packages
                                  May 9 13:35:26 php: : pfSense package system has detected an ip change -> 192.168.24.1 … Restarting packages.
                                  May 9 13:35:26 php: : ROUTING: setting default route to 97.64.213.193
                                  May 9 13:35:23 check_reload_status: rc.newwanip starting ovpnc3
                                  May 9 13:35:23 kernel: ovpnc3: link state changed to UP
                                  May 9 13:35:22 check_reload_status: Reloading filter
                                  May 9 13:35:22 apinger: Starting Alarm Pinger, apinger(33542)
                                  May 9 13:35:22 php: : rc.newwanip: on (IP address: 192.168.24.1) (interface: ) (real interface: ovpns1).
                                  May 9 13:35:22 php: : rc.newwanip: Informational is starting ovpns1.
                                  May 9 13:35:21 php: : Removing static route for monitor 8.8.8.8 and adding a new route through 97.64.213.193
                                  May 9 13:35:21 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:20 php: : filter_generate_address: is not a valid source port.
                                  May 9 13:35:18 kernel: pflog0: promiscuous mode enabled
                                  May 9 13:35:18 kernel: Trying to mount root from ufs:/dev/ad0s1a
                                  ....

                                  1 Reply Last reply Reply Quote 0
                                  • M
                                    mschiek01
                                    last edited by

                                    Try this.

                                    Go into package manager and uninstall:
                                    nut, pfblocker, postfix forwarder.

                                    Go to the command line and type pkg_info

                                    Look at the installed packages and make sure none of them are showing installed also make sure none of them show older versions installed.

                                    If any of them are type pkg_delete -f "package name"

                                    Reboot the box

                                    Then go into package manager and install.

                                    postfix first and make sure that you check the check box to run postfix as well as save the configuration.  It should still be there as it was before.

                                    Reboot the box and make sure postfix starts.

                                    Then install the other two packages and you should be good to go.

                                    1 Reply Last reply Reply Quote 0
                                    • H
                                      hcoin
                                      last edited by

                                      Love to, but as 'the packages are reinstalling in the background' the package manager won't display them.  When I navigate to the package manager, it tells me to wait until the re-installation, which will never finish, finishes.

                                      If I reboot I think I can do as you suggest.  Should I reboot then try?

                                      1 Reply Last reply Reply Quote 0
                                      • M
                                        mschiek01
                                        last edited by

                                        Yes

                                        1 Reply Last reply Reply Quote 0
                                        • H
                                          hcoin
                                          last edited by

                                          I removed as indicated. After removal, of interest pkg_info included:

                                          postfix-current-2.9.20120102,4 A secure alternative to widely-used Sendmail

                                          There were no references to pfblocker or nut.  I removed the above, the package manager having already removed others noted.

                                          I followed the directions indicated above, all appears to be working.    What a ride!

                                          What could I have done to have avoided this prize?  What went wrong?

                                          And, mostly, thanks!   :D

                                          1 Reply Last reply Reply Quote 0
                                          • M
                                            mschiek01
                                            last edited by

                                            This -> postfix-current-2.9.20120102 was your problem.

                                            For some reason the old package did not completely uninstall.  I have had this problem a few times.

                                            On an upgrade I usually uninstall the packages and then do the upgrade and then manually reinstall the packages through the gui after the upgrade has finnished.  Also I always check from the command line to make sure the package has unistalled properly.

                                            1 Reply Last reply Reply Quote 0
                                            • First post
                                              Last post
                                            Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.