Closed xeruf closed 1 year ago
Looks like the random port used for the test Syncthing instance is already in use for some reason. Maybe a previous test case couldn't terminate it's test instance cleanly or tests are wrongly executed in parallel.
but I tried over 3 times, and it kept on failing :/
I'm not saying that the problem might not be persistent. I've just been observing the logs - and Syncthing clearly says that the UI port is already in use:
3: [D44DU] 2022/12/16 15:51:52 WARNING: Starting API/GUI: listen tcp 127.0.0.1:49217: bind: address already in use
And that's likely the reason for the test failure:
3: FAIL! : WizardTests::testConfiguringCurrentlyRunningSyncthing() '!cfgCurrentlyRunningRadioButton->isHidden()' returned FAL
SE. ()
3: Loc: [/home/janek/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets/tests/wizard.cpp(334)]
3: QDEBUG : WizardTests::cleanupTestCase() Syncthing log during testrun:
I don't think the output of the different tests should be interleaved like it is in your logs, e.g.:
1: - Resuming all dirs ...
3: PASS : WizardTests::testConfiguringLauncher()
3: Info: Launched process, PID: 336884
3: QWARN : WizardTests::testConfiguringCurrentlyRunningSyncthing() QFSFileEngine::open: No file name specified
1: [2QOBR] 2022/12/16 15:51:53 INFO: No stored folder metadata for "test2"; recalculating
…
1: - Connection status changed to: connected
3: FAIL! : WizardTests::testConfiguringCurrentlyRunningSyncthing() '!cfgCurrentlyRunningRadioButton->isHidden()' returned FAL
SE. ()
3: Loc: [/home/janek/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets/tests/wizard.cpp(334)]
3: QDEBUG : WizardTests::cleanupTestCase() Syncthing log during testrun:
3: [start] 2022/12/16 15:51:50 INFO: syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 0
7:35:59 UTC [noupgrade]
If this interleaving of "1:" and "3:" messages means that those test cases really ran in parallel then this is likely the culprit. Note that in my build environment those log messages never appear interleaved. (Tests should also actually be blocked from running in parallel via a lock so I'm not sure what's going on here.)
I recommend running the wizard test individually to see whether it passes then or not. I also recommend checking for lingering Syncthing test instances before invoking a test. Sometimes it is also helpful to step though the test with the debugger. Since I cannot reproduce the issue myself and the exact source of the problem isn't immediately obvious I cannot help you much here.
The fact that I globally set CTEST_PARALLEL_LEVEL=3 should not be an issue anymore, I thought you fixed that, right? Because it sounds like it is.
That variable could be the culprit although it should be fixed. Maybe the fix doesn't work as intended.
Since you can reproduce it so reliably, you can try executing the wizard test alone to cross check. I'll also cross check with CTEST_PARALLEL_LEVEL=3 enabled locally (on the full testsuite).
The resource lock for the wizard test (that I've only introduced recently) wasn't working, indeed. I've pushed a fix you can try if you like. Note that it would be useful to mention things like CTEST_PARALLEL_LEVEL=3
from the beginning.
I don't often do C++ development, so I didn't have it on my mind so far. Unfortunately, unexporting the variable did not fix it:
[21/22] cd /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1 && /usr/bin/ctest -V
UpdateCTestConfiguration from :/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/DartConfiguration.tcl
UpdateCTestConfiguration from :/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/DartConfiguration.tcl
Test project /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1
Constructing a list of tests
Done constructing a list of tests
Updating test list for fixtures
Added 0 tests to meet fixture requirements
Checking test dependency graph...
Checking test dependency graph end
test 1
Start 1: syncthingconnector_run_tests
1: Test command: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/syncthingconnector_tests "-p" "/home/janekf/.local/cache/yay/syncthingtray/src
/syncthingtray-1.3.1/connector/testfiles" "-w" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testworkingdir"
1: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector
1: Test timeout computed to be: 10000000
test 3
Start 3: syncthingwidgets_run_wizard_tests
3: Test command: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets/syncthingwidgets_wizard_tests
3: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets
3: Test timeout computed to be: 10000000
test 4
Start 4: syncthingtray_appstream_validation
4: Test command: /usr/bin/appstreamcli "validate" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/tray/resources/syncthingtray.appdata.xml"
4: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/tray
4: Test timeout computed to be: 10000000
1: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testfiles/
1: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testfiles/
1: ./testfiles/
1: Directory used to store working copies:
1: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testworkingdir/
1: Executing test cases ...
1: .
1: - Setup configuration for Syncthing tests ...
1: - Using timeout factor 3
1:
1: - Launching Syncthing: syncthing -gui-address=http://127.0.0.1:52250 -gui-apikey=syncthingtestinstance -home=/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtra
y-1.3.1/connector/testworkingdir/testconfig -no-browser -verbose
1: Info: Launched process, PID: 50381
1:
1: - Preparing connection ...
1:
1: - Error handling in case of insufficient configuration ...
1: - Connection error: Connection configuration is insufficient.
1: - Connection status changed to: disconnected
1:
1: - Error handling in case of inavailability ...
1: [start] 2022/12/18 00:47:17 INFO: syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 07:35:59 UTC [noupgrade]
1: [start] 2022/12/18 00:47:17 INFO: Generating ECDSA key and certificate for syncthing...
1: [start] 2022/12/18 00:47:17 INFO: Archiving a copy of old config file format at: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testworking
dir/testconfig/config.xml.v28
1: - Connection error: Unable to request Syncthing config: Connection refused
1: - Connection error: Unable to request Syncthing status: Connection refused
1: [F77GG] 2022/12/18 00:47:17 INFO: My ID: F77GG2U-T32AWA6-JOE7GV2-KKWYOYW-65M7FDT-77HCHYK-5AHBPNE-AKAPCAQ
3: ********* Start testing of WizardTests *********
3: Config: Using QtTest library 5.15.7, Qt 5.15.7 (x86_64-little_endian-lp64 shared (dynamic) release build; by GCC 12.2.0), manjaro unknown
3: QDEBUG : WizardTests::initTestCase() "HOME dir: /tmp/syncthingwidgets_wizard_tests-ADBbgD"
3: PASS : WizardTests::initTestCase()
3: PASS : WizardTests::testShowingSettings()
3: QWARN : WizardTests::testConfiguringLauncher() This plugin does not support propagateSizeHints()
3: QWARN : WizardTests::testConfiguringLauncher() QFSFileEngine::open: No file name specified
3: Info: Launched process, PID: 50442
3: QWARN : WizardTests::testConfiguringLauncher() QFSFileEngine::open: No file name specified
3: Info: Launched process, PID: 50448
3: QDEBUG : WizardTests::testConfiguringLauncher() waiting for Syncthing to write config file
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection error: Unable to request Syncthing config: Connection refused
1: - Connection error: Unable to request Syncthing status: Connection refused
1: [F77GG] 2022/12/18 00:47:18 INFO: Single thread SHA256 performance is 1847 MB/s using minio/sha256-simd (501 MB/s using crypto/sha256).
1: [F77GG] 2022/12/18 00:47:18 VERBOSE: Starting up (/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testworkingdir/testconfig)
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection error: Unable to request Syncthing config: Connection refused
1: - Connection error: Unable to request Syncthing status: Connection refused
1: [F77GG] 2022/12/18 00:47:19 INFO: Hashing performance is 1147.06 MB/s
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 1...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 2...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 3...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 5...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 6...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 7...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 9...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 10...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 11...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 13...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 14...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 16...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 17...
1: [F77GG] 2022/12/18 00:47:19 INFO: Running database migration 19...
1: [F77GG] 2022/12/18 00:47:19 INFO: Compacting database after migration...
1: [F77GG] 2022/12/18 00:47:19 INFO: Overall send rate is unlimited, receive rate is unlimited
1: [F77GG] 2022/12/18 00:47:19 INFO: No stored folder metadata for "test1"; recalculating
1: [F77GG] 2022/12/18 00:47:19 INFO: Ready to synchronize test1 (sendreceive)
1: [F77GG] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6
QJA3-MPFYMSO-U56GTUK-NA2MIAW
1: [F77GG] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
1: [F77GG] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
1: [F77GG] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv4 local broadcast discovery on port 21027
1: [F77GG] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv6 local multicast discovery on address [ff12::8384]:21027
1: [F77GG] 2022/12/18 00:47:19 INFO: TCP listener (127.0.0.1:32452) starting
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Folder "test1" is now scan-waiting
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Listen address tcp://127.0.0.1:32452 resolution has changed: lan addresses: [tcp://127.0.0.1:32452] wan addresses: [tcp://0.0.0.0:0 t
cp://127.0.0.1:32452]
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Folder "test1" is now scanning
1: [F77GG] 2022/12/18 00:47:19 INFO: Loading HTTPS certificate: open /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/testworkingdir/testconfig/
https-cert.pem: no such file or directory
1: [F77GG] 2022/12/18 00:47:19 INFO: Creating new HTTPS certificate
1: [F77GG] 2022/12/18 00:47:19 INFO: Completed initial scan of sendreceive folder test1
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Folder "test1" is now idle
1: [F77GG] 2022/12/18 00:47:19 INFO: GUI and API listening on 127.0.0.1:52250
1: [F77GG] 2022/12/18 00:47:19 INFO: Access the GUI via the following URL: http://127.0.0.1:52250/
1: [F77GG] 2022/12/18 00:47:19 INFO: My name is "grasshopper"
1: [F77GG] 2022/12/18 00:47:19 INFO: Device MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7 is "Test dev 2" at [tcp://192.168.2.2:22001]
1: [F77GG] 2022/12/18 00:47:19 INFO: Device 6EIS2PN-J2IHWGS-AXS3YUL-HC5FT3K-77ZXTLL-AKQLJ4C-7SWVPUS-AZW4RQ4 is "Test dev 1" at [dynamic]
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Startup complete
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Discovered device IJMVVEB-KMP4IJ6-ERRFOJ4-AUBSKH7-EFXJEQS-CTV4DLD-REVFSFD-HOXXWA2 at [quic://172.16.0.250:44807 quic://172.17.77.1:44
807 quic://172.18.0.1:44807 quic://172.19.0.1:44807 tcp://172.16.0.250:44807 tcp://172.17.77.1:44807 tcp://172.18.0.1:44807 tcp://172.19.0.1:44807]
1: [F77GG] 2022/12/18 00:47:19 VERBOSE: Discovered device GCSXFQC-DWFY4RF-ALLEFZ3-GXBC2BH-KCB46VZ-LGU4QY4-7RVQXRS-D2WQHQB at [tcp://172.16.0.250:22000 tcp://172.17.77.1:2200
0 tcp://172.18.0.1:22000 tcp://172.19.0.1:22000 relay://85.5.216.91:22067/?id=QVNSFXE-UJVC3L2-4DW6VU7-NFXUFMW-JKCGCN4-QZLPARN-SNBD4YQ-XNQNRQT&networkTimeout=2m0s&pingInterva
l=1m0s&providedBy=IgorNovgorodov&statusAddr=%3A22070 quic://172.16.0.250:22000 quic://172.17.77.1:22000 quic://172.18.0.1:22000 quic://172.19.0.1:22000 quic://188.192.136.70
:22000]
4:
4: ✔ Validation was successful.
1/6 Test #4: syncthingtray_appstream_validation ............. Passed 1.84 sec
test 5
Start 5: syncthingplasmoid_appstream_validation
5: Test command: /usr/bin/appstreamcli "validate" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/plasmoid/lib/resources/syncthingplasmoid.appdata.xml"
5: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/plasmoid/lib
5: Test timeout computed to be: 10000000
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1: - Connection error: Unable to request Syncthing status: Host requires authentication
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1:
1: - Error handling in case of wrong credentials ...
1: - Connection error: Unable to request Syncthing config: Host requires authentication
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1:
1: - Error handling in case of wrong API key ...
3: PASS : WizardTests::testConfiguringLauncher()
3: Info: Launched process, PID: 50495
3: QWARN : WizardTests::testConfiguringCurrentlyRunningSyncthing() QFSFileEngine::open: No file name specified
3: FAIL! : WizardTests::testConfiguringCurrentlyRunningSyncthing() '!cfgCurrentlyRunningRadioButton->isHidden()' returned FALSE. ()
3: Loc: [/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets/tests/wizard.cpp(334)]
3: QDEBUG : WizardTests::cleanupTestCase() terminating Syncthing
3: QDEBUG : WizardTests::cleanupTestCase() Syncthing log during testrun:
3: [start] 2022/12/18 00:47:18 INFO: syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 07:35:59 UTC [noupgrade]
3: [start] 2022/12/18 00:47:18 INFO: Generating ECDSA key and certificate for syncthing...
3: [start] 2022/12/18 00:47:18 INFO: Default folder created and/or linked to new config
3: [start] 2022/12/18 00:47:18 INFO: Default config saved. Edit /tmp/syncthingwidgets_wizard_tests-ADBbgD/config.xml to taste (with Syncthing stopped) or use the GUI
3: [start] 2022/12/18 00:47:18 INFO: Archiving a copy of old config file format at: /tmp/syncthingwidgets_wizard_tests-ADBbgD/config.xml.v0
3: [IJMVV] 2022/12/18 00:47:18 INFO: My ID: IJMVVEB-KMP4IJ6-ERRFOJ4-AUBSKH7-EFXJEQS-CTV4DLD-REVFSFD-HOXXWA2
3: [IJMVV] 2022/12/18 00:47:19 INFO: Single thread SHA256 performance is 1821 MB/s using minio/sha256-simd (478 MB/s using crypto/sha256).
3: [IJMVV] 2022/12/18 00:47:19 INFO: Hashing performance is 609.83 MB/s
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 1...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 2...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 3...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 5...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 6...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 7...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 9...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 10...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 11...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 13...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 14...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 16...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 17...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 19...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Compacting database after migration...
3: [IJMVV] 2022/12/18 00:47:19 INFO: Overall send rate is unlimited, receive rate is unlimited
3: [IJMVV] 2022/12/18 00:47:19 INFO: No stored folder metadata for "default"; recalculating
3: [IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6
QJA3-MPFYMSO-U56GTUK-NA2MIAW
3: [IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
3: [IJMVV] 2022/12/18 00:47:19 INFO: Relay listener (dynamic+https://relays.syncthing.net/endpoint) starting
3: [IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
3: [IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv4 local broadcast discovery on port 21027
3: [IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv6 local multicast discovery on address [ff12::8384]:21027
3: [IJMVV] 2022/12/18 00:47:19 INFO: Loading HTTPS certificate: open /tmp/syncthingwidgets_wizard_tests-ADBbgD/https-cert.pem: no such file or directory
3: [IJMVV] 2022/12/18 00:47:19 INFO: Creating new HTTPS certificate
3: [IJMVV] 2022/12/18 00:47:19 INFO: TCP listener ([::]:44807) starting
3: [IJMVV] 2022/12/18 00:47:19 INFO: QUIC listener ([::]:44807) starting
3: [IJMVV] 2022/12/18 00:47:19 INFO: Ready to synchronize "Default Folder" (default) (sendreceive)
3: [IJMVV] 2022/12/18 00:47:19 INFO: Completed initial scan of sendreceive folder "Default Folder" (default)
3: [IJMVV] 2022/12/18 00:47:19 WARNING: Failed starting API: listen tcp 127.0.0.1:52250: bind: address already in use
3: [IJMVV] 2022/12/18 00:47:19 INFO: QUIC listener ([::]:44807) shutting down
3: [IJMVV] 2022/12/18 00:47:19 INFO: Detected 0 NAT services
3: [IJMVV] 2022/12/18 00:47:19 INFO: Relay listener (dynamic+https://relays.syncthing.net/endpoint) shutting down
3: [IJMVV] 2022/12/18 00:47:19 WARNING: Starting API/GUI: listen tcp 127.0.0.1:52250: bind: address already in use
3:
3: PASS : WizardTests::cleanupTestCase()
3: Totals: 4 passed, 1 failed, 0 skipped, 0 blacklisted, 2122ms
3: ********* Finished testing of WizardTests *********
2/6 Test #3: syncthingwidgets_run_wizard_tests ..............***Failed 2.40 sec
********* Start testing of WizardTests *********
Config: Using QtTest library 5.15.7, Qt 5.15.7 (x86_64-little_endian-lp64 shared (dynamic) release build; by GCC 12.2.0), manjaro unknown
QDEBUG : WizardTests::initTestCase() "HOME dir: /tmp/syncthingwidgets_wizard_tests-ADBbgD"
PASS : WizardTests::initTestCase()
PASS : WizardTests::testShowingSettings()
QWARN : WizardTests::testConfiguringLauncher() This plugin does not support propagateSizeHints()
QWARN : WizardTests::testConfiguringLauncher() QFSFileEngine::open: No file name specified
Info: Launched process, PID: 50442
QWARN : WizardTests::testConfiguringLauncher() QFSFileEngine::open: No file name specified
Info: Launched process, PID: 50448
QDEBUG : WizardTests::testConfiguringLauncher() waiting for Syncthing to write config file
PASS : WizardTests::testConfiguringLauncher()
Info: Launched process, PID: 50495
QWARN : WizardTests::testConfiguringCurrentlyRunningSyncthing() QFSFileEngine::open: No file name specified
FAIL! : WizardTests::testConfiguringCurrentlyRunningSyncthing() '!cfgCurrentlyRunningRadioButton->isHidden()' returned FALSE. ()
Loc: [/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/widgets/tests/wizard.cpp(334)]
QDEBUG : WizardTests::cleanupTestCase() terminating Syncthing
QDEBUG : WizardTests::cleanupTestCase() Syncthing log during testrun:
[start] 2022/12/18 00:47:18 INFO: syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 07:35:59 UTC [noupgrade]
[start] 2022/12/18 00:47:18 INFO: Generating ECDSA key and certificate for syncthing...
[start] 2022/12/18 00:47:18 INFO: Default folder created and/or linked to new config
[start] 2022/12/18 00:47:18 INFO: Default config saved. Edit /tmp/syncthingwidgets_wizard_tests-ADBbgD/config.xml to taste (with Syncthing stopped) or use the GUI
[start] 2022/12/18 00:47:18 INFO: Archiving a copy of old config file format at: /tmp/syncthingwidgets_wizard_tests-ADBbgD/config.xml.v0
[IJMVV] 2022/12/18 00:47:18 INFO: My ID: IJMVVEB-KMP4IJ6-ERRFOJ4-AUBSKH7-EFXJEQS-CTV4DLD-REVFSFD-HOXXWA2
[IJMVV] 2022/12/18 00:47:19 INFO: Single thread SHA256 performance is 1821 MB/s using minio/sha256-simd (478 MB/s using crypto/sha256).
[IJMVV] 2022/12/18 00:47:19 INFO: Hashing performance is 609.83 MB/s
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 1...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 2...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 3...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 5...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 6...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 7...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 9...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 10...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 11...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 13...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 14...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 16...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 17...
[IJMVV] 2022/12/18 00:47:19 INFO: Running database migration 19...
[IJMVV] 2022/12/18 00:47:19 INFO: Compacting database after migration...
[IJMVV] 2022/12/18 00:47:19 INFO: Overall send rate is unlimited, receive rate is unlimited
[IJMVV] 2022/12/18 00:47:19 INFO: No stored folder metadata for "default"; recalculating
[IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA
3-MPFYMSO-U56GTUK-NA2MIAW
[IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJ
A3-MPFYMSO-U56GTUK-NA2MIAW
[IJMVV] 2022/12/18 00:47:19 INFO: Relay listener (dynamic+https://relays.syncthing.net/endpoint) starting
[IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: global discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJ
A3-MPFYMSO-U56GTUK-NA2MIAW
[IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv4 local broadcast discovery on port 21027
[IJMVV] 2022/12/18 00:47:19 INFO: Using discovery mechanism: IPv6 local multicast discovery on address [ff12::8384]:21027
[IJMVV] 2022/12/18 00:47:19 INFO: Loading HTTPS certificate: open /tmp/syncthingwidgets_wizard_tests-ADBbgD/https-cert.pem: no such file or directory
[IJMVV] 2022/12/18 00:47:19 INFO: Creating new HTTPS certificate
[IJMVV] 2022/12/18 00:47:19 INFO: TCP listener ([::]:44807) starting
[IJMVV] 2022/12/18 00:47:19 INFO: QUIC listener ([::]:44807) starting
[IJMVV] 2022/12/18 00:47:19 INFO: Ready to synchronize "Default Folder" (default) (sendreceive)
[IJMVV] 2022/12/18 00:47:19 INFO: Completed initial scan of sendreceive folder "Default Folder" (default)
[IJMVV] 2022/12/18 00:47:19 WARNING: Failed starting API: listen tcp 127.0.0.1:52250: bind: address already in use
[IJMVV] 2022/12/18 00:47:19 INFO: QUIC listener ([::]:44807) shutting down
[IJMVV] 2022/12/18 00:47:19 INFO: Detected 0 NAT services
[IJMVV] 2022/12/18 00:47:19 INFO: Relay listener (dynamic+https://relays.syncthing.net/endpoint) shutting down
[IJMVV] 2022/12/18 00:47:19 WARNING: Starting API/GUI: listen tcp 127.0.0.1:52250: bind: address already in use
PASS : WizardTests::cleanupTestCase()
Totals: 4 passed, 1 failed, 0 skipped, 0 blacklisted, 2122ms
********* Finished testing of WizardTests *********
test 6
Start 6: syncthingfileitemaction_appstream_validation
6: Test command: /usr/bin/appstreamcli "validate" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/fileitemactionplugin/resources/syncthingfileitemaction
.appdata.xml"
6: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/fileitemactionplugin
6: Test timeout computed to be: 10000000
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Login successful for username nobody.
1: - Connection error: Unable to request Syncthing config: Error transferring http://nobody:supersecret@127.0.0.1:52250/rest/system/config - server replied: Forbidden
1: - Connection status changed to: disconnected
1: - Connection status changed to: disconnected
1:
1: - Connecting initially ...
1: - Connection status changed to: connected, paused
1:
1: - Reconnecting ...
1: - Connection status changed to: reconnecting
1:
1: - Waiting for dirs/devs after reconnect ...
1: - Connection status changed to: connected, paused
1:
1: - Resuming all devices ...
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Login successful for username nobody.
1: [F77GG] 2022/12/18 00:47:20 INFO: Resuming MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Device MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7 was resumed
1: - Connection status changed to: connected
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Configuration was saved
1:
1: - Resuming all dirs ...
1: [F77GG] 2022/12/18 00:47:20 INFO: No stored folder metadata for "test2"; recalculating
1: [F77GG] 2022/12/18 00:47:20 INFO: Ready to synchronize "Test dir 2" (test2) (sendreceive)
1: [F77GG] 2022/12/18 00:47:20 INFO: Unpaused folder "Test dir 2" (test2) (sendreceive)
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder test2 (Test dir 2) was resumed
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Configuration was saved
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now scan-waiting
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now scanning
1: [F77GG] 2022/12/18 00:47:20 INFO: Completed initial scan of sendreceive folder "Test dir 2" (test2)
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now idle
1:
1: - Pause dir 1 ...
1: [F77GG] 2022/12/18 00:47:20 INFO: Paused folder test1 (sendreceive)
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder test1 () was paused
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Configuration was saved
1:
1: - Requesting log ...
1:
1: - Requesting QR-Code for own device ID ...
1:
1: - Disconnecting while there are outstanding requests ...
1: - Connection status changed to: disconnected
1:
1: - Connecting with settings ...
1: - Connection status changed to: reconnecting
1: - Connection status changed to: connected
1:
1: - Requesting rescan ...
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now scan-waiting
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now scanning
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Folder "test2" is now idle
1: - Connection status changed to: connected, scanning
1: - Connection status changed to: connected
1: - Connection error: Unable to request rescan: Error transferring http://127.0.0.1:52250/rest/db/scan?folder=non-existing-dir&sub=sub/path - server replied: Internal Serv
er Error
1:
1: - Changing arbitrary config ...
1: [F77GG] 2022/12/18 00:47:20 VERBOSE: Configuration was saved
1:
1: - Waiting for Syncthing to terminate ...
1: [monitor] 2022/12/18 00:47:20 INFO: Signal 15 received; exiting
1: [F77GG] 2022/12/18 00:47:20 INFO: Detected 1 NAT service
6:
6: ✔ Validation was successful.
3/6 Test #6: syncthingfileitemaction_appstream_validation ... Passed 0.90 sec
5:
5: ✔ Validation was successful.
4/6 Test #5: syncthingplasmoid_appstream_validation ......... Passed 1.47 sec
1: [F77GG] 2022/12/18 00:47:21 INFO: TCP listener (127.0.0.1:32452) shutting down
1: [F77GG] 2022/12/18 00:47:21 INFO: Exiting
1: Info: Launched process 50381 exited with error: No child processes
1:
1: - Syncthing terminated with exit code -1.
1: ......
1:
1:
1: OK (7 tests)
1:
1:
1: Tests successful
5/6 Test #1: syncthingconnector_run_tests ................... Passed 3.56 sec
test 2
Start 2: syncthingctl_run_tests
2: Test command: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl_tests "-p" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtr
ay-1.3.1/cli/testfiles" "-w" "/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir" "-a" "/home/janekf/.local/cache/yay/syncthingtray/src/s
yncthingtray-1.3.1/cli/syncthingctl"
2: Working Directory: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli
2: Test timeout computed to be: 10000000
2: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testfiles/
2: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testfiles/
2: ./testfiles/
2: Directory used to store working copies:
2: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/
2: Executing test cases ...
2: .
2: - Setup configuration for Syncthing tests ...
2: - Using timeout factor 3
2:
2: - Launching Syncthing: syncthing -gui-address=http://127.0.0.1:52250 -gui-apikey=syncthingtestinstance -home=/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtra
y-1.3.1/cli/testworkingdir/testconfig -no-browser -verbose
2: Info: Launched process, PID: 50521
2:
2: Waiting till Syncthing GUI becomes available ...
2: [start] 2022/12/18 00:47:21 INFO: syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 07:35:59 UTC [noupgrade]
2: [start] 2022/12/18 00:47:21 INFO: Generating ECDSA key and certificate for syncthing...
2: [start] 2022/12/18 00:47:21 INFO: Archiving a copy of old config file format at: /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/te
stconfig/config.xml.v28
2: [7DSC2] 2022/12/18 00:47:21 INFO: My ID: 7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM
2: [7DSC2] 2022/12/18 00:47:22 INFO: Single thread SHA256 performance is 975 MB/s using minio/sha256-simd (254 MB/s using crypto/sha256).
2: [7DSC2] 2022/12/18 00:47:22 VERBOSE: Starting up (/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/testconfig)
2: [7DSC2] 2022/12/18 00:47:22 INFO: Hashing performance is 611.39 MB/s
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 1...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 2...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 3...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 5...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 6...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 7...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 9...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 10...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 11...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 13...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 14...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 16...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 17...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Running database migration 19...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Compacting database after migration...
2: [7DSC2] 2022/12/18 00:47:22 INFO: Overall send rate is unlimited, receive rate is unlimited
2: [7DSC2] 2022/12/18 00:47:22 INFO: No stored folder metadata for "test1"; recalculating
2: [7DSC2] 2022/12/18 00:47:22 INFO: Using discovery mechanism: global discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6
QJA3-MPFYMSO-U56GTUK-NA2MIAW
2: [7DSC2] 2022/12/18 00:47:22 INFO: Using discovery mechanism: global discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2: [7DSC2] 2022/12/18 00:47:22 INFO: Using discovery mechanism: global discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG
6QJA3-MPFYMSO-U56GTUK-NA2MIAW
2: [7DSC2] 2022/12/18 00:47:22 INFO: TCP listener (127.0.0.1:32452) starting
2: [7DSC2] 2022/12/18 00:47:22 INFO: Ready to synchronize test1 (sendreceive)
2: [7DSC2] 2022/12/18 00:47:22 VERBOSE: Listen address tcp://127.0.0.1:32452 resolution has changed: lan addresses: [tcp://127.0.0.1:32452] wan addresses: [tcp://0.0.0.0:0 t
cp://127.0.0.1:32452]
2: [7DSC2] 2022/12/18 00:47:22 INFO: Using discovery mechanism: IPv4 local broadcast discovery on port 21027
2: [7DSC2] 2022/12/18 00:47:22 INFO: Using discovery mechanism: IPv6 local multicast discovery on address [ff12::8384]:21027
2: [7DSC2] 2022/12/18 00:47:22 INFO: Loading HTTPS certificate: open /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/testconfig/https-
cert.pem: no such file or directory
2: [7DSC2] 2022/12/18 00:47:22 INFO: Creating new HTTPS certificate
2: [7DSC2] 2022/12/18 00:47:22 VERBOSE: Folder "test1" is now scan-waiting
2: [7DSC2] 2022/12/18 00:47:22 VERBOSE: Folder "test1" is now scanning
2: [7DSC2] 2022/12/18 00:47:22 INFO: Completed initial scan of sendreceive folder test1
2: [7DSC2] 2022/12/18 00:47:22 VERBOSE: Folder "test1" is now idle
2: [7DSC2] 2022/12/18 00:47:22 INFO: GUI and API listening on 127.0.0.1:52250
2: [7DSC2] 2022/12/18 00:47:22 INFO: Access the GUI via the following URL: http://127.0.0.1:52250/
2: [7DSC2] 2022/12/18 00:47:22 INFO: My name is "grasshopper"
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl status --api-key syncthingtestinstance --url http://localhost:52250 --no-color
Overall statisticsg to http://localhost:52250 ...
2: Status idle
2: Global 0 file(s), 0 dir(s), 0 bytes
2: Local 0 file(s), 0 dir(s), 0 bytes
2: Incoming traffic 0 bytes
2: Outgoing traffic 0 bytes
2: Connected to no other devices
2: Uptime 1 s
2: Version syncthing v1.22.1 "Fermium Flea" (go1.19.3 linux-amd64) syncthing@archlinux 2022-11-03 07:35:59 UTC [noupgrade]
2:
2: Directories
2: - test2
2: Label Test dir 2
2: Path /tmp/some/path/2/
2: Status paused
2: Shared with Test dev 2
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval 365 d, file system watcher disabled
2:
2: - test1
2: Path /tmp/some/path/1/
2: Status idle
2: Global 0 file(s), 0 dir(s), 0 bytes
2: Local 0 file(s), 0 dir(s), 0 bytes
2: Last scan time 2022-12-18 00:47:22
2: Shared with Test dev 2
2: Test dev 1
2: Remote progress all up-to-date
2: Test dev 1 0 bytes / 0 bytes (100 %)
2: Test dev 2 0 bytes / 0 bytes (100 %)
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval 2 h, file system watcher disabled
2:
2: Devices
2: - grasshopper
2: ID 7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM
2: Status own device
2: Addresses dynamic
2: Compression metadata
2:
2: - Test dev 1
2: ID 6EIS2PN-J2IHWGS-AXS3YUL-HC5FT3K-77ZXTLL-AKQLJ4C-7SWVPUS-AZW4RQ4
2: Status disconnected
2: Addresses dynamic
2: Compression metadata
2:
2: - Test dev 2
2: ID MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7
2: Status paused
2: Addresses tcp://192.168.2.2:22001
2: Compression metadata
2:
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl log --api-key syncthingtestinstance --url http://localhost:52250
2: 2022-12-18 00:47:21: My ID: 7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM
2: 2022-12-18 00:47:22: Single thread SHA256 performance is 975 MB/s using minio/sha256-simd (254 MB/s using crypto/sha256).
2: 2022-12-18 00:47:22: Starting up (/home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/testconfig)
2: 2022-12-18 00:47:22: Hashing performance is 611.39 MB/s
2: 2022-12-18 00:47:22: Running database migration 1...
2: 2022-12-18 00:47:22: Running database migration 2...
2: 2022-12-18 00:47:22: Running database migration 3...
2: 2022-12-18 00:47:22: Running database migration 5...
2: 2022-12-18 00:47:22: Running database migration 6...
2: 2022-12-18 00:47:22: Running database migration 7...
2: 2022-12-18 00:47:22: ...
2: 2022-12-18 00:47:22: Running database migration 9...
2: 2022-12-18 00:47:22: Running database migration 10...
2: 2022-12-18 00:47:22: Running database migration 11...
2: 2022-12-18 00:47:22: Running database migration 13...
2: 2022-12-18 00:47:22: Running database migration 14...
2: 2022-12-18 00:47:22: Running database migration 16...
2: 2022-12-18 00:47:22: Running database migration 17...
2: 2022-12-18 00:47:22: Running database migration 19...
2: 2022-12-18 00:47:22: Compacting database after migration...
2: 2022-12-18 00:47:22: Overall send rate is unlimited, receive rate is unlimited
2: 2022-12-18 00:47:22: No stored folder metadata for "test1"; recalculating
2: 2022-12-18 00:47:22: Using discovery mechanism: global discovery server https://discovery.syncthing.net/v2/?noannounce&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO-
U56GTUK-NA2MIAW
2: 2022-12-18 00:47:22: Using discovery mechanism: global discovery server https://discovery-v4.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO
-U56GTUK-NA2MIAW
2: 2022-12-18 00:47:22: Using discovery mechanism: global discovery server https://discovery-v6.syncthing.net/v2/?nolookup&id=LYXKCHX-VI3NYZR-ALCJBHF-WMZYSPK-QG6QJA3-MPFYMSO
-U56GTUK-NA2MIAW
2: 2022-12-18 00:47:22: TCP listener (127.0.0.1:32452) starting
2: 2022-12-18 00:47:22: Ready to synchronize test1 (sendreceive)
2: 2022-12-18 00:47:22: Listen address tcp://127.0.0.1:32452 resolution has changed: lan addresses: [tcp://127.0.0.1:32452] wan addresses: [tcp://0.0.0.0:0 tcp://127.0.0.1:3
2452]
2: 2022-12-18 00:47:22: Using discovery mechanism: IPv4 local broadcast discovery on port 21027
2: 2022-12-18 00:47:22: Using discovery mechanism: IPv6 local multicast discovery on address [ff12::8384]:21027
2: 2022-12-18 00:47:22: Loading HTTPS certificate: open /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/testworkingdir/testconfig/https-cert.pem: no
such file or directory
2: 2022-12-18 00:47:22: Creating new HTTPS certificate
2: 2022-12-18 00:47:22: Folder "test1" is now scan-waiting
2: 2022-12-18 00:47:22: Folder "test1" is now scanning
2: 2022-12-18 00:47:22: Completed initial scan of sendreceive folder test1
2: 2022-12-18 00:47:22: Folder "test1" is now idle
2: 2022-12-18 00:47:22: GUI and API listening on 127.0.0.1:52250
2: 2022-12-18 00:47:22: Access the GUI via the following URL: http://127.0.0.1:52250/
2: 2022-12-18 00:47:22: My name is "grasshopper"
2: 2022-12-18 00:47:22: Device MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7 is "Test dev 2" at [tcp://192.168.2.2:22001]
2: 2022-12-18 00:47:22: Device 6EIS2PN-J2IHWGS-AXS3YUL-HC5FT3K-77ZXTLL-AKQLJ4C-7SWVPUS-AZW4RQ4 is "Test dev 1" at [dynamic]
2: 2022-12-18 00:47:22: Startup complete
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl resume --dir test2
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl status --all-dirs
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl pause --dir test2
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl status --dir test2
2: Directories
2: - test2
2: Label Test dir 2
2: Path /tmp/some/path/2/
2: Status paused
2: Shared with Test dev 2
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval 365 d, file system watcher disabled
2:
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl cat
2: {
2: "defaults": {
2: "device": {
2: "addresses": [
2: "dynamic"
2: ],
2: "allowedNetworks": [
2: ],
2: "autoAcceptFolders": false,
2: "certName": "",
2: "compression": "metadata",
2: "deviceID": "",
2: "ignoredFolders": [
2: ],
2: "introducedBy": "",
2: "introducer": false,
2: "maxRecvKbps": 0,
2: "maxRequestKiB": 0,
2: "maxSendKbps": 0,
2: "name": "",
2: "paused": false,
2: "remoteGUIPort": 0,
2: "skipIntroductionRemovals": false,
2: "untrusted": false
2: },
2: "folder": {
2: "autoNormalize": true,
2: "blockPullOrder": "standard",
2: "caseSensitiveFS": false,
2: "copiers": 0,
2: "copyOwnershipFromParent": false,
2: "copyRangeMethod": "standard",
2: "devices": [
2: {
2: "deviceID": "7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: }
2: ],
2: "disableFsync": false,
2: "disableSparseFiles": false,
2: "disableTempIndexes": false,
2: "filesystemType": "basic",
2: "fsWatcherDelayS": 10,
2: "fsWatcherEnabled": true,
2: "hashers": 0,
2: "id": "",
2: "ignoreDelete": false,
2: "ignorePerms": false,
2: "junctionsAsDirs": false,
2: "label": "",
2: "markerName": ".stfolder",
2: "maxConcurrentWrites": 2,
2: "maxConflicts": 10,
2: "minDiskFree": {
2: "unit": "%",
2: "value": 1
2: },
2: "modTimeWindowS": 0,
2: "order": "random",
2: "path": "~",
2: "paused": false,
2: "pullerMaxPendingKiB": 0,
2: "pullerPauseS": 0,
2: "rescanIntervalS": 3600,
2: "scanProgressIntervalS": 0,
2: "sendOwnership": false,
2: "sendXattrs": false,
2: "syncOwnership": false,
2: "syncXattrs": false,
2: "type": "sendreceive",
2: "versioning": {
2: "cleanupIntervalS": 3600,
2: "fsPath": "",
2: "fsType": "basic",
2: "params": {
2: },
2: "type": ""
2: },
2: "weakHashThresholdPct": 25,
2: "xattrFilter": {
2: "entries": [
2: ],
2: "maxSingleEntrySize": 1024,
2: "maxTotalSize": 4096
2: }
2: },
2: "ignores": {
2: "lines": [
2: ]
2: }
2: },
2: "devices": [
2: {
2: "addresses": [
2: "tcp://192.168.2.2:22001"
2: ],
2: "allowedNetworks": [
2: ],
2: "autoAcceptFolders": false,
2: "certName": "",
2: "compression": "metadata",
2: "deviceID": "MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7",
2: "ignoredFolders": [
2: ],
2: "introducedBy": "",
2: "introducer": false,
2: "maxRecvKbps": 0,
2: "maxRequestKiB": 0,
2: "maxSendKbps": 0,
2: "name": "Test dev 2",
2: "paused": true,
2: "remoteGUIPort": 0,
2: "skipIntroductionRemovals": false,
2: "untrusted": false
2: },
2: {
2: "addresses": [
2: "dynamic"
2: ],
2: "allowedNetworks": [
2: ],
2: "autoAcceptFolders": false,
2: "certName": "",
2: "compression": "metadata",
2: "deviceID": "6EIS2PN-J2IHWGS-AXS3YUL-HC5FT3K-77ZXTLL-AKQLJ4C-7SWVPUS-AZW4RQ4",
2: "ignoredFolders": [
2: ],
2: "introducedBy": "",
2: "introducer": true,
2: "maxRecvKbps": 0,
2: "maxRequestKiB": 0,
2: "maxSendKbps": 0,
2: "name": "Test dev 1",
2: "paused": false,
2: "remoteGUIPort": 0,
2: "skipIntroductionRemovals": false,
2: "untrusted": false
2: },
2: {
2: "addresses": [
2: "dynamic"
2: ],
2: "allowedNetworks": [
2: ],
2: "autoAcceptFolders": false,
2: "certName": "",
2: "compression": "metadata",
2: "deviceID": "7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM",
2: "ignoredFolders": [
2: ],
2: "introducedBy": "",
2: "introducer": false,
2: "maxRecvKbps": 0,
2: "maxRequestKiB": 0,
2: "maxSendKbps": 0,
2: "name": "grasshopper",
2: "paused": false,
2: "remoteGUIPort": 0,
2: "skipIntroductionRemovals": false,
2: "untrusted": false
2: }
2: ],
2: "folders": [
2: {
2: "autoNormalize": true,
2: "blockPullOrder": "standard",
2: "caseSensitiveFS": false,
2: "copiers": 1,
2: "copyOwnershipFromParent": false,
2: "copyRangeMethod": "standard",
2: "devices": [
2: {
2: "deviceID": "MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: },
2: {
2: "deviceID": "6EIS2PN-J2IHWGS-AXS3YUL-HC5FT3K-77ZXTLL-AKQLJ4C-7SWVPUS-AZW4RQ4",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: },
2: {
2: "deviceID": "7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: }
2: ],
2: "disableFsync": false,
2: "disableSparseFiles": false,
2: "disableTempIndexes": false,
2: "filesystemType": "basic",
2: "fsWatcherDelayS": 10,
2: "fsWatcherEnabled": false,
2: "hashers": 0,
2: "id": "test1",
2: "ignoreDelete": false,
2: "ignorePerms": false,
2: "junctionsAsDirs": true,
2: "label": "",
2: "markerName": ".stfolder",
2: "maxConcurrentWrites": 2,
2: "maxConflicts": -1,
2: "minDiskFree": {
2: "unit": "%",
2: "value": 1
2: },
2: "modTimeWindowS": 0,
2: "order": "random",
2: "path": "/tmp/some/path/1/",
2: "paused": false,
2: "pullerMaxPendingKiB": 2048,
2: "pullerPauseS": 0,
2: "rescanIntervalS": 7200,
2: "scanProgressIntervalS": 0,
2: "sendOwnership": false,
2: "sendXattrs": false,
2: "syncOwnership": false,
2: "syncXattrs": false,
2: "type": "sendreceive",
2: "versioning": {
2: "cleanupIntervalS": 3600,
2: "fsPath": "",
2: "fsType": "basic",
2: "params": {
2: },
2: "type": ""
2: },
2: "weakHashThresholdPct": 25,
2: "xattrFilter": {
2: "entries": [
2: ],
2: "maxSingleEntrySize": 0,
2: "maxTotalSize": 0
2: }
2: },
2: {
2: "autoNormalize": true,
2: "blockPullOrder": "standard",
2: "caseSensitiveFS": false,
2: "copiers": 1,
2: "copyOwnershipFromParent": false,
2: "copyRangeMethod": "standard",
2: "devices": [
2: {
2: "deviceID": "MMGUI6U-WUEZQCP-XZZ6VYB-LCT4TVC-ER2HAVX-QYT6X7D-S6ZSG2B-323KLQ7",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: },
2: {
2: "deviceID": "7DSC2GN-GGOQ6LM-HCWY6XD-3SO4YP5-YNXIN3O-3TLA3QO-X43H7YE-IIX6JQM",
2: "encryptionPassword": "",
2: "introducedBy": ""
2: }
2: ],
2: "disableFsync": false,
2: "disableSparseFiles": false,
2: "disableTempIndexes": false,
2: "filesystemType": "basic",
2: "fsWatcherDelayS": 10,
2: "fsWatcherEnabled": false,
2: "hashers": 0,
2: "id": "test2",
2: "ignoreDelete": false,
2: "ignorePerms": false,
2: "junctionsAsDirs": true,
2: "label": "Test dir 2",
2: "markerName": ".stfolder",
2: "maxConcurrentWrites": 2,
2: "maxConflicts": -1,
2: "minDiskFree": {
2: "unit": "%",
2: "value": 1
2: },
2: "modTimeWindowS": 0,
2: "order": "random",
2: "path": "/tmp/some/path/2/",
2: "paused": true,
2: "pullerMaxPendingKiB": 2048,
2: "pullerPauseS": 0,
2: "rescanIntervalS": 31536000,
2: "scanProgressIntervalS": 0,
2: "sendOwnership": false,
2: "sendXattrs": false,
2: "syncOwnership": false,
2: "syncXattrs": false,
2: "type": "sendreceive",
2: "versioning": {
2: "cleanupIntervalS": 3600,
2: "fsPath": "",
2: "fsType": "basic",
2: "params": {
2: },
2: "type": ""
2: },
2: "weakHashThresholdPct": 25,
2: "xattrFilter": {
2: "entries": [
2: ],
2: "maxSingleEntrySize": 0,
2: "maxTotalSize": 0
2: }
2: }
2: ],
2: "gui": {
2: "address": "127.0.0.1:4001",
2: "apiKey": "syncthingconnectortest",
2: "authMode": "static",
2: "debugging": false,
2: "enabled": true,
2: "insecureAdminAccess": false,
2: "insecureAllowFrameLoading": false,
2: "insecureSkipHostcheck": false,
2: "password": "$2a$12$35MnbsQgQNn1hzPYK/lWXOaP.U5D2TO0nuuQy2M4gsqJB4ff4q2RK",
2: "theme": "default",
2: "unixSocketPermissions": "",
2: "useTLS": false,
2: "user": "nobody"
2: },
2: "ldap": {
2: "address": "",
2: "bindDN": "",
2: "insecureSkipVerify": false,
2: "searchBaseDN": "",
2: "searchFilter": "",
2: "transport": "plain"
2: },
2: "options": {
2: "alwaysLocalNets": [
2: ],
2: "announceLANAddresses": true,
2: "autoUpgradeIntervalH": 0,
2: "cacheIgnoredFiles": false,
2: "connectionLimitEnough": 0,
2: "connectionLimitMax": 0,
2: "crURL": "https://crash.syncthing.net/newcrash",
2: "crashReportingEnabled": true,
2: "databaseTuning": "auto",
2: "featureFlags": [
2: ],
2: "globalAnnounceEnabled": true,
2: "globalAnnounceServers": [
2: "default"
2: ],
2: "insecureAllowOldTLSVersions": false,
2: "keepTemporariesH": 24,
2: "limitBandwidthInLan": false,
2: "listenAddresses": [
2: "tcp://127.0.0.1:32452"
2: ],
2: "localAnnounceEnabled": true,
2: "localAnnounceMCAddr": "[ff12::8384]:21027",
2: "localAnnouncePort": 21027,
2: "maxConcurrentIncomingRequestKiB": 0,
2: "maxFolderConcurrency": 0,
2: "maxRecvKbps": 0,
2: "maxSendKbps": 0,
2: "minHomeDiskFree": {
2: "unit": "%",
2: "value": 1
2: },
2: "natEnabled": true,
2: "natLeaseMinutes": 0,
2: "natRenewalMinutes": 30,
2: "natTimeoutSeconds": 10,
2: "overwriteRemoteDeviceNamesOnConnect": false,
2: "progressUpdateIntervalS": 5,
2: "reconnectionIntervalS": 60,
2: "relayReconnectIntervalM": 10,
2: "relaysEnabled": true,
2: "releasesURL": "https://upgrades.syncthing.net/meta.json",
2: "sendFullIndexOnUpgrade": false,
2: "setLowPriority": true,
2: "startBrowser": true,
2: "stunKeepaliveMinS": 20,
2: "stunKeepaliveStartS": 180,
2: "stunServers": [
2: "default"
2: ],
2: "tempIndexMinBlocks": 10,
2: "trafficClass": 0,
2: "unackedNotificationIDs": [
2: "fsWatcherNotification",
2: "crAutoEnabled"
2: ],
2: "upgradeToPreReleases": true,
2: "urAccepted": 2,
2: "urInitialDelayS": 1800,
2: "urPostInsecurely": false,
2: "urSeen": 2,
2: "urURL": "https://data.syncthing.net/newdata",
2: "urUniqueId": "fhjO-ICd"
2: },
2: "remoteIgnoredDevices": [
2: ],
2: "version": 37
2: }
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl edit --js-lines assignIfPresent(findFolder('test1'), 'rescanIntervalS', 0);
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl status --dir test1
2: Directories
2: - test1
2: Path /tmp/some/path/1/
2: Status idle
2: Global 0 file(s), 0 dir(s), 0 bytes
2: Local 0 file(s), 0 dir(s), 0 bytes
2: Last scan time 2022-12-18 00:47:23
2: Shared with Test dev 2
2: Test dev 1
2: Remote progress all up-to-date
2: Test dev 1 0 bytes / 0 bytes (100 %)
2: Test dev 2 0 bytes / 0 bytes (100 %)
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval file system watcher and periodic rescan disabled
2:
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl rescan test1
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl status --dir test1
2: Directories
2: - test1
2: Path /tmp/some/path/1/
2: Status idle
2: Global 1 file(s), 0 dir(s), 3 bytes
2: Local 1 file(s), 0 dir(s), 3 bytes
2: Last scan time 2022-12-18 00:47:23
2: Shared with Test dev 2
2: Test dev 1
2: Remote progress some need bytes
2: Test dev 2 0 bytes / 3 bytes (0 %)
2: Test dev 1 0 bytes / 3 bytes (0 %)
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval file system watcher and periodic rescan disabled
2:
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl pwd rescan
2: - /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/cli/syncthingctl pwd status
2: - test1
2: Path /tmp/some/path/1/
2: Status idle
2: Global 2 file(s), 1 dir(s), 134 bytes
2: Local 2 file(s), 1 dir(s), 134 bytes
2: Last scan time 2022-12-18 00:47:23
2: Shared with Test dev 2
2: Test dev 1
2: Remote progress some need bytes
2: Test dev 2 0 bytes / 134 bytes (0 %)
2: Test dev 1 0 bytes / 134 bytes (0 %)
2: Type Send & Receive
2: Ignore permissions no
2: Auto-normalize yes
2: Rescan interval file system watcher and periodic rescan disabled
2:
2:
2: - Waiting for Syncthing to terminate ...
2: Info: Launched process 50521 exited with error: No child processes
2:
2: - Syncthing terminated with exit code -1.
2:
2:
2:
2: OK (1 tests)
2:
2:
2: Tests successful
6/6 Test #2: syncthingctl_run_tests ......................... Passed 2.53 sec
83% tests passed, 1 tests failed out of 6
Total Test time (real) = 6.10 sec
The following tests FAILED:
3 - syncthingwidgets_run_wizard_tests (Failed)
Errors while running CTest
FAILED: connector/CMakeFiles/check /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1/connector/CMakeFiles/check
cd /home/janekf/.local/cache/yay/syncthingtray/src/syncthingtray-1.3.1 && /usr/bin/ctest -V
ninja: build stopped: subcommand failed.
Too bad. Then I'm out of ideas again.
This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.
Maybe this works on now. I've fixed a race condition on master.
This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.
This reliably happens evey time right now: