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

Editor

You can edit this paste and save as new:


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