Jump to content

Mover Cron Job Crashes System

jazzyjeff

By jazzyjeff
in General Support (V5 and Older)

Recommended Posts

jazzyjeff Noob

jazzyjeff

Posted
Posted

The Mover script is crashing my system I grabbed the system log from log window down below. It will also eventually turn into a  Kernel Panic only to be seen via terminal window at the machine  due to responsiveness of the machine.

So what I did as you can see in the log is correct  share settings and then changed the clock to coincide with the cron mover schedule to force the failure. And yes! Its the mover schedule now the question is why? In the mean time I think all the cache drive moves will be removed.

Any ideas??

 

/usr/bin/tail -f /var/log/syslog
Sep 3 09:16:18 unraid avahi-daemon[492]: Files changed, reloading.
Sep 3 09:16:18 unraid avahi-daemon[492]: Service group file /services/smb.service changed, reloading.
Sep 3 09:16:18 unraid emhttp: shcmd (166): /usr/local/sbin/emhttp_event svcs_restarted
Sep 3 09:16:18 unraid emhttp_event: svcs_restarted
Sep 3 09:16:19 unraid avahi-daemon[492]: Service "unraid" (/services/afp.service) successfully established.
Sep 3 09:16:19 unraid avahi-daemon[492]: Service "unraid-SMB" (/services/smb.service) successfully established.
Sep 3 09:16:46 unraid mountd[1366]: refused mount request from 192.168.0.253 for /mnt/user/datastore (/): not exported
Sep 3 09:17:46 unraid last message repeated 2 times
Sep 3 09:19:16 unraid last message repeated 3 times
Sep 3 09:20:46 unraid last message repeated 3 times
Sep 3 09:22:16 unraid last message repeated 3 times
Sep 3 09:22:17 unraid emhttp: shcmd (167): :>/etc/samba/smb-shares.conf
Sep 3 09:22:17 unraid emhttp: shcmd (168): cp /etc/exports- /etc/exports
Sep 3 09:22:17 unraid emhttp: shcmd (169): cp /etc/netatalk/AppleVolumes.default- /etc/netatalk/AppleVolumes.default
Sep 3 09:22:17 unraid emhttp: shcmd (170): echo '"/mnt/user/KidsMovies" -async,no_subtree_check,fsid=109 *(rw,insecure,anongid=100,anonuid=99,all_squash)' >>/etc/exports
Sep 3 09:22:17 unraid emhttp: get_config_idx: fopen /boot/config/shares/_newznab_database.cfg: No such file or directory - assigning defaults
Sep 3 09:22:17 unraid emhttp: Restart SMB...
Sep 3 09:22:17 unraid emhttp: shcmd (171): killall -HUP smbd
Sep 3 09:22:17 unraid emhttp: shcmd (172): ps axc | grep -q rpc.mountd
Sep 3 09:22:17 unraid emhttp: Restart NFS...
Sep 3 09:22:17 unraid emhttp: shcmd (173): exportfs -ra |& logger
Sep 3 09:22:17 unraid emhttp: Restart AFP...
Sep 3 09:22:17 unraid emhttp: shcmd (174): killall -HUP afpd
Sep 3 09:22:17 unraid emhttp: shcmd (175): cp /etc/avahi/services/afp.service- /etc/avahi/services/afp.service
Sep 3 09:22:17 unraid avahi-daemon[492]: Files changed, reloading.
Sep 3 09:22:17 unraid avahi-daemon[492]: Service group file /services/afp.service changed, reloading.
Sep 3 09:22:17 unraid emhttp: shcmd (176): cp /etc/avahi/services/smb.service- /etc/avahi/services/smb.service
Sep 3 09:22:17 unraid avahi-daemon[492]: Files changed, reloading.
Sep 3 09:22:17 unraid avahi-daemon[492]: Service group file /services/smb.service changed, reloading.
Sep 3 09:22:17 unraid emhttp: shcmd (177): /usr/local/sbin/emhttp_event svcs_restarted
Sep 3 09:22:17 unraid emhttp_event: svcs_restarted
Sep 3 09:22:18 unraid avahi-daemon[492]: Service "unraid" (/services/afp.service) successfully established.
Sep 3 09:22:18 unraid avahi-daemon[492]: Service "unraid-SMB" (/services/smb.service) successfully established.
Sep 3 09:22:46 unraid mountd[1366]: refused mount request from 192.168.0.253 for /mnt/user/datastore (/): not exported
Sep 3 09:23:02 unraid emhttp: shcmd (178): :>/etc/samba/smb-shares.conf
Sep 3 09:23:02 unraid emhttp: shcmd (179): cp /etc/exports- /etc/exports
Sep 3 09:23:02 unraid emhttp: shcmd (180): cp /etc/netatalk/AppleVolumes.default- /etc/netatalk/AppleVolumes.default
Sep 3 09:23:02 unraid emhttp: shcmd (181): echo '"/mnt/user/KidsMovies" -async,no_subtree_check,fsid=109 *(rw,insecure,anongid=100,anonuid=99,all_squash)' >>/etc/exports
Sep 3 09:23:02 unraid emhttp: get_config_idx: fopen /boot/config/shares/_newznab_database.cfg: No such file or directory - assigning defaults
Sep 3 09:23:02 unraid emhttp: shcmd (182): echo '"/mnt/user/datastore" -async,no_subtree_check,fsid=106 *(rw,insecure,anongid=100,anonuid=99,all_squash)' >>/etc/exports
Sep 3 09:23:02 unraid emhttp: Restart SMB...
Sep 3 09:23:02 unraid emhttp: shcmd (183): killall -HUP smbd
Sep 3 09:23:02 unraid emhttp: shcmd (184): ps axc | grep -q rpc.mountd
Sep 3 09:23:02 unraid emhttp: Restart NFS...
Sep 3 09:23:02 unraid emhttp: shcmd (185): exportfs -ra |& logger
Sep 3 09:23:02 unraid emhttp: Restart AFP...
Sep 3 09:23:02 unraid emhttp: shcmd (186): killall -HUP afpd
Sep 3 09:23:02 unraid emhttp: shcmd (187): cp /etc/avahi/services/afp.service- /etc/avahi/services/afp.service
Sep 3 09:23:02 unraid avahi-daemon[492]: Files changed, reloading.
Sep 3 09:23:02 unraid avahi-daemon[492]: Service group file /services/afp.service changed, reloading.
Sep 3 09:23:02 unraid emhttp: shcmd (188): cp /etc/avahi/services/smb.service- /etc/avahi/services/smb.service
Sep 3 09:23:02 unraid avahi-daemon[492]: Files changed, reloading.
Sep 3 09:23:02 unraid avahi-daemon[492]: Service group file /services/smb.service changed, reloading.
Sep 3 09:23:02 unraid emhttp: shcmd (189): /usr/local/sbin/emhttp_event svcs_restarted
Sep 3 09:23:02 unraid emhttp_event: svcs_restarted
Sep 3 09:23:03 unraid avahi-daemon[492]: Service "unraid" (/services/afp.service) successfully established.
Sep 3 09:23:03 unraid avahi-daemon[492]: Service "unraid-SMB" (/services/smb.service) successfully established.
Sep 3 09:23:16 unraid mountd[1366]: authenticated mount request from 192.168.0.253:845 for /mnt/user/datastore (/mnt/user/datastore)
Sep 3 09:24:37 unraid emhttp: shcmd (190): ln -sf /usr/share/zoneinfo/America/Los_Angeles /etc/localtime-copied-from
Sep 3 09:24:37 unraid emhttp: shcmd (191): cp /etc/localtime-copied-from /etc/localtime
Sep 3 09:24:37 unraid emhttp: shcmd (192): /etc/rc.d/rc.ntpd stop |& logger
Sep 3 09:24:37 unraid ntpd[1227]: ntpd exiting on signal 1
Sep 3 09:24:37 unraid logger: Stopping NTP daemon...
Sep 3 09:24:37 unraid emhttp: shcmd (193): date -s '2013-09-03 03:39:06' &> /dev/null
Sep 3 03:39:06 unraid emhttp: shcmd (194): hwclock --utc --systohc --noadjfile
Sep 3 03:39:29 unraid crond[1249]: time disparity of -344 minutes detected 
Sep 3 03:40:01 unraid logger: mover started
Sep 3 03:40:01 unraid logger: skipping Nook/
Sep 3 03:40:01 unraid logger: moving TV/
Sep 3 03:40:01 unraid logger: ./TV/Breaking Bad/Breaking Bad S05E11 Confessions.nfo
Sep 3 03:40:01 unraid logger: *** glibc detected *** rsync: free(): invalid next size (normal): 0x080cbfb0 ***
Sep 3 03:40:01 unraid logger: ======= Backtrace: =========
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x705aa)[0xb769b5aa]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x73503)[0xb769e503]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(cfree+0x70)[0xb76a16b0]
Sep 3 03:40:01 unraid logger: rsync[0x807cd74]
Sep 3 03:40:01 unraid logger: rsync[0x807de60]
Sep 3 03:40:01 unraid logger: rsync[0x804f3aa]
Sep 3 03:40:01 unraid logger: rsync[0x8050b5f]
Sep 3 03:40:01 unraid logger: rsync[0x8051e56]
Sep 3 03:40:01 unraid logger: rsync[0x8065825]
Sep 3 03:40:01 unraid logger: rsync[0x80666ac]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(__libc_start_main+0xe6)[0xb7641b86]
Sep 3 03:40:01 unraid logger: rsync[0x804aad1]
Sep 3 03:40:01 unraid logger: ======= Memory map: ========
Sep 3 03:40:01 unraid logger: 08048000-0809d000 r-xp 00000000 00:01 2148 /usr/bin/rsync
Sep 3 03:40:01 unraid logger: 0809d000-080a1000 rw-p 00054000 00:01 2148 /usr/bin/rsync
Sep 3 03:40:01 unraid logger: 080a1000-080f3000 rw-p 00000000 00:00 0 [heap]
Sep 3 03:40:01 unraid logger: b7400000-b7421000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b7421000-b7500000 ---p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b75c6000-b75e2000 r-xp 00000000 00:01 2625 /usr/lib/libgcc_s.so.1
Sep 3 03:40:01 unraid logger: b75e2000-b75e3000 rw-p 0001b000 00:01 2625 /usr/lib/libgcc_s.so.1
Sep 3 03:40:01 unraid logger: b75e3000-b7626000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b7626000-b762a000 r-xp 00000000 00:01 1571 /lib/libattr.so.1.1.0
Sep 3 03:40:01 unraid logger: b762a000-b762b000 rw-p 00003000 00:01 1571 /lib/libattr.so.1.1.0
Sep 3 03:40:01 unraid logger: b762b000-b7787000 r-xp 00000000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b7787000-b7788000 ---p 0015c000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b7788000-b778a000 r--p 0015c000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b778a000-b778b000 rw-p 0015e000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b778b000-b778e000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b778e000-b7794000 r-xp 00000000 00:01 1844 /lib/libpopt.so.0.0.0
Sep 3 03:40:01 unraid logger: b7794000-b7795000 rw-p 00006000 00:01 1844 /lib/libpopt.so.0.0.0
Sep 3 03:40:01 unraid logger: b7795000-b779b000 r-xp 00000000 00:01 1897 /lib/libacl.so.1.1.0
Sep 3 03:40:01 unraid logger: b779b000-b779c000 rw-p 00005000 00:01 1897 /lib/libacl.so.1.1.0
Sep 3 03:40:01 unraid logger: b77a5000-b77a6000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b77a6000-b77a7000 r-xp 00000000 00:00 0 [vdso]
Sep 3 03:40:01 unraid logger: b77a7000-b77c4000 r-xp 00000000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: b77c4000-b77c5000 r--p 0001d000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: b77c5000-b77c6000 rw-p 0001e000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: bfcaa000-bfccb000 rw-p 00000000 00:00 0 [stack]
Sep 3 03:40:01 unraid logger: find: `rsync' terminated by signal 6
Sep 3 03:40:01 unraid logger: rsync: writefd_unbuffered failed to write 79 bytes to socket [Receiver]: Broken pipe (32)
Sep 3 03:40:01 unraid logger: rsync error: error in rsync protocol data stream (code 12) at io.c(1530) [Receiver=3.0.7]
Sep 3 03:40:01 unraid logger: ./TV/Breaking Bad/Breaking Bad S05E11 Confessions.tbn
Sep 3 03:40:01 unraid logger: *** glibc detected *** rsync: free(): invalid next size (normal): 0x080cbfb0 ***
Sep 3 03:40:01 unraid logger: ======= Backtrace: =========
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x705aa)[0xb76b45aa]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x73503)[0xb76b7503]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(cfree+0x70)[0xb76ba6b0]
Sep 3 03:40:01 unraid logger: rsync[0x807cd74]
Sep 3 03:40:01 unraid logger: rsync[0x807de60]
Sep 3 03:40:01 unraid logger: rsync[0x804f3aa]
Sep 3 03:40:01 unraid logger: rsync[0x8050b5f]
Sep 3 03:40:01 unraid logger: rsync[0x8051e56]
Sep 3 03:40:01 unraid logger: rsync[0x8065825]
Sep 3 03:40:01 unraid logger: rsync[0x80666ac]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(__libc_start_main+0xe6)[0xb765ab86]
Sep 3 03:40:01 unraid logger: rsync[0x804aad1]
Sep 3 03:40:01 unraid logger: ======= Memory map: ========
Sep 3 03:40:01 unraid logger: 08048000-0809d000 r-xp 00000000 00:01 2148 /usr/bin/rsync
Sep 3 03:40:01 unraid logger: 0809d000-080a1000 rw-p 00054000 00:01 2148 /usr/bin/rsync
Sep 3 03:40:01 unraid logger: 080a1000-080f3000 rw-p 00000000 00:00 0 [heap]
Sep 3 03:40:01 unraid logger: b7400000-b7421000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b7421000-b7500000 ---p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b75df000-b75fb000 r-xp 00000000 00:01 2625 /usr/lib/libgcc_s.so.1
Sep 3 03:40:01 unraid logger: b75fb000-b75fc000 rw-p 0001b000 00:01 2625 /usr/lib/libgcc_s.so.1
Sep 3 03:40:01 unraid logger: b75fc000-b763f000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b763f000-b7643000 r-xp 00000000 00:01 1571 /lib/libattr.so.1.1.0
Sep 3 03:40:01 unraid logger: b7643000-b7644000 rw-p 00003000 00:01 1571 /lib/libattr.so.1.1.0
Sep 3 03:40:01 unraid logger: b7644000-b77a0000 r-xp 00000000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b77a0000-b77a1000 ---p 0015c000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b77a1000-b77a3000 r--p 0015c000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b77a3000-b77a4000 rw-p 0015e000 00:01 1894 /lib/libc-2.11.1.so
Sep 3 03:40:01 unraid logger: b77a4000-b77a7000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b77a7000-b77ad000 r-xp 00000000 00:01 1844 /lib/libpopt.so.0.0.0
Sep 3 03:40:01 unraid logger: b77ad000-b77ae000 rw-p 00006000 00:01 1844 /lib/libpopt.so.0.0.0
Sep 3 03:40:01 unraid logger: b77ae000-b77b4000 r-xp 00000000 00:01 1897 /lib/libacl.so.1.1.0
Sep 3 03:40:01 unraid logger: b77b4000-b77b5000 rw-p 00005000 00:01 1897 /lib/libacl.so.1.1.0
Sep 3 03:40:01 unraid logger: b77be000-b77bf000 rw-p 00000000 00:00 0 
Sep 3 03:40:01 unraid logger: b77bf000-b77c0000 r-xp 00000000 00:00 0 [vdso]
Sep 3 03:40:01 unraid logger: b77c0000-b77dd000 r-xp 00000000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: b77dd000-b77de000 r--p 0001d000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: b77de000-b77df000 rw-p 0001e000 00:01 1579 /lib/ld-2.11.1.so
Sep 3 03:40:01 unraid logger: bfa70000-bfa91000 rw-p 00000000 00:00 0 [stack]
Sep 3 03:40:01 unraid kernel: BUG: unable to handle kernel paging request at 73750073
Sep 3 03:40:01 unraid kernel: IP: [] fget_light+0x31/0x7e
Sep 3 03:40:01 unraid kernel: *pdpt = 0000000036e5d001 *pde = 0000000000000000 
Sep 3 03:40:01 unraid kernel: Oops: 0000 [#1] SMP 
Sep 3 03:40:01 unraid kernel: Modules linked in: md_mod xor ata_piix sg e1000 coretemp hwmon e1000e mpt2sas scsi_transport_sas raid_class piix vmxnet3 i2c_piix4 i2c_core
Sep 3 03:40:01 unraid kernel: 
Sep 3 03:40:01 unraid kernel: Pid: 2647, comm: find Not tainted 3.4.26-unRAID #2 VMware, Inc. VMware Virtual Platform/440BX Desktop Reference Platform
Sep 3 03:40:01 unraid kernel: EIP: 0060:[] EFLAGS: 00010206 CPU: 0
Sep 3 03:40:01 unraid kernel: EIP is at fget_light+0x31/0x7e
Sep 3 03:40:01 unraid kernel: EAX: f768e408 EBX: 00000000 ECX: 7375004e EDX: f74d6ea0
Sep 3 03:40:01 unraid kernel: ESI: fffffff7 EDI: bfe6f334 EBP: eed31f8c ESP: eed31f80
Sep 3 03:40:01 unraid kernel: DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
Sep 3 03:40:01 unraid kernel: CR0: 8005003b CR2: 73750073 CR3: 2ee07000 CR4: 000407f0
Sep 3 03:40:01 unraid kernel: DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
Sep 3 03:40:01 unraid kernel: DR6: ffff0ff0 DR7: 00000400
Sep 3 03:40:01 unraid kernel: Process find (pid: 2647, ti=eed30000 task=f25d3600 task.ti=eed30000)
Sep 3 03:40:01 unraid kernel: Stack:
Sep 3 03:40:01 unraid kernel: 00000002 fffffff7 bfe6f334 eed31fac c108978c eed31fac c1026cda 00000a5c
Sep 3 03:40:01 unraid kernel: 00000000 00000002 00000006 eed30000 c1342275 00000002 bfe6f334 00000006
Sep 3 03:40:01 unraid kernel: 00000006 bfe6f334 bfe6f2cc 00000004 0000007b 0000007b 00000000 00000000
Sep 3 03:40:01 unraid kernel: Call Trace:
Sep 3 03:40:01 unraid kernel: [] sys_write+0x18/0x61
Sep 3 03:40:01 unraid kernel: [] ? sys_waitpid+0x27/0x29
Sep 3 03:40:01 unraid kernel: [] syscall_call+0x7/0xb
Sep 3 03:40:01 unraid kernel: Code: 64 8b 0d 4c e6 4c c1 8b 89 74 02 00 00 c7 02 00 00 00 00 8b 19 4b 75 1b 8b 51 04 3b 02 73 50 c1 e0 02 03 42 04 8b 08 85 c9 74 46 41 25 40 74 40 eb 3c 8b 49 04 3b 01 73 35 c1 e0 02 03 41 04 
Sep 3 03:40:01 unraid kernel: EIP: [] fget_light+0x31/0x7e SS:ESP 0068:eed31f80
Sep 3 03:40:01 unraid kernel: CR2: 0000000073750073
Sep 3 03:40:01 unraid logger: rsync: writefd_unbuffered failed to write 79 bytes to socket [Receiver]: Broken pipe (32)
Sep 3 03:40:01 unraid logger: rsync error: error in rsync protocol data stream (code 12) at io.c(1530) [Receiver=3.0.7]
Sep 3 03:40:01 unraid kernel: ---[ end trace e15124224e2e7557 ]---
Sep 3 03:40:01 unraid kernel: BUG: unable to handle kernel paging request at 7a2e737d
Sep 3 03:40:01 unraid kernel: IP: [] filp_close+0xd/0x63
Sep 3 03:40:01 unraid kernel: *pdpt = 0000000036839001 *pde = 0000000000000000 
Sep 3 03:40:01 unraid kernel: Oops: 0000 [#2] SMP 
Sep 3 03:40:01 unraid kernel: Modules linked in: md_mod xor ata_piix sg e1000 coretemp hwmon e1000e mpt2sas scsi_transport_sas raid_class piix vmxnet3 i2c_piix4 i2c_core
Sep 3 03:40:01 unraid kernel: 
Sep 3 03:40:01 unraid kernel: Pid: 2647, comm: find Tainted: G D 3.4.26-unRAID #2 VMware, Inc. VMware Virtual Platform/440BX Desktop Reference Platform
Sep 3 03:40:01 unraid kernel: EIP: 0060:[] EFLAGS: 00010286 CPU: 0
Sep 3 03:40:01 unraid kernel: EIP is at filp_close+0xd/0x63
Sep 3 03:40:01 unraid kernel: EAX: 7a2e7361 EBX: 7a2e7361 ECX: 0000000f EDX: f2c91000
Sep 3 03:40:01 unraid kernel: ESI: f2c91000 EDI: 00000000 EBP: eed31dd0 ESP: eed31dc0
Sep 3 03:40:01 unraid kernel: DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
Sep 3 03:40:01 unraid kernel: CR0: 8005003b CR2: 7a2e737d CR3: 36faf000 CR4: 000407f0
Sep 3 03:40:01 unraid kernel: DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
Sep 3 03:40:01 unraid kernel: DR6: ffff0ff0 DR7: 00000400
Sep 3 03:40:01 unraid kernel: Process find (pid: 2647, ti=eed30000 task=f25d3600 task.ti=eed30000)
Sep 3 03:40:01 unraid kernel: Stack:
Sep 3 03:40:01 unraid kernel: 000000bc f2c91000 f74d6ea0 00000000 eed31df0 c1025f3b 0000000f 00000000
Sep 3 03:40:01 unraid kernel: 00000000 f25d3600 f2c91000 00000001 eed31e00 c1025fb5 f25d3600 f25d38ce
Sep 3 03:40:01 unraid kernel: eed31e38 c1027396 00000000 eed31e1c c13403c5 00000001 f6f81200 0000d74f
Sep 3 03:40:01 unraid kernel: Call Trace:
Sep 3 03:40:01 unraid kernel: [] put_files_struct+0x5b/0xa1
Sep 3 03:40:01 unraid kernel: [] exit_files+0x34/0x38
Sep 3 03:40:01 unraid kernel: [] do_exit+0x1e9/0x29e
Sep 3 03:40:01 unraid kernel: [] ? printk+0x18/0x1a
Sep 3 03:40:01 unraid kernel: [] oops_end+0x7d/0x82
Sep 3 03:40:01 unraid kernel: [] ? printk+0x18/0x1a
Sep 3 03:40:01 unraid kernel: [] no_context+0xff/0x107
Sep 3 03:40:01 unraid kernel: [] __bad_area_nosemaphore+0x11a/0x122
Sep 3 03:40:01 unraid kernel: [] bad_area+0x37/0x3d
Sep 3 03:40:01 unraid kernel: [] do_page_fault+0x195/0x32a
Sep 3 03:40:01 unraid kernel: [] ? handle_mm_fault+0x129/0x138
Sep 3 03:40:01 unraid kernel: [] ? mm_fault_error+0x15b/0x15b
Sep 3 03:40:01 unraid kernel: [] error_code+0x5a/0x60
Sep 3 03:40:01 unraid kernel: [] ? mm_fault_error+0x15b/0x15b
Sep 3 03:40:01 unraid kernel: [] ? fget_light+0x31/0x7e
Sep 3 03:40:01 unraid kernel: [] sys_write+0x18/0x61
Sep 3 03:40:01 unraid kernel: [] ? sys_waitpid+0x27/0x29
Sep 3 03:40:01 unraid kernel: [] syscall_call+0x7/0xb
Sep 3 03:40:01 unraid kernel: Code: e8 ee a7 2b 00 8b 43 04 8b 40 0c 0f b3 30 3b 73 44 73 03 89 73 44 80 43 40 01 5b 5e 5d c3 55 89 e5 57 56 89 d6 53 89 c3 83 ec 04 <8b> 40 1c 85 c0 75 10 c7 04 24 80 1d 3d c1 31 ff e8 8d 8b 2b 00 
Sep 3 03:40:01 unraid kernel: EIP: [] filp_close+0xd/0x63 SS:ESP 0068:eed31dc0
Sep 3 03:40:01 unraid kernel: CR2: 000000007a2e737d
Sep 3 03:40:01 unraid kernel: ---[ end trace e15124224e2e7558 ]---
Sep 3 03:40:01 unraid kernel: Fixing recursive fault but reboot is needed!

syslog.txt

Link to comment
jazzyjeff Noob

jazzyjeff

Posted
  • Author
Posted

I am running 5.0 rc11 but I tried updating but it still showed rc11 so I went back to rc11. I am running this in a VM on ESXi with the setup as described here ... http://lime-technology.com/forum/index.php?topic=26639.0

All was running fine then in an effort to update to rc16 I scrambled it up so I tried removing all traces of simple features and moved back down to rc11. But now I'm getting that mover issue and kernel panics I would assume something to do with the upgrade attempt changing the linux kernel? But I have no clue?

 

Rsync Version

root@unraid:~# rsync --version
rsync  version 3.0.7  protocol version 30
Copyright (C) 1996-2009 by Andrew Tridgell, Wayne Davison, and others.
Web site: http://rsync.samba.org/
Capabilities:
    64-bit files, 64-bit inums, 32-bit timestamps, 64-bit long ints,
    socketpairs, hardlinks, symlinks, IPv6, batchfiles, inplace,
    append, ACLs, xattrs, iconv, symtimes

FREE

root@unraid:~# free -l
             total       used       free     shared    buffers     cached
Mem:       4147004    4031924     115080          0     168828    3563600
Low:        866164     757332     108832
High:      3280840    3274592       6248
-/+ buffers/cache:     299496    3847508
Swap:            0          0          0

 

And yes ran a mem test with all Passed.

Ok I know I screwed up something just what is it?

 

Thanks

Jeff

Link to comment
WeeboTech Veteran

WeeboTech

Posted
Posted

I would remove all addons boot in safe mode and test with the 'latest' released version of unRAID.

 

Something may have updated glibc or some library that is being used by rsync causing the free() failure.

It's either that or there's  bad memory flipping a bit and altering a pointer to some piece of memory that is not owned.

 

Link to comment
WeeboTech Veteran

WeeboTech

Posted
Posted

Something may have updated glibc or some library that is being used by rsync causing the free() failure.

 

what tells you there is a problem the shared @ 0? or the swap??

here it is running 16c in safe mode.

Screen_Shot_2013_09_03_at_1_56_14_PM.png

 

What next??

 

Have you upgraded to the latest unRAID?

 

low memory looks fine.

 

Sep 3 03:40:01 unraid logger: *** glibc detected *** rsync: free(): invalid next size (normal): 0x080cbfb0 ***
Sep 3 03:40:01 unraid logger: ======= Backtrace: =========
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x705aa)[0xb76b45aa]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(+0x73503)[0xb76b7503]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(cfree+0x70)[0xb76ba6b0]
Sep 3 03:40:01 unraid logger: rsync[0x807cd74]
Sep 3 03:40:01 unraid logger: rsync[0x807de60]
Sep 3 03:40:01 unraid logger: rsync[0x804f3aa]
Sep 3 03:40:01 unraid logger: rsync[0x8050b5f]
Sep 3 03:40:01 unraid logger: rsync[0x8051e56]
Sep 3 03:40:01 unraid logger: rsync[0x8065825]
Sep 3 03:40:01 unraid logger: rsync[0x80666ac]
Sep 3 03:40:01 unraid logger: /lib/libc.so.6(__libc_start_main+0xe6)[0xb765ab86]
Sep 3 03:40:01 unraid logger: rsync[0x804aad1]
Sep 3 03:40:01 unraid logger: ======= Memory map: ========

 

rsync called free() to an object (structure) that was not allocated.

Either it's a program bug in rsync, or somewhere a bad call that was never checked.

I've seen stuff like this if I free() memory I don't have or one of the shared libraries is not the correct version.

I've seen stuff like this if there was a problem with physical memory.

 


root@unRAID2:~# cat /etc/unraid-version 
version=5.0



root@unRAID2:~# rsync --version
rsync  version 3.0.7  protocol version 30
Copyright (C) 1996-2009 by Andrew Tridgell, Wayne Davison, and others.
Web site: http://rsync.samba.org/
Capabilities:
    64-bit files, 64-bit inums, 32-bit timestamps, 64-bit long ints,
    socketpairs, hardlinks, symlinks, IPv6, batchfiles, inplace,
    append, ACLs, xattrs, iconv, symtimes


rsync comes with ABSOLUTELY NO WARRANTY.  This is free software, and you
are welcome to redistribute it under certain conditions.  See the GNU
General Public Licence for details.



root@unRAID2:~# ldd /usr/bin/rsync
        linux-gate.so.1 =>  (0x4001f000)
        libacl.so.1 => /lib/libacl.so.1 (0x40026000)
        libpopt.so.0 => /lib/libpopt.so.0 (0x4002d000)
        libc.so.6 => /lib/libc.so.6 (0x40034000)
        libattr.so.1 => /lib/libattr.so.1 (0x40197000)
        /lib/ld-linux.so.2 (0x40000000)

root@unRAID2:~# ls -l /lib/libc.so.6
lrwxrwxrwx 1 root root 14 2013-08-28 13:45 /lib/libc.so.6 -> libc-2.11.1.so*
root@unRAID2:~# ls -lL /lib/libc.so.6
-rwxr-xr-x 1 root root 1649149 2010-05-13 02:55 /lib/libc.so.6*

Link to comment
WeeboTech Veteran

WeeboTech

Posted
Posted

Have you upgraded to the latest unRAID?

 

I guess not I'm on 5.0 rc16

 

Screen_Shot_2013_09_03_at_2_10_25_PM.png

 

I had a little trouble getting 5.0 stable to work in ESXi do you know where I can get a stable vmdk prebuilt?

 

There are instructions for doing a manual update on the forum. 

 

Have you tried the mover under bare metal?

 

What issue did you have with 5.0 stable under ESX, I have not had any issues, but I don't have a cache drive.

Link to comment
jazzyjeff Noob

jazzyjeff

Posted
  • Author
Posted
There are instructions for doing a manual update on the forum. 

 

Have you tried the mover under bare metal?

 

What issue did you have with 5.0 stable under ESX, I have not had any issues, but I don't have a cache drive.

 

Yes I tried those updates and all I get is syslinux on the console with no further action?

No install of unRAID just the syslinux 1 line. I updated the vmdk from rc15 to rc16 with no issues but not to the stable 5.0.

Link to comment
jazzyjeff Noob

jazzyjeff

Posted
  • Author
Posted

Ok got unRAID updated to 5.0 stable...

 

 

 

/usr/bin/tail -f /var/log/syslog
Sep 3 20:49:25 unraid kernel: [] do_page_fault+0x8/0xd
Sep 3 20:49:25 unraid kernel: [] error_code+0x5a/0x60
Sep 3 20:49:25 unraid kernel: [] ? __do_page_fault+0x366/0x366
Sep 3 20:49:25 unraid kernel: [] ? sys_fchdir+0x27/0x6e
Sep 3 20:49:25 unraid kernel: [] syscall_call+0x7/0xb
Sep 3 20:49:25 unraid kernel: Code: 01 02 00 00 83 fa 01 76 0e 3d fe fd ff ff 74 07 3d fc fd ff ff 75 05 b8 fc ff ff ff 5d c3 55 89 e5 57 56 89 d6 53 89 c3 83 ec 04 <8b> 40 20 85 c0 75 10 c7 04 24 64 bf 4b c1 31 ff e8 b2 c3 36 00
Sep 3 20:49:25 unraid kernel: EIP: [] filp_close+0xd/0x63 SS:ESP 0068:f6bf7dd4
Sep 3 20:49:25 unraid kernel: CR2: 0000000073750071
Sep 3 20:49:25 unraid kernel: ---[ end trace 286d30a91d7caf17 ]---
Sep 3 20:49:25 unraid kernel: Fixing recursive fault but reboot is needed!

 

I think the mover script is having trouble with the logging turned on???

I will test and see.

Link to comment

Archived

This topic is now archived and is closed to further replies.

Go to topic listing
×
×
  • Create New...