Approach to system testing for Osmocom stack

Holger Freyther holger at freyther.de
Sun Feb 25 22:08:13 UTC 2018



> On 7. Feb 2018, at 09:31, Holger Freyther <holger at freyther.de> wrote:
> 

Hey,


> Knowing what I know now I would have gone for Go. It is made for the kind of
> concurrency where python seems unusable. I hesitated to build my own event
> loop but will build something that looks like our C apps in python (single
> select, no async/await).


finally... still plenty of things to improve and do... The next thing is to
improve "mobile" to not add the >30s timeout for first cell selection.



python3 -mosmo_ms_driver
22:59:56.255200 ---                                -: Going to store files in  {tmp_dir='/tmp/tmp5wbisu2hosmo-ms-driver'}
22:59:56.256180 run                          lu_test: Starting testcase
22:59:56.256813 run                          lu_test: Pre-launching all virtphy's
22:59:56.257391 run                    osmo-ms/00000: Starting virtphy process  [lu_test↪osmo-ms/00000]
22:59:56.258454 run                    osmo-ms/00000: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00000  [lu_test↪osmo-ms/00000]
22:59:56.262654 run                    osmo-ms/00001: Starting virtphy process  [lu_test↪osmo-ms/00001]
22:59:56.262793 run                    osmo-ms/00001: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00001  [lu_test↪osmo-ms/00001]
22:59:56.269087 run                    osmo-ms/00002: Starting virtphy process  [lu_test↪osmo-ms/00002]
22:59:56.269241 run                    osmo-ms/00002: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00002  [lu_test↪osmo-ms/00002]
22:59:56.274869 run                    osmo-ms/00003: Starting virtphy process  [lu_test↪osmo-ms/00003]
22:59:56.275031 run                    osmo-ms/00003: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00003  [lu_test↪osmo-ms/00003]
22:59:56.280416 run                    osmo-ms/00004: Starting virtphy process  [lu_test↪osmo-ms/00004]
22:59:56.280570 run                    osmo-ms/00004: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00004  [lu_test↪osmo-ms/00004]
22:59:56.285699 run                    osmo-ms/00005: Starting virtphy process  [lu_test↪osmo-ms/00005]
22:59:56.285809 run                    osmo-ms/00005: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00005  [lu_test↪osmo-ms/00005]
22:59:56.291308 run                    osmo-ms/00006: Starting virtphy process  [lu_test↪osmo-ms/00006]
22:59:56.291488 run                    osmo-ms/00006: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00006  [lu_test↪osmo-ms/00006]
22:59:56.300046 run                    osmo-ms/00007: Starting virtphy process  [lu_test↪osmo-ms/00007]
22:59:56.300352 run                    osmo-ms/00007: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00007  [lu_test↪osmo-ms/00007]
22:59:56.306980 run                    osmo-ms/00008: Starting virtphy process  [lu_test↪osmo-ms/00008]
22:59:56.307134 run                    osmo-ms/00008: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00008  [lu_test↪osmo-ms/00008]
22:59:56.312373 run                    osmo-ms/00009: Starting virtphy process  [lu_test↪osmo-ms/00009]
22:59:56.312526 run                    osmo-ms/00009: virtphy --l1ctl-sock=/tmp/tmp5wbisu2hosmo-ms-driver/osmocom_l2_00009  [lu_test↪osmo-ms/00009]
22:59:56.319714 run                          lu_test: Checking if sockets are in the filesystem
22:59:56.539503 run                    osmo-ms/00000: Starting process  [lu_test↪osmo-ms/00000]
22:59:56.539977 run                    osmo-ms/00000: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00000.cfg --vty-port=0  [lu_test↪osmo-ms/00000]
22:59:56.607714 run                          lu_test: MS start registered  {at=282743.473796635, delay=0.06289598299190402, ms=00000}
23:00:09.926047 run                    osmo-ms/00001: Starting process  [lu_test↪osmo-ms/00001]
23:00:09.926563 run                    osmo-ms/00001: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00001.cfg --vty-port=0  [lu_test↪osmo-ms/00001]
23:00:09.994826 run                          lu_test: MS start registered  {at=282756.860905894, delay=0.06362233200343326, ms=00001}
23:00:15.424151 run                    osmo-ms/00002: Starting process  [lu_test↪osmo-ms/00002]
23:00:15.425052 run                    osmo-ms/00002: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00002.cfg --vty-port=0  [lu_test↪osmo-ms/00002]
23:00:15.488956 run                          lu_test: MS start registered  {at=282762.355019533, delay=0.05711088899988681, ms=00002}
23:00:19.724246 run                    osmo-ms/00003: Starting process  [lu_test↪osmo-ms/00003]
23:00:19.724996 run                    osmo-ms/00003: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00003.cfg --vty-port=0  [lu_test↪osmo-ms/00003]
23:00:19.785684 run                          lu_test: MS start registered  {at=282766.651760042, delay=0.05416889599291608, ms=00003}
23:00:23.325812 run                    osmo-ms/00004: Starting process  [lu_test↪osmo-ms/00004]
23:00:23.326760 run                    osmo-ms/00004: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00004.cfg --vty-port=0  [lu_test↪osmo-ms/00004]
23:00:23.393305 run                          lu_test: MS start registered  {at=282770.259381962, delay=0.06159330299124122, ms=00004}
23:00:26.427752 run                    osmo-ms/00005: Starting process  [lu_test↪osmo-ms/00005]
23:00:26.428336 run                    osmo-ms/00005: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00005.cfg --vty-port=0  [lu_test↪osmo-ms/00005]
23:00:26.494263 run                          lu_test: MS start registered  {at=282773.360336693, delay=0.059708705986849964, ms=00005}
23:00:29.627894 run                    osmo-ms/00006: Starting process  [lu_test↪osmo-ms/00006]
23:00:29.628553 run                    osmo-ms/00006: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00006.cfg --vty-port=0  [lu_test↪osmo-ms/00006]
23:00:29.683842 run                          lu_test: MS start registered  {at=282776.54991861, delay=0.04898471699561924, ms=00006}
23:00:33.226115 run                    osmo-ms/00007: Starting process  [lu_test↪osmo-ms/00007]
23:00:33.226723 run                    osmo-ms/00007: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00007.cfg --vty-port=0  [lu_test↪osmo-ms/00007]
23:00:33.288238 run                          lu_test: MS start registered  {at=282780.154311069, delay=0.055712109024170786, ms=00007}
23:00:34.052813 run                          lu_test: MS performed LU  {at=282780.918918862, lu_delay=37.44512222701451, ms=00000}
23:00:37.525812 run                    osmo-ms/00008: Starting process  [lu_test↪osmo-ms/00008]
23:00:37.526370 run                    osmo-ms/00008: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00008.cfg --vty-port=0  [lu_test↪osmo-ms/00008]
23:00:37.590594 run                          lu_test: MS start registered  {at=282784.456672404, delay=0.05611523304833099, ms=00008}
23:00:43.028214 run                    osmo-ms/00009: Starting process  [lu_test↪osmo-ms/00009]
23:00:43.028898 run                    osmo-ms/00009: mobile -c /tmp/tmp5wbisu2hosmo-ms-driver/mob_00009.cfg --vty-port=0  [lu_test↪osmo-ms/00009]
23:00:43.087549 run                          lu_test: MS start registered  {at=282789.95362104, delay=0.05322436196729541, ms=00009}
23:00:43.087727 run                          lu_test: All started... {duration=46.56737156602321, too_slow=0}
23:00:47.231257 run                          lu_test: MS performed LU  {at=282794.097388755, lu_delay=37.23648286098614, ms=00001}
23:00:52.878843 run                          lu_test: MS performed LU  {at=282799.744935023, lu_delay=37.38991548999911, ms=00002}
23:00:56.661687 run                          lu_test: MS performed LU  {at=282803.527769994, lu_delay=36.87600995198591, ms=00003}
23:01:00.411273 run                          lu_test: MS performed LU  {at=282807.277505226, lu_delay=37.01812326401705, ms=00004}
23:01:04.194030 run                          lu_test: MS performed LU  {at=282811.06022426, lu_delay=37.699887567025144, ms=00005}
23:01:06.060154 run                          lu_test: MS performed LU  {at=282812.926287126, lu_delay=36.37636851600837, ms=00006}
23:01:09.847484 run                          lu_test: MS performed LU  {at=282816.713489132, lu_delay=36.559178063005675, ms=00007}
23:01:15.475567 run                          lu_test: MS performed LU  {at=282822.341696909, lu_delay=37.8850245049689, ms=00008}
23:01:21.124497 run                          lu_test: MS performed LU  {at=282827.990630504, lu_delay=38.037009463994764, ms=00009}
23:01:56.525619 run                          lu_test: Tests done {all_completed=True, max=38.037009463994764, min=36.37636851600837}


More information about the baseband-devel mailing list