- jenkins-notifications - lists.osmocom.org

Build failed in Jenkins: ttcn3-bts-test-io_uring #578
by jenkins＠lists.osmocom.org 11 Dec '25

11 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-io_uring/578/display…> Changes: [Pau Espin Pedrol] ttcn3-bts-test: Run fake_trx with sched-prio-rr ------------------------------------------ [...truncated 9.11 MiB...] <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1480 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:315 TC_sabm_retransmit_bts testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='15.045708'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1481 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:379 TC_sabm_invalid_resp testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='15.050108'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1482 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:425 TC_sabm_dm testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='15.033272'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1483 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:488 TC_establish_ign_first_sabm testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='15.044550'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='15.046878'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='15.044867'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='15.043988'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1487 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:831 TC_nr_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='15.047661'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='15.044512'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='15.036025'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='15.044754'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='15.049737'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.080193'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1493 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1293 TC_incorrect_cr testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='15.049344'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='15.033638'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='15.043685'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='15.035899'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:316 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:210 TC_vamos_chan_act_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='15.040075'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:317 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:224 TC_vamos_chan_act_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='15.034542'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:318 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:234 TC_vamos_chan_act_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='15.042537'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:319 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:214 TC_vamos_chan_act_dyn_ipa_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='15.055892'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:320 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:218 TC_vamos_chan_act_dyn_osmo_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='15.045533'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:321 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:228 TC_vamos_chan_act_dyn_osmo_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='15.043579'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:322 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:238 TC_vamos_chan_act_dyn_osmo_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='15.043579'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:325 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:283 TC_vamos_chan_mode_modify_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='15.050055'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:326 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:297 TC_vamos_chan_mode_modify_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='15.043718'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:327 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:307 TC_vamos_chan_mode_modify_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='15.035615'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:328 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:287 TC_vamos_chan_mode_modify_dyn_ipa_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='15.046459'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:329 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:291 TC_vamos_chan_mode_modify_dyn_osmo_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='15.047735'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:330 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:301 TC_vamos_chan_mode_modify_dyn_osmo_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='15.044678'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:331 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:311 TC_vamos_chan_mode_modify_dyn_osmo_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='15.043289'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:422 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:83 TC_vbs_notification testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='15.049183'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:423 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:190 TC_vgcs_uplink_free_and_busy testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='15.039569'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:424 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:289 TC_vgcs_talker_fail testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='15.038670'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:425 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:333 TC_vgcs_talker_est_rel testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='15.041942'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:426 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:411 TC_vgcs_listener_det testcase </failure> </testcase> </testsuite> <?xml version="1.0"?> <testsuite name='Titan' tests='17' failures='1' errors='0' skipped='0' inconc='0' time='98.00'> <testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.684898'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.676253'/> <testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.683600'/> <testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.685004'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.690329'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.687221'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.671738'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.677184'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.688233'/> <testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.692938'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.688530'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.684497'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.713807'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.687709'/> <testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.667520'/> <testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='2.691904'> <failure type='fail-verdict'>Timeout waiting for ANR request on PCU inteface BTS_Tests_OML.ttcn:818 BTS_Tests_OML control part BTS_Tests_OML.ttcn:797 TC_ipa_osmo_pcu_anr_fwd testcase </failure> </testcase> <testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.684494'/> </testsuite> + RC=1 + uptime + grep --color=always -o load.* [01;31m[Kload average: 0.54, 0.49, 0.81[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Jenkins build became unstable: ttcn3-bsc-test-latest #2634
by jenkins＠lists.osmocom.org 10 Dec '25

10 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/2634/display/…>

1 1

Jenkins build is back to stable : ttcn3-s1gw-test-latest #427
by jenkins＠lists.osmocom.org 09 Dec '25

09 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-s1gw-test-latest/427/display/…>

1 0

Jenkins build became unstable: ttcn3-smlc-test #1894
by jenkins＠lists.osmocom.org 08 Dec '25

08 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test/1894/display/redire…>

1 1

Build failed in Jenkins: master-osmo-msc » --enable-iu,1,a3=default,a4=default,osmocom-master #50030
by jenkins＠lists.osmocom.org 08 Dec '25

08 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/master-osmo-msc/IU=--enable-iu,WITH…> Changes: ------------------------------------------ [...truncated 705.14 KiB...] > ss -tln State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 10 127.0.0.1:4254 0.0.0.0:* LISTEN 0 10 127.0.0.1:4255 0.0.0.0:* LISTEN 0 10 0.0.0.0:2775 0.0.0.0:* > ps xua | grep osmo build 1 0.0 0.0 2592 1152 ? Ss 13:24 0:00 /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 7 0.0 0.0 2680 1072 ? S 13:24 0:00 /bin/sh /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 119659 0.0 0.0 4068 3252 ? S 13:27 0:00 /bin/bash -c test -d osmo-msc-1.15.0/_build || exit 0; \ dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \ && dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \ && am__cwd=`pwd` \ && CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_build/sub \ && ../../configure \ --with-systemdsystemunitdir=$dc_install_base/ \ --enable-werror --enable-smpp --enable-iu --enable-external-tests --enable-manuals \ --srcdir=../.. --prefix="$dc_install_base" \ && make \ && make dvi \ && make check \ && make install \ && make installcheck \ && make uninstall \ && make distuninstallcheck_dir="$dc_install_base" \ distuninstallcheck \ && chmod -R a-w "$dc_install_base" \ && ({ \ (cd ../.. && umask 077 && mkdir "$dc_destdir") \ && make DESTDIR="$dc_destdir" install \ && make DESTDIR="$dc_destdir" uninstall \ && make DESTDIR="$dc_destdir" \ distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \ } || { rm -rf "$dc_destdir"; exit 1; }) \ && rm -rf "$dc_destdir" \ && make dist \ && rm -rf osmo-msc-1.15.0.tar.gz osmo-msc-1.15.0.tar.bz2 \ && make distcleancheck \ && cd "$am__cwd" \ || exit 1 build 123622 0.0 0.0 4068 3212 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '2'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='include src doc contrib tests ' ;; \ *) list='include src doc contrib tests ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 123815 0.0 0.0 4068 3108 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '3'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub/tests'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp' ;; \ *) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 130613 1.2 0.0 21584 16360 ? S 13:28 0:00 python3 ../../../tests/vty_test_runner.py -w /build/osmo-msc-1.15.0/_build/sub -v build 130625 0.0 0.0 21474931716 39956 ? D 13:28 0:00 ./src/osmo-msc/osmo-msc -c /build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg build 130632 0.0 0.0 2680 1116 ? S 13:28 0:00 /bin/sh -c ps xua | grep osmo build 130634 0.0 0.0 3896 1900 ? S 13:28 0:00 grep osmo Socket: in 1 tries, connected to 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 41432), raddr=('127.0.0.1', 4254)> (1 sockets open) Rec: ["% 'periodic location update' is now deprecated. Use 'msc' / 'timer vlr T3212' to change subscriber expiration timeout.", '% Setting T3212 to 121 minutes (emulating the old behaviour).'] Exp: ["% 'periodic location update' is now deprecated: use 'timer T3212' to change subscriber expiration timeout.", '% Setting T3212 to 121 minutes (emulating the old behaviour).'] Socket: closing 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 41432), raddr=('127.0.0.1', 4254)> (0 sockets open) Terminating took 0.475s Process returned code: 0 ok testShowNetwork (__main__.TestVTYMSC.testShowNetwork) ... Launching: PWD=/build/osmo-msc-1.15.0/_build/sub './src/osmo-msc/osmo-msc' '-c' '/build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg' > ls -l /proc/130613/fd total 0 lr-x------ 1 build build 64 Dec 7 13:28 0 -> pipe:[1198531509] l-wx------ 1 build build 64 Dec 7 13:28 1 -> pipe:[1198531510] l-wx------ 1 build build 64 Dec 7 13:28 2 -> pipe:[1198531511] lrwx------ 1 build build 64 Dec 7 13:28 3 -> /tmp/tmp6mv0jh47 (deleted) lrwx------ 1 build build 64 Dec 7 13:28 4 -> /tmp/tmp6eatghcb (deleted) > ss -tn State Recv-Q Send-Q Local Address:Port Peer Address:Port > ss -tln State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 10 127.0.0.1:4254 0.0.0.0:* LISTEN 0 10 127.0.0.1:4255 0.0.0.0:* LISTEN 0 10 0.0.0.0:2775 0.0.0.0:* > ps xua | grep osmo build 1 0.0 0.0 2592 1152 ? Ss 13:24 0:00 /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 7 0.0 0.0 2680 1072 ? S 13:24 0:00 /bin/sh /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 119659 0.0 0.0 4068 3252 ? S 13:27 0:00 /bin/bash -c test -d osmo-msc-1.15.0/_build || exit 0; \ dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \ && dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \ && am__cwd=`pwd` \ && CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_build/sub \ && ../../configure \ --with-systemdsystemunitdir=$dc_install_base/ \ --enable-werror --enable-smpp --enable-iu --enable-external-tests --enable-manuals \ --srcdir=../.. --prefix="$dc_install_base" \ && make \ && make dvi \ && make check \ && make install \ && make installcheck \ && make uninstall \ && make distuninstallcheck_dir="$dc_install_base" \ distuninstallcheck \ && chmod -R a-w "$dc_install_base" \ && ({ \ (cd ../.. && umask 077 && mkdir "$dc_destdir") \ && make DESTDIR="$dc_destdir" install \ && make DESTDIR="$dc_destdir" uninstall \ && make DESTDIR="$dc_destdir" \ distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \ } || { rm -rf "$dc_destdir"; exit 1; }) \ && rm -rf "$dc_destdir" \ && make dist \ && rm -rf osmo-msc-1.15.0.tar.gz osmo-msc-1.15.0.tar.bz2 \ && make distcleancheck \ && cd "$am__cwd" \ || exit 1 build 123622 0.0 0.0 4068 3212 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '2'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='include src doc contrib tests ' ;; \ *) list='include src doc contrib tests ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 123815 0.0 0.0 4068 3108 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '3'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub/tests'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp' ;; \ *) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 130613 1.0 0.0 21584 16364 ? S 13:28 0:00 python3 ../../../tests/vty_test_runner.py -w /build/osmo-msc-1.15.0/_build/sub -v build 130636 25.0 0.0 21474931716 39956 ? D 13:28 0:00 ./src/osmo-msc/osmo-msc -c /build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg build 130643 0.0 0.0 2680 1084 ? S 13:28 0:00 /bin/sh -c ps xua | grep osmo build 130645 0.0 0.0 3896 1984 ? S 13:28 0:00 grep osmo Socket: in 1 tries, connected to 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 41448), raddr=('127.0.0.1', 4254)> (1 sockets open) Socket: closing 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 41448), raddr=('127.0.0.1', 4254)> (0 sockets open) Terminating took 0.897s Process returned code: 0 ok testSi2Q (__main__.TestVTYMSC.testSi2Q) ... Launching: PWD=/build/osmo-msc-1.15.0/_build/sub './src/osmo-msc/osmo-msc' '-c' '/build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg' > ls -l /proc/130613/fd total 0 lr-x------ 1 build build 64 Dec 7 13:28 0 -> pipe:[1198531509] l-wx------ 1 build build 64 Dec 7 13:28 1 -> pipe:[1198531510] l-wx------ 1 build build 64 Dec 7 13:28 2 -> pipe:[1198531511] lrwx------ 1 build build 64 Dec 7 13:28 3 -> /tmp/tmpqk9uy8on (deleted) lrwx------ 1 build build 64 Dec 7 13:28 4 -> /tmp/tmp2m_ow_3n (deleted) > ss -tn State Recv-Q Send-Q Local Address:Port Peer Address:Port > ss -tln State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 10 127.0.0.1:4254 0.0.0.0:* LISTEN 0 10 127.0.0.1:4255 0.0.0.0:* LISTEN 0 10 0.0.0.0:2775 0.0.0.0:* > ps xua | grep osmo build 1 0.0 0.0 2592 1152 ? Ss 13:24 0:00 /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 7 0.0 0.0 2680 1072 ? S 13:24 0:00 /bin/sh /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 119659 0.0 0.0 4068 3252 ? S 13:27 0:00 /bin/bash -c test -d osmo-msc-1.15.0/_build || exit 0; \ dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \ && dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \ && am__cwd=`pwd` \ && CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_build/sub \ && ../../configure \ --with-systemdsystemunitdir=$dc_install_base/ \ --enable-werror --enable-smpp --enable-iu --enable-external-tests --enable-manuals \ --srcdir=../.. --prefix="$dc_install_base" \ && make \ && make dvi \ && make check \ && make install \ && make installcheck \ && make uninstall \ && make distuninstallcheck_dir="$dc_install_base" \ distuninstallcheck \ && chmod -R a-w "$dc_install_base" \ && ({ \ (cd ../.. && umask 077 && mkdir "$dc_destdir") \ && make DESTDIR="$dc_destdir" install \ && make DESTDIR="$dc_destdir" uninstall \ && make DESTDIR="$dc_destdir" \ distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \ } || { rm -rf "$dc_destdir"; exit 1; }) \ && rm -rf "$dc_destdir" \ && make dist \ && rm -rf osmo-msc-1.15.0.tar.gz osmo-msc-1.15.0.tar.bz2 \ && make distcleancheck \ && cd "$am__cwd" \ || exit 1 build 123622 0.0 0.0 4068 3212 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '2'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='include src doc contrib tests ' ;; \ *) list='include src doc contrib tests ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 123815 0.0 0.0 4068 3108 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '3'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub/tests'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp' ;; \ *) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 130613 0.6 0.0 21584 16364 ? S 13:28 0:00 python3 ../../../tests/vty_test_runner.py -w /build/osmo-msc-1.15.0/_build/sub -v build 130647 0.0 0.0 21474931716 37848 ? D 13:28 0:00 ./src/osmo-msc/osmo-msc -c /build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg build 130654 0.0 0.0 2680 1076 ? S 13:28 0:00 /bin/sh -c ps xua | grep osmo build 130656 0.0 0.0 3896 1884 ? S 13:28 0:00 grep osmo Socket: in 1 tries, connected to 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42878), raddr=('127.0.0.1', 4254)> (1 sockets open) Socket: closing 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42878), raddr=('127.0.0.1', 4254)> (0 sockets open) Terminating took 0.897s Process returned code: 0 ok testSmppFirst (__main__.TestVTYMSC.testSmppFirst) ... Launching: PWD=/build/osmo-msc-1.15.0/_build/sub './src/osmo-msc/osmo-msc' '-c' '/build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg' > ls -l /proc/130613/fd total 0 lr-x------ 1 build build 64 Dec 7 13:28 0 -> pipe:[1198531509] l-wx------ 1 build build 64 Dec 7 13:28 1 -> pipe:[1198531510] l-wx------ 1 build build 64 Dec 7 13:28 2 -> pipe:[1198531511] lrwx------ 1 build build 64 Dec 7 13:28 3 -> /tmp/tmpknneoqt6 (deleted) lrwx------ 1 build build 64 Dec 7 13:28 4 -> /tmp/tmp0_rt3g1f (deleted) > ss -tn State Recv-Q Send-Q Local Address:Port Peer Address:Port > ss -tln State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 10 127.0.0.1:4254 0.0.0.0:* LISTEN 0 10 127.0.0.1:4255 0.0.0.0:* LISTEN 0 10 0.0.0.0:2775 0.0.0.0:* > ps xua | grep osmo build 1 0.0 0.0 2592 1152 ? Ss 13:24 0:00 /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 7 0.0 0.0 2680 1072 ? S 13:24 0:00 /bin/sh /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 119659 0.0 0.0 4068 3252 ? S 13:27 0:00 /bin/bash -c test -d osmo-msc-1.15.0/_build || exit 0; \ dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \ && dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \ && am__cwd=`pwd` \ && CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_build/sub \ && ../../configure \ --with-systemdsystemunitdir=$dc_install_base/ \ --enable-werror --enable-smpp --enable-iu --enable-external-tests --enable-manuals \ --srcdir=../.. --prefix="$dc_install_base" \ && make \ && make dvi \ && make check \ && make install \ && make installcheck \ && make uninstall \ && make distuninstallcheck_dir="$dc_install_base" \ distuninstallcheck \ && chmod -R a-w "$dc_install_base" \ && ({ \ (cd ../.. && umask 077 && mkdir "$dc_destdir") \ && make DESTDIR="$dc_destdir" install \ && make DESTDIR="$dc_destdir" uninstall \ && make DESTDIR="$dc_destdir" \ distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \ } || { rm -rf "$dc_destdir"; exit 1; }) \ && rm -rf "$dc_destdir" \ && make dist \ && rm -rf osmo-msc-1.15.0.tar.gz osmo-msc-1.15.0.tar.bz2 \ && make distcleancheck \ && cd "$am__cwd" \ || exit 1 build 123622 0.0 0.0 4068 3212 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '2'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='include src doc contrib tests ' ;; \ *) list='include src doc contrib tests ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 123815 0.0 0.0 4068 3108 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '3'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub/tests'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp' ;; \ *) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 130613 0.5 0.0 21584 16372 ? S 13:28 0:00 python3 ../../../tests/vty_test_runner.py -w /build/osmo-msc-1.15.0/_build/sub -v build 130658 40.0 0.0 21474931716 43844 ? R 13:28 0:00 ./src/osmo-msc/osmo-msc -c /build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg build 130665 0.0 0.0 2680 1120 ? S 13:28 0:00 /bin/sh -c ps xua | grep osmo build 130667 0.0 0.0 3896 1932 ? S 13:28 0:00 grep osmo Socket: in 1 tries, connected to 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42892), raddr=('127.0.0.1', 4254)> (1 sockets open) Socket: closing 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42892), raddr=('127.0.0.1', 4254)> (0 sockets open) Terminating took 0.897s Process returned code: 0 ok testVtyTree (__main__.TestVTYMSC.testVtyTree) ... Launching: PWD=/build/osmo-msc-1.15.0/_build/sub './src/osmo-msc/osmo-msc' '-c' '/build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg' > ls -l /proc/130613/fd total 0 lr-x------ 1 build build 64 Dec 7 13:28 0 -> pipe:[1198531509] l-wx------ 1 build build 64 Dec 7 13:28 1 -> pipe:[1198531510] l-wx------ 1 build build 64 Dec 7 13:28 2 -> pipe:[1198531511] lrwx------ 1 build build 64 Dec 7 13:28 3 -> /tmp/tmp_2bwsofk (deleted) lrwx------ 1 build build 64 Dec 7 13:28 4 -> /tmp/tmpjmd433vh (deleted) > ss -tn State Recv-Q Send-Q Local Address:Port Peer Address:Port > ss -tln State Recv-Q Send-Q Local Address:Port Peer Address:Port LISTEN 0 10 127.0.0.1:4254 0.0.0.0:* LISTEN 0 10 127.0.0.1:4255 0.0.0.0:* LISTEN 0 10 0.0.0.0:2775 0.0.0.0:* > ps xua | grep osmo build 1 0.0 0.0 2592 1152 ? Ss 13:24 0:00 /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 7 0.0 0.0 2680 1072 ? S 13:24 0:00 /bin/sh /osmo-ci/scripts/wrapper_core_bt_on_error.sh /build/contrib/jenkins.sh build 119659 0.0 0.0 4068 3252 ? S 13:27 0:00 /bin/bash -c test -d osmo-msc-1.15.0/_build || exit 0; \ dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \ && dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \ && am__cwd=`pwd` \ && CDPATH="${ZSH_VERSION+.}:" && cd osmo-msc-1.15.0/_build/sub \ && ../../configure \ --with-systemdsystemunitdir=$dc_install_base/ \ --enable-werror --enable-smpp --enable-iu --enable-external-tests --enable-manuals \ --srcdir=../.. --prefix="$dc_install_base" \ && make \ && make dvi \ && make check \ && make install \ && make installcheck \ && make uninstall \ && make distuninstallcheck_dir="$dc_install_base" \ distuninstallcheck \ && chmod -R a-w "$dc_install_base" \ && ({ \ (cd ../.. && umask 077 && mkdir "$dc_destdir") \ && make DESTDIR="$dc_destdir" install \ && make DESTDIR="$dc_destdir" uninstall \ && make DESTDIR="$dc_destdir" \ distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \ } || { rm -rf "$dc_destdir"; exit 1; }) \ && rm -rf "$dc_destdir" \ && make dist \ && rm -rf osmo-msc-1.15.0.tar.gz osmo-msc-1.15.0.tar.bz2 \ && make distcleancheck \ && cd "$am__cwd" \ || exit 1 build 123622 0.0 0.0 4068 3212 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '2'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='include src doc contrib tests ' ;; \ *) list='include src doc contrib tests ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 123815 0.0 0.0 4068 3108 ? S 13:27 0:00 /bin/bash -c fail=; \ if (target_option=k; case ${target_option-} in ?) ;; *) echo "am__make_running_with_option: internal error: invalid" "target option '${target_option-}' specified" >&2; exit 1;; esac; has_opt=no; sane_makeflags=$MAKEFLAGS; if { if test -z '3'; then false; elif test -n 'x86_64-pc-linux-gnu'; then true; elif test -n '4.4.1' && test -n '/build/osmo-msc-1.15.0/_build/sub/tests'; then true; else false; fi; }; then sane_makeflags=$MFLAGS; else case $MAKEFLAGS in *\\[\ \?]*) bs=\\; sane_makeflags=`printf '%s\n' "$MAKEFLAGS" | sed "s/$bs$bs[$bs $bs?]*//g"`;; esac; fi; skip_next=no; strip_trailopt () { flg=`printf '%s\n' "$flg" | sed "s/$1.*$//"`; }; for flg in $sane_makeflags; do test $skip_next = yes && { skip_next=no; continue; }; case $flg in *=*|--*) continue;; -*I) strip_trailopt 'I'; skip_next=yes;; -*I?*) strip_trailopt 'I';; -*O) strip_trailopt 'O'; skip_next=yes;; -*O?*) strip_trailopt 'O';; -*l) strip_trailopt 'l'; skip_next=yes;; -*l?*) strip_trailopt 'l';; -[dEDm]) skip_next=yes;; -[JT]) skip_next=yes;; esac; case $flg in *$target_option*) has_opt=yes; break;; esac; done; test $has_opt = yes); then \ failcom='fail=yes'; \ else \ failcom='exit 1'; \ fi; \ dot_seen=no; \ target=`echo check-recursive | sed s/-recursive//`; \ case "check-recursive" in \ distclean-* | maintainer-clean-*) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp' ;; \ *) list='sms_queue msc_vlr db_sms sdp_msg mncc csd smpp ' ;; \ esac; \ for subdir in $list; do \ echo "Making $target in $subdir"; \ if test "$subdir" = "."; then \ dot_seen=yes; \ local_target="$target-am"; \ else \ local_target="$target"; \ fi; \ (CDPATH="${ZSH_VERSION+.}:" && cd $subdir && make $local_target) \ || eval $failcom; \ done; \ if test "$dot_seen" = "no"; then \ make "$target-am" || exit 1; \ fi; test -z "$fail" build 130613 0.4 0.0 21584 16376 ? S 13:28 0:00 python3 ../../../tests/vty_test_runner.py -w /build/osmo-msc-1.15.0/_build/sub -v build 130669 25.0 0.0 21474931716 40532 ? S 13:28 0:00 ./src/osmo-msc/osmo-msc -c /build/osmo-msc-1.15.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg build 130676 0.0 0.0 2680 1112 ? S 13:28 0:00 /bin/sh -c ps xua | grep osmo build 130678 0.0 0.0 3896 1900 ? S 13:28 0:00 grep osmo Socket: in 1 tries, connected to 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42904), raddr=('127.0.0.1', 4254)> (1 sockets open) Socket: closing 127.0.0.1:4254 <socket.socket fd=5, family=2, type=1, proto=0, laddr=('127.0.0.1', 42904), raddr=('127.0.0.1', 4254)> (0 sockets open) Killed child process Process returned code: -9 ================================================================================ stdout ================================================================================ ================================================================================ stderr ================================================================================ [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4254 (telnet_interface.c:88) [1;33mDSMPP[0;m [1;33mNOTICE[0;m SMPP at 0.0.0.0 2775 (smpp_smsc.c:990) [38;5;33m[1;33mDLCTRL[0;m[38;5;33m [1;33mNOTICE[0;m[38;5;33m CTRL at 127.0.0.1 4255 (control_if.c:1026)[0;m [38;5;39m[1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.1:4222 (gsup_client.c:77)[0;m [1;33mDDB[0;m [1;33mNOTICE[0;m Init database connection to 'sms.db' using SQLite3 lib version 3.46.1 (db.c:518) [38;5;51m[1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m MGW(mgw) MGCP client: using endpoint domain '@mgw' (mgcp_client.c:945)[0;m [1;33mDMSC[0;m [1;33mNOTICE[0;m MGW pool with 1 pool members configured, (ignoring MGW configuration in VTY node 'msc'). (msc_main.c:576) [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Creating SS7 instance (sccp_instance.c:628)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Using SS7 instance 0, pc:0.23.1 (sccp_instance.c:655)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Creating AS instance (sccp_instance.c:662)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Using AS instance as-clnt-OsmoMSC-A-Iu (sccp_instance.c:673)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Creating default route (sccp_instance.c:679)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: No unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoMSC-A-Iu (sccp_instance.c:717)[0;m [38;5;45m[1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m OsmoMSC-A-Iu: Using ASP instance asp-clnt-OsmoMSC-A-Iu (sccp_instance.c:784)[0;m [38;5;43m[1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: Creating SCCP instance (ss7_instance.c:214)[0;m [1;33mDSGS[0;m [1;33mNOTICE[0;m SGs socket bound to r=NULL<->l=0.0.0.0:29118 (sgs_server.c:186) [1;33mDMSC[0;m [1;33mNOTICE[0;m A-interface: SCCP user OsmoMSC-A:RI=SSN_PC,PC=(no PC),SSN=BSSAP, cs7-instance 0 ((null)) (msc_main.c:790) [1;33mDMSC[0;m [1;33mNOTICE[0;m Iu-interface: SCCP user OsmoMSC-IuCS:RI=SSN_PC,PC=(no PC),SSN=RANAP, cs7-instance 0 ((null)) (msc_main.c:811) [38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-clnt-OsmoMSC-A-Iu,){CONNECTING} connect failed (-111) (stream_cli.c:424)[0;m [1;33mDMSC[0;m [1;33mNOTICE[0;m Terminating due to signal 15 (msc_main.c:285) ERROR ====================================================================== ERROR: testVtyTree (__main__.TestVTYMSC.testVtyTree) ---------------------------------------------------------------------- Traceback (most recent call last): File "/build/osmo-msc-1.15.0/_build/sub/tests/../../../tests/vty_test_runner.py", line 85, in tearDown raise Exception("Process returned %d" % rc) Exception: Process returned -9 ---------------------------------------------------------------------- Ran 6 tests in 13.572s FAILED (errors=1) make[8]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[8]: *** [Makefile:727: vty-python-test] Error 1 make[7]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[7]: *** [Makefile:747: vty-test] Error 2 make[6]: *** [Makefile:717: python-tests] Error 2 make[6]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[5]: *** [Makefile:771: check-local] Error 2 make[5]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[4]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[4]: *** [Makefile:576: check-am] Error 2 make[3]: *** [Makefile:426: check-recursive] Error 1 make[3]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub/tests' make[2]: *** [Makefile:477: check-recursive] Error 1 make[2]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub' make[1]: *** [Makefile:776: check] Error 2 make[1]: Leaving directory '/build/osmo-msc-1.15.0/_build/sub' make: *** [Makefile:696: distcheck] Error 1 + exit_tar_workspace + '[' 1 = 1 ']' + tar -cJf /tmp/workspace.tar.xz /build tar: Removing leading `/' from member names + mv /tmp/workspace.tar.xz /build + cat-testlogs.sh Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 3500082 killed; [ssh-agent] Stopped. Archiving artifacts

1 1

Build failed in Jenkins: ttcn3-bsc-test-latest #2631
by jenkins＠lists.osmocom.org 07 Dec '25

07 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/2631/display/…> Changes: ------------------------------------------ [...truncated 11.67 MiB...] [38;5;49m20251206125014910 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125014910 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=8 (m3ua.c:632)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=8 -> eSLS=40: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 dyn via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 56 bytes: [L2]> 01 00 01 01 00 00 00 38 02 10 00 26 00 00 00 bb 00 00 00 b9 03 00 00 08 09 00 03 07 0b 04 43 b9 00 fe 04 43 bb 00 fe 06 00 04 30 04 01 20 00 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125014910 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125014910 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125014910 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=13 (m3ua.c:632)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=13 -> eSLS=45: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125014910 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 52 bytes: [L2]> 01 00 01 01 00 00 00 34 02 10 00 23 00 00 00 bb 00 00 00 b9 03 00 00 0d 09 00 03 07 0b 04 43 b9 00 fe 04 43 bb 00 fe 03 00 01 31 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400)[0;m MTC@538ec151fcde: BSSMAP: Respoding to inbound RESET with RESET-ACK [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125014916 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125014916 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125014916 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125014916 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 44 bytes: [L2]> 01 00 01 01 00 00 00 2c 02 10 00 23 00 00 00 b9 00 00 00 bb 03 00 00 00 09 00 03 07 0b 04 43 bb 00 fe 04 43 b9 00 fe 03 00 01 31 00 (ss7_asp.c:1400)[0;m MTC@538ec151fcde: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go! 20251206125014917 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) RESET ACK from MSC: RI=SSN_PC,PC=0.23.1,SSN=BSSAP (osmo_bsc_bssap.c:84) VirtSMLC-M3UA(1272)@538ec151fcde: ************************************************* VirtSMLC-M3UA(1272)@538ec151fcde: M3UA emulation initiated, the test can be started VirtSMLC-M3UA(1272)@538ec151fcde: ************************************************* VirtSMLC-SCCP(1271)@538ec151fcde: v_sccp_pdu_maxlen:268 [38;5;43m20251206125015135 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m (r=127.0.0.1:23908<->l=127.0.0.1:2905): New m3ua connection accepted (ss7_xua_srv.c:75)[0;m [38;5;43m20251206125015135 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m (r=127.0.0.1:23908<->l=127.0.0.1:2905): matched connection to ASP virt-smlc-0 (ss7_xua_srv.c:79)[0;m [38;5;43m20251206125015135 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: Received Event SCTP-EST.ind (ss7_xua_srv.c:215)[0;m [38;5;43m20251206125015135 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_ESTABLISH.indication (xua_asp_fsm.c:129)[0;m VirtSMLC-M3UA(1272)@538ec151fcde: SCTP_ConnectResult -> connection established from: "127.0.0.1":23908 to server: "127.0.0.1":2905 association #8 [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125015139 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-smlc-0) Received M3UA Message (ASPSM:UP) (m3ua.c:811)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: Received Event ASPSM-ASP_UP (m3ua.c:772)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 8 bytes: [L2]> 01 00 03 04 00 00 00 08 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: state_chg to ASP_INACTIVE (xua_asp_fsm.c:596)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_INACTIVE}: Tx HEARTBEAT (0 unacked) (xua_asp_fsm.c:447)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 8 bytes: [L2]> 01 00 03 03 00 00 00 08 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_DOWN}: state_chg to AS_INACTIVE (xua_as_fsm.c:357)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_INACTIVE}: Broadcasting NOTIFY Type State Change:AS Inactive () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206125015139 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx NOTIFY Type State Change:AS Inactive () (xua_as_fsm.c:142)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 24 bytes: [L2]> 01 00 00 01 00 00 00 18 00 0d 00 08 00 01 00 02 00 06 00 08 00 00 00 06 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015139 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_INACTIVE}: No Layer Manager, dropping M-ASP_UP.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125015141 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-smlc-0) Received M3UA Message (ASPTM:ACTIVE) (m3ua.c:811)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC (m3ua.c:772)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 16 bytes: [L2]> 01 00 04 03 00 00 00 10 00 06 00 08 00 00 00 06 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_INACTIVE}: state_chg to ASP_ACTIVE (xua_asp_fsm.c:723)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_INACTIVE}: state_chg to AS_ACTIVE (xua_as_fsm.c:565)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_ACTIVE}: Broadcasting NOTIFY Type State Change:AS Active () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206125015141 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx NOTIFY Type State Change:AS Active () (xua_as_fsm.c:142)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 24 bytes: [L2]> 01 00 00 01 00 00 00 18 00 0d 00 08 00 01 00 03 00 06 00 08 00 00 00 06 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015141 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=unavailable) changed to status 'available' (ss7_route.c:409)[0;m [38;5;43m20251206125015141 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=unavailable) changed to status 'available' (ss7_route.c:409)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 16 bytes: [L2]> 01 00 02 02 00 00 00 10 00 12 00 08 00 00 00 be (ss7_asp.c:1400)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 24 bytes: [L2]> 01 00 02 02 00 00 00 18 00 06 00 08 00 00 00 01 00 12 00 08 00 00 00 be (ss7_asp.c:1400)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 48 bytes: [L2]> 01 00 02 01 00 00 00 30 00 06 00 08 00 00 00 06 00 12 00 0c 00 00 00 02 00 00 00 03 00 04 00 12 52 46 43 34 36 36 36 20 34 2e 35 2e 31 00 00 00 (ss7_asp.c:1400)[0;m [38;5;43m20251206125015141 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_ACTIVE}: No Layer Manager, dropping M-ASP_ACTIVE.indication (xua_asp_fsm.c:129)[0;m [38;5;49m20251206125015142 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DAVA() for 0.23.6/0 (xua_snm.c:536)[0;m [38;5;43m20251206125015142 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'available' (ss7_route.c:409)[0;m 20251206125015142 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251206125015693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Timeout (ss7_instance.c:858)[0;m [38;5;43m20251206125015694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Timeout (ss7_instance.c:858)[0;m IPA-BTS0-TRX0-RSL-IPA(1275)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS0-TRX0-RSL-IPA(1275)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS0-TRX0-RSL-IPA(1275)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } [1;35m20251206125016002 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=0,trx=0) bootstrapping RSL on ARFCN 871 using MCC-MNC 001-01 LAC=1 CID=0 BSIC=10 (osmo_bsc_main.c:320)[0;m IPA-BTS0-TRX0-RSL-IPA(1275)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(1276)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(1276)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS1-TRX0-RSL-IPA(1276)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233352F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } [1;35m20251206125018051 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=1,trx=0) bootstrapping RSL on ARFCN 871 using MCC-MNC 001-01 LAC=1 CID=1 BSIC=11 (osmo_bsc_main.c:320)[0;m IPA-BTS1-TRX0-RSL-IPA(1276)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125019693 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125019693 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125019693 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=2=0.0.2 sls=11 (m3ua.c:632)[0;m [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125019693 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=2=0.0.2,SLS=11 -> eSLS=107: Normal Route via 'virt-msc1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251206125019693 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=2=0.0.2: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Start (ss7_instance.c:879)[0;m [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=2=0.0.2,DPC=187=0.23.3,SLS=11 -> eSLS=107: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125019693 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 2=0.0.2. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125019693 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 02 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251206125019694 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.2/0 (xua_snm.c:501)[0;m [38;5;43m20251206125019694 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251206125019694 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125019694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125019694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125019694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=3=0.0.3 sls=7 (m3ua.c:632)[0;m [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=3=0.0.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125019694 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=3=0.0.3,SLS=7 -> eSLS=119: Normal Route via 'virt-msc2' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251206125019694 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=3=0.0.3: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Start (ss7_instance.c:879)[0;m [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=3=0.0.3,DPC=187=0.23.3,SLS=7 -> eSLS=119: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125019694 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 3=0.0.3. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125019694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 03 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251206125019694 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.3/0 (xua_snm.c:501)[0;m [38;5;43m20251206125019694 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251206125019694 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) IPA-BTS2-TRX0-RSL-IPA(1277)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX0-RSL-IPA(1277)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX0-RSL-IPA(1277)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS2-TRX0-RSL-IPA(1277)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [1;35m20251206125020097 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=2,trx=0) bootstrapping RSL on ARFCN 871 using MCC-MNC 001-01 LAC=2 CID=1 BSIC=12 (osmo_bsc_main.c:320)[0;m IPA-BTS2-TRX1-RSL-IPA(1278)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX1-RSL-IPA(1278)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX1-RSL-IPA(1278)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3100'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } [1;35m20251206125020111 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=2,trx=1) bootstrapping RSL on ARFCN 873 using MCC-MNC 001-01 LAC=2 CID=1 BSIC=12 (osmo_bsc_main.c:320)[0;m IPA-BTS2-TRX1-RSL-IPA(1278)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX2-RSL-IPA(1279)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX2-RSL-IPA(1279)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX2-RSL-IPA(1279)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3200'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } [1;35m20251206125020139 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=2,trx=2) bootstrapping RSL on ARFCN 875 using MCC-MNC 001-01 LAC=2 CID=1 BSIC=12 (osmo_bsc_main.c:320)[0;m IPA-BTS2-TRX2-RSL-IPA(1279)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX3-RSL-IPA(1280)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX3-RSL-IPA(1280)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX3-RSL-IPA(1280)@538ec151fcde: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3300'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } [1;35m20251206125020164 [1;33mDRSL[0;m[1;35m [1;33mNOTICE[0;m[1;35m (bts=2,trx=3) bootstrapping RSL on ARFCN 877 using MCC-MNC 001-01 LAC=2 CID=1 BSIC=12 (osmo_bsc_main.c:320)[0;m IPA-BTS2-TRX3-RSL-IPA(1280)@538ec151fcde: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [38;5;43m20251206125020695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Timeout (ss7_instance.c:858)[0;m [38;5;43m20251206125020695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Timeout (ss7_instance.c:858)[0;m MTC@538ec151fcde: "TC_paging_tmsi_nochan() start" 20251206125021174 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_paging_tmsi_nochan() start (logging_vty.c:1172) [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125021179 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125021179 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125021179 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125021179 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 64 bytes: [L2]> 01 00 01 01 00 00 00 40 02 10 00 36 00 00 00 b9 00 00 00 bb 03 00 00 00 09 00 03 07 0b 04 43 bb 00 fe 04 43 b9 00 fe 16 00 14 52 08 08 09 10 10 10 00 00 00 10 09 04 a1 b2 c3 01 1a 01 03 00 00 (ss7_asp.c:1400)[0;m MTC@538ec151fcde: setverdict(pass): none -> pass MTC@538ec151fcde: talloc reports "struct bsc_subscr" x 1, expecting 0 MTC@538ec151fcde: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 MTC@538ec151fcde: count mismatch, retrying in 3.000000 [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125024694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125024694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125024694 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=2=0.0.2 sls=4 (m3ua.c:632)[0;m [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125024694 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=2=0.0.2,SLS=4 -> eSLS=100: Normal Route via 'virt-msc1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251206125024694 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=2=0.0.2: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Start (ss7_instance.c:879)[0;m [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=2=0.0.2,DPC=187=0.23.3,SLS=4 -> eSLS=100: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125024694 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 2=0.0.2. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125024694 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 02 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251206125024695 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.2/0 (xua_snm.c:501)[0;m [38;5;43m20251206125024695 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251206125024695 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251206125024695 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251206125024695 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251206125024695 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=3=0.0.3 sls=3 (m3ua.c:632)[0;m [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=3=0.0.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251206125024695 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=3=0.0.3,SLS=3 -> eSLS=115: Normal Route via 'virt-msc2' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251206125024695 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=3=0.0.3: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Start (ss7_instance.c:879)[0;m [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=3=0.0.3,DPC=187=0.23.3,SLS=3 -> eSLS=115: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251206125024695 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 3=0.0.3. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251206125024695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 03 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251206125024695 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.3/0 (xua_snm.c:501)[0;m [38;5;43m20251206125024695 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251206125024695 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251206125025695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Timeout (ss7_instance.c:858)[0;m [38;5;43m20251206125025695 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Timeout (ss7_instance.c:858)[0;m MTC@538ec151fcde: talloc reports "struct bsc_subscr" x 1, expecting 0 MTC@538ec151fcde: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 MTC@538ec151fcde: count mismatch, retrying in 3.000000 FATAL: command execution failed Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy68.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins16343118924726714715.sh Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-bsc-test-latest #2631

1 1

Build failed in Jenkins: ttcn3-pcu-test-sns-latest #2475
by jenkins＠lists.osmocom.org 07 Dec '25

07 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns-latest/2475/disp…> Changes: ------------------------------------------ [...truncated 504.78 KiB...] [1;32m20251206124938344 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS2 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS3 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS4 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS5 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS6 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS7 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS8 (pcu_l1_if.cpp:858)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS9 (pcu_l1_if.cpp:858)[0;m [0;33m20251206124938345 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max MCS: DL=9 UL=9 (bts.cpp:1449)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_cs=2 (VTY forced, ignoring) (pcu_l1_if.cpp:863)[0;m [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_mcs=1 (pcu_l1_if.cpp:868)[0;m 20251206124938345 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f5ceef325a0(bssgp:bss_ctx) with 7 counters (rate_ctr.c:86) 20251206124938345 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55eacc3dc700(pcu:sgsn) with 2 counters (rate_ctr.c:86) [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 nsvci=1234 (pcu_l1_if.cpp:715)[0;m MTC@bd095fb043ca: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } } [1;32m20251206124938345 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 address: r=127.0.0.1:23000<->l=NULL:22000 (pcu_l1_if.cpp:719)[0;m [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Allocated (fsm.c:456)[0;m 20251206124938345 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f5ceef33140(ns:nse) with 15 counters (rate_ctr.c:86) [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_ADD_BIND (gprs_ns2_sns.c:2680)[0;m [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2028)[0;m [38;5;57m20251206124938345 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS no endpoints, transfer=0, first=1, mtu=0 (gprs_ns2.c:575)[0;m [1;35m20251206124938345 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSE SNS no endpoints / 6 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to UNCONFIGURED (gprs_ns2_sns.c:2240)[0;m [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2513)[0;m [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to BSS_SIZE (gprs_ns2_sns.c:2255)[0;m 20251206124938345 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f5ceef33120(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251206124938345 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;63m20251206124938345 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-SIZE (reset=1, max_nr_nsvc=8192, num_ip4=1, num_ip6=-1) (gprs_ns2_message.c:807)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=0,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=0, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=1,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=1, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=2,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=2, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=3,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=3, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=4,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=4, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=5,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=5, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=6,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=6, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=7,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251206124938345 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=7, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251206124938345 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS model: osmo-bts-trx (pcu_l1_if.cpp:974)[0;m MTC@bd095fb043ca: f_incoming_sns_config(idx=0) MTC@bd095fb043ca: f_ns_exp() expecting { pDU_SNS_Config := { nsPduType := '0F'O, endFlag := { eBIT := '1'B, spare := '0000000'B }, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, listofIP4Elements := { iEI := '05'O, ext := '1'B, lengthIndicator := { length1 := ? }, iP4_Elements := { { ipAddress := '7F000001'O, uDP_Port := '55F0'O, signallingWeight := ?, dataWeight := ? } } }, listofIP6Elements := omit } } [38;5;63m20251206124938353 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-SIZE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938353 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: NSEI=1234 Rx SNS PDU type SNS-SIZE-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251206124938353 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: Received Event RX_SIZE_ACK (gprs_ns2_sns.c:2349)[0;m [38;5;57m20251206124938353 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: state_chg to BSS_CONFIG_BSS (gprs_ns2_sns.c:827)[0;m [38;5;63m20251206124938353 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG (end_flag=1, num_ip4=1, num_ip6=0) (gprs_ns2_message.c:713)[0;m MTC@bd095fb043ca: f_outgoing_sns_config_1c1u(idx=0) MTC@bd095fb043ca: f_ns_exp() expecting { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, causeNS := omit } } [38;5;63m20251206124938356 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938356 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: NSEI=1234 Rx SNS PDU type SNS-CONFIG-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251206124938356 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: Received Event RX_CONFIG_ACK (gprs_ns2_sns.c:2358)[0;m [38;5;57m20251206124938356 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: state_chg to BSS_CONFIG_SGSN (gprs_ns2_sns.c:1059)[0;m [38;5;63m20251206124938356 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: NSEI=1234 Rx SNS PDU type SNS-CONFIG (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Received Event RX_CONFIG_END (gprs_ns2_sns.c:2353)[0;m [38;5;57m20251206124938357 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Rx SNS-CONFIG: Remote IPv4 list now 2 entries (gprs_ns2_sns.c:1126)[0;m 20251206124938357 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f5ceef33120(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;57m20251206124938357 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (cause=NULL) (gprs_ns2_message.c:755)[0;m [38;5;63m20251206124938357 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (gprs_ns2_message.c:757)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251206124938357 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=0, first=1, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251206124938357 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251206124938357 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251206124938357 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=1, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251206124938357 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became available (gprs_bssgp_pcu.c:681)[0;m [38;5;59m20251206124938357 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 0 (gprs_bssgp_pcu.c:1045)[0;m [38;5;59m20251206124938357 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=0) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m [38;5;61m20251206124938357 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;57m20251206124938357 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251206124938357 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251206124938357 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251206124938357 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: state_chg to CONFIGURED (gprs_ns2_sns.c:1195)[0;m [38;5;57m20251206124938357 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS configured, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251206124938357 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 SNS configured. (gprs_bssgp_pcu.c:678)[0;m [38;5;63m20251206124938359 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938360 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206124938361 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938361 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251206124938361 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124938362 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938362 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206124938362 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251206124938362 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251206124938363 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124948360 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124948363 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124948363 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124948363 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206124948367 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124948367 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206124958364 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124958367 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206124958369 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124958369 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206124958370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206124958370 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;59m20251206125008358 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 0 (gprs_bssgp_pcu.c:1045)[0;m [38;5;59m20251206125008358 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=0) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m [38;5;61m20251206125008358 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;61m20251206125008362 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2.c:1311)[0;m [38;5;57m20251206125008362 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;61m20251206125008362 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2_vc_fsm.c:686)[0;m [38;5;59m20251206125008362 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m rx BVCI_SIGNALLING gprs_bssgp_rx_sign (gprs_bssgp_pcu.c:562)[0;m [38;5;59m20251206125008362 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Rx BSSGP BVCI=0 (SIGN) BVC_RESET_ACK (gprs_bssgp_pcu.c:421)[0;m [38;5;59m20251206125008362 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 1234 (gprs_bssgp_pcu.c:1053)[0;m [38;5;59m20251206125008362 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=1234) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m [38;5;61m20251206125008362 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;61m20251206125008363 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2.c:1311)[0;m [38;5;57m20251206125008363 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;61m20251206125008363 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2_vc_fsm.c:686)[0;m [38;5;59m20251206125008363 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m rx BVCI_SIGNALLING gprs_bssgp_rx_sign (gprs_bssgp_pcu.c:562)[0;m [38;5;59m20251206125008363 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Rx BSSGP BVCI=0 (SIGN) BVC_RESET_ACK (gprs_bssgp_pcu.c:421)[0;m [38;5;59m20251206125008363 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending unblock on BVCI 1234 (gprs_bssgp_pcu.c:1062)[0;m [38;5;59m20251206125008363 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=1234) Tx BVC-UNBLOCK (gprs_bssgp_bss.c:281)[0;m [38;5;61m20251206125008363 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;61m20251206125008364 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2.c:1311)[0;m [38;5;57m20251206125008364 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;61m20251206125008364 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2_vc_fsm.c:686)[0;m [38;5;59m20251206125008364 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m rx BVCI_SIGNALLING gprs_bssgp_rx_sign (gprs_bssgp_pcu.c:562)[0;m [38;5;59m20251206125008364 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Rx BSSGP BVCI=0 (SIGN) BVC_UNBLOCK_ACK (gprs_bssgp_pcu.c:435)[0;m [38;5;59m20251206125008364 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Sending flow control info on BVCI 1234 (gprs_bssgp_pcu.c:1070)[0;m [38;5;59m20251206125008364 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Computed BVC leak rate = 59200, num_pdch = 8, cs = MCS-9 (gprs_bssgp_pcu.c:978)[0;m [38;5;59m20251206125008364 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Computed MS default leak rate = 29600, ms_num_pdch = 4, cs = MCS-9 (gprs_bssgp_pcu.c:1001)[0;m [38;5;59m20251206125008364 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Sending FLOW CONTROL BVC, Bmax = 592000, R = 59200, Bmax_MS = 296000, R_MS = 29600, avg_dly = 0 (gprs_bssgp_pcu.c:1029)[0;m [38;5;61m20251206125008364 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m MTC@bd095fb043ca: f_outgoing_sns_chg_weight(idx_chg=0) [38;5;61m20251206125008365 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2.c:1311)[0;m [38;5;57m20251206125008365 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;61m20251206125008365 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Rx NS-UNITDATA (gprs_ns2_vc_fsm.c:686)[0;m [38;5;59m20251206125008365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m rx BVCI_PTP=1234 gprs_bssgp_rx_ptp (gprs_bssgp_pcu.c:572)[0;m [38;5;59m20251206125008365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Rx BSSGP BVCI=1234 (PTP) FLOW-CONTROL-BVC-ACK (gprs_bssgp_pcu.c:358)[0;m MTC@bd095fb043ca: f_ns_exp() expecting { pDU_SNS_Ack := { nsPduType := '0C'O ("\f"), nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, transactionID := 25, causeNS := omit, iP_Address_NS := omit, listofIP4Elements := omit, listofIP6Elements := * } } [38;5;63m20251206125008365 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CHANGEWEIGHT (gprs_ns2.c:1309)[0;m [38;5;57m20251206125008365 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: NSEI=1234 Rx SNS PDU type SNS-CHANGEWEIGHT (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251206125008365 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: Received Event RX_CHANGE_WEIGHT (gprs_ns2_sns.c:2367)[0;m [38;5;57m20251206125008365 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: CHANGE-WEIGHT NS-VC udp)[0.0.0.0]:22000<>[127.0.0.1]:23000 data_weight 0->1, sig_weight 1->1 (gprs_ns2_sns.c:682)[0;m [38;5;63m20251206125008365 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-ACK (trans_id=25, cause=NULL, num_ip4=0, num_ip6=0) (gprs_ns2_message.c:655)[0;m MTC@bd095fb043ca: Start timer T: 1 s [38;5;63m20251206125008369 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206125008370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206125008370 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251206125008371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206125008371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251206125008371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m MTC@bd095fb043ca: f_outgoing_sns_del(idx_del=1) MTC@bd095fb043ca: f_ns_exp() expecting { pDU_SNS_Ack := { nsPduType := '0C'O ("\f"), nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, transactionID := 24, causeNS := omit, iP_Address_NS := omit, listofIP4Elements := { iEI := '05'O, ext := '1'B, lengthIndicator := { length1 := ? }, iP4_Elements := { { ipAddress := '7F000001'O, uDP_Port := '59D9'O, signallingWeight := '00'O, dataWeight := '01'O } } }, listofIP6Elements := * } } [38;5;63m20251206125009371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-DELETE (gprs_ns2.c:1309)[0;m [38;5;57m20251206125009371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: NSEI=1234 Rx SNS PDU type SNS-DELETE (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251206125009371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: Received Event RX_DELETE (gprs_ns2_sns.c:2364)[0;m [38;5;57m20251206125009371 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){CONFIGURED}: DELETE NS-VC udp)[0.0.0.0]:22000<>[127.0.0.1]:23001 (gprs_ns2_sns.c:729)[0;m [38;5;57m20251206125009371 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251206125009371 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251206125009371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251206125009371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251206125009371 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Deallocated (fsm.c:568)[0;m [38;5;63m20251206125009371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-ACK (trans_id=24, cause=NULL, num_ip4=1, num_ip6=0) (gprs_ns2_message.c:655)[0;m MTC@bd095fb043ca: Start timer Tgrace: 1 s MTC@bd095fb043ca: Start timer T: 20 s [38;5;59m20251206125018365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Sending flow control info on BVCI 1234 (gprs_bssgp_pcu.c:1070)[0;m [38;5;59m20251206125018365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Computed BVC leak rate = 59200, num_pdch = 8, cs = MCS-9 (gprs_bssgp_pcu.c:978)[0;m [38;5;59m20251206125018365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Computed MS default leak rate = 29600, ms_num_pdch = 4, cs = MCS-9 (gprs_bssgp_pcu.c:1001)[0;m [38;5;59m20251206125018365 [1;34mDLBSSGP[0;m[38;5;59m [1;34mDEBUG[0;m[38;5;59m Sending FLOW CONTROL BVC, Bmax = 592000, R = 59200, Bmax_MS = 296000, R_MS = 29600, avg_dly = 0 (gprs_bssgp_pcu.c:1029)[0;m [38;5;61m20251206125018365 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;63m20251206125018370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206125021371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251206125024372 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m FATAL: command execution failed Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy68.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins14878904733103157637.sh Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-pcu-test-sns-latest #2475 [38;5;63m20251206125027373 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m

1 1

Build failed in Jenkins: ttcn3-stp-test-latest #2211
by jenkins＠lists.osmocom.org 07 Dec '25

07 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test-latest/2211/display/…> Changes: ------------------------------------------ [...truncated 3.45 MiB...] [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_DOWN}: state_chg to AS_INACTIVE (xua_as_fsm.c:357)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: Broadcasting NOTIFY Type State Change:AS Inactive () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: Received Event ASP-UP.conf (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: state_chg to WAIT_NOTIFY (xua_default_lm_fsm.c:182)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827773 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (MGMT:NOTIFY) (m3ua.c:811)[0;m [38;5;49m20251206124827773 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-client0) Received NOTIFY Type State Change:AS Inactive () (m3ua.c:707)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: Received primitive M-NOTIFY.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: Received Event NOTIFY.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: state_chg to ACTIVE (xua_default_lm_fsm.c:258)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req (xua_default_lm_fsm.c:259)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) Tx 24 bytes: [L2]> 01 00 04 01 00 00 00 18 00 0b 00 08 00 00 00 02 00 06 00 08 00 00 04 1f (ss7_asp.c:1400)[0;m MTC@030e1a54e5a2: [0] Answering M3UA BEAT { connId := 2, remName := "::ffff:127.0.0.1", remPort := 2906, locName := "::ffff:127.0.0.1", locPort := 10002, msg := { m3UA_BEAT := { version := '01'O, reserved := '00'O, messageClassAndType := '0303'O, messageLength := 8, messageParameters := { heartbeat_Data := omit } } } } [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827774 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (ASPSM:BEAT-ACK) (m3ua.c:811)[0;m [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event ASPSM_BEAT_ACK (m3ua.c:772)[0;m [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Rx HEARTBEAT ACK (xua_asp_fsm.c:868)[0;m MTC@030e1a54e5a2: setverdict(pass): pass -> pass, component reason not changed MTC@030e1a54e5a2: Clearing M3UA... [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827978 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (ASPTM:ACTIVE-ACK) (m3ua.c:811)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK (m3ua.c:772)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: T(ack) stopped (xua_asp_fsm.c:420)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: state_chg to ASP_ACTIVE (xua_asp_fsm.c:662)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: state_chg to AS_ACTIVE (xua_as_fsm.c:565)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: Broadcasting NOTIFY Type State Change:AS Active () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-ASP_ACTIVE.confirm (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827978 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827978 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (MGMT:NOTIFY) (m3ua.c:811)[0;m [38;5;49m20251206124827978 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-client0) Received NOTIFY Type State Change:AS Active () (m3ua.c:707)[0;m [38;5;43m20251206124827979 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-NOTIFY.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827979 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received Event NOTIFY.ind (xua_default_lm_fsm.c:410)[0;m MTC@030e1a54e5a2: setverdict(pass): pass -> pass reason: "M3UA cleared", component reason not changed 20251206124830276 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:47714<->l=127.0.0.1:4239 (telnet_interface.c:138) [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-client0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 160 (flags=0x80) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-client0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 168 (flags=0x80) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CONNECTED} connection closed with srv (stream_cli.c:631)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CONNECTED} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) disconnect_cb() from lower layers, reconnecting (ss7_asp.c:1248)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) Closing and reconnecting ASP (ss7_asp.c:1238)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1230)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Ignoring primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-SCTP_RELEASE.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received Event SCTP-RELEASE.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: state_chg to IDLE (xua_default_lm_fsm.c:324)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) Closing and reconnecting ASP (ss7_asp.c:1238)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1230)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Ignoring primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received primitive M-SCTP_RELEASE.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received Event SCTP-RELEASE.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: state_chg to IDLE (xua_default_lm_fsm.c:324)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m MTC@030e1a54e5a2: Setting final verdict of the test case. MTC@030e1a54e5a2: Local verdict of MTC: pass MTC@030e1a54e5a2: No PTCs were created. MTC@030e1a54e5a2: Test case TC_clnt_asp_act_tmt_loadshare finished. Verdict: pass MTC@030e1a54e5a2: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/ttcn3-tcpdump-stop.sh STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass'. [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m Sat Dec 6 12:48:30 UTC 2025 [1;32m====== STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass ======[0m Saving talloc report from 127.0.0.1:4239 to STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare.talloc 20251206124830301 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:47722<->l=127.0.0.1:4239 (telnet_interface.c:192) 20251206124830904 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:47722<->l=127.0.0.1:4239 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=35024) [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} reconnecting (stream_cli.c:1352)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} reconnecting (stream_cli.c:1352)[0;m [38;5;23m20251206124831297 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){CONNECTING} connect failed (-111) (stream_cli.c:424)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){CONNECTING} close() (stream_cli.c:179)[0;m [38;5;23m20251206124831297 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251206124831297 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){CONNECTING} connect failed (-111) (stream_cli.c:424)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){CONNECTING} close() (stream_cli.c:179)[0;m [38;5;23m20251206124831297 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m Waiting for packet dumper to finish... 1 (prev_count=35024, count=38372) [38;5;43m20251206124832277 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m [38;5;43m20251206124832278 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665)[0;m [38;5;43m20251206124832278 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m MTC@030e1a54e5a2: External command `/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/ttcn3-tcpdump-stop.sh STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass' was executed successfully (exit status: 0). MC@030e1a54e5a2: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@030e1a54e5a2: Terminating MTC. MC@030e1a54e5a2: MTC terminated. MC2> exit MC@030e1a54e5a2: Shutting down session. MC@030e1a54e5a2: Shutdown complete. Comparing expected results '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp/expected-results.xml' against results in 'junit-xml-650.log' -------------------- [1;32mpass[0;m STP_Tests_M3UA.TC_connect_asp_up [1;32mpass[0;m STP_Tests_M3UA.TC_beat [1;32mpass[0;m STP_Tests_M3UA.TC_beat_payload [1;32mpass[0;m STP_Tests_M3UA.TC_beat_timeout [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_override [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_loadshare [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_broadcast [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_override [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_loadshare_roundrobin [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_loadshare_sls [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_broadcast [1;32mpass[0;m STP_Tests_M3UA.TC_act_rctx_data_no_rctx [1;32mpass[0;m STP_Tests_M3UA.TC_m3ua_sctp_srv_adm_shutdown [1;32mpass[0;m STP_Tests_M3UA.TC_combinedlset_loadshare [1;32mpass[0;m STP_Tests_M3UA.TC_inaccessible_sp_duna [1;32mpass[0;m STP_Tests_M3UA.TC_inaccessible_sp_duna2 [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_static_notpermitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_static_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_dynamic_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_never_registered [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_invalid [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_registered [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_active [1;32mpass[0;m STP_Tests_M3UA.TC_dyn_asp_as_rkm_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_dyn_2asp_as_rkm_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_connect_asp_up [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_beat_timeout [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sg_to_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_to_sg [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sg_to_asp_norctx [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_to_sg_norctx [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_no_notify_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_no_daud_in_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_daud_in_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_no_snm_inactive [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_snm_inactive [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sctp_adm_shutdown [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_aspac_dava_aspia_duna [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dava_duna [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dava_duna_multipc [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dupu [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_scon [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare Summary: [1;32mpass[0;m: 45 [1;34mskip[0;m: 39 [0;94m[testenv][m3ua] Testsuite is done[0m [1;34m[testenv][m3ua] Stopping testsuite (3093911)[0m [1;34m[testenv][m3ua] Merging log files[0m [0;94m[testenv][m3ua] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/m3ua/testsuite', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/log_merge.sh STP_Tests --rm >/dev/null'][0m [1;34m[testenv][m3ua] Formatting log files[0m [0;94m[testenv][m3ua] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/m3ua/testsuite', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/log_format.sh'][0m [1;34m[testenv][m3ua] Stopping stp (3093831)[0m [1;34m[testenv] Stopping podman container (restart)[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3'][0m testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3 [0;94m[testenv] + ['podman', 'run', '--rm', '--name', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', '--log-driver', 'json-file', '--log-opt', 'path=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/podman/testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4.log', '--security-opt=seccomp=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/podman/seccomp.json', '--cap-add=NET_ADMIN', '--cap-add=NET_RAW', '--cap-add=SYS_ADMIN', '--device=/dev/net/tun', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/var-cache-apt-debian-trixie:/var/cache/apt', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/var-lib-apt-debian-trixie:/var/lib/apt', '--sysctl', 'net.ipv4.conf.all.send_redirects=0', '--sysctl', 'net.ipv4.conf.default.send_redirects=0', '-e', 'PODMAN=1', '-e', 'TESTENV_BINARY_REPO=1', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '--volume', '/home/osmocom-build/ccache/testenv:/home/osmocom-build/ccache/testenv', '--volume', '/home/osmocom-build/jenkins/workspace:/home/osmocom-build/jenkins/workspace', 'debian-trixie-osmo-ttcn3-testenv', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/testenv-podman-main.sh'][0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'rm', '/etc/apt/apt.conf.d/docker-clean'][0m Running testenv-podman-main.sh [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'sh', '-c', "echo 'deb [signed-by=/obs.key] https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13/ ./' > /etc/apt/sources.list.d/osmocom.list"][0m [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'sh', '-c', 'echo \'Package: *\nPin: origin "downloads.osmocom.org"\nPin-Priority: 1100\n\' > /etc/apt/preferences.d/osmocom-apt-pin'][0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'apt-get', '-q', 'update'][0m Ign:1 http://deb.debian.org/debian trixie InRelease Ign:2 https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13 ./ InRelease Ign:3 http://deb.debian.org/debian trixie-updates InRelease Ign:2 https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13 ./ InRelease FATAL: command execution failed Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy68.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins5406105115446944055.sh Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-stp-test-latest #2211

1 1

Build failed in Jenkins: ttcn3-bts-test-asan #545
by jenkins＠lists.osmocom.org 07 Dec '25

07 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-asan/545/display/red…> Changes: ------------------------------------------ [...truncated 3.81 MiB...] <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_ua_dcch_sapi3' time='15.044041'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1476 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:162 TC_sabm_ua_dcch_sapi3 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_ua_dcch_sapi4' time='15.041911'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1477 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:167 TC_sabm_ua_dcch_sapi4 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_contention' time='15.044046'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1478 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:201 TC_sabm_contention testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_retransmit' time='15.045209'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1479 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:225 TC_sabm_retransmit testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_retransmit_bts' time='15.041034'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1480 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:315 TC_sabm_retransmit_bts testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_invalid_resp' time='15.042782'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1481 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:379 TC_sabm_invalid_resp testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_dm' time='15.032082'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1482 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:425 TC_sabm_dm testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_establish_ign_first_sabm' time='15.047105'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1483 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:488 TC_establish_ign_first_sabm testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_iframe_seq_and_ack' time='15.041580'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_iframe_timer_recovery' time='15.042800'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_ns_seq_error' time='15.042353'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_nr_seq_error' time='15.047139'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1487 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:831 TC_nr_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_rec_invalid_frame' time='15.038759'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_segm_concat_dcch' time='15.041615'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_segm_concat_sacch' time='15.043223'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_t200_n200' time='15.042608'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_rr_response_frame_loss' time='15.045552'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_incorrect_cr' time='15.032206'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1493 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1293 TC_incorrect_cr testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_sabm_incorrect_c' time='15.044653'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_normal_reestablishment' time='15.039553'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm' name='TC_normal_reestablishment_state_unacked' time='15.041025'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_vff' time='15.043931'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:316 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:210 TC_vamos_chan_act_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_vhh' time='15.041600'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:317 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:224 TC_vamos_chan_act_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_hvhh' time='15.041395'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:318 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:234 TC_vamos_chan_act_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_dyn_ipa_vff' time='15.033826'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:319 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:214 TC_vamos_chan_act_dyn_ipa_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_dyn_osmo_vff' time='15.044674'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:320 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:218 TC_vamos_chan_act_dyn_osmo_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_dyn_osmo_vhh' time='15.041463'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:321 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:228 TC_vamos_chan_act_dyn_osmo_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='15.045549'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:322 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:238 TC_vamos_chan_act_dyn_osmo_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_vff' time='15.043275'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:325 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:283 TC_vamos_chan_mode_modify_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_vhh' time='15.037884'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:326 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:297 TC_vamos_chan_mode_modify_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_hvhh' time='15.044323'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:327 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:307 TC_vamos_chan_mode_modify_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='15.042868'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:328 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:287 TC_vamos_chan_mode_modify_dyn_ipa_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='15.043660'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:329 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:291 TC_vamos_chan_mode_modify_dyn_osmo_vff testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='15.045165'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:330 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:301 TC_vamos_chan_mode_modify_dyn_osmo_vhh testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='15.041450'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_VAMOS.ttcn:331 BTS_Tests_VAMOS control part BTS_Tests_VAMOS.ttcn:311 TC_vamos_chan_mode_modify_dyn_osmo_hvhh testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI' name='TC_vbs_notification' time='15.045165'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:422 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:83 TC_vbs_notification testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI' name='TC_vgcs_uplink_free_and_busy' time='15.042902'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:423 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:190 TC_vgcs_uplink_free_and_busy testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI' name='TC_vgcs_talker_fail' time='15.044363'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:424 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:289 TC_vgcs_talker_fail testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI' name='TC_vgcs_talker_est_rel' time='15.045903'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:425 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:333 TC_vgcs_talker_est_rel testcase </failure> </testcase> <testcase classname='BTS_Tests_ASCI' name='TC_vgcs_listener_det' time='15.044006'> <failure type='fail-verdict'>"BTS_Tests.ttcn:291 : Timeout waiting for RSL bring up" BTS_Tests_ASCI.ttcn:426 BTS_Tests_ASCI control part BTS_Tests_ASCI.ttcn:411 TC_vgcs_listener_det testcase </failure> </testcase> </testsuite> + RC=1 + uptime + grep --color=always -o load.* [01;31m[Kload average: 0.17, 0.17, 1.04[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Build failed in Jenkins: ttcn3-pcu-test-latest #2500
by jenkins＠lists.osmocom.org 06 Dec '25

06 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/2500/display/…> Changes: ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build4-deb12build-ansible (registry-build-amd64 ttcn3 obs osmo-gsm-tester-build ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-latest FATAL: hudson.remoting.Channel$OrderlyShutdown: Command Close created at Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to build4-deb12build-ansible at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1923) at hudson.remoting.Request.call(Request.java:204) at hudson.remoting.Channel.call(Channel.java:1111) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.mkdirs(FilePath.java:1387) at hudson.model.AbstractProject.checkout(AbstractProject.java:1244) at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:649) at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:85) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:522) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Caused: hudson.remoting.RequestAbortedException at hudson.remoting.Request.abort(Request.java:358) at hudson.remoting.Channel.terminate(Channel.java:1196) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-pcu-test-latest #2500

1 0

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications