smtpd stops immediately after starting in -current

classic Classic list List threaded Threaded
19 messages Options
Reply | Threaded
Open this post in threaded view
|

smtpd stops immediately after starting in -current

Allan Streib
Just upgraded to -current from my local mirror. Was previously working with a recent-ish -current (late April or early May)

In /var/log/maillog:

May 17 17:00:29 fabrik smtpd[8370]: info: OpenSMTPD 5.4.3 starting
May 17 17:00:29 fabrik smtpd[23758]: info: startup
May 17 17:00:30 fabrik smtpd[23464]: smtp-in: New session 3c7a8ed5bcd7c87e from host 1000@localhost [local]
May 17 17:00:30 fabrik smtpd[6061]: warn: ca -> pony: pipe closed
May 17 17:00:30 fabrik smtpd[29180]: warn: control -> pony: pipe closed
May 17 17:00:30 fabrik smtpd[23758]: warn: parent -> pony: pipe closed
May 17 17:00:30 fabrik smtpd[15221]: warn: lka -> pony: pipe closed
May 17 17:00:30 fabrik smtpd[12014]: warn: queue -> pony: pipe closed
May 17 17:00:30 fabrik smtpd[11724]: warn: scheduler -> control: pipe closed

$ sudo /usr/sbin/smtpd -d -v
debug: init ssl-tree
info: OpenSMTPD 5.4.3 starting
debug: bounce warning after 4h
debug: using "fs" queue backend
debug: using "ramqueue" scheduler backend
debug: using "ram" stat backend
info: startup [debug mode]
debug: init ssl-tree
debug: ca_engine_init: using RSAX engine support
debug: parent_send_config_ruleset: reloading
debug: parent_send_config: configuring pony process
debug: parent_send_config: configuring ca process
debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
debug: smtp: will accept at most 501 clients
debug: init private ssl-tree
debug: queue: done loading queue into scheduler
debug: smtpd: scanning offline queue...
debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400360297.gGOeYTNHCB
debug: smtpd: offline scanning done
debug: smtp: new client on listener: 0x870670680c0
smtp-in: New session 196f0b916c2465b1 from host 1000@localhost [local]
warn: ca -> pony: pipe closed
warn: lka -> pony: pipe closed
warn: queue -> pony: pipe closed
warn: control -> pony: pipe closed
warn: parent -> pony: pipe closed
warn: scheduler -> queue: pipe closed

$ dmesg
OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
    [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
real mem = 10715041792 (10218MB)
avail mem = 10421043200 (9938MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe00f0 (73 entries)
bios0: vendor Apple Computer, Inc. version "MP11.88Z.005C.B08.0707021221" date 07/02/07
bios0: Apple Computer, Inc. MacPro1,1
acpi0 at bios0: rev 2
acpi0: sleep states S0 S1 S3 S4 S5
acpi0: tables DSDT ECDT FACP HPET APIC MCFG SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSD
T SSDT SSDT
acpi0: wakeup devices P2P5(S4) P2P3(S4) ARPT(S4) RP04(S4) UHC1(S3) UHC2(S3) UHC3(S3) UHC4(S3) EHCI(S3) AC9M(S4) EC__(S3) NRP4(S4) SR
P1(S4) SRP3(S4)
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpiec0 at acpi0
acpihpet0 at acpi0: 14318179 Hz
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.38 MHz
cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
cpu0: 4MB 64b/line 16-way L2 cache
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 8 var ranges, 88 fixed ranges
cpu0: apic clock running at 332MHz
cpu0: mwait min=64, max=64, C-substates=0.2.0.0.0, IBE
cpu1 at mainbus0: apid 1 (application processor)
cpu1: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.00 MHz
cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
cpu1: 4MB 64b/line 16-way L2 cache
cpu1: smt 0, core 1, package 0
cpu2 at mainbus0: apid 7 (application processor)
cpu2: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
cpu2: 4MB 64b/line 16-way L2 cache
cpu2: smt 0, core 1, package 3
cpu3 at mainbus0: apid 6 (application processor)
cpu3: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
cpu3: 4MB 64b/line 16-way L2 cache
cpu3: smt 0, core 0, package 3
ioapic0 at mainbus0: apid 8 pa 0xfec00000, version 20, 24 pins
acpimcfg0 at acpi0 addr 0xd0000000, bus 0-255
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus 1 (P0P1)
acpiprt2 at acpi0: bus 2 (P1P2)
acpiprt3 at acpi0: bus 5 (P2P5)
acpiprt4 at acpi0: bus 3 (P2P3)
acpiprt5 at acpi0: bus 15 (RP04)
acpiprt6 at acpi0: bus 16 (PCIB)
acpiprt7 at acpi0: bus 8 (NRP4)
acpiprt8 at acpi0: bus 12 (SRP1)
acpiprt9 at acpi0: bus 14 (SRP3)
acpicpu0 at acpi0: C1
acpicpu1 at acpi0: C1
acpicpu2 at acpi0: C1
acpicpu3 at acpi0: C1
acpibtn0 at acpi0: PWRB
memory map conflict 0xfff90000/0x30000
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel 5000X Host" rev 0x30
ppb0 at pci0 dev 2 function 0 "Intel 5000 PCIE x8" rev 0x30
pci1 at ppb0 bus 1
ppb1 at pci1 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01
pci2 at ppb1 bus 2
ppb2 at pci2 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
pci3 at ppb2 bus 3
ppb3 at pci2 dev 1 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
pci4 at ppb3 bus 4
ppb4 at pci2 dev 2 function 0 "Intel 6321ESB PCIE" rev 0x01
pci5 at ppb4 bus 5
em0 at pci5 dev 0 function 0 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:94
em1 at pci5 dev 0 function 1 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:95
"Intel 6321ESB IOxAPIC" rev 0x01 at pci1 dev 0 function 1 not configured
ppb5 at pci1 dev 0 function 3 "Intel 6321ESB PCIE-PCIX" rev 0x01
pci6 at ppb5 bus 6
pchb1 at pci0 dev 3 function 0 "Intel 5000 PCIE" rev 0x30
ppb6 at pci0 dev 4 function 0 "Intel 5000 PCIE x16" rev 0x30: msi
pci7 at ppb6 bus 8
vga1 at pci7 dev 0 function 0 "NVIDIA GeForce 7300 GT" rev 0xa1
wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation)
wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
pchb2 at pci0 dev 5 function 0 "Intel 5000 PCIE" rev 0x30
pchb3 at pci0 dev 6 function 0 "Intel 5000 PCIE" rev 0x30
pchb4 at pci0 dev 7 function 0 "Intel 5000 PCIE" rev 0x30
"Intel I/OAT" rev 0x30 at pci0 dev 8 function 0 not configured
pchb5 at pci0 dev 16 function 0 "Intel 5000 Error Reporting" rev 0x30
pchb6 at pci0 dev 16 function 1 "Intel 5000 Error Reporting" rev 0x30
pchb7 at pci0 dev 16 function 2 "Intel 5000 Error Reporting" rev 0x30
pchb8 at pci0 dev 17 function 0 "Intel 5000 Reserved" rev 0x30
pchb9 at pci0 dev 19 function 0 "Intel 5000 Reserved" rev 0x30
pchb10 at pci0 dev 21 function 0 "Intel 5000 FBD" rev 0x30
pchb11 at pci0 dev 22 function 0 "Intel 5000 FBD" rev 0x30
azalia0 at pci0 dev 27 function 0 "Intel 6321ESB HD Audio" rev 0x09: msi
azalia0: codecs: Realtek ALC885
audio0 at azalia0
ppb7 at pci0 dev 28 function 0 "Intel 6321ESB PCIE" rev 0x09: msi
pci8 at ppb7 bus 12
ppb8 at pci0 dev 28 function 1 "Intel 6321ESB PCIE" rev 0x09: msi
pci9 at ppb8 bus 13
ppb9 at pci0 dev 28 function 2 "Intel 6321ESB PCIE" rev 0x09: msi
pci10 at ppb9 bus 14
ppb10 at pci0 dev 28 function 3 "Intel 6321ESB PCIE" rev 0x09: msi
pci11 at ppb10 bus 15
uhci0 at pci0 dev 29 function 0 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
uhci1 at pci0 dev 29 function 1 "Intel 6321ESB USB" rev 0x09: apic 8 int 20
uhci2 at pci0 dev 29 function 2 "Intel 6321ESB USB" rev 0x09: apic 8 int 21
uhci3 at pci0 dev 29 function 3 "Intel 6321ESB USB" rev 0x09: apic 8 int 22
ehci0 at pci0 dev 29 function 7 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
ppb11 at pci0 dev 30 function 0 "Intel 82801BA Hub-to-PCI" rev 0xd9
pci12 at ppb11 bus 16
"TI TSB82AA2 FireWire" rev 0x01 at pci12 dev 11 function 0 not configured
pcib0 at pci0 dev 31 function 0 "Intel 6321ESB LPC" rev 0x09
pciide0 at pci0 dev 31 function 1 "Intel 6321ESB IDE" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to nat
ive-PCI
pciide0: using apic 8 int 20 for native-PCI interrupt
atapiscsi0 at pciide0 channel 0 drive 0
scsibus1 at atapiscsi0: 2 targets
cd0 at scsibus1 targ 0 lun 0: <PIONEER, DVD-RW DVR-111D, AB09> ATAPI 5/cdrom removable
cd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 4
pciide0: channel 1 disabled (no drives)
pciide1 at pci0 dev 31 function 2 "Intel 6321ESB SATA" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to na
tive-PCI
pciide1: using apic 8 int 21 for native-PCI interrupt
wd0 at pciide1 channel 0 drive 0: <ST3160812AS P>
wd0: 16-sector PIO, LBA48, 152627MB, 312581808 sectors
wd0(pciide1:0:0): using PIO mode 4, Ultra-DMA mode 6
wd1 at pciide1 channel 1 drive 0: <ST3000VX000-9YW166>
wd1: 16-sector PIO, LBA48, 2861588MB, 5860533168 sectors
wd1(pciide1:1:0): using PIO mode 4, Ultra-DMA mode 6
ichiic0 at pci0 dev 31 function 3 "Intel 6321ESB SMBus" rev 0x09: apic 8 int 21
iic0 at ichiic0
usb1 at uhci0: USB revision 1.0
uhub1 at usb1 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb2 at uhci1: USB revision 1.0
uhub2 at usb2 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb3 at uhci2: USB revision 1.0
uhub3 at usb3 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb4 at uhci3: USB revision 1.0
uhub4 at usb4 "Intel UHCI root hub" rev 1.00/1.00 addr 1
isa0 at pcib0
isadma0 at isa0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
uhub5 at uhub0 port 2 "Apple, Inc. Keyboard Hub" rev 2.00/96.15 addr 2
uhidev0 at uhub5 port 2 configuration 1 interface 0 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
uhidev0: iclass 3/1
ukbd0 at uhidev0: 8 variable keys, 5 key codes, country code 33
wskbd0 at ukbd0: console keyboard, using wsdisplay0
uhidev1 at uhub5 port 2 configuration 1 interface 1 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
uhidev1: iclass 3/0
uhid0 at uhidev1: input=1, output=0, feature=0
uhidev2 at uhub5 port 3 configuration 1 interface 0 "Primax Electronics Apple Optical USB Mouse" rev 1.10/1.10 addr 4
uhidev2: iclass 3/1
ums0 at uhidev2: 4 buttons, Z and W dir
wsmouse0 at ums0 mux 0
rum0 at uhub0 port 5 "Cisco-Linksys Compact Wireless-G USB Adapter" rev 2.00/0.01 addr 5
rum0: MAC/BBP RT2573 (rev 0x2573a), RF RT2528, address 00:18:39:1c:22:7a
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
root on wd0a (1b370bc9a7444b9f.a) swap on wd0b dump on wd0b

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Allan Streib
On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:

> Just upgraded to -current from my local mirror. Was previously working
> with a recent-ish -current (late April or early May)

By "current" I meant "snapshot" sorry if that caused any confusion.

Allan

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Lars
Hi,

> On 18 May 2014 at 04:40 Allan Streib <[hidden email]> wrote:
>
>
> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
>
> > Just upgraded to -current from my local mirror. Was previously working
> > with a recent-ish -current (late April or early May)
>
> By "current" I meant "snapshot" sorry if that caused any confusion.
>

I seem to have the same problem on a current snapshot. I get exactly the same
messages as Allen with a generic (means default smtpd config). Sysmerge has been
executed on this system as well.

OpenBSD 5.5-current (GENERIC.MP) #133: Fri May 16 19:01:03 MDT 2014
    [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
real mem = 8439681024 (8048MB)
avail mem = 8206258176 (7826MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.7 @ 0xec1f0 (67 entries)
bios0: vendor American Megatrends Inc. version "0503" date 01/07/2014
bios0: ASUS All Series
acpi0 at bios0: rev 2
acpi0: sleep states S0 S3 S4 S5
acpi0: tables DSDT FACP APIC FPDT LPIT SSDT SSDT MCFG HPET SSDT SSDT BGRT
acpi0: wakeup devices UAR1(S4) PS2K(S4) PXSX(S4) RP01(S4) PXSX(S4) RP02(S4)
PXSX(S4) RP03(S4) PXSX(S4) RP04(S4) PXSX(S4) RP05(S4) PXSX(S4) RP06(S4) PXSX(S4)
RP07(S4) [...]
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Pentium(R) CPU G3220 @ 3.00GHz, 2993.51 MHz
cpu0:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,MOVBE,POPCNT,DEADLINE,XSAVE,RDRAND,NXE,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,ERMS,INVPCID
cpu0: 256KB 64b/line 8-way L2 cache
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 10 var ranges, 88 fixed ranges
cpu0: apic clock running at 99MHz
cpu0: mwait min=64, max=64, C-substates=0.2.1.2.0, IBE
cpu1 at mainbus0: apid 2 (application processor)
cpu1: Intel(R) Pentium(R) CPU G3220 @ 3.00GHz, 2993.07 MHz
cpu1:
FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,PCLMUL,DTES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,MOVBE,POPCNT,DEADLINE,XSAVE,RDRAND,NXE,LONG,LAHF,ABM,PERF,ITSC,FSGSBASE,ERMS,INVPCID
cpu1: 256KB 64b/line 8-way L2 cache
cpu1: smt 0, core 1, package 0
ioapic0 at mainbus0: apid 8 pa 0xfec00000, version 20, 24 pins
acpimcfg0 at acpi0 addr 0xf8000000, bus 0-63
acpihpet0 at acpi0: 14318179 Hz
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus 2 (RP01)
acpiprt2 at acpi0: bus 3 (RP03)
acpiprt3 at acpi0: bus 1 (PEG0)
acpiprt4 at acpi0: bus -1 (PEG1)
acpiprt5 at acpi0: bus -1 (PEG2)
acpiec0 at acpi0: Failed to read resource settings
acpicpu0 at acpi0: C1, PSS
acpicpu1 at acpi0: C1, PSS
acpipwrres0 at acpi0: FN00, resource for FAN0
acpipwrres1 at acpi0: FN01, resource for FAN1
acpipwrres2 at acpi0: FN02, resource for FAN2
acpipwrres3 at acpi0: FN03, resource for FAN3
acpipwrres4 at acpi0: FN04, resource for FAN4
acpitz0 at acpi0: critical temperature is 105 degC
acpitz1 at acpi0: critical temperature is 105 degC
acpibat0 at acpi0: BAT0 not present
acpibat1 at acpi0: BAT1 not present
acpibat2 at acpi0: BAT2 not present
acpibtn0 at acpi0: PWRB
acpibtn1 at acpi0: LID0
acpivideo0 at acpi0: GFX0
acpivout0 at acpivideo0: DD1F
cpu0: Enhanced SpeedStep 2993 MHz: speeds: 3000, 2900, 2700, 2600, 2400, 2300,
2100, 2000, 1800, 1700, 1500, 1400, 1200, 1100, 900, 800 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel Core 4G Host" rev 0x06
ppb0 at pci0 dev 1 function 0 "Intel Core 4G PCIE" rev 0x06: msi
pci1 at ppb0 bus 1
vga1 at pci0 dev 2 function 0 "Intel HD Graphics" rev 0x06
intagp at vga1 not configured
inteldrm0 at vga1
drm0 at inteldrm0
drm: Memory usable by graphics device = 2048M
error: [drm:pid0:i915_write32] *ERROR* Unknown unclaimed register before writing
to 100000
error: [drm:pid0:intel_dp_i2c_aux_ch] *ERROR* too many retries, giving up
error: [drm:pid0:intel_dp_i2c_aux_ch] *ERROR* too many retries, giving up
error: [drm:pid0:intel_dp_i2c_aux_ch] *ERROR* too many retries, giving up
inteldrm0: 1680x1050
wsdisplay0 at vga1 mux 1: console (std, vt100 emulation)
wsdisplay0: screen 1-5 added (std, vt100 emulation)
azalia0 at pci0 dev 3 function 0 "Intel Core 4G HD Audio" rev 0x06: msi
azalia0: No codecs found
"Intel 8 Series xHCI" rev 0x05 at pci0 dev 20 function 0 not configured
"Intel 8 Series MEI" rev 0x04 at pci0 dev 22 function 0 not configured
ehci0 at pci0 dev 26 function 0 "Intel 8 Series USB" rev 0x05: apic 8 int 20
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
azalia1 at pci0 dev 27 function 0 "Intel 8 Series HD Audio" rev 0x05: msi
azalia1: codecs: Realtek/0x0887
audio0 at azalia1
ppb1 at pci0 dev 28 function 0 "Intel 8 Series PCIE" rev 0xd5: msi
pci2 at ppb1 bus 2
ppb2 at pci0 dev 28 function 2 "Intel 8 Series PCIE" rev 0xd5: msi
pci3 at ppb2 bus 3
re0 at pci3 dev 0 function 0 "Realtek 8168" rev 0x0c: RTL8168G/8111G (0x4c00),
msi, address bc:ee:7b:9d:c4:05
rgephy0 at re0 phy 7: RTL8251 PHY, rev. 0
ehci1 at pci0 dev 29 function 0 "Intel 8 Series USB" rev 0x05: apic 8 int 23
usb1 at ehci1: USB revision 2.0
uhub1 at usb1 "Intel EHCI root hub" rev 2.00/1.00 addr 1
pcib0 at pci0 dev 31 function 0 "Intel H81 LPC" rev 0x05
ahci0 at pci0 dev 31 function 2 "Intel 8 Series AHCI" rev 0x05: msi, AHCI 1.3
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 1 lun 0: <ATA, MKNSSDAT120GB-DX, 524A> SCSI3 0/direct fixed
naa.0000120000000000
sd0: 114473MB, 512 bytes/sector, 234441648 sectors, thin
ichiic0 at pci0 dev 31 function 3 "Intel 8 Series SMBus" rev 0x05: apic 8 int 18
iic0 at ichiic0
spdmem0 at iic0 addr 0x50: 4GB DDR3 SDRAM PC3-10600 SO-DIMM
spdmem1 at iic0 addr 0x52: 4GB DDR3 SDRAM PC3-10600 SO-DIMM
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
pckbc0 at isa0 port 0x60/5
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
uhub2 at uhub0 port 1 "Intel Rate Matching Hub" rev 2.00/0.05 addr 2
uhub3 at uhub1 port 1 "Intel Rate Matching Hub" rev 2.00/0.05 addr 2
uhidev0 at uhub3 port 3 configuration 1 interface 0 "Logitech G400s Optical
Gaming Mouse" rev 2.00/83.00 addr 3
uhidev0: iclass 3/1
ums0 at uhidev0: 8 buttons, Z dir
wsmouse0 at ums0 mux 0
uhidev1 at uhub3 port 3 configuration 1 interface 1 "Logitech G400s Optical
Gaming Mouse" rev 2.00/83.00 addr 3
uhidev1: iclass 3/0, 142 report ids
uhid0 at uhidev1 reportid 32: input=0, output=0, feature=1
uhid1 at uhidev1 reportid 128: input=1, output=0, feature=0
uhid2 at uhidev1 reportid 142: input=0, output=0, feature=1
uhidev2 at uhub3 port 4 configuration 1 interface 0 "Dell Dell USB Keyboard" rev
1.10/3.06 addr 4
uhidev2: iclass 3/1
ukbd0 at uhidev2: 8 variable keys, 6 key codes
wskbd1 at ukbd0 mux 1
wskbd1: connecting to wsdisplay0
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
root on sd0a (c9d84f39a764e04c.a) swap on sd0b dump on sd0b
umass0 at uhub3 port 2 configuration 1 interface 0 "TrekStor DS maxi g.u" rev
2.00/1.12 addr 5
umass0: using SCSI over Bulk-Only
scsibus4 at umass0: 2 targets, initiator 0
sd1 at scsibus4 targ 1 lun 0: <TrekStor, DS maxi g.u :2 H, 100> SCSI2 0/direct
fixed
sd1: 715404MB, 512 bytes/sector, 1465149168 sectors

Thanks
Lars

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
In reply to this post by Allan Streib
On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
>
> > Just upgraded to -current from my local mirror. Was previously working
> > with a recent-ish -current (late April or early May)
>
> By "current" I meant "snapshot" sorry if that caused any confusion.
>

I'll have a look at this, thanks

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Kenneth Westerback
On 18 May 2014 05:37, Gilles Chehade <[hidden email]> wrote:

> On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
>> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
>>
>> > Just upgraded to -current from my local mirror. Was previously working
>> > with a recent-ish -current (late April or early May)
>>
>> By "current" I meant "snapshot" sorry if that caused any confusion.
>>
>
> I'll have a look at this, thanks
>
> --
> Gilles Chehade
>
> https://www.poolp.org                                          @poolpOrg
>

I've found that if there is anything processed from the offline queue
then smtpd stops immediately. If I remove all offline files then smtpd
starts up fine but as soon as I send an email (using mutt) it exits.

Running '/usr/sbin/smtpd -v -d' and then using mutt I see

$ sudo /usr/sbin/smtpd -v -d
debug: init ssl-tree
info: OpenSMTPD 5.4.3 starting
debug: bounce warning after 4h
debug: using "fs" queue backend
debug: using "ramqueue" scheduler backend
debug: using "ram" stat backend
info: startup [debug mode]
debug: init ssl-tree
debug: ca_engine_init: using RSAX engine support
debug: queue: done loading queue into scheduler
debug: parent_send_config_ruleset: reloading
debug: parent_send_config: configuring pony process
debug: parent_send_config: configuring ca process
debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
debug: init private ssl-tree
debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
debug: smtp: will accept at most 501 clients
debug: smtpd: scanning offline queue...
debug: smtpd: offline scanning done
debug: smtp: new client on listener: 0x8a03e068120
smtp-in: New session 7d58bb784abfedee from host 1000@localhost [local]
warn: ca -> pony: pipe closed
warn: control -> pony: pipe closed
warn: lka -> pony: pipe closed
warn: queue -> pony: pipe closed
warn: parent -> pony: pipe closed
warn: scheduler -> control: pipe closed
$

When I had four files in offline, then there were 4 "New session"
lines before the 'warn' messages start.

All on -current as of yesterday, including mutt.

.... Ken

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
In reply to this post by Allan Streib
can you share your configuration file ?

i'm unable to reproduce no matter what i try :-/


On Sat, May 17, 2014 at 05:30:25PM -0400, Allan Streib wrote:

> Just upgraded to -current from my local mirror. Was previously working with a recent-ish -current (late April or early May)
>
> In /var/log/maillog:
>
> May 17 17:00:29 fabrik smtpd[8370]: info: OpenSMTPD 5.4.3 starting
> May 17 17:00:29 fabrik smtpd[23758]: info: startup
> May 17 17:00:30 fabrik smtpd[23464]: smtp-in: New session 3c7a8ed5bcd7c87e from host 1000@localhost [local]
> May 17 17:00:30 fabrik smtpd[6061]: warn: ca -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[29180]: warn: control -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[23758]: warn: parent -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[15221]: warn: lka -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[12014]: warn: queue -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[11724]: warn: scheduler -> control: pipe closed
>
> $ sudo /usr/sbin/smtpd -d -v
> debug: init ssl-tree
> info: OpenSMTPD 5.4.3 starting
> debug: bounce warning after 4h
> debug: using "fs" queue backend
> debug: using "ramqueue" scheduler backend
> debug: using "ram" stat backend
> info: startup [debug mode]
> debug: init ssl-tree
> debug: ca_engine_init: using RSAX engine support
> debug: parent_send_config_ruleset: reloading
> debug: parent_send_config: configuring pony process
> debug: parent_send_config: configuring ca process
> debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
> debug: smtp: will accept at most 501 clients
> debug: init private ssl-tree
> debug: queue: done loading queue into scheduler
> debug: smtpd: scanning offline queue...
> debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400360297.gGOeYTNHCB
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0x870670680c0
> smtp-in: New session 196f0b916c2465b1 from host 1000@localhost [local]
> warn: ca -> pony: pipe closed
> warn: lka -> pony: pipe closed
> warn: queue -> pony: pipe closed
> warn: control -> pony: pipe closed
> warn: parent -> pony: pipe closed
> warn: scheduler -> queue: pipe closed
>
> $ dmesg
> OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
>     [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
> real mem = 10715041792 (10218MB)
> avail mem = 10421043200 (9938MB)
> mpath0 at root
> scsibus0 at mpath0: 256 targets
> mainbus0 at root
> bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe00f0 (73 entries)
> bios0: vendor Apple Computer, Inc. version "MP11.88Z.005C.B08.0707021221" date 07/02/07
> bios0: Apple Computer, Inc. MacPro1,1
> acpi0 at bios0: rev 2
> acpi0: sleep states S0 S1 S3 S4 S5
> acpi0: tables DSDT ECDT FACP HPET APIC MCFG SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSD
> T SSDT SSDT
> acpi0: wakeup devices P2P5(S4) P2P3(S4) ARPT(S4) RP04(S4) UHC1(S3) UHC2(S3) UHC3(S3) UHC4(S3) EHCI(S3) AC9M(S4) EC__(S3) NRP4(S4) SR
> P1(S4) SRP3(S4)
> acpitimer0 at acpi0: 3579545 Hz, 24 bits
> acpiec0 at acpi0
> acpihpet0 at acpi0: 14318179 Hz
> acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
> cpu0 at mainbus0: apid 0 (boot processor)
> cpu0: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.38 MHz
> cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu0: 4MB 64b/line 16-way L2 cache
> cpu0: smt 0, core 0, package 0
> mtrr: Pentium Pro MTRR support, 8 var ranges, 88 fixed ranges
> cpu0: apic clock running at 332MHz
> cpu0: mwait min=64, max=64, C-substates=0.2.0.0.0, IBE
> cpu1 at mainbus0: apid 1 (application processor)
> cpu1: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.00 MHz
> cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu1: 4MB 64b/line 16-way L2 cache
> cpu1: smt 0, core 1, package 0
> cpu2 at mainbus0: apid 7 (application processor)
> cpu2: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
> cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu2: 4MB 64b/line 16-way L2 cache
> cpu2: smt 0, core 1, package 3
> cpu3 at mainbus0: apid 6 (application processor)
> cpu3: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
> cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu3: 4MB 64b/line 16-way L2 cache
> cpu3: smt 0, core 0, package 3
> ioapic0 at mainbus0: apid 8 pa 0xfec00000, version 20, 24 pins
> acpimcfg0 at acpi0 addr 0xd0000000, bus 0-255
> acpiprt0 at acpi0: bus 0 (PCI0)
> acpiprt1 at acpi0: bus 1 (P0P1)
> acpiprt2 at acpi0: bus 2 (P1P2)
> acpiprt3 at acpi0: bus 5 (P2P5)
> acpiprt4 at acpi0: bus 3 (P2P3)
> acpiprt5 at acpi0: bus 15 (RP04)
> acpiprt6 at acpi0: bus 16 (PCIB)
> acpiprt7 at acpi0: bus 8 (NRP4)
> acpiprt8 at acpi0: bus 12 (SRP1)
> acpiprt9 at acpi0: bus 14 (SRP3)
> acpicpu0 at acpi0: C1
> acpicpu1 at acpi0: C1
> acpicpu2 at acpi0: C1
> acpicpu3 at acpi0: C1
> acpibtn0 at acpi0: PWRB
> memory map conflict 0xfff90000/0x30000
> pci0 at mainbus0 bus 0
> pchb0 at pci0 dev 0 function 0 "Intel 5000X Host" rev 0x30
> ppb0 at pci0 dev 2 function 0 "Intel 5000 PCIE x8" rev 0x30
> pci1 at ppb0 bus 1
> ppb1 at pci1 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01
> pci2 at ppb1 bus 2
> ppb2 at pci2 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
> pci3 at ppb2 bus 3
> ppb3 at pci2 dev 1 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
> pci4 at ppb3 bus 4
> ppb4 at pci2 dev 2 function 0 "Intel 6321ESB PCIE" rev 0x01
> pci5 at ppb4 bus 5
> em0 at pci5 dev 0 function 0 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:94
> em1 at pci5 dev 0 function 1 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:95
> "Intel 6321ESB IOxAPIC" rev 0x01 at pci1 dev 0 function 1 not configured
> ppb5 at pci1 dev 0 function 3 "Intel 6321ESB PCIE-PCIX" rev 0x01
> pci6 at ppb5 bus 6
> pchb1 at pci0 dev 3 function 0 "Intel 5000 PCIE" rev 0x30
> ppb6 at pci0 dev 4 function 0 "Intel 5000 PCIE x16" rev 0x30: msi
> pci7 at ppb6 bus 8
> vga1 at pci7 dev 0 function 0 "NVIDIA GeForce 7300 GT" rev 0xa1
> wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation)
> wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
> pchb2 at pci0 dev 5 function 0 "Intel 5000 PCIE" rev 0x30
> pchb3 at pci0 dev 6 function 0 "Intel 5000 PCIE" rev 0x30
> pchb4 at pci0 dev 7 function 0 "Intel 5000 PCIE" rev 0x30
> "Intel I/OAT" rev 0x30 at pci0 dev 8 function 0 not configured
> pchb5 at pci0 dev 16 function 0 "Intel 5000 Error Reporting" rev 0x30
> pchb6 at pci0 dev 16 function 1 "Intel 5000 Error Reporting" rev 0x30
> pchb7 at pci0 dev 16 function 2 "Intel 5000 Error Reporting" rev 0x30
> pchb8 at pci0 dev 17 function 0 "Intel 5000 Reserved" rev 0x30
> pchb9 at pci0 dev 19 function 0 "Intel 5000 Reserved" rev 0x30
> pchb10 at pci0 dev 21 function 0 "Intel 5000 FBD" rev 0x30
> pchb11 at pci0 dev 22 function 0 "Intel 5000 FBD" rev 0x30
> azalia0 at pci0 dev 27 function 0 "Intel 6321ESB HD Audio" rev 0x09: msi
> azalia0: codecs: Realtek ALC885
> audio0 at azalia0
> ppb7 at pci0 dev 28 function 0 "Intel 6321ESB PCIE" rev 0x09: msi
> pci8 at ppb7 bus 12
> ppb8 at pci0 dev 28 function 1 "Intel 6321ESB PCIE" rev 0x09: msi
> pci9 at ppb8 bus 13
> ppb9 at pci0 dev 28 function 2 "Intel 6321ESB PCIE" rev 0x09: msi
> pci10 at ppb9 bus 14
> ppb10 at pci0 dev 28 function 3 "Intel 6321ESB PCIE" rev 0x09: msi
> pci11 at ppb10 bus 15
> uhci0 at pci0 dev 29 function 0 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
> uhci1 at pci0 dev 29 function 1 "Intel 6321ESB USB" rev 0x09: apic 8 int 20
> uhci2 at pci0 dev 29 function 2 "Intel 6321ESB USB" rev 0x09: apic 8 int 21
> uhci3 at pci0 dev 29 function 3 "Intel 6321ESB USB" rev 0x09: apic 8 int 22
> ehci0 at pci0 dev 29 function 7 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
> usb0 at ehci0: USB revision 2.0
> uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
> ppb11 at pci0 dev 30 function 0 "Intel 82801BA Hub-to-PCI" rev 0xd9
> pci12 at ppb11 bus 16
> "TI TSB82AA2 FireWire" rev 0x01 at pci12 dev 11 function 0 not configured
> pcib0 at pci0 dev 31 function 0 "Intel 6321ESB LPC" rev 0x09
> pciide0 at pci0 dev 31 function 1 "Intel 6321ESB IDE" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to nat
> ive-PCI
> pciide0: using apic 8 int 20 for native-PCI interrupt
> atapiscsi0 at pciide0 channel 0 drive 0
> scsibus1 at atapiscsi0: 2 targets
> cd0 at scsibus1 targ 0 lun 0: <PIONEER, DVD-RW DVR-111D, AB09> ATAPI 5/cdrom removable
> cd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 4
> pciide0: channel 1 disabled (no drives)
> pciide1 at pci0 dev 31 function 2 "Intel 6321ESB SATA" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to na
> tive-PCI
> pciide1: using apic 8 int 21 for native-PCI interrupt
> wd0 at pciide1 channel 0 drive 0: <ST3160812AS P>
> wd0: 16-sector PIO, LBA48, 152627MB, 312581808 sectors
> wd0(pciide1:0:0): using PIO mode 4, Ultra-DMA mode 6
> wd1 at pciide1 channel 1 drive 0: <ST3000VX000-9YW166>
> wd1: 16-sector PIO, LBA48, 2861588MB, 5860533168 sectors
> wd1(pciide1:1:0): using PIO mode 4, Ultra-DMA mode 6
> ichiic0 at pci0 dev 31 function 3 "Intel 6321ESB SMBus" rev 0x09: apic 8 int 21
> iic0 at ichiic0
> usb1 at uhci0: USB revision 1.0
> uhub1 at usb1 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb2 at uhci1: USB revision 1.0
> uhub2 at usb2 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb3 at uhci2: USB revision 1.0
> uhub3 at usb3 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb4 at uhci3: USB revision 1.0
> uhub4 at usb4 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> isa0 at pcib0
> isadma0 at isa0
> pcppi0 at isa0 port 0x61
> spkr0 at pcppi0
> uhub5 at uhub0 port 2 "Apple, Inc. Keyboard Hub" rev 2.00/96.15 addr 2
> uhidev0 at uhub5 port 2 configuration 1 interface 0 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
> uhidev0: iclass 3/1
> ukbd0 at uhidev0: 8 variable keys, 5 key codes, country code 33
> wskbd0 at ukbd0: console keyboard, using wsdisplay0
> uhidev1 at uhub5 port 2 configuration 1 interface 1 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
> uhidev1: iclass 3/0
> uhid0 at uhidev1: input=1, output=0, feature=0
> uhidev2 at uhub5 port 3 configuration 1 interface 0 "Primax Electronics Apple Optical USB Mouse" rev 1.10/1.10 addr 4
> uhidev2: iclass 3/1
> ums0 at uhidev2: 4 buttons, Z and W dir
> wsmouse0 at ums0 mux 0
> rum0 at uhub0 port 5 "Cisco-Linksys Compact Wireless-G USB Adapter" rev 2.00/0.01 addr 5
> rum0: MAC/BBP RT2573 (rev 0x2573a), RF RT2528, address 00:18:39:1c:22:7a
> vscsi0 at root
> scsibus2 at vscsi0: 256 targets
> softraid0 at root
> scsibus3 at softraid0: 256 targets
> root on wd0a (1b370bc9a7444b9f.a) swap on wd0b dump on wd0b
>

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
In reply to this post by Kenneth Westerback
On Sun, May 18, 2014 at 07:37:26AM -0400, Kenneth Westerback wrote:

> On 18 May 2014 05:37, Gilles Chehade <[hidden email]> wrote:
> > On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
> >> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
> >>
> >> > Just upgraded to -current from my local mirror. Was previously working
> >> > with a recent-ish -current (late April or early May)
> >>
> >> By "current" I meant "snapshot" sorry if that caused any confusion.
> >>
> >
> > I'll have a look at this, thanks
> >
> > --
> > Gilles Chehade
> >
> > https://www.poolp.org                                          @poolpOrg
> >
>
> I've found that if there is anything processed from the offline queue
> then smtpd stops immediately. If I remove all offline files then smtpd
> starts up fine but as soon as I send an email (using mutt) it exits.
>

strange :-/

I am running an opensmtpd -current on top of a snapshot one week-old and
no matter what I try I can't reproduce with the default configuration.

I just tried to enqueue offline mails after reading your mail:

$ echo test | mail gilles && sudo smtpd -dv
debug: init ssl-tree
info: OpenSMTPD 5.4.3 starting
[...]
debug: smtpd: scanning offline queue...
debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400413673.u5SMhBkRCh
debug: smtpd: offline scanning done
debug: smtp: new client on listener: 0x13b5a7e68100
smtp-in: New session 4af9e1d27b2d9096 from host 0@localhost [local]
debug: 0x13b7b4d9c000: end of message, msgflags=0x0000
smtp-in: Accepted message 1540150a on session 4af9e1d27b2d9096: from=<[hidden email]>, to=<[hidden email]>, size=169, ndest=1, proto=ESMTP
debug: scheduler: evp:1540150aac11ccde scheduled (mda)
smtp-in: Closing session 4af9e1d27b2d9096
debug: smtp: 0x13b7b4d9c000: deleting session: done
mda: new user 4af9e1d38d21e391 for "<getpwnam>:gilles"
debug: lka: userinfo <getpwnam>:gilles
debug: mda: new session 4af9e1d4e7c887ea for user "<getpwnam>:gilles" evpid 1540150aac11ccde
debug: mda: no more envelope for "<getpwnam>:gilles"
debug: mda: got message fd 4 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
debug: mda: querying mda fd for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
debug: smtpd: forking mda for session 4af9e1d4e7c887ea: "/home/gilles/Maildir" as gilles
debug: mda: got mda fd 5 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
debug: mda: end-of-file for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
debug: mda: all data sent for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
debug: smtpd: mda process done for session 4af9e1d4e7c887ea: exited okay
delivery: Ok for 1540150aac11ccde: from=<[hidden email]>, to=<[hidden email]>, user=gilles, method=maildir, delay=0s, stat=Delivered
debug: mda: session 4af9e1d4e7c887ea done
debug: mda: user "gilles" becomes runnable
debug: mda: all done for user "<getpwnam>:gilles"


>
> When I had four files in offline, then there were 4 "New session"
> lines before the 'warn' messages start.
>
> All on -current as of yesterday, including mutt.
>

I have no idea right now what could cause that but I'm looking into it
and hopefully I can find a way to crash this afternoon.


--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Kenneth Westerback
On 18 May 2014 07:52, Gilles Chehade <[hidden email]> wrote:

> On Sun, May 18, 2014 at 07:37:26AM -0400, Kenneth Westerback wrote:
>> On 18 May 2014 05:37, Gilles Chehade <[hidden email]> wrote:
>> > On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
>> >> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
>> >>
>> >> > Just upgraded to -current from my local mirror. Was previously working
>> >> > with a recent-ish -current (late April or early May)
>> >>
>> >> By "current" I meant "snapshot" sorry if that caused any confusion.
>> >>
>> >
>> > I'll have a look at this, thanks
>> >
>> > --
>> > Gilles Chehade
>> >
>> > https://www.poolp.org                                          @poolpOrg
>> >
>>
>> I've found that if there is anything processed from the offline queue
>> then smtpd stops immediately. If I remove all offline files then smtpd
>> starts up fine but as soon as I send an email (using mutt) it exits.
>>
>
> strange :-/
>
> I am running an opensmtpd -current on top of a snapshot one week-old and
> no matter what I try I can't reproduce with the default configuration.

Oddly enough, I upgraded a working machine that had a week old
snapshot to -current and that's when the problem surfaced. :-)

I suspect some LibreSSL fall out, but only because of the churn there,
not because of anything I saw go by that I can link to the issue.

.... Ken

>
> I just tried to enqueue offline mails after reading your mail:
>
> $ echo test | mail gilles && sudo smtpd -dv
> debug: init ssl-tree
> info: OpenSMTPD 5.4.3 starting
> [...]
> debug: smtpd: scanning offline queue...
> debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400413673.u5SMhBkRCh
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0x13b5a7e68100
> smtp-in: New session 4af9e1d27b2d9096 from host 0@localhost [local]
> debug: 0x13b7b4d9c000: end of message, msgflags=0x0000
> smtp-in: Accepted message 1540150a on session 4af9e1d27b2d9096: from=<[hidden email]>, to=<[hidden email]>, size=169, ndest=1, proto=ESMTP
> debug: scheduler: evp:1540150aac11ccde scheduled (mda)
> smtp-in: Closing session 4af9e1d27b2d9096
> debug: smtp: 0x13b7b4d9c000: deleting session: done
> mda: new user 4af9e1d38d21e391 for "<getpwnam>:gilles"
> debug: lka: userinfo <getpwnam>:gilles
> debug: mda: new session 4af9e1d4e7c887ea for user "<getpwnam>:gilles" evpid 1540150aac11ccde
> debug: mda: no more envelope for "<getpwnam>:gilles"
> debug: mda: got message fd 4 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> debug: mda: querying mda fd for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> debug: smtpd: forking mda for session 4af9e1d4e7c887ea: "/home/gilles/Maildir" as gilles
> debug: mda: got mda fd 5 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> debug: mda: end-of-file for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> debug: mda: all data sent for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> debug: smtpd: mda process done for session 4af9e1d4e7c887ea: exited okay
> delivery: Ok for 1540150aac11ccde: from=<[hidden email]>, to=<[hidden email]>, user=gilles, method=maildir, delay=0s, stat=Delivered
> debug: mda: session 4af9e1d4e7c887ea done
> debug: mda: user "gilles" becomes runnable
> debug: mda: all done for user "<getpwnam>:gilles"
>
>
>>
>> When I had four files in offline, then there were 4 "New session"
>> lines before the 'warn' messages start.
>>
>> All on -current as of yesterday, including mutt.
>>
>
> I have no idea right now what could cause that but I'm looking into it
> and hopefully I can find a way to crash this afternoon.
>
>
> --
> Gilles Chehade
>
> https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
On Sun, May 18, 2014 at 08:03:59AM -0400, Kenneth Westerback wrote:

> On 18 May 2014 07:52, Gilles Chehade <[hidden email]> wrote:
> > On Sun, May 18, 2014 at 07:37:26AM -0400, Kenneth Westerback wrote:
> >> On 18 May 2014 05:37, Gilles Chehade <[hidden email]> wrote:
> >> > On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
> >> >> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
> >> >>
> >> >> > Just upgraded to -current from my local mirror. Was previously working
> >> >> > with a recent-ish -current (late April or early May)
> >> >>
> >> >> By "current" I meant "snapshot" sorry if that caused any confusion.
> >> >>
> >> >
> >> > I'll have a look at this, thanks
> >> >
> >> > --
> >> > Gilles Chehade
> >> >
> >> > https://www.poolp.org                                          @poolpOrg
> >> >
> >>
> >> I've found that if there is anything processed from the offline queue
> >> then smtpd stops immediately. If I remove all offline files then smtpd
> >> starts up fine but as soon as I send an email (using mutt) it exits.
> >>
> >
> > strange :-/
> >
> > I am running an opensmtpd -current on top of a snapshot one week-old and
> > no matter what I try I can't reproduce with the default configuration.
>
> Oddly enough, I upgraded a working machine that had a week old
> snapshot to -current and that's when the problem surfaced. :-)
>
> I suspect some LibreSSL fall out, but only because of the churn there,
> not because of anything I saw go by that I can link to the issue.
>

That would be strange, offline enqueuing doesn't hit any SSL code path
as far as I can think with my under-caffeinated mind :-p

I'll upgrade to -current this afternoon and see how it goes


> .... Ken
>
> >
> > I just tried to enqueue offline mails after reading your mail:
> >
> > $ echo test | mail gilles && sudo smtpd -dv
> > debug: init ssl-tree
> > info: OpenSMTPD 5.4.3 starting
> > [...]
> > debug: smtpd: scanning offline queue...
> > debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400413673.u5SMhBkRCh
> > debug: smtpd: offline scanning done
> > debug: smtp: new client on listener: 0x13b5a7e68100
> > smtp-in: New session 4af9e1d27b2d9096 from host 0@localhost [local]
> > debug: 0x13b7b4d9c000: end of message, msgflags=0x0000
> > smtp-in: Accepted message 1540150a on session 4af9e1d27b2d9096: from=<[hidden email]>, to=<[hidden email]>, size=169, ndest=1, proto=ESMTP
> > debug: scheduler: evp:1540150aac11ccde scheduled (mda)
> > smtp-in: Closing session 4af9e1d27b2d9096
> > debug: smtp: 0x13b7b4d9c000: deleting session: done
> > mda: new user 4af9e1d38d21e391 for "<getpwnam>:gilles"
> > debug: lka: userinfo <getpwnam>:gilles
> > debug: mda: new session 4af9e1d4e7c887ea for user "<getpwnam>:gilles" evpid 1540150aac11ccde
> > debug: mda: no more envelope for "<getpwnam>:gilles"
> > debug: mda: got message fd 4 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> > debug: mda: querying mda fd for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> > debug: smtpd: forking mda for session 4af9e1d4e7c887ea: "/home/gilles/Maildir" as gilles
> > debug: mda: got mda fd 5 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> > debug: mda: end-of-file for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> > debug: mda: all data sent for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
> > debug: smtpd: mda process done for session 4af9e1d4e7c887ea: exited okay
> > delivery: Ok for 1540150aac11ccde: from=<[hidden email]>, to=<[hidden email]>, user=gilles, method=maildir, delay=0s, stat=Delivered
> > debug: mda: session 4af9e1d4e7c887ea done
> > debug: mda: user "gilles" becomes runnable
> > debug: mda: all done for user "<getpwnam>:gilles"
> >
> >
> >>
> >> When I had four files in offline, then there were 4 "New session"
> >> lines before the 'warn' messages start.
> >>
> >> All on -current as of yesterday, including mutt.
> >>
> >
> > I have no idea right now what could cause that but I'm looking into it
> > and hopefully I can find a way to crash this afternoon.
> >
> >
> > --
> > Gilles Chehade
> >
> > https://www.poolp.org                                          @poolpOrg
>

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Norman Golisz-3
In reply to this post by Gilles Chehade-7
Hi Gilles,

On Sun May 18 2014 13:45, Gilles Chehade wrote:
> can you share your configuration file ?
>
> i'm unable to reproduce no matter what i try :-/

I'm also able to reproduce this crash:

$ echo test | mail norman && sudo smtpd -dv

debug: init ssl-tree
info: OpenSMTPD 5.4.3 starting
debug: bounce warning after 4h
debug: using "fs" queue backend
debug: using "ramqueue" scheduler backend
debug: using "ram" stat backend
info: startup [debug mode]
debug: init ssl-tree
debug: parent_send_config_ruleset: reloading
debug: parent_send_config: configuring pony process
debug: parent_send_config: configuring ca process
debug: init private ssl-tree
debug: queue: done loading queue into scheduler
debug: ca_engine_init: using RSAX engine support
debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
debug: smtp: will accept at most 501 clients
debug: smtpd: scanning offline queue...
debug: smtpd: enqueueing offline message
/var/spool/smtpd/offline/1400440122.uj4xYO8YaC
debug: smtpd: offline scanning done
debug: smtp: new client on listener: 0x14804c2680c0
smtp-in: New session 80dc422d384e8c2d from host 1000@localhost [local]
warn: parent -> pony: pipe closed
warn: queue -> pony: pipe closed
warn: ca -> pony: pipe closed
warn: control -> pony: pipe closed
warn: scheduler -> queue: pipe closed
warn: lka -> pony: pipe closed


smtpd.conf:

listen on lo0

table aliases db:/etc/mail/aliases.db

table secrets { me => me.local:whoohoo}

accept for local alias <aliases> deliver to maildir
accept for any relay via tls+auth://[hidden email]:587 auth <secrets>


dmesg:

OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
    [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
real mem = 4166717440 (3973MB)
avail mem = 4047036416 (3859MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe0010 (80 entries)
bios0: vendor LENOVO version "7UET94WW (3.24 )" date 10/17/2012
bios0: LENOVO 6475BE3
acpi0 at bios0: rev 2
acpi0: sleep states S0 S3 S4 S5
acpi0: tables DSDT FACP SSDT ECDT APIC MCFG HPET SLIC BOOT ASF! SSDT TCPA DMAR SSDT SSDT SSDT
acpi0: wakeup devices LID_(S3) SLPB(S3) UART(S3) IGBE(S4) EXP0(S4) EXP1(S4) EXP2(S4) EXP3(S4) EXP4(S4) PCI1(S4) USB0(S3) USB3(S3) USB5(S3) EHC0(S3) EHC1(S3) HDEF(S4)
acpitimer0 at acpi0: 3579545 Hz, 24 bits
acpiec0 at acpi0
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel(R) Core(TM)2 Duo CPU P8400 @ 2.26GHz, 2261.31 MHz
cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,NXE,LONG,LAHF,PERF
cpu0: 3MB 64b/line 8-way L2 cache
cpu0: smt 0, core 0, package 0
mtrr: Pentium Pro MTRR support, 7 var ranges, 88 fixed ranges
cpu0: apic clock running at 266MHz
cpu0: mwait min=64, max=64, C-substates=0.2.2.2.2, IBE
cpu1 at mainbus0: apid 1 (application processor)
cpu1: Intel(R) Core(TM)2 Duo CPU P8400 @ 2.26GHz, 2261.01 MHz
cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,NXE,LONG,LAHF,PERF
cpu1: 3MB 64b/line 8-way L2 cache
cpu1: smt 0, core 1, package 0
ioapic0 at mainbus0: apid 1 pa 0xfec00000, version 20, 24 pins
ioapic0: misconfigured as apic 2, remapped to apid 1
acpimcfg0 at acpi0 addr 0xe0000000, bus 0-63
acpihpet0 at acpi0: 14318179 Hz
acpiprt0 at acpi0: bus 0 (PCI0)
acpiprt1 at acpi0: bus -1 (AGP_)
acpiprt2 at acpi0: bus 2 (EXP0)
acpiprt3 at acpi0: bus 3 (EXP1)
acpiprt4 at acpi0: bus -1 (EXP2)
acpiprt5 at acpi0: bus 5 (EXP3)
acpiprt6 at acpi0: bus 13 (EXP4)
acpiprt7 at acpi0: bus 21 (PCI1)
acpicpu0 at acpi0: C3, C2, C1, PSS
acpicpu1 at acpi0: C3, C2, C1, PSS
acpipwrres0 at acpi0: PUBS, resource for USB0, USB3, USB5, EHC0, EHC1
acpitz0 at acpi0: critical temperature is 127 degC
acpitz1 at acpi0: critical temperature is 100 degC
acpibtn0 at acpi0: LID_
acpibtn1 at acpi0: SLPB
acpibat0 at acpi0: BAT0 model "42T5264" serial  3499 type LION oem "Panasonic"
acpibat1 at acpi0: BAT1 not present
acpiac0 at acpi0: AC unit online
acpithinkpad0 at acpi0
acpidock0 at acpi0: GDCK docked (15)
cpu0: Enhanced SpeedStep 2261 MHz: speeds: 2267, 2266, 1600, 800 MHz
pci0 at mainbus0 bus 0
pchb0 at pci0 dev 0 function 0 "Intel GM45 Host" rev 0x07
vga1 at pci0 dev 2 function 0 "Intel GM45 Video" rev 0x07
intagp0 at vga1
agp0 at intagp0: aperture at 0xd0000000, size 0x10000000
inteldrm0 at vga1
drm0 at inteldrm0
inteldrm0: 1440x900
wsdisplay0 at vga1 mux 1: console (std, vt100 emulation)
wsdisplay0: screen 1-5 added (std, vt100 emulation)
"Intel GM45 Video" rev 0x07 at pci0 dev 2 function 1 not configured
"Intel GM45 HECI" rev 0x07 at pci0 dev 3 function 0 not configured
em0 at pci0 dev 25 function 0 "Intel ICH9 IGP M AMT" rev 0x03: msi, address 00:1c:25:95:39:e7
uhci0 at pci0 dev 26 function 0 "Intel 82801I USB" rev 0x03: apic 1 int 20
uhci1 at pci0 dev 26 function 1 "Intel 82801I USB" rev 0x03: apic 1 int 21
uhci2 at pci0 dev 26 function 2 "Intel 82801I USB" rev 0x03: apic 1 int 22
ehci0 at pci0 dev 26 function 7 "Intel 82801I USB" rev 0x03: apic 1 int 23
usb0 at ehci0: USB revision 2.0
uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
azalia0 at pci0 dev 27 function 0 "Intel 82801I HD Audio" rev 0x03: msi
azalia0: codecs: Conexant CX20561
audio0 at azalia0
ppb0 at pci0 dev 28 function 0 "Intel 82801I PCIE" rev 0x03: msi
pci1 at ppb0 bus 2
ppb1 at pci0 dev 28 function 1 "Intel 82801I PCIE" rev 0x03: msi
pci2 at ppb1 bus 3
iwn0 at pci2 dev 0 function 0 "Intel WiFi Link 5300" rev 0x00: msi, MIMO 3T3R, MoW, address 00:16:ea:b3:62:e8
ppb2 at pci0 dev 28 function 3 "Intel 82801I PCIE" rev 0x03: msi
pci3 at ppb2 bus 5
ppb3 at pci0 dev 28 function 4 "Intel 82801I PCIE" rev 0x03: msi
pci4 at ppb3 bus 13
uhci3 at pci0 dev 29 function 0 "Intel 82801I USB" rev 0x03: apic 1 int 16
uhci4 at pci0 dev 29 function 1 "Intel 82801I USB" rev 0x03: apic 1 int 17
uhci5 at pci0 dev 29 function 2 "Intel 82801I USB" rev 0x03: apic 1 int 18
ehci1 at pci0 dev 29 function 7 "Intel 82801I USB" rev 0x03: apic 1 int 19
usb1 at ehci1: USB revision 2.0
uhub1 at usb1 "Intel EHCI root hub" rev 2.00/1.00 addr 1
ppb4 at pci0 dev 30 function 0 "Intel 82801BAM Hub-to-PCI" rev 0x93
pci5 at ppb4 bus 21
cbb0 at pci5 dev 0 function 0 "Ricoh 5C476 CardBus" rev 0xba: apic 1 int 16
cardslot0 at cbb0 slot 0 flags 0
cardbus0 at cardslot0: bus 22 device 0 cacheline 0x10, lattimer 0xb0
pcmcia0 at cardslot0
pcib0 at pci0 dev 31 function 0 "Intel 82801IEM LPC" rev 0x03
ahci0 at pci0 dev 31 function 2 "Intel 82801I AHCI" rev 0x03: msi, AHCI 1.2
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 0 lun 0: <ATA, HGST HTS725050A7, GH2O> SCSI3 0/direct fixed naa.5000cca76cc03cc3
sd0: 476940MB, 512 bytes/sector, 976773168 sectors
cd0 at scsibus1 targ 1 lun 0: <HL-DT-ST, DVDRAM GSA-U20N, HX10> ATAPI 5/cdrom removable
ichiic0 at pci0 dev 31 function 3 "Intel 82801I SMBus" rev 0x03: apic 1 int 23
iic0 at ichiic0
usb2 at uhci0: USB revision 1.0
uhub2 at usb2 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb3 at uhci1: USB revision 1.0
uhub3 at usb3 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb4 at uhci2: USB revision 1.0
uhub4 at usb4 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb5 at uhci3: USB revision 1.0
uhub5 at usb5 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb6 at uhci4: USB revision 1.0
uhub6 at usb6 "Intel UHCI root hub" rev 1.00/1.00 addr 1
usb7 at uhci5: USB revision 1.0
uhub7 at usb7 "Intel UHCI root hub" rev 1.00/1.00 addr 1
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
pckbc0 at isa0 port 0x60/5
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pms0 at pckbc0 (aux slot)
pckbc0: using irq 12 for aux slot
wsmouse0 at pms0 mux 0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
aps0 at isa0 port 0x1600/31
umass0 at uhub0 port 1 configuration 1 interface 0 "JetFlash Mass Storage Device" rev 2.00/11.00 addr 2
umass0: using SCSI over Bulk-Only
scsibus2 at umass0: 2 targets, initiator 0
sd1 at scsibus2 targ 1 lun 0: <JetFlash, Transcend 8GB, 1100> SCSI2 0/direct removable serial.856410004SXZH4F3E24H
sd1: 7728MB, 512 bytes/sector, 15826944 sectors
uhub8 at uhub0 port 5 "IBM product 0x4485" rev 2.00/0.01 addr 3
uhidev0 at uhub8 port 1 configuration 1 interface 0 "Logitech USB-PS/2 Optical Mouse" rev 2.00/18.00 addr 4
uhidev0: iclass 3/1
ums0 at uhidev0: 6 buttons, Z dir
wsmouse1 at ums0 mux 0
umodem0 at uhub1 port 4 configuration 1 interface 1 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
umodem0: data interface 2, has CM over data, has break
umodem0: status change notification available
ucom0 at umodem0
umodem1 at uhub1 port 4 configuration 1 interface 3 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
umodem1: data interface 4, has CM over data, has break
umodem1: status change notification available
ucom1 at umodem1
cdce0 at uhub1 port 4 configuration 1 interface 7 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
cdce0: address 02:80:37:ec:02:00
ugen0 at uhub1 port 4 configuration 1 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
vscsi0 at root
scsibus3 at vscsi0: 256 targets
softraid0 at root
scsibus4 at softraid0: 256 targets
sd2 at scsibus4 targ 1 lun 0: <OPENBSD, SR CRYPTO, 005> SCSI2 0/direct fixed
sd2: 250876MB, 512 bytes/sector, 513794592 sectors
root on sd2a (d2946bed46ef4222.a) swap on sd2b dump on sd2b


Please let me know if I can provide more info to you.

Norman

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

David Coppa
In reply to this post by Kenneth Westerback
On Sun, May 18, 2014 at 2:03 PM, Kenneth Westerback
<[hidden email]> wrote:

> On 18 May 2014 07:52, Gilles Chehade <[hidden email]> wrote:
>> On Sun, May 18, 2014 at 07:37:26AM -0400, Kenneth Westerback wrote:
>>> On 18 May 2014 05:37, Gilles Chehade <[hidden email]> wrote:
>>> > On Sat, May 17, 2014 at 10:40:13PM -0400, Allan Streib wrote:
>>> >> On Sat, May 17, 2014, at 05:30 PM, Allan Streib wrote:
>>> >>
>>> >> > Just upgraded to -current from my local mirror. Was previously working
>>> >> > with a recent-ish -current (late April or early May)
>>> >>
>>> >> By "current" I meant "snapshot" sorry if that caused any confusion.
>>> >>
>>> >
>>> > I'll have a look at this, thanks
>>> >
>>> > --
>>> > Gilles Chehade
>>> >
>>> > https://www.poolp.org                                          @poolpOrg
>>> >
>>>
>>> I've found that if there is anything processed from the offline queue
>>> then smtpd stops immediately. If I remove all offline files then smtpd
>>> starts up fine but as soon as I send an email (using mutt) it exits.
>>>
>>
>> strange :-/
>>
>> I am running an opensmtpd -current on top of a snapshot one week-old and
>> no matter what I try I can't reproduce with the default configuration.
>
> Oddly enough, I upgraded a working machine that had a week old
> snapshot to -current and that's when the problem surfaced. :-)
>
> I suspect some LibreSSL fall out, but only because of the churn there,
> not because of anything I saw go by that I can link to the issue.
>
> .... Ken
>
>>
>> I just tried to enqueue offline mails after reading your mail:
>>
>> $ echo test | mail gilles && sudo smtpd -dv
>> debug: init ssl-tree
>> info: OpenSMTPD 5.4.3 starting
>> [...]
>> debug: smtpd: scanning offline queue...
>> debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400413673.u5SMhBkRCh
>> debug: smtpd: offline scanning done
>> debug: smtp: new client on listener: 0x13b5a7e68100
>> smtp-in: New session 4af9e1d27b2d9096 from host 0@localhost [local]
>> debug: 0x13b7b4d9c000: end of message, msgflags=0x0000
>> smtp-in: Accepted message 1540150a on session 4af9e1d27b2d9096: from=<[hidden email]>, to=<[hidden email]>, size=169, ndest=1, proto=ESMTP
>> debug: scheduler: evp:1540150aac11ccde scheduled (mda)
>> smtp-in: Closing session 4af9e1d27b2d9096
>> debug: smtp: 0x13b7b4d9c000: deleting session: done
>> mda: new user 4af9e1d38d21e391 for "<getpwnam>:gilles"
>> debug: lka: userinfo <getpwnam>:gilles
>> debug: mda: new session 4af9e1d4e7c887ea for user "<getpwnam>:gilles" evpid 1540150aac11ccde
>> debug: mda: no more envelope for "<getpwnam>:gilles"
>> debug: mda: got message fd 4 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
>> debug: mda: querying mda fd for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
>> debug: smtpd: forking mda for session 4af9e1d4e7c887ea: "/home/gilles/Maildir" as gilles
>> debug: mda: got mda fd 5 for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
>> debug: mda: end-of-file for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
>> debug: mda: all data sent for session 4af9e1d4e7c887ea evpid 1540150aac11ccde
>> debug: smtpd: mda process done for session 4af9e1d4e7c887ea: exited okay
>> delivery: Ok for 1540150aac11ccde: from=<[hidden email]>, to=<[hidden email]>, user=gilles, method=maildir, delay=0s, stat=Delivered
>> debug: mda: session 4af9e1d4e7c887ea done
>> debug: mda: user "gilles" becomes runnable
>> debug: mda: all done for user "<getpwnam>:gilles"
>>
>>
>>>
>>> When I had four files in offline, then there were 4 "New session"
>>> lines before the 'warn' messages start.
>>>
>>> All on -current as of yesterday, including mutt.
>>>
>>
>> I have no idea right now what could cause that but I'm looking into it
>> and hopefully I can find a way to crash this afternoon.

Confirmed: same problem here too :(

ciao,
David

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
In reply to this post by Allan Streib
Can you guys update to yesterday's snapshot and confirm that you still experience this issue ?

Two people have told me they no longer experience the crash since yesterday's snapshot, and
neither do I



On Sat, May 17, 2014 at 05:30:25PM -0400, Allan Streib wrote:

> Just upgraded to -current from my local mirror. Was previously working with a recent-ish -current (late April or early May)
>
> In /var/log/maillog:
>
> May 17 17:00:29 fabrik smtpd[8370]: info: OpenSMTPD 5.4.3 starting
> May 17 17:00:29 fabrik smtpd[23758]: info: startup
> May 17 17:00:30 fabrik smtpd[23464]: smtp-in: New session 3c7a8ed5bcd7c87e from host 1000@localhost [local]
> May 17 17:00:30 fabrik smtpd[6061]: warn: ca -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[29180]: warn: control -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[23758]: warn: parent -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[15221]: warn: lka -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[12014]: warn: queue -> pony: pipe closed
> May 17 17:00:30 fabrik smtpd[11724]: warn: scheduler -> control: pipe closed
>
> $ sudo /usr/sbin/smtpd -d -v
> debug: init ssl-tree
> info: OpenSMTPD 5.4.3 starting
> debug: bounce warning after 4h
> debug: using "fs" queue backend
> debug: using "ramqueue" scheduler backend
> debug: using "ram" stat backend
> info: startup [debug mode]
> debug: init ssl-tree
> debug: ca_engine_init: using RSAX engine support
> debug: parent_send_config_ruleset: reloading
> debug: parent_send_config: configuring pony process
> debug: parent_send_config: configuring ca process
> debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
> debug: smtp: will accept at most 501 clients
> debug: init private ssl-tree
> debug: queue: done loading queue into scheduler
> debug: smtpd: scanning offline queue...
> debug: smtpd: enqueueing offline message /var/spool/smtpd/offline/1400360297.gGOeYTNHCB
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0x870670680c0
> smtp-in: New session 196f0b916c2465b1 from host 1000@localhost [local]
> warn: ca -> pony: pipe closed
> warn: lka -> pony: pipe closed
> warn: queue -> pony: pipe closed
> warn: control -> pony: pipe closed
> warn: parent -> pony: pipe closed
> warn: scheduler -> queue: pipe closed
>
> $ dmesg
> OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
>     [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
> real mem = 10715041792 (10218MB)
> avail mem = 10421043200 (9938MB)
> mpath0 at root
> scsibus0 at mpath0: 256 targets
> mainbus0 at root
> bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe00f0 (73 entries)
> bios0: vendor Apple Computer, Inc. version "MP11.88Z.005C.B08.0707021221" date 07/02/07
> bios0: Apple Computer, Inc. MacPro1,1
> acpi0 at bios0: rev 2
> acpi0: sleep states S0 S1 S3 S4 S5
> acpi0: tables DSDT ECDT FACP HPET APIC MCFG SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSDT SSD
> T SSDT SSDT
> acpi0: wakeup devices P2P5(S4) P2P3(S4) ARPT(S4) RP04(S4) UHC1(S3) UHC2(S3) UHC3(S3) UHC4(S3) EHCI(S3) AC9M(S4) EC__(S3) NRP4(S4) SR
> P1(S4) SRP3(S4)
> acpitimer0 at acpi0: 3579545 Hz, 24 bits
> acpiec0 at acpi0
> acpihpet0 at acpi0: 14318179 Hz
> acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
> cpu0 at mainbus0: apid 0 (boot processor)
> cpu0: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.38 MHz
> cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu0: 4MB 64b/line 16-way L2 cache
> cpu0: smt 0, core 0, package 0
> mtrr: Pentium Pro MTRR support, 8 var ranges, 88 fixed ranges
> cpu0: apic clock running at 332MHz
> cpu0: mwait min=64, max=64, C-substates=0.2.0.0.0, IBE
> cpu1 at mainbus0: apid 1 (application processor)
> cpu1: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2660.00 MHz
> cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu1: 4MB 64b/line 16-way L2 cache
> cpu1: smt 0, core 1, package 0
> cpu2 at mainbus0: apid 7 (application processor)
> cpu2: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
> cpu2: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu2: 4MB 64b/line 16-way L2 cache
> cpu2: smt 0, core 1, package 3
> cpu3 at mainbus0: apid 6 (application processor)
> cpu3: Intel(R) Xeon(R) CPU 5150 @ 2.66GHz, 2659.99 MHz
> cpu3: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DT
> ES64,MWAIT,DS-CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA,NXE,LONG,LAHF,PERF
> cpu3: 4MB 64b/line 16-way L2 cache
> cpu3: smt 0, core 0, package 3
> ioapic0 at mainbus0: apid 8 pa 0xfec00000, version 20, 24 pins
> acpimcfg0 at acpi0 addr 0xd0000000, bus 0-255
> acpiprt0 at acpi0: bus 0 (PCI0)
> acpiprt1 at acpi0: bus 1 (P0P1)
> acpiprt2 at acpi0: bus 2 (P1P2)
> acpiprt3 at acpi0: bus 5 (P2P5)
> acpiprt4 at acpi0: bus 3 (P2P3)
> acpiprt5 at acpi0: bus 15 (RP04)
> acpiprt6 at acpi0: bus 16 (PCIB)
> acpiprt7 at acpi0: bus 8 (NRP4)
> acpiprt8 at acpi0: bus 12 (SRP1)
> acpiprt9 at acpi0: bus 14 (SRP3)
> acpicpu0 at acpi0: C1
> acpicpu1 at acpi0: C1
> acpicpu2 at acpi0: C1
> acpicpu3 at acpi0: C1
> acpibtn0 at acpi0: PWRB
> memory map conflict 0xfff90000/0x30000
> pci0 at mainbus0 bus 0
> pchb0 at pci0 dev 0 function 0 "Intel 5000X Host" rev 0x30
> ppb0 at pci0 dev 2 function 0 "Intel 5000 PCIE x8" rev 0x30
> pci1 at ppb0 bus 1
> ppb1 at pci1 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01
> pci2 at ppb1 bus 2
> ppb2 at pci2 dev 0 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
> pci3 at ppb2 bus 3
> ppb3 at pci2 dev 1 function 0 "Intel 6321ESB PCIE" rev 0x01: msi
> pci4 at ppb3 bus 4
> ppb4 at pci2 dev 2 function 0 "Intel 6321ESB PCIE" rev 0x01
> pci5 at ppb4 bus 5
> em0 at pci5 dev 0 function 0 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:94
> em1 at pci5 dev 0 function 1 "Intel 80003ES2" rev 0x01: msi, address 00:17:f2:03:41:95
> "Intel 6321ESB IOxAPIC" rev 0x01 at pci1 dev 0 function 1 not configured
> ppb5 at pci1 dev 0 function 3 "Intel 6321ESB PCIE-PCIX" rev 0x01
> pci6 at ppb5 bus 6
> pchb1 at pci0 dev 3 function 0 "Intel 5000 PCIE" rev 0x30
> ppb6 at pci0 dev 4 function 0 "Intel 5000 PCIE x16" rev 0x30: msi
> pci7 at ppb6 bus 8
> vga1 at pci7 dev 0 function 0 "NVIDIA GeForce 7300 GT" rev 0xa1
> wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation)
> wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
> pchb2 at pci0 dev 5 function 0 "Intel 5000 PCIE" rev 0x30
> pchb3 at pci0 dev 6 function 0 "Intel 5000 PCIE" rev 0x30
> pchb4 at pci0 dev 7 function 0 "Intel 5000 PCIE" rev 0x30
> "Intel I/OAT" rev 0x30 at pci0 dev 8 function 0 not configured
> pchb5 at pci0 dev 16 function 0 "Intel 5000 Error Reporting" rev 0x30
> pchb6 at pci0 dev 16 function 1 "Intel 5000 Error Reporting" rev 0x30
> pchb7 at pci0 dev 16 function 2 "Intel 5000 Error Reporting" rev 0x30
> pchb8 at pci0 dev 17 function 0 "Intel 5000 Reserved" rev 0x30
> pchb9 at pci0 dev 19 function 0 "Intel 5000 Reserved" rev 0x30
> pchb10 at pci0 dev 21 function 0 "Intel 5000 FBD" rev 0x30
> pchb11 at pci0 dev 22 function 0 "Intel 5000 FBD" rev 0x30
> azalia0 at pci0 dev 27 function 0 "Intel 6321ESB HD Audio" rev 0x09: msi
> azalia0: codecs: Realtek ALC885
> audio0 at azalia0
> ppb7 at pci0 dev 28 function 0 "Intel 6321ESB PCIE" rev 0x09: msi
> pci8 at ppb7 bus 12
> ppb8 at pci0 dev 28 function 1 "Intel 6321ESB PCIE" rev 0x09: msi
> pci9 at ppb8 bus 13
> ppb9 at pci0 dev 28 function 2 "Intel 6321ESB PCIE" rev 0x09: msi
> pci10 at ppb9 bus 14
> ppb10 at pci0 dev 28 function 3 "Intel 6321ESB PCIE" rev 0x09: msi
> pci11 at ppb10 bus 15
> uhci0 at pci0 dev 29 function 0 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
> uhci1 at pci0 dev 29 function 1 "Intel 6321ESB USB" rev 0x09: apic 8 int 20
> uhci2 at pci0 dev 29 function 2 "Intel 6321ESB USB" rev 0x09: apic 8 int 21
> uhci3 at pci0 dev 29 function 3 "Intel 6321ESB USB" rev 0x09: apic 8 int 22
> ehci0 at pci0 dev 29 function 7 "Intel 6321ESB USB" rev 0x09: apic 8 int 19
> usb0 at ehci0: USB revision 2.0
> uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
> ppb11 at pci0 dev 30 function 0 "Intel 82801BA Hub-to-PCI" rev 0xd9
> pci12 at ppb11 bus 16
> "TI TSB82AA2 FireWire" rev 0x01 at pci12 dev 11 function 0 not configured
> pcib0 at pci0 dev 31 function 0 "Intel 6321ESB LPC" rev 0x09
> pciide0 at pci0 dev 31 function 1 "Intel 6321ESB IDE" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to nat
> ive-PCI
> pciide0: using apic 8 int 20 for native-PCI interrupt
> atapiscsi0 at pciide0 channel 0 drive 0
> scsibus1 at atapiscsi0: 2 targets
> cd0 at scsibus1 targ 0 lun 0: <PIONEER, DVD-RW DVR-111D, AB09> ATAPI 5/cdrom removable
> cd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 4
> pciide0: channel 1 disabled (no drives)
> pciide1 at pci0 dev 31 function 2 "Intel 6321ESB SATA" rev 0x09: DMA, channel 0 configured to native-PCI, channel 1 configured to na
> tive-PCI
> pciide1: using apic 8 int 21 for native-PCI interrupt
> wd0 at pciide1 channel 0 drive 0: <ST3160812AS P>
> wd0: 16-sector PIO, LBA48, 152627MB, 312581808 sectors
> wd0(pciide1:0:0): using PIO mode 4, Ultra-DMA mode 6
> wd1 at pciide1 channel 1 drive 0: <ST3000VX000-9YW166>
> wd1: 16-sector PIO, LBA48, 2861588MB, 5860533168 sectors
> wd1(pciide1:1:0): using PIO mode 4, Ultra-DMA mode 6
> ichiic0 at pci0 dev 31 function 3 "Intel 6321ESB SMBus" rev 0x09: apic 8 int 21
> iic0 at ichiic0
> usb1 at uhci0: USB revision 1.0
> uhub1 at usb1 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb2 at uhci1: USB revision 1.0
> uhub2 at usb2 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb3 at uhci2: USB revision 1.0
> uhub3 at usb3 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb4 at uhci3: USB revision 1.0
> uhub4 at usb4 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> isa0 at pcib0
> isadma0 at isa0
> pcppi0 at isa0 port 0x61
> spkr0 at pcppi0
> uhub5 at uhub0 port 2 "Apple, Inc. Keyboard Hub" rev 2.00/96.15 addr 2
> uhidev0 at uhub5 port 2 configuration 1 interface 0 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
> uhidev0: iclass 3/1
> ukbd0 at uhidev0: 8 variable keys, 5 key codes, country code 33
> wskbd0 at ukbd0: console keyboard, using wsdisplay0
> uhidev1 at uhub5 port 2 configuration 1 interface 1 "Apple, Inc Apple Keyboard" rev 2.00/0.69 addr 3
> uhidev1: iclass 3/0
> uhid0 at uhidev1: input=1, output=0, feature=0
> uhidev2 at uhub5 port 3 configuration 1 interface 0 "Primax Electronics Apple Optical USB Mouse" rev 1.10/1.10 addr 4
> uhidev2: iclass 3/1
> ums0 at uhidev2: 4 buttons, Z and W dir
> wsmouse0 at ums0 mux 0
> rum0 at uhub0 port 5 "Cisco-Linksys Compact Wireless-G USB Adapter" rev 2.00/0.01 addr 5
> rum0: MAC/BBP RT2573 (rev 0x2573a), RF RT2528, address 00:18:39:1c:22:7a
> vscsi0 at root
> scsibus2 at vscsi0: 256 targets
> softraid0 at root
> scsibus3 at softraid0: 256 targets
> root on wd0a (1b370bc9a7444b9f.a) swap on wd0b dump on wd0b
>

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

David Coppa
On Mon, May 19, 2014 at 3:22 PM, Gilles Chehade <[hidden email]> wrote:
> Can you guys update to yesterday's snapshot and confirm that you still experience this issue ?
>
> Two people have told me they no longer experience the crash since yesterday's snapshot, and
> neither do I

Confirmed again. It's working now.

thanks,
David

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
On Mon, May 19, 2014 at 03:55:20PM +0200, David Coppa wrote:
> On Mon, May 19, 2014 at 3:22 PM, Gilles Chehade <[hidden email]> wrote:
> > Can you guys update to yesterday's snapshot and confirm that you still experience this issue ?
> >
> > Two people have told me they no longer experience the crash since yesterday's snapshot, and
> > neither do I
>
> Confirmed again. It's working now.
>

Just to be clear, I have not changed anything meanwhile.
It has stopped working recently and has resumed working yesterday.

Either we hit a LibreSSL fallout that was fixed meanwhile, or we still
have the bug and as toddf@ pointed out the gcc randomization of local
variables is exposing it ... if we're lucky :-/

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Kenneth Westerback
In reply to this post by Norman Golisz-3
On 18 May 2014 15:19, Norman Golisz <[hidden email]> wrote:

> Hi Gilles,
>
> On Sun May 18 2014 13:45, Gilles Chehade wrote:
>> can you share your configuration file ?
>>
>> i'm unable to reproduce no matter what i try :-/
>
> I'm also able to reproduce this crash:
>
> $ echo test | mail norman && sudo smtpd -dv
>
> debug: init ssl-tree
> info: OpenSMTPD 5.4.3 starting
> debug: bounce warning after 4h
> debug: using "fs" queue backend
> debug: using "ramqueue" scheduler backend
> debug: using "ram" stat backend
> info: startup [debug mode]
> debug: init ssl-tree
> debug: parent_send_config_ruleset: reloading
> debug: parent_send_config: configuring pony process
> debug: parent_send_config: configuring ca process
> debug: init private ssl-tree
> debug: queue: done loading queue into scheduler
> debug: ca_engine_init: using RSAX engine support
> debug: smtp: listen on 127.0.0.1 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x0 pki ""
> debug: smtp: listen on IPv6:::1 port 25 flags 0x0 pki ""
> debug: smtp: will accept at most 501 clients
> debug: smtpd: scanning offline queue...
> debug: smtpd: enqueueing offline message
> /var/spool/smtpd/offline/1400440122.uj4xYO8YaC
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0x14804c2680c0
> smtp-in: New session 80dc422d384e8c2d from host 1000@localhost [local]
> warn: parent -> pony: pipe closed
> warn: queue -> pony: pipe closed
> warn: ca -> pony: pipe closed
> warn: control -> pony: pipe closed
> warn: scheduler -> queue: pipe closed
> warn: lka -> pony: pipe closed
>
>
> smtpd.conf:
>
> listen on lo0
>
> table aliases db:/etc/mail/aliases.db
>
> table secrets { me => me.local:whoohoo}
>
> accept for local alias <aliases> deliver to maildir
> accept for any relay via tls+auth://[hidden email]:587 auth <secrets>
>
>
> dmesg:
>
> OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
>     [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP

Try with something newer. Others (including me) have found today's
snap to work. For as yet unknown reasons.

.... Ken

> real mem = 4166717440 (3973MB)
> avail mem = 4047036416 (3859MB)
> mpath0 at root
> scsibus0 at mpath0: 256 targets
> mainbus0 at root
> bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe0010 (80 entries)
> bios0: vendor LENOVO version "7UET94WW (3.24 )" date 10/17/2012
> bios0: LENOVO 6475BE3
> acpi0 at bios0: rev 2
> acpi0: sleep states S0 S3 S4 S5
> acpi0: tables DSDT FACP SSDT ECDT APIC MCFG HPET SLIC BOOT ASF! SSDT TCPA DMAR SSDT SSDT SSDT
> acpi0: wakeup devices LID_(S3) SLPB(S3) UART(S3) IGBE(S4) EXP0(S4) EXP1(S4) EXP2(S4) EXP3(S4) EXP4(S4) PCI1(S4) USB0(S3) USB3(S3) USB5(S3) EHC0(S3) EHC1(S3) HDEF(S4)
> acpitimer0 at acpi0: 3579545 Hz, 24 bits
> acpiec0 at acpi0
> acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
> cpu0 at mainbus0: apid 0 (boot processor)
> cpu0: Intel(R) Core(TM)2 Duo CPU P8400 @ 2.26GHz, 2261.31 MHz
> cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,NXE,LONG,LAHF,PERF
> cpu0: 3MB 64b/line 8-way L2 cache
> cpu0: smt 0, core 0, package 0
> mtrr: Pentium Pro MTRR support, 7 var ranges, 88 fixed ranges
> cpu0: apic clock running at 266MHz
> cpu0: mwait min=64, max=64, C-substates=0.2.2.2.2, IBE
> cpu1 at mainbus0: apid 1 (application processor)
> cpu1: Intel(R) Core(TM)2 Duo CPU P8400 @ 2.26GHz, 2261.01 MHz
> cpu1: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,NXE,LONG,LAHF,PERF
> cpu1: 3MB 64b/line 8-way L2 cache
> cpu1: smt 0, core 1, package 0
> ioapic0 at mainbus0: apid 1 pa 0xfec00000, version 20, 24 pins
> ioapic0: misconfigured as apic 2, remapped to apid 1
> acpimcfg0 at acpi0 addr 0xe0000000, bus 0-63
> acpihpet0 at acpi0: 14318179 Hz
> acpiprt0 at acpi0: bus 0 (PCI0)
> acpiprt1 at acpi0: bus -1 (AGP_)
> acpiprt2 at acpi0: bus 2 (EXP0)
> acpiprt3 at acpi0: bus 3 (EXP1)
> acpiprt4 at acpi0: bus -1 (EXP2)
> acpiprt5 at acpi0: bus 5 (EXP3)
> acpiprt6 at acpi0: bus 13 (EXP4)
> acpiprt7 at acpi0: bus 21 (PCI1)
> acpicpu0 at acpi0: C3, C2, C1, PSS
> acpicpu1 at acpi0: C3, C2, C1, PSS
> acpipwrres0 at acpi0: PUBS, resource for USB0, USB3, USB5, EHC0, EHC1
> acpitz0 at acpi0: critical temperature is 127 degC
> acpitz1 at acpi0: critical temperature is 100 degC
> acpibtn0 at acpi0: LID_
> acpibtn1 at acpi0: SLPB
> acpibat0 at acpi0: BAT0 model "42T5264" serial  3499 type LION oem "Panasonic"
> acpibat1 at acpi0: BAT1 not present
> acpiac0 at acpi0: AC unit online
> acpithinkpad0 at acpi0
> acpidock0 at acpi0: GDCK docked (15)
> cpu0: Enhanced SpeedStep 2261 MHz: speeds: 2267, 2266, 1600, 800 MHz
> pci0 at mainbus0 bus 0
> pchb0 at pci0 dev 0 function 0 "Intel GM45 Host" rev 0x07
> vga1 at pci0 dev 2 function 0 "Intel GM45 Video" rev 0x07
> intagp0 at vga1
> agp0 at intagp0: aperture at 0xd0000000, size 0x10000000
> inteldrm0 at vga1
> drm0 at inteldrm0
> inteldrm0: 1440x900
> wsdisplay0 at vga1 mux 1: console (std, vt100 emulation)
> wsdisplay0: screen 1-5 added (std, vt100 emulation)
> "Intel GM45 Video" rev 0x07 at pci0 dev 2 function 1 not configured
> "Intel GM45 HECI" rev 0x07 at pci0 dev 3 function 0 not configured
> em0 at pci0 dev 25 function 0 "Intel ICH9 IGP M AMT" rev 0x03: msi, address 00:1c:25:95:39:e7
> uhci0 at pci0 dev 26 function 0 "Intel 82801I USB" rev 0x03: apic 1 int 20
> uhci1 at pci0 dev 26 function 1 "Intel 82801I USB" rev 0x03: apic 1 int 21
> uhci2 at pci0 dev 26 function 2 "Intel 82801I USB" rev 0x03: apic 1 int 22
> ehci0 at pci0 dev 26 function 7 "Intel 82801I USB" rev 0x03: apic 1 int 23
> usb0 at ehci0: USB revision 2.0
> uhub0 at usb0 "Intel EHCI root hub" rev 2.00/1.00 addr 1
> azalia0 at pci0 dev 27 function 0 "Intel 82801I HD Audio" rev 0x03: msi
> azalia0: codecs: Conexant CX20561
> audio0 at azalia0
> ppb0 at pci0 dev 28 function 0 "Intel 82801I PCIE" rev 0x03: msi
> pci1 at ppb0 bus 2
> ppb1 at pci0 dev 28 function 1 "Intel 82801I PCIE" rev 0x03: msi
> pci2 at ppb1 bus 3
> iwn0 at pci2 dev 0 function 0 "Intel WiFi Link 5300" rev 0x00: msi, MIMO 3T3R, MoW, address 00:16:ea:b3:62:e8
> ppb2 at pci0 dev 28 function 3 "Intel 82801I PCIE" rev 0x03: msi
> pci3 at ppb2 bus 5
> ppb3 at pci0 dev 28 function 4 "Intel 82801I PCIE" rev 0x03: msi
> pci4 at ppb3 bus 13
> uhci3 at pci0 dev 29 function 0 "Intel 82801I USB" rev 0x03: apic 1 int 16
> uhci4 at pci0 dev 29 function 1 "Intel 82801I USB" rev 0x03: apic 1 int 17
> uhci5 at pci0 dev 29 function 2 "Intel 82801I USB" rev 0x03: apic 1 int 18
> ehci1 at pci0 dev 29 function 7 "Intel 82801I USB" rev 0x03: apic 1 int 19
> usb1 at ehci1: USB revision 2.0
> uhub1 at usb1 "Intel EHCI root hub" rev 2.00/1.00 addr 1
> ppb4 at pci0 dev 30 function 0 "Intel 82801BAM Hub-to-PCI" rev 0x93
> pci5 at ppb4 bus 21
> cbb0 at pci5 dev 0 function 0 "Ricoh 5C476 CardBus" rev 0xba: apic 1 int 16
> cardslot0 at cbb0 slot 0 flags 0
> cardbus0 at cardslot0: bus 22 device 0 cacheline 0x10, lattimer 0xb0
> pcmcia0 at cardslot0
> pcib0 at pci0 dev 31 function 0 "Intel 82801IEM LPC" rev 0x03
> ahci0 at pci0 dev 31 function 2 "Intel 82801I AHCI" rev 0x03: msi, AHCI 1.2
> scsibus1 at ahci0: 32 targets
> sd0 at scsibus1 targ 0 lun 0: <ATA, HGST HTS725050A7, GH2O> SCSI3 0/direct fixed naa.5000cca76cc03cc3
> sd0: 476940MB, 512 bytes/sector, 976773168 sectors
> cd0 at scsibus1 targ 1 lun 0: <HL-DT-ST, DVDRAM GSA-U20N, HX10> ATAPI 5/cdrom removable
> ichiic0 at pci0 dev 31 function 3 "Intel 82801I SMBus" rev 0x03: apic 1 int 23
> iic0 at ichiic0
> usb2 at uhci0: USB revision 1.0
> uhub2 at usb2 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb3 at uhci1: USB revision 1.0
> uhub3 at usb3 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb4 at uhci2: USB revision 1.0
> uhub4 at usb4 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb5 at uhci3: USB revision 1.0
> uhub5 at usb5 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb6 at uhci4: USB revision 1.0
> uhub6 at usb6 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> usb7 at uhci5: USB revision 1.0
> uhub7 at usb7 "Intel UHCI root hub" rev 1.00/1.00 addr 1
> isa0 at pcib0
> isadma0 at isa0
> com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
> pckbc0 at isa0 port 0x60/5
> pckbd0 at pckbc0 (kbd slot)
> pckbc0: using irq 1 for kbd slot
> wskbd0 at pckbd0: console keyboard, using wsdisplay0
> pms0 at pckbc0 (aux slot)
> pckbc0: using irq 12 for aux slot
> wsmouse0 at pms0 mux 0
> pcppi0 at isa0 port 0x61
> spkr0 at pcppi0
> aps0 at isa0 port 0x1600/31
> umass0 at uhub0 port 1 configuration 1 interface 0 "JetFlash Mass Storage Device" rev 2.00/11.00 addr 2
> umass0: using SCSI over Bulk-Only
> scsibus2 at umass0: 2 targets, initiator 0
> sd1 at scsibus2 targ 1 lun 0: <JetFlash, Transcend 8GB, 1100> SCSI2 0/direct removable serial.856410004SXZH4F3E24H
> sd1: 7728MB, 512 bytes/sector, 15826944 sectors
> uhub8 at uhub0 port 5 "IBM product 0x4485" rev 2.00/0.01 addr 3
> uhidev0 at uhub8 port 1 configuration 1 interface 0 "Logitech USB-PS/2 Optical Mouse" rev 2.00/18.00 addr 4
> uhidev0: iclass 3/1
> ums0 at uhidev0: 6 buttons, Z dir
> wsmouse1 at ums0 mux 0
> umodem0 at uhub1 port 4 configuration 1 interface 1 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
> umodem0: data interface 2, has CM over data, has break
> umodem0: status change notification available
> ucom0 at umodem0
> umodem1 at uhub1 port 4 configuration 1 interface 3 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
> umodem1: data interface 4, has CM over data, has break
> umodem1: status change notification available
> ucom1 at umodem1
> cdce0 at uhub1 port 4 configuration 1 interface 7 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
> cdce0: address 02:80:37:ec:02:00
> ugen0 at uhub1 port 4 configuration 1 "Ericsson Ericsson F3507g Mobile Broadband Minicard Composite Device" rev 2.00/0.00 addr 2
> vscsi0 at root
> scsibus3 at vscsi0: 256 targets
> softraid0 at root
> scsibus4 at softraid0: 256 targets
> sd2 at scsibus4 targ 1 lun 0: <OPENBSD, SR CRYPTO, 005> SCSI2 0/direct fixed
> sd2: 250876MB, 512 bytes/sector, 513794592 sectors
> root on sd2a (d2946bed46ef4222.a) swap on sd2b dump on sd2b
>
>
> Please let me know if I can provide more info to you.
>
> Norman

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Philip Guenther-2
On Mon, May 19, 2014 at 11:22 AM, Kenneth Westerback
<[hidden email]>wrote:

> On 18 May 2014 15:19, Norman Golisz <[hidden email]> wrote:
> ...
> > smtp-in: New session 80dc422d384e8c2d from host 1000@localhost [local]
> > warn: parent -> pony: pipe closed
> > warn: queue -> pony: pipe closed
> ...
> > OpenBSD 5.5-current (GENERIC.MP) #132: Fri May 16 10:26:11 MDT 2014
> >     [hidden email]:/usr/src/sys/arch/amd64/compile/GENERIC.MP
>
> Try with something newer. Others (including me) have found today's
> snap to work. For as yet unknown reasons.
>

Actually, before doing that, you can try capturing a core for the privseped
processes?  Add
   kern.nosuidcoredump=3
to /etc/sysctl.conf, reboot, and mkdir /var/crash/smtpd, then reproduce the
problem and see if there's core file(s) in /var/crash/smtpd/

If this *is* a problem in the code found by the new -fshuffle-stack option,
we should track it down!


Philip Guenther

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Allan Streib
On Mon, May 19, 2014, at 02:27 PM, Philip Guenther wrote:

> Actually, before doing that, you can try capturing a core for the
> privseped processes?  Add
>   kern.nosuidcoredump=3
> to /etc/sysctl.conf, reboot, and mkdir /var/crash/smtpd, then reproduce
> the problem and see if there's core file(s) in /var/crash/smtpd/

Yes, there is a core file. It's been years since I looked at core files, but happy to try if you can give me some direction.

Allan

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Allan Streib
On Mon, May 19, 2014, at 09:06 PM, Allan Streib wrote:

> Yes, there is a core file.

Also, confirmed that the problem is not appearing in snapshot OpenBSD 5.5-current (GENERIC.MP) #136: Mon May 19 09:40:42 MDT 2014

Allan

Reply | Threaded
Open this post in threaded view
|

Re: smtpd stops immediately after starting in -current

Gilles Chehade-7
In reply to this post by Kenneth Westerback
On Mon, May 19, 2014 at 02:22:38PM -0400, Kenneth Westerback wrote:
>
> Try with something newer. Others (including me) have found today's
> snap to work. For as yet unknown reasons.
>
> .... Ken
>

Now that the mysterious crash has been understood, a quick follow-up and
explanation of what happened so my two hours wasted on this werent spent
in vain [0]:

Last week, during a short timeframe, smtpd had a few warnings at compile
time that were caused by a missing header. Charles (chl@) fixed this and
we forgot about it.

Sadly, during that short timeframe a snapshot was made and that snapshot
had the code compiled with the missing header. Some functions which were
supposed to return pointers implicitely started to return integers, with
amazing consequences on machines were sizeof int != sizeof pointer ;-)

Stupid bug, check.


[0] "It is well known, that time once past never returns;
         and that the moment which is lost is lost for ever."
             -- Samuel Johnson

--
Gilles Chehade

https://www.poolp.org                                          @poolpOrg