Hello ,
The test of CPS/SEN/SPE/BI-04-C [Procedure Already In Progress] failed.
While this test, the tester sometimes received the unexpected ATT Disconnect which our product might send.
Could you tell me how to stop the ATT Disconnect?
The test-log
+0 ms Initialize Test Case: Build date: Jul 18 2018, 09:31:58 +0 ms Start Test Case: CPS/SEN/SPE/BI-04-C +0 ms Message: PTS Log started on Tuesday, October 02, 2018, 16:34:18 +0 ms Message: PTS: v7.3.0 Build 40 +0 ms Message: OS: Microsoft Windows NT 6.1.7601 Service Pack 1 +0 ms Message: OS Name: Microsoft Windows 7 Home Premium +4009 ms Preliminary Verdict: PASS +4056 ms Send Event: HCI!HCI_RESET +4180 ms Receive Event: HCI?HCI_RESET_COMPLETE_EVENT{ status=HCI_OK } +4180 ms Preliminary Verdict: PASS +4180 ms Send Event: HCI!HCI_SET_EVENT_MASK{ eventMask=0x3FFFFFFFFFFFFFFF } +4180 ms Receive Event: HCI?HCI_SET_EVENT_MASK_COMPLETE_EVENT{ status=HCI_OK } +4180 ms Preliminary Verdict: PASS +4180 ms Send Event: HCI!HCI_READ_LOCAL_VERSION_INFORMATION +4180 ms Receive Event: HCI?HCI_READ_LOCAL_VERSION_INFORMATION_COMPLETE_EVENT{ status=HCI_OK, hciVersion=HCI_VERSION_4_0, hciRevision=0x2031, lmpVersion=0x06, manufacturerName=0x000A, lmpSubversion=0x2031 } +4180 ms Preliminary Verdict: PASS +4180 ms Send Event: HCI!HCI_READ_BD_ADDR +4196 ms Receive Event: HCI?HCI_READ_BD_ADDR_COMPLETE_EVENT{ status=HCI_OK, bdAddress=0x001BDC0808A3 } +4196 ms Preliminary Verdict: PASS +4196 ms Send Event: HCI!HCI_LE_SET_EVENT_MASK{ leEventMask=0x000000000000001F } +4196 ms Receive Event: HCI?HCI_LE_SET_EVENT_MASK_COMPLETE_EVENT{ status=HCI_OK } +4196 ms Preliminary Verdict: PASS +4196 ms Send Event: HCI!HCI_WRITE_LE_HOST_SUPPORTED{ leSupportedHost=HCI_LE_SUPPORTED_HOST_ENABLED, simultaneousLeHost=HCI_SIMULTANEOUS_LE_HOST_ENABLED } +4196 ms Receive Event: HCI?HCI_WRITE_LE_HOST_SUPPORTED_COMPLETE_EVENT{ status=HCI_OK } +4196 ms Preliminary Verdict: PASS +4196 ms Send Event: HCI!HCI_LE_CLEAR_WHITE_LIST +4196 ms Receive Event: HCI?HCI_LE_CLEAR_WHITE_LIST_COMPLETE_EVENT{ status=HCI_OK } +4196 ms Preliminary Verdict: PASS +4196 ms Send Event: HCI!HCI_WRITE_SIMPLE_PAIRING_MODE{ simplePairingMode=HCI_SIMPLE_PAIRING_ENABLED } +4212 ms Receive Event: HCI?HCI_WRITE_SIMPLE_PAIRING_MODE_COMPLETE_EVENT{ status=HCI_OK } +4212 ms Preliminary Verdict: PASS +4212 ms Preliminary Verdict: PASS +4212 ms Send Event: HCI!HCI_LE_ADD_DEVICE_TO_WHITE_LIST{ addressType=HCI_LE_PUBLIC_DEVICE_ADDRESS, address=0xC58DCE70012F } +4212 ms Receive Event: HCI?HCI_LE_ADD_DEVICE_TO_WHITE_LIST_COMPLETE_EVENT{ status=HCI_OK } +4212 ms Preliminary Verdict: PASS +4212 ms Send Event: HCI!HCI_LE_ADD_DEVICE_TO_WHITE_LIST{ addressType=HCI_LE_RANDOM_DEVICE_ADDRESS, address=0xC58DCE70012F } +4212 ms Receive Event: HCI?HCI_LE_ADD_DEVICE_TO_WHITE_LIST_COMPLETE_EVENT{ status=HCI_OK } +4212 ms Preliminary Verdict: PASS +4212 ms Preliminary Verdict: PASS +4212 ms Preliminary Verdict: PASS +4212 ms Send Event: HCI!HCI_LE_SET_SCAN_PARAMETERS{ leScanType=HCI_LE_ACTIVE_SCANNING, leScanInterval=0x001E, leScanWindow=0x001E, ownAddressType=HCI_LE_PUBLIC_DEVICE_ADDRESS, scanningFilterPolicy=HCI_LE_ACCEPT_WHITE_LIST_ADVERTISING_PACKETS } +4212 ms Receive Event: HCI?HCI_LE_SET_SCAN_PARAMETERS_COMPLETE_EVENT{ status=HCI_OK } +4212 ms Preliminary Verdict: PASS +4227 ms Send Event: HCI!HCI_LE_SET_SCAN_ENABLE{ leScanEnable=HCI_LE_SCAN_ENABLE, filterDuplicates=HCI_LE_DUPLICATE_FILTERING_DISABLE } +5506 ms Preliminary Verdict: PASS +5506 ms Preliminary Verdict: PASS +7519 ms Preliminary Verdict: PASS +7519 ms Preliminary Verdict: PASS +9516 ms Preliminary Verdict: PASS +9516 ms Send Event: HCI!HCI_LE_SET_SCAN_ENABLE{ leScanEnable=HCI_LE_SCAN_DISABLE, filterDuplicates=HCI_LE_DUPLICATE_FILTERING_DISABLE } +9531 ms Preliminary Verdict: PASS +9562 ms Send Event: ATT!ATT_CONNECT_REQ{ peerAddr=0xC58DCE70012F, peerAddrType=HCI_LE_RANDOM_DEVICE_ADDRESS, ownAddrType=HCI_LE_PUBLIC_DEVICE_ADDRESS, minInterval=0x0032, maxInterval=0x0046, latency=0x0000 } +13556 ms Receive Event: SEC?SEC_LE_REMOTE_CSRK_REQUEST_REQ{ peerAddr=0xC58DCE70012F, peerAddrType=HCI_LE_RANDOM_DEVICE_ADDRESS } +13556 ms Receive Event: ATT?ATT_CONNECT_RSP{ bdAddr=0xC58DCE70012F, peerAddrType=HCI_LE_RANDOM_DEVICE_ADDRESS, lcid=0x0040, status=HCI_OK } +13556 ms Receive Event: HCI?HCI_LE_CONNECTION_COMPLETE_EVENT{ status=HCI_OK, connectionHandle=0x004D, role=HCI_ROLE_MASTER, peerAddressType=HCI_LE_RANDOM_DEVICE_ADDRESS, peerAddress=C58DCE70012F, connInterval=0x0046, connLatency=0x0000, supervisionTimeout=0x07D0, clockAccuracy=HCI_LE_CLOCK_ACCURACY_500PPM } +13556 ms Send Event: SEC!SEC_LE_REMOTE_CSRK_REQUEST_RSP{ peerAddr=0xC58DCE70012F, peerAddrType=HCI_LE_RANDOM_DEVICE_ADDRESS, accept=FALSE, keyType=HCI_UNAUTHENTICATED_COMBINATION_KEY, csrk=0x00000000000000000000000000000000, signCounter=0x00000000 } +13556 ms Preliminary Verdict: PASS +13556 ms Verdict Description: Connection setup successfully. +13556 ms Pass: Tester successfully initiated connection. +13556 ms Verdict Description: Tester successfully initiated connection. +13556 ms Verdict Description: LE Security not initiated due to TSPX_security_enabled value. +13556 ms Send Event: ATT!ATT_FIND_BY_TYPE_VALUE_REQ{ lcid=0x0040, startingHandle=0x0001, endingHandle=0xFFFF, attributeType=0x2800, length=0x0002, PrimaryService=0x1818 } +13681 ms Receive Event: ATT?ATT_FIND_BY_TYPE_VALUE_RSP{ lcid=0x0040, HandleRange={ { foundAttributeHandle=0x000F, endFoundHandle=0x0019 } } } +13852 ms Receive Event: ATT?ATT_FIND_BY_TYPE_VALUE_CFM{ lcid=0040, result=0x0100, errorHandle=0x0000, HandleRanges={ { foundAttributeHandle=0x000F, endFoundHandle=0x0019, } } } +13852 ms Preliminary Verdict: PASS +13852 ms Verdict Description: Discover Primary Service by Service UUID Request completed successfully. +13852 ms Pass: The IUT successfully returned the UUID for Cycling Power +13852 ms Verdict Description: The IUT successfully returned the UUID for Cycling Power +13852 ms Send Event: ATT!ATT_READ_BY_TYPE_REQ{ lcid=0x0040, startingHandle=0x000F, endingHandle=0x0019, attributeTypeSize=0x02, attributeType=0x2803 } +14024 ms Receive Event: ATT?ATT_READ_BY_TYPE_RSP{ { handle=0x0010 value{ Properties=0x10, Handle=0x0011, UUID=0x2A63 } } { handle=0x0013 value{ Properties=0x02, Handle=0x0014, UUID=0x2A65 } } { handle=0x0015 value{ Properties=0x02, Handle=0x0016, UUID=0x2A5D } } } +14196 ms Receive Event: ATT?ATT_READ_BY_TYPE_RSP{ { handle=0x0017 value{ Properties=0x28, Handle=0x0018, UUID=0x2A66 } } } +14383 ms Receive Event: ATT?ATT_READ_BY_TYPE_CFM{ lcid=0x0040, attributeType=0x2803, attributeTypeSize=0x02, result=0x0100, errorHandle=0x0000 } +14383 ms Receive Event: ATT?ATT_READ_BY_TYPE_CFM{ { handle=0x0010 value{ Properties=0x10, Handle=0x0011, UUID=0x2A63 } } { handle=0x0013 value{ Properties=0x02, Handle=0x0014, UUID=0x2A65 } } { handle=0x0015 value{ Properties=0x02, Handle=0x0016, UUID=0x2A5D } } { handle=0x0017 value{ Properties=0x28, Handle=0x0018, UUID=0x2A66 } } } +14383 ms Preliminary Verdict: PASS +14383 ms Verdict Description: Discover All Characteristics of Service Request completed successfully. +14383 ms Send Event: ATT!ATT_FIND_INFO_REQ{ lcid=0x0040, startingHandle=0x0012, endingHandle=0x0012 } +14554 ms Receive Event: ATT?ATT_FIND_INFO_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +14554 ms Receive Event: ATT?ATT_FIND_INFO_RSP{ { handle=0x0012, uuid=0x2902 } } +14554 ms Receive Event: ATT?ATT_FIND_INFO_CFM{ { handle=0x0012, uuid=0x2902, } } +14554 ms Preliminary Verdict: PASS +14554 ms Verdict Description: Discover All Characteristic Descriptors Request completed successfully. +14554 ms Send Event: ATT!ATT_FIND_INFO_REQ{ lcid=0x0040, startingHandle=0x0019, endingHandle=0x0019 } +14726 ms Receive Event: ATT?ATT_FIND_INFO_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +14726 ms Receive Event: ATT?ATT_FIND_INFO_RSP{ { handle=0x0019, uuid=0x2902 } } +14726 ms Receive Event: ATT?ATT_FIND_INFO_CFM{ { handle=0x0019, uuid=0x2902, } } +14726 ms Preliminary Verdict: PASS +14726 ms Verdict Description: Discover All Characteristic Descriptors Request completed successfully. +14726 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0019, length=0x0002, value{ Properties=0x0000 } } +14991 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +14991 ms Preliminary Verdict: PASS +17987 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0019, length=0x0002, value{ Properties=0x0002 } } +18143 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +18143 ms Preliminary Verdict: PASS +18143 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0012, length=0x0002, value{ Properties=0x0000 } } +18314 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +18314 ms Preliminary Verdict: PASS +21309 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0012, length=0x0002, value{ Properties=0x0001 } } +21543 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +21543 ms Preliminary Verdict: PASS +21559 ms Send Event: ATT!ATT_READ_REQ{ lcid=0x0040, attributeHandle=0x0014 } +21731 ms Receive Event: ATT?ATT_READ_CFM{ lcid=0x0040, length=0x0004, result=0x0100, errorHandle=0x0000, value{ CyclingPowerFeature=0x0019020A } } +21731 ms Preliminary Verdict: PASS +21731 ms Verdict Description: Read Request completed successfully. +21731 ms Preliminary Verdict: PASS +21731 ms Pass: The data received for 'Cycling Power Feature' conforms to the schema +21731 ms Verdict Description: The data received for 'Cycling Power Feature' conforms to the schema +21777 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0018, length=0x0001, value{ OpCode=0x0C, CyclingPowerVectorControlValue=0x0063 } } +21902 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +21902 ms Preliminary Verdict: PASS +22511 ms Receive Event: ATT?ATT_HANDLE_VALUE_NOTIFICATION_REQ{ lcid=0x0040, attributeHandle=0x0011, length=0x000A, attributeValue=0x00000000005B0000002C } +22511 ms Receive Event: ATT?ATT_HANDLE_VALUE_IND{ lcid=0x0040, attributeHandle=0x0018, length=0x0005, attributeValue=0x00FFFF010C20 } +22511 ms Preliminary Verdict: PASS +22511 ms Pass: The value for 'Cycling Power Control Point' sent by the IUT was correctly formatted. +22511 ms Verdict Description: The value for 'Cycling Power Control Point' sent by the IUT was correctly formatted. +22511 ms Send Event: ATT!ATT_WRITE_REQ{ lcid=0x0040, attributeHandle=0x0018, length=0x0001, value{ OpCode=0x0C, CyclingPowerVectorControlValue=0x006E } } +22682 ms Receive Event: ATT?ATT_WRITE_CFM{ lcid=0x0040, result=0x0100, errorHandle=0x0000 } +22682 ms Preliminary Verdict: PASS +22682 ms Verdict Description: Write Request completed successfully. +43383 ms Receive Event: ATT?ATT_DISCONNECT_REQ{ lcid=0x0040 } +43383 ms Verdict Description: Unexpected ATT Disconnect received. +43383 ms Receive Event: HCI?HCI_LE_DISCONNECTION_COMPLETE_EVENT{ status=HCI_OK, connectionHandle=0x004D, reason=HCI_CONNECTION_TIMEOUT } +43383 ms Verdict Description: Unexpected ATT Disconnect received. +43383 ms Inconclusive: Non-passing verdict returned from APICOM, see log for more details. +43383 ms Verdict Description: Non-passing verdict returned from APICOM, see log for more details. +45021 ms Final Verdict: INCONC +45021 ms Encrypted Verdict: A1#ZWY4MzQ5MDg3MmQwNTIxOGQzMzQ5YjY2ODA5M2MzNjQ5ZDY4NmY0Yjc2MzIzM2VkNTUyNTFhNGU4Mjg1MjI0NA==#S7tk81R4UBM+y2jy+jTqSwIFbmqou/dBe0rGBnSk5Rd1NLMNsmJ6x0apjF5t71xW +45021 ms Test Case Ended: CPS/SEN/SPE/BI-04-C
Best regards,
Kiyoshi Iwai