WinSCP mit Fritz!Box ab 06.50 geht nicht mehr richtig

[eisbaerin]

Hallo, wer kennt sich mit WinSCP und dropbear und SFTP-Server aus?

Also im groben geht alles noch, aber:

Bis FW 06.30 ging alles und das seit mindenstens (das ist nicht falsch geschrieben, das ist unsere innerfamiliäre Steigerungsform ) FW 04.30.

Seit der FW 06.50 auf der FB (7412 und 7490) kann ich mit WinSCP (aber über SFTP) nicht mehr die "C-Dateien" wie z.B. ar7.cfg anschauen, editieren oder kopieren.
Das neuste WinSCP (5.7.6) habe ich schon probiert, geht auch nicht.
Kann es an den dropbearmulti oder sftp-server binaries liegen? Da habe ich bis Dropbearmulti.0.71 alles probiert.
Oder muß man im WinSCP etwas besonderes einstellen?
Oder geht es gar nicht mehr?
Ursache ist doch das neue SQFS4? Oder liege ich da falsch?
Obwohl andere normale Dateien lassen sich damit noch editieren, nur diese C-Dateien nicht.
Als Fehlermeldung kommt:

Copying files from remote side failed.

General failure (server should provide error description)
Error code: 4
Error message from server: Failure
Request code: 5

 

[PeterPawn]

Ich hatte das im anderen Thread auch gelesen und wollte dort die Antwort hinter einen eigenen Test verschieben, um keinen Unsinn zu schreiben ... zu diesem Test komme ich gerade nicht, daher nur "theoretische Überlegungen".

Das SquashFS sollte damit nichts zu tun haben ... am Ende nicht einmal der neuere Kernel.

Der entscheidende Unterschied (aus Programmsicht) zwischen einem char-Device, einem block-Device und einer regulären Datei ist die beim char-Device fehlende Möglichkeit, die "Länge" der dort bei Leseoperationen vorhandenen Daten anders als durch das sequentielle Lesen zu bestimmen. Das heißt am Ende, daß diverse Dateioperationen (lseek(), tell()) bei char-Devices nicht funktionieren und ggf. mit einem Fehler quittiert werden. Das liegt in erster Linie daran, daß die "historischen" char-Devices z.B. das Keyboard und andere HIDs waren, wo naturgemäß zwar die Information vorliegt, daß/ob im Moment weitere Zeichen gelesen werden können (wenn der Benutzer eine Taste drückt), aber keine "Vorhersage" erfolgen kann, wieviele Tasten der Benutzer noch drücken wird.

Wenn also ein Programm so eine Operation zur Ermittlung der Größe einer Datei auf ein char-Device anwendet, wird das in der Regel fehlschlagen. Bei der Konstellation mit WinSCP und einer FRITZ!Box mit SSH sollte eigentlich auf die Box mit dem SFTP-Protokoll zugegriffen werden (schreibst Du ja auch) und da dieses Protokoll keinen Unterschied zwischen char- und block-Devices und regulären Dateien macht/kennt, ist es vielleicht am Ende der SFTP-Server, der hier das Problem darstellt.

Wie gesagt, alles nur Theorie ... einen eigenen Test habe ich nicht gemacht.

Ich würde als erstes mal anstelle von WinSCP einen anderen SFTP-Client nehmen und schauen, ob sich die char-Devices damit auslesen lassen. Wenn der SFTP-Server (ob der sich geändert hat, weiß ich ja nicht so richtig anhand der Beschreibung) aus irgendeinem Grund von sich aus vorher die Größe der Datei ermitteln will (ggf. aber auch nur, weil der Client von ihm diese Größe wissen will, um irgendwelchen "progress" anzuzeigen), dann dürfte das schiefgehen und dann könnte wiederum der Client (also WinSCP) etwas an der Behandlung dieses Fehlers geändert haben.

Als "Ausschlußtest" kann man dann vielleicht sogar mit einer älteren WinSCP-Version noch einmal probieren ... wenn dort solche Fehler beim Ermitteln der Größe "weggedrückt" wurden, dann läßt sich das ja vielleicht sogar irgendwo einstellen? Vielleicht auch, daß vorher keine Größe ermittelt werden soll bei so einem Transfer (der ja im Hintergrund abläuft)? Vielleicht liest ja auch WinSCP aus den Listings den "node type" aus (oder kann entsprechend eingestellt werden) und verzichtet für char-Devices auf die vorherige Ermittlung der Dateigröße?

Alles mehr Fragen als Antworten, daher wollte ich das selbst testen ... aber vielleicht helfen ja auch die o.a. Hinweise schon bei der Eingrenzung des Problems.

 

[andiling]

Poste doch mal das Server Log zu...

 

[eisbaerin]

Erst mal nur schnell das log:

Mar 8 17:59:34 fritz auth.err sftp-server6[3218]: error: process_read: seek failed

 

[andiling]

Die Datei kann wohl nicht gelesen werden...

Setze mal das Level auf mind. DEBUG1, damit man mehr sieht, insbesondere errno.

 

[PeterPawn]

Ein "seek" (POSIX: lseek(), siehe #2) funktioniert auf einem char-Device (man könnte schreiben "per Definition", es gäbe aber Möglichkeiten für Ausnahmen) nicht ... warum das so ist, habe ich versucht in #2 zu erläutern.

http://doc.inver.sk/linux/programming/node7.html

http://pubs.opengroup.org/onlinepubs/009695399/functions/lseek.html

 

[eisbaerin]

Setze mal das Level auf mind. DEBUG1

Einfacher gesagt als getan. Wenn ich den syslogd mit -l 1 bis 6 starte, dann ist er zwar bei ps zu sehen, aber es passiert nichts. Nur -l 7 oder 8 gehen. Ich habe verschiedene busyboxen ausprobiert. Was mach ich da falsch oder geht das gar nicht?

Ich habe mal "stat /var/flash/ar7.cfg" bei verschiedenen FB gemacht, vielleicht erkennt ja da ein Experte den Grund:

Bei denen geht es:
FB7170 mit 29.04.88:

  File: "/var/flash/ar7.cfg"
  Size: 0               Blocks: 0          IO Block: 4096   character special file
Device: ah/10d  Inode: 8003736     Links: 1     Device type: f0,71
Access: (0644/crw-r--r--)  Uid: (    0/    root)   Gid: (    0/    root)
Access: 2016-03-09 13:01:46.000000000
Modify: 2016-03-09 13:01:46.000000000
Change: 2016-03-09 13:01:46.000000000

FB7270 mit 54.06.05:

  File: /var/flash/ar7.cfg
  Size: 0               Blocks: 0          IO Block: 4096   character special file
Device: bh/11d  Inode: 193         Links: 1     Device type: f9,71
Access: (0644/crw-r--r--)  Uid: (    0/    root)   Gid: (    0/    root)
Access: 1970-01-01 01:00:04.000000000
Modify: 1970-01-01 01:00:04.000000000
Change: 1970-01-01 01:00:04.000000000

Bei denen geht es nicht mehr:
FB7412 mit 06.50:

  File: /var/flash/ar7.cfg
  Size: 0               Blocks: 0          IO Block: 4096   character special file
Device: ch/12d  Inode: 376         Links: 1     Device type: f3,71
Access: (0644/crw-r--r--)  Uid: (    0/    root)   Gid: (    0/    root)
Access: 1970-01-01 01:00:07.000000000
Modify: 1970-01-01 01:00:07.000000000
Change: 1970-01-01 01:00:07.000000000

FB7490 mit 06.51:

  File: /var/flash/ar7.cfg
  Size: [COLOR="#FF0000"]2048[/COLOR]            Blocks: [COLOR="#FF0000"]4[/COLOR]          IO Block: 4096   character special file
Device: 1f04h/7940d     Inode: 258         Links: 1     Device type: f3,71
Access: (0644/crw-r--r--)  Uid: (    0/    root)   Gid: (    0/    root)
Access: 1970-01-01 01:00:07.000000000
Modify: 1970-01-01 01:00:07.000000000
Change: 1970-01-01 01:00:07.000000000

Schon da wundert mich wieso die 7490 eine andere Size anzeigt.
Woran liegt das?
Das sehe ich auch im WinSCP über SFTP.
(mit SCP wird in der Spalte Size die tffs_minor Nummer angezeigt)


Die ältesten und auch die neusten binarys (Danke an andiling) habe ich probiert. Kein Erfolg.
Was kann ich noch tun?

 

[KunterBunter]

Da war es bei eisbaerin noch kein char-Device, wie sie in einem anderen Thread geschrieben hat.

 

[PeterPawn]

Solange der SFTP-Server kein "seek" ausführt bzw. einen Fehler dabei nicht an den SFTP-Client "reported", solange stört das ja nicht wirklich ... dann wird eben bei der Übetragung der Datei deren Inhalt sequentiell gelesen und wenn da nichts mehr ist (EOF), ist eben das Ende der Fahnenstange erreicht. Vielleicht liegt es ja auch daran, daß WinSCP anhand einer Angabe ungleich 0 im Listing den Schluß zieht, da könne man auch "seek" machen - das macht bei Dateilänge 0 natürlich keinen Sinn - und dabei eben "übersieht", daß diese Angaben zur Größe der Datei bei einem char-Device irrelevant sind. Wobei es dann eigentlich auch bei der 7412 klappen sollte, wenn die tatsächlich eine Dateilänge von 0 zurückgibt.

Einfach mal in das SFTP "hineinsehen" (der Client (WinSCP) kann das m.W. ausführllich protokollieren, was da zwischen Client und Server abgeht) und dort ebenfalls die Kommandos und Fehler vergleichen zwischen den verschiedenen Boxen. Der verwendete SFTP-Server kann ja schon aus Prinzip nicht derselbe sein, denn die 7270 ist ja LE im Gegensatz zu den anderen (bei der 7170 müßte ich auch erst nachsehen).

 

[eisbaerin]

Danke euch allen fürs mitdenken und suchen!

@KunterBunter: Ja, das ist nur die 7362SL. Die ist da eine Ausnahme. Bei der geht auch alles (noch). Deshalb erwähne ich sie hier auch nicht.

@PeterPawn: Ja, ich werde mal die Protokolle von WinSCP suchen und vergleichen.
Ich habe es gerade mal mit BitKinex versucht.
Nachdem ich die Hürde überwunden habe,
daß dort eine andere Form an Privatkeys gebraucht werden.
Da geht es noch nicht mal mit den alten FB.

@andiling: Das war vor kurzem im modfs Thread.
Warum soll es nach einem downgrade auf 06.30 nicht gehen?

 

[eisbaerin]

Hier die Protokolle vom WinSCP mit DEBUG1:

Hier geht es:
FB7270:

. 2016-03-09 16:04:31.442 File: "/var/flash/ar7.cfg"
. 2016-03-09 16:04:31.442 Copying "/var/flash/ar7.cfg" to local directory started.
. 2016-03-09 16:04:31.442 Ascii transfer mode selected.
. 2016-03-09 16:04:31.442 Opening remote file.
> 2016-03-09 16:04:31.442 Type: SSH_FXP_OPEN, Size: 35, Number: 262147
. 2016-03-09 16:04:31.442 Sent 39 bytes
. 2016-03-09 16:04:31.442 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.442 Read 4 bytes (24 pending)
. 2016-03-09 16:04:31.442 Read 24 bytes (0 pending)
< 2016-03-09 16:04:31.442 Type: SSH_FXP_STATUS, Size: 24, Number: 261892
. 2016-03-09 16:04:31.442 Discarding reserved response
. 2016-03-09 16:04:31.442 Waiting for another 4 bytes
. 2016-03-09 16:04:31.442 Detected network event
. 2016-03-09 16:04:31.442 Received 17 bytes (0)
. 2016-03-09 16:04:31.442 Read 4 bytes (13 pending)
. 2016-03-09 16:04:31.442 Read 13 bytes (0 pending)
< 2016-03-09 16:04:31.442 Type: SSH_FXP_HANDLE, Size: 13, Number: 262147
> 2016-03-09 16:04:31.442 Type: SSH_FXP_FSTAT, Size: 13, Number: 262408
. 2016-03-09 16:04:31.442 Sent 17 bytes
. 2016-03-09 16:04:31.442 There are 0 bytes remaining in the send buffer
> 2016-03-09 16:04:31.442 Type: SSH_FXP_READ, Size: 25, Number: 262661
. 2016-03-09 16:04:31.442 Sent 29 bytes
. 2016-03-09 16:04:31.442 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.442 Waiting for another 4 bytes
. 2016-03-09 16:04:31.458 Detected network event
. 2016-03-09 16:04:31.458 Received 41 bytes (0)
. 2016-03-09 16:04:31.458 Read 4 bytes (37 pending)
. 2016-03-09 16:04:31.458 Read 37 bytes (0 pending)
< 2016-03-09 16:04:31.458 Type: SSH_FXP_ATTRS, Size: 37, Number: 262408
. 2016-03-09 16:04:31.458 Storing reserved response
. 2016-03-09 16:04:31.458 Waiting for another 4 bytes
. 2016-03-09 16:04:31.458 Detected network event
. 2016-03-09 16:04:31.458 [COLOR="#FF0000"]Received 4109 bytes (0)[/COLOR]
. 2016-03-09 16:04:31.458 Read 4 bytes (4105 pending)
. 2016-03-09 16:04:31.458 Read 4105 bytes (0 pending)
< 2016-03-09 16:04:31.458 Type: SSH_FXP_DATA, Size: 4105, Number: 262661
> 2016-03-09 16:04:31.458 Type: SSH_FXP_READ, Size: 25, Number: 262917
. 2016-03-09 16:04:31.458 Sent 29 bytes
. 2016-03-09 16:04:31.458 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.458 Waiting for another 4 bytes
. 2016-03-09 16:04:31.473 Detected network event
. 2016-03-09 16:04:31.473 Received 4109 bytes (0)
. 2016-03-09 16:04:31.473 Read 4 bytes (4105 pending)
. 2016-03-09 16:04:31.473 Read 4105 bytes (0 pending)
< 2016-03-09 16:04:31.473 Type: SSH_FXP_DATA, Size: 4105, Number: 262917
> 2016-03-09 16:04:31.473 Type: SSH_FXP_READ, Size: 25, Number: 263173
. 2016-03-09 16:04:31.473 Sent 29 bytes
. 2016-03-09 16:04:31.473 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.473 Waiting for another 4 bytes
. 2016-03-09 16:04:31.489 Detected network event
. 2016-03-09 16:04:31.489 Received 4109 bytes (0)
. 2016-03-09 16:04:31.489 Read 4 bytes (4105 pending)
. 2016-03-09 16:04:31.489 Read 4105 bytes (0 pending)
< 2016-03-09 16:04:31.489 Type: SSH_FXP_DATA, Size: 4105, Number: 263173
> 2016-03-09 16:04:31.489 Type: SSH_FXP_READ, Size: 25, Number: 263429
. 2016-03-09 16:04:31.489 Sent 29 bytes
. 2016-03-09 16:04:31.489 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.489 Waiting for another 4 bytes
. 2016-03-09 16:04:31.520 Detected network event
. 2016-03-09 16:04:31.520 Received 16375 bytes (0)
. 2016-03-09 16:04:31.520 Read 4 bytes (16371 pending)
. 2016-03-09 16:04:31.520 Waiting for another 16393 bytes
. 2016-03-09 16:04:31.536 Detected network event
. 2016-03-09 16:04:31.536 Received 16375 bytes (0)
. 2016-03-09 16:04:31.536 Waiting for another 18 bytes
. 2016-03-09 16:04:31.536 Detected network event
. 2016-03-09 16:04:31.536 Received 18 bytes (0)
. 2016-03-09 16:04:31.536 Read 32764 bytes (0 pending)
< 2016-03-09 16:04:31.536 Type: SSH_FXP_DATA, Size: 32764, Number: 263429
> 2016-03-09 16:04:31.536 Type: SSH_FXP_READ, Size: 25, Number: 263685
. 2016-03-09 16:04:31.536 Sent 29 bytes
. 2016-03-09 16:04:31.536 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.551 Waiting for another 4 bytes
. 2016-03-09 16:04:31.567 Detected network event
. 2016-03-09 16:04:31.567 Received 16375 bytes (0)
. 2016-03-09 16:04:31.583 Received 875 bytes (0)
. 2016-03-09 16:04:31.583 Read 4 bytes (17246 pending)
. 2016-03-09 16:04:31.583 Read 17246 bytes (0 pending)
< 2016-03-09 16:04:31.583 Type: SSH_FXP_DATA, Size: 17246, Number: 263685
> 2016-03-09 16:04:31.583 Type: SSH_FXP_READ, Size: 25, Number: 263941
. 2016-03-09 16:04:31.583 Sent 29 bytes
. 2016-03-09 16:04:31.583 There are 0 bytes remaining in the send buffer
> 2016-03-09 16:04:31.583 Type: SSH_FXP_READ, Size: 25, Number: 264197
. 2016-03-09 16:04:31.583 Sent 29 bytes
. 2016-03-09 16:04:31.583 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.583 Detected network event
. 2016-03-09 16:04:31.598 Received 32 bytes (0)
. 2016-03-09 16:04:31.598 Read 4 bytes (28 pending)
. 2016-03-09 16:04:31.598 Read 28 bytes (0 pending)
< 2016-03-09 16:04:31.598 Type: SSH_FXP_STATUS, Size: 28, Number: 263941
. 2016-03-09 16:04:31.598 Storing reserved response
. 2016-03-09 16:04:31.598 Waiting for another 4 bytes
. 2016-03-09 16:04:31.598 Detected network event
. 2016-03-09 16:04:31.598 Received 32 bytes (0)
. 2016-03-09 16:04:31.598 Read 4 bytes (28 pending)
. 2016-03-09 16:04:31.598 Read 28 bytes (0 pending)
< 2016-03-09 16:04:31.598 Type: SSH_FXP_STATUS, Size: 28, Number: 264197
< 2016-03-09 16:04:31.598 Status code: 1
> 2016-03-09 16:04:31.598 Type: SSH_FXP_CLOSE, Size: 13, Number: 264452
. 2016-03-09 16:04:31.598 Sent 17 bytes
. 2016-03-09 16:04:31.598 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:04:31.598 Detected network event
. 2016-03-09 16:04:31.598 Received 28 bytes (0)
. 2016-03-09 16:04:31.598 1 requests to fill 1 data gaps were issued.

Hier geht es nicht:
FB7412:

. 2016-03-09 16:03:28.395 File: "/var/flash/ar7.cfg"
. 2016-03-09 16:03:28.411 Copying "/var/flash/ar7.cfg" to local directory started.
. 2016-03-09 16:03:28.411 Ascii transfer mode selected.
. 2016-03-09 16:03:28.411 Opening remote file.
> 2016-03-09 16:03:28.426 Type: SSH_FXP_OPEN, Size: 35, Number: 249091
. 2016-03-09 16:03:28.426 Sent 39 bytes
. 2016-03-09 16:03:28.426 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:03:28.426 Detected network event
. 2016-03-09 16:03:28.426 Received 17 bytes (0)
. 2016-03-09 16:03:28.426 Read 4 bytes (41 pending)
. 2016-03-09 16:03:28.426 Read 24 bytes (17 pending)
< 2016-03-09 16:03:28.426 Type: SSH_FXP_STATUS, Size: 24, Number: 248836
. 2016-03-09 16:03:28.426 Discarding reserved response
. 2016-03-09 16:03:28.426 Read 4 bytes (13 pending)
. 2016-03-09 16:03:28.426 Read 13 bytes (0 pending)
< 2016-03-09 16:03:28.426 Type: SSH_FXP_HANDLE, Size: 13, Number: 249091
> 2016-03-09 16:03:28.426 Type: SSH_FXP_FSTAT, Size: 13, Number: 249352
. 2016-03-09 16:03:28.426 Sent 17 bytes
. 2016-03-09 16:03:28.426 There are 0 bytes remaining in the send buffer
> 2016-03-09 16:03:28.426 Type: SSH_FXP_READ, Size: 25, Number: 249605
. 2016-03-09 16:03:28.426 Sent 29 bytes
. 2016-03-09 16:03:28.426 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:03:28.426 Waiting for another 4 bytes
. 2016-03-09 16:03:28.426 Detected network event
. 2016-03-09 16:03:28.426 Received 41 bytes (0)
. 2016-03-09 16:03:28.426 Read 4 bytes (37 pending)
. 2016-03-09 16:03:28.426 Read 37 bytes (0 pending)
< 2016-03-09 16:03:28.426 Type: SSH_FXP_ATTRS, Size: 37, Number: 249352
. 2016-03-09 16:03:28.426 Storing reserved response
. 2016-03-09 16:03:28.426 Waiting for another 4 bytes
. 2016-03-09 16:03:28.442 Detected network event
. 2016-03-09 16:03:28.442 [COLOR="#FF0000"]Received 28 bytes (0)[/COLOR]
. 2016-03-09 16:03:28.442 Read 4 bytes (24 pending)
. 2016-03-09 16:03:28.442 Read 24 bytes (0 pending)
< 2016-03-09 16:03:28.442 Type: SSH_FXP_STATUS, Size: 24, Number: 249605
< 2016-03-09 16:03:28.442 Status code: 4, Message: 249605, Server: Failure, Language:  
> 2016-03-09 16:03:28.442 Type: SSH_FXP_CLOSE, Size: 13, Number: 249860
. 2016-03-09 16:03:28.442 Sent 17 bytes
. 2016-03-09 16:03:28.442 There are 0 bytes remaining in the send buffer
. 2016-03-09 16:03:28.442 Detected network event
. 2016-03-09 16:03:28.442 Received 28 bytes (0)
* 2016-03-09 16:03:28.489 (ECommand) Copying files from remote side failed.
* 2016-03-09 16:03:28.489 General failure (server should provide error description).
* 2016-03-09 16:03:28.489 Error code: 4
* 2016-03-09 16:03:28.489 Error message from server: Failure
* 2016-03-09 16:03:28.489 Request code: 5

FB7490:

. 2016-03-09 15:56:09.364 File: "/var/flash/ar7.cfg"
. 2016-03-09 15:56:09.364 Copying "/var/flash/ar7.cfg" to local directory started.
. 2016-03-09 15:56:09.364 Ascii transfer mode selected.
. 2016-03-09 15:56:09.364 Opening remote file.
> 2016-03-09 15:56:09.364 Type: SSH_FXP_OPEN, Size: 35, Number: 236291
. 2016-03-09 15:56:09.380 Sent 39 bytes
. 2016-03-09 15:56:09.380 There are 0 bytes remaining in the send buffer
. 2016-03-09 15:56:09.380 Read 4 bytes (24 pending)
. 2016-03-09 15:56:09.380 Read 24 bytes (0 pending)
< 2016-03-09 15:56:09.380 Type: SSH_FXP_STATUS, Size: 24, Number: 236036
. 2016-03-09 15:56:09.380 Discarding reserved response
. 2016-03-09 15:56:09.380 Waiting for another 4 bytes
. 2016-03-09 15:56:09.380 Detected network event
. 2016-03-09 15:56:09.380 Received 17 bytes (0)
. 2016-03-09 15:56:09.380 Read 4 bytes (13 pending)
. 2016-03-09 15:56:09.380 Read 13 bytes (0 pending)
< 2016-03-09 15:56:09.380 Type: SSH_FXP_HANDLE, Size: 13, Number: 236291
> 2016-03-09 15:56:09.380 Type: SSH_FXP_FSTAT, Size: 13, Number: 236552
. 2016-03-09 15:56:09.380 Sent 17 bytes
. 2016-03-09 15:56:09.380 There are 0 bytes remaining in the send buffer
> 2016-03-09 15:56:09.380 Type: SSH_FXP_READ, Size: 25, Number: 236805
. 2016-03-09 15:56:09.380 Sent 29 bytes
. 2016-03-09 15:56:09.380 There are 0 bytes remaining in the send buffer
. 2016-03-09 15:56:09.380 Waiting for another 4 bytes
. 2016-03-09 15:56:09.380 Detected network event
. 2016-03-09 15:56:09.380 Received 41 bytes (0)
. 2016-03-09 15:56:09.380 Read 4 bytes (37 pending)
. 2016-03-09 15:56:09.380 Read 37 bytes (0 pending)
< 2016-03-09 15:56:09.380 Type: SSH_FXP_ATTRS, Size: 37, Number: 236552
. 2016-03-09 15:56:09.380 Storing reserved response
. 2016-03-09 15:56:09.380 Waiting for another 4 bytes
. 2016-03-09 15:56:09.380 Detected network event
. 2016-03-09 15:56:09.380 [COLOR="#FF0000"]Received 28 bytes (0)[/COLOR]
. 2016-03-09 15:56:09.380 Read 4 bytes (24 pending)
. 2016-03-09 15:56:09.380 Read 24 bytes (0 pending)
< 2016-03-09 15:56:09.380 Type: SSH_FXP_STATUS, Size: 24, Number: 236805
< 2016-03-09 15:56:09.380 Status code: 4, Message: 236805, Server: Failure, Language:  
> 2016-03-09 15:56:09.380 Type: SSH_FXP_CLOSE, Size: 13, Number: 237060
. 2016-03-09 15:56:09.380 Sent 17 bytes
. 2016-03-09 15:56:09.380 There are 0 bytes remaining in the send buffer
* 2016-03-09 15:56:09.411 (ECommand) Copying files from remote side failed.
* 2016-03-09 15:56:09.411 General failure (server should provide error description).
* 2016-03-09 15:56:09.411 Error code: 4
* 2016-03-09 15:56:09.411 Error message from server: Failure
* 2016-03-09 15:56:09.411 Request code: 5

Ab Zeile 33 wird es interessant, Ich habe sie mal rot gemacht.
Aber mir sagt das leide gar nix.
Ich hoffe ihr seht da mehr drin.

 

[andiling]

Ich kann das Problem nachstellen, bei einer 7490 mit Werkseinstellungen funktioniert der Zugriff mit WinSCP mit 06.30, nicht mehr jedoch mit 06.51. Server, Client und Configs waren unverändert, in beiden Versionen wird eine Dateigröße von 2048kb angezeigt. Zum weiteren Testen fehlt mir gerade die Zeit.

Zu den Logs oben kann ich nicht viel sagen (da vom Client), denn das entscheidende fehlt:

server should provide error description

Auch gibt das Log nicht den Inhalt von SSH_FXP_ATTRS und SSH_FXP_STATUS wider.

P.S.
Ich würde die Dateien direkt auf dem System ändern, entweder mit nvi oder wenn es komfortabler sein soll z.B. mit nano.
Alternativ kannst Du ja z.B. mit cat /var/flash/ar7.cfg > /var/tmp/ar7.cfg die Datei ins temporäre Verzeichnis schreiben, von dort aus dürfte eine Änderung mit WinSCP ohne weiteres möglich sein und dann kopierst Du diese mit cat /var/tmp/ar7.cfg > /var/flash/ar7.cfg wieder zurück.

 

[meiser79]

Das Problem tritt auch mit WinSCP 4.3.8 und mit dem Filezilla Client 3.12.0.2 auf.

Ich habe auch folgenden Debian-Bugreport gefunden (ohne Lösung): https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=650982

PS: Auch SCP scheitert mit "not a regular file".

 

[PeterPawn]

Ich gehe mal davon aus, daß es an der neuen TFFS-Version 3 liegt ... AVM hat ja den TFFS-Treiber weitgehend überarbeitet. Vermutlich (!, keine Zeit für Recherche und irgendwie auch kein richtiges Interesse) reagierte der alte Code "gemütlicher", wenn er zum "seek" aufgefordert wurde ... eventuell ist es aber auch sogar der neue Kernel, der da den entscheidenden Unterschied macht, wenn die fops-Struktur für ein File keinen "llseek"-Handler beinhaltet (was nach einer flüchtigen Suche in den Quellen (grep llseek) bei den TFFS-Nodes nirgendwo gesetzt wird (jedenfalls für das File selbst und nicht für das procfs).

Gibt es irgendeinen zwingenden Grund, warum man die genaue Ursache ermitteln müßte? Wenn ja, welchen?

Will man es genau wissen, müßte man wohl ein kleines Testprogramm schreiben, was unter den jeweils passenden Bedingungen (also richtiger Kernel und richtige TFFS-Version) einen "lseek()"-Aufruf ausführt und den gemeldeten Fehler protokolliert. Gibt es da einen Unterschied, müßte man in den lseek()-Code im Kernel und/oder in die TFFS-Quellen eintauchen ... keine Sache "im Handumdrehen" und eine Lösung dürfte ohnehin nicht ohne weiteres machbar sein (mind. "replace kernel" im Freetz wäre dann angesagt).

 

[eisbaerin]

Gibt es irgendeinen zwingenden Grund, warum man die genaue Ursache ermitteln müßte?

Nö überhaupt nicht. Eine Lösung würde mir schon reichen.

Aber bitte kein nvi und auch nano ist nicht gerade komfortabel, obwohl andiling es so behauptet.
Also ein Scrollbalken u.s.w. sollte schon vorhanden sein, man hat sich über 6 Jahre so stark daran gewöhnt.

Noch ist es nicht so wichtig, sind noch meine Spielboxen.
Aber irgendwann wird es ja nichts anderes mehr geben.
Aber bis dahin hoffe ich, daß es mit einem neuen WinSCP o.ä. und/oder neuen binaries geht.

 

[andiling]

Also ich finde (n)vi äußerst komfortabel, da kann kein Scrollbalken mithalten!
Im Produktivsystem wirst Du ja zudem auch die ar7.cfg & Co. nicht regelmäßig bearbeiten müssen?!?

 

[eisbaerin]

Ach, dann schreibst du deine Briefe auch noch mit Word for DOS?

 

[PeterPawn]

Auch OT, aber erste Wahl für alle Aufgaben: https://www.gnu.org/software/emacs/

 

[andiling]

hmm... @PeterPawn schießt mit Raketen auf Flöhe.
@eisbaerin, meine Briefe schreibe ich nicht auf der Fritzbox, genausowenig editiere ich meine Konfigs auf dem PC.

Ich weiß halt nicht, ob sich jemand findet, der den sftp-server so patcht, dass er mit den char devices der Fritzbox ab 06.50 umgehen kann. Dieser spezielle Fall dürfte eine ganz niedrige Priorität haben. Und bevor es überhaupt soweit ist, müsste erstmal die Ursache geklärt sein, bisher ist ja alles nur spekulativ.

 

[eisbaerin]

Auch gibt das Log nicht den Inhalt von SSH_FXP_ATTRS und SSH_FXP_STATUS wider.

Ich habe es nochmal mit DEBUG2 gemacht, da siehst du auch den Inhalt:

. 2016-03-10 12:57:59.426 Received 41 bytes (0)
. 2016-03-10 12:57:59.426 Received 28 bytes (0)
. 2016-03-10 12:57:59.426 Read 4 bytes (65 pending)
. 2016-03-10 12:57:59.426 Read 37 bytes (28 pending)
< 2016-03-10 12:57:59.426 Type: SSH_FXP_ATTRS, Size: 37, Number: 282888
< 2016-03-10 12:57:59.426 69,00,04,51,08,00,00,00,0F,00,00,00,00,00,00,08,00,00,00,00,00,00,00,00,00,
< 2016-03-10 12:57:59.426 00,00,21,A4,00,00,00,07,00,00,00,07,
. 2016-03-10 12:57:59.426 Storing reserved response
. 2016-03-10 12:57:59.426 Read 4 bytes (24 pending)
. 2016-03-10 12:57:59.426 Read 24 bytes (0 pending)
< 2016-03-10 12:57:59.426 Type: SSH_FXP_STATUS, Size: 24, Number: 283141
< 2016-03-10 12:57:59.426 65,00,04,52,05,00,00,00,04,00,00,00,07,46,61,69,6C,75,72,65,00,00,00,00,
< 2016-03-10 12:57:59.442 Status code: 4, Message: 283141, Server: Failure, Language:  
> 2016-03-10 12:57:59.458 Type: SSH_FXP_CLOSE, Size: 13, Number: 283396
> 2016-03-10 12:57:59.458 04,00,04,53,04,00,00,00,04,00,00,00,00,
. 2016-03-10 12:57:59.458 Sent 17 bytes
. 2016-03-10 12:57:59.458 There are 0 bytes remaining in the send buffer