InterLinked1 / lbbs

Lightweight BBS For Linux - Bulletin Board System server software
GNU General Public License v2.0
35 stars 4 forks source link

Old machines without ANSI support cause strange behaviour #29

Closed marrold closed 3 months ago

marrold commented 3 months ago

I'm attempting to connect a BBC Micro (Emulator) up to the public Phreaknet BBS. It's from the early 1980s and doesn't support ANSI.

It asks me to hit enter twice. It then asks if I want ANSI support. When I hit N, it freezes for a while and then the connection closes.

It would be great if these old machines could be supported.

image

marrold commented 3 months ago

And the logs

[2024-03-28 09:02:08.165]   DEBUG[27]: socket.c:1067 __bbs_tcp_listener: Accepting new TELNET connection from 81.187.135.200
[2024-03-28 09:02:08.165]   DEBUG[27]: socket.c:1068 __bbs_tcp_listener: accepted fd = 35
[2024-03-28 09:02:08.166]   DEBUG[27]: node.c:312 __bbs_node_request: Allocated new node with ID 2 (lifetime ID 4)
[2024-03-28 09:02:08.166]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC WILL ECHO
[2024-03-28 09:02:08.166]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC WILL SUPPRESS GO AHEAD
[2024-03-28 09:02:08.166]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC WONT LINEMODE
[2024-03-28 09:02:08.316]   DEBUG[27]: socket.c:1435 bbs_poll: poll returned 0 on fd 35
[2024-03-28 09:02:08.317]   DEBUG[27]: net_telnet.c:105 telnet_read_command: poll returned 0: Success
[2024-03-28 09:02:08.317]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC DO NAWS
[2024-03-28 09:02:08.467]   DEBUG[27]: socket.c:1435 bbs_poll: poll returned 0 on fd 35
[2024-03-28 09:02:08.468]   DEBUG[27]: net_telnet.c:105 telnet_read_command: poll returned 0: Success
[2024-03-28 09:02:08.468]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC DO TERMINAL TYPE
[2024-03-28 09:02:08.618]   DEBUG[27]: socket.c:1435 bbs_poll: poll returned 0 on fd 35
[2024-03-28 09:02:08.619]   DEBUG[27]: net_telnet.c:105 telnet_read_command: poll returned 0: Success
[2024-03-28 09:02:08.619]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC DO TSPEED
[2024-03-28 09:02:08.770]   DEBUG[27]: socket.c:1435 bbs_poll: poll returned 0 on fd 35
[2024-03-28 09:02:08.771]   DEBUG[27]: net_telnet.c:105 telnet_read_command: poll returned 0: Success
[2024-03-28 09:02:08.771]   DEBUG[27]: net_telnet.c:328 telnet_handshake: Request to enable ECHO not yet acknowledged, retrying
[2024-03-28 09:02:08.771]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC WONT ECHO
[2024-03-28 09:02:08.772]   DEBUG[27]: net_telnet.c:75 telnet_send_command: Sent Telnet command: IAC WILL ECHO
[2024-03-28 09:02:08.922]   DEBUG[27]: socket.c:1435 bbs_poll: poll returned 0 on fd 35
[2024-03-28 09:02:08.923]   DEBUG[27]: net_telnet.c:105 telnet_read_command: poll returned 0: Success
[2024-03-28 09:02:08.930]   DEBUG[61]: thread.c:106 thread_register: Thread 61 spawned from handler               started by thread 27 at socket.c:1077 __bbs_tcp_listener()
[2024-03-28 09:02:08.931]   DEBUG[61]: node.c:2070 bbs_node_begin: Running BBS for node 2
[2024-03-28 09:02:08.931]    AUTH[61]: node.c:2071 bbs_node_begin: New TELNET connection to node 2 from 81.187.135.200:61117
[2024-03-28 09:02:08.931]   DEBUG[61]: socket.c:312 bbs_set_fd_tcp_nodelay: Disabled Nagle's algorithm on socket 35
[2024-03-28 09:02:08.932]   DEBUG[61]: term.c:116 bbs_node_set_input: Node 2 (fd 37): input now unbuffered, echo disabled
[2024-03-28 09:02:08.939]   DEBUG[62]: thread.c:106 thread_register: Thread 62 spawned from pty_master            started by thread 61 at pty.c:249 bbs_pty_allocate()
[2024-03-28 09:02:08.939]   DEBUG[62]: pty.c:510 pty_master: Starting PTY master for node 2: 36 => /dev/pts/2
[2024-03-28 09:02:11.934]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:11.935]   DEBUG[61]: node.c:1445 node_read_cursor_pos: No response to cursor position query after 3 seconds...
[2024-03-28 09:02:12.704]   DEBUG[38]: irc.c:534 irc_read: <= irc.libera.chat PING :tungsten.libera.chat
[2024-03-28 09:02:12.705]   DEBUG[38]: irc.c:575 irc_write: => irc.libera.chat [28] PONG :tungsten.libera.chat
[2024-03-28 09:02:15.938]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:19.942]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:23.946]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:26.443]   DEBUG[62]: pty.c:618 pty_master: Got CR, translating to CR LF for slave
[2024-03-28 09:02:26.444]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:26.444]   DEBUG[61]: node.c:1685 read_cursor_pos_response: Retrying cursor position query due to timeout (attempt 5)
[2024-03-28 09:02:26.444]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:26.445]   DEBUG[61]: socket.c:1783 bbs_node_read: Node 2: read 1 byte (10)
[2024-03-28 09:02:31.446]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:31.448]   DEBUG[61]: node.c:1464 node_read_cursor_pos: Incomplete response to cursor position query...
[2024-03-28 09:02:33.184]   DEBUG[62]: pty.c:618 pty_master: Got CR, translating to CR LF for slave
[2024-03-28 09:02:33.186]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:33.187]   DEBUG[61]: node.c:1685 read_cursor_pos_response: Retrying cursor position query due to timeout (attempt 6)
[2024-03-28 09:02:33.188]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:33.189]   DEBUG[61]: socket.c:1783 bbs_node_read: Node 2: read 1 byte (10)
[2024-03-28 09:02:38.193]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:02:38.194]   DEBUG[61]: node.c:1464 node_read_cursor_pos: Incomplete response to cursor position query...
[2024-03-28 09:02:38.194]    -- Failed to read cursor position query response, bad client or broken connection?
[2024-03-28 09:02:38.194]   -- Could not autodetect ANSI support for node 2
[2024-03-28 09:02:40.221]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:40.222]   DEBUG[61]: socket.c:1783 bbs_node_read: Node 2: read 1 byte (78)
[2024-03-28 09:02:45.360]   DEBUG[32]: mod_discord.c:555 on_presence_update: Presence update: guild=1115805342597652611, user=100707584054415360 (foxocube), status=online (desktop: , mobile: online, web: )
[2024-03-28 09:02:58.598]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 1
[2024-03-28 09:02:58.598]   DEBUG[61]: socket.c:1783 bbs_node_read: Node 2: read 1 byte (78)
[2024-03-28 09:03:25.110]   DEBUG[19]: net_irc.c:3707 ping_thread: Performed periodic ping of 1 client
[2024-03-28 09:03:25.111]   DEBUG[36]: irc.c:534 irc_read: <= 127.0.0.1 PING :1711616605
[2024-03-28 09:03:25.112]   DEBUG[34]: net_irc.c:3217 handle_client: 0x7f35dc000bb0 => PONG :1711616605
[2024-03-28 09:03:25.112]   DEBUG[36]: irc.c:575 irc_write: => 127.0.0.1 [18] PONG :1711616605
[2024-03-28 09:03:26.761]   DEBUG[32]: mod_discord.c:555 on_presence_update: Presence update: guild=1115805342597652611, user=325665577878290433 (henearxn), status=idle (desktop: , mobile: , web: idle)
[2024-03-28 09:03:28.622]   DEBUG[61]: socket.c:1653 bbs_node_poll: Node 2: poll returned 0
[2024-03-28 09:03:28.623]   -- Node 2 timed out due to inactivity
[2024-03-28 09:03:28.623]   DEBUG[61]: node.c:2042 node_handler_term: Exiting
[2024-03-28 09:03:28.624]   DEBUG[61]: node.c:2084 bbs_node_exit: Node 2 has ended its TELNET session
[2024-03-28 09:03:28.624]   DEBUG[61]: node.c:530 node_shutdown: Terminating node 2
[2024-03-28 09:03:28.624]   DEBUG[61]: term.c:116 bbs_node_set_input: Node 2 (fd 37): input now buffered, echo enabled
[2024-03-28 09:03:28.625]   DEBUG[62]: pty.c:812 pty_master: poll returned 1 (revent[1] = POLLNVAL)
[2024-03-28 09:03:28.625]   DEBUG[62]: pty.c:814 pty_master: PTY slave (server) closed the connection
[2024-03-28 09:03:28.625]   DEBUG[62]: pty.c:824 pty_master: PTY master exiting for node 2
[2024-03-28 09:03:28.625]   DEBUG[62]: thread.c:140 __thread_unregister: Thread 62 is exiting (must be joined)
[2024-03-28 09:03:28.625]   DEBUG[61]: thread.c:304 __bbs_pthread_join: Attempting to join thread 139869586167488 (LWP 62) at node.c:564 node_shutdown()
[2024-03-28 09:03:28.626]   DEBUG[61]: thread.c:135 __thread_unregister: Thread 62 has been joined by thread 61 at node.c:564 node_shutdown()
[2024-03-28 09:03:28.626]   DEBUG[61]: node.c:603 node_shutdown: Shutdown pending finalization for node 2
[2024-03-28 09:03:28.627]   DEBUG[61]: node.c:621 node_free: Node 2 now freed
[2024-03-28 09:03:28.627]   == Node 2 has exited
[2024-03-28 09:03:28.627]   DEBUG[61]: thread.c:133 __thread_unregister: Thread 61 is exiting (detached)