Skip to content

clone: silently stuck on transferkey #194

@yarikoptic

Description

@yarikoptic

Originally was done using datalad clone but that one was silent, so ran directly with GIT_TRACE to hope to gain some clue, but didn't really, so I Ctrl-C'ed it

❯ GIT_TRACE=1 git clone osf://gvjbn
❯ GIT_TRACE=1 git clone osf://gvjbn
15:58:21.457958 git.c:463               trace: built-in: git clone osf://gvjbn
Cloning into 'gvjbn'...
15:58:21.461340 run-command.c:659       trace: run_command: git remote-osf origin osf://gvjbn
15:58:21.463788 git.c:749               trace: exec: git-remote-osf origin osf://gvjbn
15:58:21.463834 run-command.c:659       trace: run_command: git-remote-osf origin osf://gvjbn
15:58:21.486704 git.c:463               trace: built-in: git describe --tags --dirty --always --long --match '[[:digit:]]*'
15:58:21.488953 git.c:463               trace: built-in: git rev-parse HEAD
15:58:21.490061 git.c:463               trace: built-in: git rev-parse --abbrev-ref HEAD
15:58:21.491483 git.c:463               trace: built-in: git show -s '--format=%ci' HEAD
^Cobject address  : 0x7f7cda732440
object refcount : 2
object type     : 0x9e5980
object type name: KeyboardInterrupt
object repr     : KeyboardInterrupt()
lost sys.stderr

rerunning after complete redo of virtualenv and thus all fresh updates to datalad etc:

❯ DATALAD_LOG_LEVEL=debug GIT_TRACE=1 git clone osf://gvjbn
❯ DATALAD_LOG_LEVEL=debug GIT_TRACE=1 git clone osf://gvjbn
16:05:07.654502 git.c:463               trace: built-in: git clone osf://gvjbn
Cloning into 'gvjbn'...
16:05:07.657304 run-command.c:659       trace: run_command: git remote-osf origin osf://gvjbn
16:05:07.659415 git.c:749               trace: exec: git-remote-osf origin osf://gvjbn
16:05:07.659466 run-command.c:659       trace: run_command: git-remote-osf origin osf://gvjbn
16:05:07.684729 git.c:463               trace: built-in: git describe --tags --dirty --always --long --match '[[:digit:]]*'
16:05:07.687019 git.c:463               trace: built-in: git rev-parse HEAD
16:05:07.688120 git.c:463               trace: built-in: git rev-parse --abbrev-ref HEAD
16:05:07.689517 git.c:463               trace: built-in: git show -s '--format=%ci' HEAD
[DEBUG] Enable posting DataLad config overrides CLI/ENV as GIT_CONFIG items in process ENV 
[DEBUG] Run ['git', 'version'] (protocol_class=StdOutErrCapture) (cwd=None) 
[DEBUG] Finished ['git', 'version'] with status 0 
...
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'init'] (protocol_class=GeneratorStdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[Level 11] stderr| 16:05:07.841237 git.c:749               trace: exec: git-annex init 
[Level 11] stderr| 16:05:07.841260 run-command.c:659       trace: run_command: git-annex init 
[Level 11] stderr| 16:05:07.844741 git.c:463               trace: built-in: git config --null --list 
[Level 11] stderr| 16:05:07.848830 git.c:463               trace: built-in: git show-ref --hash refs/heads/git-annex 
[Level 11] stderr| 16:05:07.850199 git.c:463               trace: built-in: git show-ref --verify -q refs/remotes/origin/git-annex 
[Level 11] stderr| 16:05:07.852587 git.c:463               trace: built-in: git update-index -z --index-info 
[Level 11] stderr| 16:05:07.859731 git.c:463               trace: built-in: git write-tree 
[Level 11] stderr| 16:05:07.862192 git.c:463               trace: built-in: git commit-tree 4b825dc642cb6eb9a060e54bf8d69288fbee4904 --no-gpg-sign 
[Level 11] stderr| 16:05:07.865301 git.c:463               trace: built-in: git update-ref refs/heads/git-annex 59c9e1aeae5641af42a9132de0f1c5c1ccf6d4c0 
[Level 11] stderr| 16:05:07.867777 git.c:463               trace: built-in: git config annex.uuid 67d613e2-c811-4f2c-9fea-3bf391ff4a5c 
[Level 11] stderr| 16:05:07.869669 git.c:463               trace: built-in: git config --null --list 
[Level 11] stderr| 16:05:07.873051 git.c:463               trace: built-in: git show-ref git-annex 
[Level 11] stderr| 16:05:07.875492 git.c:463               trace: built-in: git show-ref --hash refs/heads/git-annex 
[Level 11] stderr| 16:05:07.877075 git.c:463               trace: built-in: git log refs/heads/git-annex..59c9e1aeae5641af42a9132de0f1c5c1ccf6d4c0 '--pretty=%H' -n1 
[Level 11] stderr| 16:05:07.879132 git.c:463               trace: built-in: git cat-file --batch 
[Level 11] stderr| 16:05:07.880270 git.c:463               trace: built-in: git config annex.version 10 
[Level 11] stderr| 16:05:07.881469 git.c:463               trace: built-in: git config --null --list 
[DEBUG] Run ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] (protocol_class=KillOutput) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] with status 128 
[DEBUG] Run ['git', 'config', '-z', '-l', '--show-origin'] (protocol_class=StdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', 'config', '-z', '-l', '--show-origin'] with status 0 
[DEBUG] Run ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] (protocol_class=KillOutput) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] with status 128 
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'initremote', 'origin', 'type=external', 'externaltype=osf', 'encryption=none', 'node=gvjbn', '-c', 'annex.dotfiles=true'] (protocol_class=StdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'initremote', 'origin', 'type=external', 'externaltype=osf', 'encryption=none', 'node=gvjbn', '-c', 'annex.dotfiles=true'] with status 0 
[DEBUG] Run ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] (protocol_class=KillOutput) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', 'cat-file', '-e', 'HEAD:.datalad/config'] with status 128 
[DEBUG] Run ['git', 'config', '-z', '-l', '--show-origin'] (protocol_class=StdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', 'config', '-z', '-l', '--show-origin'] with status 0 
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'cat-file', 'blob', 'git-annex:remote.log'] (protocol_class=GeneratorStdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[Level 11] stderr| 16:05:08.180464 git.c:463               trace: built-in: git cat-file blob git-annex:remote.log 
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'cat-file', 'blob', 'git-annex:trust.log'] (protocol_class=GeneratorStdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[Level 11] CommandError: 'git -c diff.ignoreSubmodules=none -c core.quotepath=false cat-file blob git-annex:trust.log' failed with exitcode 128 [err: '16:05:08.182195 git.c:463               trace: built-in: git cat-file blob git-annex:trust.log
| fatal: path 'trust.log' does not exist in 'git-annex''] 
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'drop', '--force', '--key', 'XDLRA--refs', '-c', 'annex.dotfiles=true'] (protocol_class=StdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 
[DEBUG] Finished ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'drop', '--force', '--key', 'XDLRA--refs', '-c', 'annex.dotfiles=true'] with status 0 
[DEBUG] Run ['git', '-c', 'diff.ignoreSubmodules=none', '-c', 'core.quotepath=false', 'annex', 'transferkey', 'XDLRA--refs', '--from=dd10930d-eb14-4c97-9e7e-be5551d23fd6', '-c', 'annex.dotfiles=true'] (protocol_class=StdOutErrCapture) (cwd=/tmp/gvjbn/.git/dl-repoannex/origin/repoannex) 

and there it hangs. So it is an 'annex transferkey' but I do not see any size change there etc... if I make copy/rerun manually with --debug there

full log trace
❯ DATALAD_LOG_LEVEL=debug 'git' '-c' 'diff.ignoreSubmodules=none' '-c' 'core.quotepath=false' 'annex' --debug 'transferkey' 'XDLRA--refs' '--from=5cca7d1d-437b-4f2f-9d60-73e349813574' '-c' 'annex.dotfiles=true'
[2024-02-13 16:13:10.881794966] (Utility.Process) process [1099420] read: git ["--git-dir=.","--literal-pathspecs","-c","annex.debug=true","-c","annex.dotfiles=true","show-ref","git-annex"]
[2024-02-13 16:13:10.884209951] (Utility.Process) process [1099420] done ExitSuccess
[2024-02-13 16:13:10.884969642] (Utility.Process) process [1099421] read: git ["--git-dir=.","--literal-pathspecs","-c","annex.debug=true","-c","annex.dotfiles=true","show-ref","--hash","refs/heads/git-annex"]
[2024-02-13 16:13:10.887391647] (Utility.Process) process [1099421] done ExitSuccess
[2024-02-13 16:13:10.888530492] (Utility.Process) process [1099422] read: git ["--git-dir=.","--literal-pathspecs","-c","annex.debug=true","-c","annex.dotfiles=true","log","refs/heads/git-annex..42a65c105dda79dca024be8a64521e8824ffc5b8","--pretty=%H","-n1"]
[2024-02-13 16:13:10.891665292] (Utility.Process) process [1099422] done ExitSuccess
[2024-02-13 16:13:10.892287012] (Annex.Branch) read remote.log
[2024-02-13 16:13:10.900718114] (Utility.Process) process [1099423] chat: git ["--git-dir=.","--literal-pathspecs","-c","annex.debug=true","-c","annex.dotfiles=true","cat-file","--batch"]
[2024-02-13 16:13:10.905447758] (Utility.Process) process [1099424] chat: /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA []
[2024-02-13 16:13:10.90555036] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] <-- GETVERSION
[DEBUG] Enable posting DataLad config overrides CLI/ENV as GIT_CONFIG items in process ENV 
[DEBUG] Run ['git', 'version'] (protocol_class=StdOutErrCapture) (cwd=None) 
[DEBUG] Finished ['git', 'version'] with status 0 
[DEBUG] Not retro-fitting GitRepo with deprecated symbols, datalad-deprecated package not found 
[DEBUG] Apply datalad-next patch to annexrepo.py:AnnexRepo.enable_remote 
[DEBUG] Building doc for <class 'datalad.local.configuration.Configuration'> 
[DEBUG] Building doc for <class 'datalad_next.patches.configuration.Configuration'> 
[DEBUG] Building doc for <class 'datalad.local.configuration.Configuration'> 
[DEBUG] Failed to import requests_ftp, thus no ftp support: ModuleNotFoundError(No module named 'requests_ftp') 
[DEBUG] Apply datalad-next patch to create_sibling_ghlike.py:_GitHubLike._set_request_headers 
[DEBUG] Apply datalad-next patch to interface.(utils|base).py:_execute_command_ 
[DEBUG] Building doc for <class 'datalad.core.local.status.Status'> 
[DEBUG] Building doc for <class 'datalad.core.local.diff.Diff'> 
[DEBUG] Building doc for <class 'datalad.core.distributed.push.Push'> 
[DEBUG] Apply patch to datalad.core.distributed.push._transfer_data 
[DEBUG] Patching datalad.core.distributed.push.Push docstring and parameters 
[DEBUG] Building doc for <class 'datalad.core.distributed.push.Push'> 
[DEBUG] Patching datalad.support.AnnexRepo.get_export_records (new method) 
[DEBUG] Apply patch to datalad.core.distributed.push._push 
[DEBUG] Apply patch to datalad.distribution.siblings._enable_remote 
[DEBUG] Building doc for <class 'datalad.distribution.update.Update'> 
[DEBUG] Building doc for <class 'datalad.distribution.siblings.Siblings'> 
[DEBUG] Replace special remote _main() with datalad-next's progress logging enabled variant 
[DEBUG] Building doc for <class 'datalad.local.subdatasets.Subdatasets'> 
[DEBUG] Building doc for <class 'datalad.distributed.create_sibling_gitlab.CreateSiblingGitlab'> 
[DEBUG] Apply patch to datalad.distributed.create_sibling_gitlab._proc_dataset 
[DEBUG] Stop advertising discontinued "hierarchy" layout for `create_siblign_gitlab()` 
[DEBUG] Building doc for <class 'datalad.distributed.create_sibling_gitlab.CreateSiblingGitlab'> 
[DEBUG] Building doc for <class 'datalad.core.local.save.Save'> 
[DEBUG] Building doc for <class 'datalad.core.distributed.clone.Clone'> 
[DEBUG] Building doc for <class 'datalad.distribution.get.Get'> 
[DEBUG] Building doc for <class 'datalad.distribution.install.Install'> 
[DEBUG] Building doc for <class 'datalad.local.unlock.Unlock'> 
[DEBUG] Building doc for <class 'datalad.core.local.run.Run'> 
[DEBUG] Apply patch to datalad.core.local.run.format_command 
[DEBUG] Apply patch to datalad.distribution.update._choose_update_target 
[2024-02-13 16:13:11.050201466] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] --> VERSION 1
[2024-02-13 16:13:11.050286775] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] <-- CANVERIFY
[2024-02-13 16:13:11.050565926] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] --> CANVERIFY-YES
[2024-02-13 16:13:11.050608601] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] <-- ISSTABLE
[2024-02-13 16:13:11.050800033] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] --> ISSTABLE-NO
[2024-02-13 16:13:11.050842405] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] <-- ISCRYPTOGRAPHICALLYSECURE
[2024-02-13 16:13:11.050987764] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-backend-XDLRA[1] --> ISCRYPTOGRAPHICALLYSECURE-NO
[2024-02-13 16:13:11.052128431] (Utility.Process) process [1099434] chat: /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf []
[2024-02-13 16:13:11.125947184] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] --> VERSION 1
[2024-02-13 16:13:11.126039146] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] <-- EXTENSIONS INFO GETGITREMOTENAME ASYNC
[2024-02-13 16:13:11.126243833] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] --> EXTENSIONS
[2024-02-13 16:13:11.126287875] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] <-- PREPARE
[2024-02-13 16:13:11.126538659] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] --> GETCONFIG node
[2024-02-13 16:13:11.126580498] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] <-- VALUE gvjbn
[DEBUG] Run ['git', 'version'] (protocol_class=StdOutErrCapture) (cwd=None) 
[DEBUG] Finished ['git', 'version'] with status 0 
[DEBUG] Not retro-fitting GitRepo with deprecated symbols, datalad-deprecated package not found 
[DEBUG] Importing keyring 
[2024-02-13 16:13:13.562265998] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] --> PREPARE-SUCCESS
[2024-02-13 16:13:13.562455525] (Annex.ExternalAddonProcess) /home/yoh/proj/datalad/datalad-osf/venvs/dev3.11/bin/git-annex-remote-osf[1] <-- TRANSFER RETRIEVE XDLRA--refs ./annex/tmp/XDLRA--refs

so seems stalling at that git-annex-remote-osf TRANSFER request silently. and again size of folder doesn't grow.

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions