| 00:05.18 | *** join/#asterisk zopsi (~zopsi@dir.ac) |
| 00:38.49 | *** join/#asterisk setham (~setham@unaffiliated/setham) |
| 00:41.40 | *** join/#asterisk zamba (marius@flage.org) |
| 01:00.14 | *** join/#asterisk youtmon (~yout@c-98-242-250-233.hsd1.fl.comcast.net) |
| 01:02.39 | *** join/#asterisk J0hnSteel (~J0hnSteel@92.55.116.178) |
| 01:18.30 | *** join/#asterisk [[thufir]] (~thufir@192.157.119.2) |
| 01:48.26 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:49.15 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:50.02 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:50.50 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:51.42 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:52.27 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:53.16 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:53.35 | *** join/#asterisk shuishui (~chirpin-2@114.221.16.185) |
| 01:54.03 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:54.56 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:55.47 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:56.26 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:57.14 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:58.02 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:58.58 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 01:59.39 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 02:00.32 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 02:01.14 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 02:02.02 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 02:02.59 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 02:28.06 | *** join/#asterisk Th3Mafia (~yourname@12.50.3.98) |
| 02:42.08 | *** join/#asterisk stoopkid_ (uid137696@gateway/web/irccloud.com/x-ulgdctmuvtochlyy) |
| 02:45.51 | *** join/#asterisk retentiveboy (~retentive@c-73-82-30-193.hsd1.ga.comcast.net) |
| 03:12.08 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 03:22.01 | *** join/#asterisk shuishui (~chirpin-2@114.221.16.185) |
| 03:41.14 | *** join/#asterisk nix8n82 (~nathan@2601:283:8302:2f0d:5885:8ae8:eb72:d7a5) |
| 04:14.28 | *** join/#asterisk [TK]D-Fender (~joe@64.235.216.2) |
| 04:32.44 | *** join/#asterisk r0ckp3arl_ (~admin@203.149.66.146) |
| 05:06.41 | *** join/#asterisk jrabe (irc@janikrabe.com) |
| 05:11.03 | *** join/#asterisk startledmarmot (~startledm@198.199.67.247) |
| 05:38.31 | *** join/#asterisk k-man (~jason@unaffiliated/k-man) |
| 05:56.04 | *** join/#asterisk [[thufir]] (~thufir@192.157.119.2) |
| 05:57.57 | *** join/#asterisk shuishui (~chirpin-2@114.221.16.185) |
| 06:02.32 | *** join/#asterisk gerhard7_ (~gerhard7@ip5657ee30.direct-adsl.nl) |
| 06:02.33 | *** join/#asterisk shuishui (~chirpin-2@114.221.16.185) |
| 06:04.58 | *** join/#asterisk sh_smith (~sh_smith@cpe-76-174-26-91.socal.res.rr.com) |
| 06:26.25 | *** join/#asterisk MrMojit0 (~MrMojit0@52D9DF74.cm-11-1d.dynamic.ziggo.nl) |
| 06:30.14 | *** part/#asterisk MrMojit0 (~MrMojit0@52D9DF74.cm-11-1d.dynamic.ziggo.nl) |
| 06:48.07 | *** join/#asterisk LiuYan (~NiHola@unaffiliated/liuyan) |
| 06:54.09 | LiuYan | does res_odbc forbid empty/blank password ? I configure a unixODBC data source (MySQL) in /etc/odbc.ini, and use isql to test it, it works. but in asterisk, it tell me "res_odbc: Error SQLConnect=-1 errno=1698 [unixODBC][MySQL][ODBC 5.1 Driver]Access denied for user 'root'@'localhost'" when `mobule reload res_odbc` |
| 07:12.01 | *** join/#asterisk gerhard7_ (~gerhard7@ip5657ee30.direct-adsl.nl) |
| 07:12.01 | *** join/#asterisk clarjon1 (~clarjon1@unaffiliated/clarjon1) |
| 07:12.01 | *** join/#asterisk RovingWriter (~RovingWri@unaffiliated/rovingwriter) |
| 07:12.01 | *** join/#asterisk wyoung (~wyoung@wesleyy.com) |
| 07:12.01 | *** join/#asterisk themayor (~themayor@unaffiliated/themayor) |
| 07:12.01 | *** join/#asterisk raden (~Raden@206.8.80.67) |
| 07:12.01 | *** join/#asterisk davlefou (~davlefou@unaffiliated/davlefou) |
| 07:12.01 | *** join/#asterisk Iamnacho (~Iamnacho@ip72-213-55-66.om.om.cox.net) |
| 07:12.01 | *** join/#asterisk tehgooch (~tehgooch@unaffiliated/tehgooch) |
| 07:12.01 | *** join/#asterisk AndyCap (~aoy@pdpc/supporter/sustaining/AndyCap) |
| 07:12.01 | *** join/#asterisk [d__d] (~d__d]@ec2-54-85-45-223.compute-1.amazonaws.com) |
| 07:12.01 | *** join/#asterisk wdoekes (~walter@wjd.osso.nl) |
| 07:12.01 | *** join/#asterisk cryptic (~cryptic@142.196.170.87) |
| 07:12.01 | *** join/#asterisk alpharde (~alphard@155.254.32.77) |
| 07:12.01 | *** mode/#asterisk [+o wdoekes] by card.freenode.net |
| 07:13.33 | *** join/#asterisk cryptic (~cryptic@142.196.170.87) |
| 07:55.19 | *** join/#asterisk clarjon1 (~clarjon1@unaffiliated/clarjon1) |
| 08:03.25 | *** join/#asterisk tzafrir (~tzafrir@local.xorcom.com) |
| 08:06.41 | *** join/#asterisk DanB (~DanB@clt-195.192.206.211.ip-anschluss.net) |
| 08:09.57 | *** join/#asterisk pchero_work (~pchero@109.70.54.56) |
| 08:29.06 | *** join/#asterisk jkroon (~jkroon@uls-154-73-35-201.wall.uls.co.za) |
| 08:39.39 | *** join/#asterisk defsdoor (~andy@cpc120600-sutt6-2-0-cust177.19-1.cable.virginm.net) |
| 08:52.01 | *** join/#asterisk hehol (~hehol@gatekeeper.loca.net) |
| 09:14.21 | *** join/#asterisk anthm (~anthm@freeswitch-10.freeswitch.org) |
| 09:14.38 | *** join/#asterisk anthm (~anthm@freeswitch/developer/anthm) |
| 09:17.12 | *** join/#asterisk aness (~aness@cm-84.209.56.255.getinternet.no) |
| 09:22.16 | *** join/#asterisk GregUK (~textual@2a02:8011:9012:1::3) |
| 09:55.19 | *** join/#asterisk sekil (~sekil@nat-73.net011.net) |
| 10:39.31 | *** join/#asterisk Worldexe (~Worldexe@95-107-33-134.dsl.orel.ru) |
| 11:06.17 | *** join/#asterisk pawiecki (~pawiecki@router.dir.pl) |
| 11:12.33 | *** join/#asterisk startups (~startups@cpe-76-175-177-156.socal.res.rr.com) |
| 11:32.04 | *** join/#asterisk Maliuta_ (maliutamat@gateway/shell/matrix.org/x-akktlwodhtabpmbw) |
| 11:34.02 | *** join/#asterisk gerhard7 (~gerhard7@ip5657ee30.direct-adsl.nl) |
| 11:35.28 | *** join/#asterisk lankanmon (~LKNnet@CPE64777dd7e053-CM64777dd7e050.cpe.net.cable.rogers.com) |
| 11:38.11 | *** join/#asterisk r0ckp3arl_ (~yaaaaaaay@drinkmorebliss.com) |
| 12:08.23 | *** join/#asterisk r0ckp3arl_ (~yaaaaaaay@120.22.171.25) |
| 12:37.12 | *** join/#asterisk thiagoc (~thiagoc@unaffiliated/thiagoc) |
| 12:37.52 | *** join/#asterisk jkroon_ (~jkroon@165.16.204.36) |
| 12:41.30 | *** join/#asterisk sommarnatt (~sommarnat@193.180.18.201) |
| 12:45.33 | *** join/#asterisk luckman212 (~luckman21@unaffiliated/luckman212) |
| 12:56.40 | *** join/#asterisk jkroon_ (~jkroon@uls-154-73-35-201.wall.uls.co.za) |
| 12:59.28 | *** join/#asterisk hehol (~hehol@gatekeeper.loca.net) |
| 13:17.55 | *** join/#asterisk davidbowlby (~textual@99-67-53-141.lightspeed.clmboh.sbcglobal.net) |
| 13:20.46 | *** join/#asterisk [TK]D-Fender (~joe@216.191.106.165) |
| 13:24.16 | *** join/#asterisk craysiii (sid205503@gateway/web/irccloud.com/x-enrhnqzgwfwijugr) |
| 13:24.20 | *** join/#asterisk thiagoc (~thiagoc@unaffiliated/thiagoc) |
| 13:24.48 | *** join/#asterisk gerhard7_ (~gerhard7@ip5657ee30.direct-adsl.nl) |
| 13:36.35 | *** join/#asterisk _0x5eb_ (~seb@seb-hpws2.w1.tele.crt1.net) |
| 13:37.58 | *** join/#asterisk brad_mssw (~brad@66.129.88.50) |
| 13:57.51 | *** join/#asterisk luckman212 (~luckman21@unaffiliated/luckman212) |
| 14:27.51 | *** join/#asterisk s-mutin (~s-mutin@85.234.114.134) |
| 14:55.47 | *** join/#asterisk Catatronic (~catatroni@184.75.223.235) |
| 14:58.34 | *** join/#asterisk sekil (~sekil@nat-73.net011.net) |
| 15:00.45 | *** join/#asterisk cemotyz09 (~cemotyz09@cpe-70-121-157-202.satx.res.rr.com) |
| 15:02.34 | *** join/#asterisk bford (d8cff501@gateway/web/freenode/ip.216.207.245.1) |
| 15:02.34 | *** mode/#asterisk [+o bford] by ChanServ |
| 15:15.27 | *** join/#asterisk kharwell (kharwell@nat/digium/x-gmofohgpcnctrari) |
| 15:15.27 | *** mode/#asterisk [+o kharwell] by ChanServ |
| 15:20.16 | *** join/#asterisk aness (~aness@cm-84.209.56.255.getinternet.no) |
| 15:20.46 | *** join/#asterisk gregs (sid160074@gateway/web/irccloud.com/x-oteogdjoeudqffhu) |
| 15:23.34 | *** join/#asterisk happy-dude (uid62780@gateway/web/irccloud.com/x-xowicqnmbewmamcg) |
| 15:25.02 | *** join/#asterisk TandyUK (~admin@2a02:13a0:a006:1:1887:4d17:f8ee:a27c) |
| 15:33.21 | *** join/#asterisk cresl1n (~Adium@asterisk/libpri-and-libss7-expert/Cresl1n) |
| 15:33.21 | *** mode/#asterisk [+o cresl1n] by ChanServ |
| 15:50.56 | *** join/#asterisk rmudgett (rmudgett@nat/digium/x-ljkzohvdnigkyqvs) |
| 15:50.56 | *** mode/#asterisk [+o rmudgett] by ChanServ |
| 16:02.56 | *** join/#asterisk rangotail (~rangotail@unaffiliated/rangotail) |
| 16:18.20 | *** join/#asterisk s-mutin (~s-mutin@85.234.114.134) |
| 16:28.20 | *** join/#asterisk mutin-s (~s-mutin@85.234.114.134) |
| 16:37.22 | *** join/#asterisk saint_ (~saint_@unaffiliated/saint-/x-0540772) |
| 16:42.52 | *** join/#asterisk s-mutin (~s-mutin@85.234.114.134) |
| 16:43.29 | *** join/#asterisk TandyUK (~admin@2a02:13a0:a006:1:6432:cd4a:7469:6f09) |
| 16:48.09 | *** join/#asterisk TandyUK (~admin@2a02:13a0:a006:1:21c7:8974:15c3:1480) |
| 16:48.53 | *** join/#asterisk mutin-s (~s-mutin@85.234.114.134) |
| 16:48.55 | *** join/#asterisk cresl1n (~Adium@asterisk/libpri-and-libss7-expert/Cresl1n) |
| 16:48.55 | *** mode/#asterisk [+o cresl1n] by ChanServ |
| 16:59.44 | *** join/#asterisk corretico (~corretico@200.91.143.34) |
| 17:12.23 | *** join/#asterisk robinak (~quassel@unaffilated/robink) |
| 17:13.17 | *** join/#asterisk opmrcl (~Thunderbi@78.133.34.141) |
| 17:21.22 | *** join/#asterisk MrTAP (~MrT@unaffiliated/mrtap) |
| 17:47.24 | *** join/#asterisk [red] (sid221222@gateway/web/irccloud.com/x-wcqrasoatdnjotfj) |
| 17:47.40 | [red] | can someone please explain this error message in plain english? |
| 17:47.41 | [red] | [Nov 6 17:40:30] WARNING[28207][C-00000732]: func_channel.c:765 func_channels_read: Number of channels exceeds the available buffer space. Output will be truncated! |
| 17:47.43 | [red] | thank you |
| 17:49.32 | file | there is a fixed maximum amount of space for storing the names of the channels, and there are so many channels that it is exceeded |
| 17:50.00 | [red] | that's scary |
| 17:50.04 | [red] | is there any workaround? |
| 17:50.20 | Samot | What are the resources of the machine? |
| 17:50.24 | Samot | CPU/RAM? |
| 17:51.55 | Samot | [red] ^^ |
| 17:52.34 | [red] | working on it, my internet decided to start flaking out |
| 17:54.35 | [red] | https://gist.github.com/erichowey/27a8936398e23eef40b64909de8e6b29 |
| 17:55.28 | Samot | How man concurrent calls? |
| 17:55.33 | Samot | How many concurrent calls? |
| 17:55.36 | [red] | ~ 50 |
| 17:55.36 | infobot | hmm... 50 is still quite common |
| 17:55.48 | Samot | Yeah. |
| 17:55.51 | Samot | 6 cores |
| 17:55.54 | *** join/#asterisk jkroon (~jkroon@165.16.204.167) |
| 17:56.14 | Samot | How much RAM? |
| 17:56.20 | Samot | That's not listed. |
| 17:57.08 | [red] | sorry refresh it |
| 17:57.50 | Samot | Yeah, 50 concurrent calls shouldn't be filling the buffer. |
| 17:58.08 | Samot | How many users on the system? |
| 17:58.32 | [red] | users? |
| 17:58.37 | Samot | Devices |
| 17:58.39 | Samot | Peers |
| 17:58.44 | Samot | How many things are connected to it |
| 17:59.20 | [red] | only several trunks. basically it's many channels on few trunks |
| 17:59.37 | Samot | So it's just passing calls? |
| 17:59.41 | [red] | correct |
| 17:59.54 | Samot | And you're not trying to use it as a switch, right? |
| 18:00.02 | [red] | i am |
| 18:00.45 | Samot | Transcoding? |
| 18:00.53 | [red] | sometimes. usually not |
| 18:01.01 | [red] | but it does happen on occasion |
| 18:02.25 | Samot | Well even with it doing the extra work in the dialplan and transcoding, I can't see how 50 calls would cause that |
| 18:02.46 | Samot | I have boxes with actual end users that do that on less. |
| 18:03.31 | Samot | But I don't "switch" on it. |
| 18:04.21 | Samot | Are you doing LCR in the switching process? |
| 18:04.47 | Samot | Like are you loading a crap ton of dialplan to match various destination patterns? |
| 18:08.18 | Samot | Also, when I say "concurrent calls"I mean actual connected and bridged calls. |
| 18:08.55 | Samot | So if you have 50 concurrent calls that are actually connected and bridged, how man "in progress" calls do you have on top of that? |
| 18:09.16 | Samot | Calls that are in some sort of Ringing or 1XX status? |
| 18:10.07 | [red] | Sorry. My internet is acting up at the worst time possible |
| 18:10.27 | Samot | Are you doing LCR? |
| 18:10.32 | Samot | Least-Cost Routing? |
| 18:10.34 | [red] | Yes |
| 18:10.45 | Samot | Do you have a lot of destination patterns? |
| 18:10.52 | [red] | It pulls the lcr data from mysql |
| 18:10.56 | Samot | OK |
| 18:10.59 | [red] | Thousands |
| 18:11.05 | Samot | Important piece of information. |
| 18:11.06 | Samot | Well |
| 18:11.11 | [red] | I'm using lua to pull that data |
| 18:11.21 | Samot | My US/Canada LCR table is like 100's of thousands. |
| 18:12.05 | Samot | The short and correct answer for this problem is: Don't use Asterisk as a switch. It's not what it is designed to do. |
| 18:12.19 | voipmonk | Us that dB on the same server? |
| 18:12.25 | Samot | The short and correct answer for this problem is: Don't use Asterisk as a switch. It's not what it is designed to do. |
| 18:12.28 | voipmonk | Is |
| 18:12.48 | Samot | You have 50 con. calls |
| 18:12.51 | Samot | That's 100 channels. |
| 18:12.56 | [red] | No. Different server |
| 18:12.59 | Samot | To sustain 50 con. calls... |
| 18:13.06 | Samot | You have to handle about 2 calls per second.. |
| 18:13.17 | Samot | That you are making a DB call remotely to |
| 18:13.27 | Samot | Looking through thousands of records.. |
| 18:13.44 | Samot | I can see now where the buffer can be filled. |
| 18:14.15 | [red] | what happens when that buffer is filled? |
| 18:14.35 | Samot | What happens? |
| 18:14.42 | Samot | What happens when you start getting that error? |
| 18:14.44 | Samot | Do calls fail? |
| 18:14.47 | [red] | yes |
| 18:14.58 | [red] | do calls fail or is it simply logging that's affected? |
| 18:15.03 | Samot | Right because Asterisk is too busy processing crap for those other calls. |
| 18:15.13 | Samot | I'm asking *you* |
| 18:15.20 | Samot | It's happening on your system. |
| 18:15.30 | Samot | When that error appears in the logs, do calls stop working? |
| 18:15.32 | [red] | nothing fails from what i can see |
| 18:15.37 | Samot | OK |
| 18:15.41 | [red] | at least call wise |
| 18:16.01 | Samot | So its just not sending stuff to the log or the console it would seem. |
| 18:16.06 | Samot | file: is that right? |
| 18:16.17 | [red] | i'm just trying to understand, is that a hard coded buffer in asterisk and no setting or system config change can change that, correct? |
| 18:16.18 | Samot | It just stops logging and console output? |
| 18:16.28 | *** join/#asterisk opmrcl (~Thunderbi@78.133.34.141) |
| 18:16.40 | file | hmmm? |
| 18:16.49 | Samot | Once that error he see's hit |
| 18:16.54 | Samot | The buffer is full.. |
| 18:17.05 | Samot | It just stops logging and console output, right? |
| 18:17.13 | Samot | Asterisk will still process calls? |
| 18:17.31 | file | it'll still process calls, it's because of a call to the dialplan function |
| 18:17.59 | file | you've asked for a list of all the channels using the dialplan function and it exceeds the space to store the combined list, so it cuts off at the end |
| 18:18.18 | [red] | ^that makes sense |
| 18:18.46 | [red] | i know exactly what function is asking for that |
| 18:18.54 | *** join/#asterisk opmrcl1 (~Thunderbi@78.133.34.141) |
| 18:19.01 | [red] | but is there really no way to expand that buffer? |
| 18:19.33 | file | there's probably a specified limit somewhere in the code, I don't know where it is off the top of my head or what other limits it may run into |
| 18:19.57 | [red] | okay. thanks. yeah, i'm not about to make source changes for that |
| 18:20.23 | [red] | and yeah. i know. shoulnd't use asterisk as a switch |
| 18:20.28 | [red] | i'm trying to find a better solution :/ |
| 18:22.50 | [red] | i appreciate the help. thanks everyone |
| 18:23.47 | voipmonk | Giving up? |
| 18:24.09 | voipmonk | Just getting to the good stuff. |
| 18:36.59 | *** join/#asterisk strgout (0ce3da3b@gateway/web/freenode/ip.12.227.218.59) |
| 18:38.43 | strgout | howdy all! Anyone using snmp on asterisk? |
| 18:40.19 | strgout | i'm trying to figure out if i can see if a given extension is registered and if its a member of a confbridge. I know i can do this with a shell script but i'd rather tie it into snmp.. which i guess i could do with a script as well, but if i can pull directly from asterisk \o/ |
| 18:40.57 | strgout | also i'd like to be able to read something like sip show channelstats |
| 18:48.20 | *** join/#asterisk gtjoseph (~gtjoseph@unaffiliated/gtj) |
| 18:48.20 | *** mode/#asterisk [+o gtjoseph] by ChanServ |
| 18:57.12 | *** join/#asterisk tripleslash (~triplesla@unaffiliated/imsaguy) |
| 18:59.53 | *** join/#asterisk troyt (~troyt@2601:681:4100:901:44dd:acff:fe85:9c8e) |
| 19:03.29 | *** join/#asterisk cemotyz09 (~cemotyz09@cpe-70-121-157-202.satx.res.rr.com) |
| 19:06.10 | *** join/#asterisk puzzola (~puzzola@unaffiliated/puzzola) |
| 19:17.28 | *** join/#asterisk cresl1n (Adium@asterisk/libpri-and-libss7-expert/Cresl1n) |
| 19:17.29 | *** mode/#asterisk [+o cresl1n] by ChanServ |
| 19:22.45 | *** join/#asterisk woose (~woose@unaffiliated/woose) |
| 19:31.41 | *** join/#asterisk joako (~joako@opensuse/member/joak0) |
| 20:07.14 | *** join/#asterisk joako (~joako@opensuse/member/joak0) |
| 20:12.00 | *** join/#asterisk cbdev (~fnord@hieristdas.internetzuen.de) |
| 20:18.21 | *** join/#asterisk opmrcl (~Thunderbi@78.133.34.141) |
| 20:21.52 | *** join/#asterisk joshelson (~joshelson@64.55.128.243) |
| 20:26.06 | *** join/#asterisk tzafrir (~tzafrir@62-90-199-170.barak.net.il) |
| 20:32.39 | *** join/#asterisk r0ckp3arl_ (~yaaaaaaay@drinkmorebliss.com) |
| 20:41.10 | *** join/#asterisk GregUK (~GregUK@2a02:8011:9012:1::3) |
| 21:18.06 | *** join/#asterisk miralin (~Thunderbi@91.237.94.8) |
| 21:51.21 | *** join/#asterisk opmrcl (~Thunderbi@78.133.34.141) |
| 22:11.45 | *** join/#asterisk startledmarmot (~startledm@cpe-75-82-221-87.socal.res.rr.com) |
| 22:24.56 | *** join/#asterisk paulgrmn_ (~paulgrmn@198-0-107-153-static.hfc.comcastbusiness.net) |
| 22:28.09 | *** join/#asterisk lankanmon (~LKNnet@CPE64777dd7e053-CM64777dd7e050.cpe.net.cable.rogers.com) |
| 22:31.49 | *** join/#asterisk [TK]D-Fender (~joe@64.235.216.2) |
| 22:32.43 | *** join/#asterisk bford (d8cff501@gateway/web/freenode/ip.216.207.245.1) |
| 22:32.43 | *** mode/#asterisk [+o bford] by ChanServ |
| 23:08.36 | TandyUK | righty ho lolz |
| 23:08.37 | TandyUK | [2017-11-06 23:08:17] == Problem setting up ssl connection: error:00000000:lib(0):func(0):reason(0) |
| 23:08.37 | TandyUK | [2017-11-06 23:08:17] WARNING[7791]: tcptls.c:683 handle_tcptls_connection: FILE * open failed! |
| 23:08.50 | TandyUK | this is categorically NOT because we were using a wildcard cert for sip |
| 23:09.07 | TandyUK | 2 weeks ago that was replaced by a single domain cert for our voip server, yet the issues persist |
| 23:09.34 | TandyUK | every 62 seconds we get that error logged |
| 23:09.44 | TandyUK | while over 400 peers are connected without a problem |
| 23:09.57 | voipmonk | using the cert? |
| 23:09.59 | TandyUK | and all but about 5 are using TLS/SRTP |
| 23:10.41 | TandyUK | its the frequency of the error that confuses me - its consistently exactly every 62 seconds |
| 23:11.46 | Samot | That sounds like the amount of time between the default qualify (60 seconds) and the reply timeout (2 seconds) |
| 23:12.08 | TandyUK | a friend of mine who operates big scale voip has just had a look a tthe server for me, and we've installed a brand new, freshly issued certifcate/key/etc to rule out there being any possible issues with how the certificate is installed |
| 23:12.23 | Samot | Asterisk qualifies the peer and it waits 2 seconds for a reply. |
| 23:12.56 | TandyUK | this is asterisk 11, but please god if a dev is listening, in current versions please like say _which_ peer that might relate to |
| 23:13.11 | TandyUK | give some clue for debugging it (i dont expect that to be backported to 11 though) |
| 23:14.09 | TandyUK | i currently have 16 phones showing as unavailable, so it could be related to one of them, but i have absolutely no way to know which! |
| 23:14.51 | Samot | So you have 5 devices using TLS/SRTP and giving you these errors? |
| 23:15.21 | Samot | Every other device is either UDP or TCP? |
| 23:16.57 | TandyUK | samot: other way round |
| 23:17.08 | Samot | Ahh |
| 23:17.09 | Samot | Yeah. |
| 23:17.13 | Samot | OK |
| 23:17.15 | TandyUK | if its even as high as 5 devices NOT using tcp/tls |
| 23:20.22 | *** join/#asterisk puzzola (~puzzola@unaffiliated/puzzola) |
| 23:21.02 | Samot | So everything works fine? |
| 23:21.07 | Samot | Just those errors show up? |
| 23:21.18 | Samot | Or warnings, actually. |
| 23:22.11 | TandyUK | everything else is fine |
| 23:22.22 | TandyUK | as you know ive been hunting this 'noise' for months now |
| 23:22.32 | TandyUK | imho thats all it is, just noise in the logs |
| 23:39.20 | Samot | Do you setup the devices? |
| 23:39.40 | TandyUK | 99% of them are centrally autoprovisioned |
| 23:39.45 | Samot | OK |
| 23:39.48 | TandyUK | the remainder are manually conigured |
| 23:40.15 | Samot | Are things like the re-register, subscriptions and such controlled? |
| 23:40.21 | TandyUK | things like, grandstream HT704 ATA's and such that we havent bothered ssetting up autoprov for |
| 23:40.37 | Samot | What do the devices do every 60 seconds? |
| 23:40.40 | Samot | Start with that. |
| 23:41.07 | Samot | If Device A is sitting there over night, what does it do every minute on its own? |
| 23:41.23 | TandyUK | well its either ONE device doing something every 60s, or surely we'd be getting this warning for eac hdevice |
| 23:41.45 | Samot | K |
| 23:42.01 | Samot | What are they supposed to do? |
| 23:42.44 | TandyUK | sorry im missing the point here clearly |
| 23:42.52 | Samot | You have the "noise" |
| 23:43.03 | Samot | It appears in the logs every 62 seconds. |
| 23:43.12 | MrTAP | TandyUK: I see errors like that whenever TLS peers have crap connectivity. Ast will write that anytime it tries and fails to do a qualify until the device connects again and reopens the tcp stream |
| 23:43.26 | Samot | It is from the device. |
| 23:43.36 | Samot | So. |
| 23:43.42 | TandyUK | MrTAP: do you see it once per connection though? |
| 23:43.56 | TandyUK | eg, right now i have about 16 devices that will be using tcp/tls and are down |
| 23:44.02 | Samot | I'm asking, if you control how the phone works, what is the phone set to do every 60 seconds? |
| 23:44.06 | TandyUK | but i only see that message once per 62s |
| 23:44.14 | *** join/#asterisk bipolar (~bipolar@offsite.guru) |
| 23:44.27 | TandyUK | Samot: I dont know _WHICH_ phone |
| 23:44.32 | Samot | I don't care. |
| 23:44.36 | TandyUK | thats the real crux of this whole issue |
| 23:44.44 | Samot | Do they all use the same template?! |
| 23:44.49 | TandyUK | if i knew which bloody phone it was, id go fix it |
| 23:44.57 | Samot | Do all your phones have the Register Expire set to X time? |
| 23:44.59 | TandyUK | all the ones we provision yes |
| 23:45.06 | Samot | Or is that different per phone? |
| 23:45.08 | TandyUK | 3600 registratioon expiry, not 60 |
| 23:45.11 | Samot | OK |
| 23:45.20 | Samot | How often is the MWI Subscribe set for? |
| 23:45.49 | Samot | How often is the BLF/other stuff that subscribes set for (if applicable)? |
| 23:45.57 | Samot | These are things the device does |
| 23:46.00 | MrTAP | TandyUK: I don't know because it doesn't say, but I've reproduced it and decided it's not worth worrying about |
| 23:46.02 | Samot | One a timer. |
| 23:46.06 | Samot | On a timer. |
| 23:46.31 | TandyUK | MrTAP: we're just getting annoyed by the spurious logs |
| 23:46.41 | Samot | Is the device sending PUBLISHes? |
| 23:46.47 | TandyUK | if i can simply work out _which_ of our 400+ devices is causing it, i will go remove the damn thing |
| 23:47.01 | MrTAP | Yep I find it annoying too but since Ast 11 is EOL, not much point in opening a bug unless it happens in 13+ |
| 23:47.03 | Samot | TandyUK: Please wait for a second. |
| 23:47.12 | Samot | MrTAP: Please also wait. |
| 23:47.29 | Samot | I'm trying to help here. |
| 23:47.37 | Samot | Follow my logic, please. |
| 23:47.55 | Samot | 1) IP Phones / ATA Devices need to send requests to the PBX |
| 23:48.13 | Samot | 2) Those include REGISTER, SUBSCRIBE and even PUBLISH |
| 23:48.37 | Samot | 3) REGISTER and SUBSCRIBE happen based on the time period the device is programmed for. |
| 23:48.52 | Samot | 4) MWI is also handled in such a way on devices. |
| 23:49.19 | Samot | 5) The "noise" in the log is from the device sending something to Asterisk over TLS it didn't like. |
| 23:49.35 | Samot | 6) It happens _every_ 62 seconds. |
| 23:49.54 | Samot | 7) Look at what happens every 60 second on your network and device side. |
| 23:49.57 | Samot | ^^^^^ |
| 23:50.05 | Samot | Does the PBX send qualifies every 60 seconds? |
| 23:50.24 | Samot | Does the phone send any type of request every 60 seconds |
| 23:50.33 | Samot | It doesn't matter _which_ phone right now |
| 23:50.35 | TandyUK | samot:L yes hundreds of them do |
| 23:50.42 | Samot | Because they should all have the *same* config. |
| 23:50.48 | Samot | Look at the process.. |
| 23:50.51 | TandyUK | i dont see any login there that helps me work out _WHICH FUCKING DEVICE_ |
| 23:50.52 | Samot | Then you know where to look. |
| 23:50.56 | TandyUK | logic* |
| 23:51.01 | Samot | K |
| 23:51.19 | Samot | If the device shouldn't be sending PUBLISHes |
| 23:51.30 | Samot | And you see a device sending a PUBLISH.. |
| 23:51.34 | Samot | Could be the culprit. |
| 23:51.47 | sibiria | disconnect half of the phones. does it stop? reconnect one fourth. etc. :P |
| 23:51.50 | Samot | If devices are trying to SUBSCRIBE to something they can't.. |
| 23:51.54 | Samot | Could be the culprit. |
| 23:51.55 | TandyUK | sibiria: if only lol |
| 23:52.06 | TandyUK | Samot: why is that a TLS error |
| 23:52.25 | TandyUK | id expect asterisk to log that in a sane way like "device bllah just tried to subscribe to something they dont have access to" |
| 23:52.25 | Samot | Perhaps it's not sending it in a way Asterisk likes for TLS |
| 23:52.31 | TandyUK | not a cryptic TLS error |
| 23:52.40 | Samot | You have to look at SIP MESSAGES |
| 23:52.47 | Samot | Not just the console output. |
| 23:53.00 | TandyUK | yeah i much prefer voipmonk's methosd of logging, decrypting and inspecting the actual traffic |
| 23:53.04 | Samot | It won't show you what is in the SIP message unless you are debugging. |