Subject: Re: Problems connecting to free.teranews.com via Pine 4.55 (fwd)

From: Birl <[email-masked]>
Date: Mon, 28 Jul 2003 10:06:03 -0400 (EDT)

Does Pine use the XOVER or XHDR command when connecting to NNTP servers?
Can I get Pine to do even more verbose debugging?

I've re-arragned the email below for top-to-bottom reading:

---------- Forwarded message ----------

> Hello TeraNews Admins:
>
> Im having trouble using (UNIX) Pine 4.55 to connect to
> free.teranews.com:119
>
> It used to work before (months ago), but now I cannot log in.  400
> error. I know Im entering the correct username and password.
>
>
>
> Here's the output from Pine's debug file:
>
>
>     ---- FOLDER LISTER ----
> IMAP 09:57:45 7/24 mm_log babble: Trying IP address [66.150.105.111]
> IMAP 09:57:56 7/24 mm_log babble: Trying IP address [66.150.105.111]
> === folders_for_sub called ====
>
>
>     ---- FOLDER LISTER ----
> About to open folder "comp.mail.sendmail"    inbox: "INBOX"
> Close - saved inbox state: max 1833
> IMAP 09:58:04 7/24 mm_log babble: Reusing connection to free.teranews.com
> IMAP 09:58:04 7/24 mm_log error: 200 Are you happy now?
> IMAP 09:58:07 7/24 mm_log error: Login aborted
>
>
>
>
>
> Dont know why the debug file mentions IMAP.  I specifically configured
> it for NNTP.
>
>
> I opened up a telnet session too, just to make sure I could get to port
> 119.  It worked.  I dont know anything about the NNTP protocol to send
> some test commands to the server.
>
>
> 4:36:18pm   Thu: Jul-24-2003 % telnet free.teranews.com 119
> Trying 66.150.105.111...
> Connected to free.teranews.com.
> Escape character is '^]'.
> 200 Welcome 155.247.27.102 cnt 1
>
>
>
> Look forward to your response.
>
> Thanks.
> Birl

Date: Fri, 25 Jul 2003 15:48:59 -0500 (EST)
From: [email-masked]
Subject: Re: Problems connecting to free.teranews.com via Pine 4.55

If it connected in the telnet session then you should be able to connect.
If the news client uses the xhdr command then you need to disable it..

Thanks,
Tera News Support.

---

From: Steve Hubert <[email-masked]>
Date: Mon, 28 Jul 2003 14:42:19 -0700 (Pacific Daylight Time)

> Can I get Pine to do even more verbose debugging?

Mark knows all the nitty-gritty about pine's NNTP stuff and he is not at
work for a while. Pine certainly does use the XOVER command. I don't know
about XHDR.

Start pine with more debugging with command line flags. You'll get a whole
lot with

         pine -dimap=4,verbose=9

You may want to leave the imap=4 but scale the verbosity back some. The
lines that look like

IMAP DEBUG ...

should give you all the NNTP commands used (despite the misleading "IMAP")
when you connect to the news server. Alternatively, you may be able to get
this information using the Journal command off the Main menu followed by
"DebugView" if it is included in the pine you are running.

Steve Hubert <[email-masked]>
Networks and Distributed Computing, Univ. of Washington, Seattle

---

From: Birl <[email-masked]>
Date: Tue, 29 Jul 2003 10:21:05 -0400 (EDT)

As it was written on Jul 28, thus Steve Hubert spake unto Pine Discussion Forum:

Steve:  Date: Mon, 28 Jul 2003 14:42:19 -0700 (Pacific Daylight Time)
Steve:  From: Steve Hubert <[email-masked]>
Steve:
Steve:  On Mon, 28 Jul 2003, Birl wrote:
Steve:
Steve:  > Does Pine use the XOVER or XHDR command when connecting to NNTP servers?
Steve:  > Can I get Pine to do even more verbose debugging?
Steve:
Steve:  Mark knows all the nitty-gritty about pine's NNTP stuff and he is not at
Steve:  work for a while. Pine certainly does use the XOVER command. I don't know
Steve:  about XHDR.
Steve:
Steve:  Start pine with more debugging with command line flags. You'll get a whole
Steve:  lot with
Steve:
Steve:           pine -dimap=4,verbose=9
Steve:
Steve:  You may want to leave the imap=4 but scale the verbosity back some. The
Steve:  lines that look like
Steve:
Steve:  IMAP DEBUG ...
Steve:
Steve:  should give you all the NNTP commands used (despite the misleading "IMAP")
Steve:  when you connect to the news server. Alternatively, you may be able to get
Steve:  this information using the Journal command off the Main menu followed by
Steve:  "DebugView" if it is included in the pine you are running.
Steve:
Steve:  Steve Hubert <[email-masked]>
Steve:  Networks and Distributed Computing, Univ. of Washington, Seattle

Thanks for the information Steve.  Here's additional information for Mark:

I used "pine -dimap=5,verbose=9" and tail'ed about the last 200 lines of
the debug file:

(Sidenote: One thing I noticed when I tried to set -dimap=9, it was
           dropped to 5.
           When I tried to set verbose=20, it stayed at 20 but no
           additional information was provided.)

    ---- FOLDER LISTER ----
busy_alarm(1, Formatting scroll text, 106a6c, 0)
build_folder_list: {free.teranews.com/nntp}#news.%s NULL
busy_alarm(1, Busy, 0, 0)
same_stream: {free.teranews.com/nntp}#news.%s == /var/mail/sbirl
same_stream: no dice
pine_mail_open: opening "{free.teranews.com/nntp}#news.x" (stream was NULL) flag=0x50  OP_HALFOPEN OP_SILENT
same_stream: {free.teranews.com/nntp}#news.x == NULL
same_stream: no dice
IMAP 09:45:45 7/29 mm_log babble: Trying IP address [66.150.105.111]
IMAP DEBUG 09:45:46 7/29: 200 Welcome 155.247.27.102 cnt 1
IMAP 09:45:46 7/29 mm_notify babble: -no folder- (nostream) : Welcome 155.247.27.102 cnt 1
IMAP DEBUG 09:45:46 7/29: LIST EXTENSIONS
IMAP DEBUG 09:45:46 7/29: 215 list active
IMAP DEBUG 09:45:46 7/29: MODE READER
IMAP DEBUG 09:45:46 7/29: .
build_folder_list: mail_open(free.teranews.com/nntp) OK.
LSUB "{free.teranews.com/nntp}#news.comp.mail.sendmail": delim: '.',
cancel_busy_alarm(-1)
cancel_busy_alarm(-1)
@@@@ current:1838
set_titlebar - style: 1  current message cnt:1  current_pl: 4  total_pl: 4
 0 ?       Help       0
 1 O       OTHER CMDS 0
 2 <       ClctnList  22
 3 >       [View Fldr 22
 4 P       PrevFldr   44
 5 N       NextFldr   44
 6 -       PrevPage   68
 7 Spc     NextPage   66
 8 A       Add        88
 9 D       Delete     88
10 R       Rename     110
11 W       WhereIs    110
row: -2, real_row: 38, column: 0
new mail called (0 0 1)
- adrbk_maintenance -
check_point(GoodTime)
******** new mail returning -1  ********
q_status_message(ALL of folder list)
output_message(ALL of folder list)
STATUS cmd:120, max:1, min0
cancel_busy_alarm(-1)
Read char returning: 13 RETURN
Read command returning: 13 RETURN
New_mail_count zeroed
PROCESS_CMD return: 1
About to open folder "comp.mail.sendmail"    inbox: "INBOX"
Close - saved inbox state: max 1838
busy_alarm(1, Opening "comp.mail.sendmail", 0, 1)
q_status_message(Opening "comp.mail.sendmail"     )
STATUS: diff:-1, displayed: 1059486346, now: 1059486347
d_q_status_message(ALL of folder list)
output_message(Opening "comp.mail.sendmail"     )
STATUS cmd:120, max:1, min0
pine_mail_open: opening "{free.teranews.com/nntp}#news.comp.mail.sendmail" flag=0x0
IMAP 09:45:47 7/29 mm_log babble: Reusing connection to free.teranews.com
IMAP DEBUG 09:45:47 7/29: GROUP comp.mail.sendmail
IMAP DEBUG 09:45:47 7/29: 200 Are you happy now?
IMAP 09:45:47 7/29 mm_log error: 200 Are you happy now?
q_status_message(200 Are you happy now?)
IMAP DEBUG 09:45:47 7/29: QUIT
IMAP DEBUG 09:45:47 7/29: 480 Username/password required
mm_login trial=0 user= service=nntp
d_q_status_message(Opening "comp.mail.sendmail"     )
output_message(200 Are you happy now?)
alarm_signal()
d_q_status_message(200 Are you happy now?)
mm_login: host=free.teranews.com
imap_get_passwd: checking user=sbirl alt=0 host=free.teranews.com
imap_get_passwd: no match
=== optionally_enter called ===
string:""  y:0  x:-3  length: 100 append: 0
passwd:1   prompt:"HOST: free.teranews.com (INSECURE)  USER: sbirl  ENTER PASSWORD: "   label:""
suspend_busy_alarm
 0 ^G      Help       0
 1 ^C      Cancel     0
 2 (null)  xxx        25
 3 Ret     Accept     22
 4 (null)  (null)     44
 5 (null)  (null)     44
 6 (null)  (null)     66
 7 (null)  (null)     66
 8 (null)  (null)     88
 9 (null)  (null)     88
10 (null)  (null)     110
11 (null)  (null)     110
row: -2, real_row: 38, column: 0
[snip]                                  <--- note my modification, password deleted
Read char returning: 13 RETURN
resume_busy_alarm
imap_set_passwd
imap_set_passwd: user=sbirl altflag=0
imap_set_passwd: host=free.teranews.com
imap_set_passwd: passwd=""              <--- note my modification, password deleted
IMAP DEBUG 09:45:52 7/29: AUTHINFO USER sbirl
IMAP DEBUG 09:45:52 7/29: 205 Bye
IMAP 09:45:52 7/29 mm_log warning: 205 Bye
q_status_message(205 Bye)
mm_login trial=1 user=sbirl service=nntp
output_message(205 Bye)
alarm_signal()
d_q_status_message(205 Bye)
mm_login: host=free.teranews.com
=== optionally_enter called ===
string:""  y:0  x:-3  length: 100 append: 0
passwd:1   prompt:"HOST: free.teranews.com (INSECURE)  USER: sbirl  ENTER PASSWORD: "   label:""
suspend_busy_alarm
 0 ^G      Help       0
 1 ^C      Cancel     0
 2 (null)  xxx        25
 3 Ret     Accept     22
 4 (null)  (null)     44
 5 (null)  (null)     44
 6 (null)  (null)     66
 7 (null)  (null)     66
 8 (null)  (null)     88
 9 (null)  (null)     88
10 (null)  (null)     110
11 (null)  (null)     110
row: -2, real_row: 38, column: 0
[snip]                                  <--- note my modification, password deleted
Read char returning: 13 RETURN
resume_busy_alarm
imap_set_passwd
IMAP DEBUG 09:45:56 7/29: AUTHINFO USER sbirl
IMAP 09:45:56 7/29 mm_log warning: 400 NNTP connection broken (command)
q_status_message(400 NNTP connection broken (command))
Opened folder 0 "nil" (context: "{free.teranews.com/nntp}#news.%s")
cancel_busy_alarm(-1)
Old folder: "INBOX"
Reactivate inbox 1838 1838 59f3e0
conf_scroll_screen()
new mail called (0 2 3)
set_titlebar - style: 1  current message cnt:1  current_pl: 0  total_pl: 0
output_message(400 NNTP connection broken (command))
STATUS cmd:120, max:5, min3
 0 ?       Help       0
 1 O       OTHER CMDS 0
 2 <       Main Menu  22
 3 >       [View Cltn 22
 4 P       PrevCltn   44
 5 N       NextCltn   44
 6 -       PrevPage   68
 7 Spc     NextPage   66
 8 (null)  (null)     88
 9 (null)  (null)     88
10 (null)  (null)     111
11 W       WhereIs    110
row: -2, real_row: 38, column: 0
cancel_busy_alarm(-1)
Read char returning: 81 Q
Read command returning: 81 Q
New_mail_count zeroed

The password is valid and at the time entered in correctly.  As I told the
TeraNews administrators, my telnet session was successful: (password
masked)

# telnet free.teranews.com nntp
Trying 66.150.105.111...
Connected to free.teranews.com.
Escape character is '^]'.
200 Welcome 155.247.27.102 cnt 1
AUTHINFO USER sbirl
381 More authentication information required
AUTHINFO PASS **************
281 Authentication accepted: cnt: 1
HELP
100 Legal commands
  authinfo user Name|pass Password
  article [MessageID|Number]
  body [MessageID|Number]
  check MessageID
  date
  group newsgroup
  head [MessageID|Number]
  help
  ihave
  last
  list [active|active.times|newsgroups|subscriptions]
  listgroup newsgroup
  mode stream
  mode reader
  newgroups yymmdd hhmmss [GMT] [<distributions>]
  newnews newsgroups yymmdd hhmmss [GMT] [<distributions>]
  next
  post
  slave
  stat [MessageID|Number]
  takethis MessageID
  xgtitle [group_pattern]
  xhdr header [range|MessageID]
  xover [range]
  xpat header range|MessageID pat [morepat...]
.
quit
205 Bye
Connection to free.teranews.com closed by foreign host.

In the meantime, I'll see if the problem is fixed in the latest version.

Thanks again
-- Birl