[IRCServices Coding] fork: resource temporarily unavailable
Andrew Church
achurch at achurch.org
Thu Jul 18 01:07:53 PDT 2002
This looks like a memory leak. Does this happen on a network with
no traffic? Has anyone else noticed memory leaks or unusually high memory
usage?
--Andrew Church
achurch at achurch.org
http://achurch.org/
>after a seemingly random amount of time, services 5 takes up all process IDs
>on a system so that any other app trying to fork gets "resource temporarily
>unavailable" we thought it was my custom modules originally so we unloaded
>themthe problem continued.
>Then we thought it was the box, so we moved to a new provider
>and yet again we got the problem... so it must be in the core of services
>somewhere.
>It occurs on cygwin, redhat, Mandrake and Slackware to our knowledge usually
>in recv (log enclosed below):
>
>--[ SNIP ]--
>[May 31 00:10:12 2002] unknown message from server (:test.chatspike.net SMO
>o
>[May 31 00:10:12 2002] operserv/sline: warning: client IP addresses not
>available
>[May 31 00:10:12 2002] user: New maximum user count: 1
>[May 31 00:10:12 2002] Read error from server: Resource temporarily
>unavailable
>--[ /SNIP ]--
>
>--[ SNIP ]--
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] sockets: accept(4): Invalid argument
>[Jun 03 20:35:56 2002] Read error from server: Resource temporarily
>unavailable
>--[ /SNIP ]--
>
>The box eventually reaches the point in which we cant even SSH into the box
>as it cant fork a new process.
>then the only way to fix the problem is /os restart or wait for it to commit
>suicide that way.
>
>the errors occure from pre0 to present.
>
>Also under CYGWIN (not sure if related..)
>
>--[ SNIP ]--
>[Jun 28 17:22:40 2002] PANIC! buffer = :test.chatspike.net NOTICE AUTH :***
>Couldn't resolve your hostname; using your IP address instead
>[Jun 28 17:22:40 2002] Services terminating: Arithmetic exception
>--[ /SNIP ]--
>
>Here is a full debug of services starting
>
>--[ SNIP ]--
>[Jun 04 04:30:56.460154 2002] IRC Services 5.0pre0 starting up (options:
>debug)
>[Jun 04 04:30:57.508271 2002] debug: Loading language 0 from file
>`languages/en_us'
>[Jun 04 04:30:57.910403 2002] debug: Loading language 10 from file
>`languages/nl'
>[Jun 04 04:30:58.494873 2002] debug: Loading language 9 from file
>`languages/de'
>[Jun 04 04:30:58.953611 2002] debug: Loading language 8 from file
>`languages/it'
>[Jun 04 04:30:58.299709 2002] debug: Loading language 2 from file
>`languages/ja_euc'
>[Jun 04 04:30:59.710164 2002] debug: Loading language 3 from file
>`languages/ja_sjis'
>[Jun 04 04:30:59.055359 2002] debug: Loading language 5 from file
>`languages/pt'
>[Jun 04 04:30:59.507050 2002] debug: Loading language 4 from file
>`languages/es'
>[Jun 04 04:31:00.148606 2002] debug: Loading language 7 from file
>`languages/tr'
>[Jun 04 04:31:01.697643 2002] debug: Loaded languages
>[Jun 04 04:31:01.718681 2002] debug: Loading module `protocol/unreal'
>[Jun 04 04:31:01.925415 2002] debug: Successfully loaded module
>`protocol/unreal'
>[Jun 04 04:31:01.935691 2002] debug: Loading module `database/version4'
>[Jun 04 04:31:01.968551 2002] debug: Successfully loaded module
>`database/version4'
>[Jun 04 04:31:01.970623 2002] debug: Loading module `operserv/main'
>[Jun 04 04:31:01.071957 2002] debug: Successfully loaded module
>`operserv/main'
>[Jun 04 04:31:01.075053 2002] debug: Loading module `operserv/akill'
>[Jun 04 04:31:01.130590 2002] debug: Successfully loaded module
>`operserv/akill'
>[Jun 04 04:31:01.132072 2002] debug: Loading module `operserv/news'
>[Jun 04 04:31:01.200207 2002] debug: Successfully loaded module
>`operserv/news'
>[Jun 04 04:31:01.201676 2002] debug: Loading module `operserv/sessions'
>[Jun 04 04:31:01.240783 2002] debug: Successfully loaded module
>`operserv/sessions'
>[Jun 04 04:31:01.250335 2002] debug: Loading module `operserv/sline'
>[Jun 04 04:31:01.326200 2002] debug: Successfully loaded module
>`operserv/sline'
>[Jun 04 04:31:01.327671 2002] debug: Loading module `nickserv/main'
>[Jun 04 04:31:01.443053 2002] debug: Successfully loaded module
>`nickserv/main'
>[Jun 04 04:31:01.444523 2002] debug: Loading module `nickserv/access'
>[Jun 04 04:31:01.450464 2002] debug: Successfully loaded module
>`nickserv/access'
>[Jun 04 04:31:01.451872 2002] debug: Loading module `nickserv/autojoin'
>[Jun 04 04:31:01.455518 2002] debug: Successfully loaded module
>`nickserv/autojoin'
>[Jun 04 04:31:01.456924 2002] debug: Loading module `nickserv/link'
>[Jun 04 04:31:01.481702 2002] debug: Successfully loaded module
>`nickserv/link'
>[Jun 04 04:31:01.483103 2002] debug: Loading module `chanserv/main'
>[Jun 04 04:31:02.565011 2002] debug: Successfully loaded module
>`chanserv/main'
>[Jun 04 04:31:02.566516 2002] debug: Loading module `chanserv/access-levels'
>[Jun 04 04:31:02.572908 2002] debug: Successfully loaded module
>`chanserv/access-levels'
>[Jun 04 04:31:02.584062 2002] debug: Loading module `chanserv/access-xop'
>[Jun 04 04:31:02.601665 2002] debug: Successfully loaded module
>`chanserv/access-xop'
>[Jun 04 04:31:02.603084 2002] debug: Loading module `memoserv/main'
>[Jun 04 04:31:02.631097 2002] debug: Successfully loaded module
>`memoserv/main'
>[Jun 04 04:31:02.632525 2002] debug: Loading module `memoserv/ignore'
>[Jun 04 04:31:02.664631 2002] debug: Successfully loaded module
>`memoserv/ignore'
>[Jun 04 04:31:02.666258 2002] debug: Loading module `statserv/main'
>[Jun 04 04:31:02.332792 2002] statserv/main: StatServ initialised: Log
>Channel="#services", Nick="StatServ"
>[Jun 04 04:31:02.414299 2002] debug: Successfully loaded module
>`statserv/main'
>[Jun 04 04:31:02.415756 2002] debug: Loading module `httpd/main'
>[Jun 04 04:31:03.373919 2002] httpd/main: Listening on 127.0.0.1:8080
>[Jun 04 04:31:03.375956 2002] debug: Successfully loaded module `httpd/main'
>[Jun 04 04:31:03.377310 2002] debug: Loading module `httpd/auth-ip'
>[Jun 04 04:31:03.382979 2002] debug: Successfully loaded module
>`httpd/auth-ip'
>[Jun 04 04:31:03.384358 2002] debug: Loading module `httpd/auth-password'
>[Jun 04 04:31:03.406551 2002] debug: Successfully loaded module
>`httpd/auth-password'
>[Jun 04 04:31:03.407985 2002] debug: Loading module `httpd/dbaccess'
>[Jun 04 04:31:03.467954 2002] debug: Successfully loaded module
>`httpd/dbaccess'
>[Jun 04 04:31:03.469424 2002] debug: Loading module `misc/xml-export'
>[Jun 04 04:31:03.477162 2002] debug: Successfully loaded module
>`misc/xml-export'
>[Jun 04 04:31:03.478735 2002] debug: Loading module `misc/xml-import'
>[Jun 04 04:31:03.484250 2002] debug: Successfully loaded module
>`misc/xml-import'
>[Jun 04 04:31:03.495042 2002] debug: Loading module `misc/loveserv'
>[Jun 04 04:31:03.530735 2002] debug: Successfully loaded module
>`misc/loveserv'
>[Jun 04 04:31:04.532994 2002] debug: Loaded modules
>[Jun 04 04:31:10.407854 2002] Initiated connection to 127.0.0.1:7025
>[Jun 04 04:31:11.977804 2002] debug: Sent: PROTOCTL SJOIN SJOIN2 SJ3 NICKv2
>VHP VL NOQUIT UMODE2 TOKEN
>[Jun 04 04:31:11.980098 2002] debug: Sent: PASS :cheezypass
>[Jun 04 04:31:11.982204 2002] debug: Sent: SERVER services.chatspike.net 1
>:U0-*-254 ChatSpikes Services Server
>[Jun 04 04:31:11.984460 2002] debug: Sent: :services.chatspike.net TSCTL
>SVSTIME 1023161471
>[Jun 04 04:31:11.005110 2002] sockets: accept(4): Invalid argument
>[Jun 04 04:31:11.476130 2002] debug: Sent: NICK OperServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +oiSqd chatspike.net
>:Operator Service
>[Jun 04 04:31:11.478680 2002] debug: Sent: NICK Global 1 1023161471 services
>chatspike.net services.chatspike.net 0 +oiSqd chatspike.net :Global Service
>[Jun 04 04:31:11.480950 2002] debug: Sent: NICK NickServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +oSqd chatspike.net
>:Nickname Service
>[Jun 04 04:31:11.483447 2002] debug: Sent: NICK ChanServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +oSqd chatspike.net :Channel
>Service
>[Jun 04 04:31:11.485719 2002] debug: Sent: NICK MemoServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +oSqd chatspike.net :Memo
>Service
>[Jun 04 04:31:11.488062 2002] debug: Sent: NICK StatServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +iSqd chatspike.net
>:Statistics Service
>[Jun 04 04:31:11.490124 2002] debug: Sent: :StatServ JOIN #services
>[Jun 04 04:31:11.492266 2002] debug: Sent: :StatServ MODE #services +ao
>StatServ StatServ
>[Jun 04 04:31:11.496367 2002] debug: Sent: NICK LoveServ 1 1023161471
>services chatspike.net services.chatspike.net 0 +Sqd chatspike.net :Network
>Love Service - Feel the love!
>[Jun 04 04:31:12.498727 2002] debug: Sent: :LoveServ JOIN #services
>[Jun 04 04:31:12.500790 2002] debug: Sent: :LoveServ MODE #services +ao
>LoveServ LoveServ
>[Jun 04 04:31:12.502400 2002] debug: Received: :test.chatspike.net NOTICE
>AUTH :*** Looking up your hostname...
>[Jun 04 04:31:12.627324 2002] debug: sockets: read(0): Resource temporarily
>unavailable
>[Jun 04 04:31:12.629587 2002] Read error from server: Resource temporarily
>unavailable
>--[ /SNIP ]--
>
>it only bails that fast under CYGWIN, normally takes some time otherwise,
>but commands like OP become "temporarily unavailable"
>
>its also preventing the servers services arnt connected to from picking up
>modes from other servers.. also some times traffic goes 1 way, servers
>services are not connected too will pick up all data from all servers but
>the server services are connected to, so services are rendered "br0ked" to
>the rest of the network
>
>any1 else having these probs? any solutions? we are starting to get angry
>users ;)
>
>I hope i have given enuf detail here for some1 to make a solution, if u need
>anything else, just ask :)
>
>--
>Craig McLure
>Craig at chatspike.net
>Network Administrator of the ChatSpike IRC Network.
>ChatSpike, the users network! www.chatspike.net
>
>
>_________________________________________________________________
>Chat with friends online, try MSN Messenger: http://messenger.msn.com
>
>------------------------------------------------------------------
>To unsubscribe or change your subscription options, visit:
>http://www.ircservices.za.net/mailman/listinfo/ircservices-coding