[pskmail] Re: Problems with Server - Client

  • From: Robert Krasowski <rkrasowski@xxxxxxxxx>
  • To: "pskmail@xxxxxxxxxxxxx" <pskmail@xxxxxxxxxxxxx>
  • Date: Fri, 4 Nov 2011 16:31:19 -0700 (PDT)

Here we go some more interesting logs : CO3VT connected to me today and here 
what I see::

23:11 UTC Nov-4-2011: > Connect: CO3VT:1024 KB2PNM:24 8 

Using default modePSK500R=
We don't know that mode - asymlink reset to 0
Final connect status:  Asymmetric link:0 , tx mode: default , rx mode: default
23:11 UTC Nov-4-2011: Added CO3VT to link list
CO3VT->KB2PNM
23:11 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING CO3VT

MAXIDLE=10.8233724140352
4 acks left...
23:11 UTC Nov-4-2011: Added CO3VT to link list
CO3VT->KB2PNM
23:11 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING CO3VT

MAXIDLE=10.8233724140352
23:11 UTC Nov-4-2011: 
Send>APRS-IS:KB2PNM>PSKAPR:@042311z3543.59NP07948.71W&PSKmail 1.4.12  Server & 
BoatLink 10.148MHz & 14.111MHz

3 acks left...
23:12 UTC Nov-4-2011: Added CO3VT to link list
CO3VT->KB2PNM
23:12 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING CO3VT

MAXIDLE=10.8233724140352
2 acks left...
23:12 UTC Nov-4-2011: Added CO3VT to link list
CO3VT->KB2PNM
23:12 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING CO3VT

MAXIDLE=10.8233724140352
23:12 UTC Nov-4-2011: > Status : last=0 good=0 end=0 missing=
Server's rx s2n: 49.5 , averaged: 49.8333333333333 
Client's rx s2n: 100 , averaged: 66.6666666666667 
TX=default , RX=default , T=0 LQ=6 BL=5
OK, Status received
~ABORTSEND
23:12 UTC Nov-4-2011: Call CO3VT o.k.
23:12 UTC Nov-4-2011: Connected to CO3VT
Stationname =CO3VT
Mail count =-1
::
KB2PNM 1.4.12-23:12:39-IM1052>
Cannot find any mail



23:12 UTC Nov-4-2011: > Status : last=0 good=2 end=2 missing=
Server's rx s2n: 15.8 , averaged: 38.4888888888889 
Client's rx s2n: 100 , averaged: 77.7777777777778 
TX=default , RX=default , T=0 LQ=4 BL=5
23:13 UTC Nov-4-2011: > Status : last=0 good=2 end=2 missing=
Server's rx s2n: 42.2 , averaged: 39.7259259259259 
Client's rx s2n: 100 , averaged: 85.1851851851852 
Try Turbo upgrade of TX mode
TX=default , RX=default , T=0 LQ=0 BL=5
23:13 UTC Nov-4-2011: > Status : last=0 good=2 end=2 missing=
Server's rx s2n: 96.5 , averaged: 58.6506172839506 
Client's rx s2n: 100 , averaged: 90.1234567901235 
Try Turbo upgrade of TX mode
TX=default , RX=default , T=0 LQ=0 BL=6
23:13 UTC Nov-4-2011: > Status : last=0 good=2 end=2 missing=
Server's rx s2n: 83.5 , averaged: 66.9337448559671 


And second part with some errors:
TX=default , RX=default , T=0 LQ=0 BL=6
~LISTFILES
::
Your_files: 60
pings.log 2011-11-04 19:11 227
wwv.txt 2011-11-04 17:05 576

-end-

23:21 UTC Nov-4-2011: > Status : last=5 good=31 end=33 missing=@
Server's rx s2n: 82.8 , averaged: 66.0572182474771 
Client's rx s2n: 100 , averaged: 99.9970298403969 
Use of uninitialized value $lengthlastframe in numeric gt (>) at 
/usr/local/share/pskmail_server/arq.pm line 4925.
TX=default , RX=default , T=1 LQ=0 BL=6
23:21 UTC Nov-4-2011: > Status : last=5 good=33 end=33 missing=B
Server's rx s2n: 78 , averaged: 70.038145498318 
Client's rx s2n: 100 , averaged: 99.9980198935979 
Use of uninitialized value $lengthlastframe in numeric gt (>) at 
/usr/local/share/pskmail_server/arq.pm line 4925.
Try Turbo upgrade of TX mode
TX=default , RX=default , T=0 LQ=0 BL=6
23:21 UTC Nov-4-2011: > Status : last=5 good=33 end=33 missing=B
Server's rx s2n: 77 , averaged: 72.3587636655454 
Client's rx s2n: 100 , averaged: 99.9986799290653 
Use of uninitialized value $lengthlastframe in numeric gt (>) at 
/usr/local/share/pskmail_server/arq.pm line 4925.
Try Turbo upgrade of TX mode
TX=default , RX=default , T=0 LQ=0 BL=6
23:21 UTC Nov-4-2011: > Status : last=5 good=33 end=33 missing=B
Server's rx s2n: 76.7 , averaged: 73.8058424436969 
Client's rx s2n: 100 , averaged: 99.9991199527102 
Use of uninitialized value $lengthlastframe in numeric gt (>) at 
/usr/local/share/pskmail_server/arq.pm line 4925.
Try Turbo upgrade of TX mode
TX=default , RX=default , T=0 LQ=0 BL=6
23:21 UTC Nov-4-2011: 
Send>APRS-IS:KB2PNM>PSKAPR:@042321z3543.59NP07948.71W&PSKmail 1.4.12  Server & 
BoatLink 10.148MHz & 14.111MHz




Again I see problem with initial server TX mode, it is set up at default, 

Also during  the QSO there errors with undefined version $lengthastframe. Any 
way to correct this??

By the way I reinstall my server, I also reinstall my client, and tried two 
diffrent laptops with jPSKmail 1.4. 

Always the same result: client calling with request :  KB2PNM-6:1024 KB2PNM:24 
785b4321

Hmm, interesting 

Any advice??
Thanks 

Robert 

KB2PNM 




________________________________
From: Robert Krasowski <rkrasowski@xxxxxxxxx>
To: "pskmail@xxxxxxxxxxxxx" <pskmail@xxxxxxxxxxxxx>
Sent: Friday, November 4, 2011 4:12 PM
Subject: [pskmail] Re: Problems with Server - Client


Thanks John, 

so from what I understand 785b... on connect call from client  is request of 
the client to server to respond in PSK500 mode which is outside the list, is 
that correct??

problem is that no matter how I set up my client it sends :KB2PNM-6:1024 
KB2PNM:24 785b4321
I updated my server again with Modes (default is PSK250) In Prepare def. I have 
PSK250,PSK250R,PSK125R,MFSK32,THOR22,MFSK16,THOR8.
Modes are Mail/APRS and PSK250
So I do not know how to set up request from client for PSK250??

Am I the only one having problem like this??

Robert 
KB2PNM 





________________________________
From: John Douyere <vk2eta@xxxxxxxxx>
To: pskmail@xxxxxxxxxxxxx
Sent: Friday, November 4, 2011 3:13 PM
Subject: [pskmail] Re: Problems with Server - Client


Hi Robert,

There are two issues there:

1. The clientś connect string is not consistent as it requests a start-up mode 
outside of the list of modes it wants. This is the line saying:

17:46 UTC Nov-4-2011: > Connect: KB2PNM-6:1024 KB2PNM:24 785b4321

Ignoring the last 4 characters that make up the checksum, the mode list is 85b 
but the startup mode is 7 which is outside of the 85b list.

So I suspect that either on the client there has been a change of the list of 
modes which has not been updated before choosing the default mode, OR that just 
before connection a mode was selected from the drop down menu that was outside 
of the mode list. 

Either way, this is a condition that should be managed by the application 
before sending a connect.

In the mean time, can you try going back to the preferences in the client and 
re-do an update of the list and re-select the default mode. I suspect that 
should fix the issue.

Rein, Per,

In the Android version if I encounter that condition (default mode outside of 
list of modes) I adjust the startup mode to the lowest mode to ensure a 
reliable connection and then let the system adjust back up afterwards. Plus I 
alert the operator of that situation.

Maybe a similar logic could be applied or a pop-up windows requesting 
adjustment be presented in that situation.

2. The line saying Cannot connect to server socket err = No such file or 
directory: I don;t know where that is coming from but it did not seem to create 
an issue as all the exchanges with Fldigi and the APRS networked seem to have 
completed correctly.

Regards,

John



On Sat, Nov 5, 2011 at 5:03 AM, Robert Krasowski <rkrasowski@xxxxxxxxx> wrote:

HI, 
>
>This is very frustrating......
>
>
>Still testing Server and Client 
>
>
>
>Server 1.4.12
>Client jPSKmail 1.4
>
>
>
>
>My frq.txt file : 
>
>
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>10148000,10148000,10148000,14111000,14111000,
>PSK250,PSK250,PSK250,PSK250,PSK250,
>
>
>
>
>
>
>And here is what I am getting while turning on my server and try to connect ( 
>there is CO3VT there that was linking as well ) :
>
>
>
>
>### Server v. Pskmail_server 1.4.12. (C) 2011 PA0R
>17:44 UTC Nov-4-2011: Program start
>17:44 UTC Nov-4-2011: Connected to netherlands.aprs2.net port 1314
>Cannot connect to server socket err = No such file or directory
>Cannot connect to server socket
>jack server is not running or cannot be started
>POS=35N:079W
>Outside BigEar geo area...
>BigEar serverport:10148
>BigEar not available
>Scanning: 10148000,10148000,10148000,14111000,14111000,
>Offset = 0 minute(s)
>Pskmodes:PSK250,PSK250,PSK250,PSK250,PSK250,
>.
>17:44 UTC Nov-4-2011: 
>Listening to the radio
>initialized
>I: ParseMode: ARQ new modem set to PSK500R
>17:44 UTC Nov-4-2011: 
>Send>APRS-IS:KB2PNM>PSKAPR:@041744z3543.59NP07948.71W&PSKmail 1.4.12  Server & 
>BoatLink 10.148MHz & 14.111MHz
>
>I: ParseMode: ARQ new modem set to PSK250
>17:44 UTC Nov-4-2011: 
>Send>APRS-IS:KB2PNM>PSKAPR:@041744z3543.59NP07948.71W&PSKmail 1.4.12  Server & 
>BoatLink 10.148MHz & 14.111MHz
>
>I: ParseMode: ARQ new modem set to PSK250
>I: apply: RSID: BPSK250 @ 1017 Hz
>17:45 UTC Nov-4-2011: > Unproto: CO3VT><KB2PNM 
>
>17:45 UTC Nov-4-2011: Added CO3VT to list
>NEW ROUTE:CO3VT
>TIME=1320428713
>CO3VT->KB2PNM
>17:45 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING CO3VT
>
>I: apply: RSID: BPSK250 @ 1012 Hz
>Message_out:
>00uKB2PNM<>CO3VT F2E2
>-
>I: ParseMode: ARQ new modem set to PSK250
>I: apply: RSID: BPSK250 @ 1007 Hz
>I: ParseMode: ARQ new modem set to PSK250
>17:46 UTC Nov-4-2011: > Connect: KB2PNM-6:1024 KB2PNM:24 785b4321
>
>Use of uninitialized value $newtxmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4857.
>Use of uninitialized value $newtxmodenumber in numeric gt (>) at 
>/usr/local/share/pskmail_server/arq.pm line 4857.
>Use of uninitialized value $newtxmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4865.
>Use of uninitialized value $newtxmodenumber in print at 
>/usr/local/share/pskmail_server/arq.pm line 4870.
>Final connect status:  Asymmetric link:1 , tx mode: default , rx mode: PSK250
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Use of uninitialized value in array element at 
>/usr/local/share/pskmail_server/arq.pm line 5361.
>17:46 UTC Nov-4-2011: Added KB2PNM-6 to link list
>KB2PNM-6->KB2PNM
>17:46 UTC Nov-4-2011: Send>APRS-IS:KB2PNM>PSKAPR,TCPIP*::PSKAPR   :GATING 
>KB2PNM-6
>
>MAXIDLE=10.8701248168945
>I: ParseMode: ARQ new modem set to PSK250
>17:46 UTC Nov-4-2011: > Status : last=0 good=0 end=0 missing=
>Server's rx s2n: 97.7 , averaged: 65.9 
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Client's rx s2n: 100 , averaged: 66.6666666666667 
>Use of uninitialized value $currenttxmode in array element at 
>/usr/local/share/pskmail_server/arq.pm line 3788.
>Use of uninitialized value $currentmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4920.
>Use of uninitialized value $currentmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4925.
>TX=default , RX=PSK250 , T=0 LQ=0 BL=5
>Use of uninitialized value $currenttxmode in string ne at 
>/usr/local/share/pskmail_server/arq.pm line 3852.
>Use of uninitialized value $currentmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4920.
>Use of uninitialized value $currentmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4925.
>OK, Status received
>~ABORTSEND
>17:46 UTC Nov-4-2011: Call KB2PNM-6 o.k.
>17:46 UTC Nov-4-2011: Connected to KB2PNM-6
>Stationname =KB2PNM-6
>Mail count =4
>::
>KB2PNM 1.4.12-17:46:35-IM1414>
>4 mails.
>
>
>
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Use of uninitialized value in numeric gt (>) at 
>/usr/local/share/pskmail_server/rflinkserver.pl line 3783.
>I: ParseMode: ARQ new modem set to PSK250
>17:46 UTC Nov-4-2011: > Data:  1
>~Mp1167,
>
>17:46 UTC Nov-4-2011: > Status : last=1 good=1 end=1 missing=
>Server's rx s2n: 98.2 , averaged: 76.6666666666667 
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Client's rx s2n: 100 , averaged: 77.7777777777778 
>Use of uninitialized value $currenttxmode in array element at 
>/usr/local/share/pskmail_server/arq.pm line 3788.
>Use of uninitialized value $currentmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4920.
>Use of uninitialized value $currentmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4925.
>TX=default , RX=PSK250 , T=0 LQ=2 BL=6
>~Mp1167,
>OK:1
>::Auth ok...
>
>I: ParseMode: ARQ new modem set to PSK250
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Use of uninitialized value in numeric gt (>) at 
>/usr/local/share/pskmail_server/rflinkserver.pl line 3783.
>17:46 UTC Nov-4-2011: > Status : last=1 good=2 end=2 missing=
>Server's rx s2n: 97.8 , averaged: 83.7111111111111 
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Client's rx s2n: 100 , averaged: 85.1851851851852 
>Use of uninitialized value $currenttxmode in array element at 
>/usr/local/share/pskmail_server/arq.pm line 3788.
>Use of uninitialized value $currentmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4920.
>Use of uninitialized value $currentmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4925.
>Try Turbo upgrade of TX mode
>TX=default , RX=PSK250 , T=0 LQ=0 BL=6
>Use of uninitialized value $currenttxmode in string ne at 
>/usr/local/share/pskmail_server/arq.pm line 3852.
>Use of uninitialized value $currentmodenumber in array element at 
>/usr/local/share/pskmail_server/arq.pm line 4920.
>Use of uninitialized value $currentmodenumber in numeric lt (<) at 
>/usr/local/share/pskmail_server/arq.pm line 4925.
>Use of uninitialized value $speed in scalar chomp at 
>/usr/local/share/pskmail_server/arq.pm line 4778.
>Use of uninitialized value in addition (+) at 
>/usr/local/share/pskmail_server/arq.pm line 4797.
>Up-speed to TX:THOR8 , RX: PSK250
>I: ParseMode: ARQ new modem set to THOR8
>I: ParseMode: ARQ new modem set to PSK250
>17:47 UTC Nov-4-2011: > Status : last=1 good=2 end=2 missing=
>Server's rx s2n: 98.2 , averaged: 88.5407407407408 
>Upgrading RX speed 
>Client's rx s2n: 100 , averaged: 66.6666666666667 
>TX=THOR8 , RX=PSK250 , T=0 LQ=0 BL=5
>I: ParseMode: ARQ new modem set to THOR8
>I: ParseMode: ARQ new modem set to PSK250
>I: ParseMode: ARQ new modem set to PSK250
>2 Sending poll with havesoh: 0, maxidle: 10.3565583825111, Shortidle: 
>8.30987644195557, currentidle: 9 
>Set retry mode to TX=THOR8 , RX=PSK250R
>I: ParseMode: ARQ new modem set to THOR8
>
>
>
>
>Hmm, interesting and  idea what is the problem ??
>
>
>Robert 
>
>KB2PNM 

Other related posts: