{"level":"warn","ts":"2026-05-26T15:56:25.331511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T15:56:25.331647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T15:56:25.331754Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T15:56:25.331773Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-26T15:56:25.331806Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T15:56:25.332925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-05-26T15:56:25.333073Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T15:56:25.335673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.246015ms"} {"level":"info","ts":"2026-05-26T15:56:25.343027Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-05-26T15:56:25.343099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T15:56:25.343115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-05-26T15:56:25.343123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T15:56:25.343129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-05-26T15:56:25.343158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-05-26T15:56:25.352281Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T15:56:25.354451Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T15:56:25.356149Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T15:56:25.359188Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T15:56:25.359437Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T15:56:25.359637Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T15:56:25.35976Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T15:56:25.359787Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T15:56:25.361239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-05-26T15:56:25.363616Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-26T15:56:25.365546Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T15:56:25.365751Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T15:56:25.365784Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T15:56:25.365898Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-26T15:56:25.365914Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-26T15:56:25.443575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T15:56:25.443671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T15:56:25.443733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-05-26T15:56:25.443764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-05-26T15:56:25.44378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-26T15:56:25.443804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-05-26T15:56:25.443833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-26T15:56:25.444981Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T15:56:25.445175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:56:25.445243Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:56:25.445501Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:25.446568Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:25.446724Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:25.446819Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:56:25.448618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T15:56:25.448765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"info","ts":"2026-05-26T15:56:25.448883Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T15:56:25.448927Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T15:56:47.302885Z","caller":"traceutil/trace.go:171","msg":"trace[1307306344] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"102.705959ms","start":"2026-05-26T15:56:47.200146Z","end":"2026-05-26T15:56:47.302851Z","steps":["trace[1307306344] 'process raft request' (duration: 102.513935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:47.581029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.760965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T15:56:47.581129Z","caller":"traceutil/trace.go:171","msg":"trace[1337505130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"113.910718ms","start":"2026-05-26T15:56:47.467195Z","end":"2026-05-26T15:56:47.581105Z","steps":["trace[1337505130] 'range keys from in-memory index tree' (duration: 113.645482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:47.5811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.349747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:56:47.581197Z","caller":"traceutil/trace.go:171","msg":"trace[501681849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"151.495659ms","start":"2026-05-26T15:56:47.429673Z","end":"2026-05-26T15:56:47.581169Z","steps":["trace[501681849] 'range keys from in-memory index tree' (duration: 151.238785ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:47.702399Z","caller":"traceutil/trace.go:171","msg":"trace[165251117] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"116.528352ms","start":"2026-05-26T15:56:47.585847Z","end":"2026-05-26T15:56:47.702375Z","steps":["trace[165251117] 'process raft request' (duration: 114.629443ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:47.710417Z","caller":"traceutil/trace.go:171","msg":"trace[363200562] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"124.488476ms","start":"2026-05-26T15:56:47.585896Z","end":"2026-05-26T15:56:47.710385Z","steps":["trace[363200562] 'process raft request' (duration: 124.351242ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:53.983324Z","caller":"traceutil/trace.go:171","msg":"trace[778160145] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"171.337286ms","start":"2026-05-26T15:56:53.811948Z","end":"2026-05-26T15:56:53.983285Z","steps":["trace[778160145] 'process raft request' (duration: 159.913372ms)","trace[778160145] 'compare' (duration: 11.231781ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:01.854688Z","caller":"traceutil/trace.go:171","msg":"trace[1008273645] transaction","detail":"{read_only:false; number_of_response:1; response_revision:543; }","duration":"116.114844ms","start":"2026-05-26T15:57:01.738531Z","end":"2026-05-26T15:57:01.854646Z","steps":["trace[1008273645] 'process raft request' (duration: 102.156658ms)","trace[1008273645] 'compare' (duration: 13.768993ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:01.854746Z","caller":"traceutil/trace.go:171","msg":"trace[225907199] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"114.724615ms","start":"2026-05-26T15:57:01.739997Z","end":"2026-05-26T15:57:01.854721Z","steps":["trace[225907199] 'process raft request' (duration: 114.558762ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:01.893292Z","caller":"traceutil/trace.go:171","msg":"trace[61642415] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"100.37368ms","start":"2026-05-26T15:57:01.792878Z","end":"2026-05-26T15:57:01.893252Z","steps":["trace[61642415] 'process raft request' (duration: 100.193696ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.450752Z","caller":"traceutil/trace.go:171","msg":"trace[1174243812] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"125.948155ms","start":"2026-05-26T15:57:02.324777Z","end":"2026-05-26T15:57:02.450725Z","steps":["trace[1174243812] 'process raft request' (duration: 125.741551ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:02.45079Z","caller":"traceutil/trace.go:171","msg":"trace[1271314943] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"127.342433ms","start":"2026-05-26T15:57:02.323417Z","end":"2026-05-26T15:57:02.45076Z","steps":["trace[1271314943] 'process raft request' (duration: 117.854609ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:08.261007Z","caller":"traceutil/trace.go:171","msg":"trace[520430807] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"105.048501ms","start":"2026-05-26T15:57:08.155931Z","end":"2026-05-26T15:57:08.26098Z","steps":["trace[520430807] 'process raft request' (duration: 99.750312ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:08.275486Z","caller":"traceutil/trace.go:171","msg":"trace[1350510571] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"106.639984ms","start":"2026-05-26T15:57:08.168825Z","end":"2026-05-26T15:57:08.275465Z","steps":["trace[1350510571] 'process raft request' (duration: 106.182264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:08.50153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.340062ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:08.501772Z","caller":"traceutil/trace.go:171","msg":"trace[105827714] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:632; }","duration":"121.660278ms","start":"2026-05-26T15:57:08.380067Z","end":"2026-05-26T15:57:08.501727Z","steps":["trace[105827714] 'range keys from in-memory index tree' (duration: 121.320291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:08.502152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.022929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:08.502322Z","caller":"traceutil/trace.go:171","msg":"trace[1462048573] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"170.254362ms","start":"2026-05-26T15:57:08.332044Z","end":"2026-05-26T15:57:08.502298Z","steps":["trace[1462048573] 'process raft request' (duration: 32.383605ms)","trace[1462048573] 'compare' (duration: 136.851035ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:08.502446Z","caller":"traceutil/trace.go:171","msg":"trace[1959588203] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"169.690511ms","start":"2026-05-26T15:57:08.332691Z","end":"2026-05-26T15:57:08.502381Z","steps":["trace[1959588203] 'process raft request' (duration: 169.529317ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:08.699773Z","caller":"traceutil/trace.go:171","msg":"trace[1617805003] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"174.423239ms","start":"2026-05-26T15:57:08.525327Z","end":"2026-05-26T15:57:08.69975Z","steps":["trace[1617805003] 'read index received' (duration: 153.917852ms)","trace[1617805003] 'applied index is now lower than readState.Index' (duration: 20.504297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:08.699949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.602053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:08.699915Z","caller":"traceutil/trace.go:171","msg":"trace[978229702] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"181.561299ms","start":"2026-05-26T15:57:08.518314Z","end":"2026-05-26T15:57:08.699875Z","steps":["trace[978229702] 'process raft request' (duration: 160.854437ms)","trace[978229702] 'compare' (duration: 20.455736ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:08.699992Z","caller":"traceutil/trace.go:171","msg":"trace[1259648513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"174.690085ms","start":"2026-05-26T15:57:08.525291Z","end":"2026-05-26T15:57:08.699982Z","steps":["trace[1259648513] 'agreement among raft nodes before linearized reading' (duration: 174.585933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:09.072731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.578983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:09.072836Z","caller":"traceutil/trace.go:171","msg":"trace[1160803194] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"282.228528ms","start":"2026-05-26T15:57:08.790591Z","end":"2026-05-26T15:57:09.07282Z","steps":["trace[1160803194] 'process raft request' (duration: 49.364159ms)","trace[1160803194] 'compare' (duration: 232.476641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:24.190247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.475679ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T15:57:24.190339Z","caller":"traceutil/trace.go:171","msg":"trace[1697441457] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"191.317673ms","start":"2026-05-26T15:57:23.999006Z","end":"2026-05-26T15:57:24.190324Z","steps":["trace[1697441457] 'read index received' (duration: 64.691241ms)","trace[1697441457] 'applied index is now lower than readState.Index' (duration: 126.625202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:24.190472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.460855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T15:57:24.190493Z","caller":"traceutil/trace.go:171","msg":"trace[749504586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"191.533657ms","start":"2026-05-26T15:57:23.998953Z","end":"2026-05-26T15:57:24.190486Z","steps":["trace[749504586] 'agreement among raft nodes before linearized reading' (duration: 191.408034ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:54.852262Z","caller":"traceutil/trace.go:171","msg":"trace[77914137] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"121.225439ms","start":"2026-05-26T15:57:54.731008Z","end":"2026-05-26T15:57:54.852233Z","steps":["trace[77914137] 'process raft request' (duration: 121.024425ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:10.053381Z","caller":"traceutil/trace.go:171","msg":"trace[1115229077] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"122.62588ms","start":"2026-05-26T15:58:09.930727Z","end":"2026-05-26T15:58:10.053353Z","steps":["trace[1115229077] 'read index received' (duration: 122.012957ms)","trace[1115229077] 'applied index is now lower than readState.Index' (duration: 611.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:10.053674Z","caller":"traceutil/trace.go:171","msg":"trace[2121974716] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"145.935302ms","start":"2026-05-26T15:58:09.907705Z","end":"2026-05-26T15:58:10.05364Z","steps":["trace[2121974716] 'process raft request' (duration: 145.156087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:10.05372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.980778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-9zc4n\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-26T15:58:10.053799Z","caller":"traceutil/trace.go:171","msg":"trace[1545030289] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-9zc4n; range_end:; response_count:1; response_revision:1336; }","duration":"123.11094ms","start":"2026-05-26T15:58:09.930668Z","end":"2026-05-26T15:58:10.053779Z","steps":["trace[1545030289] 'agreement among raft nodes before linearized reading' (duration: 122.888315ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:40.357182Z","caller":"traceutil/trace.go:171","msg":"trace[2034923000] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"272.232948ms","start":"2026-05-26T15:58:40.084911Z","end":"2026-05-26T15:58:40.357144Z","steps":["trace[2034923000] 'read index received' (duration: 271.818569ms)","trace[2034923000] 'applied index is now lower than readState.Index' (duration: 412.809µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:40.357224Z","caller":"traceutil/trace.go:171","msg":"trace[1278420564] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"382.996103ms","start":"2026-05-26T15:58:39.974189Z","end":"2026-05-26T15:58:40.357185Z","steps":["trace[1278420564] 'process raft request' (duration: 382.765588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:40.357646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:39.974164Z","time spent":"383.206707ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:58:40.357735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.79763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bae2fce8-3b5a-42b0-a26e-2aba9bd36749\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-26T15:58:40.357797Z","caller":"traceutil/trace.go:171","msg":"trace[1028323500] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bae2fce8-3b5a-42b0-a26e-2aba9bd36749; range_end:; response_count:1; response_revision:1872; }","duration":"272.921432ms","start":"2026-05-26T15:58:40.084852Z","end":"2026-05-26T15:58:40.357774Z","steps":["trace[1028323500] 'agreement among raft nodes before linearized reading' (duration: 272.481513ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:40.365216Z","caller":"traceutil/trace.go:171","msg":"trace[1931317490] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"175.191289ms","start":"2026-05-26T15:58:40.19Z","end":"2026-05-26T15:58:40.365192Z","steps":["trace[1931317490] 'process raft request' (duration: 175.017675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:40.365258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.586775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:40.365315Z","caller":"traceutil/trace.go:171","msg":"trace[288260783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"158.688047ms","start":"2026-05-26T15:58:40.20661Z","end":"2026-05-26T15:58:40.365298Z","steps":["trace[288260783] 'agreement among raft nodes before linearized reading' (duration: 158.544204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:40.365482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.366175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-26T15:58:40.365592Z","caller":"traceutil/trace.go:171","msg":"trace[1816505253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"137.499897ms","start":"2026-05-26T15:58:40.228076Z","end":"2026-05-26T15:58:40.365576Z","steps":["trace[1816505253] 'agreement among raft nodes before linearized reading' (duration: 137.262742ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:40.691864Z","caller":"traceutil/trace.go:171","msg":"trace[1226558939] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"120.997815ms","start":"2026-05-26T15:58:40.570841Z","end":"2026-05-26T15:58:40.691839Z","steps":["trace[1226558939] 'process raft request' (duration: 120.758231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:53.269431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.533833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T15:58:53.269661Z","caller":"traceutil/trace.go:171","msg":"trace[1004155232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1981; }","duration":"130.799289ms","start":"2026-05-26T15:58:53.138805Z","end":"2026-05-26T15:58:53.269604Z","steps":["trace[1004155232] 'agreement among raft nodes before linearized reading' (duration: 130.075074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:35.590612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.219815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:59:35.590959Z","caller":"traceutil/trace.go:171","msg":"trace[1572826337] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"237.022538ms","start":"2026-05-26T15:59:35.353911Z","end":"2026-05-26T15:59:35.590933Z","steps":["trace[1572826337] 'process raft request' (duration: 15.394994ms)","trace[1572826337] 'compare' (duration: 220.450749ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:59:35.591385Z","caller":"traceutil/trace.go:171","msg":"trace[313377086] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2306; }","duration":"111.764012ms","start":"2026-05-26T15:59:35.479606Z","end":"2026-05-26T15:59:35.59137Z","steps":["trace[313377086] 'read index received' (duration: 91.434444ms)","trace[313377086] 'applied index is now lower than readState.Index' (duration: 20.328688ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:59:35.591481Z","caller":"traceutil/trace.go:171","msg":"trace[958345832] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"158.107017ms","start":"2026-05-26T15:59:35.433332Z","end":"2026-05-26T15:59:35.591439Z","steps":["trace[958345832] 'process raft request' (duration: 157.534676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:35.592289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.65535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T15:59:35.592341Z","caller":"traceutil/trace.go:171","msg":"trace[830891078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2223; }","duration":"112.773103ms","start":"2026-05-26T15:59:35.479556Z","end":"2026-05-26T15:59:35.592329Z","steps":["trace[830891078] 'agreement among raft nodes before linearized reading' (duration: 111.894265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:35.64241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.42224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:59:35.642485Z","caller":"traceutil/trace.go:171","msg":"trace[1082020660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2224; }","duration":"116.535343ms","start":"2026-05-26T15:59:35.525935Z","end":"2026-05-26T15:59:35.64247Z","steps":["trace[1082020660] 'agreement among raft nodes before linearized reading' (duration: 116.39603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:35.929588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.952292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-26T15:59:35.929671Z","caller":"traceutil/trace.go:171","msg":"trace[1849262127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2225; }","duration":"128.089556ms","start":"2026-05-26T15:59:35.801562Z","end":"2026-05-26T15:59:35.929652Z","steps":["trace[1849262127] 'range keys from in-memory index tree' (duration: 127.765078ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:00:43.498881Z","caller":"traceutil/trace.go:171","msg":"trace[1429120077] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"146.346288ms","start":"2026-05-26T16:00:43.352496Z","end":"2026-05-26T16:00:43.498842Z","steps":["trace[1429120077] 'process raft request' (duration: 146.136373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:00:43.49896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.52473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-26T16:00:43.49907Z","caller":"traceutil/trace.go:171","msg":"trace[650913335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2786; }","duration":"117.648122ms","start":"2026-05-26T16:00:43.381395Z","end":"2026-05-26T16:00:43.499043Z","steps":["trace[650913335] 'agreement among raft nodes before linearized reading' (duration: 117.383776ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:00:43.575927Z","caller":"traceutil/trace.go:171","msg":"trace[1945603197] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"140.077633ms","start":"2026-05-26T16:00:43.435788Z","end":"2026-05-26T16:00:43.575866Z","steps":["trace[1945603197] 'process raft request' (duration: 118.085931ms)","trace[1945603197] 'compare' (duration: 21.852229ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:18.767406Z","caller":"traceutil/trace.go:171","msg":"trace[1782584973] transaction","detail":"{read_only:false; response_revision:3804; number_of_response:1; }","duration":"103.694565ms","start":"2026-05-26T16:03:18.663687Z","end":"2026-05-26T16:03:18.767382Z","steps":["trace[1782584973] 'process raft request' (duration: 103.532951ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:37.377835Z","caller":"traceutil/trace.go:171","msg":"trace[706328748] linearizableReadLoop","detail":"{readStateIndex:4915; appliedIndex:4914; }","duration":"125.780404ms","start":"2026-05-26T16:04:37.252034Z","end":"2026-05-26T16:04:37.377814Z","steps":["trace[706328748] 'read index received' (duration: 73.889008ms)","trace[706328748] 'applied index is now lower than readState.Index' (duration: 51.890576ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:04:37.377924Z","caller":"traceutil/trace.go:171","msg":"trace[1697705343] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"141.389049ms","start":"2026-05-26T16:04:37.236521Z","end":"2026-05-26T16:04:37.37791Z","steps":["trace[1697705343] 'process raft request' (duration: 89.486433ms)","trace[1697705343] 'compare' (duration: 51.680921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:04:37.377996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.941887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:04:37.378031Z","caller":"traceutil/trace.go:171","msg":"trace[1770298463] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4735; }","duration":"126.019608ms","start":"2026-05-26T16:04:37.252003Z","end":"2026-05-26T16:04:37.378023Z","steps":["trace[1770298463] 'agreement among raft nodes before linearized reading' (duration: 125.925986ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:43.528103Z","caller":"traceutil/trace.go:171","msg":"trace[1604703060] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"112.654782ms","start":"2026-05-26T16:04:43.415419Z","end":"2026-05-26T16:04:43.528074Z","steps":["trace[1604703060] 'read index received' (duration: 112.465308ms)","trace[1604703060] 'applied index is now lower than readState.Index' (duration: 188.254µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:04:43.528224Z","caller":"traceutil/trace.go:171","msg":"trace[1879851525] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"162.894211ms","start":"2026-05-26T16:04:43.365287Z","end":"2026-05-26T16:04:43.528182Z","steps":["trace[1879851525] 'process raft request' (duration: 162.629255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:04:43.528702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.266195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728883"} {"level":"info","ts":"2026-05-26T16:04:43.5288Z","caller":"traceutil/trace.go:171","msg":"trace[1046094996] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4973; }","duration":"113.419378ms","start":"2026-05-26T16:04:43.415359Z","end":"2026-05-26T16:04:43.528778Z","steps":["trace[1046094996] 'agreement among raft nodes before linearized reading' (duration: 112.832765ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:56.948612Z","caller":"traceutil/trace.go:171","msg":"trace[1119047952] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"137.115508ms","start":"2026-05-26T16:04:56.811475Z","end":"2026-05-26T16:04:56.948591Z","steps":["trace[1119047952] 'process raft request' (duration: 136.911313ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:56.9836Z","caller":"traceutil/trace.go:171","msg":"trace[1126418159] linearizableReadLoop","detail":"{readStateIndex:5423; appliedIndex:5421; }","duration":"143.580857ms","start":"2026-05-26T16:04:56.839994Z","end":"2026-05-26T16:04:56.983575Z","steps":["trace[1126418159] 'read index received' (duration: 108.425951ms)","trace[1126418159] 'applied index is now lower than readState.Index' (duration: 35.153526ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:04:56.983721Z","caller":"traceutil/trace.go:171","msg":"trace[1834626538] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"170.594607ms","start":"2026-05-26T16:04:56.81308Z","end":"2026-05-26T16:04:56.983674Z","steps":["trace[1834626538] 'process raft request' (duration: 170.364232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:04:56.983946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.901754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T16:04:56.984042Z","caller":"traceutil/trace.go:171","msg":"trace[2124181034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5228; }","duration":"144.077128ms","start":"2026-05-26T16:04:56.839946Z","end":"2026-05-26T16:04:56.984023Z","steps":["trace[2124181034] 'agreement among raft nodes before linearized reading' (duration: 143.783301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:04:57.045202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.818786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:04:57.04526Z","caller":"traceutil/trace.go:171","msg":"trace[90214456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5230; }","duration":"153.925509ms","start":"2026-05-26T16:04:56.891321Z","end":"2026-05-26T16:04:57.045247Z","steps":["trace[90214456] 'agreement among raft nodes before linearized reading' (duration: 153.760065ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.264086Z","caller":"traceutil/trace.go:171","msg":"trace[269723235] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"111.098368ms","start":"2026-05-26T16:05:05.152946Z","end":"2026-05-26T16:05:05.264045Z","steps":["trace[269723235] 'process raft request' (duration: 110.855883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:05:05.57692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.453836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:05:05.577135Z","caller":"traceutil/trace.go:171","msg":"trace[1771980368] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"300.933208ms","start":"2026-05-26T16:05:05.276137Z","end":"2026-05-26T16:05:05.577071Z","steps":["trace[1771980368] 'process raft request' (duration: 122.233516ms)","trace[1771980368] 'compare' (duration: 178.300853ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:05:05.577238Z","caller":"traceutil/trace.go:171","msg":"trace[948564169] linearizableReadLoop","detail":"{readStateIndex:5614; appliedIndex:5613; }","duration":"247.170363ms","start":"2026-05-26T16:05:05.330026Z","end":"2026-05-26T16:05:05.577196Z","steps":["trace[948564169] 'read index received' (duration: 68.178616ms)","trace[948564169] 'applied index is now lower than readState.Index' (duration: 178.987077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:05:05.577332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:05:05.27612Z","time spent":"301.100882ms","remote":"127.0.0.1:37094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4753,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:05:05.577572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.529901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b76e56a-8f16-477e-a68c-9d9f9fc06f64\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-26T16:05:05.577653Z","caller":"traceutil/trace.go:171","msg":"trace[279175661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b76e56a-8f16-477e-a68c-9d9f9fc06f64; range_end:; response_count:1; response_revision:5415; }","duration":"247.613042ms","start":"2026-05-26T16:05:05.330017Z","end":"2026-05-26T16:05:05.57763Z","steps":["trace[279175661] 'agreement among raft nodes before linearized reading' (duration: 247.307946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:05:05.577691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.328277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-26T16:05:05.57783Z","caller":"traceutil/trace.go:171","msg":"trace[1851044348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5415; }","duration":"207.527161ms","start":"2026-05-26T16:05:05.370276Z","end":"2026-05-26T16:05:05.577803Z","steps":["trace[1851044348] 'agreement among raft nodes before linearized reading' (duration: 207.154783ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.840921Z","caller":"traceutil/trace.go:171","msg":"trace[947360664] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"256.884491ms","start":"2026-05-26T16:05:05.584004Z","end":"2026-05-26T16:05:05.840888Z","steps":["trace[947360664] 'process raft request' (duration: 256.742688ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.841032Z","caller":"traceutil/trace.go:171","msg":"trace[996396003] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"257.073155ms","start":"2026-05-26T16:05:05.583899Z","end":"2026-05-26T16:05:05.840972Z","steps":["trace[996396003] 'process raft request' (duration: 245.615489ms)","trace[996396003] 'compare' (duration: 11.077098ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:05:05.841267Z","caller":"traceutil/trace.go:171","msg":"trace[839060335] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"257.212668ms","start":"2026-05-26T16:05:05.584028Z","end":"2026-05-26T16:05:05.841241Z","steps":["trace[839060335] 'process raft request' (duration: 256.791059ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.841636Z","caller":"traceutil/trace.go:171","msg":"trace[262651513] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"257.257459ms","start":"2026-05-26T16:05:05.584312Z","end":"2026-05-26T16:05:05.841569Z","steps":["trace[262651513] 'process raft request' (duration: 256.84567ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.841659Z","caller":"traceutil/trace.go:171","msg":"trace[1161010248] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"253.666702ms","start":"2026-05-26T16:05:05.587956Z","end":"2026-05-26T16:05:05.841623Z","steps":["trace[1161010248] 'process raft request' (duration: 253.598971ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.841863Z","caller":"traceutil/trace.go:171","msg":"trace[969306476] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"257.419253ms","start":"2026-05-26T16:05:05.584402Z","end":"2026-05-26T16:05:05.841821Z","steps":["trace[969306476] 'process raft request' (duration: 257.080486ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:05.844363Z","caller":"traceutil/trace.go:171","msg":"trace[422000243] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5616; }","duration":"198.413645ms","start":"2026-05-26T16:05:05.645912Z","end":"2026-05-26T16:05:05.844326Z","steps":["trace[422000243] 'read index received' (duration: 183.595545ms)","trace[422000243] 'applied index is now lower than readState.Index' (duration: 14.816539ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:05:05.844452Z","caller":"traceutil/trace.go:171","msg":"trace[1824666062] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"205.024228ms","start":"2026-05-26T16:05:05.6394Z","end":"2026-05-26T16:05:05.844425Z","steps":["trace[1824666062] 'process raft request' (duration: 204.773142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:05:05.84474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.800264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5pjrn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T16:05:05.844816Z","caller":"traceutil/trace.go:171","msg":"trace[1604505787] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5pjrn; range_end:; response_count:1; response_revision:5423; }","duration":"198.928436ms","start":"2026-05-26T16:05:05.645871Z","end":"2026-05-26T16:05:05.8448Z","steps":["trace[1604505787] 'agreement among raft nodes before linearized reading' (duration: 198.564958ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:06:25.812556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018} {"level":"info","ts":"2026-05-26T16:06:25.902594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"87.629486ms","hash":958753724,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T16:06:25.902669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":958753724,"revision":3018,"compact-revision":-1} {"level":"info","ts":"2026-05-26T16:11:20.618811Z","caller":"traceutil/trace.go:171","msg":"trace[282164679] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"128.841019ms","start":"2026-05-26T16:11:20.48994Z","end":"2026-05-26T16:11:20.618781Z","steps":["trace[282164679] 'process raft request' (duration: 99.714279ms)","trace[282164679] 'compare' (duration: 29.021438ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:11:25.818896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6139} {"level":"info","ts":"2026-05-26T16:11:25.911583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6139,"took":"90.677346ms","hash":2947720713,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":23814144,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-26T16:11:25.91166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2947720713,"revision":6139,"compact-revision":3018} {"level":"info","ts":"2026-05-26T16:11:48.836743Z","caller":"traceutil/trace.go:171","msg":"trace[219657703] linearizableReadLoop","detail":"{readStateIndex:8791; appliedIndex:8790; }","duration":"196.788157ms","start":"2026-05-26T16:11:48.639922Z","end":"2026-05-26T16:11:48.83671Z","steps":["trace[219657703] 'read index received' (duration: 196.575612ms)","trace[219657703] 'applied index is now lower than readState.Index' (duration: 210.975µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:11:48.836872Z","caller":"traceutil/trace.go:171","msg":"trace[121354767] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"247.604162ms","start":"2026-05-26T16:11:48.589256Z","end":"2026-05-26T16:11:48.83686Z","steps":["trace[121354767] 'process raft request' (duration: 247.303556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:11:48.837033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.101633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:11:48.837076Z","caller":"traceutil/trace.go:171","msg":"trace[510699952] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8493; }","duration":"197.186066ms","start":"2026-05-26T16:11:48.639881Z","end":"2026-05-26T16:11:48.837067Z","steps":["trace[510699952] 'agreement among raft nodes before linearized reading' (duration: 197.101734ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:11:52.960513Z","caller":"traceutil/trace.go:171","msg":"trace[889091704] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"112.605116ms","start":"2026-05-26T16:11:52.847889Z","end":"2026-05-26T16:11:52.960494Z","steps":["trace[889091704] 'process raft request' (duration: 112.466153ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:12:01.645187Z","caller":"traceutil/trace.go:171","msg":"trace[2132890097] linearizableReadLoop","detail":"{readStateIndex:8907; appliedIndex:8906; }","duration":"121.974455ms","start":"2026-05-26T16:12:01.523191Z","end":"2026-05-26T16:12:01.645166Z","steps":["trace[2132890097] 'read index received' (duration: 120.281718ms)","trace[2132890097] 'applied index is now lower than readState.Index' (duration: 1.691527ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:12:01.645258Z","caller":"traceutil/trace.go:171","msg":"trace[1481171721] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"122.312173ms","start":"2026-05-26T16:12:01.522915Z","end":"2026-05-26T16:12:01.645227Z","steps":["trace[1481171721] 'process raft request' (duration: 120.501163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:12:01.645534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.335773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-q5dll\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-26T16:12:01.645617Z","caller":"traceutil/trace.go:171","msg":"trace[2128119261] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-q5dll; range_end:; response_count:1; response_revision:8603; }","duration":"122.461676ms","start":"2026-05-26T16:12:01.523137Z","end":"2026-05-26T16:12:01.645599Z","steps":["trace[2128119261] 'agreement among raft nodes before linearized reading' (duration: 122.099898ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:12:01.856618Z","caller":"traceutil/trace.go:171","msg":"trace[109236744] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8907; }","duration":"139.390095ms","start":"2026-05-26T16:12:01.717204Z","end":"2026-05-26T16:12:01.856594Z","steps":["trace[109236744] 'read index received' (duration: 139.18603ms)","trace[109236744] 'applied index is now lower than readState.Index' (duration: 203.045µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:12:01.856724Z","caller":"traceutil/trace.go:171","msg":"trace[1831036352] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"190.883495ms","start":"2026-05-26T16:12:01.66583Z","end":"2026-05-26T16:12:01.856713Z","steps":["trace[1831036352] 'process raft request' (duration: 190.62149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:12:01.856966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.731072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:12:01.857057Z","caller":"traceutil/trace.go:171","msg":"trace[2061974704] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8604; }","duration":"139.853014ms","start":"2026-05-26T16:12:01.717182Z","end":"2026-05-26T16:12:01.857035Z","steps":["trace[2061974704] 'agreement among raft nodes before linearized reading' (duration: 139.689381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:12:01.85702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.132788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:12:01.857306Z","caller":"traceutil/trace.go:171","msg":"trace[1726429794] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8604; }","duration":"113.473626ms","start":"2026-05-26T16:12:01.743814Z","end":"2026-05-26T16:12:01.857288Z","steps":["trace[1726429794] 'agreement among raft nodes before linearized reading' (duration: 113.081917ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:12:02.378212Z","caller":"traceutil/trace.go:171","msg":"trace[97984646] linearizableReadLoop","detail":"{readStateIndex:8911; appliedIndex:8910; }","duration":"184.909061ms","start":"2026-05-26T16:12:02.19328Z","end":"2026-05-26T16:12:02.378189Z","steps":["trace[97984646] 'read index received' (duration: 184.720757ms)","trace[97984646] 'applied index is now lower than readState.Index' (duration: 187.294µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:12:02.37837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:12:02.378409Z","caller":"traceutil/trace.go:171","msg":"trace[223007935] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8607; }","duration":"136.01647ms","start":"2026-05-26T16:12:02.24238Z","end":"2026-05-26T16:12:02.378397Z","steps":["trace[223007935] 'agreement among raft nodes before linearized reading' (duration: 135.934928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:12:02.378461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.167968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5pjrn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T16:12:02.378542Z","caller":"traceutil/trace.go:171","msg":"trace[955846606] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5pjrn; range_end:; response_count:1; response_revision:8607; }","duration":"185.286299ms","start":"2026-05-26T16:12:02.193234Z","end":"2026-05-26T16:12:02.37852Z","steps":["trace[955846606] 'agreement among raft nodes before linearized reading' (duration: 185.073835ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:12:02.378987Z","caller":"traceutil/trace.go:171","msg":"trace[1421182182] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"195.338315ms","start":"2026-05-26T16:12:02.182923Z","end":"2026-05-26T16:12:02.378261Z","steps":["trace[1421182182] 'process raft request' (duration: 195.13311ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:12:18.338819Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002364.wal"} {"level":"info","ts":"2026-05-26T16:14:01.477437Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T16:14:01.482617Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T16:14:01.482725Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-26T16:16:25.826211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-05-26T16:16:25.926494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"97.176109ms","hash":911122739,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":25448448,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-26T16:16:25.926608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":911122739,"revision":8320,"compact-revision":6139} {"level":"warn","ts":"2026-05-26T16:16:57.851245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.975056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:16:57.851339Z","caller":"traceutil/trace.go:171","msg":"trace[2070456152] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11175; }","duration":"179.12157ms","start":"2026-05-26T16:16:57.672196Z","end":"2026-05-26T16:16:57.851318Z","steps":["trace[2070456152] 'range keys from in-memory index tree' (duration: 178.904605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:57.85135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.945395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-26T16:16:57.851444Z","caller":"traceutil/trace.go:171","msg":"trace[445405342] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11175; }","duration":"179.066247ms","start":"2026-05-26T16:16:57.672356Z","end":"2026-05-26T16:16:57.851422Z","steps":["trace[445405342] 'range keys from in-memory index tree' (duration: 178.797792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:57.851462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.76098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-26T16:16:57.851491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.638168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:16:57.851532Z","caller":"traceutil/trace.go:171","msg":"trace[515686878] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11175; }","duration":"234.872933ms","start":"2026-05-26T16:16:57.616642Z","end":"2026-05-26T16:16:57.851515Z","steps":["trace[515686878] 'range keys from in-memory index tree' (duration: 234.586786ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:16:57.851538Z","caller":"traceutil/trace.go:171","msg":"trace[1518659728] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11175; }","duration":"178.7121ms","start":"2026-05-26T16:16:57.672815Z","end":"2026-05-26T16:16:57.851527Z","steps":["trace[1518659728] 'range keys from in-memory index tree' (duration: 178.532286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:57.851588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.406473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:16:57.85165Z","caller":"traceutil/trace.go:171","msg":"trace[1040639496] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11175; }","duration":"178.818244ms","start":"2026-05-26T16:16:57.672819Z","end":"2026-05-26T16:16:57.851637Z","steps":["trace[1040639496] 'range keys from in-memory index tree' (duration: 178.302341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:57.8517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.314254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:16:57.851876Z","caller":"traceutil/trace.go:171","msg":"trace[522739857] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11175; }","duration":"179.56527ms","start":"2026-05-26T16:16:57.672284Z","end":"2026-05-26T16:16:57.85185Z","steps":["trace[522739857] 'range keys from in-memory index tree' (duration: 179.16762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:58.522594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.263301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T16:16:58.522717Z","caller":"traceutil/trace.go:171","msg":"trace[1957651502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11180; }","duration":"116.432705ms","start":"2026-05-26T16:16:58.406256Z","end":"2026-05-26T16:16:58.522689Z","steps":["trace[1957651502] 'range keys from in-memory index tree' (duration: 116.059956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:16:58.522812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.281596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5pjrn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T16:16:58.522925Z","caller":"traceutil/trace.go:171","msg":"trace[1548863236] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5pjrn; range_end:; response_count:1; response_revision:11180; }","duration":"131.430599ms","start":"2026-05-26T16:16:58.391475Z","end":"2026-05-26T16:16:58.522905Z","steps":["trace[1548863236] 'range keys from in-memory index tree' (duration: 131.075692ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:16:58.953236Z","caller":"traceutil/trace.go:171","msg":"trace[960601080] linearizableReadLoop","detail":"{readStateIndex:11561; appliedIndex:11560; }","duration":"117.995299ms","start":"2026-05-26T16:16:58.835212Z","end":"2026-05-26T16:16:58.953207Z","steps":["trace[960601080] 'read index received' (duration: 117.967978ms)","trace[960601080] 'applied index is now lower than readState.Index' (duration: 25.651µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:16:58.953486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.220873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:16:58.953537Z","caller":"traceutil/trace.go:171","msg":"trace[380253365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11183; }","duration":"118.352376ms","start":"2026-05-26T16:16:58.835166Z","end":"2026-05-26T16:16:58.953518Z","steps":["trace[380253365] 'agreement among raft nodes before linearized reading' (duration: 118.149552ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:16:58.979088Z","caller":"traceutil/trace.go:171","msg":"trace[2034804100] transaction","detail":"{read_only:false; response_revision:11184; number_of_response:1; }","duration":"112.756286ms","start":"2026-05-26T16:16:58.866304Z","end":"2026-05-26T16:16:58.979061Z","steps":["trace[2034804100] 'process raft request' (duration: 112.604053ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:21:25.831185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10811} {"level":"info","ts":"2026-05-26T16:21:25.909106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10811,"took":"75.194397ms","hash":3665915710,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":27869184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-26T16:21:25.90915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3665915710,"revision":10811,"compact-revision":8320} {"level":"info","ts":"2026-05-26T16:23:28.956694Z","caller":"traceutil/trace.go:171","msg":"trace[549318179] linearizableReadLoop","detail":"{readStateIndex:14265; appliedIndex:14264; }","duration":"110.417309ms","start":"2026-05-26T16:23:28.846248Z","end":"2026-05-26T16:23:28.956665Z","steps":["trace[549318179] 'read index received' (duration: 100.773359ms)","trace[549318179] 'applied index is now lower than readState.Index' (duration: 9.642779ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:23:28.95695Z","caller":"traceutil/trace.go:171","msg":"trace[1977084959] transaction","detail":"{read_only:false; response_revision:13796; number_of_response:1; }","duration":"135.146024ms","start":"2026-05-26T16:23:28.821793Z","end":"2026-05-26T16:23:28.956939Z","steps":["trace[1977084959] 'process raft request' (duration: 125.27978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:28.957205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.947981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.170\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-05-26T16:23:28.957234Z","caller":"traceutil/trace.go:171","msg":"trace[200831658] range","detail":"{range_begin:/registry/masterleases/199.19.213.170; range_end:; response_count:1; response_revision:13796; }","duration":"111.036622ms","start":"2026-05-26T16:23:28.846189Z","end":"2026-05-26T16:23:28.957226Z","steps":["trace[200831658] 'agreement among raft nodes before linearized reading' (duration: 110.90966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:28.969158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.673326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:23:28.96926Z","caller":"traceutil/trace.go:171","msg":"trace[1576119908] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:13797; }","duration":"105.87119ms","start":"2026-05-26T16:23:28.863367Z","end":"2026-05-26T16:23:28.969238Z","steps":["trace[1576119908] 'agreement among raft nodes before linearized reading' (duration: 105.623465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:29.746167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.088138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-26T16:23:29.746442Z","caller":"traceutil/trace.go:171","msg":"trace[662314609] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:13805; }","duration":"176.464447ms","start":"2026-05-26T16:23:29.569961Z","end":"2026-05-26T16:23:29.746425Z","steps":["trace[662314609] 'range keys from in-memory index tree' (duration: 175.812182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:29.74648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.155891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5pjrn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T16:23:29.746556Z","caller":"traceutil/trace.go:171","msg":"trace[817345941] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5pjrn; range_end:; response_count:1; response_revision:13805; }","duration":"134.278504ms","start":"2026-05-26T16:23:29.612258Z","end":"2026-05-26T16:23:29.746536Z","steps":["trace[817345941] 'range keys from in-memory index tree' (duration: 133.66809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:51.51869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.79307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T16:23:51.518818Z","caller":"traceutil/trace.go:171","msg":"trace[651245445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14108; }","duration":"217.980805ms","start":"2026-05-26T16:23:51.300811Z","end":"2026-05-26T16:23:51.518792Z","steps":["trace[651245445] 'range keys from in-memory index tree' (duration: 217.652387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:51.518888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.347362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-26T16:23:51.518892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.159738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59782c5e-9020-4bf0-bf42-3b18100b10d8\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-26T16:23:51.518956Z","caller":"traceutil/trace.go:171","msg":"trace[483755399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14108; }","duration":"217.490614ms","start":"2026-05-26T16:23:51.301449Z","end":"2026-05-26T16:23:51.51894Z","steps":["trace[483755399] 'range keys from in-memory index tree' (duration: 216.939372ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:23:51.51897Z","caller":"traceutil/trace.go:171","msg":"trace[1790153362] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-59782c5e-9020-4bf0-bf42-3b18100b10d8; range_end:; response_count:1; response_revision:14108; }","duration":"218.285171ms","start":"2026-05-26T16:23:51.300664Z","end":"2026-05-26T16:23:51.518949Z","steps":["trace[1790153362] 'range keys from in-memory index tree' (duration: 217.978335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:25:17.242459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.118296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737473"} {"level":"info","ts":"2026-05-26T16:25:17.242575Z","caller":"traceutil/trace.go:171","msg":"trace[628991467] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14915; }","duration":"160.268599ms","start":"2026-05-26T16:25:17.082283Z","end":"2026-05-26T16:25:17.242551Z","steps":["trace[628991467] 'range keys from in-memory index tree' (duration: 159.287047ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:25:23.479426Z","caller":"traceutil/trace.go:171","msg":"trace[1159809827] transaction","detail":"{read_only:false; response_revision:14947; number_of_response:1; }","duration":"112.980267ms","start":"2026-05-26T16:25:23.366411Z","end":"2026-05-26T16:25:23.479391Z","steps":["trace[1159809827] 'process raft request' (duration: 105.954054ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:25:23.620063Z","caller":"traceutil/trace.go:171","msg":"trace[1730264173] linearizableReadLoop","detail":"{readStateIndex:15455; appliedIndex:15453; }","duration":"174.022667ms","start":"2026-05-26T16:25:23.446015Z","end":"2026-05-26T16:25:23.620037Z","steps":["trace[1730264173] 'read index received' (duration: 26.447682ms)","trace[1730264173] 'applied index is now lower than readState.Index' (duration: 147.573975ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:25:23.620274Z","caller":"traceutil/trace.go:171","msg":"trace[272214746] transaction","detail":"{read_only:false; response_revision:14948; number_of_response:1; }","duration":"242.860668ms","start":"2026-05-26T16:25:23.377402Z","end":"2026-05-26T16:25:23.620263Z","steps":["trace[272214746] 'process raft request' (duration: 241.264413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:25:23.620594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.572329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-26T16:25:23.620746Z","caller":"traceutil/trace.go:171","msg":"trace[1632896303] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14948; }","duration":"174.719212ms","start":"2026-05-26T16:25:23.44595Z","end":"2026-05-26T16:25:23.620669Z","steps":["trace[1632896303] 'agreement among raft nodes before linearized reading' (duration: 174.451306ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:26:25.837964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13004} {"level":"info","ts":"2026-05-26T16:26:25.916249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13004,"took":"75.098536ms","hash":630836714,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":30601216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T16:26:25.916327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":630836714,"revision":13004,"compact-revision":10811} {"level":"info","ts":"2026-05-26T16:31:25.849682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15365} {"level":"info","ts":"2026-05-26T16:31:25.950715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15365,"took":"97.82249ms","hash":677616704,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":30756864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T16:31:25.950796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":677616704,"revision":15365,"compact-revision":13004} {"level":"info","ts":"2026-05-26T16:36:25.856461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17587} {"level":"info","ts":"2026-05-26T16:36:25.94916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17587,"took":"89.631137ms","hash":983675175,"current-db-size-bytes":57729024,"current-db-size":"58 MB","current-db-size-in-use-bytes":24985600,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-26T16:36:25.949251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":983675175,"revision":17587,"compact-revision":15365} {"level":"info","ts":"2026-05-26T16:37:00.749322Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"80be33c6b0d5a492","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T16:37:00.757017Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-26T16:37:00.757144Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}