0s autopkgtest [18:10:26]: starting date and time: 2026-02-09 18:10:26+0000 0s autopkgtest [18:10:26]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [18:10:26]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.5ztc4vwp/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:asterisk --apt-upgrade asterisk --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=asterisk/1:22.8.2+dfsg+~cs6.15.60671435-1' -- lxd -r lxd-armhf-10.145.243.207 lxd-armhf-10.145.243.207:autopkgtest/ubuntu/resolute/armhf 20s autopkgtest [18:10:46]: testbed dpkg architecture: armhf 22s autopkgtest [18:10:48]: testbed apt version: 3.1.15 26s autopkgtest [18:10:52]: @@@@@@@@@@@@@@@@@@@@ test bed setup 27s autopkgtest [18:10:53]: testbed release detected to be: None 35s autopkgtest [18:11:01]: updating testbed package index (apt update) 37s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 37s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 37s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 37s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 37s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [174 kB] 38s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [1764 B] 38s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1630 kB] 38s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [29.4 kB] 38s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [245 kB] 38s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [1403 kB] 38s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [7452 B] 38s Get:12 http://ftpmaster.internal/ubuntu resolute/restricted Sources [15.1 kB] 38s Get:13 http://ftpmaster.internal/ubuntu resolute/main Sources [1412 kB] 38s Get:14 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.3 MB] 38s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1374 kB] 38s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.1 MB] 42s Fetched 43.2 MB in 5s (8507 kB/s) 44s Reading package lists... 49s autopkgtest [18:11:15]: upgrading testbed (apt dist-upgrade and autopurge) 51s Reading package lists... 51s Building dependency tree... 51s Reading state information... 52s Calculating upgrade... 52s The following packages will be upgraded: 52s cryptsetup-bin dracut-install iproute2 iptables libcryptsetup12 libip4tc2 52s libip6tc2 libxtables12 wget 52s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 52s Need to get 2407 kB of archives. 52s After this operation, 152 kB of additional disk space will be used. 52s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf iptables armhf 1.8.11-2ubuntu3 [345 kB] 53s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf libip4tc2 armhf 1.8.11-2ubuntu3 [22.0 kB] 53s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf libip6tc2 armhf 1.8.11-2ubuntu3 [22.3 kB] 53s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libxtables12 armhf 1.8.11-2ubuntu3 [33.5 kB] 53s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf iproute2 armhf 6.18.0-1ubuntu1 [1123 kB] 53s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libcryptsetup12 armhf 2:2.8.0-1ubuntu3 [254 kB] 53s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf wget armhf 1.25.0-2ubuntu4 [327 kB] 53s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf cryptsetup-bin armhf 2:2.8.0-1ubuntu3 [232 kB] 53s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf dracut-install armhf 109-11ubuntu1 [47.9 kB] 53s Preconfiguring packages ... 53s Fetched 2407 kB in 1s (3744 kB/s) 54s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 68683 files and directories currently installed.) 54s Preparing to unpack .../0-iptables_1.8.11-2ubuntu3_armhf.deb ... 54s Unpacking iptables (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 54s Preparing to unpack .../1-libip4tc2_1.8.11-2ubuntu3_armhf.deb ... 54s Unpacking libip4tc2:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 54s Preparing to unpack .../2-libip6tc2_1.8.11-2ubuntu3_armhf.deb ... 54s Unpacking libip6tc2:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 54s Preparing to unpack .../3-libxtables12_1.8.11-2ubuntu3_armhf.deb ... 54s Unpacking libxtables12:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 54s Preparing to unpack .../4-iproute2_6.18.0-1ubuntu1_armhf.deb ... 54s Unpacking iproute2 (6.18.0-1ubuntu1) over (6.16.0-1ubuntu3) ... 54s Preparing to unpack .../5-libcryptsetup12_2%3a2.8.0-1ubuntu3_armhf.deb ... 54s Unpacking libcryptsetup12:armhf (2:2.8.0-1ubuntu3) over (2:2.8.0-1ubuntu2) ... 54s Preparing to unpack .../6-wget_1.25.0-2ubuntu4_armhf.deb ... 54s Unpacking wget (1.25.0-2ubuntu4) over (1.25.0-2ubuntu3) ... 54s Preparing to unpack .../7-cryptsetup-bin_2%3a2.8.0-1ubuntu3_armhf.deb ... 54s Unpacking cryptsetup-bin (2:2.8.0-1ubuntu3) over (2:2.8.0-1ubuntu2) ... 54s Preparing to unpack .../8-dracut-install_109-11ubuntu1_armhf.deb ... 54s Unpacking dracut-install (109-11ubuntu1) over (109-9ubuntu1) ... 54s Setting up libip4tc2:armhf (1.8.11-2ubuntu3) ... 54s Setting up wget (1.25.0-2ubuntu4) ... 54s Setting up libip6tc2:armhf (1.8.11-2ubuntu3) ... 54s Setting up libxtables12:armhf (1.8.11-2ubuntu3) ... 54s Setting up dracut-install (109-11ubuntu1) ... 54s Setting up libcryptsetup12:armhf (2:2.8.0-1ubuntu3) ... 54s Setting up cryptsetup-bin (2:2.8.0-1ubuntu3) ... 54s Setting up iptables (1.8.11-2ubuntu3) ... 54s Setting up iproute2 (6.18.0-1ubuntu1) ... 55s Processing triggers for man-db (2.13.1-1build1) ... 56s Processing triggers for install-info (7.2-5) ... 56s Processing triggers for libc-bin (2.42-2ubuntu4) ... 59s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Solving dependencies... 60s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s autopkgtest [18:11:28]: rebooting testbed after setup commands that affected boot 102s autopkgtest [18:12:08]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2 126s autopkgtest [18:12:32]: @@@@@@@@@@@@@@@@@@@@ apt-source asterisk 174s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (dsc) [5370 B] 174s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (tar) [11.3 kB] 174s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (tar) [22.0 kB] 174s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (tar) [22.6 kB] 174s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (tar) [6394 kB] 174s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (tar) [6127 kB] 174s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe asterisk 1:22.8.2+dfsg+~cs6.15.60671435-1 (diff) [123 kB] 174s gpgv: Signature made Sun Feb 8 20:13:25 2026 UTC 174s gpgv: using RSA key 9FE3E9C36691A69FF53CC6842C7C3146C1A00121 174s gpgv: issuer "dr@jones.dk" 174s gpgv: Can't check signature: No public key 174s dpkg-source: warning: cannot verify inline signature for ./asterisk_22.8.2+dfsg+~cs6.15.60671435-1.dsc: no acceptable signature found 176s autopkgtest [18:13:22]: testing package asterisk version 1:22.8.2+dfsg+~cs6.15.60671435-1 179s autopkgtest [18:13:25]: build not needed 190s autopkgtest [18:13:36]: test asttestmods: preparing testbed 191s Reading package lists... 192s Building dependency tree... 192s Reading state information... 192s Solving dependencies... 192s The following NEW packages will be installed: 192s asterisk asterisk-config asterisk-core-sounds-en asterisk-core-sounds-en-gsm 192s asterisk-modules asterisk-tests freetds-common libasound2-data libasound2t64 192s libasyncns0 libb64-0d libcodec2-1.2 libdeflate0 libevent-2.1-7t64 libflac14 192s libgmime-3.0-0t64 libgsm1 libical3t64 libiksemel3 libjack-jackd2-0 libjbig0 192s libjpeg-turbo8 libjpeg8 libjwt2 liblerc4 libltdl7 liblua5.1-0 libmp3lame0 192s libmpg123-0t64 libneon27t64 libodbc2 libogg0 libopencore-amrnb0 192s libopencore-amrwb0 libopus0 libopusenc0 libopusfile0 libportaudio2 libpq5 192s libpulse0 libradcli4 libresample1 libsamplerate0 libsharpyuv0 libsndfile1 192s libsnmp-base libsnmp40t64 libspandsp2t64 libspeex1 libspeexdsp1 libsrtp2-1 192s libsybdb5 libtiff6 libunbound8 liburiparser1 libvo-amrwbenc0 libvorbis0a 192s libvorbisenc2 libvorbisfile3 libwebp7 libx11-xcb1 libxml2-utils libxslt1.1 193s 0 upgraded, 63 newly installed, 0 to remove and 0 not upgraded. 193s Need to get 28.5 MB of archives. 193s After this operation, 85.4 MB of additional disk space will be used. 193s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk-config all 1:22.8.2+dfsg+~cs6.15.60671435-1 [228 kB] 193s Get:2 http://ftpmaster.internal/ubuntu resolute/universe armhf asterisk-core-sounds-en-gsm all 1.6.1-1build1 [2436 kB] 193s Get:3 http://ftpmaster.internal/ubuntu resolute/universe armhf asterisk-core-sounds-en all 1.6.1-1build1 [21.2 kB] 193s Get:4 http://ftpmaster.internal/ubuntu resolute/universe armhf libcodec2-1.2 armhf 1.2.0-4 [8252 kB] 193s Get:5 http://ftpmaster.internal/ubuntu resolute/universe armhf libgmime-3.0-0t64 armhf 3.2.15+dfsg-1build1 [150 kB] 193s Get:6 http://ftpmaster.internal/ubuntu resolute/universe armhf libgsm1 armhf 1.0.23-2 [25.0 kB] 193s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf libical3t64 armhf 3.0.20-2build1 [270 kB] 193s Get:8 http://ftpmaster.internal/ubuntu resolute/universe armhf libiksemel3 armhf 1.4-4build3 [22.2 kB] 193s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf libopus0 armhf 1.6.1-1 [3510 kB] 193s Get:10 http://ftpmaster.internal/ubuntu resolute/main armhf libsamplerate0 armhf 0.2.2-4build2 [1340 kB] 194s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libjack-jackd2-0 armhf 1.9.22~dfsg-5build1 [246 kB] 194s Get:12 http://ftpmaster.internal/ubuntu resolute/universe armhf libb64-0d armhf 1.2-5build2 [9096 B] 194s Get:13 http://ftpmaster.internal/ubuntu resolute/universe armhf libjwt2 armhf 1.17.2-1build1 [15.1 kB] 194s Get:14 http://ftpmaster.internal/ubuntu resolute/universe armhf liblua5.1-0 armhf 5.1.5-11build1 [92.6 kB] 194s Get:15 http://ftpmaster.internal/ubuntu resolute/universe armhf libneon27t64 armhf 0.36.0-1 [93.5 kB] 194s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf libltdl7 armhf 2.5.4-9 [40.4 kB] 194s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libodbc2 armhf 2.3.14-1 [147 kB] 194s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libogg0 armhf 1.3.6-2 [20.6 kB] 194s Get:19 http://ftpmaster.internal/ubuntu resolute/universe armhf libopencore-amrnb0 armhf 0.1.6-1build2 [88.8 kB] 194s Get:20 http://ftpmaster.internal/ubuntu resolute/universe armhf libopencore-amrwb0 armhf 0.1.6-1build2 [48.8 kB] 194s Get:21 http://ftpmaster.internal/ubuntu resolute/universe armhf libopusenc0 armhf 0.3-1 [24.2 kB] 194s Get:22 http://ftpmaster.internal/ubuntu resolute/universe armhf libopusfile0 armhf 0.12-4build4 [42.0 kB] 194s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2-data all 1.2.15.3-1ubuntu1 [21.4 kB] 194s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2t64 armhf 1.2.15.3-1ubuntu1 [362 kB] 194s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf libasyncns0 armhf 0.8-7 [10.8 kB] 194s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libflac14 armhf 1.5.0+ds-5 [136 kB] 194s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf libmp3lame0 armhf 3.100-6build2 [126 kB] 194s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf libmpg123-0t64 armhf 1.33.3-2 [160 kB] 194s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbis0a armhf 1.3.7-3build1 [86.6 kB] 194s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbisenc2 armhf 1.3.7-3build1 [72.7 kB] 194s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf libsndfile1 armhf 1.2.2-4 [201 kB] 194s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf libx11-xcb1 armhf 2:1.8.12-1build1 [7920 B] 194s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libpulse0 armhf 1:17.0+dfsg1-2ubuntu4 [280 kB] 194s Get:34 http://ftpmaster.internal/ubuntu resolute/universe armhf libportaudio2 armhf 19.7.0+git20260206.e1b70d33-0ubuntu1 [70.2 kB] 194s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf libpq5 armhf 18.1-2 [137 kB] 194s Get:36 http://ftpmaster.internal/ubuntu resolute/universe armhf libradcli4 armhf 1.2.11-1build4 [37.7 kB] 194s Get:37 http://ftpmaster.internal/ubuntu resolute/universe armhf libresample1 armhf 0.1.3-8build1 [7906 B] 194s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf libsnmp-base all 5.9.4+dfsg-2ubuntu3 [207 kB] 194s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf libsnmp40t64 armhf 5.9.4+dfsg-2ubuntu3 [1026 kB] 194s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf libdeflate0 armhf 1.23-2build1 [38.8 kB] 194s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf libjbig0 armhf 2.1-6.1ubuntu3 [25.3 kB] 194s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf libjpeg-turbo8 armhf 2.1.5-4ubuntu3 [129 kB] 194s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf libjpeg8 armhf 8c-2ubuntu11 [2148 B] 194s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf liblerc4 armhf 4.0.0+ds-5ubuntu2 [162 kB] 194s Get:45 http://ftpmaster.internal/ubuntu resolute/main armhf libsharpyuv0 armhf 1.5.0-0.1build1 [16.3 kB] 194s Get:46 http://ftpmaster.internal/ubuntu resolute/main armhf libwebp7 armhf 1.5.0-0.1build1 [189 kB] 194s Get:47 http://ftpmaster.internal/ubuntu resolute/main armhf libtiff6 armhf 4.7.0-3ubuntu3 [188 kB] 194s Get:48 http://ftpmaster.internal/ubuntu resolute/universe armhf libspandsp2t64 armhf 0.0.6+dfsg-2.2build1 [268 kB] 194s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf libspeex1 armhf 1.2.1-3build1 [49.3 kB] 194s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf libspeexdsp1 armhf 1.2.1-3build1 [37.1 kB] 194s Get:51 http://ftpmaster.internal/ubuntu resolute/universe armhf libsrtp2-1 armhf 2.7.0-3build1 [35.8 kB] 194s Get:52 http://ftpmaster.internal/ubuntu resolute/main armhf freetds-common all 1.5.5+ds-1 [29.1 kB] 194s Get:53 http://ftpmaster.internal/ubuntu resolute/main armhf libsybdb5 armhf 1.5.5+ds-1 [185 kB] 194s Get:54 http://ftpmaster.internal/ubuntu resolute/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10build2 [130 kB] 194s Get:55 http://ftpmaster.internal/ubuntu resolute/main armhf libunbound8 armhf 1.24.2-1ubuntu1 [433 kB] 194s Get:56 http://ftpmaster.internal/ubuntu resolute/universe armhf libvo-amrwbenc0 armhf 0.1.3-2build2 [66.4 kB] 194s Get:57 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbisfile3 armhf 1.3.7-3build1 [16.4 kB] 194s Get:58 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk-modules armhf 1:22.8.2+dfsg+~cs6.15.60671435-1 [3024 kB] 194s Get:59 http://ftpmaster.internal/ubuntu resolute/universe armhf liburiparser1 armhf 0.9.8+dfsg-2 [30.3 kB] 194s Get:60 http://ftpmaster.internal/ubuntu resolute/main armhf libxslt1.1 armhf 1.1.43-0.3 [146 kB] 194s Get:61 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk armhf 1:22.8.2+dfsg+~cs6.15.60671435-1 [2537 kB] 194s Get:62 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk-tests armhf 1:22.8.2+dfsg+~cs6.15.60671435-1 [618 kB] 194s Get:63 http://ftpmaster.internal/ubuntu resolute/main armhf libxml2-utils armhf 2.15.1+dfsg-2ubuntu1 [41.0 kB] 195s Fetched 28.5 MB in 2s (15.2 MB/s) 195s Selecting previously unselected package asterisk-config. 195s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 68686 files and directories currently installed.) 195s Preparing to unpack .../00-asterisk-config_1%3a22.8.2+dfsg+~cs6.15.60671435-1_all.deb ... 195s Unpacking asterisk-config (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 195s Selecting previously unselected package asterisk-core-sounds-en-gsm. 195s Preparing to unpack .../01-asterisk-core-sounds-en-gsm_1.6.1-1build1_all.deb ... 195s Unpacking asterisk-core-sounds-en-gsm (1.6.1-1build1) ... 195s Selecting previously unselected package asterisk-core-sounds-en. 195s Preparing to unpack .../02-asterisk-core-sounds-en_1.6.1-1build1_all.deb ... 195s Unpacking asterisk-core-sounds-en (1.6.1-1build1) ... 195s Selecting previously unselected package libcodec2-1.2:armhf. 195s Preparing to unpack .../03-libcodec2-1.2_1.2.0-4_armhf.deb ... 195s Unpacking libcodec2-1.2:armhf (1.2.0-4) ... 195s Selecting previously unselected package libgmime-3.0-0t64:armhf. 195s Preparing to unpack .../04-libgmime-3.0-0t64_3.2.15+dfsg-1build1_armhf.deb ... 195s Unpacking libgmime-3.0-0t64:armhf (3.2.15+dfsg-1build1) ... 195s Selecting previously unselected package libgsm1:armhf. 195s Preparing to unpack .../05-libgsm1_1.0.23-2_armhf.deb ... 195s Unpacking libgsm1:armhf (1.0.23-2) ... 195s Selecting previously unselected package libical3t64:armhf. 195s Preparing to unpack .../06-libical3t64_3.0.20-2build1_armhf.deb ... 195s Unpacking libical3t64:armhf (3.0.20-2build1) ... 195s Selecting previously unselected package libiksemel3:armhf. 195s Preparing to unpack .../07-libiksemel3_1.4-4build3_armhf.deb ... 195s Unpacking libiksemel3:armhf (1.4-4build3) ... 195s Selecting previously unselected package libopus0:armhf. 195s Preparing to unpack .../08-libopus0_1.6.1-1_armhf.deb ... 195s Unpacking libopus0:armhf (1.6.1-1) ... 195s Selecting previously unselected package libsamplerate0:armhf. 195s Preparing to unpack .../09-libsamplerate0_0.2.2-4build2_armhf.deb ... 195s Unpacking libsamplerate0:armhf (0.2.2-4build2) ... 195s Selecting previously unselected package libjack-jackd2-0:armhf. 195s Preparing to unpack .../10-libjack-jackd2-0_1.9.22~dfsg-5build1_armhf.deb ... 195s Unpacking libjack-jackd2-0:armhf (1.9.22~dfsg-5build1) ... 196s Selecting previously unselected package libb64-0d:armhf. 196s Preparing to unpack .../11-libb64-0d_1.2-5build2_armhf.deb ... 196s Unpacking libb64-0d:armhf (1.2-5build2) ... 196s Selecting previously unselected package libjwt2:armhf. 196s Preparing to unpack .../12-libjwt2_1.17.2-1build1_armhf.deb ... 196s Unpacking libjwt2:armhf (1.17.2-1build1) ... 196s Selecting previously unselected package liblua5.1-0:armhf. 196s Preparing to unpack .../13-liblua5.1-0_5.1.5-11build1_armhf.deb ... 196s Unpacking liblua5.1-0:armhf (5.1.5-11build1) ... 196s Selecting previously unselected package libneon27t64:armhf. 196s Preparing to unpack .../14-libneon27t64_0.36.0-1_armhf.deb ... 196s Unpacking libneon27t64:armhf (0.36.0-1) ... 196s Selecting previously unselected package libltdl7:armhf. 196s Preparing to unpack .../15-libltdl7_2.5.4-9_armhf.deb ... 196s Unpacking libltdl7:armhf (2.5.4-9) ... 196s Selecting previously unselected package libodbc2:armhf. 196s Preparing to unpack .../16-libodbc2_2.3.14-1_armhf.deb ... 196s Unpacking libodbc2:armhf (2.3.14-1) ... 196s Selecting previously unselected package libogg0:armhf. 196s Preparing to unpack .../17-libogg0_1.3.6-2_armhf.deb ... 196s Unpacking libogg0:armhf (1.3.6-2) ... 196s Selecting previously unselected package libopencore-amrnb0:armhf. 196s Preparing to unpack .../18-libopencore-amrnb0_0.1.6-1build2_armhf.deb ... 196s Unpacking libopencore-amrnb0:armhf (0.1.6-1build2) ... 196s Selecting previously unselected package libopencore-amrwb0:armhf. 196s Preparing to unpack .../19-libopencore-amrwb0_0.1.6-1build2_armhf.deb ... 196s Unpacking libopencore-amrwb0:armhf (0.1.6-1build2) ... 196s Selecting previously unselected package libopusenc0:armhf. 196s Preparing to unpack .../20-libopusenc0_0.3-1_armhf.deb ... 196s Unpacking libopusenc0:armhf (0.3-1) ... 196s Selecting previously unselected package libopusfile0:armhf. 196s Preparing to unpack .../21-libopusfile0_0.12-4build4_armhf.deb ... 196s Unpacking libopusfile0:armhf (0.12-4build4) ... 196s Selecting previously unselected package libasound2-data. 196s Preparing to unpack .../22-libasound2-data_1.2.15.3-1ubuntu1_all.deb ... 196s Unpacking libasound2-data (1.2.15.3-1ubuntu1) ... 196s Selecting previously unselected package libasound2t64:armhf. 196s Preparing to unpack .../23-libasound2t64_1.2.15.3-1ubuntu1_armhf.deb ... 196s Unpacking libasound2t64:armhf (1.2.15.3-1ubuntu1) ... 196s Selecting previously unselected package libasyncns0:armhf. 196s Preparing to unpack .../24-libasyncns0_0.8-7_armhf.deb ... 196s Unpacking libasyncns0:armhf (0.8-7) ... 196s Selecting previously unselected package libflac14:armhf. 196s Preparing to unpack .../25-libflac14_1.5.0+ds-5_armhf.deb ... 196s Unpacking libflac14:armhf (1.5.0+ds-5) ... 196s Selecting previously unselected package libmp3lame0:armhf. 196s Preparing to unpack .../26-libmp3lame0_3.100-6build2_armhf.deb ... 196s Unpacking libmp3lame0:armhf (3.100-6build2) ... 196s Selecting previously unselected package libmpg123-0t64:armhf. 196s Preparing to unpack .../27-libmpg123-0t64_1.33.3-2_armhf.deb ... 196s Unpacking libmpg123-0t64:armhf (1.33.3-2) ... 196s Selecting previously unselected package libvorbis0a:armhf. 196s Preparing to unpack .../28-libvorbis0a_1.3.7-3build1_armhf.deb ... 196s Unpacking libvorbis0a:armhf (1.3.7-3build1) ... 196s Selecting previously unselected package libvorbisenc2:armhf. 196s Preparing to unpack .../29-libvorbisenc2_1.3.7-3build1_armhf.deb ... 196s Unpacking libvorbisenc2:armhf (1.3.7-3build1) ... 196s Selecting previously unselected package libsndfile1:armhf. 196s Preparing to unpack .../30-libsndfile1_1.2.2-4_armhf.deb ... 196s Unpacking libsndfile1:armhf (1.2.2-4) ... 196s Selecting previously unselected package libx11-xcb1:armhf. 196s Preparing to unpack .../31-libx11-xcb1_2%3a1.8.12-1build1_armhf.deb ... 196s Unpacking libx11-xcb1:armhf (2:1.8.12-1build1) ... 196s Selecting previously unselected package libpulse0:armhf. 196s Preparing to unpack .../32-libpulse0_1%3a17.0+dfsg1-2ubuntu4_armhf.deb ... 196s Unpacking libpulse0:armhf (1:17.0+dfsg1-2ubuntu4) ... 196s Selecting previously unselected package libportaudio2:armhf. 196s Preparing to unpack .../33-libportaudio2_19.7.0+git20260206.e1b70d33-0ubuntu1_armhf.deb ... 196s Unpacking libportaudio2:armhf (19.7.0+git20260206.e1b70d33-0ubuntu1) ... 196s Selecting previously unselected package libpq5:armhf. 196s Preparing to unpack .../34-libpq5_18.1-2_armhf.deb ... 196s Unpacking libpq5:armhf (18.1-2) ... 196s Selecting previously unselected package libradcli4. 196s Preparing to unpack .../35-libradcli4_1.2.11-1build4_armhf.deb ... 196s Unpacking libradcli4 (1.2.11-1build4) ... 196s Selecting previously unselected package libresample1. 197s Preparing to unpack .../36-libresample1_0.1.3-8build1_armhf.deb ... 197s Unpacking libresample1 (0.1.3-8build1) ... 197s Selecting previously unselected package libsnmp-base. 197s Preparing to unpack .../37-libsnmp-base_5.9.4+dfsg-2ubuntu3_all.deb ... 197s Unpacking libsnmp-base (5.9.4+dfsg-2ubuntu3) ... 197s Selecting previously unselected package libsnmp40t64:armhf. 197s Preparing to unpack .../38-libsnmp40t64_5.9.4+dfsg-2ubuntu3_armhf.deb ... 197s Unpacking libsnmp40t64:armhf (5.9.4+dfsg-2ubuntu3) ... 197s Selecting previously unselected package libdeflate0:armhf. 197s Preparing to unpack .../39-libdeflate0_1.23-2build1_armhf.deb ... 197s Unpacking libdeflate0:armhf (1.23-2build1) ... 197s Selecting previously unselected package libjbig0:armhf. 197s Preparing to unpack .../40-libjbig0_2.1-6.1ubuntu3_armhf.deb ... 197s Unpacking libjbig0:armhf (2.1-6.1ubuntu3) ... 197s Selecting previously unselected package libjpeg-turbo8:armhf. 197s Preparing to unpack .../41-libjpeg-turbo8_2.1.5-4ubuntu3_armhf.deb ... 197s Unpacking libjpeg-turbo8:armhf (2.1.5-4ubuntu3) ... 197s Selecting previously unselected package libjpeg8:armhf. 197s Preparing to unpack .../42-libjpeg8_8c-2ubuntu11_armhf.deb ... 197s Unpacking libjpeg8:armhf (8c-2ubuntu11) ... 197s Selecting previously unselected package liblerc4:armhf. 197s Preparing to unpack .../43-liblerc4_4.0.0+ds-5ubuntu2_armhf.deb ... 197s Unpacking liblerc4:armhf (4.0.0+ds-5ubuntu2) ... 197s Selecting previously unselected package libsharpyuv0:armhf. 197s Preparing to unpack .../44-libsharpyuv0_1.5.0-0.1build1_armhf.deb ... 197s Unpacking libsharpyuv0:armhf (1.5.0-0.1build1) ... 197s Selecting previously unselected package libwebp7:armhf. 197s Preparing to unpack .../45-libwebp7_1.5.0-0.1build1_armhf.deb ... 197s Unpacking libwebp7:armhf (1.5.0-0.1build1) ... 197s Selecting previously unselected package libtiff6:armhf. 197s Preparing to unpack .../46-libtiff6_4.7.0-3ubuntu3_armhf.deb ... 197s Unpacking libtiff6:armhf (4.7.0-3ubuntu3) ... 197s Selecting previously unselected package libspandsp2t64:armhf. 197s Preparing to unpack .../47-libspandsp2t64_0.0.6+dfsg-2.2build1_armhf.deb ... 197s Unpacking libspandsp2t64:armhf (0.0.6+dfsg-2.2build1) ... 197s Selecting previously unselected package libspeex1:armhf. 197s Preparing to unpack .../48-libspeex1_1.2.1-3build1_armhf.deb ... 197s Unpacking libspeex1:armhf (1.2.1-3build1) ... 197s Selecting previously unselected package libspeexdsp1:armhf. 197s Preparing to unpack .../49-libspeexdsp1_1.2.1-3build1_armhf.deb ... 197s Unpacking libspeexdsp1:armhf (1.2.1-3build1) ... 197s Selecting previously unselected package libsrtp2-1:armhf. 197s Preparing to unpack .../50-libsrtp2-1_2.7.0-3build1_armhf.deb ... 197s Unpacking libsrtp2-1:armhf (2.7.0-3build1) ... 197s Selecting previously unselected package freetds-common. 197s Preparing to unpack .../51-freetds-common_1.5.5+ds-1_all.deb ... 197s Unpacking freetds-common (1.5.5+ds-1) ... 197s Selecting previously unselected package libsybdb5:armhf. 197s Preparing to unpack .../52-libsybdb5_1.5.5+ds-1_armhf.deb ... 197s Unpacking libsybdb5:armhf (1.5.5+ds-1) ... 197s Selecting previously unselected package libevent-2.1-7t64:armhf. 197s Preparing to unpack .../53-libevent-2.1-7t64_2.1.12-stable-10build2_armhf.deb ... 197s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10build2) ... 197s Selecting previously unselected package libunbound8:armhf. 197s Preparing to unpack .../54-libunbound8_1.24.2-1ubuntu1_armhf.deb ... 197s Unpacking libunbound8:armhf (1.24.2-1ubuntu1) ... 197s Selecting previously unselected package libvo-amrwbenc0:armhf. 197s Preparing to unpack .../55-libvo-amrwbenc0_0.1.3-2build2_armhf.deb ... 197s Unpacking libvo-amrwbenc0:armhf (0.1.3-2build2) ... 197s Selecting previously unselected package libvorbisfile3:armhf. 197s Preparing to unpack .../56-libvorbisfile3_1.3.7-3build1_armhf.deb ... 197s Unpacking libvorbisfile3:armhf (1.3.7-3build1) ... 197s Selecting previously unselected package asterisk-modules. 197s Preparing to unpack .../57-asterisk-modules_1%3a22.8.2+dfsg+~cs6.15.60671435-1_armhf.deb ... 197s Unpacking asterisk-modules (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Selecting previously unselected package liburiparser1:armhf. 198s Preparing to unpack .../58-liburiparser1_0.9.8+dfsg-2_armhf.deb ... 198s Unpacking liburiparser1:armhf (0.9.8+dfsg-2) ... 198s Selecting previously unselected package libxslt1.1:armhf. 198s Preparing to unpack .../59-libxslt1.1_1.1.43-0.3_armhf.deb ... 198s Unpacking libxslt1.1:armhf (1.1.43-0.3) ... 198s Selecting previously unselected package asterisk. 198s Preparing to unpack .../60-asterisk_1%3a22.8.2+dfsg+~cs6.15.60671435-1_armhf.deb ... 198s Unpacking asterisk (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Selecting previously unselected package asterisk-tests. 198s Preparing to unpack .../61-asterisk-tests_1%3a22.8.2+dfsg+~cs6.15.60671435-1_armhf.deb ... 198s Unpacking asterisk-tests (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Selecting previously unselected package libxml2-utils. 198s Preparing to unpack .../62-libxml2-utils_2.15.1+dfsg-2ubuntu1_armhf.deb ... 198s Unpacking libxml2-utils (2.15.1+dfsg-2ubuntu1) ... 198s Setting up libsharpyuv0:armhf (1.5.0-0.1build1) ... 198s Setting up libx11-xcb1:armhf (2:1.8.12-1build1) ... 198s Setting up libvo-amrwbenc0:armhf (0.1.3-2build2) ... 198s Setting up libneon27t64:armhf (0.36.0-1) ... 198s Setting up libogg0:armhf (1.3.6-2) ... 198s Setting up liblerc4:armhf (4.0.0+ds-5ubuntu2) ... 198s Setting up libspeex1:armhf (1.2.1-3build1) ... 198s Setting up libgsm1:armhf (1.0.23-2) ... 198s Setting up asterisk-core-sounds-en-gsm (1.6.1-1build1) ... 198s Setting up libsnmp-base (5.9.4+dfsg-2ubuntu3) ... 198s Setting up libcodec2-1.2:armhf (1.2.0-4) ... 198s Setting up libsrtp2-1:armhf (2.7.0-3build1) ... 198s Setting up libpq5:armhf (18.1-2) ... 198s Setting up libdeflate0:armhf (1.23-2build1) ... 198s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10build2) ... 198s Setting up libb64-0d:armhf (1.2-5build2) ... 198s Setting up libmpg123-0t64:armhf (1.33.3-2) ... 198s Setting up libjwt2:armhf (1.17.2-1build1) ... 198s Setting up libjbig0:armhf (2.1-6.1ubuntu3) ... 198s Setting up libasound2-data (1.2.15.3-1ubuntu1) ... 198s Setting up libopencore-amrwb0:armhf (0.1.6-1build2) ... 198s Setting up libunbound8:armhf (1.24.2-1ubuntu1) ... 198s Setting up libasound2t64:armhf (1.2.15.3-1ubuntu1) ... 198s Setting up libradcli4 (1.2.11-1build4) ... 198s Setting up libopus0:armhf (1.6.1-1) ... 198s Setting up asterisk-config (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Setting up libvorbis0a:armhf (1.3.7-3build1) ... 198s Setting up libflac14:armhf (1.5.0+ds-5) ... 198s Setting up libical3t64:armhf (3.0.20-2build1) ... 198s Setting up libjpeg-turbo8:armhf (2.1.5-4ubuntu3) ... 198s Setting up libltdl7:armhf (2.5.4-9) ... 198s Setting up asterisk-core-sounds-en (1.6.1-1build1) ... 198s update-alternatives: using /usr/share/asterisk/sounds/en_US_f_Allison to provide /usr/share/asterisk/sounds/en (asterisk-prompt-en) in auto mode 198s update-alternatives: using /usr/share/asterisk/sounds/en_US_f_Allison to provide /usr/share/asterisk/sounds/en_US (asterisk-prompt-en-us) in auto mode 198s Setting up libwebp7:armhf (1.5.0-0.1build1) ... 198s Setting up libiksemel3:armhf (1.4-4build3) ... 198s Setting up libodbc2:armhf (2.3.14-1) ... 198s Setting up liburiparser1:armhf (0.9.8+dfsg-2) ... 198s Setting up libasyncns0:armhf (0.8-7) ... 198s Setting up libspeexdsp1:armhf (1.2.1-3build1) ... 198s Setting up liblua5.1-0:armhf (5.1.5-11build1) ... 198s Setting up libxslt1.1:armhf (1.1.43-0.3) ... 198s Setting up libopencore-amrnb0:armhf (0.1.6-1build2) ... 198s Setting up libresample1 (0.1.3-8build1) ... 198s Setting up freetds-common (1.5.5+ds-1) ... 198s Setting up libxml2-utils (2.15.1+dfsg-2ubuntu1) ... 198s Setting up libgmime-3.0-0t64:armhf (3.2.15+dfsg-1build1) ... 198s Setting up libsamplerate0:armhf (0.2.2-4build2) ... 198s Setting up libopusfile0:armhf (0.12-4build4) ... 198s Setting up libmp3lame0:armhf (3.100-6build2) ... 198s Setting up libvorbisenc2:armhf (1.3.7-3build1) ... 198s Setting up libjpeg8:armhf (8c-2ubuntu11) ... 198s Setting up libopusenc0:armhf (0.3-1) ... 198s Setting up libsnmp40t64:armhf (5.9.4+dfsg-2ubuntu3) ... 198s Setting up libsybdb5:armhf (1.5.5+ds-1) ... 198s Setting up libvorbisfile3:armhf (1.3.7-3build1) ... 198s Setting up libjack-jackd2-0:armhf (1.9.22~dfsg-5build1) ... 198s Setting up libtiff6:armhf (4.7.0-3ubuntu3) ... 198s Setting up libsndfile1:armhf (1.2.2-4) ... 198s Setting up libpulse0:armhf (1:17.0+dfsg1-2ubuntu4) ... 198s Setting up libspandsp2t64:armhf (0.0.6+dfsg-2.2build1) ... 198s Setting up libportaudio2:armhf (19.7.0+git20260206.e1b70d33-0ubuntu1) ... 198s Setting up asterisk-modules (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Setting up asterisk (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 198s Adding system user for Asterisk 199s Created symlink '/etc/systemd/system/multi-user.target.wants/asterisk.service' → '/usr/lib/systemd/system/asterisk.service'. 201s Setting up asterisk-tests (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 201s Processing triggers for libc-bin (2.42-2ubuntu4) ... 201s Processing triggers for man-db (2.13.1-1build1) ... 212s autopkgtest [18:13:57]: test asttestmods: [----------------------- 213s dpkg-architecture: warning: cannot determine CC system type, falling back to default (native compilation) 215s 'U' option is not compatible with remote console mode and has no effect. 215s 215s Asterisk has fully booted. 215s 'U' option is not compatible with remote console mode and has no effect. 215s 215s Running all available tests... 215s 215s START /apps/app_gosub/ - gosub application 215s END /apps/app_gosub/ - gosub application Time: <1ms Result: PASS 215s START /apps/app_voicemail/ - test_voicemail_vm_info 215s END /apps/app_voicemail/ - test_voicemail_vm_info Time: <1ms Result: PASS 215s START /apps/app_voicemail/ - test_voicemail_load_config 215s END /apps/app_voicemail/ - test_voicemail_load_config Time: <1ms Result: PASS 215s START /apps/app_voicemail/ - test_voicemail_notify_endl 215s END /apps/app_voicemail/ - test_voicemail_notify_endl Time: 4ms Result: PASS 215s START /apps/app_voicemail/ - vmuser 215s END /apps/app_voicemail/ - vmuser Time: <1ms Result: PASS 215s START /apps/app_voicemail/ - test_voicemail_msgcount 215s END /apps/app_voicemail/ - test_voicemail_msgcount Time: 93ms Result: PASS 215s START /apps/app_voicemail/ - vmsayname_exec 215s [app_voicemail.c:test_voicemail_vmsayname:15749]: Test playing of extension when greeting is not available... 220s [app_voicemail.c:test_voicemail_vmsayname:15770]: Test playing created mailbox greeting... 224s END /apps/app_voicemail/ - vmsayname_exec Time: 8889ms Result: PASS 224s START /ari/validators/ - validate_list 224s END /ari/validators/ - validate_list Time: <1ms Result: PASS 224s START /ari/validators/ - validate_date 224s END /ari/validators/ - validate_date Time: <1ms Result: PASS 224s START /ari/validators/ - validate_string 224s END /ari/validators/ - validate_string Time: <1ms Result: PASS 224s START /ari/validators/ - validate_long 224s END /ari/validators/ - validate_long Time: <1ms Result: PASS 224s START /ari/validators/ - validate_int 224s END /ari/validators/ - validate_int Time: <1ms Result: PASS 224s START /ari/validators/ - validate_boolean 224s END /ari/validators/ - validate_boolean Time: <1ms Result: PASS 224s START /ari/validators/ - validate_byte 224s END /ari/validators/ - validate_byte Time: <1ms Result: PASS 224s START /bridges/bridge_softmix/ - sfu_remove_destination_streams 224s END /bridges/bridge_softmix/ - sfu_remove_destination_streams Time: <1ms Result: PASS 224s START /bridges/bridge_softmix/ - sfu_append_source_streams 224s END /bridges/bridge_softmix/ - sfu_append_source_streams Time: <1ms Result: PASS 224s START /channels/features/ - test_features_channel_interval 227s END /channels/features/ - test_features_channel_interval Time: 3003ms Result: PASS 227s START /channels/features/ - test_features_channel_dtmf 229s END /channels/features/ - test_features_channel_dtmf Time: 2016ms Result: PASS 229s START /config/ - config_options_test 229s END /config/ - config_options_test Time: <1ms Result: PASS 229s START /config/ - ast_parse_arg 229s END /config/ - ast_parse_arg Time: <1ms Result: PASS 229s START /core/endpoints/ - setters 229s END /core/endpoints/ - setters Time: <1ms Result: PASS 229s START /core/endpoints/ - defaults 229s END /core/endpoints/ - defaults Time: <1ms Result: PASS 229s START /core/endpoints/ - create 229s END /core/endpoints/ - create Time: <1ms Result: PASS 229s START /funcs/func_curl/ - vulnerable_url 229s END /funcs/func_curl/ - vulnerable_url Time: <1ms Result: PASS 229s START /funcs/func_env/ - func_file 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1)=a'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,1)=a'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-8)=a'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,-8)=a'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,5,1)=b'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-4,1)=b'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,5,-3)=b'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-4,-3)=b'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,2)=c'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,2)=c'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-7)=c'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,-7)=c'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,4,2)=d'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-5,2)=d'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,4,-3)=d'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-5,-3)=d'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,5)=e'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,5)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-4)=e'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-4)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1)=fg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-8)=fg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,1)=fg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-9,-8)=fg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,)=h'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,,,,)=h'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,,,l)=h'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,,,ld)=h'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-2,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-2,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,-1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,-1,l)=abc'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-2,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-2,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,-1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,-1,l)=ab'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,-1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,-1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,2,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,2,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-1,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-1,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,2,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,2,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,,l)=abcdefg'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,2,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,2,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,2,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-1,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-1,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,2,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,2,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,,l)=abcdefghijklmnop'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,2,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-1,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,3,0,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,,,la)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-2,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-2,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,-1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,-1,l)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,-2,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-3,-2,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,1,-1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,-2,-1,ld)='... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1)=a'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,10)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=abcd'... OK! 229s [test_func_file.c:test_func_file:375]: Expression 'FILE(...,0,1,l)=a'... OK! 229s END /funcs/func_env/ - func_file Time: 20ms Result: PASS 229s START /funcs/func_json/ - func_JSON_DECODE 229s END /funcs/func_json/ - func_JSON_DECODE Time: <1ms Result: PASS 229s START /funcs/func_presence/ - test_presence_state_base64_encode 229s END /funcs/func_presence/ - test_presence_state_base64_encode Time: 17ms Result: PASS 229s START /funcs/func_presence/ - test_presence_state_change 229s END /funcs/func_presence/ - test_presence_state_change Time: <1ms Result: PASS 229s START /funcs/func_presence/ - parse_invalid_presence_data 229s END /funcs/func_presence/ - parse_invalid_presence_data Time: <1ms Result: PASS 229s START /funcs/func_presence/ - parse_valid_presence_data 229s END /funcs/func_presence/ - parse_valid_presence_data Time: <1ms Result: PASS 229s START /funcs/func_sayfiles/ - test_SAYFILES_function 229s [func_sayfiles.c:test_SAYFILES_function:179]: Testing SAYFILES() substitution ... 229s END /funcs/func_sayfiles/ - test_SAYFILES_function Time: 6ms Result: PASS 229s START /funcs/func_strings/ - func_TRIM 229s END /funcs/func_strings/ - func_TRIM Time: <1ms Result: PASS 229s START /funcs/func_strings/ - func_STRBETWEEN 229s END /funcs/func_strings/ - func_STRBETWEEN Time: <1ms Result: PASS 229s START /funcs/func_strings/ - func_STRREPLACE_test 229s END /funcs/func_strings/ - func_STRREPLACE_test Time: <1ms Result: PASS 229s START /funcs/func_strings/ - func_FILTER_test 229s END /funcs/func_strings/ - func_FILTER_test Time: <1ms Result: PASS 229s START /funcs/func_strings/ - func_REPLACE_test 229s END /funcs/func_strings/ - func_REPLACE_test Time: <1ms Result: PASS 229s START /funcs/func_strings/ - func_FIELDNUM_test 229s END /funcs/func_strings/ - func_FIELDNUM_test Time: <1ms Result: PASS 229s START /geoloc/ - create_from_pidf 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1364]: eprofile: ID: 'pres:alice@asterisk.org' pidf_element: 'tuple' peid: point-2d format: 'GML' method: 'Manual' device_id: 'mac:112233445566' 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1370]: xml: ID: 'pres:alice@asterisk.org' pidf_element: 'tuple' peid: point-2d format: 'GML' method: 'Manual' device_id: 'mac:112233445566' 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1386]: location_vars expected: shape=Point,crs=2d,pos=-34.410649 150.87651 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1387]: location_vars received: shape=Point,crs=2d,pos=-34.410649 150.87651 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1393]: usage_rules expected: retransmission-allowed='no',retention-expiry='2010-11-14T20:00:00Z' 229s [res_geolocation/geoloc_eprofile.c:validate_eprofile:1394]: usage_rules received: retransmission-allowed='no',retention-expiry='2010-11-14T20:00:00Z' 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //def:tuple/@id eprofile: point-2d pidf: point-2d Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gml:Point/@srsName eprofile: urn:ogc:def:crs:EPSG::4326 pidf: urn:ogc:def:crs:EPSG::4326 Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gml:pos/text() eprofile: -34.410649 150.87651 pidf: -34.410649 150.87651 Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //con:confidence/text() eprofile: 66 pidf: 66 Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //con:confidence/@pdf eprofile: normal pidf: normal Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1487]: Element: //gp:usage-rules eprofile: exists pidf: exists Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gbp:retransmission-allowed/text() eprofile: no pidf: no Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gbp:retention-expiry/text() eprofile: 2010-11-14T20:00:00Z pidf: 2010-11-14T20:00:00Z Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gp:method/text() eprofile: Manual pidf: Manual Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //gp:note-well/text() eprofile: this is a test of the emergency broadcast system pidf: this is a test of the emergency broadcast system Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1478]: Element: //dm:deviceID/text() eprofile: mac:112233445566 pidf: mac:112233445566 Result: pass 229s [res_geolocation/geoloc_eprofile.c:validate_xml:1487]: Element: //def:timestamp eprofile: exists pidf: exists Result: pass 229s END /geoloc/ - create_from_pidf Time: <1ms Result: PASS 229s START /geoloc/ - create_from_uri 229s END /geoloc/ - create_from_uri Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_FIXED_put_out_of_order 229s [test_abstract_jb.c:AST_JB_FIXED_put_out_of_order:531]: Executing AST_JB_FIXED_put_out_of_order... 229s END /main/abstract_jb/ - AST_JB_FIXED_put_out_of_order Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_FIXED_put_overflow 229s [test_abstract_jb.c:AST_JB_FIXED_put_overflow:529]: Executing AST_JB_FIXED_put_overflow... 229s END /main/abstract_jb/ - AST_JB_FIXED_put_overflow Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_FIXED_put 229s [test_abstract_jb.c:AST_JB_FIXED_put:527]: Executing AST_JB_FIXED_put... 229s END /main/abstract_jb/ - AST_JB_FIXED_put Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_FIXED_put_first 229s [test_abstract_jb.c:AST_JB_FIXED_put_first:525]: Executing AST_JB_FIXED_create... 229s END /main/abstract_jb/ - AST_JB_FIXED_put_first Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_FIXED_create 229s [test_abstract_jb.c:AST_JB_FIXED_create:523]: Executing AST_JB_FIXED_create... 229s END /main/abstract_jb/ - AST_JB_FIXED_create Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_ADAPTIVE_put_out_of_order 229s [test_abstract_jb.c:AST_JB_ADAPTIVE_put_out_of_order:521]: Executing AST_JB_ADAPTIVE_put_out_of_order... 229s END /main/abstract_jb/ - AST_JB_ADAPTIVE_put_out_of_order Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_ADAPTIVE_put_overflow 229s [test_abstract_jb.c:AST_JB_ADAPTIVE_put_overflow:519]: Executing AST_JB_ADAPTIVE_put_overflow... 229s END /main/abstract_jb/ - AST_JB_ADAPTIVE_put_overflow Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_ADAPTIVE_put 229s [test_abstract_jb.c:AST_JB_ADAPTIVE_put:517]: Executing AST_JB_ADAPTIVE_put... 229s END /main/abstract_jb/ - AST_JB_ADAPTIVE_put Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_ADAPTIVE_put_first 229s [test_abstract_jb.c:AST_JB_ADAPTIVE_put_first:515]: Executing AST_JB_ADAPTIVE_create... 229s END /main/abstract_jb/ - AST_JB_ADAPTIVE_put_first Time: <1ms Result: PASS 229s START /main/abstract_jb/ - AST_JB_ADAPTIVE_create 229s [test_abstract_jb.c:AST_JB_ADAPTIVE_create:513]: Executing AST_JB_ADAPTIVE_create... 229s END /main/abstract_jb/ - AST_JB_ADAPTIVE_create Time: <1ms Result: PASS 229s START /main/acl/ - acl 229s END /main/acl/ - acl Time: <1ms Result: PASS 229s START /main/acl/ - invalid_acl 229s END /main/acl/ - invalid_acl Time: <1ms Result: PASS 229s START /main/amihooks/ - amihook_cli_send 229s END /main/amihooks/ - amihook_cli_send Time: <1ms Result: PASS 229s START /main/aoc/ - aoc_event_test 229s END /main/aoc/ - aoc_event_test Time: <1ms Result: PASS 229s START /main/aoc/ - aoc_encode_decode_test 229s END /main/aoc/ - aoc_encode_decode_test Time: <1ms Result: PASS 229s START /main/app/ - options_parsing 229s END /main/app/ - options_parsing Time: <1ms Result: PASS 229s START /main/app/ - app_group 229s [test_app.c:app_group:172]: Creating test channels with the following groups: 229s 'a groupgroupgroup', 'a groupGroupgroup', 'a group@a_category', 'a group@another!Category' 229s [test_app.c:app_group:211]: (Subtest 1) Found 4 matches as expected when examining group:'gr' category:'' 229s [test_app.c:app_group:211]: (Subtest 2) Found 1 matches as expected when examining group:'(group){2}$' category:'' 229s [test_app.c:app_group:211]: (Subtest 3) Found 4 matches as expected when examining group:'[:ascii:]' category:'' 229s [test_app.c:app_group:211]: (Subtest 4) Found 0 matches as expected when examining group:'^(NOMATCH)' category:'' 229s [test_app.c:app_group:211]: (Subtest 5) Found 2 matches as expected when examining group:'a group' category:'(gory)$' 229s [test_app.c:app_group:211]: (Subtest 6) Found 1 matches as expected when examining group:'a group' category:'[A-Z]+' 229s [test_app.c:app_group:211]: (Subtest 7) Found 0 matches as expected when examining group:'a group' category:'[[' 229s END /main/app/ - app_group Time: <1ms Result: PASS 229s START /main/ast_expr/ - expr_test 229s END /main/ast_expr/ - expr_test Time: <1ms Result: PASS 229s START /main/astdb/ - put_get_long 229s END /main/astdb/ - put_get_long Time: 129ms Result: PASS 229s START /main/astdb/ - perftest 230s END /main/astdb/ - perftest Time: 343ms Result: PASS 230s START /main/astdb/ - gettree_deltree 230s END /main/astdb/ - gettree_deltree Time: <1ms Result: PASS 230s START /main/astdb/ - put_get_del 230s END /main/astdb/ - put_get_del Time: <1ms Result: PASS 230s START /main/astobj2/ - astobj2_weak_container 230s END /main/astobj2/ - astobj2_weak_container Time: <1ms Result: PASS 230s START /main/astobj2/ - astobj2_weak1 230s END /main/astobj2/ - astobj2_weak1 Time: <1ms Result: PASS 230s START /main/astobj2/ - thrash 230s [test_astobj2_thrash.c:hash_test:266]: Executing hash concurrency test... 230s END /main/astobj2/ - thrash Time: 887ms Result: PASS 230s START /main/astobj2/ - astobj2_test4 230s [test_astobj2.c:test_traversal_nonsorted:1389]: Test 1, List containers. 230s [test_astobj2.c:test_traversal_nonsorted:1389]: Test 2, Hash containers. 230s [test_astobj2.c:test_traversal_sorted:1615]: Test 3, List containers. 230s [test_astobj2.c:test_traversal_sorted:1615]: Test 4, Hash containers. 230s [test_astobj2.c:test_traversal_sorted:1615]: Test 5, RBTree containers. 230s END /main/astobj2/ - astobj2_test4 Time: <1ms Result: PASS 230s START /main/astobj2/ - astobj2_test3 230s END /main/astobj2/ - astobj2_test3 Time: <1ms Result: PASS 230s START /main/astobj2/ - astobj2_test2 230s END /main/astobj2/ - astobj2_test2 Time: <1ms Result: PASS 230s START /main/astobj2/ - astobj2_test1 230s [test_astobj2.c:astobj2_test_1_helper:434]: Test 1, List containers (non-sorted). 230s [test_astobj2.c:astobj2_test_1_helper:488]: List container created: buckets: 1, items: 50 230s [test_astobj2.c:astobj2_test_1_helper:434]: Test 2, List containers (sorted). 230s [test_astobj2.c:astobj2_test_1_helper:488]: List container created: buckets: 1, items: 50 230s [test_astobj2.c:astobj2_test_1_helper:434]: Test 3, Hash containers (non-sorted). 230s [test_astobj2.c:astobj2_test_1_helper:488]: Hash container created: buckets: 199, items: 1000 230s [test_astobj2.c:astobj2_test_1_helper:434]: Test 4, Hash containers (sorted). 230s [test_astobj2.c:astobj2_test_1_helper:488]: Hash container created: buckets: 63, items: 1000 230s [test_astobj2.c:astobj2_test_1_helper:434]: Test 4, RBTree containers (sorted). 230s [test_astobj2.c:astobj2_test_1_helper:488]: RBTree container created: buckets: 1, items: 1000 230s END /main/astobj2/ - astobj2_test1 Time: 11ms Result: PASS 230s START /main/astobj2/perf/ - astobj2_test_perf 231s [test_astobj2.c:testloop:2001]: 20.00K traversals, List 232s [test_astobj2.c:testloop:2001]: 40.00K traversals, List 233s [test_astobj2.c:testloop:2001]: 60.00K traversals, List 234s [test_astobj2.c:testloop:2001]: 80.00K traversals, List 235s [test_astobj2.c:testloop:2006]: 100.00K traversals, List : 4852 ms 236s [test_astobj2.c:testloop:2001]: 20.00K traversals, Hash 237s [test_astobj2.c:testloop:2001]: 40.00K traversals, Hash 237s [test_astobj2.c:testloop:2001]: 60.00K traversals, Hash 238s [test_astobj2.c:testloop:2001]: 80.00K traversals, Hash 239s [test_astobj2.c:testloop:2006]: 100.00K traversals, Hash : 3333 ms 239s [test_astobj2.c:testloop:2001]: 20.00K traversals, RBTree 240s [test_astobj2.c:testloop:2001]: 40.00K traversals, RBTree 241s [test_astobj2.c:testloop:2001]: 60.00K traversals, RBTree 242s [test_astobj2.c:testloop:2001]: 80.00K traversals, RBTree 242s [test_astobj2.c:testloop:2006]: 100.00K traversals, RBTree : 3761 ms 242s END /main/astobj2/perf/ - astobj2_test_perf Time: 11947ms Result: PASS 242s START /main/bridging/ - test_bridging_deferred_queue 243s END /main/bridging/ - test_bridging_deferred_queue Time: 1009ms Result: PASS 243s START /main/bucket/ - bucket_file_json 243s END /main/bucket/ - bucket_file_json Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_metadata_get 243s END /main/bucket/ - bucket_file_metadata_get Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_metadata_unset 243s END /main/bucket/ - bucket_file_metadata_unset Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_metadata_set 243s END /main/bucket/ - bucket_file_metadata_set Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_is_stale 243s END /main/bucket/ - bucket_file_is_stale Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_delete 243s END /main/bucket/ - bucket_file_delete Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_update 243s END /main/bucket/ - bucket_file_update Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_retrieve 243s END /main/bucket/ - bucket_file_retrieve Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_copy 243s END /main/bucket/ - bucket_file_copy Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_clone 243s END /main/bucket/ - bucket_file_clone Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_create 243s END /main/bucket/ - bucket_file_create Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_file_alloc 243s END /main/bucket/ - bucket_file_alloc Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_json 243s END /main/bucket/ - bucket_json Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_is_stale 243s END /main/bucket/ - bucket_is_stale Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_retrieve 243s END /main/bucket/ - bucket_retrieve Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_delete 243s END /main/bucket/ - bucket_delete Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_clone 243s END /main/bucket/ - bucket_clone Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_create 243s END /main/bucket/ - bucket_create Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_alloc 243s END /main/bucket/ - bucket_alloc Time: <1ms Result: PASS 243s START /main/bucket/ - bucket_scheme_register_unregister 243s END /main/bucket/ - bucket_scheme_register_unregister Time: <1ms Result: PASS 243s START /main/callerid/ - parse_off_nominal 243s END /main/callerid/ - parse_off_nominal Time: <1ms Result: PASS 243s START /main/callerid/ - parse_nominal 243s END /main/callerid/ - parse_nominal Time: <1ms Result: PASS 243s START /main/cdr/ - test_cdr_fork_cdr 245s Verifying expected record CDRTestChannel/Alice, 245s Finished expected record CDRTestChannel/Alice, 245s Verifying expected record CDRTestChannel/Alice, 245s Finished expected record CDRTestChannel/Alice, 245s Verifying expected record CDRTestChannel/Alice, 245s Finished expected record CDRTestChannel/Alice, 245s END /main/cdr/ - test_cdr_fork_cdr Time: 2001ms Result: PASS 245s START /main/cdr/ - test_cdr_no_reset_cdr 246s Verifying expected record CDRTestChannel/Alice, 246s Finished expected record CDRTestChannel/Alice, 246s END /main/cdr/ - test_cdr_no_reset_cdr Time: 1000ms Result: PASS 246s START /main/cdr/ - test_cdr_fields 248s Verifying expected record CDRTestChannel/Alice, 248s Finished expected record CDRTestChannel/Alice, 248s Verifying expected record CDRTestChannel/Alice, 248s Finished expected record CDRTestChannel/Alice, 248s Verifying expected record CDRTestChannel/Alice, 248s Finished expected record CDRTestChannel/Alice, 248s END /main/cdr/ - test_cdr_fields Time: 2001ms Result: PASS 248s START /main/cdr/ - test_cdr_park 251s Verifying expected record CDRTestChannel/Alice, 251s Finished expected record CDRTestChannel/Alice, 251s Verifying expected record CDRTestChannel/Bob, 251s Finished expected record CDRTestChannel/Bob, 251s END /main/cdr/ - test_cdr_park Time: 3002ms Result: PASS 251s START /main/cdr/ - test_cdr_dial_answer_multiparty 256s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 256s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 256s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 256s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 256s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/David 256s Finished expected record CDRTestChannel/Alice, CDRTestChannel/David 256s Verifying expected record CDRTestChannel/Bob, CDRTestChannel/David 256s Finished expected record CDRTestChannel/Bob, CDRTestChannel/David 256s Verifying expected record CDRTestChannel/Charlie, CDRTestChannel/David 256s Finished expected record CDRTestChannel/Charlie, CDRTestChannel/David 256s Verifying expected record CDRTestChannel/Charlie, CDRTestChannel/Bob 256s Finished expected record CDRTestChannel/Charlie, CDRTestChannel/Bob 256s END /main/cdr/ - test_cdr_dial_answer_multiparty Time: 5006ms Result: PASS 256s START /main/cdr/ - test_cdr_dial_answer_twoparty_bridge_b 259s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 259s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 259s END /main/cdr/ - test_cdr_dial_answer_twoparty_bridge_b Time: 3003ms Result: PASS 259s START /main/cdr/ - test_cdr_dial_answer_twoparty_bridge_a 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_answer_twoparty_bridge_a Time: 2001ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_answer_no_bridge 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Verifying expected record CDRTestChannel/Alice, 261s Finished expected record CDRTestChannel/Alice, 261s Verifying expected record CDRTestChannel/Bob, 261s Finished expected record CDRTestChannel/Bob, 261s END /main/cdr/ - test_cdr_dial_answer_no_bridge Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_parallel_failed 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/David 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/David 261s END /main/cdr/ - test_cdr_dial_parallel_failed Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_caller_cancel 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_caller_cancel Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_unavailable 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_unavailable Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_busy 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_busy Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_congestion 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_congestion Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_dial_unanswered 261s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 261s END /main/cdr/ - test_cdr_dial_unanswered Time: <1ms Result: PASS 261s START /main/cdr/ - test_cdr_outbound_bridged_call 264s Verifying expected record CDRTestChannel/Bob, 264s Finished expected record CDRTestChannel/Bob, 264s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 264s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 264s END /main/cdr/ - test_cdr_outbound_bridged_call Time: 3004ms Result: PASS 264s START /main/cdr/ - test_cdr_single_multiparty_bridge 268s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 268s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 268s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 268s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Charlie 268s Verifying expected record CDRTestChannel/Bob, CDRTestChannel/Charlie 268s Finished expected record CDRTestChannel/Bob, CDRTestChannel/Charlie 268s Verifying expected record CDRTestChannel/Charlie, 268s Finished expected record CDRTestChannel/Charlie, 268s END /main/cdr/ - test_cdr_single_multiparty_bridge Time: 4004ms Result: PASS 268s START /main/cdr/ - test_cdr_single_twoparty_bridge_b 271s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 271s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 271s Verifying expected record CDRTestChannel/Bob, 271s Finished expected record CDRTestChannel/Bob, 271s END /main/cdr/ - test_cdr_single_twoparty_bridge_b Time: 3002ms Result: PASS 271s START /main/cdr/ - test_cdr_single_twoparty_bridge_a 273s Verifying expected record CDRTestChannel/Alice, CDRTestChannel/Bob 273s Finished expected record CDRTestChannel/Alice, CDRTestChannel/Bob 273s Verifying expected record CDRTestChannel/Bob, 273s Finished expected record CDRTestChannel/Bob, 273s END /main/cdr/ - test_cdr_single_twoparty_bridge_a Time: 2001ms Result: PASS 273s START /main/cdr/ - test_cdr_single_bridge_continue 275s Verifying expected record CDRTestChannel/Alice, 275s Finished expected record CDRTestChannel/Alice, 275s Verifying expected record CDRTestChannel/Alice, 275s Finished expected record CDRTestChannel/Alice, 275s END /main/cdr/ - test_cdr_single_bridge_continue Time: 2001ms Result: PASS 275s START /main/cdr/ - test_cdr_single_bridge 277s Verifying expected record CDRTestChannel/Alice, 277s Finished expected record CDRTestChannel/Alice, 277s END /main/cdr/ - test_cdr_single_bridge Time: 2001ms Result: PASS 277s START /main/cdr/ - test_cdr_single_party 277s Verifying expected record CDRTestChannel/Alice, 277s Finished expected record CDRTestChannel/Alice, 277s END /main/cdr/ - test_cdr_single_party Time: <1ms Result: PASS 277s START /main/cdr/ - test_cdr_unanswered_outbound_call 277s Verifying expected record CDRTestChannel/Alice, 277s Finished expected record CDRTestChannel/Alice, 277s END /main/cdr/ - test_cdr_unanswered_outbound_call Time: <1ms Result: PASS 277s START /main/cdr/ - test_cdr_unanswered_inbound_call 277s Verifying expected record CDRTestChannel/Alice, 277s Finished expected record CDRTestChannel/Alice, 277s END /main/cdr/ - test_cdr_unanswered_inbound_call Time: <1ms Result: PASS 277s START /main/cdr/ - test_cdr_channel_creation 277s Verifying expected record CDRTestChannel/Alice, 277s Finished expected record CDRTestChannel/Alice, 277s END /main/cdr/ - test_cdr_channel_creation Time: <1ms Result: PASS 277s START /main/channel/ - add_fd 277s END /main/channel/ - add_fd Time: <1ms Result: PASS 277s START /main/channel/ - set_fd_grow 277s END /main/channel/ - set_fd_grow Time: <1ms Result: PASS 277s START /main/channelstorage/ - ao2_legacy 277s [channelstorage.c:test_storage_thread:201]: Channel Count: 500 277s [channelstorage.c:test_storage_thread:218]: create channels: 1560418776 277s [channelstorage.c:test_storage_thread:233]: by name exact: 1560418776 277s [channelstorage.c:test_storage_thread:244]: by uniqueid exact: 1560418776 277s [channelstorage.c:test_storage_thread:255]: by uniqueid via nm: 1560418776 277s [channelstorage.c:test_storage_thread:266]: by name prefix: 1560418776 277s [channelstorage.c:test_storage_thread:278]: by context/exten: 1560418776 277s [channelstorage.c:test_storage_thread:290]: iter all chan: 1560418776 277s [channelstorage.c:test_storage_thread:310]: iter 10 partial name: 1560418776 277s [channelstorage.c:test_storage_thread:332]: iter context/exten: 1560418776 277s [channelstorage.c:test_storage_thread:350]: del all channels: 1560418776 277s END /main/channelstorage/ - ao2_legacy Time: 48ms Result: PASS 277s START /main/config/ - variable_list_from_quoted_string 277s END /main/config/ - variable_list_from_quoted_string Time: <1ms Result: PASS 277s START /main/config/ - variable_list_join_replace 277s END /main/config/ - variable_list_join_replace Time: <1ms Result: PASS 277s START /main/config/ - variable_lists_match 277s END /main/config/ - variable_lists_match Time: <1ms Result: PASS 277s START /main/config/ - config_dialplan_function 277s END /main/config/ - config_dialplan_function Time: <1ms Result: PASS 277s START /main/config/ - config_hook 277s END /main/config/ - config_hook Time: <1ms Result: PASS 277s START /main/config/ - copy_config 277s END /main/config/ - copy_config Time: <1ms Result: PASS 277s START /main/config/ - config_template_ops 277s END /main/config/ - config_template_ops Time: <1ms Result: PASS 277s START /main/config/ - config_filtered_ops 277s END /main/config/ - config_filtered_ops Time: <1ms Result: PASS 277s START /main/config/ - config_basic_ops 277s END /main/config/ - config_basic_ops Time: <1ms Result: PASS 277s START /main/config/ - config_save 277s END /main/config/ - config_save Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_umax 277s END /main/conversions/ - str_to_umax Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_imax 277s END /main/conversions/ - str_to_imax Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_ulong 277s END /main/conversions/ - str_to_ulong Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_long 277s END /main/conversions/ - str_to_long Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_uint 277s END /main/conversions/ - str_to_uint Time: <1ms Result: PASS 277s START /main/conversions/ - str_to_int 277s END /main/conversions/ - str_to_int Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_get_unknown 277s END /main/core_codec/ - codec_get_unknown Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_get_unknown 277s END /main/core_codec/ - codec_get_unknown Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_get_unregistered 277s END /main/core_codec/ - codec_get_unregistered Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_get 277s END /main/core_codec/ - codec_get Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_register_audio_no_sample_rate 277s END /main/core_codec/ - codec_register_audio_no_sample_rate Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_register_unknown 277s END /main/core_codec/ - codec_register_unknown Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_register_twice 277s END /main/core_codec/ - codec_register_twice Time: <1ms Result: PASS 277s START /main/core_codec/ - codec_register 277s END /main/core_codec/ - codec_register Time: <1ms Result: PASS 277s START /main/core_format/ - format_parse_and_generate_sdp_fmtp 277s END /main/core_format/ - format_parse_and_generate_sdp_fmtp Time: <1ms Result: PASS 277s START /main/core_format/ - format_parse_sdp_fmtp_without_interface 277s END /main/core_format/ - format_parse_sdp_fmtp_without_interface Time: <1ms Result: PASS 277s START /main/core_format/ - format_attribute_get_without_interface 277s END /main/core_format/ - format_attribute_get_without_interface Time: <1ms Result: PASS 277s START /main/core_format/ - format_attribute_set_without_interface 277s END /main/core_format/ - format_attribute_set_without_interface Time: <1ms Result: PASS 277s START /main/core_format/ - format_copy 277s END /main/core_format/ - format_copy Time: <1ms Result: PASS 277s START /main/core_format/ - format_joint_different_codec 277s END /main/core_format/ - format_joint_different_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_attr_joint_same_codec 277s END /main/core_format/ - format_attr_joint_same_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_joint_same_codec 277s END /main/core_format/ - format_joint_same_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_cmp_different_codec 277s END /main/core_format/ - format_cmp_different_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_attr_cmp_same_codec 277s END /main/core_format/ - format_attr_cmp_same_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_cmp_same_codec 277s END /main/core_format/ - format_cmp_same_codec Time: <1ms Result: PASS 277s START /main/core_format/ - format_clone 277s END /main/core_format/ - format_clone Time: <1ms Result: PASS 277s START /main/core_format/ - format_retrieve_attr 277s END /main/core_format/ - format_retrieve_attr Time: <1ms Result: PASS 277s START /main/core_format/ - format_create_attr 277s END /main/core_format/ - format_create_attr Time: <1ms Result: PASS 277s START /main/core_format/ - format_create 277s END /main/core_format/ - format_create Time: <1ms Result: PASS 277s START /main/data_buffer/ - buffer_nominal 277s END /main/data_buffer/ - buffer_nominal Time: <1ms Result: PASS 277s START /main/data_buffer/ - buffer_resize 277s END /main/data_buffer/ - buffer_resize Time: <1ms Result: PASS 277s START /main/data_buffer/ - buffer_put 277s END /main/data_buffer/ - buffer_put Time: <1ms Result: PASS 277s START /main/data_buffer/ - buffer_create 277s END /main/data_buffer/ - buffer_create Time: <1ms Result: PASS 277s START /main/devicestate/ - devstate_channels 277s END /main/devicestate/ - devstate_channels Time: <1ms Result: PASS 277s START /main/devicestate/ - devstate_conversions 277s END /main/devicestate/ - devstate_conversions Time: <1ms Result: PASS 277s START /main/devicestate/ - devstate_changed 277s [test_devicestate.c:wait_for_device_state_updates:308]: Received 8 of 8 updates 277s [test_devicestate.c:devstate_changed:716]: Testing update 0: actual is 1; expected is 1 277s [test_devicestate.c:devstate_changed:716]: Testing update 1: actual is 2; expected is 2 277s [test_devicestate.c:devstate_changed:716]: Testing update 2: actual is 3; expected is 3 277s [test_devicestate.c:devstate_changed:716]: Testing update 3: actual is 4; expected is 4 277s [test_devicestate.c:devstate_changed:716]: Testing update 4: actual is 5; expected is 5 277s [test_devicestate.c:devstate_changed:716]: Testing update 5: actual is 6; expected is 6 277s [test_devicestate.c:devstate_changed:716]: Testing update 6: actual is 7; expected is 7 277s [test_devicestate.c:devstate_changed:716]: Testing update 7: actual is 8; expected is 8 277s [test_devicestate.c:wait_for_device_state_updates:308]: Received 1 of 1 updates 277s [test_devicestate.c:wait_for_device_state_updates:308]: Received 8 of 8 updates 277s [test_devicestate.c:devstate_changed:754]: Testing update 0: actual is 1; expected is 1 277s [test_devicestate.c:devstate_changed:754]: Testing update 1: actual is 2; expected is 2 277s [test_devicestate.c:devstate_changed:754]: Testing update 2: actual is 3; expected is 3 277s [test_devicestate.c:devstate_changed:754]: Testing update 3: actual is 4; expected is 4 277s [test_devicestate.c:devstate_changed:754]: Testing update 4: actual is 5; expected is 5 277s [test_devicestate.c:devstate_changed:754]: Testing update 5: actual is 6; expected is 6 277s [test_devicestate.c:devstate_changed:754]: Testing update 6: actual is 7; expected is 7 277s [test_devicestate.c:devstate_changed:754]: Testing update 7: actual is 8; expected is 8 277s END /main/devicestate/ - devstate_changed Time: 2ms Result: PASS 277s START /main/devicestate/ - devstate_prov_del 277s END /main/devicestate/ - devstate_prov_del Time: <1ms Result: PASS 277s START /main/devicestate/ - devstate_prov_add 277s END /main/devicestate/ - devstate_prov_add Time: <1ms Result: PASS 277s START /main/devicestate/ - device2extenstate_test 277s END /main/devicestate/ - device2extenstate_test Time: <1ms Result: PASS 277s START /main/devicestate/ - device_state_aggregation_test 277s END /main/devicestate/ - device_state_aggregation_test Time: <1ms Result: PASS 277s START /main/dns/ - resolver_resolve_async_cancel 277s END /main/dns/ - resolver_resolve_async_cancel Time: <1ms Result: PASS 277s START /main/dns/ - resolver_resolve_async_off_nominal 277s END /main/dns/ - resolver_resolve_async_off_nominal Time: <1ms Result: PASS 277s START /main/dns/ - resolver_resolve_async 282s END /main/dns/ - resolver_resolve_async Time: 5000ms Result: PASS 282s START /main/dns/ - resolver_resolve_sync_off_nominal 282s END /main/dns/ - resolver_resolve_sync_off_nominal Time: <1ms Result: PASS 282s START /main/dns/ - resolver_resolve_sync 287s END /main/dns/ - resolver_resolve_sync Time: 5000ms Result: PASS 287s START /main/dns/ - resolver_add_record_off_nominal 287s END /main/dns/ - resolver_add_record_off_nominal Time: <1ms Result: PASS 287s START /main/dns/ - resolver_add_record 287s END /main/dns/ - resolver_add_record Time: <1ms Result: PASS 287s START /main/dns/ - resolver_set_result_off_nominal 287s END /main/dns/ - resolver_set_result_off_nominal Time: <1ms Result: PASS 287s START /main/dns/ - resolver_set_result 287s END /main/dns/ - resolver_set_result Time: <1ms Result: PASS 287s START /main/dns/ - resolver_data 287s END /main/dns/ - resolver_data Time: <1ms Result: PASS 287s START /main/dns/ - resolver_unregister_off_nominal 287s END /main/dns/ - resolver_unregister_off_nominal Time: <1ms Result: PASS 287s START /main/dns/ - resolver_register_off_nominal 287s END /main/dns/ - resolver_register_off_nominal Time: <1ms Result: PASS 287s START /main/dns/ - resolver_register_unregister 287s END /main/dns/ - resolver_register_unregister Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve_off_nominal_interactions 287s END /main/dns/naptr/ - naptr_resolve_off_nominal_interactions Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve_off_nominal_regexp 287s END /main/dns/naptr/ - naptr_resolve_off_nominal_regexp Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve_off_nominal_services 287s END /main/dns/naptr/ - naptr_resolve_off_nominal_services Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve_off_nominal_flags 287s END /main/dns/naptr/ - naptr_resolve_off_nominal_flags Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve_off_nominal_length 287s END /main/dns/naptr/ - naptr_resolve_off_nominal_length Time: <1ms Result: PASS 287s START /main/dns/naptr/ - naptr_resolve 287s END /main/dns/naptr/ - naptr_resolve Time: <1ms Result: PASS 287s START /main/dns/query_set/ - query_set_off_nominal_cancel 287s END /main/dns/query_set/ - query_set_off_nominal_cancel Time: <1ms Result: PASS 287s START /main/dns/query_set/ - query_set_nominal_cancel 287s END /main/dns/query_set/ - query_set_nominal_cancel Time: <1ms Result: PASS 287s START /main/dns/query_set/ - query_set_empty 287s END /main/dns/query_set/ - query_set_empty Time: <1ms Result: PASS 287s START /main/dns/query_set/ - query_set 288s END /main/dns/query_set/ - query_set Time: <1ms Result: PASS 288s START /main/dns/recurring/ - recurring_query_cancel_during 288s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 294s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 304s END /main/dns/recurring/ - recurring_query_cancel_during Time: 17000ms Result: PASS 304s START /main/dns/recurring/ - recurring_query_cancel_between 304s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 314s END /main/dns/recurring/ - recurring_query_cancel_between Time: 10000ms Result: PASS 314s START /main/dns/recurring/ - recurring_query_off_nominal 314s END /main/dns/recurring/ - recurring_query_off_nominal Time: <1ms Result: PASS 314s START /main/dns/recurring/ - recurring_query 314s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 321s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 333s [test_dns_recurring.c:wait_for_resolution:252]: Query completed in expected time frame 333s END /main/dns/recurring/ - recurring_query Time: 18999ms Result: PASS 333s START /main/dns/srv/ - srv_resolve_record_missing_host 333s END /main/dns/srv/ - srv_resolve_record_missing_host Time: 1ms Result: PASS 333s START /main/dns/srv/ - srv_resolve_record_missing_port_host 333s END /main/dns/srv/ - srv_resolve_record_missing_port_host Time: <1ms Result: PASS 333s START /main/dns/srv/ - srv_resolve_record_missing_weight_port_host 333s END /main/dns/srv/ - srv_resolve_record_missing_weight_port_host Time: <1ms Result: PASS 333s START /main/dns/srv/ - srv_resolve_different_priorities_different_weights 334s END /main/dns/srv/ - srv_resolve_different_priorities_different_weights Time: 10ms Result: PASS 334s START /main/dns/srv/ - srv_resolve_same_priority_different_weights 334s END /main/dns/srv/ - srv_resolve_same_priority_different_weights Time: 8ms Result: PASS 334s START /main/dns/srv/ - srv_resolve_same_priority_zero_weight 334s END /main/dns/srv/ - srv_resolve_same_priority_zero_weight Time: <1ms Result: PASS 334s START /main/dns/srv/ - srv_resolve_sort_priority 334s END /main/dns/srv/ - srv_resolve_sort_priority Time: <1ms Result: PASS 334s START /main/dns/srv/ - srv_resolve_single_record 334s END /main/dns/srv/ - srv_resolve_single_record Time: <1ms Result: PASS 334s START /main/dsp/ - dtmf 334s END /main/dsp/ - dtmf Time: 4ms Result: PASS 334s START /main/dsp/ - fax 334s END /main/dsp/ - fax Time: 740ms Result: PASS 334s START /main/event/ - ast_event_new_test 334s [test_event.c:event_new_test:125]: First, test dynamic event creation... 334s [test_event.c:check_event:88]: Event looks good. 334s [test_event.c:check_event:88]: Event looks good. 334s END /main/event/ - ast_event_new_test Time: <1ms Result: PASS 334s START /main/file/ - read_dir_test 334s END /main/file/ - read_dir_test Time: 1ms Result: PASS 334s START /main/file/ - ast_format_str_reduce_test_1 334s END /main/file/ - ast_format_str_reduce_test_1 Time: <1ms Result: PASS 334s START /main/format_cache/ - format_cache_get_nonxistent 334s END /main/format_cache/ - format_cache_get_nonxistent Time: <1ms Result: PASS 334s START /main/format_cache/ - format_cache_get 334s END /main/format_cache/ - format_cache_get Time: <1ms Result: PASS 334s START /main/format_cache/ - format_cache_set_null 334s END /main/format_cache/ - format_cache_set_null Time: <1ms Result: PASS 334s START /main/format_cache/ - format_cache_set_duplicate 334s END /main/format_cache/ - format_cache_set_duplicate Time: <1ms Result: PASS 334s START /main/format_cache/ - format_cache_set 334s END /main/format_cache/ - format_cache_set Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_replace_from_cap 334s END /main/format_cap/ - format_cap_replace_from_cap Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_best_by_type 334s END /main/format_cap/ - format_cap_best_by_type Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_iscompatible 334s END /main/format_cap/ - format_cap_iscompatible Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_get_compatible 334s END /main/format_cap/ - format_cap_get_compatible Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_iscompatible_format 334s END /main/format_cap/ - format_cap_iscompatible_format Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_get_compatible_format 334s END /main/format_cap/ - format_cap_get_compatible_format Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_get_names 334s END /main/format_cap/ - format_cap_get_names Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_remove_all 334s END /main/format_cap/ - format_cap_remove_all Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_remove_bytype 334s END /main/format_cap/ - format_cap_remove_bytype Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_remove_multiple 334s END /main/format_cap/ - format_cap_remove_multiple Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_remove_single 334s END /main/format_cap/ - format_cap_remove_single Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_set_framing 334s END /main/format_cap/ - format_cap_set_framing Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_from_cap_duplicate 334s END /main/format_cap/ - format_cap_append_from_cap_duplicate Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_from_cap 334s END /main/format_cap/ - format_cap_append_from_cap Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_duplicate 334s END /main/format_cap/ - format_cap_append_duplicate Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_all_audio 334s END /main/format_cap/ - format_cap_append_all_audio Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_all_unknown 334s END /main/format_cap/ - format_cap_append_all_unknown Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_multiple 334s END /main/format_cap/ - format_cap_append_multiple Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_append_single 334s END /main/format_cap/ - format_cap_append_single Time: <1ms Result: PASS 334s START /main/format_cap/ - format_cap_alloc 334s END /main/format_cap/ - format_cap_alloc Time: <1ms Result: PASS 334s START /main/hashtab/ - thrash 334s [test_hashtab_thrash.c:hash_test:246]: Executing hash concurrency test... 335s END /main/hashtab/ - thrash Time: 537ms Result: PASS 335s START /main/heap/ - heap_test_3 335s END /main/heap/ - heap_test_3 Time: 89ms Result: PASS 335s START /main/heap/ - heap_test_2 335s END /main/heap/ - heap_test_2 Time: 81ms Result: PASS 335s START /main/heap/ - heap_test_1 335s END /main/heap/ - heap_test_1 Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_resynch_control 335s END /main/jitterbuf/ - jitterbuffer_resynch_control Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_resynch_voice 335s END /main/jitterbuf/ - jitterbuffer_resynch_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_overflow_control 335s END /main/jitterbuf/ - jitterbuffer_overflow_control Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_overflow_voice 335s END /main/jitterbuf/ - jitterbuffer_overflow_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_late_control 335s END /main/jitterbuf/ - jitterbuffer_late_control Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_late_voice 335s END /main/jitterbuf/ - jitterbuffer_late_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_lost_control 335s END /main/jitterbuf/ - jitterbuffer_lost_control Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_lost_voice 335s END /main/jitterbuf/ - jitterbuffer_lost_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_out_of_order_voice 335s END /main/jitterbuf/ - jitterbuffer_out_of_order_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_out_of_order_voice 335s END /main/jitterbuf/ - jitterbuffer_out_of_order_voice Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_nominal_control_frames 335s END /main/jitterbuf/ - jitterbuffer_nominal_control_frames Time: <1ms Result: PASS 335s START /main/jitterbuf/ - jitterbuffer_nominal_voice_frames 335s END /main/jitterbuf/ - jitterbuffer_nominal_voice_frames Time: <1ms Result: PASS 335s START /main/json/ - cep 335s END /main/json/ - cep Time: <1ms Result: PASS 335s START /main/json/ - type_timeval 335s END /main/json/ - type_timeval Time: 1ms Result: PASS 335s START /main/json/ - name_number 335s END /main/json/ - name_number Time: <1ms Result: PASS 335s START /main/json/ - clever_circle 335s END /main/json/ - clever_circle Time: <1ms Result: PASS 335s START /main/json/ - circular_array 335s END /main/json/ - circular_array Time: <1ms Result: PASS 335s START /main/json/ - circular_object 335s END /main/json/ - circular_object Time: <1ms Result: PASS 335s START /main/json/ - copy_null 335s END /main/json/ - copy_null Time: <1ms Result: PASS 335s START /main/json/ - deep_copy 335s END /main/json/ - deep_copy Time: <1ms Result: PASS 335s START /main/json/ - copy 335s END /main/json/ - copy Time: <1ms Result: PASS 335s START /main/json/ - object_alloc 335s END /main/json/ - object_alloc Time: <1ms Result: PASS 335s START /main/json/ - pack_ownership 335s END /main/json/ - pack_ownership Time: <1ms Result: PASS 335s START /main/json/ - pack 335s END /main/json/ - pack Time: <1ms Result: PASS 335s START /main/json/ - parse_errors 335s END /main/json/ - parse_errors Time: <1ms Result: PASS 335s START /main/json/ - dump_load_null 335s END /main/json/ - dump_load_null Time: <1ms Result: PASS 335s START /main/json/ - dump_load_new_file 335s END /main/json/ - dump_load_new_file Time: <1ms Result: PASS 335s START /main/json/ - dump_load_file 335s END /main/json/ - dump_load_file Time: <1ms Result: PASS 335s START /main/json/ - load_buffer 335s END /main/json/ - load_buffer Time: <1ms Result: PASS 335s START /main/json/ - dump_str_fail 335s END /main/json/ - dump_str_fail Time: <1ms Result: PASS 335s START /main/json/ - dump_load_str 335s END /main/json/ - dump_load_str Time: <1ms Result: PASS 335s START /main/json/ - dump_load_string 335s END /main/json/ - dump_load_string Time: <1ms Result: PASS 335s START /main/json/ - object_create_vars 335s END /main/json/ - object_create_vars Time: <1ms Result: PASS 335s START /main/json/ - object_iter_null 335s END /main/json/ - object_iter_null Time: <1ms Result: PASS 335s START /main/json/ - object_iter 335s END /main/json/ - object_iter Time: <1ms Result: PASS 335s START /main/json/ - object_null 335s END /main/json/ - object_null Time: <1ms Result: PASS 335s START /main/json/ - object_merge_missing 335s END /main/json/ - object_merge_missing Time: <1ms Result: PASS 335s START /main/json/ - object_alloc 335s END /main/json/ - object_alloc Time: <1ms Result: PASS 335s START /main/json/ - object_alloc 335s END /main/json/ - object_alloc Time: <1ms Result: PASS 335s START /main/json/ - object_clear 335s END /main/json/ - object_clear Time: <1ms Result: PASS 335s START /main/json/ - object_del 335s END /main/json/ - object_del Time: <1ms Result: PASS 335s START /main/json/ - object_get 335s END /main/json/ - object_get Time: <1ms Result: PASS 335s START /main/json/ - object_set_overwriting 335s END /main/json/ - object_set_overwriting Time: <1ms Result: PASS 335s START /main/json/ - object_set 335s END /main/json/ - object_set Time: <1ms Result: PASS 335s START /main/json/ - object_alloc 335s END /main/json/ - object_alloc Time: <1ms Result: PASS 335s START /main/json/ - array_null 335s END /main/json/ - array_null Time: <1ms Result: PASS 335s START /main/json/ - array_extend 335s END /main/json/ - array_extend Time: <1ms Result: PASS 335s START /main/json/ - array_clear 335s END /main/json/ - array_clear Time: <1ms Result: PASS 335s START /main/json/ - array_remove 335s END /main/json/ - array_remove Time: <1ms Result: PASS 335s START /main/json/ - array_set 335s END /main/json/ - array_set Time: <1ms Result: PASS 335s START /main/json/ - array_insert 335s END /main/json/ - array_insert Time: <1ms Result: PASS 335s START /main/json/ - array_append 335s END /main/json/ - array_append Time: <1ms Result: PASS 335s START /main/json/ - array_create 335s END /main/json/ - array_create Time: <1ms Result: PASS 335s START /main/json/ - non_int 335s END /main/json/ - non_int Time: <1ms Result: PASS 335s START /main/json/ - type_int 335s END /main/json/ - type_int Time: <1ms Result: PASS 335s START /main/json/ - stringf 335s END /main/json/ - stringf Time: <1ms Result: PASS 335s START /main/json/ - string_null 335s END /main/json/ - string_null Time: <1ms Result: PASS 335s START /main/json/ - type_string 335s END /main/json/ - type_string Time: <1ms Result: PASS 335s START /main/json/ - null_val 335s END /main/json/ - null_val Time: <1ms Result: PASS 335s START /main/json/ - type_null 335s END /main/json/ - type_null Time: <1ms Result: PASS 335s START /main/json/ - type_bool1 335s END /main/json/ - type_bool1 Time: <1ms Result: PASS 335s START /main/json/ - type_bool0 335s END /main/json/ - type_bool0 Time: <1ms Result: PASS 335s START /main/json/ - type_true 335s END /main/json/ - type_true Time: <1ms Result: PASS 335s START /main/json/ - type_false 335s END /main/json/ - type_false Time: <1ms Result: PASS 335s START /main/linkedlists/ - double_ll_tests 335s END /main/linkedlists/ - double_ll_tests Time: <1ms Result: PASS 335s START /main/linkedlists/ - ll_tests 335s END /main/linkedlists/ - ll_tests Time: <1ms Result: PASS 335s START /main/lock/ - cleanup_order_test 335s [test_scoped_lock.c:test_ref:149]: Ref is occurring 335s [test_scoped_lock.c:test_lock:117]: Lock is occurring 335s [test_scoped_lock.c:test_unlock:133]: Unlock is occurring 335s [test_scoped_lock.c:test_unref:166]: Unref is occurring 335s [test_scoped_lock.c:test_ref:149]: Ref is occurring 335s [test_scoped_lock.c:test_lock:117]: Lock is occurring 335s [test_scoped_lock.c:test_unlock:133]: Unlock is occurring 335s [test_scoped_lock.c:test_unref:166]: Unref is occurring 335s END /main/lock/ - cleanup_order_test Time: <1ms Result: PASS 335s START /main/lock/ - lock_test 335s END /main/lock/ - lock_test Time: <1ms Result: PASS 335s START /main/lock/ - named_lock_test 335s [test_named_lock.c:named_lock_test:80]: This test should take about 3 seconds 338s END /main/lock/ - named_lock_test Time: 3000ms Result: PASS 338s START /main/logging/ - scope_test 338s END /main/logging/ - scope_test Time: <1ms Result: PASS 338s START /main/manager/ - originate_permissions_test 338s END /main/manager/ - originate_permissions_test Time: <1ms Result: PASS 338s START /main/manager/ - eventfilter_test_matching 338s Loaded 7 filters 338s Tested 9 events 338s END /main/manager/ - eventfilter_test_matching Time: <1ms Result: PASS 338s START /main/manager/ - eventfilter_test_creation 338s [manager.c:eventfilter_test_creation:6281]: Tested 29 filters 338s END /main/manager/ - eventfilter_test_creation Time: <1ms Result: PASS 338s START /main/media_cache/ - create_update_off_nominal 338s END /main/media_cache/ - create_update_off_nominal Time: <1ms Result: PASS 338s START /main/media_cache/ - create_update_metadata 338s [test_media_cache.c:create_update_metadata:367]: Got /tmp/test-media-cache-r03jhw for second file path 338s END /main/media_cache/ - create_update_metadata Time: <1ms Result: PASS 338s START /main/media_cache/ - create_update_nominal 338s [test_media_cache.c:create_update_nominal:236]: Creating resource with /tmp/test-media-cache-lLV4ZX 338s [test_media_cache.c:create_update_nominal:241]: Got /tmp/test-media-cache-lLV4ZX for first file path 338s [test_media_cache.c:create_update_nominal:245]: Creating resource with /tmp/test-media-cache-y0eO17 338s [test_media_cache.c:create_update_nominal:250]: Got /tmp/test-media-cache-y0eO17 for second file path 338s END /main/media_cache/ - create_update_nominal Time: 15ms Result: PASS 338s START /main/media_cache/ - exists_off_nominal 338s END /main/media_cache/ - exists_off_nominal Time: <1ms Result: PASS 338s START /main/media_cache/ - exists_nominal 338s END /main/media_cache/ - exists_nominal Time: <1ms Result: PASS 338s START /main/netsock2/ - split_hostport 338s END /main/netsock2/ - split_hostport Time: <1ms Result: PASS 338s START /main/netsock2/ - parsing 338s END /main/netsock2/ - parsing Time: <1ms Result: PASS 338s START /main/optional_api/ - test_provide_last 338s END /main/optional_api/ - test_provide_last Time: <1ms Result: PASS 338s START /main/optional_api/ - test_provide_first 338s END /main/optional_api/ - test_provide_first Time: <1ms Result: PASS 338s START /main/pbx/ - test_substitution 338s [test_substitution.c:test_substitution:244]: Testing variable substitution ... 338s [test_substitution.c:test_chan_integer:65]: Tested '${CALLINGANI2}' . . . . . passed 338s [test_substitution.c:test_chan_integer:65]: Tested '${CALLINGTON}' . . . . . passed 338s [test_substitution.c:test_chan_integer:65]: Tested '${CALLINGTNS}' . . . . . passed 338s [test_substitution.c:test_chan_integer_accessor:89]: Tested '${HANGUPCAUSE}' . . . . . passed 338s [test_substitution.c:test_chan_integer_accessor:89]: Tested '${PRIORITY}' . . . . . passed 338s [test_substitution.c:test_chan_string:115]: Tested '${CONTEXT}' . . . . . passed 338s [test_substitution.c:test_chan_string:115]: Tested '${EXTEN}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CHANNEL(language)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CHANNEL(musicclass)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CHANNEL(parkinglot)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CALLERID(name)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CURLOPT(proxyuserpwd)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${CDR(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${ENV(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${GLOBAL(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_variable:144]: Tested '${GROUP()}' . . . . . passed 338s [test_substitution.c:test_2way_function:193]: Tested '${AES_ENCRYPT(abcdefghijklmnop,)}' and '${AES_DECRYPT(abcdefghijklmnop,)}' . . . . . passed 338s [test_substitution.c:test_2way_function:193]: Tested '${BASE64_ENCODE()}' and '${BASE64_DECODE()}' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '123123' ('${foo}${foo}') == '123123' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A123A123A' ('A${foo}A${foo}A') == 'A123A123A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A123A' ('A${${bar}}A') == 'A123A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A123A' ('A${${baz}o}A') == 'A123A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A23A' ('A${${baz}o:1}A') == 'A23A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A2A' ('A${${baz}o:1:1}A') == 'A2A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A2A' ('A${${baz}o:1:-1}A') == 'A2A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A3A' ('A${${baz}o:-1:1}A') == 'A3A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A2A' ('A${${baz}o:-2:1}A') == 'A2A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'A2A' ('A${${baz}o:-2:-1}A') == 'A2A' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'cd&ef' ('${LISTFILTER(list1,&,ab)}') == 'cd&ef' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'ab&ef' ('${LISTFILTER(list1,&,cd)}') == 'ab&ef' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'ab&cd' ('${LISTFILTER(list1,&,ef)}') == 'ab&cd' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'ab&cd&ef' ('${LISTFILTER(list1,&,gh)}') == 'ab&cd&ef' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'ab&cd&ef' ('${LISTFILTER(list1,&,c)}') == 'ab&cd&ef' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested 'ab&cd&ef' ('${LISTFILTER(list1,&,d)}') == 'ab&cd&ef' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '' ('${LISTFILTER(list2,&,ab)}') == '' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '' ('${LISTFILTER(list_empty,&,ab)}') == '' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '123,456' ('${SHELL(printf '%d' 123)},${SHELL(printf '%d' 456)}') == '123,456' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '123,,456' ('${foo},${CDR(answer)},${SHELL(printf '%d' 456)}') == '123,,456' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '123,0.000000,456' ('${foo},${CDR(answer,u)},${SHELL(printf '%d' 456)}') == '123,0.000000,456' . . . . . passed 338s [test_substitution.c:test_expected_result:215]: Tested '123,,' ('${foo},${this_does_not_exist},${THIS_DOES_NOT_EXIST(either)}') == '123,,' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${BLACKLIST(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${EVAL(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${FIELDNUM(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${FIELDQTY(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${HASHKEYS(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${IMPORT(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${PP_EACH_USER(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${TOLOWER(foo)}' . . . . . passed 338s [test_substitution.c:test_chan_function:168]: Tested '${TOUPPER(foo)}' . . . . . passed 338s END /main/pbx/ - test_substitution Time: 18ms Result: PASS 338s START /main/pbx/ - pattern_match_test 338s [test_pbx.c:test_exten:183]: Successfully matched 200 to exten _2. in context test_pattern with the old pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern with the old pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern with the old pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern_include with the old pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 200 to exten _2. in context test_pattern with the new pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern with the new pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern with the new pattern match engine 338s [test_pbx.c:test_exten:183]: Successfully matched 2000 to exten 2000 in context test_pattern_include with the new pattern match engine 338s END /main/pbx/ - pattern_match_test Time: <1ms Result: PASS 338s START /main/pbx/ - test_MATH_function 338s [func_math.c:test_MATH_function:736]: Testing MATH() substitution ... 338s END /main/pbx/ - test_MATH_function Time: <1ms Result: PASS 338s START /main/pbx/ - variable_substrings 338s END /main/pbx/ - variable_substrings Time: <1ms Result: PASS 338s START /main/poll/ - poll_test 338s [test_poll.c:poll_test:95]: Creating handle that should NEVER block on write 338s [test_poll.c:poll_test:101]: Creating handle that should NEVER block on read 338s [test_poll.c:poll_test:108]: Creating handle that should block on read 338s [test_poll.c:poll_test:168]: Starting thread to ensure we don't block forever 338s [test_poll.c:poll_test:194]: Cancelling failsafe thread. 338s END /main/poll/ - poll_test Time: <1ms Result: PASS 338s START /main/presence/ - channel_presence 338s END /main/presence/ - channel_presence Time: <1ms Result: PASS 338s START /main/sample/ - sample_test 338s [test_skel.c:sample_test:93]: Test argument: 0: test 338s [test_skel.c:sample_test:93]: Test argument: 1: execute 338s [test_skel.c:sample_test:93]: Test argument: 2: all 338s [test_skel.c:sample_test:102]: Executing sample test with test-option=999 and test-option2= 338s END /main/sample/ - sample_test Time: <1ms Result: PASS 338s START /main/sched/ - sched_test_freebird 338s [test_sched.c:sched_test_freebird:440]: Received signal, calling Scedule and UNREF 338s [test_sched.c:sched_test_freebird:441]: ID: 1 341s [test_sched.c:sched_test_freebird:448]: Correct number of references '2' 341s END /main/sched/ - sched_test_freebird Time: 3000ms Result: PASS 341s START /main/sched/ - sched_test_order 341s END /main/sched/ - sched_test_order Time: 400ms Result: PASS 341s START /main/sorcery/ - wizard_read_only 341s END /main/sorcery/ - wizard_read_only Time: <1ms Result: PASS 341s START /main/sorcery/ - wizard_observation 341s END /main/sorcery/ - wizard_observation Time: <1ms Result: PASS 341s START /main/sorcery/ - instance_observation 341s END /main/sorcery/ - instance_observation Time: <1ms Result: PASS 341s START /main/sorcery/ - global_observation 341s END /main/sorcery/ - global_observation Time: <1ms Result: PASS 341s START /main/sorcery/ - object_field_registered 341s END /main/sorcery/ - object_field_registered Time: <1ms Result: PASS 341s START /main/sorcery/ - dialplan_function 341s END /main/sorcery/ - dialplan_function Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard_retrieve_multiple_all 341s END /main/sorcery/ - configuration_file_wizard_retrieve_multiple_all Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard_retrieve_multiple 341s END /main/sorcery/ - configuration_file_wizard_retrieve_multiple Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard_retrieve_field 341s END /main/sorcery/ - configuration_file_wizard_retrieve_field Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard_with_criteria 341s END /main/sorcery/ - configuration_file_wizard_with_criteria Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard_with_file_integrity 341s END /main/sorcery/ - configuration_file_wizard_with_file_integrity Time: <1ms Result: PASS 341s START /main/sorcery/ - configuration_file_wizard 341s END /main/sorcery/ - configuration_file_wizard Time: <1ms Result: PASS 342s START /main/sorcery/ - object_type_observer 342s END /main/sorcery/ - object_type_observer Time: 1ms Result: PASS 342s START /main/sorcery/ - caching_wizard_behavior 342s END /main/sorcery/ - caching_wizard_behavior Time: <1ms Result: PASS 342s START /main/sorcery/ - object_is_stale 342s END /main/sorcery/ - object_is_stale Time: <1ms Result: PASS 342s START /main/sorcery/ - object_delete_uncreated 342s END /main/sorcery/ - object_delete_uncreated Time: <1ms Result: PASS 342s START /main/sorcery/ - object_delete 342s END /main/sorcery/ - object_delete Time: <1ms Result: PASS 342s START /main/sorcery/ - object_update_uncreated 342s END /main/sorcery/ - object_update_uncreated Time: <1ms Result: PASS 342s START /main/sorcery/ - object_update 342s END /main/sorcery/ - object_update Time: <1ms Result: PASS 342s START /main/sorcery/ - object_retrieve_regex 342s END /main/sorcery/ - object_retrieve_regex Time: <1ms Result: PASS 342s START /main/sorcery/ - object_retrieve_multiple_field 342s END /main/sorcery/ - object_retrieve_multiple_field Time: <1ms Result: PASS 342s START /main/sorcery/ - object_retrieve_multiple_all 342s END /main/sorcery/ - object_retrieve_multiple_all Time: <1ms Result: PASS 342s START /main/sorcery/ - object_retrieve_field 342s END /main/sorcery/ - object_retrieve_field Time: <1ms Result: PASS 342s START /main/sorcery/ - object_retrieve_id 342s END /main/sorcery/ - object_retrieve_id Time: <1ms Result: PASS 342s START /main/sorcery/ - object_create 342s END /main/sorcery/ - object_create Time: <1ms Result: PASS 342s START /main/sorcery/ - changeset_create_unchanged 342s END /main/sorcery/ - changeset_create_unchanged Time: <1ms Result: PASS 342s START /main/sorcery/ - changeset_create 342s END /main/sorcery/ - changeset_create Time: <1ms Result: PASS 342s START /main/sorcery/ - extended_fields 342s END /main/sorcery/ - extended_fields Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_apply_fields 342s END /main/sorcery/ - objectset_apply_fields Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_transform 342s END /main/sorcery/ - objectset_transform Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_apply_invalid 342s END /main/sorcery/ - objectset_apply_invalid Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_apply_handler 342s END /main/sorcery/ - objectset_apply_handler Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_apply 342s END /main/sorcery/ - objectset_apply Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_create_regex 342s END /main/sorcery/ - objectset_create_regex Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_json_create 342s END /main/sorcery/ - objectset_json_create Time: <1ms Result: PASS 342s START /main/sorcery/ - objectset_create 342s END /main/sorcery/ - objectset_create Time: <1ms Result: PASS 342s START /main/sorcery/ - object_diff_native 342s END /main/sorcery/ - object_diff_native Time: <1ms Result: PASS 342s START /main/sorcery/ - object_diff 342s END /main/sorcery/ - object_diff Time: <1ms Result: PASS 342s START /main/sorcery/ - object_copy_native 342s END /main/sorcery/ - object_copy_native Time: <1ms Result: PASS 342s START /main/sorcery/ - object_copy 342s END /main/sorcery/ - object_copy Time: <1ms Result: PASS 342s START /main/sorcery/ - object_alloc_without_id 342s END /main/sorcery/ - object_alloc_without_id Time: <1ms Result: PASS 342s START /main/sorcery/ - object_alloc_with_id 342s END /main/sorcery/ - object_alloc_with_id Time: <1ms Result: PASS 342s START /main/sorcery/ - object_fields_register 342s END /main/sorcery/ - object_fields_register Time: <1ms Result: PASS 342s START /main/sorcery/ - object_field_register 342s END /main/sorcery/ - object_field_register Time: <1ms Result: PASS 342s START /main/sorcery/ - object_register_without_mapping 342s END /main/sorcery/ - object_register_without_mapping Time: <1ms Result: PASS 342s START /main/sorcery/ - object_register 342s END /main/sorcery/ - object_register Time: <1ms Result: PASS 342s START /main/sorcery/ - apply_config 342s END /main/sorcery/ - apply_config Time: <1ms Result: PASS 342s START /main/sorcery/ - apply_default 342s END /main/sorcery/ - apply_default Time: <1ms Result: PASS 342s START /main/sorcery/ - open 342s END /main/sorcery/ - open Time: <1ms Result: PASS 342s START /main/sorcery/ - wizard_registration 342s END /main/sorcery/ - wizard_registration Time: <1ms Result: PASS 342s START /main/sorcery/ - wizard_apply_and_insert 342s END /main/sorcery/ - wizard_apply_and_insert Time: <1ms Result: PASS 342s START /main/stdtime/ - time_create_by_unit_str 342s END /main/stdtime/ - time_create_by_unit_str Time: <1ms Result: PASS 342s START /main/stdtime/ - time_create_by_unit 342s END /main/stdtime/ - time_create_by_unit Time: <1ms Result: PASS 342s START /main/stdtime/ - time_str_to_unit 342s END /main/stdtime/ - time_str_to_unit Time: <1ms Result: PASS 342s START /main/stdtime/ - time_tv_to_usec 342s END /main/stdtime/ - time_tv_to_usec Time: <1ms Result: PASS 342s START /main/stdtime/ - timezone_watch 342s [test_time.c:test_timezone_watch:82]: Executing deletion test... 342s [test_time.c:test_timezone_watch:90]: Querying timezone /tmp/timezone.wrH5kM/test 343s [test_time.c:test_timezone_watch:90]: Querying timezone /tmp/timezone.wrH5kM/test 343s [test_time.c:test_timezone_watch:82]: Executing symlink test... 343s [test_time.c:test_timezone_watch:90]: Querying timezone /tmp/timezone.wrH5kM/test 344s [test_time.c:test_timezone_watch:90]: Querying timezone /tmp/timezone.wrH5kM/test 344s END /main/stdtime/ - timezone_watch Time: 2279ms Result: PASS 344s START /main/stream/ - stream_topology_map_create 344s END /main/stream/ - stream_topology_map_create Time: <1ms Result: PASS 344s START /main/stream/ - format_cap_from_stream_topology 344s END /main/stream/ - format_cap_from_stream_topology Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_change_request_from_channel 344s END /main/stream/ - stream_topology_change_request_from_channel Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_change_request_from_application 344s END /main/stream/ - stream_topology_change_request_from_application Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_change_request_from_channel_non_multistream 344s END /main/stream/ - stream_topology_change_request_from_channel_non_multistream Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_change_request_from_application_non_multistream 344s END /main/stream/ - stream_topology_change_request_from_application_non_multistream Time: <1ms Result: PASS 344s START /main/stream/ - stream_read_multistream 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: NON-STREAM Streams: 2 Frames: 16 Frames per read: 1 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: STREAM Streams: 2 Frames: 16 Frames per read: 1 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: NON-STREAM Streams: 4 Frames: 16 Frames per read: 1 Expected Nulls: 8 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 8 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: STREAM Streams: 4 Frames: 16 Frames per read: 1 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: NON-STREAM Streams: 2 Frames: 16 Frames per read: 3 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: STREAM Streams: 2 Frames: 16 Frames per read: 3 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: NON-STREAM Streams: 4 Frames: 16 Frames per read: 3 Expected Nulls: 8 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 8 344s [test_stream.c:read_test:1726]: ChanType: MULTI ReadType: STREAM Streams: 4 Frames: 16 Frames per read: 3 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s END /main/stream/ - stream_read_multistream Time: 1ms Result: PASS 344s START /main/stream/ - stream_read_non_multistream 344s [test_stream.c:read_test:1726]: ChanType: NON-MULTI ReadType: NON-STREAM Streams: 2 Frames: 16 Frames per read: 1 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: NON-MULTI ReadType: STREAM Streams: 2 Frames: 16 Frames per read: 1 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: NON-MULTI ReadType: NON-STREAM Streams: 2 Frames: 16 Frames per read: 3 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s [test_stream.c:read_test:1726]: ChanType: NON-MULTI ReadType: STREAM Streams: 2 Frames: 16 Frames per read: 3 Expected Nulls: 0 344s [test_stream.c:read_test:1759]: Frames read: 16 NULL frames: 0 344s END /main/stream/ - stream_read_non_multistream Time: <1ms Result: PASS 344s START /main/stream/ - stream_write_multistream 344s END /main/stream/ - stream_write_multistream Time: <1ms Result: PASS 344s START /main/stream/ - stream_write_non_multistream 344s END /main/stream/ - stream_write_non_multistream Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_channel_set 344s END /main/stream/ - stream_topology_channel_set Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_create_from_channel_nativeformats 344s END /main/stream/ - stream_topology_create_from_channel_nativeformats Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_get_first_stream_by_type 344s END /main/stream/ - stream_topology_get_first_stream_by_type Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_create_from_format_cap 344s END /main/stream/ - stream_topology_create_from_format_cap Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_del_stream 344s END /main/stream/ - stream_topology_del_stream Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_set_stream 344s END /main/stream/ - stream_topology_set_stream Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_append_stream 344s END /main/stream/ - stream_topology_append_stream Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_clone 344s END /main/stream/ - stream_topology_clone Time: <1ms Result: PASS 344s START /main/stream/ - stream_topology_create 344s END /main/stream/ - stream_topology_create Time: <1ms Result: PASS 344s START /main/stream/ - stream_metadata 344s END /main/stream/ - stream_metadata Time: <1ms Result: PASS 344s START /main/stream/ - stream_set_state 344s END /main/stream/ - stream_set_state Time: <1ms Result: PASS 344s START /main/stream/ - stream_set_formats 344s END /main/stream/ - stream_set_formats Time: <1ms Result: PASS 344s START /main/stream/ - stream_set_type 344s END /main/stream/ - stream_set_type Time: <1ms Result: PASS 344s START /main/stream/ - stream_create_no_name 344s END /main/stream/ - stream_create_no_name Time: <1ms Result: PASS 344s START /main/stream/ - stream_create 344s END /main/stream/ - stream_create Time: <1ms Result: PASS 344s START /main/strings/ - in_delimited_string 344s END /main/strings/ - in_delimited_string Time: <1ms Result: PASS 344s START /main/strings/ - temp_strings 344s END /main/strings/ - temp_strings Time: <1ms Result: PASS 344s START /main/strings/ - strings_match 344s END /main/strings/ - strings_match Time: <1ms Result: PASS 344s START /main/strings/ - escape 344s END /main/strings/ - escape Time: <1ms Result: PASS 344s START /main/strings/ - escape_semicolons 344s END /main/strings/ - escape_semicolons Time: <1ms Result: PASS 344s START /main/strings/ - strsep_quoted 344s END /main/strings/ - strsep_quoted Time: <1ms Result: PASS 344s START /main/strings/ - strsep 344s END /main/strings/ - strsep Time: <1ms Result: PASS 344s START /main/strings/ - ends_with 344s END /main/strings/ - ends_with Time: <1ms Result: PASS 344s START /main/strings/ - begins_with 344s END /main/strings/ - begins_with Time: <1ms Result: PASS 344s START /main/strings/ - str_test 344s END /main/strings/ - str_test Time: <1ms Result: PASS 344s START /main/taskpool/ - serializer_push_wait_while_suspended_from_other_serializer 349s END /main/taskpool/ - serializer_push_wait_while_suspended_from_other_serializer Time: 5000ms Result: PASS 349s START /main/taskpool/ - serializer_multiple_suspension 354s END /main/taskpool/ - serializer_multiple_suspension Time: 5000ms Result: PASS 354s START /main/taskpool/ - serializer_suspension 359s END /main/taskpool/ - serializer_suspension Time: 5000ms Result: PASS 359s START /main/taskpool/ - push_shrink 361s END /main/taskpool/ - push_shrink Time: 2000ms Result: PASS 361s START /main/taskpool/ - push_grow 361s END /main/taskpool/ - push_grow Time: <1ms Result: PASS 361s START /main/taskpool/ - push_serializer_synchronous_requeue 361s END /main/taskpool/ - push_serializer_synchronous_requeue Time: <1ms Result: PASS 361s START /main/taskpool/ - push_serializer_synchronous 361s END /main/taskpool/ - push_serializer_synchronous Time: <1ms Result: PASS 361s START /main/taskpool/ - push_serializer 361s END /main/taskpool/ - push_serializer Time: <1ms Result: PASS 361s START /main/taskpool/ - push_synchronous 361s END /main/taskpool/ - push_synchronous Time: <1ms Result: PASS 361s START /main/taskpool/ - push 361s END /main/taskpool/ - push Time: <1ms Result: PASS 361s START /main/taskprocessor/ - taskprocessor_cli_show 361s [test_taskprocessor.c:taskprocessor_cli_show:1070]: CLI Output: 361s 361s Taskprocessor: test_cli_taskprocessor 361s =========================================== 361s Subsystem: (none) 361s Tasks processed: 0 361s Current queue size: 2 361s Max queue depth: 0 361s Low water mark: 450 361s High water mark: 500 361s High water alert: No 361s Suspended: No 361s Currently executing: Yes 361s Highest time (us): 0 361s Lowest time (us): 0 361s 361s Queued Tasks: 361s ------------------------------------------- 361s Task #1: 361s Origin: test_taskprocessor.c:1038 361s Function: taskprocessor_cli_show 361s Type: Standard 361s Task #2: 361s Origin: test_taskprocessor.c:1044 361s Function: taskprocessor_cli_show 361s Type: Standard 361s 361s Total queued tasks: 2 361s 361s 361s [test_taskprocessor.c:taskprocessor_cli_show:1095]: CLI command output validated successfully 361s [test_taskprocessor.c:taskprocessor_cli_show:1100]: Waiting for tasks to complete 367s [test_taskprocessor.c:taskprocessor_cli_show:1129]: Tasks complete 367s END /main/taskprocessor/ - taskprocessor_cli_show Time: 6002ms Result: PASS 367s START /main/taskprocessor/ - serializer_pool 371s END /main/taskprocessor/ - serializer_pool Time: 4001ms Result: PASS 371s START /main/taskprocessor/ - taskprocessor_push_local 371s END /main/taskprocessor/ - taskprocessor_push_local Time: <1ms Result: PASS 371s START /main/taskprocessor/ - taskprocessor_shutdown 371s END /main/taskprocessor/ - taskprocessor_shutdown Time: <1ms Result: PASS 371s START /main/taskprocessor/ - taskprocessor_listener 371s END /main/taskprocessor/ - taskprocessor_listener Time: <1ms Result: PASS 371s START /main/taskprocessor/ - subsystem_alert 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 1 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 2 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 3 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 4 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 5 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 6 371s [test_taskprocessor.c:subsystem_alert:240]: Subsystem alert triggered correctly at 6 371s [test_taskprocessor.c:subsystem_alert:243]: Global alert triggered correctly at 6 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 7 371s [test_taskprocessor.c:subsystem_alert:240]: Subsystem alert triggered correctly at 6 371s [test_taskprocessor.c:subsystem_alert:243]: Global alert triggered correctly at 6 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 8 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 9 371s [test_taskprocessor.c:subsystem_alert:227]: Pushing task 10 371s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 1 371s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 2 372s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 3 372s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 4 373s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 5 373s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 6 374s [test_taskprocessor.c:subsystem_alert:282]: Subsystem alert cleared correctly at 3 374s [test_taskprocessor.c:subsystem_alert:285]: Global alert cleared correctly at 3 374s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 7 374s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 8 375s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 9 375s [test_taskprocessor.c:subsystem_alert:269]: Waiting on task 10 376s END /main/taskprocessor/ - subsystem_alert Time: 5001ms Result: PASS 376s START /main/taskprocessor/ - default_taskprocessor_load 376s END /main/taskprocessor/ - default_taskprocessor_load Time: 35ms Result: PASS 376s START /main/taskprocessor/ - default_taskprocessor 376s END /main/taskprocessor/ - default_taskprocessor Time: <1ms Result: PASS 376s START /main/test/ - registrations 376s END /main/test/ - registrations Time: <1ms Result: PASS 376s START /main/test_capture/ - test_capture_false 376s [test_capture.c:test_capture_false:120]: Executing false exit test... 376s END /main/test_capture/ - test_capture_false Time: 17ms Result: PASS 376s START /main/test_capture/ - test_capture_true 376s [test_capture.c:test_capture_true:61]: Executing true exit test... 376s END /main/test_capture/ - test_capture_true Time: 11ms Result: PASS 376s START /main/test_capture/ - test_capture_stdout_stderr 376s [test_capture.c:test_capture_stdout_stderr:318]: Executing stdout/stderr test... 376s END /main/test_capture/ - test_capture_stdout_stderr Time: 10ms Result: PASS 376s START /main/test_capture/ - test_capture_with_dynamic 376s [test_capture.c:test_capture_with_dynamic:249]: Executing dynamic argument test... 376s [test_capture.c:test_capture_with_dynamic:272]: unexpected value for stdout 376s END /main/test_capture/ - test_capture_with_dynamic Time: 15ms Result: FAIL 376s START /main/test_capture/ - test_capture_with_stdin 376s [test_capture.c:test_capture_with_stdin:183]: Executing stdin test... 376s END /main/test_capture/ - test_capture_with_stdin Time: 12ms Result: PASS 376s START /main/threadpool/ - threadpool_serializer_dupe 376s END /main/threadpool/ - threadpool_serializer_dupe Time: <1ms Result: PASS 376s START /main/threadpool/ - threadpool_serializer 377s END /main/threadpool/ - threadpool_serializer Time: 1001ms Result: PASS 377s START /main/threadpool/ - more_destruction 377s END /main/threadpool/ - more_destruction Time: 1ms Result: PASS 377s START /main/threadpool/ - task_distribution 377s END /main/threadpool/ - task_distribution Time: <1ms Result: PASS 377s START /main/threadpool/ - reactivation 377s END /main/threadpool/ - reactivation Time: <1ms Result: PASS 377s START /main/threadpool/ - max_size 377s END /main/threadpool/ - max_size Time: <1ms Result: PASS 377s START /main/threadpool/ - auto_increment 377s END /main/threadpool/ - auto_increment Time: <1ms Result: PASS 377s START /main/threadpool/ - one_thread_multiple_tasks 377s END /main/threadpool/ - one_thread_multiple_tasks Time: <1ms Result: PASS 377s START /main/threadpool/ - one_thread_one_task 377s END /main/threadpool/ - one_thread_one_task Time: <1ms Result: PASS 377s START /main/threadpool/ - one_task_one_thread 377s END /main/threadpool/ - one_task_one_thread Time: <1ms Result: PASS 377s START /main/threadpool/ - thread_timeout_thrash 408s END /main/threadpool/ - thread_timeout_thrash Time: 31016ms Result: PASS 408s START /main/threadpool/ - thread_timeout 410s END /main/threadpool/ - thread_timeout Time: 2000ms Result: PASS 410s START /main/threadpool/ - thread_destruction 410s END /main/threadpool/ - thread_destruction Time: <1ms Result: PASS 410s START /main/threadpool/ - thread_creation 410s END /main/threadpool/ - thread_creation Time: <1ms Result: PASS 410s START /main/threadpool/ - initial_threads 410s END /main/threadpool/ - initial_threads Time: 1ms Result: PASS 410s START /main/threadpool/ - push 410s END /main/threadpool/ - push Time: <1ms Result: PASS 410s START /main/uri/ - uri_default_http_secure 410s END /main/uri/ - uri_default_http_secure Time: <1ms Result: PASS 410s START /main/uri/ - uri_default_http 410s END /main/uri/ - uri_default_http Time: <1ms Result: PASS 410s START /main/uri/ - uri_parse 410s END /main/uri/ - uri_parse Time: <1ms Result: PASS 410s START /main/utf8/ - replace_invalid 410s END /main/utf8/ - replace_invalid Time: <1ms Result: PASS 410s START /main/utf8/ - utf8_validator 410s END /main/utf8/ - utf8_validator Time: <1ms Result: PASS 410s START /main/utf8/ - copy_string 410s END /main/utf8/ - copy_string Time: <1ms Result: PASS 410s START /main/utf8/ - is_valid 410s END /main/utf8/ - is_valid Time: <1ms Result: PASS 410s START /main/utils/ - quote_unescaping 410s END /main/utils/ - quote_unescaping Time: <1ms Result: PASS 410s START /main/utils/ - quote_mutation 410s END /main/utils/ - quote_mutation Time: <1ms Result: PASS 410s START /main/utils/ - crypt_test 410s END /main/utils/ - crypt_test Time: 44ms Result: PASS 410s START /main/utils/ - safe_mkdir_test 410s END /main/utils/ - safe_mkdir_test Time: <1ms Result: PASS 410s START /main/utils/ - base64_test 410s END /main/utils/ - base64_test Time: <1ms Result: PASS 410s START /main/utils/ - sha1_test 410s [test_utils.c:sha1_test:250]: Testing SHA1 ... 410s END /main/utils/ - sha1_test Time: <1ms Result: PASS 410s START /main/utils/ - md5_test 410s [test_utils.c:md5_test:205]: Testing MD5 ... 410s END /main/utils/ - md5_test Time: <1ms Result: PASS 410s START /main/utils/ - quoted_escape_test 410s END /main/utils/ - quoted_escape_test Time: <1ms Result: PASS 410s START /main/utils/ - uri_encode_decode_test 410s END /main/utils/ - uri_encode_decode_test Time: <1ms Result: PASS 410s START /main/utils/ - string_field_aggregate_test 410s [test_stringfields.c:string_field_aggregate_test:392]: Structures 3 embedded pool initialized successfully. 410s [test_stringfields.c:string_field_aggregate_test:410]: Structures 3 additional pool initialized successfully. 410s [test_stringfields.c:string_field_aggregate_test:422]: Structures 1/2 are equal as expected. 410s [test_stringfields.c:string_field_aggregate_test:430]: Structures 1/3 are different as expected. 410s [test_stringfields.c:string_field_aggregate_test:438]: Structures 2/3 are different as expected. 410s [test_stringfields.c:string_field_aggregate_test:446]: Structures 3/4 are different as expected. 410s [test_stringfields.c:string_field_aggregate_test:454]: Copying from structure 3 to structure 1 succeeded! 410s [test_stringfields.c:string_field_aggregate_test:463]: Structures 1/3 are equal as expected. 410s [test_stringfields.c:string_field_aggregate_test:470]: Structures 1/2 are different as expected. 410s [test_stringfields.c:string_field_aggregate_test:473]: Reset but don't free. 410s [test_stringfields.c:string_field_aggregate_test:484]: Structures 1/2 are the same (empty) as expected. 410s [test_stringfields.c:string_field_aggregate_test:492]: Structure 4 embedded pool is the current pool as expected. 410s [test_stringfields.c:string_field_aggregate_test:500]: Structure 4 embedded pool does not have a previous as expected. 410s END /main/utils/ - string_field_aggregate_test Time: <1ms Result: PASS 410s START /main/utils/ - string_field_test 410s [test_stringfields.c:string_field_test:79]: First things first. Let's see if we can actually allocate string fields 410s [test_stringfields.c:string_field_test:85]: All right! Successfully allocated! Now let's get down to business 410s [test_stringfields.c:string_field_test:89]: We're going to set some string fields and perform some checks 410s [test_stringfields.c:string_field_test:94]: First we're going to make sure that the strings are actually set to what we expect 410s [test_stringfields.c:string_field_test:100]: test_struct.string1 appears to be all clear. It has 'elephant' and that's what we expect 410s [test_stringfields.c:string_field_test:107]: test_struct.string2 appears to be all clear. It has 'hippopotamus' and that's what we expect 410s [test_stringfields.c:string_field_test:110]: Now let's make sure that our recorded capacities for these strings is what we expect 410s [test_stringfields.c:string_field_test:117]: string1 has the allocation area we expect: 9 410s [test_stringfields.c:string_field_test:125]: string2 has the allocation area we expect: 13 410s [test_stringfields.c:string_field_test:128]: Now we're going to shrink string1 and see if it's in the same place in memory 410s [test_stringfields.c:string_field_test:137]: string1 successfully was changed to 'rhino' 410s [test_stringfields.c:string_field_test:144]: Shrinking string1 allowed it to stay in the same place in memory 410s [test_stringfields.c:string_field_test:151]: Shrinking string1 did not change its allocation area (This is a good thing) 410s [test_stringfields.c:string_field_test:154]: Next, let's increase it a little but not all the way to its original size 410s [test_stringfields.c:string_field_test:163]: string1 successfully was changed to 'mammoth' 410s [test_stringfields.c:string_field_test:170]: Expanding string1 allowed it to stay in the same place in memory 410s [test_stringfields.c:string_field_test:177]: Expanding string1 did not change its allocation area (This is a good thing) 410s [test_stringfields.c:string_field_test:180]: Cool, now let's bring it back to its original size and see what happens 410s [test_stringfields.c:string_field_test:188]: string1 successfully changed to 'elephant' 410s [test_stringfields.c:string_field_test:195]: Restoring string1 did not cause it to move (This is a good thing) 410s [test_stringfields.c:string_field_test:202]: The allocation amount for string1 is still holding steady 410s [test_stringfields.c:string_field_test:205]: All right, now we're going to expand string 2. It should stay in place since it was the last string allocated in this pool 410s [test_stringfields.c:string_field_test:214]: string2 successfully changed to 'hippopotamus face' 410s [test_stringfields.c:string_field_test:222]: The allocation amount successfully increased for string2 when it grew 410s [test_stringfields.c:string_field_test:229]: string2 stayed in place when it grew. Good job! 410s [test_stringfields.c:string_field_test:232]: Now we're going to set string1 to a very long string so that a new string field pool must be allocated 410s [test_stringfields.c:string_field_test:241]: string1 successfully changed to 'A professional panoramic photograph of the majestic elephant bathing itself and its young by the shores of the raging Mississippi River' 410s [test_stringfields.c:string_field_test:248]: Good. Setting string1 to a long value caused it to change addresses 410s [test_stringfields.c:string_field_test:256]: The stored allocation size of string1 is what we expect 410s [test_stringfields.c:string_field_test:260]: Now using a totally separate area of memory we're going to test a basic pool freeing scenario 410s [test_stringfields.c:string_field_test:281]: Three different pools are linked as expected. 410s [test_stringfields.c:string_field_test:289]: After removing a pool the remaining two are linked as expected. 410s END /main/utils/ - string_field_test Time: <1ms Result: PASS 410s START /main/uuid/ - uuid 410s [test_uuid.c:uuid:59]: Generate UUID direct to string, got fadc4a2e-647a-41e0-ba9d-b6f8db0e08c0 410s [test_uuid.c:uuid:90]: Second generated UUID converted to string, got ccc49d8b-8e72-4eda-bc23-095a4158383f 410s END /main/uuid/ - uuid Time: <1ms Result: PASS 410s START /main/vector/ - basic 410s END /main/vector/ - basic Time: <1ms Result: PASS 410s START /main/vector/ - basic_integer 410s END /main/vector/ - basic_integer Time: <1ms Result: PASS 410s START /main/vector/ - callbacks 410s END /main/vector/ - callbacks Time: <1ms Result: PASS 410s START /main/vector/ - locks 410s END /main/vector/ - locks Time: <1ms Result: PASS 410s START /main/voicemail_api/ - off_nominal_msg_playback 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1493]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1493]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1493]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1493]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1502]: Playing back message from invalid mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1505]: Playing back message from NULL mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1508]: Playing back message from invalid context 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1511]: Playing back message from invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1514]: Playing back message from NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1517]: Playing back message with invalid message specifier 410s [test_voicemail_api.c:voicemail_api_off_nominal_msg_playback:1520]: Playing back message with NULL message specifier 410s END /main/voicemail_api/ - off_nominal_msg_playback Time: 10ms Result: PASS 410s START /main/voicemail_api/ - nominal_msg_playback 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1431]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1431]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1431]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1431]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1443]: Playing back message from test_vm_api_1234 to mock channel 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1446]: Playing back message from test_vm_api_2345 to callback function 410s [test_voicemail_api.c:voicemail_api_nominal_msg_playback:1451]: Playing back message from test_vm_api_2345 to callback function with default context 410s END /main/voicemail_api/ - nominal_msg_playback Time: 456ms Result: PASS 410s START /main/voicemail_api/ - off_nominal_forward 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1353]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1353]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1353]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1353]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1362]: Test forwarding from an invalid mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1365]: Test forwarding from a NULL mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1368]: Test forwarding from an invalid context 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1371]: Test forwarding from an invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1374]: Test forwarding from a NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1377]: Test forwarding to an invalid mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1380]: Test forwarding to a NULL mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1383]: Test forwarding to an invalid context 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1386]: Test forwarding to an invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1390]: Test forwarding to a NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1393]: Test forwarding when no messages are select 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1396]: Test forwarding a message that doesn't exist 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1400]: Test forwarding multiple messages, where some messages don't exist 410s [test_voicemail_api.c:voicemail_api_off_nominal_forward:1403]: Test forwarding a message with an invalid size specifier 410s END /main/voicemail_api/ - off_nominal_forward Time: 10ms Result: PASS 410s START /main/voicemail_api/ - nominal_forward 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1237]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1237]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1237]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1237]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1244]: Test forwarding message 0 from test_vm_api_1234 INBOX to test_vm_api_2345 INBOX 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1257]: Test forwarding message 0 from test_vm_api_1234 INBOX with default context to test_vm_api_2345 INBOX 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1270]: Test forwarding message 0 from test_vm_api_1234 INBOX to test_vm_api_2345 INBOX with default context 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1283]: Test forwarding message 0 from test_vm_api_1234 INBOX to test_vm_api_2345 INBOX, deleting original 410s [test_voicemail_api.c:voicemail_api_nominal_forward:1296]: Test forwarding 2 messages from test_vm_api_2345 INBOX to test_vm_api_1234 INBOX[test_voicemail_api.c:voicemail_api_nominal_forward:1309]: Test forwarding 2 messages from test_vm_api_2345 INBOX to test_vm_api_1234 Family, deleting original 410s END /main/voicemail_api/ - nominal_forward Time: 28ms Result: PASS 410s START /main/voicemail_api/ - off_nominal_remove 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1178]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1178]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1178]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1178]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1184]: Test removing a single message with an invalid mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1187]: Test removing a single message with a NULL mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1190]: Test removing a single message with an invalid context 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1193]: Test removing a single message with an invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1196]: Test removing a single message with a NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1199]: Test removing a single message with an invalid message number 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1203]: Test removing multiple messages with a single invalid message number 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1207]: Test removing no messages with no message numbers 410s [test_voicemail_api.c:voicemail_api_off_nominal_remove:1210]: Test removing multiple messages with an invalid size specifier 410s END /main/voicemail_api/ - off_nominal_remove Time: 10ms Result: PASS 410s START /main/voicemail_api/ - nominal_remove 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1131]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1131]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1131]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1131]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1139]: Test removing a single message from INBOX 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1142]: Test removing a single message from Old 410s [test_voicemail_api.c:voicemail_api_nominal_remove:1145]: Test removing multiple messages from INBOX 410s END /main/voicemail_api/ - nominal_remove Time: 11ms Result: PASS 410s START /main/voicemail_api/ - off_nominal_move 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1059]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1059]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1059]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1059]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1068]: Test move attempt for invalid mailbox test_vm_3456 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1073]: Test move attempt for invalid context test_vm_api_defunct 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1076]: Test move attempt to invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1079]: Test move attempt from invalid folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1082]: Test move attempt to NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1085]: Test move attempt from NULL folder 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1088]: Test move attempt with non-existent message number 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1092]: Test move attempt with invalid message number 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1096]: Test move attempt with 0 number of messages 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1100]: Test move attempt with invalid number of messages 410s [test_voicemail_api.c:voicemail_api_off_nominal_move:1103]: Test move attempt with non-existent multiple messages, where some messages exist 410s END /main/voicemail_api/ - off_nominal_move Time: 9ms Result: PASS 410s START /main/voicemail_api/ - nominal_move 410s [test_voicemail_api.c:voicemail_api_nominal_move:985]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_nominal_move:985]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_nominal_move:985]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_nominal_move:985]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_nominal_move:992]: Test move of test_vm_api_1234 message from INBOX to Family 410s [test_voicemail_api.c:voicemail_api_nominal_move:995]: Test move of test_vm_api_1234 message from Old to Family 410s [test_voicemail_api.c:voicemail_api_nominal_move:1009]: Test move of test_vm_api_2345 messages from Inbox to Family 410s [test_voicemail_api.c:voicemail_api_nominal_move:1020]: Test move of test_vm_api_2345 message from Family to INBOX 410s END /main/voicemail_api/ - nominal_move Time: 14ms Result: PASS 410s START /main/voicemail_api/ - off_nominal_snapshot 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:945]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:945]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:945]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:945]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:947]: Test access to non-existent mailbox test_vm_api_3456 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:950]: Test access to null mailbox 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:953]: Test access non-existent context test_vm_api_defunct 410s [test_voicemail_api.c:voicemail_api_off_nominal_snapshot:956]: Test non-existent folder test_vm_api_platypus 410s END /main/voicemail_api/ - off_nominal_snapshot Time: 8ms Result: PASS 410s START /main/voicemail_api/ - nominal_snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:854]: Created message in test_vm_api_1234/Old with ID 1770661036-909401725 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:854]: Created message in test_vm_api_1234/INBOX with ID 1770661036-960311778 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:854]: Created message in test_vm_api_2345/INBOX with ID 1770661036-1500739833 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:854]: Created message in test_vm_api_2345/INBOX with ID 1770661036-719848782 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:856]: Test retrieving message 1 from INBOX of test_vm_1234 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:859]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:862]: Test retrieving message 0 from Old of test_vm_1234 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:865]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:868]: Test retrieving message 0, 1 from Old and INBOX of test_vm_1234 ordered by time 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:871]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:872]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:875]: Test retrieving message 1, 0 from Old and INBOX of test_vm_1234 ordered by time desc 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:878]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:879]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:882]: Test retrieving message 0, 1 from Old and INBOX of test_vm_1234 ordered by id 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:885]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:886]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:889]: Test retrieving message 1, 0 from Old and INBOX of test_vm_1234 ordered by id desc 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:892]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:893]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:896]: Test retrieving message 0, 1 from all folders of test_vm_1234 ordered by id 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:899]: Found message 1770661036-909401725 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:900]: Found message 1770661036-960311778 in snapshot 410s [test_voicemail_api.c:voicemail_api_nominal_snapshot:903]: Test retrieving message 0, 1 from all folders of test_vm_1234 ordered by time 411s [test_voicemail_api.c:voicemail_api_nominal_snapshot:906]: Found message 1770661036-909401725 in snapshot 411s [test_voicemail_api.c:voicemail_api_nominal_snapshot:907]: Found message 1770661036-960311778 in snapshot 411s [test_voicemail_api.c:voicemail_api_nominal_snapshot:910]: Test retrieving message 0, 1 from all folders of test_vm_1234, default context ordered by time 411s [test_voicemail_api.c:voicemail_api_nominal_snapshot:913]: Found message 1770661036-909401725 in snapshot 411s [test_voicemail_api.c:voicemail_api_nominal_snapshot:914]: Found message 1770661036-960311778 in snapshot 411s END /main/voicemail_api/ - nominal_snapshot Time: 21ms Result: PASS 411s START /main/xml_escape/ - xml_escape_test 411s END /main/xml_escape/ - xml_escape_test Time: <1ms Result: PASS 411s START /mwi/ - explicit_publish 411s END /mwi/ - explicit_publish Time: 45ms Result: PASS 411s START /mwi/ - implicit_publish 411s END /mwi/ - implicit_publish Time: 43ms Result: PASS 411s START /res/adsi/ - adsi_loaded_test 411s END /res/adsi/ - adsi_loaded_test Time: <1ms Result: PASS 411s START /res/aeap/ - send_msg_handle_request 411s END /res/aeap/ - send_msg_handle_request Time: 1ms Result: PASS 411s START /res/aeap/ - send_msg_handle_response 411s END /res/aeap/ - send_msg_handle_response Time: <1ms Result: PASS 411s START /res/aeap/ - send_msg_handle_string 413s END /res/aeap/ - send_msg_handle_string Time: 2000ms Result: PASS 413s START /res/aeap/ - create_and_connect 413s END /res/aeap/ - create_and_connect Time: 1ms Result: PASS 413s START /res/aeap/speech/ - res_speech_aeap_test 413s END /res/aeap/speech/ - res_speech_aeap_test Time: 2ms Result: PASS 413s START /res/aeap/transaction/ - transaction_exec_timeout 413s END /res/aeap/transaction/ - transaction_exec_timeout Time: 99ms Result: PASS 413s START /res/aeap/transaction/ - transaction_exec 414s END /res/aeap/transaction/ - transaction_exec Time: 1000ms Result: PASS 414s START /res/aeap/transport/ - transport_create_invalid 414s END /res/aeap/transport/ - transport_create_invalid Time: <1ms Result: PASS 414s START /res/aeap/transport/ - transport_create 414s END /res/aeap/transport/ - transport_create Time: <1ms Result: PASS 414s START /res/aeap/transport/ - transport_connect 414s END /res/aeap/transport/ - transport_connect Time: <1ms Result: PASS 414s START /res/aeap/transport/ - transport_connect_fail 414s END /res/aeap/transport/ - transport_connect_fail Time: 1ms Result: PASS 414s START /res/aeap/transport/ - transport_binary 414s END /res/aeap/transport/ - transport_binary Time: <1ms Result: PASS 414s START /res/aeap/transport/ - transport_string 414s END /res/aeap/transport/ - transport_string Time: <1ms Result: PASS 414s START /res/agi/ - agi_loaded_test 414s END /res/agi/ - agi_loaded_test Time: 2ms Result: PASS 414s START /res/agi/ - null_agi_docs 414s END /res/agi/ - null_agi_docs Time: <1ms Result: PASS 414s START /res/ari/ - invoke_not_found 414s END /res/ari/ - invoke_not_found Time: <1ms Result: PASS 414s START /res/ari/ - invoke_bad_post 414s END /res/ari/ - invoke_bad_post Time: <1ms Result: PASS 414s START /res/ari/ - invoke_post 414s END /res/ari/ - invoke_post Time: <1ms Result: PASS 414s START /res/ari/ - invoke_delete 414s END /res/ari/ - invoke_delete Time: <1ms Result: PASS 414s START /res/ari/ - invoke_wildcard 414s END /res/ari/ - invoke_wildcard Time: <1ms Result: PASS 414s START /res/ari/ - invoke_get 414s END /res/ari/ - invoke_get Time: <1ms Result: PASS 414s START /res/ari/ - get_docs_hackerz 414s END /res/ari/ - get_docs_hackerz Time: <1ms Result: PASS 414s START /res/ari/ - get_docs_notfound 414s END /res/ari/ - get_docs_notfound Time: <1ms Result: PASS 414s START /res/ari/ - get_docs_nohost 414s END /res/ari/ - get_docs_nohost Time: <1ms Result: PASS 414s START /res/ari/ - get_docs 414s END /res/ari/ - get_docs Time: <1ms Result: PASS 414s START /res/crypto/ - crypto_loaded_test 414s END /res/crypto/ - crypto_loaded_test Time: <1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_content_type 414s END /res/http_media_cache/ - retrieve_content_type Time: 1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_parsed_uri 414s END /res/http_media_cache/ - retrieve_parsed_uri Time: <1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_cache_control_directives 414s [test_http_media_cache.c:retrieve_cache_control_directives:322]: Testing no-cache... 414s [test_http_media_cache.c:retrieve_cache_control_directives:329]: Testing no-cache with ETag... 414s [test_http_media_cache.c:retrieve_cache_control_directives:339]: Testing no-cache with max-age... 414s [test_http_media_cache.c:retrieve_cache_control_directives:344]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_directives:351]: Testing must-revalidate... 414s [test_http_media_cache.c:retrieve_cache_control_directives:358]: Testing must-revalidate with ETag... 414s [test_http_media_cache.c:retrieve_cache_control_directives:368]: Testing must-revalidate with max-age... 414s [test_http_media_cache.c:retrieve_cache_control_directives:373]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s END /res/http_media_cache/ - retrieve_cache_control_directives Time: 8ms Result: PASS 414s START /res/http_media_cache/ - retrieve_cache_control_age 414s [test_http_media_cache.c:retrieve_cache_control_age:406]: Testing max-age... 414s [test_http_media_cache.c:retrieve_cache_control_age:410]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_age:414]: Testing s-maxage... 414s [test_http_media_cache.c:retrieve_cache_control_age:420]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_age:424]: Testing max-age and s-maxage... 414s [test_http_media_cache.c:retrieve_cache_control_age:430]: Checking 1770661643 >= 1770661640 and 1770661637 <= 1770661640 414s [test_http_media_cache.c:retrieve_cache_control_age:434]: Testing max-age and Expires... 414s [test_http_media_cache.c:retrieve_cache_control_age:441]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_age:445]: Testing s-maxage and Expires... 414s [test_http_media_cache.c:retrieve_cache_control_age:452]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_age:456]: Testing s-maxage and Expires... 414s [test_http_media_cache.c:retrieve_cache_control_age:463]: Checking 1770661343 >= 1770661340 and 1770661337 <= 1770661340 414s [test_http_media_cache.c:retrieve_cache_control_age:467]: Testing max-age, s-maxage, and Expires... 414s [test_http_media_cache.c:retrieve_cache_control_age:474]: Checking 1770661643 >= 1770661640 and 1770661637 <= 1770661640 414s END /res/http_media_cache/ - retrieve_cache_control_age Time: 7ms Result: PASS 414s START /res/http_media_cache/ - retrieve_etag_expired 414s [test_http_media_cache.c:retrieve_etag_expired:514]: Checking 1770661042 >= 1770661039 and 1770661036 <= 1770661039 414s END /res/http_media_cache/ - retrieve_etag_expired Time: 1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_expires 414s [test_http_media_cache.c:retrieve_expires:554]: Checking 1770664043 >= 1770664040 and 1770664037 <= 1770664040 414s [test_http_media_cache.c:retrieve_expires:564]: Checking 1770661042 >= 1770661039 and 1770661036 <= 1770661039 414s END /res/http_media_cache/ - retrieve_expires Time: 1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_etag 414s [test_http_media_cache.c:retrieve_etag:604]: Checking 1770661043 >= 1770661040 and 1770661037 <= 1770661040 414s END /res/http_media_cache/ - retrieve_etag Time: 1ms Result: PASS 414s START /res/http_media_cache/ - retrieve_nominal 414s [test_http_media_cache.c:retrieve_nominal:642]: Checking 1770661043 >= 1770661040 and 1770661037 <= 1770661040 414s END /res/http_media_cache/ - retrieve_nominal Time: <1ms Result: PASS 414s START /res/http_media_cache/ - create_nominal 414s [test_http_media_cache.c:create_nominal:675]: Checking 1770661043 >= 1770661040 and 1770661037 <= 1770661040 414s END /res/http_media_cache/ - create_nominal Time: <1ms Result: PASS 414s START /res/parking/ - dynamic_parking_variables 414s [parking/parking_tests.c:dynamic_parking_variables:756]: Creating expected configuration for dynamic parking lot 414s [parking/parking_tests.c:dynamic_parking_variables:775]: Creating template lot 414s [parking/parking_tests.c:dynamic_parking_variables:784]: Creating Alice channel to test dynamic parking lot creation. 414s [parking/parking_tests.c:dynamic_parking_variables:794]: Setting Dynamic Parking channel variables on Alice. 414s [parking/parking_tests.c:dynamic_parking_variables:802]: Generating dynamic parking lot based on Alice's channel variables. 414s [parking/parking_tests.c:dynamic_parking_variables:819]: Dynamic parking lot created successfully and matches expectations. Test passed. 414s END /res/parking/ - dynamic_parking_variables Time: 1ms Result: PASS 414s START /res/parking/ - extension_conflicts 414s [parking/parking_tests.c:extension_conflicts:597]: Creating the base lot. This should pass. 414s [parking/parking_tests.c:extension_conflicts:607]: Creating a test lot which will overlap. 414s [parking/parking_tests.c:generate_test_parking_lot:161]: Extensions for parking lot 'unit_tests_res_parking_test_lot_fail1' could not be registered. Extension Creation failed. 414s [parking/parking_tests.c:generate_test_parking_lot:161]: Extensions for parking lot 'unit_tests_res_parking_test_lot_fail2' could not be registered. Extension Creation failed. 414s [parking/parking_tests.c:generate_test_parking_lot:161]: Extensions for parking lot 'unit_tests_res_parking_test_lot_fail3' could not be registered. Extension Creation failed. 414s [parking/parking_tests.c:generate_test_parking_lot:161]: Extensions for parking lot 'unit_tests_res_parking_test_lot_fail4' could not be registered. Extension Creation failed. 414s END /res/parking/ - extension_conflicts Time: <1ms Result: PASS 414s START /res/parking/ - park_extensions 414s [parking/parking_tests.c:park_extensions:551]: Extensions for the test parking lot were verified. Cleaning up and verifying their removal. 414s [parking/parking_tests.c:park_extensions:565]: Extensions for the test parking lot verified as removed. Test completed successfully. 414s END /res/parking/ - park_extensions Time: <1ms Result: PASS 414s START /res/parking/ - park_retrieve 414s [parking/parking_tests.c:retrieve_call:405]: Creating test parking lot 'unit_tests_res_parking_test_lot' 415s [parking/parking_tests.c:retrieve_call:446]: Successfully retrieved parked user from the parking lot. Validating user data. 415s END /res/parking/ - park_retrieve Time: 1001ms Result: PASS 415s START /res/parking/ - park_channel 415s [parking/parking_tests.c:park_call:254]: Creating test parking lot 'unit_tests_res_parking_test_lot' 416s END /res/parking/ - park_channel Time: 1001ms Result: PASS 416s START /res/parking/ - create_lot 416s [parking/parking_tests.c:create_lot:206]: Creating test parking lot 'unit_tests_res_parking_test_lot' 416s [parking/parking_tests.c:create_lot:214]: Successfully created parking lot. Retrieving test parking lot from container. 416s [parking/parking_tests.c:create_lot:223]: Successfully retrieved parking lot. Removing test parking lot from container. 416s [parking/parking_tests.c:create_lot:229]: Parking lot was successfully removed from the container. Test complete. 416s END /res/parking/ - create_lot Time: <1ms Result: PASS 416s START /res/prometheus/ - bridge_to_string 416s [test_res_prometheus.c:bridge_to_string:760]: -> Retrieved: # HELP asterisk_channels_count Current channel count. 416s # TYPE asterisk_channels_count gauge 416s asterisk_channels_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_sum Total call count. 416s # TYPE asterisk_calls_sum counter 416s asterisk_calls_sum{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_count Current call count. 416s # TYPE asterisk_calls_count gauge 416s asterisk_calls_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_endpoints_count Current endpoint count. 416s # TYPE asterisk_endpoints_count gauge 416s asterisk_endpoints_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_bridges_count Current bridge count. 416s # TYPE asterisk_bridges_count gauge 416s asterisk_bridges_count{eid="00:16:3e:04:1c:e8"} 3 416s # HELP asterisk_bridges_channels_count Number of channels in the bridge. 416s # TYPE asterisk_bridges_channels_count gauge 416s asterisk_bridges_channels_count{eid="00:16:3e:04:1c:e8",id="30718f3e-1210-462a-a1c4-7def7d7d403c",tech="simple_bridge",subclass="basic",creator="",name=""} 0 416s asterisk_bridges_channels_count{eid="00:16:3e:04:1c:e8",id="7f4b04a2-b168-490b-9da5-2d07b436a0f1",tech="simple_bridge",subclass="basic",creator="",name=""} 0 416s 416s END /res/prometheus/ - bridge_to_string Time: <1ms Result: PASS 416s START /res/prometheus/ - config_general_core_metrics 416s [test_res_prometheus.c:config_general_core_metrics:680]: -> CURLing request... 416s [test_res_prometheus.c:config_general_core_metrics:688]: -> Retrieved: # HELP asterisk_channels_count Current channel count. 416s # TYPE asterisk_channels_count gauge 416s asterisk_channels_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_sum Total call count. 416s # TYPE asterisk_calls_sum counter 416s asterisk_calls_sum{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_count Current call count. 416s # TYPE asterisk_calls_count gauge 416s asterisk_calls_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_endpoints_count Current endpoint count. 416s # TYPE asterisk_endpoints_count gauge 416s asterisk_endpoints_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_bridges_count Current bridge count. 416s # TYPE asterisk_bridges_count gauge 416s asterisk_bridges_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_core_properties Asterisk instance properties. The value of this will always be 1. 416s # TYPE asterisk_core_properties counter 416s asterisk_core_properties{eid="00:16:3e:04:1c:e8",version="22.8.2+dfsg+~cs6.15.60671435-1",build_options="OPTIONAL_API, TEST_FRAMEWORK",build_date="2026-02-08 20:02:53 UTC",build_os="Linux",build_kernel="unknown",build_host="buildd.debian.org"} 1 416s # HELP asterisk_core_uptime_seconds Asterisk instance uptime in seconds. 416s # TYPE asterisk_core_uptime_seconds counter 416s asterisk_core_uptime_seconds{eid="00:16:3e:04:1c:e8"} 201 416s # HELP asterisk_core_last_reload_seconds Time since last Asterisk reload in seconds. 416s # TYPE asterisk_core_last_reload_seconds counter 416s asterisk_core_last_reload_seconds{eid="00:16:3e:04:1c:e8"} 201 416s # HELP asterisk_core_scrape_time_ms Total time taken to collect metrics, in milliseconds 416s # TYPE asterisk_core_scrape_time_ms counter 416s asterisk_core_scrape_time_ms{eid="00:16:3e:04:1c:e8"} 0 416s 416s [test_res_prometheus.c:config_general_core_metrics:690]: -> Checking for core properties 416s [test_res_prometheus.c:config_general_core_metrics:693]: -> Checking for uptime 416s [test_res_prometheus.c:config_general_core_metrics:696]: -> Checking for last reload 416s [test_res_prometheus.c:config_general_core_metrics:699]: -> Checking for scrape time 416s END /res/prometheus/ - config_general_core_metrics Time: 1ms Result: PASS 416s START /res/prometheus/ - config_general_basic_auth 416s [test_res_prometheus.c:config_general_basic_auth:555]: Testing without auth credentials 416s [test_res_prometheus.c:config_general_basic_auth:556]: -> CURLing request... 416s [test_res_prometheus.c:config_general_basic_auth:563]: -> CURL returned 401 416s [test_res_prometheus.c:config_general_basic_auth:566]: Testing with invalid auth credentials 416s [test_res_prometheus.c:config_general_basic_auth:567]: -> CURLing request... 416s [test_res_prometheus.c:config_general_basic_auth:576]: -> CURL returned 401 416s [test_res_prometheus.c:config_general_basic_auth:579]: Testing with valid auth credentials 416s [test_res_prometheus.c:config_general_basic_auth:580]: -> CURLing request... 416s [test_res_prometheus.c:config_general_basic_auth:588]: -> CURL returned 200 416s END /res/prometheus/ - config_general_basic_auth Time: 2ms Result: PASS 416s START /res/prometheus/ - config_general_enabled 416s [test_res_prometheus.c:config_general_enabled:628]: -> CURLing request... 416s [test_res_prometheus.c:config_general_enabled:635]: -> CURL returned 503 416s END /res/prometheus/ - config_general_enabled Time: <1ms Result: PASS 416s START /res/prometheus/ - gauge_create 416s END /res/prometheus/ - gauge_create Time: <1ms Result: PASS 416s START /res/prometheus/ - gauge_to_string 416s END /res/prometheus/ - gauge_to_string Time: <1ms Result: PASS 416s START /res/prometheus/ - counter_create 416s END /res/prometheus/ - counter_create Time: <1ms Result: PASS 416s START /res/prometheus/ - counter_to_string 416s END /res/prometheus/ - counter_to_string Time: <1ms Result: PASS 416s START /res/prometheus/ - metric_register 416s [test_res_prometheus.c:metric_register:291]: Testing nominal registration 416s [test_res_prometheus.c:metric_register:292]: -> Static metric 416s [test_res_prometheus.c:metric_register:294]: -> Malloc'd metric 416s [test_res_prometheus.c:metric_register:300]: Testing nominal registration of child metrics 416s [test_res_prometheus.c:metric_register:315]: Testing name collisions 416s [test_res_prometheus.c:metric_register:322]: Testing label collisions 416s [test_res_prometheus.c:metric_register:331]: Testing removal of metrics 416s END /res/prometheus/ - metric_register Time: <1ms Result: PASS 416s START /res/prometheus/ - metric_callback_register 416s [test_res_prometheus.c:metric_callback_register:241]: -> CURLing request... 416s [test_res_prometheus.c:metric_callback_register:250]: -> Retrieved: # HELP asterisk_channels_count Current channel count. 416s # TYPE asterisk_channels_count gauge 416s asterisk_channels_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_sum Total call count. 416s # TYPE asterisk_calls_sum counter 416s asterisk_calls_sum{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_count Current call count. 416s # TYPE asterisk_calls_count gauge 416s asterisk_calls_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_endpoints_count Current endpoint count. 416s # TYPE asterisk_endpoints_count gauge 416s asterisk_endpoints_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_bridges_count Current bridge count. 416s # TYPE asterisk_bridges_count gauge 416s asterisk_bridges_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP test_counter A test counter 416s # TYPE test_counter counter 416s test_counter 0 416s 416s END /res/prometheus/ - metric_callback_register Time: 1ms Result: PASS 416s START /res/prometheus/ - metric_values 416s [test_res_prometheus.c:metric_values:166]: -> CURLing request... 416s [test_res_prometheus.c:metric_values:176]: -> Retrieved: # HELP asterisk_channels_count Current channel count. 416s # TYPE asterisk_channels_count gauge 416s asterisk_channels_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_sum Total call count. 416s # TYPE asterisk_calls_sum counter 416s asterisk_calls_sum{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_calls_count Current call count. 416s # TYPE asterisk_calls_count gauge 416s asterisk_calls_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_endpoints_count Current endpoint count. 416s # TYPE asterisk_endpoints_count gauge 416s asterisk_endpoints_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP asterisk_bridges_count Current bridge count. 416s # TYPE asterisk_bridges_count gauge 416s asterisk_bridges_count{eid="00:16:3e:04:1c:e8"} 0 416s # HELP test_counter_one A test counter 416s # TYPE test_counter_one counter 416s test_counter_one 1 416s # HELP test_counter_two A test counter 416s # TYPE test_counter_two counter 416s test_counter_two 2 416s 416s END /res/prometheus/ - metric_values Time: <1ms Result: PASS 416s START /res/res_crypto/ - crypto_aes_decrypt 416s [test_crypto.c:crypto_aes_decrypt:607]: Executing AES-ECB decryption test 416s END /res/res_crypto/ - crypto_aes_decrypt Time: 21ms Result: PASS 416s START /res/res_crypto/ - crypto_aes_encrypt 416s [test_crypto.c:crypto_aes_encrypt:531]: Executing AES-ECB encryption test 416s END /res/res_crypto/ - crypto_aes_encrypt Time: 15ms Result: PASS 416s START /res/res_crypto/ - crypto_verify 416s [test_crypto.c:crypto_verify:425]: Executing RSA signature verification test 416s [test_crypto.c:crypto_verify:456]: Couldn't read key: rsa_key1 416s END /res/res_crypto/ - crypto_verify Time: <1ms Result: FAIL 416s START /res/res_crypto/ - crypto_sign 416s [test_crypto.c:crypto_sign:306]: Executing RSA signing test 416s [test_crypto.c:crypto_sign:341]: Couldn't read key: rsa_key1 416s END /res/res_crypto/ - crypto_sign Time: <1ms Result: FAIL 416s START /res/res_crypto/ - crypto_decrypt_pub_key 416s [test_crypto.c:crypto_rsa_decrypt:203]: Executing RSA decryption test 416s [test_crypto.c:crypto_rsa_decrypt:231]: Couldn't read key: rsa_key1 416s END /res/res_crypto/ - crypto_decrypt_pub_key Time: <1ms Result: FAIL 416s START /res/res_crypto/ - crypto_rsa_encrypt 416s [test_crypto.c:crypto_rsa_encrypt:107]: Executing RSA encryption test 416s [test_crypto.c:crypto_rsa_encrypt:138]: Couldn't read key: rsa_key1 416s END /res/res_crypto/ - crypto_rsa_encrypt Time: <1ms Result: FAIL 416s START /res/res_pjproject/ - ast_sockaddr_from_pj_sockaddr_test 416s END /res/res_pjproject/ - ast_sockaddr_from_pj_sockaddr_test Time: <1ms Result: PASS 416s START /res/res_pjproject/ - ast_sockaddr_to_pj_sockaddr_test 416s END /res/res_pjproject/ - ast_sockaddr_to_pj_sockaddr_test Time: <1ms Result: PASS 416s START /res/res_pjsip/ - xml_sanitization_exceeds_buffer 416s END /res/res_pjsip/ - xml_sanitization_exceeds_buffer Time: <1ms Result: PASS 416s START /res/res_pjsip/ - xml_sanitization_end_null 416s END /res/res_pjsip/ - xml_sanitization_end_null Time: <1ms Result: PASS 416s START /res/res_pjsip/scheduler/ - scheduler_policy 416s [test_res_pjsip_scheduler.c:scheduler_policy:355]: This test will take about 4.5 seconds 420s END /res/res_pjsip/scheduler/ - scheduler_policy Time: 4498ms Result: PASS 420s START /res/res_pjsip/scheduler/ - scheduler_cancel 420s [test_res_pjsip_scheduler.c:scheduler_cancel:301]: This test will take about 1.5 seconds 422s END /res/res_pjsip/scheduler/ - scheduler_cancel Time: 1500ms Result: PASS 422s START /res/res_pjsip/scheduler/ - scheduler_cleanup 422s [test_res_pjsip_scheduler.c:scheduler_cleanup:250]: This test will take about 1.6 seconds 423s END /res/res_pjsip/scheduler/ - scheduler_cleanup Time: 1600ms Result: PASS 423s START /res/res_pjsip/scheduler/ - unserialized_scheduler 423s [test_res_pjsip_scheduler.c:scheduler:136]: This test will take about 3.0 seconds 426s END /res/res_pjsip/scheduler/ - unserialized_scheduler Time: 2999ms Result: PASS 426s START /res/res_pjsip/scheduler/ - serialized_scheduler 426s [test_res_pjsip_scheduler.c:scheduler:131]: This test will take about 4.0 seconds 430s END /res/res_pjsip/scheduler/ - serialized_scheduler Time: 3999ms Result: PASS 430s START /res/res_pjsip_pubsub/ - bad_event 430s END /res/res_pjsip_pubsub/ - bad_event Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - loop 430s END /res/res_pjsip_pubsub/ - loop Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - duplicate_resource 430s END /res/res_pjsip_pubsub/ - duplicate_resource Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - bad_branch 430s END /res/res_pjsip_pubsub/ - bad_branch Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - bad_resource 430s END /res/res_pjsip_pubsub/ - bad_resource Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - complex_resource_tree 430s END /res/res_pjsip_pubsub/ - complex_resource_tree Time: <1ms Result: PASS 430s START /res/res_pjsip_pubsub/ - resource_tree 430s END /res/res_pjsip_pubsub/ - resource_tree Time: <1ms Result: PASS 430s START /res/res_pjsip_session/ - merge_refresh_topologies 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5792]: Test 1 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5812]: Test 2 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5836]: Test 3 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5855]: Test 4 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5873]: Test 5 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5894]: Test 6 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5920]: Test 7 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5941]: Test 8 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5961]: Test 9 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:5981]: Test 10 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:6004]: Test 11 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:6029]: Test 12 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:6063]: Test 13 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:6087]: Test 14 passed 430s [res_pjsip_session.c:test_resolve_refresh_media_states:6111]: Test 15 passed 430s END /res/res_pjsip_session/ - merge_refresh_topologies Time: <1ms Result: PASS 430s START /res/res_pjsip_session/caps/ - low_level 430s [test_res_pjsip_session_caps.c:low_level:134]: Testing incoming expected pass 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (local ), outgoing: (no ), expected: (alaw,g722) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (local_first ), outgoing: (no ), expected: (alaw) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (slin), remote: (all), pref: (local ), outgoing: (no ), expected: (slin) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (remote ), outgoing: (no ), expected: (g722,alaw) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (remote_first), outgoing: (no ), expected: (g722) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (all), remote: (slin), pref: (remote_first), outgoing: (no ), expected: (slin) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:low_level:142]: Testing incoming expected fail 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g729), pref: (local ), outgoing: (no ), expected: () expected result: (FAIL) 430s [test_res_pjsip_session_caps.c:test_create_joint:102]: Expected Failure: Expected: () Actual: (nothing) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (local_merge ), outgoing: (no ), expected: () expected result: (FAIL) 430s [test_res_pjsip_session_caps.c:test_create_joint:81]: Expected Failure: Invalid preference string incoming/outgoing combination. 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,alaw,g729), pref: (remote_merge), outgoing: (no ), expected: () expected result: (FAIL) 430s [test_res_pjsip_session_caps.c:test_create_joint:81]: Expected Failure: Invalid preference string incoming/outgoing combination. 430s [test_res_pjsip_session_caps.c:low_level:147]: Testing outgoing expected pass 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (local ), outgoing: (yes), expected: (alaw,g722) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (local_first ), outgoing: (yes), expected: (alaw) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (local_merge ), outgoing: (yes), expected: (ulaw,alaw,g722) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (remote ), outgoing: (yes), expected: (g722,alaw) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (remote_first), outgoing: (yes), expected: (g722) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (ulaw,alaw,g722), remote: (g722,g729,alaw), pref: (remote_merge), outgoing: (yes), expected: (g722,alaw,ulaw) expected result: (PASS) 430s [test_res_pjsip_session_caps.c:test_create_joint:60]: Testing local: (!all), remote: (g722,g729,alaw), pref: (remote_merge), outgoing: (yes), expected: (nothing) expected result: (PASS) 430s END /res/res_pjsip_session/caps/ - low_level Time: 1ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_srv 430s END /res/res_resolver_unbound/ - resolve_srv Time: 6ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_naptr 430s END /res/res_resolver_unbound/ - resolve_naptr Time: <1ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_cancel_off_nominal 430s END /res/res_resolver_unbound/ - resolve_cancel_off_nominal Time: 87ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_async_off_nominal 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query 'duck.feathers', type 1 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query 'goose.feathers', type 28 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query 'goose.feathers', type 1 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query '.1', type 1 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query '.www', type 1 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query '.1', type 12 430s [res_resolver_unbound.c:off_nominal_async_run:1022]: Performing DNS query '.www', type 12 430s END /res/res_resolver_unbound/ - resolve_async_off_nominal Time: 1ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_sync_off_nominal 430s END /res/res_resolver_unbound/ - resolve_sync_off_nominal Time: <1ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_async 430s [res_resolver_unbound.c:nominal_async_run:739]: Performing DNS query 'goose.feathers', type 1 430s [res_resolver_unbound.c:nominal_async_run:739]: Performing DNS query 'goose.feathers', type 28 430s [res_resolver_unbound.c:nominal_async_run:739]: Performing DNS query 'duck.feathers', type 1 430s END /res/res_resolver_unbound/ - resolve_async Time: <1ms Result: PASS 430s START /res/res_resolver_unbound/ - resolve_sync 430s [res_resolver_unbound.c:nominal_sync_run:589]: Performing DNS query 'goose.feathers', type 1 430s [res_resolver_unbound.c:nominal_sync_run:589]: Performing DNS query 'goose.feathers', type 28 430s [res_resolver_unbound.c:nominal_sync_run:589]: Performing DNS query 'duck.feathers', type 1 430s END /res/res_resolver_unbound/ - resolve_sync Time: <1ms Result: PASS 430s START /res/res_rtp/ - fir_nominal 430s END /res/res_rtp/ - fir_nominal Time: 3ms Result: PASS 430s START /res/res_rtp/ - sr_rr_nominal 430s END /res/res_rtp/ - sr_rr_nominal Time: 1ms Result: PASS 430s START /res/res_rtp/ - remb_nominal 431s END /res/res_rtp/ - remb_nominal Time: 1ms Result: PASS 431s START /res/res_rtp/ - lost_packet_stats_nominal 431s END /res/res_rtp/ - lost_packet_stats_nominal Time: 1ms Result: PASS 431s START /res/res_rtp/ - nack_overflow 431s END /res/res_rtp/ - nack_overflow Time: 1ms Result: PASS 431s START /res/res_rtp/ - nack_nominal 431s END /res/res_rtp/ - nack_nominal Time: 1ms Result: PASS 431s START /res/res_rtp/ - nack_no_packet_loss 431s END /res/res_rtp/ - nack_no_packet_loss Time: 1ms Result: PASS 431s START /res/res_sorcery_memory_cache/ - full_backend_cache_stale 446s END /res/res_sorcery_memory_cache/ - full_backend_cache_stale Time: 15000ms Result: PASS 446s START /res/res_sorcery_memory_cache/ - full_backend_cache_expiration 451s END /res/res_sorcery_memory_cache/ - full_backend_cache_expiration Time: 5000ms Result: PASS 451s START /res/res_sorcery_memory_cache/ - expiration 455s END /res/res_sorcery_memory_cache/ - expiration Time: 4999ms Result: PASS 455s START /res/res_sorcery_memory_cache/ - maximum_objects 456s END /res/res_sorcery_memory_cache/ - maximum_objects Time: 4ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - delete 456s END /res/res_sorcery_memory_cache/ - delete Time: <1ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - create 456s END /res/res_sorcery_memory_cache/ - create Time: <1ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - create 456s END /res/res_sorcery_memory_cache/ - create Time: <1ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - open_with_invalid_options 456s END /res/res_sorcery_memory_cache/ - open_with_invalid_options Time: <1ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - open_with_valid_options 456s END /res/res_sorcery_memory_cache/ - open_with_valid_options Time: <1ms Result: PASS 456s START /res/res_sorcery_memory_cache/ - stale 456s [res_sorcery_memory_cache.c:stale:3352]: Begininning iteration 0 461s [res_sorcery_memory_cache.c:stale:3352]: Begininning iteration 1 466s [res_sorcery_memory_cache.c:stale:3352]: Begininning iteration 2 471s END /res/res_sorcery_memory_cache/ - stale Time: 15004ms Result: PASS 471s START /res/res_sorcery_memory_cache/thrash/ - high_object_count_without_expiration 474s END /res/res_sorcery_memory_cache/thrash/ - high_object_count_without_expiration Time: 3009ms Result: PASS 474s START /res/res_sorcery_memory_cache/thrash/ - conflicting_expire_and_stale 480s END /res/res_sorcery_memory_cache/thrash/ - conflicting_expire_and_stale Time: 6008ms Result: PASS 480s START /res/res_sorcery_memory_cache/thrash/ - unique_objects_exceeding_maximum_with_expire_and_stale 486s END /res/res_sorcery_memory_cache/thrash/ - unique_objects_exceeding_maximum_with_expire_and_stale Time: 6009ms Result: PASS 486s START /res/res_sorcery_memory_cache/thrash/ - unique_objects_exceeding_maximum 489s END /res/res_sorcery_memory_cache/thrash/ - unique_objects_exceeding_maximum Time: 3012ms Result: PASS 489s START /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_high_concurrent_updates 492s END /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_high_concurrent_updates Time: 3005ms Result: PASS 492s START /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_immediately_expire 495s END /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_immediately_expire Time: 3007ms Result: PASS 495s START /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_immediately_stale 498s END /res/res_sorcery_memory_cache/thrash/ - low_unique_object_count_immediately_stale Time: 3007ms Result: PASS 498s START /res/sorcery_astdb/ - object_delete_uncreated 498s END /res/sorcery_astdb/ - object_delete_uncreated Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_delete 498s END /res/sorcery_astdb/ - object_delete Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_update_uncreated 498s END /res/sorcery_astdb/ - object_update_uncreated Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_update 498s END /res/sorcery_astdb/ - object_update Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_retrieve_regex 498s END /res/sorcery_astdb/ - object_retrieve_regex Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_retrieve_multiple_field 498s END /res/sorcery_astdb/ - object_retrieve_multiple_field Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_retrieve_multiple_all 498s END /res/sorcery_astdb/ - object_retrieve_multiple_all Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_retrieve_field 498s END /res/sorcery_astdb/ - object_retrieve_field Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_retrieve_id 498s END /res/sorcery_astdb/ - object_retrieve_id Time: <1ms Result: PASS 498s START /res/sorcery_astdb/ - object_create 498s END /res/sorcery_astdb/ - object_create Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_filter 498s END /res/sorcery_realtime/ - object_filter Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_allocate_on_retrieval 498s END /res/sorcery_realtime/ - object_allocate_on_retrieval Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_delete_uncreated 498s END /res/sorcery_realtime/ - object_delete_uncreated Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_delete 498s END /res/sorcery_realtime/ - object_delete Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_update 498s END /res/sorcery_realtime/ - object_update Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_regex_nofetch 498s END /res/sorcery_realtime/ - object_retrieve_regex_nofetch Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_regex 498s END /res/sorcery_realtime/ - object_retrieve_regex Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_multiple_field 498s END /res/sorcery_realtime/ - object_retrieve_multiple_field Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_multiple_all_nofetch 498s END /res/sorcery_realtime/ - object_retrieve_multiple_all_nofetch Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_multiple_all 498s END /res/sorcery_realtime/ - object_retrieve_multiple_all Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_field 498s END /res/sorcery_realtime/ - object_retrieve_field Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_retrieve_id 498s END /res/sorcery_realtime/ - object_retrieve_id Time: <1ms Result: PASS 498s START /res/sorcery_realtime/ - object_create 498s END /res/sorcery_realtime/ - object_create Time: <1ms Result: PASS 498s START /res/websocket/ - websocket_client_multiple_protocols 498s END /res/websocket/ - websocket_client_multiple_protocols Time: 1ms Result: PASS 498s START /res/websocket/ - websocket_client_unsupported_protocol 498s END /res/websocket/ - websocket_client_unsupported_protocol Time: <1ms Result: PASS 498s START /res/websocket/ - websocket_client_bad_url 498s END /res/websocket/ - websocket_client_bad_url Time: 1ms Result: PASS 498s START /stasis/channels/ - channel_redirect_snapshot_json 498s END /stasis/channels/ - channel_redirect_snapshot_json Time: <1ms Result: PASS 498s START /stasis/channels/ - channel_snapshot_json 498s END /stasis/channels/ - channel_snapshot_json Time: <1ms Result: PASS 498s START /stasis/channels/ - multi_channel_blob_snapshots 498s END /stasis/channels/ - multi_channel_blob_snapshots Time: <1ms Result: PASS 498s START /stasis/channels/ - multi_channel_blob_create 498s END /stasis/channels/ - multi_channel_blob_create Time: <1ms Result: PASS 498s START /stasis/channels/ - null_blob 498s END /stasis/channels/ - null_blob Time: <1ms Result: PASS 498s START /stasis/channels/ - channel_blob_create 498s END /stasis/channels/ - channel_blob_create Time: <1ms Result: PASS 498s START /stasis/core/ - caching_dtor_order 498s END /stasis/core/ - caching_dtor_order Time: <1ms Result: PASS 498s START /stasis/core/ - dtor_order 498s END /stasis/core/ - dtor_order Time: <1ms Result: PASS 498s START /stasis/core/ - to_ami 498s END /stasis/core/ - to_ami Time: <1ms Result: PASS 498s START /stasis/core/ - no_to_ami 498s END /stasis/core/ - no_to_ami Time: <1ms Result: PASS 498s START /stasis/core/ - to_json 498s END /stasis/core/ - to_json Time: <1ms Result: PASS 498s START /stasis/core/ - no_to_json 498s END /stasis/core/ - no_to_json Time: <1ms Result: PASS 498s START /stasis/core/ - subscription_interleaving 498s END /stasis/core/ - subscription_interleaving Time: <1ms Result: PASS 498s START /stasis/core/ - interleaving 498s END /stasis/core/ - interleaving Time: <1ms Result: PASS 498s START /stasis/core/ - router_cache_updates 498s END /stasis/core/ - router_cache_updates Time: 100ms Result: PASS 498s START /stasis/core/ - router_pool 498s END /stasis/core/ - router_pool Time: <1ms Result: PASS 498s START /stasis/core/ - router 498s END /stasis/core/ - router Time: <1ms Result: PASS 498s START /stasis/core/ - cache_eid_aggregate 498s END /stasis/core/ - cache_eid_aggregate Time: <1ms Result: PASS 498s START /stasis/core/ - cache_dump 498s END /stasis/core/ - cache_dump Time: <1ms Result: PASS 498s START /stasis/core/ - cache 498s END /stasis/core/ - cache Time: <1ms Result: PASS 498s START /stasis/core/ - cache_filter 498s END /stasis/core/ - cache_filter Time: 100ms Result: PASS 498s START /stasis/core/ - forward 498s END /stasis/core/ - forward Time: <1ms Result: PASS 498s START /stasis/core/ - unsubscribe_stops_messages 498s END /stasis/core/ - unsubscribe_stops_messages Time: 100ms Result: PASS 498s START /stasis/core/ - publish_pool 498s END /stasis/core/ - publish_pool Time: <1ms Result: PASS 498s START /stasis/core/ - publish_sync 498s END /stasis/core/ - publish_sync Time: <1ms Result: PASS 498s START /stasis/core/ - publish 498s END /stasis/core/ - publish Time: <1ms Result: PASS 498s START /stasis/core/ - subscription_pool_messages 498s END /stasis/core/ - subscription_pool_messages Time: <1ms Result: PASS 498s START /stasis/core/ - subscription_messages 498s END /stasis/core/ - subscription_messages Time: <1ms Result: PASS 498s START /stasis/core/ - message 498s END /stasis/core/ - message Time: <1ms Result: PASS 498s START /stasis/core/ - message_type 498s END /stasis/core/ - message_type Time: <1ms Result: PASS 498s START /stasis/core/filtering/ - combo_filters 498s [test_stasis.c:dump_consumer:2198]: Messages received: 7 Final? yes 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Subscribe 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageType1 Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageType2 Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageAMI Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageAMIEVENT Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageJSON Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Unsubscribe 498s END /stasis/core/filtering/ - combo_filters Time: <1ms Result: PASS 498s START /stasis/core/filtering/ - formatter_filters 498s [test_stasis.c:dump_consumer:2198]: Messages received: 7 Final? yes 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Subscribe 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageAMI Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageJSON Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageAMIEVENT Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageAMIEVENT Pass2 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageEVENT Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Unsubscribe 498s END /stasis/core/filtering/ - formatter_filters Time: <1ms Result: PASS 498s START /stasis/core/filtering/ - type_filters 498s [test_stasis.c:dump_consumer:2198]: Messages received: 5 Final? yes 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Subscribe 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageType1 Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageType2 Pass 498s [test_stasis.c:dump_consumer:2202]: Message type received: TestMessageType2 Pass2 498s [test_stasis.c:dump_consumer:2202]: Message type received: stasis_subscription_change_type Unsubscribe 498s END /stasis/core/filtering/ - type_filters Time: <1ms Result: PASS 498s START /stasis/core/state/ - explicit_publish 498s END /stasis/core/state/ - explicit_publish Time: 32ms Result: PASS 498s START /stasis/core/state/ - implicit_publish 498s END /stasis/core/state/ - implicit_publish Time: 28ms Result: PASS 498s START /stasis/endpoints/ - channel_messages 498s END /stasis/endpoints/ - channel_messages Time: <1ms Result: PASS 498s START /stasis/endpoints/ - cache_clear 498s END /stasis/endpoints/ - cache_clear Time: <1ms Result: PASS 498s START /stasis/endpoints/ - state_changes 498s END /stasis/endpoints/ - state_changes Time: <1ms Result: PASS 498s START /stasis/res/ - app_invoke_dne 498s END /stasis/res/ - app_invoke_dne Time: <1ms Result: PASS 498s START /stasis/res/ - app_invoke_one 498s END /stasis/res/ - app_invoke_one Time: <1ms Result: PASS 498s START /stasis/res/ - app_replaced 498s END /stasis/res/ - app_replaced Time: <1ms Result: PASS 498s 498s 671 Test(s) Executed 666 Passed 5 Failed 498s 'U' option is not compatible with remote console mode and has no effect. 498s 498s Results Generated Successfully: /tmp/autopkgtest.BnH9nW/build.B8I/src/debian/tests/testmods/output/results.txt 498s 'U' option is not compatible with remote console mode and has no effect. 498s 498s Some test modules were not loaded: 498s 20a21 498s > test_cel.so 498s 53a55 498s > test_message.so 498s Manually disabled: 498s test_message 498s test_cel 498s 498s 'U' option is not compatible with remote console mode and has no effect. 498s 500s autopkgtest [18:18:46]: test asttestmods: -----------------------] 503s asttestmods PASS 503s autopkgtest [18:18:49]: test asttestmods: - - - - - - - - - - results - - - - - - - - - - 507s autopkgtest [18:18:53]: test amr: preparing testbed 530s autopkgtest [18:19:16]: testbed dpkg architecture: armhf 532s autopkgtest [18:19:18]: testbed apt version: 3.1.15 536s autopkgtest [18:19:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 538s autopkgtest [18:19:24]: testbed release detected to be: resolute 545s autopkgtest [18:19:31]: updating testbed package index (apt update) 547s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 548s Get:2 http://ftpmaster.internal/ubuntu resolute InRelease [124 kB] 548s Get:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease [124 kB] 548s Get:4 http://ftpmaster.internal/ubuntu resolute-security InRelease [124 kB] 548s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [1764 B] 548s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [174 kB] 548s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [29.4 kB] 548s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1630 kB] 548s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [245 kB] 548s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [1403 kB] 548s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [7452 B] 548s Get:12 http://ftpmaster.internal/ubuntu resolute/main Sources [1412 kB] 548s Get:13 http://ftpmaster.internal/ubuntu resolute/universe Sources [21.3 MB] 549s Get:14 http://ftpmaster.internal/ubuntu resolute/restricted Sources [15.1 kB] 549s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf Packages [1374 kB] 549s Get:16 http://ftpmaster.internal/ubuntu resolute/universe armhf Packages [15.1 MB] 552s Fetched 43.2 MB in 5s (8646 kB/s) 553s Reading package lists... 559s autopkgtest [18:19:45]: upgrading testbed (apt dist-upgrade and autopurge) 561s Reading package lists... 561s Building dependency tree... 561s Reading state information... 561s Calculating upgrade... 562s The following packages will be upgraded: 562s cryptsetup-bin dracut-install iproute2 iptables libcryptsetup12 libip4tc2 562s libip6tc2 libxtables12 wget 562s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 562s Need to get 2407 kB of archives. 562s After this operation, 152 kB of additional disk space will be used. 562s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf iptables armhf 1.8.11-2ubuntu3 [345 kB] 563s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf libip4tc2 armhf 1.8.11-2ubuntu3 [22.0 kB] 563s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf libip6tc2 armhf 1.8.11-2ubuntu3 [22.3 kB] 563s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf libxtables12 armhf 1.8.11-2ubuntu3 [33.5 kB] 563s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf iproute2 armhf 6.18.0-1ubuntu1 [1123 kB] 564s Get:6 http://ftpmaster.internal/ubuntu resolute/main armhf libcryptsetup12 armhf 2:2.8.0-1ubuntu3 [254 kB] 564s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf wget armhf 1.25.0-2ubuntu4 [327 kB] 564s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf cryptsetup-bin armhf 2:2.8.0-1ubuntu3 [232 kB] 564s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf dracut-install armhf 109-11ubuntu1 [47.9 kB] 564s Preconfiguring packages ... 565s Fetched 2407 kB in 2s (1161 kB/s) 565s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 68683 files and directories currently installed.) 565s Preparing to unpack .../0-iptables_1.8.11-2ubuntu3_armhf.deb ... 565s Unpacking iptables (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 565s Preparing to unpack .../1-libip4tc2_1.8.11-2ubuntu3_armhf.deb ... 565s Unpacking libip4tc2:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 565s Preparing to unpack .../2-libip6tc2_1.8.11-2ubuntu3_armhf.deb ... 565s Unpacking libip6tc2:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 565s Preparing to unpack .../3-libxtables12_1.8.11-2ubuntu3_armhf.deb ... 565s Unpacking libxtables12:armhf (1.8.11-2ubuntu3) over (1.8.11-2ubuntu2) ... 565s Preparing to unpack .../4-iproute2_6.18.0-1ubuntu1_armhf.deb ... 565s Unpacking iproute2 (6.18.0-1ubuntu1) over (6.16.0-1ubuntu3) ... 565s Preparing to unpack .../5-libcryptsetup12_2%3a2.8.0-1ubuntu3_armhf.deb ... 565s Unpacking libcryptsetup12:armhf (2:2.8.0-1ubuntu3) over (2:2.8.0-1ubuntu2) ... 565s Preparing to unpack .../6-wget_1.25.0-2ubuntu4_armhf.deb ... 565s Unpacking wget (1.25.0-2ubuntu4) over (1.25.0-2ubuntu3) ... 565s Preparing to unpack .../7-cryptsetup-bin_2%3a2.8.0-1ubuntu3_armhf.deb ... 565s Unpacking cryptsetup-bin (2:2.8.0-1ubuntu3) over (2:2.8.0-1ubuntu2) ... 565s Preparing to unpack .../8-dracut-install_109-11ubuntu1_armhf.deb ... 565s Unpacking dracut-install (109-11ubuntu1) over (109-9ubuntu1) ... 565s Setting up libip4tc2:armhf (1.8.11-2ubuntu3) ... 565s Setting up wget (1.25.0-2ubuntu4) ... 565s Setting up libip6tc2:armhf (1.8.11-2ubuntu3) ... 565s Setting up libxtables12:armhf (1.8.11-2ubuntu3) ... 565s Setting up dracut-install (109-11ubuntu1) ... 565s Setting up libcryptsetup12:armhf (2:2.8.0-1ubuntu3) ... 565s Setting up cryptsetup-bin (2:2.8.0-1ubuntu3) ... 566s Setting up iptables (1.8.11-2ubuntu3) ... 566s Setting up iproute2 (6.18.0-1ubuntu1) ... 566s Processing triggers for man-db (2.13.1-1build1) ... 567s Processing triggers for install-info (7.2-5) ... 567s Processing triggers for libc-bin (2.42-2ubuntu4) ... 569s Reading package lists... 570s Building dependency tree... 570s Reading state information... 570s Solving dependencies... 570s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 572s autopkgtest [18:19:58]: rebooting testbed after setup commands that affected boot 637s Reading package lists... 637s Building dependency tree... 637s Reading state information... 637s Solving dependencies... 638s The following NEW packages will be installed: 638s asterisk asterisk-config asterisk-core-sounds-en asterisk-core-sounds-en-gsm 638s asterisk-modules freetds-common libasound2-data libasound2t64 libasyncns0 638s libb64-0d libcodec2-1.2 libdeflate0 libevent-2.1-7t64 libflac14 638s libgmime-3.0-0t64 libgsm1 libical3t64 libiksemel3 libjack-jackd2-0 libjbig0 638s libjpeg-turbo8 libjpeg8 libjwt2 liblerc4 libltdl7 liblua5.1-0 libmp3lame0 638s libmpg123-0t64 libneon27t64 libodbc2 libogg0 libopencore-amrnb0 638s libopencore-amrwb0 libopus0 libopusenc0 libopusfile0 libportaudio2 libpq5 638s libpulse0 libradcli4 libresample1 libsamplerate0 libsharpyuv0 libsndfile1 638s libsnmp-base libsnmp40t64 libspandsp2t64 libspeex1 libspeexdsp1 libsrtp2-1 638s libsybdb5 libtiff6 libunbound8 liburiparser1 libvo-amrwbenc0 libvorbis0a 638s libvorbisenc2 libvorbisfile3 libwebp7 libx11-xcb1 libxslt1.1 638s 0 upgraded, 61 newly installed, 0 to remove and 0 not upgraded. 638s Need to get 27.8 MB of archives. 638s After this operation, 78.3 MB of additional disk space will be used. 638s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk-config all 1:22.8.2+dfsg+~cs6.15.60671435-1 [228 kB] 638s Get:2 http://ftpmaster.internal/ubuntu resolute/universe armhf asterisk-core-sounds-en-gsm all 1.6.1-1build1 [2436 kB] 639s Get:3 http://ftpmaster.internal/ubuntu resolute/universe armhf asterisk-core-sounds-en all 1.6.1-1build1 [21.2 kB] 639s Get:4 http://ftpmaster.internal/ubuntu resolute/universe armhf libcodec2-1.2 armhf 1.2.0-4 [8252 kB] 641s Get:5 http://ftpmaster.internal/ubuntu resolute/universe armhf libgmime-3.0-0t64 armhf 3.2.15+dfsg-1build1 [150 kB] 641s Get:6 http://ftpmaster.internal/ubuntu resolute/universe armhf libgsm1 armhf 1.0.23-2 [25.0 kB] 641s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf libical3t64 armhf 3.0.20-2build1 [270 kB] 641s Get:8 http://ftpmaster.internal/ubuntu resolute/universe armhf libiksemel3 armhf 1.4-4build3 [22.2 kB] 641s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf libopus0 armhf 1.6.1-1 [3510 kB] 641s Get:10 http://ftpmaster.internal/ubuntu resolute/main armhf libsamplerate0 armhf 0.2.2-4build2 [1340 kB] 642s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libjack-jackd2-0 armhf 1.9.22~dfsg-5build1 [246 kB] 642s Get:12 http://ftpmaster.internal/ubuntu resolute/universe armhf libb64-0d armhf 1.2-5build2 [9096 B] 642s Get:13 http://ftpmaster.internal/ubuntu resolute/universe armhf libjwt2 armhf 1.17.2-1build1 [15.1 kB] 642s Get:14 http://ftpmaster.internal/ubuntu resolute/universe armhf liblua5.1-0 armhf 5.1.5-11build1 [92.6 kB] 642s Get:15 http://ftpmaster.internal/ubuntu resolute/universe armhf libneon27t64 armhf 0.36.0-1 [93.5 kB] 642s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf libltdl7 armhf 2.5.4-9 [40.4 kB] 642s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libodbc2 armhf 2.3.14-1 [147 kB] 642s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libogg0 armhf 1.3.6-2 [20.6 kB] 642s Get:19 http://ftpmaster.internal/ubuntu resolute/universe armhf libopencore-amrnb0 armhf 0.1.6-1build2 [88.8 kB] 642s Get:20 http://ftpmaster.internal/ubuntu resolute/universe armhf libopencore-amrwb0 armhf 0.1.6-1build2 [48.8 kB] 642s Get:21 http://ftpmaster.internal/ubuntu resolute/universe armhf libopusenc0 armhf 0.3-1 [24.2 kB] 642s Get:22 http://ftpmaster.internal/ubuntu resolute/universe armhf libopusfile0 armhf 0.12-4build4 [42.0 kB] 642s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2-data all 1.2.15.3-1ubuntu1 [21.4 kB] 642s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf libasound2t64 armhf 1.2.15.3-1ubuntu1 [362 kB] 642s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf libasyncns0 armhf 0.8-7 [10.8 kB] 642s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libflac14 armhf 1.5.0+ds-5 [136 kB] 642s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf libmp3lame0 armhf 3.100-6build2 [126 kB] 642s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf libmpg123-0t64 armhf 1.33.3-2 [160 kB] 642s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbis0a armhf 1.3.7-3build1 [86.6 kB] 642s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbisenc2 armhf 1.3.7-3build1 [72.7 kB] 642s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf libsndfile1 armhf 1.2.2-4 [201 kB] 642s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf libx11-xcb1 armhf 2:1.8.12-1build1 [7920 B] 642s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libpulse0 armhf 1:17.0+dfsg1-2ubuntu4 [280 kB] 642s Get:34 http://ftpmaster.internal/ubuntu resolute/universe armhf libportaudio2 armhf 19.7.0+git20260206.e1b70d33-0ubuntu1 [70.2 kB] 642s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf libpq5 armhf 18.1-2 [137 kB] 642s Get:36 http://ftpmaster.internal/ubuntu resolute/universe armhf libradcli4 armhf 1.2.11-1build4 [37.7 kB] 642s Get:37 http://ftpmaster.internal/ubuntu resolute/universe armhf libresample1 armhf 0.1.3-8build1 [7906 B] 642s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf libsnmp-base all 5.9.4+dfsg-2ubuntu3 [207 kB] 642s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf libsnmp40t64 armhf 5.9.4+dfsg-2ubuntu3 [1026 kB] 642s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf libdeflate0 armhf 1.23-2build1 [38.8 kB] 642s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf libjbig0 armhf 2.1-6.1ubuntu3 [25.3 kB] 642s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf libjpeg-turbo8 armhf 2.1.5-4ubuntu3 [129 kB] 642s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf libjpeg8 armhf 8c-2ubuntu11 [2148 B] 642s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf liblerc4 armhf 4.0.0+ds-5ubuntu2 [162 kB] 642s Get:45 http://ftpmaster.internal/ubuntu resolute/main armhf libsharpyuv0 armhf 1.5.0-0.1build1 [16.3 kB] 642s Get:46 http://ftpmaster.internal/ubuntu resolute/main armhf libwebp7 armhf 1.5.0-0.1build1 [189 kB] 642s Get:47 http://ftpmaster.internal/ubuntu resolute/main armhf libtiff6 armhf 4.7.0-3ubuntu3 [188 kB] 642s Get:48 http://ftpmaster.internal/ubuntu resolute/universe armhf libspandsp2t64 armhf 0.0.6+dfsg-2.2build1 [268 kB] 642s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf libspeex1 armhf 1.2.1-3build1 [49.3 kB] 642s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf libspeexdsp1 armhf 1.2.1-3build1 [37.1 kB] 642s Get:51 http://ftpmaster.internal/ubuntu resolute/universe armhf libsrtp2-1 armhf 2.7.0-3build1 [35.8 kB] 642s Get:52 http://ftpmaster.internal/ubuntu resolute/main armhf freetds-common all 1.5.5+ds-1 [29.1 kB] 642s Get:53 http://ftpmaster.internal/ubuntu resolute/main armhf libsybdb5 armhf 1.5.5+ds-1 [185 kB] 642s Get:54 http://ftpmaster.internal/ubuntu resolute/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10build2 [130 kB] 642s Get:55 http://ftpmaster.internal/ubuntu resolute/main armhf libunbound8 armhf 1.24.2-1ubuntu1 [433 kB] 642s Get:56 http://ftpmaster.internal/ubuntu resolute/universe armhf libvo-amrwbenc0 armhf 0.1.3-2build2 [66.4 kB] 642s Get:57 http://ftpmaster.internal/ubuntu resolute/main armhf libvorbisfile3 armhf 1.3.7-3build1 [16.4 kB] 642s Get:58 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk-modules armhf 1:22.8.2+dfsg+~cs6.15.60671435-1 [3024 kB] 642s Get:59 http://ftpmaster.internal/ubuntu resolute/universe armhf liburiparser1 armhf 0.9.8+dfsg-2 [30.3 kB] 642s Get:60 http://ftpmaster.internal/ubuntu resolute/main armhf libxslt1.1 armhf 1.1.43-0.3 [146 kB] 642s Get:61 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf asterisk armhf 1:22.8.2+dfsg+~cs6.15.60671435-1 [2537 kB] 643s Fetched 27.8 MB in 5s (5578 kB/s) 643s Selecting previously unselected package asterisk-config. 643s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 68686 files and directories currently installed.) 643s Preparing to unpack .../00-asterisk-config_1%3a22.8.2+dfsg+~cs6.15.60671435-1_all.deb ... 643s Unpacking asterisk-config (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 643s Selecting previously unselected package asterisk-core-sounds-en-gsm. 643s Preparing to unpack .../01-asterisk-core-sounds-en-gsm_1.6.1-1build1_all.deb ... 643s Unpacking asterisk-core-sounds-en-gsm (1.6.1-1build1) ... 643s Selecting previously unselected package asterisk-core-sounds-en. 643s Preparing to unpack .../02-asterisk-core-sounds-en_1.6.1-1build1_all.deb ... 643s Unpacking asterisk-core-sounds-en (1.6.1-1build1) ... 643s Selecting previously unselected package libcodec2-1.2:armhf. 643s Preparing to unpack .../03-libcodec2-1.2_1.2.0-4_armhf.deb ... 643s Unpacking libcodec2-1.2:armhf (1.2.0-4) ... 643s Selecting previously unselected package libgmime-3.0-0t64:armhf. 643s Preparing to unpack .../04-libgmime-3.0-0t64_3.2.15+dfsg-1build1_armhf.deb ... 643s Unpacking libgmime-3.0-0t64:armhf (3.2.15+dfsg-1build1) ... 643s Selecting previously unselected package libgsm1:armhf. 643s Preparing to unpack .../05-libgsm1_1.0.23-2_armhf.deb ... 643s Unpacking libgsm1:armhf (1.0.23-2) ... 643s Selecting previously unselected package libical3t64:armhf. 643s Preparing to unpack .../06-libical3t64_3.0.20-2build1_armhf.deb ... 643s Unpacking libical3t64:armhf (3.0.20-2build1) ... 643s Selecting previously unselected package libiksemel3:armhf. 643s Preparing to unpack .../07-libiksemel3_1.4-4build3_armhf.deb ... 643s Unpacking libiksemel3:armhf (1.4-4build3) ... 644s Selecting previously unselected package libopus0:armhf. 644s Preparing to unpack .../08-libopus0_1.6.1-1_armhf.deb ... 644s Unpacking libopus0:armhf (1.6.1-1) ... 644s Selecting previously unselected package libsamplerate0:armhf. 644s Preparing to unpack .../09-libsamplerate0_0.2.2-4build2_armhf.deb ... 644s Unpacking libsamplerate0:armhf (0.2.2-4build2) ... 644s Selecting previously unselected package libjack-jackd2-0:armhf. 644s Preparing to unpack .../10-libjack-jackd2-0_1.9.22~dfsg-5build1_armhf.deb ... 644s Unpacking libjack-jackd2-0:armhf (1.9.22~dfsg-5build1) ... 644s Selecting previously unselected package libb64-0d:armhf. 644s Preparing to unpack .../11-libb64-0d_1.2-5build2_armhf.deb ... 644s Unpacking libb64-0d:armhf (1.2-5build2) ... 644s Selecting previously unselected package libjwt2:armhf. 644s Preparing to unpack .../12-libjwt2_1.17.2-1build1_armhf.deb ... 644s Unpacking libjwt2:armhf (1.17.2-1build1) ... 644s Selecting previously unselected package liblua5.1-0:armhf. 644s Preparing to unpack .../13-liblua5.1-0_5.1.5-11build1_armhf.deb ... 644s Unpacking liblua5.1-0:armhf (5.1.5-11build1) ... 644s Selecting previously unselected package libneon27t64:armhf. 644s Preparing to unpack .../14-libneon27t64_0.36.0-1_armhf.deb ... 644s Unpacking libneon27t64:armhf (0.36.0-1) ... 644s Selecting previously unselected package libltdl7:armhf. 644s Preparing to unpack .../15-libltdl7_2.5.4-9_armhf.deb ... 644s Unpacking libltdl7:armhf (2.5.4-9) ... 644s Selecting previously unselected package libodbc2:armhf. 644s Preparing to unpack .../16-libodbc2_2.3.14-1_armhf.deb ... 644s Unpacking libodbc2:armhf (2.3.14-1) ... 644s Selecting previously unselected package libogg0:armhf. 644s Preparing to unpack .../17-libogg0_1.3.6-2_armhf.deb ... 644s Unpacking libogg0:armhf (1.3.6-2) ... 644s Selecting previously unselected package libopencore-amrnb0:armhf. 644s Preparing to unpack .../18-libopencore-amrnb0_0.1.6-1build2_armhf.deb ... 644s Unpacking libopencore-amrnb0:armhf (0.1.6-1build2) ... 644s Selecting previously unselected package libopencore-amrwb0:armhf. 644s Preparing to unpack .../19-libopencore-amrwb0_0.1.6-1build2_armhf.deb ... 644s Unpacking libopencore-amrwb0:armhf (0.1.6-1build2) ... 644s Selecting previously unselected package libopusenc0:armhf. 644s Preparing to unpack .../20-libopusenc0_0.3-1_armhf.deb ... 644s Unpacking libopusenc0:armhf (0.3-1) ... 644s Selecting previously unselected package libopusfile0:armhf. 644s Preparing to unpack .../21-libopusfile0_0.12-4build4_armhf.deb ... 644s Unpacking libopusfile0:armhf (0.12-4build4) ... 644s Selecting previously unselected package libasound2-data. 644s Preparing to unpack .../22-libasound2-data_1.2.15.3-1ubuntu1_all.deb ... 644s Unpacking libasound2-data (1.2.15.3-1ubuntu1) ... 644s Selecting previously unselected package libasound2t64:armhf. 644s Preparing to unpack .../23-libasound2t64_1.2.15.3-1ubuntu1_armhf.deb ... 644s Unpacking libasound2t64:armhf (1.2.15.3-1ubuntu1) ... 644s Selecting previously unselected package libasyncns0:armhf. 644s Preparing to unpack .../24-libasyncns0_0.8-7_armhf.deb ... 644s Unpacking libasyncns0:armhf (0.8-7) ... 644s Selecting previously unselected package libflac14:armhf. 644s Preparing to unpack .../25-libflac14_1.5.0+ds-5_armhf.deb ... 644s Unpacking libflac14:armhf (1.5.0+ds-5) ... 644s Selecting previously unselected package libmp3lame0:armhf. 644s Preparing to unpack .../26-libmp3lame0_3.100-6build2_armhf.deb ... 644s Unpacking libmp3lame0:armhf (3.100-6build2) ... 644s Selecting previously unselected package libmpg123-0t64:armhf. 644s Preparing to unpack .../27-libmpg123-0t64_1.33.3-2_armhf.deb ... 644s Unpacking libmpg123-0t64:armhf (1.33.3-2) ... 644s Selecting previously unselected package libvorbis0a:armhf. 644s Preparing to unpack .../28-libvorbis0a_1.3.7-3build1_armhf.deb ... 644s Unpacking libvorbis0a:armhf (1.3.7-3build1) ... 644s Selecting previously unselected package libvorbisenc2:armhf. 644s Preparing to unpack .../29-libvorbisenc2_1.3.7-3build1_armhf.deb ... 644s Unpacking libvorbisenc2:armhf (1.3.7-3build1) ... 644s Selecting previously unselected package libsndfile1:armhf. 644s Preparing to unpack .../30-libsndfile1_1.2.2-4_armhf.deb ... 644s Unpacking libsndfile1:armhf (1.2.2-4) ... 644s Selecting previously unselected package libx11-xcb1:armhf. 644s Preparing to unpack .../31-libx11-xcb1_2%3a1.8.12-1build1_armhf.deb ... 644s Unpacking libx11-xcb1:armhf (2:1.8.12-1build1) ... 644s Selecting previously unselected package libpulse0:armhf. 644s Preparing to unpack .../32-libpulse0_1%3a17.0+dfsg1-2ubuntu4_armhf.deb ... 644s Unpacking libpulse0:armhf (1:17.0+dfsg1-2ubuntu4) ... 644s Selecting previously unselected package libportaudio2:armhf. 644s Preparing to unpack .../33-libportaudio2_19.7.0+git20260206.e1b70d33-0ubuntu1_armhf.deb ... 644s Unpacking libportaudio2:armhf (19.7.0+git20260206.e1b70d33-0ubuntu1) ... 644s Selecting previously unselected package libpq5:armhf. 644s Preparing to unpack .../34-libpq5_18.1-2_armhf.deb ... 644s Unpacking libpq5:armhf (18.1-2) ... 644s Selecting previously unselected package libradcli4. 644s Preparing to unpack .../35-libradcli4_1.2.11-1build4_armhf.deb ... 644s Unpacking libradcli4 (1.2.11-1build4) ... 644s Selecting previously unselected package libresample1. 644s Preparing to unpack .../36-libresample1_0.1.3-8build1_armhf.deb ... 644s Unpacking libresample1 (0.1.3-8build1) ... 644s Selecting previously unselected package libsnmp-base. 644s Preparing to unpack .../37-libsnmp-base_5.9.4+dfsg-2ubuntu3_all.deb ... 644s Unpacking libsnmp-base (5.9.4+dfsg-2ubuntu3) ... 644s Selecting previously unselected package libsnmp40t64:armhf. 645s Preparing to unpack .../38-libsnmp40t64_5.9.4+dfsg-2ubuntu3_armhf.deb ... 645s Unpacking libsnmp40t64:armhf (5.9.4+dfsg-2ubuntu3) ... 645s Selecting previously unselected package libdeflate0:armhf. 645s Preparing to unpack .../39-libdeflate0_1.23-2build1_armhf.deb ... 645s Unpacking libdeflate0:armhf (1.23-2build1) ... 645s Selecting previously unselected package libjbig0:armhf. 645s Preparing to unpack .../40-libjbig0_2.1-6.1ubuntu3_armhf.deb ... 645s Unpacking libjbig0:armhf (2.1-6.1ubuntu3) ... 645s Selecting previously unselected package libjpeg-turbo8:armhf. 645s Preparing to unpack .../41-libjpeg-turbo8_2.1.5-4ubuntu3_armhf.deb ... 645s Unpacking libjpeg-turbo8:armhf (2.1.5-4ubuntu3) ... 645s Selecting previously unselected package libjpeg8:armhf. 645s Preparing to unpack .../42-libjpeg8_8c-2ubuntu11_armhf.deb ... 645s Unpacking libjpeg8:armhf (8c-2ubuntu11) ... 645s Selecting previously unselected package liblerc4:armhf. 645s Preparing to unpack .../43-liblerc4_4.0.0+ds-5ubuntu2_armhf.deb ... 645s Unpacking liblerc4:armhf (4.0.0+ds-5ubuntu2) ... 645s Selecting previously unselected package libsharpyuv0:armhf. 645s Preparing to unpack .../44-libsharpyuv0_1.5.0-0.1build1_armhf.deb ... 645s Unpacking libsharpyuv0:armhf (1.5.0-0.1build1) ... 645s Selecting previously unselected package libwebp7:armhf. 645s Preparing to unpack .../45-libwebp7_1.5.0-0.1build1_armhf.deb ... 645s Unpacking libwebp7:armhf (1.5.0-0.1build1) ... 645s Selecting previously unselected package libtiff6:armhf. 645s Preparing to unpack .../46-libtiff6_4.7.0-3ubuntu3_armhf.deb ... 645s Unpacking libtiff6:armhf (4.7.0-3ubuntu3) ... 645s Selecting previously unselected package libspandsp2t64:armhf. 645s Preparing to unpack .../47-libspandsp2t64_0.0.6+dfsg-2.2build1_armhf.deb ... 645s Unpacking libspandsp2t64:armhf (0.0.6+dfsg-2.2build1) ... 645s Selecting previously unselected package libspeex1:armhf. 645s Preparing to unpack .../48-libspeex1_1.2.1-3build1_armhf.deb ... 645s Unpacking libspeex1:armhf (1.2.1-3build1) ... 645s Selecting previously unselected package libspeexdsp1:armhf. 645s Preparing to unpack .../49-libspeexdsp1_1.2.1-3build1_armhf.deb ... 645s Unpacking libspeexdsp1:armhf (1.2.1-3build1) ... 645s Selecting previously unselected package libsrtp2-1:armhf. 645s Preparing to unpack .../50-libsrtp2-1_2.7.0-3build1_armhf.deb ... 645s Unpacking libsrtp2-1:armhf (2.7.0-3build1) ... 645s Selecting previously unselected package freetds-common. 645s Preparing to unpack .../51-freetds-common_1.5.5+ds-1_all.deb ... 645s Unpacking freetds-common (1.5.5+ds-1) ... 645s Selecting previously unselected package libsybdb5:armhf. 645s Preparing to unpack .../52-libsybdb5_1.5.5+ds-1_armhf.deb ... 645s Unpacking libsybdb5:armhf (1.5.5+ds-1) ... 645s Selecting previously unselected package libevent-2.1-7t64:armhf. 645s Preparing to unpack .../53-libevent-2.1-7t64_2.1.12-stable-10build2_armhf.deb ... 645s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10build2) ... 645s Selecting previously unselected package libunbound8:armhf. 645s Preparing to unpack .../54-libunbound8_1.24.2-1ubuntu1_armhf.deb ... 645s Unpacking libunbound8:armhf (1.24.2-1ubuntu1) ... 645s Selecting previously unselected package libvo-amrwbenc0:armhf. 645s Preparing to unpack .../55-libvo-amrwbenc0_0.1.3-2build2_armhf.deb ... 645s Unpacking libvo-amrwbenc0:armhf (0.1.3-2build2) ... 645s Selecting previously unselected package libvorbisfile3:armhf. 645s Preparing to unpack .../56-libvorbisfile3_1.3.7-3build1_armhf.deb ... 645s Unpacking libvorbisfile3:armhf (1.3.7-3build1) ... 645s Selecting previously unselected package asterisk-modules. 645s Preparing to unpack .../57-asterisk-modules_1%3a22.8.2+dfsg+~cs6.15.60671435-1_armhf.deb ... 645s Unpacking asterisk-modules (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 645s Selecting previously unselected package liburiparser1:armhf. 645s Preparing to unpack .../58-liburiparser1_0.9.8+dfsg-2_armhf.deb ... 645s Unpacking liburiparser1:armhf (0.9.8+dfsg-2) ... 645s Selecting previously unselected package libxslt1.1:armhf. 645s Preparing to unpack .../59-libxslt1.1_1.1.43-0.3_armhf.deb ... 645s Unpacking libxslt1.1:armhf (1.1.43-0.3) ... 645s Selecting previously unselected package asterisk. 645s Preparing to unpack .../60-asterisk_1%3a22.8.2+dfsg+~cs6.15.60671435-1_armhf.deb ... 645s Unpacking asterisk (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 645s Setting up libsharpyuv0:armhf (1.5.0-0.1build1) ... 645s Setting up libx11-xcb1:armhf (2:1.8.12-1build1) ... 645s Setting up libvo-amrwbenc0:armhf (0.1.3-2build2) ... 645s Setting up libneon27t64:armhf (0.36.0-1) ... 645s Setting up libogg0:armhf (1.3.6-2) ... 645s Setting up liblerc4:armhf (4.0.0+ds-5ubuntu2) ... 645s Setting up libspeex1:armhf (1.2.1-3build1) ... 645s Setting up libgsm1:armhf (1.0.23-2) ... 645s Setting up asterisk-core-sounds-en-gsm (1.6.1-1build1) ... 645s Setting up libsnmp-base (5.9.4+dfsg-2ubuntu3) ... 645s Setting up libcodec2-1.2:armhf (1.2.0-4) ... 645s Setting up libsrtp2-1:armhf (2.7.0-3build1) ... 645s Setting up libpq5:armhf (18.1-2) ... 645s Setting up libdeflate0:armhf (1.23-2build1) ... 645s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10build2) ... 645s Setting up libb64-0d:armhf (1.2-5build2) ... 645s Setting up libmpg123-0t64:armhf (1.33.3-2) ... 646s Setting up libjwt2:armhf (1.17.2-1build1) ... 646s Setting up libjbig0:armhf (2.1-6.1ubuntu3) ... 646s Setting up libasound2-data (1.2.15.3-1ubuntu1) ... 646s Setting up libopencore-amrwb0:armhf (0.1.6-1build2) ... 646s Setting up libunbound8:armhf (1.24.2-1ubuntu1) ... 646s Setting up libasound2t64:armhf (1.2.15.3-1ubuntu1) ... 646s Setting up libradcli4 (1.2.11-1build4) ... 646s Setting up libopus0:armhf (1.6.1-1) ... 646s Setting up asterisk-config (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 646s Setting up libvorbis0a:armhf (1.3.7-3build1) ... 646s Setting up libflac14:armhf (1.5.0+ds-5) ... 646s Setting up libical3t64:armhf (3.0.20-2build1) ... 646s Setting up libjpeg-turbo8:armhf (2.1.5-4ubuntu3) ... 646s Setting up libltdl7:armhf (2.5.4-9) ... 646s Setting up asterisk-core-sounds-en (1.6.1-1build1) ... 646s update-alternatives: using /usr/share/asterisk/sounds/en_US_f_Allison to provide /usr/share/asterisk/sounds/en (asterisk-prompt-en) in auto mode 646s update-alternatives: using /usr/share/asterisk/sounds/en_US_f_Allison to provide /usr/share/asterisk/sounds/en_US (asterisk-prompt-en-us) in auto mode 646s Setting up libwebp7:armhf (1.5.0-0.1build1) ... 646s Setting up libiksemel3:armhf (1.4-4build3) ... 646s Setting up libodbc2:armhf (2.3.14-1) ... 646s Setting up liburiparser1:armhf (0.9.8+dfsg-2) ... 646s Setting up libasyncns0:armhf (0.8-7) ... 646s Setting up libspeexdsp1:armhf (1.2.1-3build1) ... 646s Setting up liblua5.1-0:armhf (5.1.5-11build1) ... 646s Setting up libxslt1.1:armhf (1.1.43-0.3) ... 646s Setting up libopencore-amrnb0:armhf (0.1.6-1build2) ... 646s Setting up libresample1 (0.1.3-8build1) ... 646s Setting up freetds-common (1.5.5+ds-1) ... 646s Setting up libgmime-3.0-0t64:armhf (3.2.15+dfsg-1build1) ... 646s Setting up libsamplerate0:armhf (0.2.2-4build2) ... 646s Setting up libopusfile0:armhf (0.12-4build4) ... 646s Setting up libmp3lame0:armhf (3.100-6build2) ... 646s Setting up libvorbisenc2:armhf (1.3.7-3build1) ... 646s Setting up libjpeg8:armhf (8c-2ubuntu11) ... 646s Setting up libopusenc0:armhf (0.3-1) ... 646s Setting up libsnmp40t64:armhf (5.9.4+dfsg-2ubuntu3) ... 646s Setting up libsybdb5:armhf (1.5.5+ds-1) ... 646s Setting up libvorbisfile3:armhf (1.3.7-3build1) ... 646s Setting up libjack-jackd2-0:armhf (1.9.22~dfsg-5build1) ... 646s Setting up libtiff6:armhf (4.7.0-3ubuntu3) ... 646s Setting up libsndfile1:armhf (1.2.2-4) ... 646s Setting up libpulse0:armhf (1:17.0+dfsg1-2ubuntu4) ... 646s Setting up libspandsp2t64:armhf (0.0.6+dfsg-2.2build1) ... 646s Setting up libportaudio2:armhf (19.7.0+git20260206.e1b70d33-0ubuntu1) ... 646s Setting up asterisk-modules (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 646s Setting up asterisk (1:22.8.2+dfsg+~cs6.15.60671435-1) ... 646s Adding system user for Asterisk 647s Created symlink '/etc/systemd/system/multi-user.target.wants/asterisk.service' → '/usr/lib/systemd/system/asterisk.service'. 648s Processing triggers for man-db (2.13.1-1build1) ... 649s Processing triggers for libc-bin (2.42-2ubuntu4) ... 670s autopkgtest [18:21:36]: test amr: [----------------------- 672s 1 audio amr amr 0 (AMR) 672s 2 audio amrwb amrwb 0 (AMR-WB) 672s amr amrwb codec2 ulaw alaw gsm g726 g726aal2 adpcm slin8 slin12 slin16 slin24 slin32 slin44 slin48 slin96 slin192 lpc10 speex8 speex16 speex32 g722 opus 672s amr - 23000 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 23000 23000 17250 15000 672s amr amrwb codec2 ulaw alaw gsm g726 g726aal2 adpcm slin8 slin12 slin16 slin24 slin32 slin44 slin48 slin96 slin192 lpc10 speex8 speex16 speex32 g722 opus 672s amrwb 23500 - 23500 23500 23500 23500 23500 23500 23500 17500 17500 9000 17000 17000 17000 17000 17000 17000 23500 23500 15000 23000 15000 14999 672s autopkgtest [18:21:38]: test amr: -----------------------] 676s autopkgtest [18:21:42]: test amr: - - - - - - - - - - results - - - - - - - - - - 676s amr PASS 680s autopkgtest [18:21:46]: @@@@@@@@@@@@@@@@@@@@ summary 680s asttestmods PASS 680s amr PASS