Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

mbed_drivers-lp_timeout test fails on NUMAKER_PFM_M2351 #8042

Closed
studavekar opened this issue Sep 7, 2018 · 3 comments
Closed

mbed_drivers-lp_timeout test fails on NUMAKER_PFM_M2351 #8042

studavekar opened this issue Sep 7, 2018 · 3 comments

Comments

@studavekar
Copy link
Contributor

Description

  • Type: Bug
  • Priority: Major

Bug

Target
NUMAKER_PFM_M2351

Toolchain:
ARMC6

mbed-os sha:

6827c370 (HEAD -> master, origin/master, origin/feature-hal-spec-spi, origin/HEAD) Merge pull request #7971 from JammuKekkonen/fix_softdevice_memory_reservation_for_nrf52dk

Steps to reproduce

Use command run : mbed test -m NUMAKER_PFM_M2351 -t ARMC6 -n tests-mbed_drivers-lp_timeout -v

logs snippet

mbedgt: selecting test case observer...
        calling mbedhtrun: mbedhtrun -m NUMAKER_PFM_M2351 -p /dev/ttyACM22:9600 -f "BUILD/tests/NUMAKER_PFM_M2351/ARMC6/TESTS/mbed_drivers/lp_timeout/lp_timeout.hex" -e "TESTS/host_tests" -d /mnt/pci-0000_00_14_0-usb-0_7_2_3_1_0-scsi-0_0_0_0 -c default -t 13050000002D0101013B97B000000002 -r default -C 4 --sync 5 -P 60
mbedgt: mbed-host-test-runner: started
[1536341892.94][root]Generating grammar tables from /usr/lib/python2.7/lib2to3/Grammar.txt
[1536341892.96][root]Generating grammar tables from /usr/lib/python2.7/lib2to3/PatternGrammar.txt
[1536341892.96][HTST][INF] host test executor ver. 1.4.1
[1536341892.96][HTST][INF] copy image onto target...
[1536341892.96][COPY][INF] Waiting up to 60 sec for '13050000002D0101013B97B000000002' mount point (current is '/mnt/pci-0000_00_14_0-usb-0_7_2_3_1_0-scsi-0_0_0_0')...
[1536341893.24][mbedls.lstools_base]No valid file found to update JLink device details
[1536341905.92][HTST][INF] starting host test process...
[1536341905.92][CONN][INF] starting connection process...
[1536341905.92][CONN][INF] notify event queue about extra 60 sec timeout for serial port pooling
[1536341905.92][CONN][INF] initializing serial port listener...
[1536341905.92][PLGN][INF] Waiting up to 60 sec for '13050000002D0101013B97B000000002' serial port (current is '/dev/ttyACM22')...
[1536341905.92][HTST][INF] setting timeout to: 60 sec
[1536341906.21][mbedls.lstools_base]No valid file found to update JLink device details
[1536341906.21][mbedls.lstools_base]No valid file found to update JLink device details
[1536341906.21][mbedls.lstools_base]daplink entry: "0000" not found in platform database
[1536341906.21][mbedls.lstools_base]No valid file found to update JLink device details
[1536341906.22][mbedls.lstools_base]daplink entry: "7012" not found in platform database
[1536341906.22][SERI][INF] serial(port=/dev/ttyACM22, baudrate=9600, read_timeout=0.01, write_timeout=5)
[1536341906.22][SERI][INF] reset device using 'default' plugin...
[1536341906.33][SERI][INF] waiting 1.00 sec after reset
[1536341907.33][SERI][INF] wait for it...
[1536341907.33][SERI][TXD] mbedmbedmbedmbedmbedmbedmbedmbedmbedmbed
[1536341907.33][CONN][INF] sending up to 5 __sync packets (specified with --sync=5)
[1536341907.33][CONN][INF] sending preamble '455b3d7f-6c7d-4969-bc9d-16f827d70289'
[1536341907.33][SERI][TXD] {{__sync;455b3d7f-6c7d-4969-bc9d-16f827d70289}}
[1536341907.47][CONN][RXD] mbedmbedmbedmbedmbedmbedmbedmbed
[1536341907.52][CONN][INF] found SYNC in stream: {{__sync;455b3d7f-6c7d-4969-bc9d-16f827d70289}} it is #0 sent, queued...
[1536341907.52][HTST][INF] sync KV found, uuid=455b3d7f-6c7d-4969-bc9d-16f827d70289, timestamp=1536341907.523959
[1536341907.53][CONN][INF] found KV pair in stream: {{__version;1.3.0}}, queued...
[1536341907.54][HTST][INF] DUT greentea-client version: 1.3.0
[1536341907.57][CONN][INF] found KV pair in stream: {{__timeout;240}}, queued...
[1536341907.57][HTST][INF] setting timeout to: 240 sec
[1536341907.61][CONN][INF] found KV pair in stream: {{__host_test_name;timing_drift_auto}}, queued...
[1536341907.61][HTST][INF] host test class: '<class 'timing_drift_auto.TimingDriftSync'>'
[1536341907.61][HTST][INF] host test setup() call...
[1536341907.61][HTST][INF] CALLBACKs updated
[1536341907.61][HTST][INF] host test detected: timing_drift_auto
[1536341907.63][CONN][INF] found KV pair in stream: {{__testcase_count;22}}, queued...
[1536341907.66][CONN][RXD] >>> Running 22 test cases...
[1536341907.71][CONN][INF] found KV pair in stream: {{__testcase_name;Callback called once (attach)}}, queued...
[1536341907.77][CONN][INF] found KV pair in stream: {{__testcase_name;Callback called once (attach_us)}}, queued...
[1536341907.84][CONN][INF] found KV pair in stream: {{__testcase_name;Callback not called when cancelled (attach)}}, queued...
[1536341907.90][CONN][INF] found KV pair in stream: {{__testcase_name;Callback not called when cancelled (attach_us)}}, queued...
[1536341907.95][CONN][INF] found KV pair in stream: {{__testcase_name;Callback override (attach)}}, queued...
[1536341908.01][CONN][INF] found KV pair in stream: {{__testcase_name;Callback override (attach_us)}}, queued...
[1536341908.08][CONN][INF] found KV pair in stream: {{__testcase_name;Multiple timeouts running in parallel (attach)}}, queued...
[1536341908.15][CONN][INF] found KV pair in stream: {{__testcase_name;Multiple timeouts running in parallel (attach_us)}}, queued...
[1536341908.20][CONN][INF] found KV pair in stream: {{__testcase_name;Zero delay (attach)}}, queued...
[1536341908.24][CONN][INF] found KV pair in stream: {{__testcase_name;Zero delay (attach_us)}}, queued...
[1536341908.29][CONN][INF] found KV pair in stream: {{__testcase_name;10 ms delay accuracy (attach)}}, queued...
[1536341908.35][CONN][INF] found KV pair in stream: {{__testcase_name;10 ms delay accuracy (attach_us)}}, queued...
[1536341908.40][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay accuracy (attach)}}, queued...
[1536341908.45][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay accuracy (attach_us)}}, queued...
[1536341908.51][CONN][INF] found KV pair in stream: {{__testcase_name;5 s delay accuracy (attach)}}, queued...
[1536341908.55][CONN][INF] found KV pair in stream: {{__testcase_name;5 s delay accuracy (attach_us)}}, queued...
[1536341908.62][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay during sleep (attach)}}, queued...
[1536341908.67][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay during sleep (attach_us)}}, queued...
[1536341908.73][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay during deepsleep (attach)}}, queued...
[1536341908.79][CONN][INF] found KV pair in stream: {{__testcase_name;1 s delay during deepsleep (attach_us)}}, queued...
[1536341908.84][CONN][INF] found KV pair in stream: {{__testcase_name;Timing drift (attach)}}, queued...
[1536341908.88][CONN][INF] found KV pair in stream: {{__testcase_name;Timing drift (attach_us)}}, queued...
[1536341908.89][CONN][RXD]
[1536341908.94][CONN][RXD] >>> Running case #1: 'Callback called once (attach)'...
[1536341909.00][CONN][INF] found KV pair in stream: {{__testcase_start;Callback called once (attach)}}, queued...
[1536341909.06][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback called once (attach);1;0}}, queued...
[1536341909.12][CONN][RXD] >>> 'Callback called once (attach)': 1 passed, 0 failed
[1536341909.12][CONN][RXD]
[1536341909.19][CONN][RXD] >>> Running case #2: 'Callback called once (attach_us)'...
[1536341909.24][CONN][INF] found KV pair in stream: {{__testcase_start;Callback called once (attach_us)}}, queued...
[1536341909.31][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback called once (attach_us);1;0}}, queued...
[1536341909.37][CONN][RXD] >>> 'Callback called once (attach_us)': 1 passed, 0 failed
[1536341909.37][CONN][RXD]
[1536341909.44][CONN][RXD] >>> Running case #3: 'Callback not called when cancelled (attach)'...
[1536341909.50][CONN][INF] found KV pair in stream: {{__testcase_start;Callback not called when cancelled (attach)}}, queued...
[1536341909.59][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback not called when cancelled (attach);1;0}}, queued...
[1536341909.66][CONN][RXD] >>> 'Callback not called when cancelled (attach)': 1 passed, 0 failed
[1536341909.66][CONN][RXD]
[1536341909.74][CONN][RXD] >>> Running case #4: 'Callback not called when cancelled (attach_us)'...
[1536341909.81][CONN][INF] found KV pair in stream: {{__testcase_start;Callback not called when cancelled (attach_us)}}, queued...
[1536341909.89][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback not called when cancelled (attach_us);1;0}}, queued...
[1536341909.97][CONN][RXD] >>> 'Callback not called when cancelled (attach_us)': 1 passed, 0 failed
[1536341909.97][CONN][RXD]
[1536341910.03][CONN][RXD] >>> Running case #5: 'Callback override (attach)'...
[1536341910.07][CONN][INF] found KV pair in stream: {{__testcase_start;Callback override (attach)}}, queued...
[1536341910.14][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback override (attach);1;0}}, queued...
[1536341910.20][CONN][RXD] >>> 'Callback override (attach)': 1 passed, 0 failed
[1536341910.20][CONN][RXD]
[1536341910.25][CONN][RXD] >>> Running case #6: 'Callback override (attach_us)'...
[1536341910.31][CONN][INF] found KV pair in stream: {{__testcase_start;Callback override (attach_us)}}, queued...
[1536341910.38][CONN][INF] found KV pair in stream: {{__testcase_finish;Callback override (attach_us);1;0}}, queued...
[1536341910.44][CONN][RXD] >>> 'Callback override (attach_us)': 1 passed, 0 failed
[1536341910.44][CONN][RXD]
[1536341910.52][CONN][RXD] >>> Running case #7: 'Multiple timeouts running in parallel (attach)'...
[1536341910.58][CONN][INF] found KV pair in stream: {{__testcase_start;Multiple timeouts running in parallel (attach)}}, queued...
[1536341910.67][CONN][INF] found KV pair in stream: {{__testcase_finish;Multiple timeouts running in parallel (attach);1;0}}, queued...
[1536341910.74][CONN][RXD] >>> 'Multiple timeouts running in parallel (attach)': 1 passed, 0 failed
[1536341910.74][CONN][RXD]
[1536341910.81][CONN][RXD] >>> Running case #8: 'Multiple timeouts running in parallel (attach_us)'...
[1536341910.89][CONN][INF] found KV pair in stream: {{__testcase_start;Multiple timeouts running in parallel (attach_us)}}, queued...
[1536342148.16][HTST][INF] test suite run finished after 240.59 sec...
[1536342148.16][CONN][INF] received special event '__host_test_finished' value='True', finishing
[1536342148.20][HTST][INF] CONN exited with code: 0
[1536342148.20][HTST][INF] No events in queue
[1536342148.20][HTST][INF] stopped consuming events
[1536342148.20][HTST][INF] host test result(): None
[1536342148.20][HTST][WRN] missing __exit event from DUT
[1536342148.20][HTST][WRN] missing __exit_event_queue event from host test
[1536342148.20][HTST][ERR] missing __exit_event_queue event from host test and no result from host test, timeout...
[1536342148.20][HTST][INF] calling blocking teardown()
[1536342148.20][HTST][INF] teardown() finished
[1536342148.20][HTST][INF] {{result;timeout}}
mbedgt: retry mbedhtrun 1/1
mbedgt: ['mbedhtrun', '-m', u'NUMAKER_PFM_M2351', '-p', '/dev/ttyACM22:9600', '-f', u'"BUILD/tests/NUMAKER_PFM_M2351/ARMC6/TESTS/mbed_drivers/lp_timeout/lp_timeout.hex"', '-e', u'"TESTS/host_tests"', '-d', u'/mnt/pci-0000_00_14_0-usb-0_7_2_3_1_0-scsi-0_0_0_0', '-c', 'default', '-t', u'13050000002D0101013B97B000000002', '-r', 'default', '-C', '4', '--sync', '5', '-P', '60'] failed after 1 count
mbedgt: checking for GCOV data...
mbedgt: mbed-host-test-runner: stopped and returned 'TIMEOUT'
mbedgt: test on hardware with target id: 13050000002D0101013B97B000000002
mbedgt: test suite 'tests-mbed_drivers-lp_timeout' ................................................... TIMEOUT in 255.44 sec
        test case: '1 s delay accuracy (attach)' ..................................................... SKIPPED in 0.00 sec
        test case: '1 s delay accuracy (attach_us)' .................................................. SKIPPED in 0.00 sec
        test case: '1 s delay during deepsleep (attach)' ............................................. SKIPPED in 0.00 sec
        test case: '1 s delay during deepsleep (attach_us)' .......................................... SKIPPED in 0.00 sec
        test case: '1 s delay during sleep (attach)' ................................................. SKIPPED in 0.00 sec
        test case: '1 s delay during sleep (attach_us)' .............................................. SKIPPED in 0.00 sec
        test case: '10 ms delay accuracy (attach)' ................................................... SKIPPED in 0.00 sec
        test case: '10 ms delay accuracy (attach_us)' ................................................ SKIPPED in 0.00 sec
        test case: '5 s delay accuracy (attach)' ..................................................... SKIPPED in 0.00 sec
        test case: '5 s delay accuracy (attach_us)' .................................................. SKIPPED in 0.00 sec
        test case: 'Callback called once (attach)' ................................................... OK in 0.06 sec
        test case: 'Callback called once (attach_us)' ................................................ OK in 0.07 sec
        test case: 'Callback not called when cancelled (attach)' ..................................... OK in 0.09 sec
        test case: 'Callback not called when cancelled (attach_us)' .................................. OK in 0.08 sec
        test case: 'Callback override (attach)' ...................................................... OK in 0.07 sec
        test case: 'Callback override (attach_us)' ................................................... OK in 0.07 sec
        test case: 'Multiple timeouts running in parallel (attach)' .................................. OK in 0.09 sec
        test case: 'Multiple timeouts running in parallel (attach_us)' ............................... ERROR in 0.00 sec
        test case: 'Timing drift (attach)' ........................................................... SKIPPED in 0.00 sec
        test case: 'Timing drift (attach_us)' ........................................................ SKIPPED in 0.00 sec
        test case: 'Zero delay (attach)' ............................................................. SKIPPED in 0.00 sec
        test case: 'Zero delay (attach_us)' .......................................................... SKIPPED in 0.00 sec
mbedgt: all tests finished!
mbedgt: shuffle seed: 0.7634409732
mbedgt: test suite report:
+-------------------------+-------------------+-------------------------------+---------+--------------------+-------------+
| target                  | platform_name     | test suite                    | result  | elapsed_time (sec) | copy_method |
+-------------------------+-------------------+-------------------------------+---------+--------------------+-------------+
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | TIMEOUT | 255.44             | default     |
+-------------------------+-------------------+-------------------------------+---------+--------------------+-------------+
mbedgt: test suite results: 1 TIMEOUT
mbedgt: test case report:
+-------------------------+-------------------+-------------------------------+---------------------------------------------------+--------+--------+---------+--------------------+
| target                  | platform_name     | test suite                    | test case                                         | passed | failed | result  | elapsed_time (sec) |
+-------------------------+-------------------+-------------------------------+---------------------------------------------------+--------+--------+---------+--------------------+
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay accuracy (attach)                       | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay accuracy (attach_us)                    | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay during deepsleep (attach)               | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay during deepsleep (attach_us)            | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay during sleep (attach)                   | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 1 s delay during sleep (attach_us)                | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 10 ms delay accuracy (attach)                     | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 10 ms delay accuracy (attach_us)                  | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 5 s delay accuracy (attach)                       | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | 5 s delay accuracy (attach_us)                    | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback called once (attach)                     | 1      | 0      | OK      | 0.06               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback called once (attach_us)                  | 1      | 0      | OK      | 0.07               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback not called when cancelled (attach)       | 1      | 0      | OK      | 0.09               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback not called when cancelled (attach_us)    | 1      | 0      | OK      | 0.08               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback override (attach)                        | 1      | 0      | OK      | 0.07               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Callback override (attach_us)                     | 1      | 0      | OK      | 0.07               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Multiple timeouts running in parallel (attach)    | 1      | 0      | OK      | 0.09               |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Multiple timeouts running in parallel (attach_us) | 0      | 0      | ERROR   | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Timing drift (attach)                             | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Timing drift (attach_us)                          | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Zero delay (attach)                               | 0      | 0      | SKIPPED | 0.0                |
| NUMAKER_PFM_M2351-ARMC6 | NUMAKER_PFM_M2351 | tests-mbed_drivers-lp_timeout | Zero delay (attach_us)                            | 0      | 0      | SKIPPED | 0.0                |
+-------------------------+-------------------+-------------------------------+---------------------------------------------------+--------+--------+---------+--------------------+
mbedgt: test case results: 14 SKIPPED / 7 OK / 1 ERROR
mbedgt: completed in 255.85 sec

CC : @maclobdell @bentcooke @ccli8

@ciarmcom
Copy link
Member

ciarmcom commented Sep 7, 2018

ARM Internal Ref: MBOTRIAGE-1613

@ccli8
Copy link
Contributor

ccli8 commented Sep 10, 2018

#8029 would fix the failure.

@0xc0170
Copy link
Contributor

0xc0170 commented Sep 19, 2018

5.10.1 will contain the fix, already on master

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

4 participants