tests-mbed_hal-rtc_time test fails REALTEK_RTL8195AM only for ARM toolchain

[studavekar]

Description

tests-mbed_hal-rtc_time test fails on REALTEK_RTL8195AM

Target
REALTEK_RTL8195AM

Toolchain:
ARM

mbed-os sha:

master

Steps to reproduce

Use command run : mbed test -m REALTEK_RTL8195AM -t ARM -n tests-mbed_hal-rtc_time -v

Failure

        calling mbedhtrun: mbedhtrun -m REALTEK_RTL8195AM -p DUMMY:9600 -f "BUILD/tests/REALTEK_RTL8195AM/ARM/TESTS/mbed_hal/rtc_time/rtc_time.bin" -e "TESTS/host_tests" --grm raas_client:iron-gatekeeper:8000 -C 4 --sync 5 -P 180
mbedgt: mbed-host-test-runner: started
[1539210922.64][root]Generating grammar tables from /usr/lib/python2.7/lib2to3/Grammar.txt
[1539210922.66][root]Generating grammar tables from /usr/lib/python2.7/lib2to3/PatternGrammar.txt
[1539210922.72][HTST][INF] host test executor ver. 1.4.1
[1539210922.72][HTST][INF] copy image onto target... SKIPPED!
[1539210922.72][HTST][INF] starting host test process...
[1539210922.72][CONN][INF] starting connection process...
[1539210922.72][CONN][INF] notify event queue about extra 180 sec timeout for serial port pooling
[1539210922.72][CONN][INF] initializing global resource mgr listener...
[1539210922.72][HTST][INF] setting timeout to: 180 sec
[1539210922.77][GLRM][INF] remote resources initialization: remote(host=iron-gatekeeper, port=8000)
[1539210922.96][GLRM][INF] remote resources flashing with 'BUILD/tests/REALTEK_RTL8195AM/ARM/TESTS/mbed_hal/rtc_time/rtc_time.bin'...
[1539210972.31][GLRM][INF] opening connection to platform at baudrate='9600'
[1539210972.31][urllib3.connectionpool]Starting new HTTP connection (1): iron-gatekeeper:8000
[1539210972.50][urllib3.connectionpool]http://iron-gatekeeper:8000 "PUT /resource/460000000989d8d700000000000000000000000097969902/connect HTTP/1.1" 200 15
[1539210972.50][GLRM][INF] remote resources reset...
[1539210976.78][GLRM][TXD] mbedmbedmbedmbedmbedmbedmbedmbedmbedmbed
[1539210976.78][CONN][INF] sending up to 5 __sync packets (specified with --sync=5)
[1539210976.78][CONN][INF] sending preamble '24464abc-a5f2-43f8-a711-e45282b3efda'
[1539210976.79][GLRM][TXD] {{__sync;24464abc-a5f2-43f8-a711-e45282b3efda}}
================================<DAPLink:Overflow>
[1539210976.83][CONN][RXD] gcc version 4.8.3 (Realtek ASDK-4.8.3p1 Build 2003)
[1539210976.83][CONN][RXD]
=========================================================
Check boot type form eFuse
SPI Initial.83][CONN][RXD]
Image1 length: 0x4398, Image Addr: 0x10000bc8
Image1 Validate OK, Going jump to Image1
SDR Controller InitN][RXD]
[1539210981.79][CONN][INF] Reset the part and send in new preamble...
[1539210981.79][GLRM][INF] remote resources reset...
[1539210986.16][CONN][INF] resending new preamble 'be5fb74a-441c-4a5a-afe1-6f985d50d959' after 5.00 sec
[1539210986.16][GLRM][TXD] {{__sync;be5fb74a-441c-4a5a-afe1-6f985d50d959}}
================================<DAPLink:Overflow>
[1539210986.19][CONN][RXD]  gcc version 4.8.3 (Realtek ASDK-4.8.3p1 Build 2003)
[1539210986.19][CONN][RXD]
=========================================================
Check boot type form eFuse
SPI Initial.19][CONN][RXD]
Image1 length: 0x4398, Image Addr: 0x10000bc8
Image1 Validate OK, Going jump to Image1
SDR Controller InitN][RXD]
[1539210991.16][CONN][INF] Reset the part and send in new preamble...
[1539210991.16][GLRM][INF] remote resources reset...
[1539210995.38][CONN][INF] resending new preamble 'a80ba2a7-78db-4a1f-b692-d012fa7a931d' after 5.00 sec
[1539210995.39][GLRM][TXD] {{__sync;a80ba2a7-78db-4a1f-b692-d012fa7a931d}}
================================<DAPLink:Overflow>
[1539210995.47][CONN][RXD] 8.3 (Realtek ASDK-4.8.3p1 Build 2003)
[1539210995.47][CONN][RXD]
=========================================================
Check boot type form eFuse
SPI Initial.47][CONN][RXD]
Image1 length: 0x4398, Image Addr: 0x10000bc8
Image1 Validate OK, Going jump to Image1
SDR Controller InitN][RXD]
[1539211000.39][CONN][INF] Reset the part and send in new preamble...
[1539211000.39][GLRM][INF] remote resources reset...
[1539211004.61][CONN][INF] resending new preamble 'f1c6f883-5463-46ef-b3da-8b0dc6dd7eed' after 5.00 sec
[1539211004.62][GLRM][TXD] {{__sync;f1c6f883-5463-46ef-b3da-8b0dc6dd7eed}}
================================<DAPLink:Overflow>
[1539211004.68][CONN][RXD] on 4.8.3 (Realtek ASDK-4.8.3p1 Build 2003)
[1539211004.68][CONN][RXD]
=========================================================
Check boot type form eFuse
SPI Initial.68][CONN][RXD]
Image1 length: 0x4398, Image Addr: 0x10000bc8
Image1 Validate OK, Going jump to Image1
SDR Controller InitN][RXD]
[1539211009.62][CONN][INF] Reset the part and send in new preamble...
[1539211009.62][GLRM][INF] remote resources reset...
[1539211013.94][CONN][INF] resending new preamble '3e65fa3a-036d-47f2-a2f6-61fd73368aac' after 5.00 sec
[1539211013.94][GLRM][TXD] {{__sync;3e65fa3a-036d-47f2-a2f6-61fd73368aac}}
[1539211013.95][urllib3.connectionpool]Starting new HTTP connection (1): iron-gatekeeper:8000
[1539211015.12][urllib3.connectionpool]http://iron-gatekeeper:8000 "PUT /resource/460000000989d8d700000000000000000000000097969902/disconnect HTTP/1.1" 200 2
[1539211015.12][urllib3.connectionpool]Starting new HTTP connection (1): iron-gatekeeper:8000
[1539211020.40][urllib3.connectionpool]http://iron-gatekeeper:8000 "PUT /resource/460000000989d8d700000000000000000000000097969902/release HTTP/1.1" 200 66
[1539211020.40][HTST][ERR] None
[1539211020.40][HTST][WRN] stopped to consume events due to __notify_sync_failed event
[1539211020.40][HTST][INF] __exit_event_queue received
[1539211020.40][HTST][INF] test suite run finished after 97.68 sec...
[1539211020.40][HTST][INF] CONN exited with code: 0
[1539211020.40][HTST][INF] No events in queue
[1539211020.40][HTST][INF] host test result() call skipped, received: sync_failed
[1539211020.40][HTST][WRN] missing __exit event from DUT
[1539211020.40][HTST][INF] calling blocking teardown()
[1539211020.40][HTST][INF] teardown() finished
[1539211020.40][HTST][INF] {{result;sync_failed}}
mbedgt: retry mbedhtrun 1/1
mbedgt: ['mbedhtrun', '-m', 'REALTEK_RTL8195AM', '-p', 'DUMMY:9600', '-f', u'"BUILD/tests/REALTEK_RTL8195AM/ARM/TESTS/mbed_hal/rtc_time/rtc_time.bin"', '-e', u'"TESTS/host_tests"', '--grm', 'raas_client:iron-gatekeeper:8000', '-C', '4', '--sync', '5', '-P', '180'] failed after 1 count
mbedgt: checking for GCOV data...
mbedgt: mbed-host-test-runner: stopped and returned 'SYNC_FAILED'
mbedgt: test case summary event not found
        no test case report present, assuming test suite to be a single test case!
        test suite: tests-mbed_hal-rtc_time
        test case: tests-mbed_hal-rtc_time
mbedgt: test on hardware with target id: DUMMY
mbedgt: test suite 'tests-mbed_hal-rtc_time' ......................................................... SYNC_FAILED in 98.04 sec
        test case: 'tests-mbed_hal-rtc_time' ......................................................... ERROR in 98.04 sec
mbedgt: test case summary: 0 passes, 1 failure
mbedgt: all tests finished!
mbedgt: shuffle seed: 0.3358972372
mbedgt: test suite report:
+-----------------------+-------------------+-------------------------+-------------+--------------------+-------------+
| target                | platform_name     | test suite              | result      | elapsed_time (sec) | copy_method |
+-----------------------+-------------------+-------------------------+-------------+--------------------+-------------+
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | SYNC_FAILED | 98.04              | default     |
+-----------------------+-------------------+-------------------------+-------------+--------------------+-------------+
mbedgt: test suite results: 1 SYNC_FAILED
mbedgt: test case report:
+-----------------------+-------------------+-------------------------+-------------------------+--------+--------+--------+--------------------+
| target                | platform_name     | test suite              | test case               | passed | failed | result | elapsed_time (sec) |
+-----------------------+-------------------+-------------------------+-------------------------+--------+--------+--------+--------------------+
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | tests-mbed_hal-rtc_time | 0      | 1      | ERROR  | 98.04              |
+-----------------------+-------------------+-------------------------+-------------------------+--------+--------+--------+--------------------+

[ ] Question
[ ] Enhancement
[X ] Bug

cc : @0xc0170

[mprse]

I'm looking into it.

[mprse]

REALTEK_RTL8195AM should be added to the list of impacted targets and in steps to reproduce section there is a typo in the test name.

[mprse]

Fix for this issue can be found here:
PR #8377

[studavekar]

@mprse have updated the description.

[M-ichae-l]

I believe that PR #8377 has solved this issue. We can close this issue.

[ciarmcom]

Issue header has missing or incorrectly filled in fields! Please fix.

[studavekar]

Closing it tests are passing thanks @mprse

1540313516.21][CONN][RXD] on: gcc version 4.8.3 (Realtek ASDK-4.8.3p1 Build 2003)
[1540313516.21][CONN][RXD]
=========================================================
Check boot type form eFuse
SPI Initial.21][CONN][RXD]
Image1 length: 0x4398, Image Addr: 0x10000bc8
Image1 Validate OK, Going jump to Image1
SDR Controller InitN][RXD]
mbedmbedmbedmbedmbedmbedmbedmbed
[1540313517.32][CONN][RXD] >>> Running 6 test cases...
[1540313517.32][HTST][INF] sync KV found, uuid=b3a019f1-9400-4aa9-8839-6b4b0bde9de6, timestamp=1540313517.323582
[1540313517.32][CONN][INF] found SYNC in stream: {{__sync;b3a019f1-9400-4aa9-8839-6b4b0bde9de6}} it is #0 sent, queued...
[1540313517.32][HTST][INF] DUT greentea-client version: 1.3.0
[1540313517.32][CONN][INF] found KV pair in stream: {{__version;1.3.0}}, queued...
[1540313517.32][HTST][INF] setting timeout to: 20 sec
[1540313517.32][CONN][INF] found KV pair in stream: {{__timeout;20}}, queued...
[1540313517.32][HTST][INF] host test class: '<class 'mbed_host_tests.host_tests.default_auto.DefaultAuto'>'
[1540313517.32][HTST][INF] host test setup() call...
[1540313517.32][HTST][INF] CALLBACKs updated
[1540313517.32][HTST][INF] host test detected: default_auto
[1540313517.32][CONN][INF] found KV pair in stream: {{__host_test_name;default_auto}}, queued...
[1540313517.32][CONN][INF] found KV pair in stream: {{__testcase_count;6}}, queued...
[1540313517.33][CONN][INF] found KV pair in stream: {{__testcase_name;test is leap year - RTC leap years full support}}, queued...
[1540313517.33][CONN][INF] found KV pair in stream: {{__testcase_name;test is leap year - RTC leap years partial support}}, queued...
[1540313517.42][CONN][RXD]
[1540313517.42][CONN][RXD] >>> Running case #1: 'test is leap year - RTC leap years full support'...
[1540313517.42][CONN][INF] found KV pair in stream: {{__testcase_name;test make time boundary values - RTC leap years full support}}, queued...
[1540313517.42][CONN][INF] found KV pair in stream: {{__testcase_name;test make time boundary values - RTC leap years partial support}}, queued...
[1540313517.42][CONN][INF] found KV pair in stream: {{__testcase_name;test make time - invalid param}}, queued...
[1540313517.42][CONN][INF] found KV pair in stream: {{__testcase_name;test local time - invalid param}}, queued...
[1540313517.42][CONN][INF] found KV pair in stream: {{__testcase_start;test is leap year - RTC leap years full support}}, queued...
[1540313517.52][CONN][RXD] >>> 'test is leap year - RTC leap years full support': 1 passed, 0 failed
[1540313517.52][CONN][RXD]
[1540313517.52][CONN][RXD] >>> Running case #2: 'test is leap year - RTC leap years partial support'...
[1540313517.52][CONN][INF] found KV pair in stream: {{__testcase_finish;test is leap year - RTC leap years full support;1;0}}, queued...
[1540313517.52][CONN][INF] found KV pair in stream: {{__testcase_start;test is leap year - RTC leap years partial support}}, queued...
[1540313517.52][CONN][INF] found KV pair in stream: {{__testcase_finish;test is leap year - RTC leap years partial support;1;0}}, queued...
[1540313517.62][CONN][RXD] >>> 'test is leap year - RTC leap years partial support': 1 passed, 0 failed
[1540313517.62][CONN][RXD]
[1540313517.62][CONN][RXD] >>> Running case #3: 'test make time boundary values - RTC leap years full support'...
[1540313517.62][CONN][INF] found KV pair in stream: {{__testcase_start;test make time boundary values - RTC leap years full support}}, queued...
[1540313517.62][CONN][INF] found KV pair in stream: {{__testcase_finish;test make time boundary values - RTC leap years full support;1;0}}, queued...
[1540313517.73][CONN][RXD] >>> 'test make time boundary values - RTC leap years full support': 1 passed, 0 failed
[1540313517.73][CONN][RXD]
[1540313517.73][CONN][RXD] >>> Running case #4: 'test make time boundary values - RTC leap years partial support'...
[1540313517.73][CONN][INF] found KV pair in stream: {{__testcase_start;test make time boundary values - RTC leap years partial support}}, queued...
[1540313517.73][CONN][INF] found KV pair in stream: {{__testcase_finish;test make time boundary values - RTC leap years partial support;1;0}}, queued...
[1540313517.83][CONN][RXD] >>> 'test make time boundary values - RTC leap years partial support': 1 passed, 0 failed
[1540313517.83][CONN][RXD]
[1540313517.83][CONN][RXD] >>> Running case #5: 'test make time - invalid param'...
[1540313517.83][CONN][RXD] >>> 'test make time - invalid param': 1 passed, 0 failed
[1540313517.83][CONN][RXD]
[1540313517.83][CONN][RXD] >>> Running case #6: 'test local time - invalid param'...
[1540313517.83][CONN][INF] found KV pair in stream: {{__testcase_start;test make time - invalid param}}, queued...
[1540313517.83][CONN][INF] found KV pair in stream: {{__testcase_finish;test make time - invalid param;1;0}}, queued...
[1540313517.83][CONN][INF] found KV pair in stream: {{__testcase_start;test local time - invalid param}}, queued...
[1540313517.94][CONN][RXD] >>> 'test local time - invalid param': 1 passed, 0 failed
[1540313517.94][CONN][RXD]
[1540313517.94][CONN][RXD] >>> Test cases: 6 passed, 0 failed
[1540313517.94][CONN][RXD] {{__cpu_info        up time;0}}
[1540313517.94][CONN][RXD] {{__cpu_info     sleep time;0}}
[1540313517.94][CONN][INF] found KV pair in stream: {{__testcase_finish;test local time - invalid param;1;0}}, queued...
[1540313517.94][CONN][INF] found KV pair in stream: {{__testcase_summary;6;0}}, queued...
[1540313517.94][HTST][ERR] orphan event in main phase: {{max_heap_usage;0}}, timestamp=1540313517.939214
[1540313517.94][CONN][INF] found KV pair in stream: {{max_heap_usage;0}}, queued...
[1540313517.94][HTST][ERR] orphan event in main phase: {{reserved_heap;378084}}, timestamp=1540313517.939217
[1540313517.94][CONN][INF] found KV pair in stream: {{reserved_heap;378084}}, queued...
[1540313517.94][HTST][ERR] orphan event in main phase: {{__thread_info;"0x03000a348",592,4096}}, timestamp=1540313517.939219
[1540313517.94][CONN][INF] found KV pair in stream: {{__thread_info;"0x03000a348",592,4096}}, queued...
[1540313517.94][HTST][ERR] orphan event in main phase: {{__thread_info;"0x030007f04",64,512}}, timestamp=1540313517.939222
[1540313517.94][CONN][INF] found KV pair in stream: {{__thread_info;"0x030007f04",64,512}}, queued...
[1540313517.94][HTST][ERR] orphan event in main phase: {{__thread_info;"0x030007f48",80,768}}, timestamp=1540313517.939225
[1540313517.94][CONN][INF] found KV pair in stream: {{__thread_info;"0x030007f48",80,768}}, queued...
[1540313518.04][CONN][RXD] {{__cpu_info deepsleep time;0}}
[1540313518.04][CONN][RXD] {{__cpu_info  %  sleep/deep;0;0}}
[1540313518.04][CONN][INF] found KV pair in stream: {{end;success}}, queued...
[1540313518.04][HTST][INF] __notify_complete(True)
[1540313518.04][HTST][INF] __exit_event_queue received
[1540313518.04][HTST][INF] test suite run finished after 0.72 sec...
[1540313518.04][CONN][INF] found KV pair in stream: {{__exit;0}}, queued...
[1540313518.04][CONN][INF] received special event '__host_test_finished' value='True', finishing
[1540313518.04][urllib3.connectionpool]Starting new HTTP connection (1): iron-gatekeeper:8000
[1540313518.08][urllib3.connectionpool]http://iron-gatekeeper:8000 "PUT /resource/460000000989d8d700000000000000000000000097969902/disconnect HTTP/1.1" 200 2
[1540313518.08][urllib3.connectionpool]Starting new HTTP connection (1): iron-gatekeeper:8000
[1540313523.32][urllib3.connectionpool]http://iron-gatekeeper:8000 "PUT /resource/460000000989d8d700000000000000000000000097969902/release HTTP/1.1" 200 66
[1540313523.32][HTST][INF] CONN exited with code: 0
[1540313523.32][HTST][INF] Some events in queue
[1540313523.33][HTST][INF] stopped consuming events
[1540313523.33][HTST][INF] host test result() call skipped, received: True
[1540313523.33][HTST][WRN] missing __exit event from DUT
[1540313523.33][HTST][INF] calling blocking teardown()
[1540313523.33][HTST][INF] teardown() finished
[1540313523.33][HTST][INF] {{result;success}}
mbedgt: checking for GCOV data...
mbedgt: mbed-host-test-runner: stopped and returned 'OK'
mbedgt: test on hardware with target id: DUMMY
mbedgt: test suite 'tests-mbed_hal-rtc_time' ......................................................... OK in 61.59 sec
        test case: 'test is leap year - RTC leap years full support' ................................. OK in 0.10 sec
        test case: 'test is leap year - RTC leap years partial support' .............................. OK in 0.00 sec
        test case: 'test local time - invalid param' ................................................. OK in 0.11 sec
        test case: 'test make time - invalid param' .................................................. OK in 0.00 sec
        test case: 'test make time boundary values - RTC leap years full support' .................... OK in 0.00 sec
        test case: 'test make time boundary values - RTC leap years partial support' ................. OK in 0.00 sec
mbedgt: test case summary: 6 passes, 0 failures
mbedgt: all tests finished!
mbedgt: shuffle seed: 0.6819574942
mbedgt: test suite report:
+-----------------------+-------------------+-------------------------+--------+--------------------+-------------+
| target                | platform_name     | test suite              | result | elapsed_time (sec) | copy_method |
+-----------------------+-------------------+-------------------------+--------+--------------------+-------------+
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | OK     | 61.59              | default     |
+-----------------------+-------------------+-------------------------+--------+--------------------+-------------+
mbedgt: test suite results: 1 OK
mbedgt: test case report:
+-----------------------+-------------------+-------------------------+-----------------------------------------------------------------+--------+--------+--------+--------------------+
| target                | platform_name     | test suite              | test case                                                       | passed | failed | result | elapsed_time (sec) |
+-----------------------+-------------------+-------------------------+-----------------------------------------------------------------+--------+--------+--------+--------------------+
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test is leap year - RTC leap years full support                 | 1      | 0      | OK     | 0.1                |
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test is leap year - RTC leap years partial support              | 1      | 0      | OK     | 0.0                |
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test local time - invalid param                                 | 1      | 0      | OK     | 0.11               |
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test make time - invalid param                                  | 1      | 0      | OK     | 0.0                |
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test make time boundary values - RTC leap years full support    | 1      | 0      | OK     | 0.0                |
| REALTEK_RTL8195AM-ARM | REALTEK_RTL8195AM | tests-mbed_hal-rtc_time | test make time boundary values - RTC leap years partial support | 1      | 0      | OK     | 0.0                |
+-----------------------+-------------------+-------------------------+-----------------------------------------------------------------+--------+--------+--------+--------------------+