From nobody Thu May 19 14:26:08 2022 X-Original-To: freebsd-usb@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id C2D0A1AEC3A2 for ; Thu, 19 May 2022 14:30:13 +0000 (UTC) (envelope-from naddy@mips.inka.de) Received: from mail.inka.de (mail.inka.de [IPv6:2a04:c9c7:0:1073:217:a4ff:fe3b:e77c]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange ECDHE (P-256) server-signature RSA-PSS (4096 bits) server-digest SHA256) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 4L3shN63lrz4Yrq for ; Thu, 19 May 2022 14:30:12 +0000 (UTC) (envelope-from naddy@mips.inka.de) Received: from mips.inka.de (naddy@[127.0.0.1]) by mail.inka.de with uucp (rmailwrap 0.5) id 1nrhAD-00D4cI-Si; Thu, 19 May 2022 16:30:05 +0200 Received: from lorvorc.mips.inka.de (localhost [127.0.0.1]) by lorvorc.mips.inka.de (8.16.1/8.16.1) with ESMTP id 24JEQ8Tm027487 for ; Thu, 19 May 2022 16:26:08 +0200 (CEST) (envelope-from naddy@lorvorc.mips.inka.de) Received: (from naddy@localhost) by lorvorc.mips.inka.de (8.16.1/8.16.1/Submit) id 24JEQ8Qu027486 for freebsd-usb@freebsd.org; Thu, 19 May 2022 16:26:08 +0200 (CEST) (envelope-from naddy) Date: Thu, 19 May 2022 16:26:08 +0200 From: Christian Weisgerber To: freebsd-usb@freebsd.org Subject: Re: Yubikey uhid(4) problem? (PR 263995) Message-ID: References: List-Id: FreeBSD List-Archive: https://lists.freebsd.org/archives/freebsd-usb List-Help: List-Post: List-Subscribe: List-Unsubscribe: Sender: owner-freebsd-usb@freebsd.org X-BeenThere: freebsd-usb@freebsd.org MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: X-Rspamd-Queue-Id: 4L3shN63lrz4Yrq X-Spamd-Bar: - Authentication-Results: mx1.freebsd.org; dkim=none; dmarc=none; spf=none (mx1.freebsd.org: domain of naddy@mips.inka.de has no SPF policy when checking 2a04:c9c7:0:1073:217:a4ff:fe3b:e77c) smtp.mailfrom=naddy@mips.inka.de X-Spamd-Result: default: False [-1.59 / 15.00]; ARC_NA(0.00)[]; NEURAL_HAM_MEDIUM(-1.00)[-1.000]; FREEFALL_USER(0.00)[naddy]; FROM_HAS_DN(0.00)[]; TO_MATCH_ENVRCPT_ALL(0.00)[]; NEURAL_HAM_LONG(-1.00)[-1.000]; MIME_GOOD(-0.10)[text/plain]; PREVIOUSLY_DELIVERED(0.00)[freebsd-usb@freebsd.org]; TO_DN_NONE(0.00)[]; AUTH_NA(1.00)[]; RCPT_COUNT_ONE(0.00)[1]; RCVD_COUNT_THREE(0.00)[3]; MID_RHS_MATCH_FROMTLD(0.00)[]; NEURAL_HAM_SHORT(-0.49)[-0.490]; DMARC_NA(0.00)[inka.de]; MLMMJ_DEST(0.00)[freebsd-usb]; R_SPF_NA(0.00)[no SPF record]; FROM_EQ_ENVFROM(0.00)[]; R_DKIM_NA(0.00)[]; MIME_TRACE(0.00)[0:+]; ASN(0.00)[asn:202113, ipnet:2a04:c9c7::/32, country:DE]; RCVD_TLS_LAST(0.00)[]; SUBJECT_HAS_QUESTION(0.00)[] X-ThisMailContainsUnwantedMimeParts: N Hans Petter Selasky: > usbdump > > May help get the answer for that. > > Look for USB_ERR_TIMEOUT . Hmm. So here is the output when authentication hangs: $ doas usbdump -d0.2 -v 16:15:53.330564 usbus0.2 SUBM-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=64,IVAL=2 frame[0] WRITE 64 bytes 16:15:53.330570 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:15:53.331882 usbus0.2 DONE-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=0,IVAL=2,ERR=0 frame[0] WRITE 64 bytes ... and there it sits, until I terminate the ssh-sk-helper process. 16:16:15.485831 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=0,SLEN=0,IVAL=2,ERR=CANCELLED ^C 4 packets captured 1064 packets received by filter 0 packets dropped by kernel By comparison, here's a successful authentication: $ doas usbdump -d0.2 -v 16:16:38.644787 usbus0.2 SUBM-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=64,IVAL=2 frame[0] WRITE 64 bytes 16:16:38.644794 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.645917 usbus0.2 DONE-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=0,IVAL=2,ERR=0 frame[0] WRITE 64 bytes 16:16:38.646865 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.646879 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.647005 usbus0.2 SUBM-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=64,IVAL=2 frame[0] WRITE 64 bytes 16:16:38.647865 usbus0.2 DONE-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=0,IVAL=2,ERR=0 frame[0] WRITE 64 bytes 16:16:38.648862 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.648875 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.649860 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.649873 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.650862 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.650876 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.651864 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.651878 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:38.652305 usbus0.2 SUBM-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=256,IVAL=2 frame[0] WRITE 256 bytes 16:16:38.655916 usbus0.2 DONE-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=0,IVAL=2,ERR=0 frame[0] WRITE 256 bytes 16:16:38.884917 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:38.884933 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:39.171926 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:39.171942 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:39.459925 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:39.459941 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:39.746849 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:39.746864 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.033907 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.033924 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.321843 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.321857 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.608935 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.608951 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.867934 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.867951 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.868880 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.868896 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.869882 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.869898 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.870881 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.870897 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.871881 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=64,IVAL=2,ERR=0 frame[0] READ 64 bytes 16:16:40.871896 usbus0.2 SUBM-INTR-EP=00000084,SPD=FULL,NFR=1,SLEN=0,IVAL=2 frame[0] READ 64 bytes 16:16:40.872104 usbus0.2 SUBM-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=64,IVAL=2 frame[0] WRITE 64 bytes 16:16:40.872926 usbus0.2 DONE-INTR-EP=00000004,SPD=FULL,NFR=1,SLEN=0,IVAL=2,ERR=0 frame[0] WRITE 64 bytes 16:16:40.873622 usbus0.2 DONE-INTR-EP=00000084,SPD=FULL,NFR=0,SLEN=0,IVAL=2,ERR=CANCELLED ^C 44 packets captured 488 packets received by filter 0 packets dropped by kernel -- Christian "naddy" Weisgerber naddy@mips.inka.de