GNS3 / gns3-gui

GNS3 Graphical Network Simulator
http://www.gns3.com
GNU General Public License v3.0
2.11k stars 433 forks source link

IOU starts and then fails. #45

Closed lachlanjholmes closed 10 years ago

lachlanjholmes commented 10 years ago

Hey, So the IOU's in the topology start and then turn off.

http://i.imgur.com/C41VA5X.png

GNS3 [lachlan@Ante-lappy ~]$ gns3 GNS3 GUI version 1.0a5.dev2 Copyright (c) 2007-2014 GNS3 Technologies Inc. [I 140520 12:42:06 main:74] current locale is en_AU.UTF-8 ... [I 140520 12:42:21 iou_device:498] starting IOU: ['/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin', '-L', '-c', 'startup-config', '2'] [I 140520 12:42:21 iou_device:500] logging to /home/lachlan/GNS3/test/test-files/iou/device-2/iou.log [I 140520 12:42:21 iou_device:507] IOU instance 2 started PID=22438 [I 140520 12:42:21 iou_device:413] starting ioucon for IOU instance IOU2 to accept Telnet connections on 0.0.0.0:4002 [I 140520 12:42:21 ioucon:358] Telnet server ready for connections on 0.0.0.0:4002 [I 140520 12:42:21 iou_device:383] IOU IOU2 [id=2]: iouyap.ini updated [I 140520 12:42:21 iou_device:427] starting iouyap: ['/usr/local/bin/iouyap', '-q', '514'] [I 140520 12:42:21 iou_device:429] logging to /home/lachlan/GNS3/test/test-files/iou/device-2/iouyap.log Couldn't connect to socket /tmp/netio1000/ttyS2: [Errno 111] Connection refused [I 140520 12:42:21 iou_device:436] iouyap started PID=22440 [I 140520 12:42:21 iou_device:250] IOU IOU1 [id=1]: iouyap path set to /usr/local/bin/iouyap [I 140520 12:42:21 iou_device:227] IOU IOU1 [id=1]: iourc file path set to /tmp/tmps6q6c2kv [I 140520 12:42:21 iou_device:402] IOU IOU1 [id=1]: NETMAP file created [I 140520 12:42:21 iou_device:498] starting IOU: ['/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin', '-L', '-c', 'startup-config', '1'] [I 140520 12:42:21 iou_device:500] logging to /home/lachlan/GNS3/test/test-files/iou/device-1/iou.log [I 140520 12:42:21 iou_device:507] IOU instance 1 started PID=22450 [I 140520 12:42:21 iou_device:413] starting ioucon for IOU instance IOU1 to accept Telnet connections on 0.0.0.0:4001 [I 140520 12:42:21 iou_device:383] IOU IOU1 [id=1]: iouyap.ini updated [I 140520 12:42:21 iou_device:427] starting iouyap: ['/usr/local/bin/iouyap', '-q', '513'] [I 140520 12:42:21 iou_device:429] logging to /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log [I 140520 12:42:21 ioucon:358] Telnet server ready for connections on 0.0.0.0:4001 Couldn't connect to socket /tmp/netio1000/ttyS1: [Errno 111] Connection refused [I 140520 12:42:21 iou_device:436] iouyap started PID=22452 [I 140520 12:42:21 router:336] router "R6" [id=6] has been started [I 140520 12:42:21 router:336] router "R5" [id=5] has been started [I 140520 12:42:22 router:336] router "R4" [id=4] has been started [I 140520 12:42:22 router:336] router "R3" [id=3] has been started [I 140520 12:42:22 router:336] router "R2" [id=2] has been started [I 140520 12:42:22 router:336] router "R1" [id=1] has been started [I 140520 12:42:22 iou_device:535] stopping iouyap PID=22452 for IOU instance 1 [I 140520 12:42:22 iou_device:535] stopping iouyap PID=22440 for IOU instance 2 [I 140520 12:42:28 router:346] router "R6" [id=6] has been stopped [I 140520 12:42:28 router:346] router "R5" [id=5] has been stopped [I 140520 12:42:28 router:346] router "R4" [id=4] has been stopped [I 140520 12:42:28 router:346] router "R3" [id=3] has been stopped [I 140520 12:42:28 router:346] router "R2" [id=2] has been stopped [I 140520 12:42:28 router:346] router "R1" [id=1] has been stopped

grossmj commented 10 years ago

Should be fixed hopefully.

https://github.com/GNS3/gns3-server/commit/08cb3de6837c11074dfdc11a8cba74410be48fe5

lachlanjholmes commented 10 years ago

Hasn't worked. I'm not sure if my paste worked last time.....

so i pastebin'd it...

http://pastebin.com/raw.php?i=irChVFFH

grossmj commented 10 years ago

Strange, can you start i86bi-linux-l2-adventerprisek9-15.1.bin manually?

lachlanjholmes commented 10 years ago
[lachlan@Ante-lappy]$ ./i86bi-linux-l2-adventerprisek9-15.1.bin 1
***************************************************************
IOS On Unix - Cisco Systems confidential, internal use only

              Restricted Rights Legend

Use, duplication, or disclosure by the Government is
subject to restrictions as set forth in subparagraph
(c) of the Commercial Computer Software - Restricted
Rights clause at FAR sec. 52.227-19 and subparagraph
(c) (1) (ii) of the Rights in Technical Data and Computer
Software clause at DFARS sec. 252.227-7013.

           cisco Systems, Inc.
           170 West Tasman Drive
           San Jose, California 95134-1706

Cisco IOS Software, Solaris Software (I86BI_LINUXL2-ADVENTERPRISEK9-M), Experimental Version 15.1(20130726:213425) [dstivers-july26-2013-team_track 104]
Copyright (c) 1986-2013 by Cisco Systems, Inc.
Compiled Fri 26-Jul-13 15:56 by dstivers
This product contains cryptographic features and is subject to United
States and local country laws governing import, export, transfer and
use. Delivery of Cisco cryptographic products does not imply
third-party authority to import, export, distribute or use encryption.
Importers, exporters, distributors and users are responsible for
compliance with U.S. and local country laws. By using this product you
agree to comply with applicable laws and regulations. If you are unable
to comply with U.S. and local laws, return this product immediately.

A summary of U.S. laws governing Cisco cryptographic products may be found at:
http://www.cisco.com/wwl/export/crypto/tool/stqrg.html

If you require further assistance please contact us by sending email to
export@cisco.com.

Linux Unix (Intel-x86) processor with 189092K bytes of memory.
Processor board ID 2048001
8 Ethernet interfaces
8 Serial interfaces
16K bytes of NVRAM.

         --- System Configuration Dialog ---

Enable secret warning
----------------------------------
In order to access the device manager, an enable secret is required
If you enter the initial configuration dialog, you will be prompted for the enable secret
If you choose not to enter the intial configuration dialog, or if you exit setup without setting the enable secret,
please set an enable secret using the following CLI in configuration mode-
enable secret 0 <cleartext password>
----------------------------------
Would you like to enter the initial configuration dialog? [yes/no]: n

Would you like to terminate autoinstall? [yes]: y

Would you like to terminate autoinstall? [yes]: y

Press RETURN to get started!

*May 20 23:36:46.926: %SPANTREE-5-EXTENDED_SYSID: Extended SysId enabled for type vlan
*May 20 23:36:48.527: %LINK-3-UPDOWN: Interface Ethernet0/0, changed state to up
*May 20 23:36:48.532: %LINK-3-UPDOWN: Interface Ethernet0/1, changed state to up
*May 20 23:36:48.544: %LINK-3-UPDOWN: Interface Ethernet0/2, changed state to up
*May 20 23:36:48.549: %LINK-3-UPDOWN: Interface Ethernet0/3, changed state to up
*May 20 23:36:48.562: %LINK-3-UPDOWN: Interface Ethernet1/0, changed state to up
*May 20 23:36:48.575: %LINK-3-UPDOWN: Interface Ethernet1/1, changed state to up
*May 20 23:36:48.579: %LINK-3-UPDOWN: Interface Ethernet1/2, changed state to up
*May 20 23:36:48.592: %LINK-3-UPDOWN: Interface Ethernet1/3, changed state to up
*May 20 23:36:48.592: %LINK-3-UPDOWN: Interface Serial2/0, changed state to up
*May 20 23:36:48.592: %LINK-3-UPDOWN: Interface Serial2/1, changed state to up
*May 20 23:36:48.592: %LINK-3-UPDOWN: Interface Serial2/2, changed state to up
*May 20 23:36:48.597: %LINK-3-UPDOWN: Interface Serial2/3, changed state to up
*May 20 23:36:48.597: %LINK-3-UPDOWN: Interface Serial3/0, changed state to up
*May 20 23:36:48.605: %LINK-3-UPDOWN: Interface Serial3/1, changed state to up
*May 20 23:36:48.606: %LINK-3-UPDOWN: Interface Serial3/2, changed state to up
*May 20 23:36:48.606: %LINK-3-UPDOWN: Interface Serial3/3, changed state to up
*May 20 23:36:49.533: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet0/0, changed state to up
*May 20 23:36:49.533: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet0/1, changed state to up
*May 20 23:36:49.546: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet0/2, changed state to up
*May 20 23:36:49.554: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet0/3, changed state to up
*May 20 23:36:49.562: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet1/0, changed state to up
*May 20 23:36:49.579: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet1/1, changed state to up
*May 20 23:36:49.579: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet1/2, changed state to up
*May 20 23:36:49.592: %LINEPROTO-5-UPDOWN: Line protocol on Interface Ethernet1/3, changed state to up
*May 20 23:36:49.592: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/0, changed state to up
*May 20 23:36:49.592: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/1, changed state to up
*May 20 23:37:09.976: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/0, changed state to down
*May 20 23:37:10.976: %LINK-5-CHANGED: Interface Serial2/0, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial2/1, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial2/2, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial2/3, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial3/0, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial3/1, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial3/2, changed state to administratively down
*May 20 23:37:11.652: %LINK-5-CHANGED: Interface Serial3/3, changed state to administratively down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/1, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/2, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial2/3, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial3/0, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial3/1, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial3/2, changed state to down
*May 20 23:37:12.655: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial3/3, changed state to down
*May 20 23:37:16.079: %SYS-5-RESTART: System restarted --
Cisco IOS Software, Solaris Software (I86BI_LINUXL2-ADVENTERPRISEK9-M), Experimental Version 15.1(20130726:213425) [dstivers-july26-2013-team_track 104]
Copyright (c) 1986-2013 by Cisco Systems, Inc.
Compiled Fri 26-Jul-13 15:56 by dstivers
Switch>
Switch>
[lachlan@Ante-lappy]$ 

TL;DR, Yes I can.

I re-did the same test and its still happening... i pastebin'd the logs again to help.

http://pastebin.com/raw.php?i=yh31qY3K

grossmj commented 10 years ago

Ok I will need more info I think, first do you have the rights on /tmp/ ?

 $ ls -al /tmp/netio1000/
total 40
drwxr-xr-x  2 grossmj grossmj  4096 May 20 16:42 .
drwxrwxrwt 20 root    root    36864 May 20 19:00 ..

Also, what do you have in /home/lachlan/GNS3/test/test-files/iou/device-1/iou.log and /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log AFTER you started the lab and it has stopped.

Thanks,

lachlanjholmes commented 10 years ago
[lachlan@Ante-lappy ~]$ ls -al /tmp/netio1000/
total 8
drwxr-xr-x  2 lachlan users 160 May 21 09:40 .
drwxrwxrwt 22 root    root  580 May 21 10:49 ..
srwxr-xr-x  1 lachlan users   0 May 21 09:40 1
-rw-------  1 lachlan users   6 May 21 09:40 1.lck
srwxr-xr-x  1 lachlan users   0 May 21 09:40 2
-rw-------  1 lachlan users   6 May 21 09:40 2.lck
srwxr-xr-x  1 lachlan users   0 May 21 09:40 ttyS1
srwxr-xr-x  1 lachlan users   0 May 21 09:40 ttyS2
[lachlan@Ante-lappy ~]$ 
---------------------------------------
[lachlan@Ante-lappy ~]$ cat /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log 
iouyap.c:open_tunnel_udp:689: DYNAMIPS 30001 : 127.0.0.1 : 10001
iouyap.c:open_tunnel_udp:689: DYNAMIPS 30003 : 127.0.0.1 : 10003
iouyap.c:foreign_listener:403: sendto: Connection refused
iouyap.c:foreign_listener:412: offending node removed
iouyap.c:foreign_listener:416: no nodes left!
iouyap.c:foreign_listener:424: Thread shutting down because of errors
iouyap.c:foreign_listener:403: sendto: Connection refused
iouyap.c:foreign_listener:412: offending node removed
iouyap.c:foreign_listener:416: no nodes left!
iouyap.c:foreign_listener:424: Thread shutting down because of errors
iouyap.c:main:1232: Received signal 15
iouyap.c:main:1259: Exiting
[lachlan@Ante-lappy ~]$ 
---------------------------------------
[lachlan@Ante-lappy ~]$ cat /home/lachlan/GNS3/test/test-files/iou/device-1/iou.log

So that is the details...

the cat of iou.log is actually empty.

grossmj commented 10 years ago

Thanks, I am starting to see what is the error. It comes from iouyap.

iouyap.c:foreign_listener:403: sendto: Connection refused

I bet the lab won't shutdown if you start it without the IOU nodes linked to other nodes.

So for some reason iouyap couldn't send packets to 127.0.0.1:10001 and 127.0.0.1:10003

Do you have a firewall and do you use the local server for both Dynamips and IOS on UNIX in the preferences?

grossmj commented 10 years ago

Ok, what do you have with netstat after you have loaded the lab but haven't started it yet?

$ netstat -uln

lachlanjholmes commented 10 years ago

Local Server for both.

Before:

[lachlan@Ante-lappy ~]$ netstat -uln
Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 192.168.1.50:68         0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 192.168.1.50:123        0.0.0.0:*                          
udp        0      0 192.168.3.64:123        0.0.0.0:*                          
udp        0      0 127.0.0.1:123           0.0.0.0:*                          
udp        0      0 0.0.0.0:123             0.0.0.0:*                          
udp        0      0 0.0.0.0:17736           0.0.0.0:*                          
udp        0      0 127.0.0.1:42717         0.0.0.0:*                          
udp        0      0 0.0.0.0:1900            0.0.0.0:*                          
udp        0      0 192.168.1.50:51286      0.0.0.0:*                          
udp        0      0 192.168.3.64:52651      0.0.0.0:*                          
udp        0      0 0.0.0.0:3838            0.0.0.0:*                          
udp6       0      0 fe80::fef8:aeff:fe4:123 :::*                               
udp6       0      0 fe80::290:f5ff:fef8:123 :::*                               
udp6       0      0 ::1:123                 :::*                               
udp6       0      0 :::123                  :::*                               

After opening GNS3 and opening the lab but not started yet:

[lachlan@Ante-lappy ~]$ netstat -uln
Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 192.168.1.50:68         0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 192.168.1.50:123        0.0.0.0:*                          
udp        0      0 192.168.3.64:123        0.0.0.0:*                          
udp        0      0 127.0.0.1:123           0.0.0.0:*                          
udp        0      0 0.0.0.0:123             0.0.0.0:*                          
udp        0      0 0.0.0.0:17736           0.0.0.0:*                          
udp        0      0 127.0.0.1:42717         0.0.0.0:*                          
udp        0      0 0.0.0.0:1900            0.0.0.0:*                          
udp        0      0 192.168.1.50:51286      0.0.0.0:*                          
udp        0      0 192.168.3.64:52651      0.0.0.0:*                          
udp        0      0 0.0.0.0:3838            0.0.0.0:*                          
udp6       0      0 fe80::fef8:aeff:fe4:123 :::*                               
udp6       0      0 fe80::290:f5ff:fef8:123 :::*                               
udp6       0      0 ::1:123                 :::*                               
udp6       0      0 :::123                  :::*                               

So they are the same.

When I start the lab, and it works temporarily:

Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 192.168.1.50:68         0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 192.168.1.50:123        0.0.0.0:*                          
udp        0      0 192.168.3.64:123        0.0.0.0:*                          
udp        0      0 127.0.0.1:123           0.0.0.0:*                          
udp        0      0 0.0.0.0:123             0.0.0.0:*                          
udp        0      0 0.0.0.0:49513           0.0.0.0:*                          
udp        0      0 0.0.0.0:49514           0.0.0.0:*                          
udp        0      0 0.0.0.0:17736           0.0.0.0:*                          
udp        0      0 127.0.0.1:42717         0.0.0.0:*                          
udp        0      0 0.0.0.0:1900            0.0.0.0:*                          
udp        0      0 192.168.1.50:51286      0.0.0.0:*                          
udp        0      0 192.168.3.64:52651      0.0.0.0:*                          
udp        0      0 0.0.0.0:3838            0.0.0.0:*                          
udp6       0      0 fe80::fef8:aeff:fe4:123 :::*                               
udp6       0      0 fe80::290:f5ff:fef8:123 :::*                               
udp6       0      0 ::1:123                 :::*                               
udp6       0      0 :::123                  :::*                               

Then when the IOU has crashed on the LAB.

[lachlan@Ante-lappy ~]$ netstat -uln
Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 192.168.1.50:68         0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 0.0.0.0:68              0.0.0.0:*                          
udp        0      0 192.168.1.50:123        0.0.0.0:*                          
udp        0      0 192.168.3.64:123        0.0.0.0:*                          
udp        0      0 127.0.0.1:123           0.0.0.0:*                          
udp        0      0 0.0.0.0:123             0.0.0.0:*                          
udp        0      0 0.0.0.0:17736           0.0.0.0:*                          
udp        0      0 127.0.0.1:42717         0.0.0.0:*                          
udp        0      0 0.0.0.0:1900            0.0.0.0:*                          
udp        0      0 192.168.1.50:51286      0.0.0.0:*                          
udp        0      0 192.168.3.64:52651      0.0.0.0:*                          
udp        0      0 0.0.0.0:3838            0.0.0.0:*                          
udp6       0      0 fe80::fef8:aeff:fe4:123 :::*                               
udp6       0      0 fe80::290:f5ff:fef8:123 :::*                               
udp6       0      0 ::1:123                 :::*                               
udp6       0      0 :::123                  :::*                               
grossmj commented 10 years ago

Sorry, actually I need netstat -un not netstat -uln

Thanks,

lachlanjholmes commented 10 years ago

Hang on, I'll get you that info right now.

Before starting the LAB:

[lachlan@Ante-lappy ~]$ netstat -un
Active Internet connections (w/o servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 127.0.0.1:10001         127.0.0.1:30001         ESTABLISHED
udp        0      0 127.0.0.1:10002         127.0.0.1:30002         ESTABLISHED
udp        0      0 127.0.0.1:10003         127.0.0.1:30003         ESTABLISHED
udp        0      0 127.0.0.1:10004         127.0.0.1:30004         ESTABLISHED
udp        0      0 127.0.0.1:10005         127.0.0.1:10006         ESTABLISHED
udp        0      0 127.0.0.1:10006         127.0.0.1:10005         ESTABLISHED
udp        0      0 127.0.0.1:10007         127.0.0.1:10008         ESTABLISHED
udp        0      0 127.0.0.1:10008         127.0.0.1:10007         ESTABLISHED
udp        0      0 127.0.0.1:10009         127.0.0.1:10010         ESTABLISHED
udp        0      0 127.0.0.1:10010         127.0.0.1:10009         ESTABLISHED
udp        0      0 127.0.0.1:10011         127.0.0.1:10012         ESTABLISHED
udp        0      0 127.0.0.1:10012         127.0.0.1:10011         ESTABLISHED

After starting the LAB:

[lachlan@Ante-lappy ~]$ netstat -un
Active Internet connections (w/o servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 127.0.0.1:10001         127.0.0.1:30001         ESTABLISHED
udp        0      0 127.0.0.1:10002         127.0.0.1:30002         ESTABLISHED
udp        0      0 127.0.0.1:10003         127.0.0.1:30003         ESTABLISHED
udp        0      0 127.0.0.1:10004         127.0.0.1:30004         ESTABLISHED
udp        0      0 127.0.0.1:10005         127.0.0.1:10006         ESTABLISHED
udp        0      0 127.0.0.1:10006         127.0.0.1:10005         ESTABLISHED
udp        0      0 127.0.0.1:10007         127.0.0.1:10008         ESTABLISHED
udp        0      0 127.0.0.1:10008         127.0.0.1:10007         ESTABLISHED
udp        0      0 127.0.0.1:10009         127.0.0.1:10010         ESTABLISHED
udp        0      0 127.0.0.1:10010         127.0.0.1:10009         ESTABLISHED
udp        0      0 127.0.0.1:10011         127.0.0.1:10012         ESTABLISHED
udp        0      0 127.0.0.1:10012         127.0.0.1:10011         ESTABLISHED
udp        0      0 127.0.0.1:30001         127.0.0.1:10001         ESTABLISHED
udp        0      0 127.0.0.1:30002         127.0.0.1:10002         ESTABLISHED
udp        0      0 127.0.0.1:30003         127.0.0.1:10003         ESTABLISHED
udp        0      0 127.0.0.1:30004         127.0.0.1:10004         ESTABLISHED
[lachlan@Ante-lappy ~]$ 

After LAB IOUs crashes:

[lachlan@Ante-lappy ~]$ netstat -un
Active Internet connections (w/o servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
udp        0      0 127.0.0.1:10001         127.0.0.1:30001         ESTABLISHED
udp        0      0 127.0.0.1:10002         127.0.0.1:30002         ESTABLISHED
udp        0      0 127.0.0.1:10003         127.0.0.1:30003         ESTABLISHED
udp        0      0 127.0.0.1:10004         127.0.0.1:30004         ESTABLISHED
udp        0      0 127.0.0.1:10005         127.0.0.1:10006         ESTABLISHED
udp        0      0 127.0.0.1:10006         127.0.0.1:10005         ESTABLISHED
udp        0      0 127.0.0.1:10007         127.0.0.1:10008         ESTABLISHED
udp        0      0 127.0.0.1:10008         127.0.0.1:10007         ESTABLISHED
udp        0      0 127.0.0.1:10009         127.0.0.1:10010         ESTABLISHED
udp        0      0 127.0.0.1:10010         127.0.0.1:10009         ESTABLISHED
udp        0      0 127.0.0.1:10011         127.0.0.1:10012         ESTABLISHED
udp        0      0 127.0.0.1:10012         127.0.0.1:10011         ESTABLISHED
[lachlan@Ante-lappy ~]$ 
grossmj commented 10 years ago

Ok, can you backup iouyap (should be in /usr/local/bin/) and put this one instead?

https://drive.google.com/file/d/0B558b5_SCiAHZ3VGR3VtVlpJVTg/edit?usp=sharing

lachlanjholmes commented 10 years ago

I did that.

I renamed the old one .old and then applied the same permissions that I had before.

Still no go. :confused:

It's OK, go a coffee and I'll come back to you if it happens in another lab.

grossmj commented 10 years ago

Argh, never give up, never surrender! ;) ... yes that's from a movie.

Ok, one last thing for now, what do you have this time in /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log?

Thanks,

lachlanjholmes commented 10 years ago
[lachlan@Ante-lappy ~]$ cat /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log 
iouyap.c:open_tunnel_udp:690: DYNAMIPS 30001 : 127.0.0.1 : 10001
iouyap.c:open_tunnel_udp:690: DYNAMIPS 30003 : 127.0.0.1 : 10003
iouyap.c:main:1233: Received signal 15
iouyap.c:main:1260: Exiting
[lachlan@Ante-lappy ~]$ 

is that what you are after?

grossmj commented 10 years ago

Yep, looks like you don't have the iouyap error anymore. Does your lab still stop after a while? how long is that period? finally, can you repost gns3 output?

Thanks,

lachlanjholmes commented 10 years ago

Hey, so its still not working.... as you can see from the logs its taking 8 seconds for the IOUs to stop working.

Maybe in the version number in the start of the applications it can show the github commit number so you know exactly what version us bleeding edge guys are running. just a idea :stuck_out_tongue:

chat to you tomorrow I'm sure its like 2 am in Canada.

[lachlan@Ante-lappy ~]$ gns3server 
GNS3 server version 1.0a5.dev2
Copyright (c) 2007-2014 GNS3 Technologies Inc.
Running with Python 3.4.0 and has PID 16163
[I 140521 15:27:10 main:74] current locale is en_AU.UTF-8
[W 140521 15:27:10 config:82] no configuration file could be found or read
[I 140521 15:27:10 base:126] dynamips module running with PID 16165
[I 140521 15:27:10 base:79] ZeroMQ client (dynamips) connecting to 127.0.0.1:47883
[I 140521 15:27:10 base:126] vpcs module running with PID 16168
[I 140521 15:27:10 base:79] ZeroMQ client (vpcs) connecting to 127.0.0.1:47883
[I 140521 15:27:10 server:214] ZeroMQ server listening to 127.0.0.1:47883
[I 140521 15:27:10 base:126] iou module running with PID 16169
[I 140521 15:27:10 base:79] ZeroMQ client (iou) connecting to 127.0.0.1:47883
Starting server on 0.0.0.0:8000 (Tornado v3.2, PyZMQ v14.1.1, ZMQ v4.0.4)
[I 140521 15:27:19 jsonrpc_websocket:118] Websocket client 2d75dc25-2652-4abe-894f-d2328be6fb38 connected
[I 140521 15:27:19 web:1728] 200 GET /version (127.0.0.1) 1.22ms
[I 140521 15:27:33 __init__:227] saving iourc file content to /tmp/tmpxduk6xch
[I 140521 15:27:33 __init__:235] iouyap path set to /usr/local/bin/iouyap
[I 140521 15:27:33 __init__:239] this server is local with working directory path to /home/lachlan/GNS3/test/test-files
[I 140521 15:27:33 iou_device:299] IOU IOU1 [id=1]: working directory changed to /home/lachlan/GNS3/test/test-files/iou/device-1
[I 140521 15:27:33 iou_device:123] IOU device IOU1 [id=1] has been created
[I 140521 15:27:33 __init__:297] this server is local
[I 140521 15:27:33 iou_device:299] IOU IOU2 [id=2]: working directory changed to /home/lachlan/GNS3/test/test-files/iou/device-2
[I 140521 15:27:33 iou_device:123] IOU device IOU2 [id=2] has been created
[I 140521 15:27:33 __init__:264] starting the hypervisor manager with Dynamips working directory set to '/home/lachlan/GNS3/test/test-files/dynamips'
[I 140521 15:27:33 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7200 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:33 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7200.txt', '-H', '7200']
[I 140521 15:27:33 hypervisor:208] Dynamips process logging to /tmp/tmp82ccxpgg
[I 140521 15:27:33 hypervisor:213] Dynamips started PID=16189
[I 140521 15:27:33 iou_device:904] IOU IOU1 [id=1]: startup_config set to startup-config
[I 140521 15:27:33 iou_device:904] IOU IOU2 [id=2]: startup_config set to startup-config
[I 140521 15:27:33 hypervisor_manager:525] Dynamips server ready after 0.3662 seconds
[I 140521 15:27:33 hypervisor_manager:548] hypervisor 0.0.0.0:7200 has successfully started
[I 140521 15:27:33 router:112] router c2691 "R1" [id=1] has been created
[I 140521 15:27:33 router:499] router "R1" [id=1]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:33 router:734] router "R1" [id=1]: sparse memory enabled
[I 140521 15:27:33 router:701] router "R1" [id=1]: mmap enabled
[I 140521 15:27:33 router:1114] router "R1" [id=1]: console port updated from 2001 to 2101
[I 140521 15:27:33 router:1222] router "R1" [id=1]: MAC address updated from c001.3f3d.0000 to c001.0b22.0000
[I 140521 15:27:33 router:70] creating a new ghost IOS file
[I 140521 15:27:33 router:499] router "Ghost" [id=0]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:33 router:969] router "Ghost" [id=0]: ghost status set to 1
[I 140521 15:27:33 router:924] router "Ghost" [id=0]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 router:380] router "Ghost" [id=0] has been started
[I 140521 15:27:34 router:390] router "Ghost" [id=0] has been stopped
[I 140521 15:27:34 router:347] router "Ghost" [id=0] has been deleted (including associated files)
[I 140521 15:27:34 router:969] router "R1" [id=1]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R1" [id=1]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7201 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:34 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7201.txt', '-H', '7201']
[I 140521 15:27:34 hypervisor:208] Dynamips process logging to /tmp/tmpdebnvlj2
[I 140521 15:27:34 hypervisor:213] Dynamips started PID=16207
[I 140521 15:27:34 hypervisor_manager:525] Dynamips server ready after 0.0106 seconds
[I 140521 15:27:34 hypervisor_manager:548] hypervisor 0.0.0.0:7201 has successfully started
[I 140521 15:27:34 router:112] router c2691 "R2" [id=2] has been created
[I 140521 15:27:34 router:499] router "R2" [id=2]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:34 router:734] router "R2" [id=2]: sparse memory enabled
[I 140521 15:27:34 router:701] router "R2" [id=2]: mmap enabled
[I 140521 15:27:34 router:1114] router "R2" [id=2]: console port updated from 2001 to 2102
[I 140521 15:27:34 router:1222] router "R2" [id=2]: MAC address updated from c002.3f4f.0000 to c002.0b35.0000
[I 140521 15:27:34 router:969] router "R2" [id=2]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R2" [id=2]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7202 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:34 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7202.txt', '-H', '7202']
[I 140521 15:27:34 hypervisor:208] Dynamips process logging to /tmp/tmpdc_5ssko
[I 140521 15:27:34 hypervisor:213] Dynamips started PID=16223
[I 140521 15:27:34 hypervisor_manager:525] Dynamips server ready after 0.0105 seconds
[I 140521 15:27:34 hypervisor_manager:548] hypervisor 0.0.0.0:7202 has successfully started
[I 140521 15:27:34 router:112] router c2691 "R3" [id=3] has been created
[I 140521 15:27:34 router:499] router "R3" [id=3]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:34 router:734] router "R3" [id=3]: sparse memory enabled
[I 140521 15:27:34 router:701] router "R3" [id=3]: mmap enabled
[I 140521 15:27:34 router:1114] router "R3" [id=3]: console port updated from 2001 to 2103
[I 140521 15:27:34 router:1222] router "R3" [id=3]: MAC address updated from c003.3f5f.0000 to c003.0b46.0000
[I 140521 15:27:34 router:969] router "R3" [id=3]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R3" [id=3]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7203 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:34 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7203.txt', '-H', '7203']
[I 140521 15:27:34 hypervisor:208] Dynamips process logging to /tmp/tmp3b8u4b8z
[I 140521 15:27:34 hypervisor:213] Dynamips started PID=16239
[I 140521 15:27:34 hypervisor_manager:525] Dynamips server ready after 0.0107 seconds
[I 140521 15:27:34 hypervisor_manager:548] hypervisor 0.0.0.0:7203 has successfully started
[I 140521 15:27:34 router:112] router c2691 "R4" [id=4] has been created
[I 140521 15:27:34 router:499] router "R4" [id=4]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:34 router:734] router "R4" [id=4]: sparse memory enabled
[I 140521 15:27:34 router:701] router "R4" [id=4]: mmap enabled
[I 140521 15:27:34 router:1114] router "R4" [id=4]: console port updated from 2001 to 2104
[I 140521 15:27:34 router:1222] router "R4" [id=4]: MAC address updated from c004.3f6f.0000 to c004.0b56.0000
[I 140521 15:27:34 router:969] router "R4" [id=4]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R4" [id=4]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7204 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:34 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7204.txt', '-H', '7204']
[I 140521 15:27:34 hypervisor:208] Dynamips process logging to /tmp/tmpgq_leypd
[I 140521 15:27:34 hypervisor:213] Dynamips started PID=16255
[I 140521 15:27:34 hypervisor_manager:525] Dynamips server ready after 0.0107 seconds
[I 140521 15:27:34 hypervisor_manager:548] hypervisor 0.0.0.0:7204 has successfully started
[I 140521 15:27:34 router:112] router c2691 "R5" [id=5] has been created
[I 140521 15:27:34 router:499] router "R5" [id=5]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:34 router:734] router "R5" [id=5]: sparse memory enabled
[I 140521 15:27:34 router:701] router "R5" [id=5]: mmap enabled
[I 140521 15:27:34 router:1114] router "R5" [id=5]: console port updated from 2001 to 2105
[I 140521 15:27:34 router:1222] router "R5" [id=5]: MAC address updated from c005.3f7f.0000 to c005.0f69.0000
[I 140521 15:27:34 router:969] router "R5" [id=5]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R5" [id=5]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 hypervisor_manager:544] creating new hypervisor 0.0.0.0:7205 with working directory /home/lachlan/GNS3/test/test-files/dynamips
[I 140521 15:27:34 hypervisor:205] starting Dynamips: ['/usr/bin/dynamips', '-N1', '-l', 'dynamips_log_7205.txt', '-H', '7205']
[I 140521 15:27:34 hypervisor:208] Dynamips process logging to /tmp/tmp0ftndwy4
[I 140521 15:27:34 hypervisor:213] Dynamips started PID=16271
[I 140521 15:27:34 hypervisor_manager:525] Dynamips server ready after 0.0107 seconds
[I 140521 15:27:34 hypervisor_manager:548] hypervisor 0.0.0.0:7205 has successfully started
[I 140521 15:27:34 router:112] router c2691 "R6" [id=6] has been created
[I 140521 15:27:34 router:499] router "R6" [id=6]: has a new IOS image set: "/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image"
[I 140521 15:27:34 router:734] router "R6" [id=6]: sparse memory enabled
[I 140521 15:27:34 router:701] router "R6" [id=6]: mmap enabled
[I 140521 15:27:34 router:1114] router "R6" [id=6]: console port updated from 2001 to 2106
[I 140521 15:27:34 router:1222] router "R6" [id=6]: MAC address updated from c006.3f8f.0000 to c006.0f79.0000
[I 140521 15:27:34 router:969] router "R6" [id=6]: ghost status set to 2
[I 140521 15:27:34 router:924] router "R6" [id=6]: ghost file set to c2691-adventerprisek9-mz.124-23.image-128.ghost
[I 140521 15:27:34 router:561] router "R1" [id=1]: has a startup-config set: "configs/R1.cfg"
[I 140521 15:27:34 router:796] router "R1" [id=1]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 router:561] router "R2" [id=2]: has a startup-config set: "configs/R2.cfg"
[I 140521 15:27:34 router:796] router "R2" [id=2]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 __init__:587] IOU1 [id=1] has allocated UDP port 30001 with host 0.0.0.0
[I 140521 15:27:34 router:561] router "R3" [id=3]: has a startup-config set: "configs/R3.cfg"
[I 140521 15:27:34 router:796] router "R3" [id=3]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 router:1307] router "R3" [id=3]: adapter NM-1FE-TX inserted into slot 1
[I 140521 15:27:34 router:561] router "R4" [id=4]: has a startup-config set: "configs/R4.cfg"
[I 140521 15:27:34 router:796] router "R4" [id=4]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 router:1307] router "R4" [id=4]: adapter NM-1FE-TX inserted into slot 1
[I 140521 15:27:34 __init__:587] IOU2 [id=2] has allocated UDP port 30002 with host 0.0.0.0
[I 140521 15:27:34 __init__:587] IOU1 [id=1] has allocated UDP port 30003 with host 0.0.0.0
[I 140521 15:27:34 router:561] router "R5" [id=5]: has a startup-config set: "configs/R5.cfg"
[I 140521 15:27:34 router:796] router "R5" [id=5]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 router:1307] router "R5" [id=5]: adapter NM-1FE-TX inserted into slot 1
[I 140521 15:27:34 router:561] router "R6" [id=6]: has a startup-config set: "configs/R6.cfg"
[I 140521 15:27:34 router:796] router "R6" [id=6]: idle-PC set to 0x60a48df4
[I 140521 15:27:34 router:1307] router "R6" [id=6]: adapter NM-1FE-TX inserted into slot 1
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto0 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R1 [id=1] has allocated UDP port 10001 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto1 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R2 [id=2] has allocated UDP port 10002 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto2 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R3 [id=3] has allocated UDP port 10003 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto3 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R4 [id=4] has allocated UDP port 10004 with host 0.0.0.0
[I 140521 15:27:34 __init__:587] IOU2 [id=2] has allocated UDP port 30004 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto4 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R3 [id=3] has allocated UDP port 10005 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto5 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R4 [id=4] has allocated UDP port 10006 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto6 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R5 [id=5] has allocated UDP port 10007 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto7 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R3 [id=3] has allocated UDP port 10008 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto8 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R6 [id=6] has allocated UDP port 10009 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto9 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R5 [id=5] has allocated UDP port 10010 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto10 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R4 [id=4] has allocated UDP port 10011 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:58] NIO UDP AUTO nio_udp_auto11 created with laddr=0.0.0.0, lport_start=10001, lport_end=20000
[I 140521 15:27:34 __init__:416] R6 [id=6] has allocated UDP port 10012 with host 0.0.0.0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto0 connected to 127.0.0.1:30001
[I 140521 15:27:34 router:1475] router "R1" [id=1]: NIO nio_udp_auto0 bound to port 0/0
[I 140521 15:27:34 iou_device:675] IOU IOU1 [id=1]: NIO UDP added to 0/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto1 connected to 127.0.0.1:30002
[I 140521 15:27:34 router:1475] router "R2" [id=2]: NIO nio_udp_auto1 bound to port 0/0
[I 140521 15:27:34 iou_device:675] IOU IOU2 [id=2]: NIO UDP added to 0/1
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto2 connected to 127.0.0.1:30003
[I 140521 15:27:34 router:1475] router "R3" [id=3]: NIO nio_udp_auto2 bound to port 0/0
[I 140521 15:27:34 iou_device:675] IOU IOU1 [id=1]: NIO UDP added to 0/1
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto3 connected to 127.0.0.1:30004
[I 140521 15:27:34 router:1475] router "R4" [id=4]: NIO nio_udp_auto3 bound to port 0/0
[I 140521 15:27:34 iou_device:675] IOU IOU2 [id=2]: NIO UDP added to 0/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto4 connected to 127.0.0.1:10006
[I 140521 15:27:34 router:1475] router "R3" [id=3]: NIO nio_udp_auto4 bound to port 0/1
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto5 connected to 127.0.0.1:10005
[I 140521 15:27:34 router:1475] router "R4" [id=4]: NIO nio_udp_auto5 bound to port 0/1
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto6 connected to 127.0.0.1:10008
[I 140521 15:27:34 router:1475] router "R5" [id=5]: NIO nio_udp_auto6 bound to port 1/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto7 connected to 127.0.0.1:10007
[I 140521 15:27:34 router:1475] router "R3" [id=3]: NIO nio_udp_auto7 bound to port 1/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto8 connected to 127.0.0.1:10010
[I 140521 15:27:34 router:1475] router "R6" [id=6]: NIO nio_udp_auto8 bound to port 0/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto9 connected to 127.0.0.1:10009
[I 140521 15:27:34 router:1475] router "R5" [id=5]: NIO nio_udp_auto9 bound to port 0/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto10 connected to 127.0.0.1:10012
[I 140521 15:27:34 router:1475] router "R4" [id=4]: NIO nio_udp_auto10 bound to port 1/0
[I 140521 15:27:34 nio_udp_auto:126] NIO UDP AUTO nio_udp_auto11 connected to 127.0.0.1:10011
[I 140521 15:27:34 router:1475] router "R6" [id=6]: NIO nio_udp_auto11 bound to port 1/0
[I 140521 15:28:12 iou_device:267] IOU IOU2 [id=2]: iouyap path set to /usr/local/bin/iouyap
[I 140521 15:28:12 iou_device:244] IOU IOU2 [id=2]: iourc file path set to /tmp/tmpxduk6xch
[I 140521 15:28:12 iou_device:441] IOU IOU2 [id=2]: NETMAP file created
[I 140521 15:28:12 iou_device:537] starting IOU: ['/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin', '-L', '-c', 'startup-config', '-l', '2']
[I 140521 15:28:12 iou_device:539] logging to /home/lachlan/GNS3/test/test-files/iou/device-2/iou.log
[I 140521 15:28:12 iou_device:546] IOU instance 2 started PID=16296
[I 140521 15:28:12 iou_device:452] starting ioucon for IOU instance IOU2 to accept Telnet connections on 0.0.0.0:4002
[I 140521 15:28:12 ioucon:358] Telnet server ready for connections on 0.0.0.0:4002
[I 140521 15:28:12 iou_device:422] IOU IOU2 [id=2]: iouyap.ini updated
[I 140521 15:28:12 iou_device:466] starting iouyap: ['/usr/local/bin/iouyap', '-q', '514']
[I 140521 15:28:12 iou_device:468] logging to /home/lachlan/GNS3/test/test-files/iou/device-2/iouyap.log
[I 140521 15:28:12 iou_device:475] iouyap started PID=16298
[I 140521 15:28:12 iou_device:267] IOU IOU1 [id=1]: iouyap path set to /usr/local/bin/iouyap
[I 140521 15:28:12 iou_device:244] IOU IOU1 [id=1]: iourc file path set to /tmp/tmpxduk6xch
[I 140521 15:28:12 iou_device:441] IOU IOU1 [id=1]: NETMAP file created
[I 140521 15:28:12 iou_device:537] starting IOU: ['/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin', '-L', '-c', 'startup-config', '-l', '1']
[I 140521 15:28:12 iou_device:539] logging to /home/lachlan/GNS3/test/test-files/iou/device-1/iou.log
[I 140521 15:28:12 iou_device:546] IOU instance 1 started PID=16309
[I 140521 15:28:12 iou_device:452] starting ioucon for IOU instance IOU1 to accept Telnet connections on 0.0.0.0:4001
[I 140521 15:28:12 ioucon:358] Telnet server ready for connections on 0.0.0.0:4001
[I 140521 15:28:12 iou_device:422] IOU IOU1 [id=1]: iouyap.ini updated
[I 140521 15:28:12 iou_device:466] starting iouyap: ['/usr/local/bin/iouyap', '-q', '513']
[I 140521 15:28:12 iou_device:468] logging to /home/lachlan/GNS3/test/test-files/iou/device-1/iouyap.log
[I 140521 15:28:12 iou_device:475] iouyap started PID=16311
[I 140521 15:28:12 router:380] router "R6" [id=6] has been started
[I 140521 15:28:12 router:380] router "R5" [id=5] has been started
[I 140521 15:28:12 router:380] router "R4" [id=4] has been started
[I 140521 15:28:13 router:380] router "R3" [id=3] has been started
[I 140521 15:28:13 router:380] router "R2" [id=2] has been started
[I 140521 15:28:13 router:380] router "R1" [id=1] has been started
[I 140521 15:28:18 ioucon:358] Telnet server ready for connections on 0.0.0.0:4002
[I 140521 15:28:18 ioucon:640] exiting...
[I 140521 15:28:18 iou_device:574] stopping iouyap PID=16311 for IOU instance 1
[I 140521 15:28:21 ioucon:640] exiting...
[I 140521 15:28:21 iou_device:574] stopping iouyap PID=16298 for IOU instance 2
^Z
[1]+  Stopped                 gns3server
[lachlan@Ante-lappy ~]$ 
[lachlan@Ante-lappy ~]$ gns3
GNS3 GUI version 1.0a5.dev2
Copyright (c) 2007-2014 GNS3 Technologies Inc.
[I 140521 15:27:17 main:74] current locale is en_AU.UTF-8
libpng warning: iCCP: Not recognizing known sRGB profile that has been edited
[I 140521 15:27:17 __init__:424] Dynamips module reset
[I 140521 15:27:17 __init__:397] IOU module reset
[I 140521 15:27:17 __init__:267] vpcs module reset
[I 140521 15:27:17 topology:136] topology has been reset
[I 140521 15:27:17 main_window:1006] creating temporary topology file: /tmp/gns3-8nnm4h60
[I 140521 15:27:17 main_window:1009] creating temporary project files directory: /tmp/gns3-8nnm4h60-files
[I 140521 15:27:17 __init__:173] local working directory for Dynamips module: /tmp/gns3-8nnm4h60-files
[I 140521 15:27:17 __init__:140] local working directory for IOU module: /tmp/gns3-8nnm4h60-files
[I 140521 15:27:17 __init__:84] local working directory for VPCS module: /tmp/gns3-8nnm4h60-files
libpng warning: iCCP: Not recognizing known sRGB profile that has been edited
[I 140521 15:27:19 servers:210] new local server connection ws://127.0.0.1:8000 registered
[I 140521 15:27:19 websocket_client:114] connected to 127.0.0.1:8000
[I 140521 15:27:19 main_window:785] use an already started local server on 127.0.0.1:8000
[I 140521 15:27:33 main_window:990] deleting temporary project files directory: /tmp/gns3-8nnm4h60-files
[I 140521 15:27:33 main_window:993] deleting temporary topology file: /tmp/gns3-8nnm4h60
[I 140521 15:27:33 __init__:424] Dynamips module reset
[I 140521 15:27:33 __init__:397] IOU module reset
[I 140521 15:27:33 __init__:267] vpcs module reset
[I 140521 15:27:33 topology:136] topology has been reset
[I 140521 15:27:33 main_window:965] loading project: /home/lachlan/GNS3/test/test.gns3
[I 140521 15:27:33 __init__:173] local working directory for Dynamips module: /home/lachlan/GNS3/test/test-files
[I 140521 15:27:33 __init__:140] local working directory for IOU module: /home/lachlan/GNS3/test/test-files
[I 140521 15:27:33 __init__:84] local working directory for VPCS module: /home/lachlan/GNS3/test/test-files
[I 140521 15:27:33 __init__:329] creating node <class 'gns3.modules.iou.iou_device.IOUDevice'>
[I 140521 15:27:33 __init__:154] adding server 127.0.0.1:8000 to IOU module
[I 140521 15:27:33 __init__:282] sending IOU settings to server 127.0.0.1:8000
[I 140521 15:27:33 __init__:235] opening iourc file: /home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/iourc
[I 140521 15:27:33 iou_device:44] IOU instance is being created
[I 140521 15:27:33 iou_device:524] iou device IOU1 is loading
[I 140521 15:27:33 __init__:329] creating node <class 'gns3.modules.iou.iou_device.IOUDevice'>
[I 140521 15:27:33 iou_device:44] IOU instance is being created
[I 140521 15:27:33 iou_device:524] iou device IOU2 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 __init__:187] adding server 127.0.0.1:8000 to Dynamips module
[I 140521 15:27:33 __init__:290] sending Dynamips settings to server 127.0.0.1:8000
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R1 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R2 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R3 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R4 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R5 is loading
[I 140521 15:27:33 __init__:331] creating node <class 'gns3.modules.dynamips.nodes.c2691.C2691'>
[I 140521 15:27:33 router:48] router c2691 is being created
[I 140521 15:27:33 router:851] router R6 is loading
[I 140521 15:27:33 iou_device:145] IOU instance setting up and updating path from '' to '/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin'
[I 140521 15:27:33 iou_device:145] IOU instance setting up and updating console from 'None' to '4001'
[I 140521 15:27:33 iou_device:145] IOU instance setting up and updating path from '' to '/home/lachlan/BTSync/Cisco/IOS/Cisco-IOU-L2-L3-Collection-v2/i86bi-linux-l2-adventerprisek9-15.1.bin'
[I 140521 15:27:33 iou_device:145] IOU instance setting up and updating console from 'None' to '4002'
[I 140521 15:27:33 iou_device:244] IOU1: updating startup_config from '' to 'startup-config'
[I 140521 15:27:33 iou_device:263] IOU device IOU1 has been updated
[I 140521 15:27:33 iou_device:544] router IOU1 has been loaded
[I 140521 15:27:33 iou_device:244] IOU2: updating startup_config from '' to 'startup-config'
[I 140521 15:27:33 iou_device:263] IOU device IOU2 has been updated
[I 140521 15:27:33 iou_device:544] router IOU2 has been loaded
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:282] router setting up and updating image from '' to '/home/lachlan/BTSync/Cisco/IOS/c2691/12.4/c2691-adventerprisek9-mz.124-23.image'
[I 140521 15:27:34 router:364] R1: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R1: updating startup_config from '' to 'configs/R1.cfg'
[I 140521 15:27:34 router:396] router R1 has been updated
[I 140521 15:27:34 router:871] router R1 has been loaded
[I 140521 15:27:34 link:55] adding link from R1 FastEthernet0/0 to IOU1 Ethernet0/0
[I 140521 15:27:34 router:364] R2: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R2: updating startup_config from '' to 'configs/R2.cfg'
[I 140521 15:27:34 router:396] router R2 has been updated
[I 140521 15:27:34 router:871] router R2 has been loaded
[I 140521 15:27:34 link:55] adding link from IOU2 Ethernet0/1 to R2 FastEthernet0/0
[I 140521 15:27:34 router:364] R3: updating slot1 from 'None' to 'NM-1FE-TX'
[I 140521 15:27:34 router:364] R3: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R3: updating startup_config from '' to 'configs/R3.cfg'
[I 140521 15:27:34 router:396] router R3 has been updated
[I 140521 15:27:34 router:871] router R3 has been loaded
[I 140521 15:27:34 link:55] adding link from R3 FastEthernet0/0 to IOU1 Ethernet0/1
[I 140521 15:27:34 router:364] R4: updating slot1 from 'None' to 'NM-1FE-TX'
[I 140521 15:27:34 router:364] R4: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R4: updating startup_config from '' to 'configs/R4.cfg'
[I 140521 15:27:34 router:396] router R4 has been updated
[I 140521 15:27:34 router:871] router R4 has been loaded
[I 140521 15:27:34 link:55] adding link from R4 FastEthernet0/0 to IOU2 Ethernet0/0
[I 140521 15:27:34 link:55] adding link from R3 FastEthernet0/1 to R4 FastEthernet0/1
[I 140521 15:27:34 router:364] R5: updating slot1 from 'None' to 'NM-1FE-TX'
[I 140521 15:27:34 router:364] R5: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R5: updating startup_config from '' to 'configs/R5.cfg'
[I 140521 15:27:34 router:396] router R5 has been updated
[I 140521 15:27:34 router:871] router R5 has been loaded
[I 140521 15:27:34 link:55] adding link from R5 FastEthernet1/0 to R3 FastEthernet1/0
[I 140521 15:27:34 router:364] R6: updating slot1 from 'None' to 'NM-1FE-TX'
[I 140521 15:27:34 router:364] R6: updating idlepc from '' to '0x60a48df4'
[I 140521 15:27:34 router:364] R6: updating startup_config from '' to 'configs/R6.cfg'
[I 140521 15:27:34 router:396] router R6 has been updated
[I 140521 15:27:34 router:871] router R6 has been loaded
[I 140521 15:27:34 link:55] adding link from R6 FastEthernet0/0 to R5 FastEthernet0/0
[I 140521 15:27:34 link:55] adding link from R4 FastEthernet1/0 to R6 FastEthernet1/0
[I 140521 15:28:12 iou_device:290] IOU2 has started
[I 140521 15:28:12 iou_device:290] IOU1 has started
[I 140521 15:28:12 router:423] R6 has started
[I 140521 15:28:12 router:423] R5 has started
[I 140521 15:28:13 router:423] R4 has started
[I 140521 15:28:13 router:423] R3 has started
[I 140521 15:28:13 router:423] R2 has started
[I 140521 15:28:13 router:423] R1 has started
[I 140521 15:28:21 iou_device:321] IOU1 has stopped
[I 140521 15:28:21 iou_device:321] IOU2 has stopped
^Z
[1]+  Stopped                 gns3
[lachlan@Ante-lappy ~]$ 
AdrianSimionov commented 10 years ago

Can you reproduce this on a different machine? If not, please give it a try.

grossmj commented 10 years ago

Still cannot understand what's wrong. As Adrian said, try to reproduce on a difference machine, if you can then just send me your zipped project.

Thanks,

lachlanjholmes commented 10 years ago

Hey,

So I've tested it in my Debian VM.

and it still doesn't work with the same issue.

Is there a email address or something I can send to you the tar file?

the .gns3 .net configs are 36 mb and github has a 10mb file limit ( its probably due to the IOS image that get saved locally in the sub-folders)

let me know... I don't know if github can do private messages?

:grin:

lachlanjholmes commented 10 years ago

please see email :smile:

grossmj commented 10 years ago

Hey,

I could reproduce the problem, excepting that it is almost instantaneous here. So the reason is... well IOU just crash because there is something it doesn't like! This may come from your startup-config which was made for an EtherSwitch router with NM-16ESW.

Here is the wonderful message when IOU crashed:

Cisco IOS Software, Solaris Software (I86BI_LINUXL2-ADVENTERPRISEK9-M), Experimental Version 15.1(20130726:213425) [dstivers-july26-2013-team_track 104]
Copyright (c) 1986-2013 by Cisco Systems, Inc.
Compiled Fri 26-Jul-13 15:56 by dstivers

Exception:
Frame pointer EBA1633C, PC = AB673A7

LINUX-EXT-SIGNAL: U_SIGSEGV(11), Process = Init
-Traceback= 0xAB673A7z 0x9EC8B9Dz 0x9EC8AF1z 0x9EC94CBz 0x9ECA481z 0x9ECB1BDz 0x9EC8421z 0x9EC60EFz 0x98EB99Ez 0x9FFABAEz 0x9FFAC54z 0x9FFABEAz 0x9FF9B30z 0x9FF7E36z 0x9FF85D9z 0x9FF7A88z 

EAX = EBA163F4  EBX = 0D726D58  ECX = EBA163F4  EDX = 00000008
ESP = EBA1633C  EBP = EBA16348  ESI = 00000020  EDI = 00000020
EFL = 00010202  EIP = 0AB673A7
CS = 0023  SS = 002B  DS = 002B  ES = 002B
FS = 0000  GS = 0063

Crashinfo file not found.

Buffered messages:
Queued messages:Segmentation fault

Anyway, just renaming the nvrams files (e.g. nvram_00001 to nvram_00001.bak) and I was able to boot IOU, then I got many errors while it was trying to apply the startup-config. What I would recommend is to configure IOU from scratch and not try to apply a startup-config with commands are not supported by IOU.

Cheers,