Anope IRC Services
Anope Support => 2.0.x/1.9.x Support => Topic started by: Mike on April 23, 2009, 11:37:53 PM
-
Services have been running fine for the last week after upgrading to 1.9, but today they dropped and i see this in the logs
[Apr 23 18:08:35 2009] PANIC! buffer = :468AAAAHB PRIVMSG 00AAAAAAD :SOP #ThreadofHope del unc_2009champs
[Apr 23 18:08:35 2009] Backtrace: not available on this platform
[Apr 23 18:08:35 2009] Services terminating on signal 11
edit: FreeBSD 7.1 and Inspircd1.2
-
Ok i have been keeping an eye on this an trying to figure it out, but i dont know what it is, it was fine before but its not fine now.. everytime someone tries to delete and AOP or SOP it crashes with PANIC Buffer backtrack now available on this system and i dont get it, it worked frine for 2 weeks. and then it netsplits and leaves :( bplease does anyone have a way to help me fix this i have tons of registered nicks i dont want lose.
-
Can you run it with ./services -support and pastebin the debug log again? Get it to crash while it's in debug.
-
[Apr 24 01:09:25.635097 2009] debug: Received: ERROR :Command (QUIT) from unknown prefix (souljah4christ)! Dropping link.
[Apr 24 01:09:25.635146 2009] debug: unknown message from server (ERROR :Command (QUIT) from unknown prefix (souljah4christ)! Dropping link.)
[Apr 24 01:09:25.635198 2009] debug: Emitting event "db_saving" (1 args)
[Apr 24 01:09:25.635212 2009] debug: Saving FFF databases
[Apr 24 01:09:25.637414 2009] debug: Emitting event "db_saving" (1 args)
[Apr 24 01:09:25.637444 2009] debug: Emitting event "shutdown" (1 args)
[Apr 24 01:09:25.637459 2009] Read error from server: Unknown error: 0 (error num: 0)
[Apr 24 01:09:25.637511 2009] debug: Sent: :00A SQUIT services.chatcitizen.com :Read error from server: Unknown error: 0 (error num: 0)
[Apr 24 01:09:25.637537 2009] debug: firstuser() returning Citizen
[Apr 24 01:09:25.637551 2009] debug: nextuser() returning CoffeeBot
[Apr 24 01:09:25.637567 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637586 2009] debug: nextuser() returning CynWorkin
[Apr 24 01:09:25.637600 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637619 2009] debug: nextuser() returning Dominatrix
[Apr 24 01:09:25.637635 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637670 2009] debug: Sent: :00AAAAAAN PART #CynfulMoments
[Apr 24 01:09:25.637688 2009] debug: Deleting channel #CynfulMoments
[Apr 24 01:09:25.637706 2009] debug: nextuser() returning GameBot
[Apr 24 01:09:25.637720 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637735 2009] debug: nextuser() returning HDDS_Trivia_Bot
[Apr 24 01:09:25.637749 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637765 2009] debug: Sent: :00AAAAAAF PART #TheLogCabin
[Apr 24 01:09:25.637779 2009] debug: Deleting channel #TheLogCabin
[Apr 24 01:09:25.637792 2009] debug: nextuser() returning IForgotToIdentify29666
[Apr 24 01:09:25.637806 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637822 2009] debug: Sent: :00AAAAAAE PART #HavenDesignz
[Apr 24 01:09:25.637836 2009] debug: Deleting channel #HavenDesignz
[Apr 24 01:09:25.637849 2009] debug: nextuser() returning IForgotToIdentify30145
[Apr 24 01:09:25.637863 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637877 2009] debug: nextuser() returning IForgotToIdentify51681
[Apr 24 01:09:25.637891 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637906 2009] debug: nextuser() returning IForgotToIdentify25726
[Apr 24 01:09:25.637919 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637932 2009] debug: Deleting channel #trainsroom
[Apr 24 01:09:25.637946 2009] debug: Deleting channel #coffeeshop
[Apr 24 01:09:25.637960 2009] debug: nextuser() returning IForgotToIdentify15124
[Apr 24 01:09:25.637974 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.637988 2009] debug: nextuser() returning IForgotToIdentify26769
[Apr 24 01:09:25.638001 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638015 2009] debug: nextuser() returning IForgotToIdentify64994
[Apr 24 01:09:25.638029 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638043 2009] debug: nextuser() returning I_C_U_2
[Apr 24 01:09:25.638057 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638071 2009] debug: nextuser() returning LoBOTomy
[Apr 24 01:09:25.638084 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638098 2009] debug: nextuser() returning Mike
[Apr 24 01:09:25.638111 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638127 2009] debug: Sent: :00AAAAAAH PART #40splus
[Apr 24 01:09:25.638140 2009] debug: Deleting channel #40splus
[Apr 24 01:09:25.638155 2009] debug: nextuser() returning runnforward
[Apr 24 01:09:25.638168 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638184 2009] debug: Deleting channel #Staff
[Apr 24 01:09:25.638199 2009] debug: Deleting channel #TheLounge
[Apr 24 01:09:25.638213 2009] debug: nextuser() returning Sweet_Mistress
[Apr 24 01:09:25.638227 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638240 2009] debug: Deleting channel #ThreadofHope
[Apr 24 01:09:25.638253 2009] debug: nextuser() returning The-Doctor
[Apr 24 01:09:25.638267 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638283 2009] debug: Sent: :00AAAAAAQ PART #Sweet_Temptations
[Apr 24 01:09:25.638307 2009] debug: Deleting channel #Sweet_Temptations
[Apr 24 01:09:25.638338 2009] debug: nextuser() returning ToadlyCool
[Apr 24 01:09:25.638362 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638393 2009] debug: Sent: :00AAAAAAG PART #slaversplace
[Apr 24 01:09:25.638417 2009] debug: Deleting channel #slaversplace
[Apr 24 01:09:25.638449 2009] debug: nextuser() returning xRobotx
[Apr 24 01:09:25.638471 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638489 2009] debug: Sent: :00AAAAAAB PART #frogpond
[Apr 24 01:09:25.638503 2009] debug: Deleting channel #frogpond
[Apr 24 01:09:25.638518 2009] debug: nextuser() returning NULL (end of list)
[Apr 24 01:09:25.638531 2009] debug: Emitting event "user_logoff" (1 args)
[Apr 24 01:09:25.638545 2009] debug: Deleting channel #talkcitizen-20s
[Apr 24 01:09:25.638559 2009] debug: Emitting event "shutdown" (1 args)
[Apr 24 01:09:25.647512 2009] debug: Cleaning out Module run time directory (/usr/home/mike/services3/bin/./../data/modules/runtime) - this may take a moment please wait
[Apr 24 01:09:25.647691 2009] debug: Module run time directory has been cleaned out
its shutting down now every 3 minutes
-
[Apr 24 01:09:25.635097 2009] debug: Received: ERROR :Command (QUIT) from unknown prefix (souljah4christ)! Dropping link.
it seems your ircd is receiving an invalid message from the services and closing the link.
1. please paste the output of /version from your services and the ircd
2. please paste the lines before the "Received: ERROR" line?
[edit]
oh, I just found your attached gdb.txt. :-)
the ircd is already shutting down when its sending the invalid quit message.
please can you paste more of your debug log?
[/edit]
-
services 1.9.0
Inspircd1.2
-
Sorry i dont think i posted it all... heres the start to end log
-
InspIRCd-1.2 192.168.1.1 :FreeBSD www.Mynet.com 7.1-RELEASE (InspIRCd-1.2.0a6+Tuxer) [FLAGS=0,kqueue,468]
-
WALLCHOPS WALLVOICES MODES=20 CHANTYPES=# PREFIX=(qaohv)~&@%+ MAP MAXCHANNELS=20 MAXBANS=60 VBANLIST NICKLEN=32 CASEMAPPING=rfc1459 STATUSMSG=@%+ CHARSET=ascii are supported by this server
TOPICLEN=308 KICKLEN=256 MAXTARGETS=20 AWAYLEN=201 CHANMODES=be,k,Fl,BCMNPQRSTcimnpst FNC NETWORK=TalkCitizen MAXPARA=32 ELIST=MU EXCEPTS=e EXTBAN=,SMRTNQcB NAMESX OPERLOG are supported by this server
OVERRIDE REMOVE UHNAMES WATCH=600 are supported by this server
InspIRCd Log
Fri Apr 24 01:50:11 2009: LINK: Sent ERROR to services.mynet.com: Command (QUIT) from unknown prefix (itsanewday)! Dropping link.
Fri Apr 24 01:50:11 2009: LINK: Server services.mynet.com split: Remote host closed the connection
Fri Apr 24 01:50:11 2009: LINK: Netsplit complete, lost 17 users on 1 servers.
Fri Apr 24 01:50:11 2009: LINK: Connection to 'services.mynet.com' failed.
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: OperServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: FireplaceBot!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: ChanServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: DesignzBot!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: itsanewday!enforcer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: Geezer!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: Barmixer!Botserver@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: SportsFreak!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: FloggyDaBear!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: NickServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: Selena!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: tempting_wench!BotServer@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: HostServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: MemoServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: HelpServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:12 2009: REMOTEQUIT: Client exiting on server services.mynet.com: TalkCitizenNotice!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:15 2009: REMOTEQUIT: Client exiting on server services.mynet.com: BotServ!services@mynet.com [192.168.1.1 services.mynet.com]
Fri Apr 24 01:50:15 2009: LINK: Connection to 'services.mynet.com' was established for 2m4s
-
Looking at the log, it looks like it's caused by Anope trying to make the Services Enforcer quit. I notice you don't have m_svshold enabled in InspIRCd. I don't have the time at the moment to look into the bug, but I can tell you that a quick way to get around this bug is to enable m_svshold in InspIRCd.
-
Thanks That worked, appreciate it ;D