EMC randomly shutting off (Toggle F2)

More
8 years 2 weeks ago #118728 by Rick4004
OK, this is what I got when the program stopped after about 15-20 minutes,

Code:
emcTaskPlanCommand(N47010 X-.8009 Y-.6486 Z-.0101) called. (line_number=4712) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4711 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.264511,0.254288,0.255494,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,1.005298,8.616844, +0,) Outgoing motion id is 3711. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4713 emcTaskPlanCommand(N47020 X-.7959 Y-.6445 Z-.0075) called. (line_number=4713) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4712 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.272411,0.262988,0.242894,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.546972,6.837153, +0,) Motion id 1722 took 0.023961 seconds. Outgoing motion id is 3712. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4714 emcTaskPlanCommand(N47030 X-.7958 Y-.6444 Z-.0074) called. (line_number=4714) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4713 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.286711,0.278888,0.220494,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.553012,6.912655, +0,) Motion id 1723 took 0.203558 seconds. Outgoing motion id is 3713. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4715 emcTaskPlanCommand(N47040 X-.7934 Y-.6425 Z-.0065) called. (line_number=4715) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4714 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.287211,0.279488,0.219894,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.656591,8.207382, +0,) Motion id 1724 took 0.021965 seconds. Outgoing motion id is 3714. Motion id 1725 took 0.018494 seconds. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4716 emcTaskPlanCommand(N47050 X-.7925 Y-.6417 Z-.006) called. (line_number=4716) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4715 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.288111,0.280488,0.218294,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.522614,6.532670, +0,) Outgoing motion id is 3715. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4717 emcTaskPlanCommand(N47060 X-.7841 Y-.635 Z-.0032) called. (line_number=4717) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4716 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.288811,0.281288,0.216694,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.480234,6.002929, +0,) Motion id 1726 took 0.032258 seconds. Outgoing motion id is 3716. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4718 emcTaskPlanCommand(N47070 X-.7831 Y-.6342) called. (line_number=4718) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4717 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.289311,0.281788,0.214994,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.433222,5.415280, +0,) Motion id 1727 took 0.030289 seconds. Outgoing motion id is 3717. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4719 emcTaskPlanCommand(N47080 X-.7804 Y-.6319 Z-.0023) called. (line_number=4719) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4718 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.289511,0.282088,0.213694,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.415100,5.188745, +0,) Motion id 1728 took 0.070912 seconds. Outgoing motion id is 3718. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4720 emcTaskPlanCommand(N47090 X-.7793 Y-.6311 Z-.0021) called. (line_number=4720) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4719 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.289711,0.282288,0.213194,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.459565,5.744563, +0,) Motion id 1729 took 3.766419 seconds. Outgoing motion id is 3719. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4721 emcTaskPlanCommand(N47100 X-.7786 Y-.6305 Z-.0019) called. (line_number=4721) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4720 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.290011,0.282588,0.211294,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.409851,5.123137, +0,) Motion id 1730 took 0.322318 seconds. Outgoing motion id is 3720. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4722 emcTaskPlanCommand(N47110 X-.7775 Y-.6296 Z-.0015) called. (line_number=4722) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4721 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.290111,0.282688,0.209394,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.401107,5.013831, +0,) Motion id 1731 took 3.762625 seconds. Outgoing motion id is 3721. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4723 emcTaskPlanCommand(N47120 X-.7769 Y-.6291) called. (line_number=4723) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4722 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.290111,0.282688,0.164194,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.105500,0.400000,5.000000, +0,) Motion id 1732 took 0.081968 seconds. Motion id 1733 took 0.010096 seconds. Outgoing motion id is 3722. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4724 emcTaskPlanCommand(N47130 X-.7762 Y-.6286 Z-.0014) called. (line_number=4724) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4723 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-2.505911,1.632988,0.164194,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.941938,8.073750, +0,) Outgoing motion id is 3723. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4725 emcTaskPlanCommand(N47140 X-.7754 Y-.6279 Z-.0011) called. (line_number=4725) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4724 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-2.614211,1.530288,0.164194,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.964695,8.719692, +0,) Motion id 1734 took 0.051081 seconds. Outgoing motion id is 3724. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4726 emcTaskPlanCommand(N47150 X-.7749 Y-.6275) called. (line_number=4726) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4725 emcTaskPlanExecute(0) return 0 Issuing EMC_TRAJ_LINEAR_MOVE -- (+220,+116, +0,-1.322411,0.238488,0.164194,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000, +2,0.250000,0.989949,8.485281, +0,) Motion id 1735 took 0.030918 seconds. Motion id 1736 took 0.010115 seconds. Outgoing motion id is 3725. emcTaskPlanRead() returned 0 emcTaskPlanLine() returned 4727 emcTaskPlanCommand(N47160 X-.7279 Y-.5895 Z.0096) called. (line_number=4727) NML_INTERP_LIST::append(nml_msg{size=116,type=EMC_TRAJ_LINEAR_MOVE}) : list_size=1001, line_number=4726 emcTaskPlanExecute(0) return 0

Please Log in or Create an account to join the conversation.

More
8 years 2 weeks ago #118729 by andypugh
Nothing looks wrong there.

Is there anything in dmesg (just type "dmesg" at the command prompt, and send us the last 100 lines)

Please Log in or Create an account to join the conversation.

More
8 years 2 weeks ago #118730 by Rick4004
Well there appears to be a problem. What the cause would be, I don't know. I haven't made any hardware or software setting changes for months, and the problem just started a few weeks ago.
Code:
[24793.024471] RTAI[math]: unloaded. [24793.238929] SCHED releases registered named ALIEN RTGLBH [24793.252713] RTAI[malloc]: unloaded. [24793.352069] RTAI[sched]: unloaded (forced hard/soft/hard transitions: traps 0, syscalls 0). [24793.365010] I-pipe: Domain RTAI unregistered. [24793.365036] RTAI[hal]: unmounted. [25256.075368] I-pipe: Domain RTAI registered. [25256.075394] RTAI[hal]: <3.8.1> mounted over IPIPE-NOTHREADS 2.6-03. [25256.075399] RTAI[hal]: compiled with gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) . [25256.075415] RTAI[hal]: mounted (IPIPE-NOTHREADS, IMMEDIATE (INTERNAL IRQs DISPATCHED), ISOL_CPUS_MASK: 0). [25256.075420] PIPELINE layers: [25256.075428] f95f2e20 9ac15d93 RTAI 200 [25256.075434] c085cb20 0 Linux 100 [25256.139290] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>. [25256.139735] RTAI[sched]: IMMEDIATE, MP, USER/KERNEL SPACE: <with RTAI OWN KTASKs>, kstacks pool size = 524288 bytes. [25256.139747] RTAI[sched]: hard timer type/freq = APIC/6224690(Hz); default timing: periodic; linear timed lists. [25256.139754] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq = 1693059000 hz. [25256.139760] RTAI[sched]: timer setup = 999 ns, resched latency = 2943 ns. [25256.139994] RTAI[usi]: enabled. [25256.419181] RTAI[math]: loaded. [25256.604623] config string '0x378 out ' [25541.343842] RTAI[math]: unloaded. [25541.562600] SCHED releases registered named ALIEN RTGLBH [25541.576700] RTAI[malloc]: unloaded. [25541.676159] RTAI[sched]: unloaded (forced hard/soft/hard transitions: traps 0, syscalls 0). [25541.694418] I-pipe: Domain RTAI unregistered. [25541.694444] RTAI[hal]: unmounted. [25756.132903] I-pipe: Domain RTAI registered. [25756.132929] RTAI[hal]: <3.8.1> mounted over IPIPE-NOTHREADS 2.6-03. [25756.132935] RTAI[hal]: compiled with gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) . [25756.132950] RTAI[hal]: mounted (IPIPE-NOTHREADS, IMMEDIATE (INTERNAL IRQs DISPATCHED), ISOL_CPUS_MASK: 0). [25756.132955] PIPELINE layers: [25756.132963] f9b4be20 9ac15d93 RTAI 200 [25756.132969] c085cb20 0 Linux 100 [25756.213894] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>. [25756.217074] RTAI[sched]: IMMEDIATE, MP, USER/KERNEL SPACE: <with RTAI OWN KTASKs>, kstacks pool size = 524288 bytes. [25756.217170] RTAI[sched]: hard timer type/freq = APIC/6224690(Hz); default timing: periodic; linear timed lists. [25756.217178] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq = 1693059000 hz. [25756.217183] RTAI[sched]: timer setup = 999 ns, resched latency = 2943 ns. [25756.223366] RTAI[usi]: enabled. [25756.568945] RTAI[math]: loaded. [25756.922398] config string '0x0378' [25784.256176] RTAI[math]: unloaded. [25784.533380] SCHED releases registered named ALIEN RTGLBH [25784.548724] RTAI[malloc]: unloaded. [25784.648102] RTAI[sched]: unloaded (forced hard/soft/hard transitions: traps 0, syscalls 0). [25784.676258] I-pipe: Domain RTAI unregistered. [25784.676310] RTAI[hal]: unmounted. [26805.831674] I-pipe: Domain RTAI registered. [26805.831705] RTAI[hal]: <3.8.1> mounted over IPIPE-NOTHREADS 2.6-03. [26805.831711] RTAI[hal]: compiled with gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) . [26805.831727] RTAI[hal]: mounted (IPIPE-NOTHREADS, IMMEDIATE (INTERNAL IRQs DISPATCHED), ISOL_CPUS_MASK: 0). [26805.831732] PIPELINE layers: [26805.831739] fa091e20 9ac15d93 RTAI 200 [26805.831745] c085cb20 0 Linux 100 [26805.895634] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>. [26805.896611] RTAI[sched]: IMMEDIATE, MP, USER/KERNEL SPACE: <with RTAI OWN KTASKs>, kstacks pool size = 524288 bytes. [26805.896626] RTAI[sched]: hard timer type/freq = APIC/6224690(Hz); default timing: periodic; linear timed lists. [26805.896633] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq = 1693059000 hz. [26805.896638] RTAI[sched]: timer setup = 999 ns, resched latency = 2943 ns. [26805.896877] RTAI[usi]: enabled. [26806.178353] RTAI[math]: loaded. [26806.369608] config string '0x378 out ' [28592.220225] usb 1-3: new high speed USB device using ehci_hcd and address 3 [28592.344164] usb 1-3: device descriptor read/64, error -71 [28592.572171] usb 1-3: device descriptor read/64, error -71 [28592.788164] usb 1-3: new high speed USB device using ehci_hcd and address 4 [28592.912169] usb 1-3: device descriptor read/64, error -71 [28593.140694] usb 1-3: device descriptor read/64, error -71 [28593.356142] usb 1-3: new high speed USB device using ehci_hcd and address 5 [28593.772100] usb 1-3: device not accepting address 5, error -71 [28593.884155] usb 1-3: new high speed USB device using ehci_hcd and address 6 [28594.300158] usb 1-3: device not accepting address 6, error -71 [28594.300276] hub 1-0:1.0: unable to enumerate USB device on port 3 [28594.684154] usb 4-1: new full speed USB device using ohci_hcd and address 2 [28594.884591] usb 4-1: not running at top speed; connect to a high speed hub [28594.909057] usb 4-1: configuration #1 chosen from 1 choice [28595.028751] Initializing USB Mass Storage driver... [28595.029310] scsi2 : SCSI emulation for USB Mass Storage devices [28595.030364] usbcore: registered new interface driver usb-storage [28595.030379] USB Mass Storage support registered. [28595.062921] usb-storage: device found at 2 [28595.062963] usb-storage: waiting for device to settle before scanning [28600.065317] usb-storage: device scan complete [28600.100113] scsi 2:0:0:0: Direct-Access USB DISK PMAP PQ: 0 ANSI: 0 CCS [28600.115906] sd 2:0:0:0: Attached scsi generic sg2 type 0 [28600.989201] sd 2:0:0:0: [sdb] 1961984 512-byte logical blocks: (1.00 GB/958 MiB) [28600.996255] sd 2:0:0:0: [sdb] Write Protect is off [28600.996280] sd 2:0:0:0: [sdb] Mode Sense: 23 00 00 00 [28600.996287] sd 2:0:0:0: [sdb] Assuming drive cache: write through [28601.033205] sd 2:0:0:0: [sdb] Assuming drive cache: write through [28601.033242] sdb: [28601.088795] sd 2:0:0:0: [sdb] Assuming drive cache: write through [28601.088813] sd 2:0:0:0: [sdb] Attached SCSI removable disk [28712.756193] usb 4-1: USB disconnect, address 2 [30826.077776] RTAI[math]: unloaded. [30826.298615] SCHED releases registered named ALIEN RTGLBH [30826.312657] RTAI[malloc]: unloaded. [30826.412046] RTAI[sched]: unloaded (forced hard/soft/hard transitions: traps 0, syscalls 0). [30826.425196] I-pipe: Domain RTAI unregistered. [30826.425221] RTAI[hal]: unmounted. [30827.611547] I-pipe: Domain RTAI registered. [30827.611572] RTAI[hal]: <3.8.1> mounted over IPIPE-NOTHREADS 2.6-03. [30827.611578] RTAI[hal]: compiled with gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) . [30827.611594] RTAI[hal]: mounted (IPIPE-NOTHREADS, IMMEDIATE (INTERNAL IRQs DISPATCHED), ISOL_CPUS_MASK: 0). [30827.611599] PIPELINE layers: [30827.611606] f833ce20 9ac15d93 RTAI 200 [30827.611612] c085cb20 0 Linux 100 [30827.675507] RTAI[malloc]: global heap size = 2097152 bytes, <BSD>. [30827.675934] RTAI[sched]: IMMEDIATE, MP, USER/KERNEL SPACE: <with RTAI OWN KTASKs>, kstacks pool size = 524288 bytes. [30827.675945] RTAI[sched]: hard timer type/freq = APIC/6224690(Hz); default timing: periodic; linear timed lists. [30827.675952] RTAI[sched]: Linux timer freq = 250 (Hz), TimeBase freq = 1693059000 hz. [30827.675957] RTAI[sched]: timer setup = 999 ns, resched latency = 2943 ns. [30827.681887] RTAI[usi]: enabled. [30827.960598] RTAI[math]: loaded. [30828.116146] RTAPI: ERROR: Unexpected realtime delay on task 1 [30828.116151] This Message will only display once per session. [30828.116154] Run the Latency Test and resolve before continuing. [30828.153685] config string '0x378 out ' rick@rick-shoptask:~$

Please Log in or Create an account to join the conversation.

More
8 years 2 weeks ago #118731 by andypugh
Actually, that looks fine too, if there was nothing extra happened in that log since the stop.

Is that the dmesg from right after the stop?

There might be something crazy happening on USB if you re-started LinuxCNC?

Please Log in or Create an account to join the conversation.

More
8 years 2 weeks ago #118732 by Rick4004
Yes it was the dmesg from right after the top, but not the whole thing, just the last part. Once I saw

[30828.116146] RTAPI: ERROR: Unexpected realtime delay on task 1
[30828.116151] This Message will only display once per session.
[30828.116154] Run the Latency Test and resolve before continuing.

I thought that the RTAPI: ERROR was a problem.

Should I repost the whole dmesg?

Please Log in or Create an account to join the conversation.

More
8 years 2 weeks ago #118778 by andypugh
Unexpected realtime delay is an error, but not a fatal one.
It doesn't explain the symptoms.

Please Log in or Create an account to join the conversation.

Time to create page: 0.249 seconds
Powered by Kunena Forum