[text] modified nonblock.t with timestamps and debugging

Viewer

copydownloadembedprintName: modified nonblock.t with timestamps and debugging
  1. #!perl
  2. # Before `make install' is performed this script should be runnable with
  3. # `make test'. After `make install' it should work as `perl t/nonblock.t'
  4.  
  5.  
  6. use strict;
  7. use warnings;
  8. use Net::SSLeay;
  9. use Socket;
  10. use IO::Socket::SSL;
  11. use IO::Select;
  12. use Errno qw( EWOULDBLOCK EAGAIN EINPROGRESS);
  13. do './testlib.pl' || do './t/testlib.pl' || die "no testlib";
  14.  
  15. if ( ! eval "use 5.006; use IO::Select; return 1" ) {
  16.     print "1..0 # Skipped: no support for nonblocking sockets\n";
  17.     exit;
  18. }
  19.  
  20. $|=1;
  21. print "1..27\n";
  22.  
  23. # first create simple non-blocking tcp-server
  24. my $ID = 'server';
  25. my $server = IO::Socket::INET->new(
  26.     Blocking => 0,
  27.     LocalAddr => '127.0.0.1',
  28.     LocalPort => 0,
  29.     Listen => 2,
  30. );
  31.  
  32. print "not ok: $!\n", exit if !$server; # Address in use?
  33. ok("Server Initialization");
  34.  
  35. my $saddr = $server->sockhost.':'.$server->sockport;
  36. my $ssock = $server->sockname;
  37.  
  38. defined( my $pid = fork() ) || die $!;
  39. if ( $pid == 0 ) {
  40.  
  41.     ############################################################
  42.     # CLIENT == child process
  43.     ############################################################
  44.  
  45.     close($server);
  46.     $ID = 'client';
  47.  
  48.     # fast: try connect_SSL immediately after sending plain text
  49.     #       connect_SSL should fail on the first attempt because server
  50.     #       is not ready yet
  51.     # slow: wait before calling connect_SSL
  52.     #       connect_SSL should succeed, because server was already waiting
  53.  
  54.     for my $test ( 'fast','slow' ) {
  55.  
  56.         # initial socket is unconnected, tcp, nonblocking
  57.         my $to_server = IO::Socket::INET->new( Proto => 'tcp', Blocking => 0 );
  58.  
  59.         # nonblocking connect of tcp socket
  60.         while (1) {
  61.             connect($to_server,$ssock ) && last;
  62.             if ( $!{EINPROGRESS} ) {
  63.                 diag( 'connect in progress' );
  64.                 IO::Select->new( $to_server )->can_write(30) && next;
  65.                 print "not ";
  66.                 last;
  67.             } elsif ( $!{EWOULDBLOCK} || $!{EAGAIN} ) {
  68.                 diag( 'connect not yet completed');
  69.                 # just wait
  70.                 select(undef,undef,undef,0.1);
  71.                 next;
  72.             } elsif ( $!{EISCONN} ) {
  73.                 diag('claims that socket is already connected');
  74.                 # found on Mac OS X, dunno why it does not tell me that
  75.                 # the connect succeeded before
  76.                 last;
  77.             }
  78.             diag( 'connect failed: '.$! );
  79.             print "not ";
  80.             last;
  81.         }
  82.         ok( "client tcp connect" );
  83.  
  84.         # work around (older?) systems where IO::Socket::INET
  85.         # cannot do non-blocking connect by forcing non-blocking
  86.         # again (we want to test non-blocking behavior of IO::Socket::SSL,
  87.         # not IO::Socket::INET)
  88.         $to_server->blocking(0);
  89.  
  90.         # send some plain text on non-ssl socket
  91.         my $pmsg = 'plaintext';
  92.         while ( $pmsg ne '' ) {
  93.             my $w = syswrite( $to_server,$pmsg );
  94.             if ( ! defined $w ) {
  95.                 if ( ! $!{EWOULDBLOCK} && ! $!{EAGAIN} ) {
  96.                     diag("syswrite failed with $!");
  97.                     print "not ";
  98.                     last;
  99.                 }
  100.                 IO::Select->new($to_server)->can_write(30) or do {
  101.                     diag("failed to get write ready");
  102.                     print "not ";
  103.                     last;
  104.                 };
  105.             } elsif ( $w>0 ) {
  106.                 diag("wrote $w bytes");
  107.                 substr($pmsg,0,$w,'');
  108.             } else {
  109.                 die "syswrite returned 0";
  110.             }
  111.         }
  112.         ok( "write plain text" );
  113.  
  114.         # let server catch up, so that it awaits my connection
  115.         # so that connect_SSL does not have to wait
  116.         sleep(5) if ( $test eq 'slow' );
  117.  
  118.         # upgrade to SSL socket w/o connection yet
  119.         if ( ! IO::Socket::SSL->start_SSL( $to_server,
  120.             SSL_startHandshake => 0,
  121.             SSL_verify_mode => 0,
  122.             SSL_key_file => "certs/server-key.enc",
  123.             SSL_passwd_cb => sub { return "bluebell" },
  124.         )) {
  125.             diag( 'start_SSL return undef' );
  126.             print "not ";
  127.         } elsif ( !UNIVERSAL::isa( $to_server,'IO::Socket::SSL' ) ) {
  128.             diag( 'failed to upgrade socket' );
  129.             print "not ";
  130.         }
  131.         ok( "upgrade client to IO::Socket::SSL" );
  132.  
  133.         # SSL handshake thru connect_SSL
  134.         # if $test eq 'fast' we expect one failed attempt because server
  135.         # did not call accept_SSL yet
  136.         my $attempts = 0;
  137.         while ( 1 ) {
  138.             $to_server->connect_SSL && last;
  139.             diag( $SSL_ERROR );
  140.             if ( $SSL_ERROR == SSL_WANT_READ ) {
  141.                 $attempts++;
  142.                 IO::Select->new($to_server)->can_read(30) && next; # retry if can read
  143.             } elsif ( $SSL_ERROR == SSL_WANT_WRITE ) {
  144.                 IO::Select->new($to_server)->can_write(30) && next; # retry if can write
  145.             }
  146.             diag( "failed to connect: $@" );
  147.             print "not ";
  148.             last;
  149.         }
  150.         ok( "connected" );
  151.  
  152.         if ( $test ne 'slow' ) {
  153.             print "not " if !$attempts;
  154.             ok( "nonblocking connect with $attempts attempts" );
  155.         }
  156.  
  157.         # send some data
  158.         # we send up to 500000 bytes, server reads first 10 bytes and then sleeps
  159.         # before reading more. In total server only reads 30000 bytes
  160.         # the sleep will cause the internal buffers to fill up so that the syswrite
  161.         # should return with EWOULDBLOCK+SSL_WANT_WRITE.
  162.         # the socket close should cause EPIPE or ECONNRESET
  163.  
  164.         my $msg = "1234567890";
  165.         $attempts = 0;
  166.         my $bytes_send = 0;
  167.  
  168.         # set send buffer to 8192 so it will definitely fail writing all 500000 bytes in it
  169.         # beware that linux allocates twice as much (see tcp(7))
  170.         # AIX seems to get very slow if you set the sndbuf on localhost, so don't to it
  171.         # https://rt.cpan.org/Public/Bug/Display.html?id=72305
  172.         if ( $^O !~m/aix/i ) {
  173.             eval q{
  174.                 setsockopt( $to_server, SOL_SOCKET, SO_SNDBUF, pack( "I",8192 ));
  175.                 diag( "sndbuf=".unpack( "I",getsockopt( $to_server, SOL_SOCKET, SO_SNDBUF )));
  176.             };
  177.         }
  178.  
  179.         my $test_might_fail;
  180.         if ( $@ ) {
  181.             # the next test might fail because setsockopt(... SO_SNDBUF...) failed
  182.             $test_might_fail = 1;
  183.         }
  184.  
  185.         my $can;
  186.         WRITE:
  187.         for( my $i=0;$i<50000;$i++ ) {
  188.             my $offset = 0;
  189.             while (1) {
  190.                 if ( $can && ! IO::Select->new($to_server)->$can(30)) {
  191.                     diag("fail $can");
  192.                     print "not ";
  193.                     last WRITE;
  194.                 };
  195.                 my $n = syswrite( $to_server,$msg,length($msg)-$offset,$offset );
  196.                 if ( !defined($n) ) {
  197.                     diag( "\$!=$! \$SSL_ERROR=$SSL_ERROR send=$bytes_send" );
  198.                     if ( $! == EWOULDBLOCK || $! == EAGAIN ) {
  199.                         if ( $SSL_ERROR == SSL_WANT_WRITE ) {
  200.                             diag( 'wait for write' );
  201.                             $can = 'can_write';
  202.                             $attempts++;
  203.                         } elsif ( $SSL_ERROR == SSL_WANT_READ ) {
  204.                             diag( 'wait for read' );
  205.                             $can = 'can_read';
  206.                         } else {
  207.                             $can = 'can_write';
  208.                         }
  209.                     } elsif ( $bytes_send > 30000 ) {
  210.                         diag( "connection closed" );
  211.                         last WRITE;
  212.                     }
  213.                     next;
  214.                 } elsif ( $n == 0 ) {
  215.                     diag( "connection closed" );
  216.                     last WRITE;
  217.                 } elsif ( $n<0 ) {
  218.                     diag( "syswrite returned $n!" );
  219.                     print "not ";
  220.                     last WRITE;
  221.                 }
  222.  
  223.                 $bytes_send += $n;
  224.                 if ( $n + $offset == 10 ) {
  225.                     last
  226.                 } else {
  227.                     $offset += $n;
  228.                     diag( "partial write of $n new offset=$offset" );
  229.                 }
  230.             }
  231.         }
  232.         ok( "syswrite" );
  233.  
  234.         if ( ! $attempts && $test_might_fail ) {
  235.             ok( " write attempts failed, but OK nevertheless because setsockopt failed" );
  236.         } else {
  237.             print "not " if !$attempts;
  238.             ok( "multiple write attempts" );
  239.         }
  240.  
  241.         print "not " if $bytes_send < 30000;
  242.         ok( "30000 bytes send" );
  243.     }
  244.  
  245. } else {
  246.  
  247.     ############################################################
  248.     # SERVER == parent process
  249.     ############################################################
  250.  
  251.     # pendant to tests in client. Where client is slow (sleep
  252.     # between plain text sending and connect_SSL) I need to
  253.     # be fast and where client is fast I need to be slow (sleep
  254.     # between receiving plain text and accept_SSL)
  255.  
  256.     foreach my $test ( 'slow','fast' ) {
  257.  
  258.         # accept a connection
  259.         my $can_read = IO::Select->new( $server )->can_read(30);
  260.         diag("tcp server socket is ".($can_read? "ready" : "NOT ready"));
  261.         my $from_client = $server->accept or print "not ";
  262.         ok( "tcp accept" );
  263.         $from_client || do {
  264.             diag( "failed to tcp accept: $!" );
  265.             next;
  266.         };
  267.  
  268.         # make client non-blocking!
  269.         $from_client->blocking(0);
  270.  
  271.         # read plain text data
  272.         my $buf = '';
  273.         while ( length($buf) <9 ) {
  274.             sysread( $from_client, $buf,9-length($buf),length($buf) ) && next;
  275.             die "sysread failed: $!" if $! != EWOULDBLOCK && $! != EAGAIN;
  276.             IO::Select->new( $from_client )->can_read(30);
  277.         }
  278.         $buf eq 'plaintext' || print "not ";
  279.         ok( "received plain text" );
  280.  
  281.         # upgrade socket to IO::Socket::SSL
  282.         # no handshake yet
  283.         if ( ! IO::Socket::SSL->start_SSL( $from_client,
  284.             SSL_startHandshake => 0,
  285.             SSL_server => 1,
  286.             SSL_verify_mode => 0x00,
  287.             SSL_ca_file => "certs/test-ca.pem",
  288.             SSL_use_cert => 1,
  289.             SSL_cert_file => "certs/client-cert.pem",
  290.             SSL_key_file => "certs/client-key.enc",
  291.             SSL_passwd_cb => sub { return "opossum" },
  292.         )) {
  293.             diag( 'start_SSL return undef' );
  294.             print "not ";
  295.         } elsif ( !UNIVERSAL::isa( $from_client,'IO::Socket::SSL' ) ) {
  296.             diag( 'failed to upgrade socket' );
  297.             print "not ";
  298.         }
  299.         ok( "upgrade to_client to IO::Socket::SSL" );
  300.  
  301.         sleep(5) if $test eq 'slow'; # wait until client calls connect_SSL
  302.  
  303.         # SSL handshake  thru accept_SSL
  304.         # if test is 'fast' (e.g. client is 'slow') we expect the first
  305.         # accept_SSL attempt to fail because client did not call connect_SSL yet
  306.         my $attempts = 0;
  307.         while ( 1 ) {
  308.             $from_client->accept_SSL && last;
  309.             if ( $SSL_ERROR == SSL_WANT_READ ) {
  310.                 $attempts++;
  311.                 IO::Select->new($from_client)->can_read(30) && next; # retry if can read
  312.             } elsif ( $SSL_ERROR == SSL_WANT_WRITE ) {
  313.                 $attempts++;
  314.                 IO::Select->new($from_client)->can_write(30) && next; # retry if can write
  315.             } else {
  316.                 diag( "failed to ssl accept ($test): $@" );
  317.                 print "not ";
  318.                 last;
  319.             }
  320.         }
  321.         ok( "ssl accept handshake done" );
  322.  
  323.         if ( $test eq 'fast' ) {
  324.             print "not " if !$attempts;
  325.             ok( "nonblocking accept_SSL with $attempts attempts" );
  326.         }
  327.  
  328.         # reading 10 bytes
  329.         # then sleeping so that buffers from client to server gets
  330.         # filled up and clients receives EWOULDBLOCK+SSL_WANT_WRITE
  331.  
  332.         IO::Select->new( $from_client )->can_read(30);
  333.         ( sysread( $from_client, $buf,10 ) == 10 ) || print "not ";
  334.         #diag($buf);
  335.         ok( "received client message" );
  336.  
  337.         sleep(5);
  338.         my $bytes_received = 10;
  339.  
  340.         # read up to 30000 bytes from client, then close the socket
  341.         my $can;
  342.         READ:
  343.         while ( ( my $diff = 30000 - $bytes_received ) > 0 ) {
  344.             if ( $can && ! IO::Select->new($from_client)->$can(30)) {
  345.                 diag("failed $can");
  346.                 print "not ";
  347.                 last READ;
  348.             }
  349.             my $n = sysread( $from_client,my $buf,$diff );
  350.             if ( !defined($n) ) {
  351.                 diag( "\$!=$! \$SSL_ERROR=$SSL_ERROR" );
  352.                 if ( $! == EWOULDBLOCK || $! == EAGAIN ) {
  353.                     if ( $SSL_ERROR == SSL_WANT_READ ) {
  354.                         $attempts++;
  355.                         $can = 'can_read';
  356.                     } elsif ( $SSL_ERROR == SSL_WANT_WRITE ) {
  357.                         $attempts++;
  358.                         $can = 'can_write';
  359.                     } else {
  360.                         $can = 'can_read';
  361.                     }
  362.                 } else {
  363.                     print "not ";
  364.                     last READ;
  365.                 }
  366.                 next;
  367.             } elsif ( $n == 0 ) {
  368.                 diag( "connection closed" );
  369.                 last READ;
  370.             } elsif ( $n<0 ) {
  371.                 diag( "sysread returned $n!" );
  372.                 print "not ";
  373.                 last READ;
  374.             }
  375.  
  376.             $bytes_received += $n;
  377.             #diag( "read of $n bytes total $bytes_received" );
  378.         }
  379.  
  380.         diag( "read $bytes_received ($attempts r/w attempts)" );
  381.         close($from_client);
  382.     }
  383.  
  384.     # wait until client exits
  385.     wait;
  386. }
  387.  
  388. exit;
  389.  
  390.  
  391.  
  392. sub ok { print "ok # [$ID] @_ - ".localtime()."\n"; }
  393. sub diag { print "# @_ - ".localtime()."\n" }
  394.  

Editor

You can edit this paste and save as new:


File Description
  • modified nonblock.t with timestamps and debugging
  • Paste Code
  • 21 May-2021
  • 11.09 Kb
You can Share it: