p 127.0.0.1:38888->127.0.0.1:41277: i/o timeout
qemu: meta-data=/dev/vda               isize=512    agcount=8, agsize=1638400 blks
qemu:          =                       sectsz=4096  attr=2, projid32bit=1
qemu:          =                       crc=1        finobt=1, sparse=1, rmapbt=1
qemu:          =                       reflink=1    bigtime=1 inobtcount=1 nrext64=1
qemu:          =                       exchange=1   metadir=0
qemu: data     =                       bsize=4096   blocks=13107200, imaxpct=25
qemu:          =                       sunit=0      swidth=0 blks
qemu: naming   =version 2              bsize=4096   ascii-ci=0, ftype=1, parent=1
qemu: log      =internal log           bsize=4096   blocks=16384, version=2
qemu:          =                       sectsz=4096  sunit=1 blks, lazy-count=1
qemu: realtime =none                   extsz=4096   blocks=0, rtextents=0
qemu:          =                       rgcount=0    rgsize=0 extents
qemu:          =                       zoned=0      start=0 reserved=0
qemu: Discarding blocks...Done.
qemu: [INIT] Checking for init.d scripts...
qemu: [INIT] No /opt/melange/init.d directory (optional, skipping)
qemu: ssh-keygen: generating new host keys: RSA ECDSA 
qemu: Server listening on 0.0.0.0 port 2223.
qemu: Server listening on 0.0.0.0 port 22.
conn read: read tcp 127.0.0.1:38890->127.0.0.1:41277: i/o timeout
qemu: VM started successfully, SSH server is up
qemu: Connection closed by 10.0.2.2 port 59984
qemu: verifying VM host key against pre-provisioned key
qemu: Accepted publickey for root from 10.0.2.2 port 59994 ssh2: ECDSA SHA256:dbJwCNUzN0hOHYwqIE9D8oIQCKMFf8lz8J+l5STVoSE
qemu: VM host key successfully verified against pre-provisioned key
qemu: Connection closed by 10.0.2.2 port 59994
qemu: Accepted publickey for root from 10.0.2.2 port 60002 ssh2: ECDSA SHA256:dbJwCNUzN0hOHYwqIE9D8oIQCKMFf8lz8J+l5STVoSE
qemu: Accepted publickey for root from 10.0.2.2 port 60014 ssh2: ECDSA SHA256:dbJwCNUzN0hOHYwqIE9D8oIQCKMFf8lz8J+l5STVoSE
qemu: Accepted publickey for root from 10.0.2.2 port 60008 ssh2: ECDSA SHA256:dbJwCNUzN0hOHYwqIE9D8oIQCKMFf8lz8J+l5STVoSE
qemu: running kernel version: 6.16.10-r2-qemu-generic #Chainguard SMP PREEMPT_DYNAMIC Fri Oct  3 22:31:32 UTC 2025
qemu: setting up local workspace
qemu: unmounting host workspace from guest
SeaweedFS: store billions of files and serve them fast!
    admin       start SeaweedFS web admin interface
    filer.backup resume-able continuously replicate files from a SeaweedFS cluster to another location defined in replication.toml
    filer.sync  resumable continuous synchronization between two active-active or active-passive SeaweedFS clusters
    mini        start a complete SeaweedFS setup optimized for S3 beginners and small/dev use cases
    sql         advanced SQL query interface for SeaweedFS MQ topics with multiple execution modes
    version     print SeaweedFS version
    sftp        start an SFTP server that is backed by a SeaweedFS filer
qemu: sending shutdown signal
running test pipeline for subpackage seaweedfs-iamguarded-compat
melange devel with runner qemu is testing:
image configuration:
  contents:
    build repositories: []
    runtime repositories: []
    repositories: []
    keyring:      []
    packages:     [bash busybox coreutils curl findutils git gnutar grep jq seaweedfs seaweedfs-iamguarded-compat sed symlink-check wait-for-it]
  accounts:
    runas:  
    users:
      - uid=1000(build) gid=1000
    groups:
      - gid=1000(build) members=[build]
installing wolfi-baselayout (20230201-r25)
installing ca-certificates-bundle (20251003-r0)
installing ncurses-terminfo-base (6.6_p20251230-r0)
installing libgcc (15.2.0-r6)
installing glibc-locale-posix (2.42-r4)
installing glibc (2.42-r4)
installing ld-linux (2.42-r4)
installing ncurses (6.6_p20251230-r0)
installing bash (5.3-r3)
installing libxcrypt (4.5.2-r0)
installing libcrypt1 (2.42-r4)
installing busybox (1.37.0-r50)
installing libacl1 (2.3.2-r54)
installing libattr1 (2.5.2-r54)
installing libpcre2-8-0 (10.47-r0)
installing libsepol (3.9-r1)
installing libselinux (3.9-r1)
installing libcrypto3 (3.6.0-r6)
installing coreutils (9.9-r1)
installing libunistring (1.4.1-r1)
installing libidn2 (2.3.8-r3)
installing libpsl (0.21.5-r6)
installing nghttp3 (1.14.0-r0)
installing libbrotlicommon1 (1.2.0-r1)
installing libbrotlidec1 (1.2.0-r1)
installing zlib (1.3.1.2-r0)
installing libnghttp2-14 (1.68.0-r0)
installing readline (8.3-r1)
installing sqlite-libs (3.51.1-r0)
installing heimdal-libs (7.8.0-r42)
installing gdbm (1.26-r1)
installing cyrus-sasl (2.1.28-r45)
installing libssl3 (3.6.0-r6)
installing libldap-2.6 (2.6.10-r7)
installing libverto (0.3.2-r6)
installing krb5-conf (1.0-r7)
installing libcom_err (1.47.3-r1)
installing keyutils-libs (1.6.3-r37)
installing krb5-libs (1.22.1-r1)
installing libcurl-openssl4 (8.18.0-r0)
installing curl (8.18.0-r0)
installing findutils (4.10.0-r5)
installing libexpat1 (2.7.3-r0)
installing git (2.52.0-r0)
installing gnutar-rmt (1.35-r7)
installing gnutar (1.35-r7)
installing grep (3.12-r4)
installing oniguruma (6.9.10-r1)
installing jq (1.8.1-r3)
installing fuse2 (2.9.9-r53)
installing seaweedfs (4.05-r2)
installing libproc-2-0 (4.0.5-r42)
installing procps (4.0.5-r42)
installing seaweedfs-iamguarded-compat (4.05-r2)
installing sed (4.9-r7)
installing symlink-check (0.0.35-r1)
installing wait-for-it (0.20200823-r7)
installing wolfi-keys (1-r12)
installing apk-tools (2.14.10-r9)
installing wolfi-base (1-r7)
qemu: generating ssh key pairs for ephemeral VM
qemu: generating SSH host key for VM
qemu: starting VM
qemu: waiting for SSH
conn read: read tcp 127.0.0.1:57022->127.0.0.1:45767: i/o timeout
qemu: meta-data=/dev/vda               isize=512    agcount=8, agsize=1638400 blks
qemu:          =                       sectsz=4096  attr=2, projid32bit=1
qemu:          =                       crc=1        finobt=1, sparse=1, rmapbt=1
qemu:          =                       reflink=1    bigtime=1 inobtcount=1 nrext64=1
qemu:          =                       exchange=1   metadir=0
qemu: data     =                       bsize=4096   blocks=13107200, imaxpct=25
qemu:          =                       sunit=0      swidth=0 blks
qemu: naming   =version 2              bsize=4096   ascii-ci=0, ftype=1, parent=1
qemu: log      =internal log           bsize=4096   blocks=16384, version=2
qemu:          =                       sectsz=4096  sunit=1 blks, lazy-count=1
qemu: realtime =none                   extsz=4096   blocks=0, rtextents=0
qemu:          =                       rgcount=0    rgsize=0 extents
qemu:          =                       zoned=0      start=0 reserved=0
qemu: Discarding blocks...Done.
qemu: [INIT] Checking for init.d scripts...
qemu: [INIT] No /opt/melange/init.d directory (optional, skipping)
qemu: ssh-keygen: generating new host keys: RSA ECDSA 
qemu: Server listening on 0.0.0.0 port 2223.
qemu: Server listening on 0.0.0.0 port 22.
conn read: read tcp 127.0.0.1:57028->127.0.0.1:45767: i/o timeout
qemu: VM started successfully, SSH server is up
qemu: Connection closed by 10.0.2.2 port 57034
qemu: verifying VM host key against pre-provisioned key
qemu: Accepted publickey for root from 10.0.2.2 port 57046 ssh2: ECDSA SHA256:/xb+EsXzNILn854Xq24Og6JKTe5xlketDF/FwfrLkZc
qemu: VM host key successfully verified against pre-provisioned key
qemu: Connection closed by 10.0.2.2 port 57046
qemu: Accepted publickey for root from 10.0.2.2 port 57048 ssh2: ECDSA SHA256:/xb+EsXzNILn854Xq24Og6JKTe5xlketDF/FwfrLkZc
qemu: Accepted publickey for root from 10.0.2.2 port 51302 ssh2: ECDSA SHA256:/xb+EsXzNILn854Xq24Og6JKTe5xlketDF/FwfrLkZc
qemu: Accepted publickey for root from 10.0.2.2 port 57062 ssh2: ECDSA SHA256:/xb+EsXzNILn854Xq24Og6JKTe5xlketDF/FwfrLkZc
qemu: running kernel version: 6.16.10-r2-qemu-generic #Chainguard SMP PREEMPT_DYNAMIC Fri Oct  3 22:31:32 UTC 2025
qemu: setting up local workspace
qemu: unmounting host workspace from guest
running step "iamguarded/test-compat"
running step "auth/github"
Using OctoSTS to get a token for chainguard-dev/iamguarded-tools as elastic-build
OctoSTS token SHA256: e5688971d3fcaf08ed1b3aa99c166e3f9c10d0b778ca146053a3adf2b914bddd
running step "git-checkout"
[git checkout] repo='https://github.com/chainguard-dev/iamguarded-tools' dest='./.iamguarded-tools' depth='unset' branch='main' tag='' expcommit='' recurse='false' sparse_paths=''
[git checkout] Warning: no expected-commit
[git checkout] execute: git config --global --add safe.directory /tmp/tmp.Nlr48qAO0E
[git checkout] execute: git config --global --add safe.directory /home/build/.iamguarded-tools
[git checkout] execute: git clone --quiet --origin=origin --config=user.name=Melange Build --config=user.email=melange-build@cgr.dev --config=advice.detachedHead=false --branch=main --depth=1 https://github.com/chainguard-dev/iamguarded-tools /tmp/tmp.Nlr48qAO0E
[git checkout] execute: cd /tmp/tmp.Nlr48qAO0E
[git checkout] tar -c . | tar -C "/home/build/.iamguarded-tools" -x
[git checkout] execute: cd /home/build/.iamguarded-tools
[git checkout] execute: git config --global --add safe.directory /home/build/.iamguarded-tools
[git checkout] tip of main is commit 51b1d8d21a6fce0ee36f43b45b94e8fe75bd179b
[git checkout] Setting commit date to Jan 1, 1980 (SOURCE_DATE_EPOCH found )
INFO: test iamguarded words: passed (no unexpected issues)
running step "test/tw/symlink-check"
running step "check for broken/dangling symlinks"
PASS[symlink-check]: /opt/iamguarded/seaweedfs/bin/weed -> /usr/bin/weed
INFO[symlink-check]: Tested [1] symlinks with [symlink-check]. [1/1] passed.
running step "Test master server startup and API"
running step "start daemon on localhost"
daemon started as pid 950 with: /opt/iamguarded/seaweedfs/bin/weed master -ip=0.0.0.0 -port=9333 -mdir=/tmp/seaweedfs-test
looking for 3 lines in output within 45 seconds
found within 2 seconds: Start Seaweed Master
found within 17 seconds: No existing leader found!
found within 17 seconds: Initializing new cluster
running post from /tmp/tmp.wnJt0We4hY/post
wait-for-it: waiting 30 seconds for localhost:9333
wait-for-it: localhost:9333 is available after 0 seconds
{"IsLeader":true,"Leader":"0.0.0.0:9333"}

3} from [::1]:43492
> I0107 11:54:56.718113 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:56.718127 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:56.718130 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:56.918389 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:56.918407 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:56.918423 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:56.918426 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:57.118680 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:57.118696 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:57.118710 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:57.118713 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:57.318971 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:57.318986 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:57.319001 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:57.319005 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:57.519254 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:57.519264 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:57.519276 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:57.519279 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:57.719497 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:57.719527 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:57.719550 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:57.719554 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:57.919818 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:57.919850 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:57.919887 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:57.919891 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:58.120134 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:58.120160 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:58.120180 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:58.120184 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:58.320442 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:58.320456 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:58.320470 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:58.320473 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:58.520743 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:58.520762 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:58.520782 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:58.520786 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:58.721080 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:58.721095 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:58.721109 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:58.721112 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:58.921373 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:58.921391 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:58.921408 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:58.921411 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:59.121668 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:59.121684 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:59.121701 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:59.121705 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:59.321956 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:59.321970 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:59.321984 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:59.321987 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:59.522238 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:59.522252 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:59.522276 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:59.522279 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:59.722521 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:59.722537 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:59.722550 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:59.722553 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:54:59.922819 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:54:59.922849 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:54:59.922874 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:54:59.922878 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:55:00.123123 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:55:00.123141 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:55:00.123157 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:55:00.123160 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:55:00.323424 master_server_handlers.go:146 dirAssign volume growth {"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} from [::1]:43492
> I0107 11:55:00.323439 master_grpc_server_volume.go:145 volume grow &{Option:{"replication":{},"ttl":{"Count":0,"Unit":0},"version":3} Count:0 Force:false Reason:http assign}
> I0107 11:55:00.323456 node.go:192 topo failed to pick 1 from  0 node candidates
> I0107 11:55:00.323460 volume_growth.go:136 create 7 volume, created 0: Not enough data nodes found!
> I0107 11:55:00.523807 common.go:81 response method:GET URL:/dir/assign with httpStatus:406 and JSON:{"error":"failed to find writable volumes for collection: replication:000 ttl: error: No writable volumes and no free volumes left for {\"replication\":{},\"ttl\":{\"Count\":0,\"Unit\":0},\"version\":3}"}
-- end   output --
found 3 of expected 3 lines in output.
found 0 / 9 error strings in output.
twk: SIGTERM sent to pid 159. kill returned 0.
twk: pid 159 exited within 2 seconds after SIGTERM
pod 82af0e2f0c5871d9adab57ef513afd3127571b6699304e0b441a75eb0d7d84f9 terminated
tests completed successfully
all tests passed