March 7, 201115 yr Here's a weird one. Running beta 5b and having issues with Time Machine. 2 of the 3 macs back up just fine. When TM is running on the 3rd mac, at some stage the mac reports that the Tower has closed down. At this point I can no longer access the unRaid box via the web gui (on this mac only!), can no longer view contents of afp shares, but I can ping the box just fine and have network access to everything else. Turns out that if I power cycle the switch that this mac is attached to, TM will resume, afp contents can be viewed and I can use the web gui. However I've tried to completely different switches, which should rule out either being duff. I captured this from the syslog: login timemachine (uid 1001, gid 100) AFP3.1 Mar 6 17:29:44 Tower afpd[2052]: getquota: special /mnt/user/timemachine fails Mar 6 17:29:44 Tower afpd[2052]: ipc_write: command: 2, pid: 2052, msglen: 24 Mar 6 17:29:44 Tower afpd[1492]: ipc_read: command: 2, pid: 2052, len: 24 Mar 6 17:29:44 Tower afpd[1492]: Already tried to kill (1892) before! Still there? Mar 6 17:29:44 Tower afpd[1492]: Setting clientid (len 16) for 2052, boottime 4D7434C9 Mar 6 17:29:44 Tower afpd[1492]: WARNING: 2 connections (1948, 2052), boottime identical, don't know if one needs to be disconnected. Mar 6 17:29:44 Tower afpd[1492]: ipc_get_session: len: 24, idlen 16, time 4d7434c9 Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2053 exited 1 Mar 6 17:29:44 Tower afpd[2054]: ASIP session:548(5) from 10.0.1.8:49225(7) Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2054 done Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2055 exited 1 Mar 6 17:29:44 Tower afpd[2056]: ASIP session:548(5) from 10.0.1.8:49227(7) Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2056 done Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2057 exited 1 Mar 6 17:29:44 Tower afpd[2058]: ASIP session:548(5) from 10.0.1.8:49229(7) Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2058 done Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2059 exited 1 Mar 6 17:29:44 Tower afpd[2060]: ASIP session:548(5) from 10.0.1.8:49231(7) Mar 6 17:29:44 Tower afpd[1492]: server_child[1] 2060 done Any ideas?
June 20, 201115 yr I have seen this issue a few times before. I think it's a bug, not sure what Tom will do with it yet. Slowly but surely
June 20, 201115 yr LOL - now that's funny. Just as I posted, I got the same error. Jun 20 22:09:20 Media cnid_dbd[27153]: main: Idle timeout, exiting Jun 20 22:09:20 Media cnid_dbd[27322]: main: Idle timeout, exiting Jun 20 22:09:20 Media cnid_dbd[27128]: main: Idle timeout, exiting Jun 20 22:09:20 Media cnid_dbd[27146]: main: Idle timeout, exiting Jun 20 22:09:20 Media afpd[26951]: server_child[1] 27121 done Jun 20 22:09:30 Media cnid_metad: cnid_dbd pid 27128 exited with exit code 0 Jun 20 22:09:30 Media cnid_metad: cnid_dbd pid 27146 exited with exit code 0 Jun 20 22:09:30 Media cnid_metad: cnid_dbd pid 27153 exited with exit code 0 Jun 20 22:09:30 Media cnid_metad: cnid_dbd pid 27322 exited with exit code 0 Jun 20 22:09:34 Media shfs: duplicate object: /mnt/disk4/Storage/.DS_Store Jun 20 22:09:38 Media afpd[26951]: server_child[1] 25439 exited 1 Jun 20 22:09:38 Media afpd[25440]: ASIP session:548(5) from 192.168.0.150:57693(7) Jun 20 22:09:38 Media afpd[26951]: server_child[1] 25440 done Jun 20 22:09:38 Media afpd[25441]: ASIP session:548(5) from 192.168.0.150:57694(7) Jun 20 22:09:38 Media afpd[25441]: DHX2 login: mediacenter Jun 20 22:09:40 Media afpd[25441]: login mediacenter (uid 1000, gid 100) AFP3.1 Jun 20 22:09:40 Media cnid_dbd[25448]: Setting uid/gid to 0/0 Jun 20 22:09:40 Media cnid_dbd[25448]: Startup, DB dir /mnt/user/Documentaries/.AppleDB Jun 20 22:09:41 Media afpd[25441]: getquota: special /mnt/user/Documentaries fails Jun 20 22:09:41 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:41 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:41 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:41 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:41 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:41 Media afpd[26951]: server_child[1] 25449 exited 1 Jun 20 22:09:41 Media afpd[25450]: ASIP session:548(5) from 192.168.0.150:57697(7) Jun 20 22:09:41 Media afpd[26951]: server_child[1] 25450 done Jun 20 22:09:41 Media afpd[25441]: getquota: special /mnt/user/Storage fails Jun 20 22:09:41 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:41 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:41 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:41 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:41 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:41 Media shfs: duplicate object: /mnt/disk4/Storage/.DS_Store Jun 20 22:09:42 Media afpd[26951]: server_child[1] 25452 exited 1 Jun 20 22:09:42 Media afpd[25453]: ASIP session:548(5) from 192.168.0.150:57707(7) Jun 20 22:09:42 Media afpd[26951]: server_child[1] 25453 done Jun 20 22:09:43 Media cnid_dbd[25455]: Setting uid/gid to 0/0 Jun 20 22:09:43 Media cnid_dbd[25455]: Startup, DB dir /mnt/user/HD Movies/.AppleDB Jun 20 22:09:43 Media afpd[25441]: getquota: special /mnt/user/HD Movies fails Jun 20 22:09:43 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:43 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:43 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:43 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:43 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25456 exited 1 Jun 20 22:09:43 Media afpd[25457]: ASIP session:548(5) from 192.168.0.150:57719(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25457 done Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25458 exited 1 Jun 20 22:09:43 Media afpd[25459]: ASIP session:548(5) from 192.168.0.150:57721(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25459 done Jun 20 22:09:43 Media cnid_dbd[25460]: Setting uid/gid to 0/0 Jun 20 22:09:43 Media cnid_dbd[25460]: Startup, DB dir /mnt/user/Movies/.AppleDB Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25461 exited 1 Jun 20 22:09:43 Media afpd[25462]: ASIP session:548(5) from 192.168.0.150:57723(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25462 done Jun 20 22:09:43 Media afpd[25441]: getquota: special /mnt/user/Movies fails Jun 20 22:09:43 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:43 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:43 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:43 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:43 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25463 exited 1 Jun 20 22:09:43 Media afpd[25464]: ASIP session:548(5) from 192.168.0.150:57725(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25464 done Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25465 exited 1 Jun 20 22:09:43 Media afpd[25466]: ASIP session:548(5) from 192.168.0.150:57727(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25466 done Jun 20 22:09:43 Media afpd[25441]: getquota: special /mnt/user/Music fails Jun 20 22:09:43 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:43 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:43 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:43 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:43 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25467 exited 1 Jun 20 22:09:43 Media afpd[25468]: ASIP session:548(5) from 192.168.0.150:57729(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25468 done Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25469 exited 1 Jun 20 22:09:43 Media afpd[25470]: ASIP session:548(5) from 192.168.0.150:57732(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25470 done Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25471 exited 1 Jun 20 22:09:43 Media afpd[25472]: ASIP session:548(5) from 192.168.0.150:57734(7) Jun 20 22:09:43 Media afpd[26951]: server_child[1] 25472 done Jun 20 22:09:43 Media cnid_dbd[25473]: Setting uid/gid to 0/0 Jun 20 22:09:43 Media cnid_dbd[25473]: Startup, DB dir /mnt/user/TV/.AppleDB Jun 20 22:09:43 Media afpd[25441]: getquota: special /mnt/user/TV fails Jun 20 22:09:43 Media afpd[25441]: ipc_write: command: 2, pid: 25441, msglen: 24 Jun 20 22:09:43 Media afpd[26951]: ipc_read: command: 2, pid: 25441, len: 24 Jun 20 22:09:43 Media afpd[26951]: Setting clientid (len 16) for 25441, boottime 4DFF1C10 Jun 20 22:09:43 Media afpd[26951]: WARNING: 2 connections (25231, 25441), boottime identical, don't know if one needs to be disconnected. Jun 20 22:09:43 Media afpd[26951]: ipc_get_session: len: 24, idlen 16, time 4dff1c10 Jun 20 22:09:52 Media afpd[26951]: server_child[1] 25480 exited 1 Jun 20 22:09:52 Media afpd[25481]: ASIP session:548(5) from 192.168.0.150:57777(7) Jun 20 22:09:52 Media afpd[26951]: server_child[1] 25481 done Jun 20 22:09:52 Media afpd[26951]: server_child[1] 25482 exited 1
June 20, 201115 yr I'll check my logs when I get home. I have Time Machine run nightly at 2 AM, and just updated to 5.0b7 last night. We'll see what happened...
Archived
This topic is now archived and is closed to further replies.