Enabled debug log
BeautifulSoup was not found. Geocoders assuming malformed markup will not work.
simplejson was not found. Geocoders relying on JSON parsing will not work.
FILE: C:\Documents and Settings\Master Chief\Application Data\D-RATS\d-rats.config
Loading locale `en'
Locale dir is: .\locale
Static position: 41.706130,-88.175030
C:\WinApps\HamRadio\DRats\D-RATS\library.zip\d_rats\mainwindow.py:247: GtkWarning: gtk_notebook_set_tab_label: assertion `GTK_IS_WIDGET (child)' failed
Appending ID-880H
Adding local file `C:\WinApps\HamRadio\DRats\D-RATS\Files\W9DBE_intro.txt'
Refreshing config...
Set GPS units to Imperial
Starting port ports_1 (ID-880H)
Software XON/XOFF control initialized
Registered session 0: control
Registered session 1: chat
Registered session 2: rpc
Registered session 3: Sniffer
GPS: False on @4800
Static position: 41.706130,-88.175030
[MAIL w9dbewl@gmail.com@pop.gmail.com] Periodic thread starting
[MAIL w9dbewl@gmail.com@pop.gmail.com] Querying pop.gmail.com:995
Unknown APRS symbol: `None'
Unknown APRS symbol: `None'
Unknown APRS symbol: `None'
Unknown APRS symbol: `None'
Unknown APRS symbol: `None'
Started serve_forever() thread
[MR] Starting message router thread
Running routing loop
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:1:5 W9DBE-10->CQCQCQ (1Online (D-RATS)...[16])
New session (existing) of type: <class 'd_rats.sessions.control.ControlSession'>
[SESSION 0]: Control
*** Unknown session type: ControlSession
New session (existing) of type: <class 'd_rats.sessions.chat.ChatSession'>
[SESSION 1]: Chat
*** Unknown session type: ChatSession
New session (existing) of type: <class 'd_rats.sessions.rpc.RPCSession'>
[SESSION 2]: RPC
*** Unknown session type: RPCSession
New session (existing) of type: <class 'd_rats.sessions.sniff.SniffSession'>
[SESSION 3]: Sniff
*** Unknown session type: SniffSession
### Unconverted data: äõÃ
Doing CQCQCQ ping on port ID-880H
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2-: 0:1:1 W9DBE-10->CQCQCQ (Ping Request...[12])
### Unconverted data: àãûòð )áããÂúæõÃ
Doing CQCQCQ ping on port ID-880H
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2-: 0:1:1 W9DBE-10->CQCQCQ (Ping Request...[12])
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2-: 0:1:2 W9DBE->W9DBE-10 (Running D-RATS 0.3.2...[35])
Got chat frame: DDT2-: 0:1:2 W9DBE->W9DBE-10 (Running D-RATS 0.3.2...[35])
PING OUT
Received block 0:2 for session `chat'
Got a block: DDT2+: 0:1:5 W9DBE->CQCQCQ (1Online (D-RATS)...[16])
Got chat frame: DDT2+: 0:1:5 W9DBE->CQCQCQ (1Online (D-RATS)...[16])
Received block 0:5 for session `chat'
Running routing loop
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 1:2:0 W9DBE->W9DBE-10 (RPCCheckMailsslFal...[54])
Received block 1:0 for session `rpc'
  <- {'': 'Callsign :'}
  -> W9DBE
  <- {'': 'Password :'}
  -> CMSTELNET
  <- {'': '[WL2K-2.0.2.1-B2FIHM$]'}
  -> [DRATS-0.3.2b5-B2FHIM$]
  <- {'': 'Perth CMS >'}
  -> FF
  <- {'': 'FC EM 2FZXU0FLM8V2 651 470 0'}
Creating message for FC EM 2FZXU0FLM8V2 651 470 0
  <- {'': 'FC EM JKF5U43Y6IN5 1979 1175 0\rF> BC'}
Creating message for FC EM JKF5U43Y6IN5 1979 1175 0
Returning 2 messages
  -> FS YY
Getting message...
Reading at 0
Found header at 0
Name is `FW: DuPage County ARES to w9dbe@winlink.org' offset 0

Reading at 48
Found data at 48
Reading data block 250 bytes
Reading at 300
Found data at 300
Reading data block 220 bytes
Reading at 522
Found eof at 522
Got data: 470 bytes
Running ['C:\\WinApps\\HamRadio\\DRats\\D-RATS\\libexec\\LZHUF_1.EXE', 'd', 'input', 'output'] in c:\docume~1\master~1\locals~1\temp\tmpxedjeh
LZHUF returned 0
Getting message...
Reading at 0
Found header at 0
Name is `FW: Hazardous Weather Outlook' offset 0

Reading at 34
Found data at 34
Reading data block 250 bytes
Reading at 286
Found data at 286
Reading data block 250 bytes
Reading at 538
Found data at 538
Reading data block 250 bytes
Reading at 790
Found data at 790
Reading data block 250 bytes
Reading at 1042
Found data at 1042
Reading data block 175 bytes
Reading at 1219
Found eof at 1219
Got data: 1175 bytes
Running ['C:\\WinApps\\HamRadio\\DRats\\D-RATS\\libexec\\LZHUF_1.EXE', 'd', 'input', 'output'] in c:\docume~1\master~1\locals~1\temp\tmprirdux
LZHUF returned 0
  -> FQ
Setting _auto_sender to dbe@dbelb.com (0)
Setting recipient to W9DBE (0)
Setting subject to FW: DuPage County ARES to w9dbe@winlink.org (1)
Setting message to From: Michael J. Schulz [mailto:w9mjs@dupageares.org] Sent: Monday, October 12, 2009 8:06 PMTo: Brent EnsignSubject: DuPage County ARESYes, send me a digital photo of head and shoulders for the ID  card.We have not started using the EchoLink node yet, but will in the future.The December 15 net is open if you would like to take another?MikeMichael J. SchulzW9MJSAssistant Emergency Coordinator - OperationsDuPage County Amateur Radio Emergency Service (ARES) (1)
Setting _auto_sender to dbe@dbelb.com (0)
Setting recipient to W9DBE (0)
Setting subject to FW: Hazardous Weather Outlook (1)
Setting message to From: sn@ds.bz [mailto:sn@ds.bz] Sent: Wednesday, November 11, 2009 3:30 AMTo: nwsstatements@w9dup.orgSubject: Hazardous Weather Outlook96120FLUS43 KLOT 110929HWOLOTILZ003>006-008-010>014-019>023-032-033-039-INZ001-002-010-011-019-120930-HAZARDOUS WEATHER OUTLOOK NATIONAL WEATHER SERVICE CHICAGO/ROMEOVILLE IL 329 AM CST WED NOV 11 2009  COUNTY SPECIFIC MESSAGE:  329 AM CST WED NOV 11 2009 /429 AM EST WED NOV 11 2009/  THIS HAZARDOUS WEATHER OUTLOOK IS FOR NORTH CENTRAL ILLINOIS... NORTHEAST ILLINOIS AND NORTHWEST INDIANA.  .DAY ONE...TODAY AND TONIGHT.  NO HAZARDOUS WEATHER IS EXPECTED AT THIS TIME.  .DAYS TWO THROUGH SEVEN...THURSDAY THROUGH TUESDAY.  NO HAZARDOUS WEATHER IS EXPECTED AT THIS TIME.  .SPOTTER INFORMATION STATEMENT...  SPOTTER ACTIVATION WILL NOT BE NECESSARY THROUGH TONIGHT.   COMPLETE FORECAST INFORMATION IS AVAILABLE ON THE NWS CHICAGO WEBPAGE: WEATHER.GOV/CHICAGO AFFECTED COUNTIES:IN ILLINOIS: BOONE, COOK, DEKALB, DUPAGE, FORD, GRUNDY, IROQUOIS, KANE,KANKAKEE, KENDALL, LA SALLE, LAKE, LEE, LIVINGSTON, MCHENRY, OGLE, WILL,WINNEBAGO.  IN INDIANA: BENTON, JASPER, LAKE, NEWTON, PORTER.***************Copyright 2009 DataSwitch Information Services.  All rights reserved. This material may not be published, broadcast, rewritten, or redistributed.  Any unauthorized redistribution of this message without the express written and signed consent of a DataSwitch officer constitutes a copyright violation and should be reported toadmin@dataswitch.com -- DataSwitch Information Services, Inc. You have received this bulletin as part of your paid subscription to a DataSwitch service.  To unsubscribe, send request with your account name to admin@dataswitch.com; or call 269-983-6271.*************** (1)
Job state: complete for 1: {'msg': 'Queued 2 messages', 'rc': '0'}
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:2:1 W9DBE-10->W9DBE (msgQueued 2 message...[26])
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 2:2:0 W9DBE->W9DBE-10 (RPCCheckMailsslFal...[54])
Received block 2:0 for session `rpc'
  <- {'': 'Callsign :'}
  -> W9DBE
  <- {'': 'Password :'}
  -> CMSTELNET
  <- {'': '[WL2K-2.0.2.1-B2FIHM$]'}
  -> [DRATS-0.3.2b5-B2FHIM$]
  <- {'': 'Perth CMS >'}
  -> FF
  <- {'': 'FQ'}
Returning 0 messages
  -> FS 
  -> FQ
Job state: complete for 2: {'msg': 'No messages', 'rc': '0'}
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 2:2:1 W9DBE-10->W9DBE (msgNo messagesrc0...[20])
Running routing loop
[MR] Routing message for W9DBE to W9DBE
{'W9DBE': <d_rats.station_status.Station instance at 0x01E2CF58>}
[MR] Sending C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml to W9DBE (via W9DBE)
Outgoing forms: ['C:\\Documents and Settings\\Master Chief\\Application Data\\D-RATS\\messages\\Outbox\\2FZXU0FLM8V2.xml']
Started form session
XFER STATUS: 
New limit is 4 (8/4), queueing 4
[MR] Routing message for W9DBE to W9DBE
[MR] Call W9DBE is busy
Session loop (None:Foo)
Deep sleep
Registered session 4: Foo
Awoke from deep sleep to some data
New limit is 4 (8/4), queueing 4
New session (out) of type: <class 'd_rats.sessions.form.FormTransferSession'>
[SESSION 4]: FormTransfer
[SESSION 4]: Message transfer of Foo started with W9DBE
Session loop (4:Foo)
Deep sleep
Waiting for session to open
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:0:9 W9DBE-10->W9DBE (Foo...[4])
Sent request, blocking...
[MAIL w9dbewl@gmail.com@pop.gmail.com] Querying pop.gmail.com:995
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:0:2 W9DBE->W9DBE-10 (...[2])
Signaled waiting session thread (l=4 r=4)
Received block 0:2 for session `control'
Awoke from deep sleep to some data
New limit is 4 (8/4), queueing 4
Session loop (4:Foo)
Deep sleep
New limit is 4 (8/4), queueing 4
Queuing 0 for send (4)
Waiting for block 0 to be ack'd
Awoke from deep sleep to some data
New limit is 4 (8/4), queueing 3
Sending 0
Requesting ack of blocks [0]
Waiting for block to be sent
Established session 4:4
Sending block: DDT2+: 0:4:4 W9DBE-10->W9DBE (<2FZXU0FLM8V2.xml...[20])
Average transmit rate: 169 bps
Block 0 is sent, waiting for ack
Block sent after: 0.390000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]:  [00%] (Total 20 B)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 11.6 sec
0 Not ACKED (probably canceled)
Session loop (4:Foo)
Outstanding data, short sleep
Waiting for start
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 10.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Waiting for response [00%] (Total 20 B)
Waiting for start
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 9.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Waiting for response [00%] (Total 20 B)
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 8.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
Waiting for start
[SESSION 4]: Waiting for response [00%] (Total 20 B)
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 7.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[1])
Received block 0:1 for session `Foo'
Got a block: DDT2+: 0:4:4 W9DBE->W9DBE-10 (OK...[2])
Received block 0:4 for session `Foo'
Got a block: DDT2+: 0:4:5 W9DBE->W9DBE-10 (...[1])
Received block 0:5 for session `Foo'
## Timeout for 53 bytes @ 80 bps: 12.0 sec
##  Remaining: 7.2 sec
Acked blocks: [0] (/1)
This ACKed every block
[SESSION 4]: Waiting for response [00%] (Total 20 B)
Got block 0
[SESSION 4]: Waiting for response [00%] (Total 20 B)
Acking block 0
Acking blocks [0] ({'': '\x00'})
Waiting OOO blocks: [0]
Queuing now in-order block 0: DDT2+: 0:4:4 W9DBE->W9DBE-10 (OK...[2])
Session loop (4:Foo)
Deep sleep
Waiting for start
[SESSION 4]: Negotiation Complete [00%] (Total 20 B)
<<<<function status_cb at 0x01DFEE70>>>> float division
<<<<function status_cb at 0x01DFEE70>>>> float division
[SESSION 4]: Negotiation Complete [03%] (Total 20 B)
[SESSION 4]: Negotiation Complete [00%] (Total 20 B)
New limit is 4 (8/4), queueing 4
Queuing 1 for send (4)
Waiting for block 1 to be ack'd
Awoke from deep sleep to some data
New limit is 4 (8/4), queueing 3
Sending 1
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:4:1 W9DBE-10->W9DBE (...[1])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Block 1 is sent, waiting for ack
Average transmit rate: 142 bps
Block sent after: 6.203000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 592 B)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 80 bps: 12.0 sec
##  Remaining: 4.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 80 bps: 12.0 sec
##  Remaining: 3.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 80 bps: 12.0 sec
##  Remaining: 2.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 80 bps: 12.0 sec
##  Remaining: 2.5 sec
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 69.6 bps
##  658 bytes in 9.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 2.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 1.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 0.6 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -0.4 sec
New limit is 4 (8/4), queueing 3
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 592 B) (1 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Average transmit rate: 126 bps
Block sent after: 5.797000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 1.0 KB) (1 retries)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 6.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 5.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 4.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 3.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 2.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 1.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: 0.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -0.7 sec
New limit is 4 (8/4), queueing 3
Didn't get last ack, asking again
Requesting ack of blocks [1]
Session loop (4:Foo)
Outstanding data, short sleep
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -1.7 sec
## Waiting for ACK, timeout in 7
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -2.7 sec
## Waiting for ACK, timeout in 6
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -3.7 sec
## Waiting for ACK, timeout in 5
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 69 bps: 13.1 sec
##  Remaining: -4.4 sec
## Waiting for ACK, timeout in 4
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 37.5 bps
##  658 bytes in 17.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 5.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 4.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 3.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 2.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 1.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 0.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: -0.2 sec
New limit is 4 (8/4), queueing 3
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 1.0 KB) (2 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Average transmit rate: 117 bps
Block sent after: 5.922000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 1.0 KB) (2 retries)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 17.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 16.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 15.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 37 bps: 24.3 sec
##  Remaining: 15.2 sec
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 72.1 bps
##  658 bytes in 9.1 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 2.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 1.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
Setting LatLon value: 41.706130
LatLon text: 41.706130
Setting LatLon value: -88.175030
LatLon text: -88.175030
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 0.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -0.5 sec
New limit is 4 (8/4), queueing 3
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 1.0 KB) (3 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
[MR] Call W9DBE is busy
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Average transmit rate: 114 bps
Block sent after: 5.828000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 2.0 KB) (3 retries)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 5.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 4.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 3.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 2.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 1.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 0.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -0.2 sec
New limit is 4 (8/4), queueing 3
Didn't get last ack, asking again
Requesting ack of blocks [1]
Session loop (4:Foo)
Outstanding data, short sleep
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -1.2 sec
## Waiting for ACK, timeout in 7
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -2.2 sec
## Waiting for ACK, timeout in 6
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -3.2 sec
## Waiting for ACK, timeout in 5
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -3.7 sec
## Waiting for ACK, timeout in 4
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 40.3 bps
##  658 bytes in 16.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 5.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 4.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 3.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 2.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 1.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 0.3 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: -0.7 sec
New limit is 3 (8/4), queueing 2
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 2.0 KB) (4 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Average transmit rate: 112 bps
Block sent after: 5.828000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 2.0 KB) (4 retries)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 15.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 14.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 13.8 sec
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 40 bps: 22.6 sec
##  Remaining: 13.6 sec
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 72.6 bps
##  658 bytes in 9.1 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 2.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 1.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 0.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -0.5 sec
New limit is 2 (8/4), queueing 1
Sending 1
Requesting ack of blocks [1]
[SESSION 4]: Sending [00%] 0.00 B/s (Total 2.0 KB) (5 retries)
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
Average transmit rate: 111 bps
Block sent after: 5.812000
Session loop (4:Foo)
Outstanding data, short sleep
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
[SESSION 4]: Sending [00%] 0.00 B/s (Total 3.0 KB) (5 retries)
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 5.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 4.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 3.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 2.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 1.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: 0.7 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -0.3 sec
New limit is 2 (8/4), queueing 1
Didn't get last ack, asking again
Requesting ack of blocks [1]
Session loop (4:Foo)
Outstanding data, short sleep
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -1.3 sec
## Waiting for ACK, timeout in 7
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -2.3 sec
## Waiting for ACK, timeout in 6
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -3.3 sec
## Waiting for ACK, timeout in 5
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 72 bps: 12.6 sec
##  Remaining: -3.9 sec
## Waiting for ACK, timeout in 4
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 39.9 bps
##  658 bytes in 16.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 5.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 4.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 3.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 2.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 1.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 0.4 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: -0.6 sec
New limit is 2 (8/4), queueing 1
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 3.0 KB) (6 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
[MR] Call W9DBE is busy
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
[MAIL w9dbewl@gmail.com@pop.gmail.com] Querying pop.gmail.com:995
Average transmit rate: 110 bps
Block sent after: 5.859000
Session loop (4:Foo)
Outstanding data, short sleep
[SESSION 4]: Sending [00%] 0.00 B/s (Total 3.0 KB) (6 retries)
Sending block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
### Unconverted data: 8àíáÛïÿ
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 16.0 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 15.0 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 14.0 sec
Session loop (4:Foo)
Outstanding data, short sleep
Got a block: DDT2-: 0:0:254 !->! (...[8])
Received frame for station `!'
Got a block: DDT2+: 0:4:1 W9DBE->W9DBE-10 (...[0])
Received block 0:1 for session `Foo'
## Timeout for 608 bytes @ 39 bps: 22.9 sec
##  Remaining: 13.8 sec
Acked blocks: [] (/1)
Block 1 outstanding, but not acked
This was not a full ACK
## Calculated rate for session 4: 72.7 bps
##  658 bytes in 9.0 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.5 sec
##  Remaining: 2.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.5 sec
##  Remaining: 1.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.5 sec
##  Remaining: 0.5 sec
Session loop (4:Foo)
Outstanding data, short sleep
## Timeout for 608 bytes @ 72 bps: 12.5 sec
##  Remaining: -0.5 sec
New limit is 2 (8/4), queueing 1
Sending 1
[SESSION 4]: Sending [00%] 0.00 B/s (Total 3.0 KB) (7 retries)
Requesting ack of blocks [1]
Waiting for block to be sent
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 1:4:4 W9DBE-10->W9DBE (xuSn1}Qj%...[572])
1 Not ACKED (probably canceled)
Got close request, joining thread...
Average transmit rate: 15 bps
Block sent after: 2.110000
[SESSION 4]: Sending [00%] 0.00 B/s (Total 4.0 KB) (7 retries)
Session loop (4:Foo)
Outstanding data, short sleep
Thread is done, continuing with close
Got close request
Flushing block: DDT2+: 0:4:5 W9DBE-10->W9DBE (...[1])
Sending End-of-Session
Sending block: DDT2+: 0:0:1 W9DBE-10->W9DBE (4...[1])
Sent, waiting for response
Setting LatLon value: 41.706130
LatLon text: 41.706130
Setting LatLon value: -88.175030
LatLon text: -88.175030
Sending End-of-Session
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:0:1 W9DBE-10->W9DBE (4...[1])
Sent, waiting for response
Sending End-of-Session
Sending warm-up: DDT2-: 0:0:254 !->! (...[8])
Sending block: DDT2+: 0:0:1 W9DBE-10->W9DBE (4...[1])
Sent, waiting for response
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
[MR] Call W9DBE is busy
Session closed because no response
Got close request, joining thread...
Thread is done, continuing with close
Got close request
[SESSION 4]: Failed to send file (incomplete) [00%] 0.00 B/s (Total 4.0 KB) (7 retries)
Setting ports_0: False,COM3,38400,False,False,IC-92AD
Setting ports_1: False,net:ref.d-rats.com:9000,,False,True,d-Rats Reflector
Setting ports_2: False,net:ref.d-rats.com:9001,,False,True,d-Rats Reflector 9001
Setting ports_3: True,COM9,9600,False,False,ID-880H
Setting ports_4: False,net:127.0.0.1:14550,38400,True,True,DPRS interface
Setting incoming_email_0: pop.gmail.com,w9dbewl@gmail.com,winlinkw9dbe15223,2,True,995,Form,True
Setting email_access_0: *,Both,
Setting ports_0: False,COM3,38400,False,False,IC-92AD
Setting ports_1: False,net:ref.d-rats.com:9000,,False,True,d-Rats Reflector
Setting ports_2: False,net:ref.d-rats.com:9001,,False,True,d-Rats Reflector 9001
Setting ports_3: True,COM9,9600,False,False,ID-880H
Setting ports_4: False,net:127.0.0.1:14550,38400,True,True,DPRS interface
Setting incoming_email_0: pop.gmail.com,w9dbewl@gmail.com,winlinkw9dbe15223,5,True,995,Form,True
Setting email_access_0: *,Both,
Refreshing config...
Set GPS units to Imperial
Stopping ID-880H
Stopping session `chat'
Got close request
Got close request
Stopping session `rpc'
Got close request
Got close request
Stopping session `Sniffer'
Got close request
Got close request
Starting port ports_3 (ID-880H)
Software XON/XOFF control initialized
Registered session 0: control
Registered session 1: chat
Registered session 2: rpc
Registered session 3: Sniffer
GPS: False on @4800
Static position: 41.706130,-88.175030
[MAIL w9dbewl@gmail.com@pop.gmail.com] Periodic thread starting
[MAIL w9dbewl@gmail.com@pop.gmail.com] Querying pop.gmail.com:995
Adding local file `C:\WinApps\HamRadio\DRats\D-RATS\Files\W9DBE_intro.txt'
Appending ID-880H
New session (existing) of type: <class 'd_rats.sessions.control.ControlSession'>
[SESSION 0]: Control
*** Unknown session type: ControlSession
New session (existing) of type: <class 'd_rats.sessions.chat.ChatSession'>
[SESSION 1]: Chat
*** Unknown session type: ChatSession
New session (existing) of type: <class 'd_rats.sessions.rpc.RPCSession'>
[SESSION 2]: RPC
*** Unknown session type: RPCSession
New session (existing) of type: <class 'd_rats.sessions.sniff.SniffSession'>
[SESSION 3]: Sniff
*** Unknown session type: SniffSession
[MAIL w9dbewl@gmail.com@pop.gmail.com] Thread ending
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
[MR] Call W9DBE is busy
Setting LatLon value: 41.706130
LatLon text: 41.706130
Setting LatLon value: -88.175030
LatLon text: -88.175030
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
{'W9DBE': <d_rats.station_status.Station instance at 0x01E75DC8>}
[MR] I think port ID-880H is busy
Running routing loop
Message C:\Documents and Settings\Master Chief\Application Data\D-RATS\messages\Outbox\2FZXU0FLM8V2.xml is locked, skipping
[MR] Routing message for W9DBE to W9DBE
{'W9DBE': <d_rats.station_status.Station instance at 0x01E75E40>}
[MR] I think port ID-880H is busy
Setting ports_0: False,COM3,38400,False,False,IC-92AD
Setting ports_1: False,net:ref.d-rats.com:9000,,False,True,d-Rats Reflector
Setting ports_2: False,net:ref.d-rats.com:9001,,False,True,d-Rats Reflector 9001
Setting ports_3: True,COM9,9600,False,False,ID-880H
Setting ports_4: False,net:127.0.0.1:14550,38400,True,True,DPRS interface
Setting incoming_email_0: pop.gmail.com,w9dbewl@gmail.com,winlinkw9dbe15223,2,True,995,Form,True
Setting email_access_0: *,Both,
Setting ports_0: False,COM3,38400,False,False,IC-92AD
Setting ports_1: False,net:ref.d-rats.com:9000,,False,True,d-Rats Reflector
Setting ports_2: False,net:ref.d-rats.com:9001,,False,True,d-Rats Reflector 9001
Setting ports_3: True,COM9,9600,False,False,ID-880H
Setting ports_4: False,net:127.0.0.1:14550,38400,True,True,DPRS interface
Setting incoming_email_0: pop.gmail.com,w9dbewl@gmail.com,winlinkw9dbe15223,5,True,995,Form,True
Setting email_access_0: *,Both,
Setting ports_0: False,net:ref.d-rats.com:9001,,False,True,d-Rats Reflector 9001
Setting ports_1: True,COM9,9600,False,False,ID-880H
Setting ports_2: False,COM3,38400,False,False,IC-92AD
Setting ports_3: False,net:ref.d-rats.com:9000,,False,True,d-Rats Reflector
Setting ports_4: False,net:127.0.0.1:14550,38400,True,True,DPRS interface
Setting incoming_email_0: pop.gmail.com,w9dbewl@gmail.com,winlinkw9dbe15223,5,True,995,Form,True
Setting email_access_0: *,Both,
Refreshing config...
Set GPS units to Imperial
Stopping ID-880H
Stopping session `chat'
Got close request
Got close request
Stopping session `rpc'
Got close request
Got close request
Stopping session `Sniffer'
Got close request
Got close request
Starting port ports_1 (ID-880H)
Software XON/XOFF control initialized
Registered session 0: control
Registered session 1: chat
Registered session 2: rpc
Registered session 3: Sniffer
GPS: False on @4800
Static position: 41.706130,-88.175030
[MAIL w9dbewl@gmail.com@pop.gmail.com] Periodic thread starting
[MAIL w9dbewl@gmail.com@pop.gmail.com] Querying pop.gmail.com:995
Adding local file `C:\WinApps\HamRadio\DRats\D-RATS\Files\W9DBE_intro.txt'
Appending ID-880H
New session (existing) of type: <class 'd_rats.sessions.control.ControlSession'>
[SESSION 0]: Control
*** Unknown session type: ControlSession
New session (existing) of type: <class 'd_rats.sessions.chat.ChatSession'>
[SESSION 1]: Chat
*** Unknown session type: ChatSession
New session (existing) of type: <class 'd_rats.sessions.rpc.RPCSession'>
[SESSION 2]: RPC
*** Unknown session type: RPCSession
New session (existing) of type: <class 'd_rats.sessions.sniff.SniffSession'>
[SESSION 3]: Sniff
*** Unknown session type: SniffSession
[MAIL w9dbewl@gmail.com@pop.gmail.com] Thread ending