Uploaded image for project: 'Spark'
  1. Spark
  2. SPARK-12617

socket descriptor leak killing streaming app

Attach filesAttach ScreenshotVotersWatch issueWatchersCreate sub-taskLinkCloneUpdate Comment AuthorReplace String in CommentUpdate Comment VisibilityDelete Comments
    XMLWordPrintableJSON

Details

    • Bug
    • Status: Resolved
    • Critical
    • Resolution: Fixed
    • 1.5.2, 1.6.0
    • 1.5.3, 1.6.1, 2.0.0
    • DStreams, PySpark
    • None
    • pyspark (python 2.6)

    Description

      There is a socket descriptor leakage in a pyspark streaming app when configured with batch interval more then 30 seconds. This is due to default timeout in py4j JavaGateway which (half-)closes CallbackConnection after 30 seconds of inactivity and creates new one next time. That connection doesn't get closed on the python CallbackServer side and keep piling up until it eventually blocks new connections.

      Steps to reproduce:

      • Submit attached bug.py to spark
      • Watch /tmp/bug.log to see the increasing total number of py4j callback connections of which 0 will ever be closed
        [BUG] py4j callback server port: 51282
        [BUG] py4j CB 0/0 closed
        ...
        [BUG] py4j CB 0/123 closed
        
      • You can confirm the reality by using lsof on the pyspark driver process:
        $ sudo lsof -p 39770 | grep CLOSE_WAIT | grep :51282
        python2.6 39770  das   94u  IPv4 138824906      0t0       TCP localhost.localdomain:51282->localhost.localdomain:60419 (CLOSE_WAIT)
        python2.6 39770  das   95u  IPv4 138867747      0t0       TCP localhost.localdomain:51282->localhost.localdomain:60745 (CLOSE_WAIT)
        python2.6 39770  das   96u  IPv4 138831829      0t0       TCP localhost.localdomain:51282->localhost.localdomain:32849 (CLOSE_WAIT)
        python2.6 39770  das   97u  IPv4 138890524      0t0       TCP localhost.localdomain:51282->localhost.localdomain:33184 (CLOSE_WAIT)
        python2.6 39770  das   98u  IPv4 138860190      0t0       TCP localhost.localdomain:51282->localhost.localdomain:33512 (CLOSE_WAIT)
        python2.6 39770  das   99u  IPv4 138860439      0t0       TCP localhost.localdomain:51282->localhost.localdomain:33854 (CLOSE_WAIT)
        ...
        
      • If you leave it running for long enough the CallbackServer will eventually become unable to accept new connections from the gateway and the app will crash:
        16/01/02 05:12:07 ERROR scheduler.JobScheduler: Error generating jobs for time 1451711400000 ms
        py4j.Py4JException: Error while obtaining a new communication channel
        ...
        Caused by: java.net.ConnectException: Connection timed out
                at java.net.PlainSocketImpl.socketConnect(Native Method)
                at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
                at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
                at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
                at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
                at java.net.Socket.connect(Socket.java:589)
                at java.net.Socket.connect(Socket.java:538)
                at java.net.Socket.<init>(Socket.java:434)
                at java.net.Socket.<init>(Socket.java:244)
                at py4j.CallbackConnection.start(CallbackConnection.java:104)
        

      Attachments

        Activity

          This comment will be Viewable by All Users Viewable by All Users
          Cancel

          People

            zsxwing Shixiong Zhu
            antonymayi Antony Mayi
            Votes:
            0 Vote for this issue
            Watchers:
            5 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Slack

                Issue deployment