{"level":"warn","ts":"2026-09-04T16:05:15.175736Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T16:05:15.175955Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.211: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://162.253.55.211:2380","--initial-cluster=instance=https://162.253.55.211:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.211:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.211: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-09-04T16:05:15.176083Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-04T16:05:15.176106Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-09-04T16:05:15.176136Z","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-09-04T16:05:15.177441Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"]} {"level":"info","ts":"2026-09-04T16:05:15.177605Z","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://162.253.55.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.211: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-09-04T16:05:15.184307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.247296ms"} {"level":"info","ts":"2026-09-04T16:05:15.192119Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"931f6a422b4c426f","cluster-id":"8a95cf5058d193ed"} {"level":"info","ts":"2026-09-04T16:05:15.192244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T16:05:15.192272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 0"} {"level":"info","ts":"2026-09-04T16:05:15.192283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 931f6a422b4c426f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T16:05:15.192295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 1"} {"level":"info","ts":"2026-09-04T16:05:15.192341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"warn","ts":"2026-09-04T16:05:15.203896Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T16:05:15.207549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T16:05:15.20886Z","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-09-04T16:05:15.210783Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"931f6a422b4c426f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T16:05:15.211038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"931f6a422b4c426f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T16:05:15.211149Z","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-09-04T16:05:15.211301Z","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-09-04T16:05:15.211323Z","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-09-04T16:05:15.214079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"info","ts":"2026-09-04T16:05:15.214312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","added-peer-id":"931f6a422b4c426f","added-peer-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-09-04T16:05:15.215327Z","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-09-04T16:05:15.215485Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-09-04T16:05:15.215528Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-09-04T16:05:15.215735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"931f6a422b4c426f","initial-advertise-peer-urls":["https://162.253.55.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T16:05:15.215797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T16:05:15.293291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T16:05:15.293399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T16:05:15.293458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgPreVoteResp from 931f6a422b4c426f at term 1"} {"level":"info","ts":"2026-09-04T16:05:15.293485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became candidate at term 2"} {"level":"info","ts":"2026-09-04T16:05:15.293497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgVoteResp from 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-09-04T16:05:15.293516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became leader at term 2"} {"level":"info","ts":"2026-09-04T16:05:15.293538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 931f6a422b4c426f elected leader 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-09-04T16:05:15.294681Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T16:05:15.295463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T16:05:15.295468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"931f6a422b4c426f","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.211:2379]}","request-path":"/0/members/931f6a422b4c426f/attributes","cluster-id":"8a95cf5058d193ed","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T16:05:15.295536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T16:05:15.295844Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T16:05:15.295899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T16:05:15.295941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T16:05:15.296088Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T16:05:15.296147Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T16:05:15.299197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.211:2379"} {"level":"info","ts":"2026-09-04T16:05:15.299349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-04T16:06:01.593813Z","caller":"traceutil/trace.go:171","msg":"trace[534268614] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"117.835289ms","start":"2026-09-04T16:06:01.475951Z","end":"2026-09-04T16:06:01.593786Z","steps":["trace[534268614] 'process raft request' (duration: 117.784228ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594208Z","caller":"traceutil/trace.go:171","msg":"trace[2079913951] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"137.811649ms","start":"2026-09-04T16:06:01.456386Z","end":"2026-09-04T16:06:01.594198Z","steps":["trace[2079913951] 'process raft request' (duration: 136.694283ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594443Z","caller":"traceutil/trace.go:171","msg":"trace[108002419] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"136.55547ms","start":"2026-09-04T16:06:01.457876Z","end":"2026-09-04T16:06:01.594431Z","steps":["trace[108002419] 'process raft request' (duration: 135.367453ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594597Z","caller":"traceutil/trace.go:171","msg":"trace[509926774] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"136.421157ms","start":"2026-09-04T16:06:01.458163Z","end":"2026-09-04T16:06:01.594584Z","steps":["trace[509926774] 'process raft request' (duration: 135.280341ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594727Z","caller":"traceutil/trace.go:171","msg":"trace[2018258464] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"136.503339ms","start":"2026-09-04T16:06:01.458214Z","end":"2026-09-04T16:06:01.594718Z","steps":["trace[2018258464] 'process raft request' (duration: 135.26776ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594844Z","caller":"traceutil/trace.go:171","msg":"trace[2069822730] transaction","detail":"{read_only:false; number_of_response:1; response_revision:556; }","duration":"136.061759ms","start":"2026-09-04T16:06:01.458773Z","end":"2026-09-04T16:06:01.594835Z","steps":["trace[2069822730] 'process raft request' (duration: 134.739938ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.594987Z","caller":"traceutil/trace.go:171","msg":"trace[2108383651] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"135.724581ms","start":"2026-09-04T16:06:01.459254Z","end":"2026-09-04T16:06:01.594978Z","steps":["trace[2108383651] 'process raft request' (duration: 134.300658ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.595105Z","caller":"traceutil/trace.go:171","msg":"trace[664157690] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"135.446595ms","start":"2026-09-04T16:06:01.459648Z","end":"2026-09-04T16:06:01.595095Z","steps":["trace[664157690] 'process raft request' (duration: 133.94765ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.595211Z","caller":"traceutil/trace.go:171","msg":"trace[569668089] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"127.195335ms","start":"2026-09-04T16:06:01.468007Z","end":"2026-09-04T16:06:01.595203Z","steps":["trace[569668089] 'process raft request' (duration: 125.620959ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.595317Z","caller":"traceutil/trace.go:171","msg":"trace[290342023] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"127.219346ms","start":"2026-09-04T16:06:01.46809Z","end":"2026-09-04T16:06:01.595309Z","steps":["trace[290342023] 'process raft request' (duration: 125.567617ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.59544Z","caller":"traceutil/trace.go:171","msg":"trace[1042702742] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"127.336119ms","start":"2026-09-04T16:06:01.468096Z","end":"2026-09-04T16:06:01.595432Z","steps":["trace[1042702742] 'process raft request' (duration: 125.591938ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.604347Z","caller":"traceutil/trace.go:171","msg":"trace[1130705115] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"126.641772ms","start":"2026-09-04T16:06:01.477687Z","end":"2026-09-04T16:06:01.604329Z","steps":["trace[1130705115] 'process raft request' (duration: 126.296514ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.721218Z","caller":"traceutil/trace.go:171","msg":"trace[1333826083] transaction","detail":"{read_only:false; number_of_response:1; response_revision:566; }","duration":"114.734878ms","start":"2026-09-04T16:06:01.606446Z","end":"2026-09-04T16:06:01.721181Z","steps":["trace[1333826083] 'process raft request' (duration: 109.480707ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.722289Z","caller":"traceutil/trace.go:171","msg":"trace[548933968] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"108.601257ms","start":"2026-09-04T16:06:01.613381Z","end":"2026-09-04T16:06:01.721982Z","steps":["trace[548933968] 'process raft request' (duration: 107.647926ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:01.723047Z","caller":"traceutil/trace.go:171","msg":"trace[215189832] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"103.924769ms","start":"2026-09-04T16:06:01.61909Z","end":"2026-09-04T16:06:01.723015Z","steps":["trace[215189832] 'process raft request' (duration: 102.083598ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:08.154017Z","caller":"traceutil/trace.go:171","msg":"trace[1951872565] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"258.115055ms","start":"2026-09-04T16:06:07.895848Z","end":"2026-09-04T16:06:08.153963Z","steps":["trace[1951872565] 'process raft request' (duration: 257.45696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:08.66052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.535094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:06:08.660656Z","caller":"traceutil/trace.go:171","msg":"trace[571257038] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"334.44389ms","start":"2026-09-04T16:06:08.326195Z","end":"2026-09-04T16:06:08.660639Z","steps":["trace[571257038] 'read index received' (duration: 99.192001ms)","trace[571257038] 'applied index is now lower than readState.Index' (duration: 235.250709ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:06:08.660737Z","caller":"traceutil/trace.go:171","msg":"trace[609297150] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"428.250187ms","start":"2026-09-04T16:06:08.232434Z","end":"2026-09-04T16:06:08.660684Z","steps":["trace[609297150] 'process raft request' (duration: 193.040159ms)","trace[609297150] 'compare' (duration: 234.182275ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:06:08.660894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.689766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fsrjh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-04T16:06:08.660947Z","caller":"traceutil/trace.go:171","msg":"trace[1369056806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fsrjh; range_end:; response_count:1; response_revision:655; }","duration":"334.776519ms","start":"2026-09-04T16:06:08.326143Z","end":"2026-09-04T16:06:08.660919Z","steps":["trace[1369056806] 'agreement among raft nodes before linearized reading' (duration: 334.586104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:08.660913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.232404Z","time spent":"428.430922ms","remote":"127.0.0.1:45056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-04T16:06:08.660995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.326117Z","time spent":"334.868001ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-fsrjh\" "} {"level":"warn","ts":"2026-09-04T16:06:09.174352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4787221319647706485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-04T16:06:09.363755Z","caller":"traceutil/trace.go:171","msg":"trace[1251484673] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"698.832329ms","start":"2026-09-04T16:06:08.664847Z","end":"2026-09-04T16:06:09.36368Z","steps":["trace[1251484673] 'process raft request' (duration: 648.269327ms)","trace[1251484673] 'compare' (duration: 50.259755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:06:09.364167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.664818Z","time spent":"699.054664ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T16:06:09.370578Z","caller":"traceutil/trace.go:171","msg":"trace[445959674] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:683; }","duration":"696.912545ms","start":"2026-09-04T16:06:08.673636Z","end":"2026-09-04T16:06:09.370549Z","steps":["trace[445959674] 'read index received' (duration: 639.72513ms)","trace[445959674] 'applied index is now lower than readState.Index' (duration: 57.186245ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:06:09.370909Z","caller":"traceutil/trace.go:171","msg":"trace[682208122] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"697.973609ms","start":"2026-09-04T16:06:08.672922Z","end":"2026-09-04T16:06:09.370896Z","steps":["trace[682208122] 'process raft request' (duration: 697.441457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:09.371052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.381055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:06:09.371147Z","caller":"traceutil/trace.go:171","msg":"trace[975226069] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:658; }","duration":"697.52134ms","start":"2026-09-04T16:06:08.673602Z","end":"2026-09-04T16:06:09.371123Z","steps":["trace[975226069] 'agreement among raft nodes before linearized reading' (duration: 697.319484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:09.37108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.672887Z","time spent":"698.081242ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:06:09.371201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.673591Z","time spent":"697.600661ms","remote":"127.0.0.1:45200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-09-04T16:06:09.371079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.130721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.211\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-04T16:06:09.371505Z","caller":"traceutil/trace.go:171","msg":"trace[1927125565] range","detail":"{range_begin:/registry/masterleases/162.253.55.211; range_end:; response_count:1; response_revision:658; }","duration":"307.410739ms","start":"2026-09-04T16:06:09.063888Z","end":"2026-09-04T16:06:09.371299Z","steps":["trace[1927125565] 'agreement among raft nodes before linearized reading' (duration: 307.136231ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:09.371518Z","caller":"traceutil/trace.go:171","msg":"trace[910306922] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"639.042765ms","start":"2026-09-04T16:06:08.732245Z","end":"2026-09-04T16:06:09.371288Z","steps":["trace[910306922] 'process raft request' (duration: 638.256397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:09.371574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:09.063867Z","time spent":"307.687295ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.211\" "} {"level":"warn","ts":"2026-09-04T16:06:09.371617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.675709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T16:06:09.371637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.757637ms","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-09-04T16:06:09.371698Z","caller":"traceutil/trace.go:171","msg":"trace[939876747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"285.810402ms","start":"2026-09-04T16:06:09.085858Z","end":"2026-09-04T16:06:09.371669Z","steps":["trace[939876747] 'agreement among raft nodes before linearized reading' (duration: 285.671609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:06:09.371832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:06:08.732205Z","time spent":"639.404164ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T16:06:09.371686Z","caller":"traceutil/trace.go:171","msg":"trace[332764182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"170.854049ms","start":"2026-09-04T16:06:09.200819Z","end":"2026-09-04T16:06:09.371673Z","steps":["trace[332764182] 'agreement among raft nodes before linearized reading' (duration: 170.700206ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:10.60124Z","caller":"traceutil/trace.go:171","msg":"trace[1730879866] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"138.005294ms","start":"2026-09-04T16:06:10.463206Z","end":"2026-09-04T16:06:10.601211Z","steps":["trace[1730879866] 'process raft request' (duration: 137.83888ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:06:56.218876Z","caller":"traceutil/trace.go:171","msg":"trace[1573069385] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"108.681769ms","start":"2026-09-04T16:06:56.110154Z","end":"2026-09-04T16:06:56.218836Z","steps":["trace[1573069385] 'process raft request' (duration: 108.435434ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:07:47.548495Z","caller":"traceutil/trace.go:171","msg":"trace[1284254513] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"619.38108ms","start":"2026-09-04T16:07:46.929061Z","end":"2026-09-04T16:07:47.548442Z","steps":["trace[1284254513] 'process raft request' (duration: 556.308483ms)","trace[1284254513] 'compare' (duration: 62.250817ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:07:47.548562Z","caller":"traceutil/trace.go:171","msg":"trace[554133581] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"619.025852ms","start":"2026-09-04T16:07:46.929487Z","end":"2026-09-04T16:07:47.548513Z","steps":["trace[554133581] 'process raft request' (duration: 618.794626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.548732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:46.929028Z","time spent":"619.593634ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T16:07:47.548796Z","caller":"traceutil/trace.go:171","msg":"trace[1207016282] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"618.907748ms","start":"2026-09-04T16:07:46.929864Z","end":"2026-09-04T16:07:47.548771Z","steps":["trace[1207016282] 'process raft request' (duration: 618.585311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.548911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:46.92948Z","time spent":"619.301188ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:07:47.548913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:46.929848Z","time spent":"619.007281ms","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:07:47.54927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.644056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-04T16:07:47.549341Z","caller":"traceutil/trace.go:171","msg":"trace[373356345] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244; range_end:; response_count:1; response_revision:1916; }","duration":"619.786099ms","start":"2026-09-04T16:07:46.929539Z","end":"2026-09-04T16:07:47.549325Z","steps":["trace[373356345] 'agreement among raft nodes before linearized reading' (duration: 619.364179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.549442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:46.929511Z","time spent":"619.915581ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244\" "} {"level":"info","ts":"2026-09-04T16:07:47.548811Z","caller":"traceutil/trace.go:171","msg":"trace[954147369] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1991; }","duration":"618.919169ms","start":"2026-09-04T16:07:46.929778Z","end":"2026-09-04T16:07:47.548697Z","steps":["trace[954147369] 'read index received' (duration: 15.64646ms)","trace[954147369] 'applied index is now lower than readState.Index' (duration: 603.270839ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:07:47.80353Z","caller":"traceutil/trace.go:171","msg":"trace[331061086] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"253.302819ms","start":"2026-09-04T16:07:47.550168Z","end":"2026-09-04T16:07:47.803471Z","steps":["trace[331061086] 'read index received' (duration: 238.312482ms)","trace[331061086] 'applied index is now lower than readState.Index' (duration: 14.988017ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:07:47.803608Z","caller":"traceutil/trace.go:171","msg":"trace[2012224195] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"719.294687ms","start":"2026-09-04T16:07:47.08426Z","end":"2026-09-04T16:07:47.803554Z","steps":["trace[2012224195] 'process raft request' (duration: 704.386822ms)","trace[2012224195] 'compare' (duration: 14.233468ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:07:47.803822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:47.084209Z","time spent":"719.499141ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:07:47.803841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.431512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T16:07:47.803889Z","caller":"traceutil/trace.go:171","msg":"trace[626481824] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1917; }","duration":"490.579646ms","start":"2026-09-04T16:07:47.313295Z","end":"2026-09-04T16:07:47.803874Z","steps":["trace[626481824] 'agreement among raft nodes before linearized reading' (duration: 490.399102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.803936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:47.313274Z","time spent":"490.651347ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-09-04T16:07:47.803937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.56727ms","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-09-04T16:07:47.804075Z","caller":"traceutil/trace.go:171","msg":"trace[1968212002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1917; }","duration":"122.756244ms","start":"2026-09-04T16:07:47.68129Z","end":"2026-09-04T16:07:47.804047Z","steps":["trace[1968212002] 'agreement among raft nodes before linearized reading' (duration: 122.427776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.803765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.058404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T16:07:47.804311Z","caller":"traceutil/trace.go:171","msg":"trace[182937673] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1917; }","duration":"325.662438ms","start":"2026-09-04T16:07:47.478578Z","end":"2026-09-04T16:07:47.80424Z","steps":["trace[182937673] 'agreement among raft nodes before linearized reading' (duration: 324.994333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:47.804469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:47.478558Z","time spent":"325.831883ms","remote":"127.0.0.1:45200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-04T16:07:48.28122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.75783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:07:48.281328Z","caller":"traceutil/trace.go:171","msg":"trace[479519476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"195.947394ms","start":"2026-09-04T16:07:48.085356Z","end":"2026-09-04T16:07:48.281304Z","steps":["trace[479519476] 'range keys from in-memory index tree' (duration: 195.620816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:48.281463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.020418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-04T16:07:48.281461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.955264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-04T16:07:48.28155Z","caller":"traceutil/trace.go:171","msg":"trace[1237563457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1918; }","duration":"265.211422ms","start":"2026-09-04T16:07:48.016319Z","end":"2026-09-04T16:07:48.281531Z","steps":["trace[1237563457] 'range keys from in-memory index tree' (duration: 264.797993ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:07:48.281591Z","caller":"traceutil/trace.go:171","msg":"trace[163206730] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1918; }","duration":"125.149929ms","start":"2026-09-04T16:07:48.156406Z","end":"2026-09-04T16:07:48.281556Z","steps":["trace[163206730] 'range keys from in-memory index tree' (duration: 124.692888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:48.281549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.582258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-04T16:07:48.281773Z","caller":"traceutil/trace.go:171","msg":"trace[834966480] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244; range_end:; response_count:1; response_revision:1918; }","duration":"371.890855ms","start":"2026-09-04T16:07:47.909859Z","end":"2026-09-04T16:07:48.281749Z","steps":["trace[834966480] 'range keys from in-memory index tree' (duration: 371.327313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:48.281841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:07:47.909831Z","time spent":"371.998968ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-81c8977b-d773-4fd2-97b1-457b086a4244\" "} {"level":"info","ts":"2026-09-04T16:07:48.318169Z","caller":"traceutil/trace.go:171","msg":"trace[905415782] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"104.263627ms","start":"2026-09-04T16:07:48.213861Z","end":"2026-09-04T16:07:48.318125Z","steps":["trace[905415782] 'process raft request' (duration: 103.906629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:07:48.378162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.41261ms","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-09-04T16:07:48.378294Z","caller":"traceutil/trace.go:171","msg":"trace[1925670934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1922; }","duration":"120.629625ms","start":"2026-09-04T16:07:48.257636Z","end":"2026-09-04T16:07:48.378265Z","steps":["trace[1925670934] 'agreement among raft nodes before linearized reading' (duration: 120.216835ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:07:54.524321Z","caller":"traceutil/trace.go:171","msg":"trace[102936975] linearizableReadLoop","detail":"{readStateIndex:2067; appliedIndex:2066; }","duration":"118.54881ms","start":"2026-09-04T16:07:54.405734Z","end":"2026-09-04T16:07:54.524283Z","steps":["trace[102936975] 'read index received' (duration: 40.95889ms)","trace[102936975] 'applied index is now lower than readState.Index' (duration: 77.58792ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:07:54.524636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.877588ms","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-09-04T16:07:54.524722Z","caller":"traceutil/trace.go:171","msg":"trace[1333365823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1986; }","duration":"119.024312ms","start":"2026-09-04T16:07:54.405679Z","end":"2026-09-04T16:07:54.524703Z","steps":["trace[1333365823] 'agreement among raft nodes before linearized reading' (duration: 118.772376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:00.130463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.954529ms","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-09-04T16:08:00.130551Z","caller":"traceutil/trace.go:171","msg":"trace[2139581268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2022; }","duration":"125.098142ms","start":"2026-09-04T16:08:00.005433Z","end":"2026-09-04T16:08:00.130532Z","steps":["trace[2139581268] 'range keys from in-memory index tree' (duration: 124.046167ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:08:00.785577Z","caller":"traceutil/trace.go:171","msg":"trace[512097700] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"217.924945ms","start":"2026-09-04T16:08:00.56763Z","end":"2026-09-04T16:08:00.785555Z","steps":["trace[512097700] 'process raft request' (duration: 217.637099ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:08:00.785562Z","caller":"traceutil/trace.go:171","msg":"trace[1901936877] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"217.872304ms","start":"2026-09-04T16:08:00.567659Z","end":"2026-09-04T16:08:00.785531Z","steps":["trace[1901936877] 'process raft request' (duration: 217.802222ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:08:00.813131Z","caller":"traceutil/trace.go:171","msg":"trace[1577166161] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2111; }","duration":"216.840691ms","start":"2026-09-04T16:08:00.596267Z","end":"2026-09-04T16:08:00.813108Z","steps":["trace[1577166161] 'read index received' (duration: 188.982694ms)","trace[1577166161] 'applied index is now lower than readState.Index' (duration: 27.856857ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:08:00.813241Z","caller":"traceutil/trace.go:171","msg":"trace[1420172348] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"220.26393ms","start":"2026-09-04T16:08:00.592931Z","end":"2026-09-04T16:08:00.813195Z","steps":["trace[1420172348] 'process raft request' (duration: 220.021884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:00.813384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.824016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-04T16:08:00.813418Z","caller":"traceutil/trace.go:171","msg":"trace[862513676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2032; }","duration":"215.921478ms","start":"2026-09-04T16:08:00.597485Z","end":"2026-09-04T16:08:00.813406Z","steps":["trace[862513676] 'agreement among raft nodes before linearized reading' (duration: 215.766565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:00.813441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.650222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:08:00.813476Z","caller":"traceutil/trace.go:171","msg":"trace[447637851] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2032; }","duration":"152.736774ms","start":"2026-09-04T16:08:00.660725Z","end":"2026-09-04T16:08:00.813461Z","steps":["trace[447637851] 'agreement among raft nodes before linearized reading' (duration: 152.667202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:00.813468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.164588ms","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-09-04T16:08:00.813564Z","caller":"traceutil/trace.go:171","msg":"trace[2010226560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2032; }","duration":"217.310991ms","start":"2026-09-04T16:08:00.596228Z","end":"2026-09-04T16:08:00.813539Z","steps":["trace[2010226560] 'agreement among raft nodes before linearized reading' (duration: 216.997374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:38.492729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.552985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T16:08:38.492874Z","caller":"traceutil/trace.go:171","msg":"trace[157850762] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2241; }","duration":"179.724469ms","start":"2026-09-04T16:08:38.313118Z","end":"2026-09-04T16:08:38.492843Z","steps":["trace[157850762] 'count revisions from in-memory index tree' (duration: 179.455622ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:08:39.159629Z","caller":"traceutil/trace.go:171","msg":"trace[1027740994] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2332; }","duration":"170.71405ms","start":"2026-09-04T16:08:38.988885Z","end":"2026-09-04T16:08:39.159599Z","steps":["trace[1027740994] 'read index received' (duration: 170.433114ms)","trace[1027740994] 'applied index is now lower than readState.Index' (duration: 279.686µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:08:39.159871Z","caller":"traceutil/trace.go:171","msg":"trace[1339399635] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"184.716245ms","start":"2026-09-04T16:08:38.975047Z","end":"2026-09-04T16:08:39.159763Z","steps":["trace[1339399635] 'process raft request' (duration: 184.345557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:39.160094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.118079ms","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-09-04T16:08:39.160161Z","caller":"traceutil/trace.go:171","msg":"trace[1398057065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2243; }","duration":"171.240922ms","start":"2026-09-04T16:08:38.988904Z","end":"2026-09-04T16:08:39.160145Z","steps":["trace[1398057065] 'agreement among raft nodes before linearized reading' (duration: 170.876864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:39.160154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.094747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-04T16:08:39.160216Z","caller":"traceutil/trace.go:171","msg":"trace[1810853258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2243; }","duration":"136.210349ms","start":"2026-09-04T16:08:39.023991Z","end":"2026-09-04T16:08:39.160202Z","steps":["trace[1810853258] 'agreement among raft nodes before linearized reading' (duration: 136.012925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:08:39.160089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.33712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-04T16:08:39.160095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.199711ms","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-09-04T16:08:39.16032Z","caller":"traceutil/trace.go:171","msg":"trace[1458102434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2243; }","duration":"126.608847ms","start":"2026-09-04T16:08:39.033669Z","end":"2026-09-04T16:08:39.160278Z","steps":["trace[1458102434] 'agreement among raft nodes before linearized reading' (duration: 126.240198ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:08:39.160373Z","caller":"traceutil/trace.go:171","msg":"trace[889603152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2243; }","duration":"171.518289ms","start":"2026-09-04T16:08:38.988838Z","end":"2026-09-04T16:08:39.160356Z","steps":["trace[889603152] 'agreement among raft nodes before linearized reading' (duration: 170.941435ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:09:31.002323Z","caller":"traceutil/trace.go:171","msg":"trace[759990013] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"109.624525ms","start":"2026-09-04T16:09:30.892661Z","end":"2026-09-04T16:09:31.002286Z","steps":["trace[759990013] 'process raft request' (duration: 103.096764ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:09:31.002427Z","caller":"traceutil/trace.go:171","msg":"trace[627037031] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"103.254017ms","start":"2026-09-04T16:09:30.899148Z","end":"2026-09-04T16:09:31.002402Z","steps":["trace[627037031] 'process raft request' (duration: 102.763866ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:09:34.812511Z","caller":"traceutil/trace.go:171","msg":"trace[832303160] linearizableReadLoop","detail":"{readStateIndex:2822; appliedIndex:2821; }","duration":"335.40616ms","start":"2026-09-04T16:09:34.477075Z","end":"2026-09-04T16:09:34.812481Z","steps":["trace[832303160] 'read index received' (duration: 335.185475ms)","trace[832303160] 'applied index is now lower than readState.Index' (duration: 219.475µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:09:34.812593Z","caller":"traceutil/trace.go:171","msg":"trace[1107396598] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"341.329638ms","start":"2026-09-04T16:09:34.471218Z","end":"2026-09-04T16:09:34.812547Z","steps":["trace[1107396598] 'process raft request' (duration: 341.071482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:09:34.812708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.604115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:09:34.812797Z","caller":"traceutil/trace.go:171","msg":"trace[763865418] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2714; }","duration":"335.748379ms","start":"2026-09-04T16:09:34.477029Z","end":"2026-09-04T16:09:34.812777Z","steps":["trace[763865418] 'agreement among raft nodes before linearized reading' (duration: 335.589674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:09:34.812798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:09:34.471169Z","time spent":"341.530803ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:09:34.812882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:09:34.476984Z","time spent":"335.858851ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-04T16:09:34.82124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.187556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-04T16:09:34.821259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.753416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T16:09:34.821314Z","caller":"traceutil/trace.go:171","msg":"trace[2027070084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2714; }","duration":"106.327629ms","start":"2026-09-04T16:09:34.714969Z","end":"2026-09-04T16:09:34.821297Z","steps":["trace[2027070084] 'agreement among raft nodes before linearized reading' (duration: 106.184436ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:09:34.821347Z","caller":"traceutil/trace.go:171","msg":"trace[163393941] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2714; }","duration":"260.89498ms","start":"2026-09-04T16:09:34.560425Z","end":"2026-09-04T16:09:34.82132Z","steps":["trace[163393941] 'agreement among raft nodes before linearized reading' (duration: 260.744646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:09:34.821418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.63366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-04T16:09:34.821473Z","caller":"traceutil/trace.go:171","msg":"trace[1733326227] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2714; }","duration":"287.732413ms","start":"2026-09-04T16:09:34.533728Z","end":"2026-09-04T16:09:34.82146Z","steps":["trace[1733326227] 'agreement among raft nodes before linearized reading' (duration: 287.542968ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:10:08.000082Z","caller":"traceutil/trace.go:171","msg":"trace[3693894] linearizableReadLoop","detail":"{readStateIndex:3029; appliedIndex:3028; }","duration":"122.600515ms","start":"2026-09-04T16:10:07.877448Z","end":"2026-09-04T16:10:08.000048Z","steps":["trace[3693894] 'read index received' (duration: 122.307888ms)","trace[3693894] 'applied index is now lower than readState.Index' (duration: 291.517µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:10:08.000204Z","caller":"traceutil/trace.go:171","msg":"trace[497569140] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"138.906473ms","start":"2026-09-04T16:10:07.861255Z","end":"2026-09-04T16:10:08.000162Z","steps":["trace[497569140] 'process raft request' (duration: 138.597336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:10:08.000382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.469637ms","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-09-04T16:10:08.000431Z","caller":"traceutil/trace.go:171","msg":"trace[1942774869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2914; }","duration":"111.589859ms","start":"2026-09-04T16:10:07.888826Z","end":"2026-09-04T16:10:08.000416Z","steps":["trace[1942774869] 'agreement among raft nodes before linearized reading' (duration: 111.410215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:10:08.00085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.364293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-04T16:10:08.00096Z","caller":"traceutil/trace.go:171","msg":"trace[1801408448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2914; }","duration":"123.542027ms","start":"2026-09-04T16:10:07.877393Z","end":"2026-09-04T16:10:08.000935Z","steps":["trace[1801408448] 'agreement among raft nodes before linearized reading' (duration: 122.83413ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:12:53.63401Z","caller":"traceutil/trace.go:171","msg":"trace[79219605] linearizableReadLoop","detail":"{readStateIndex:4070; appliedIndex:4069; }","duration":"131.313811ms","start":"2026-09-04T16:12:53.502665Z","end":"2026-09-04T16:12:53.633979Z","steps":["trace[79219605] 'read index received' (duration: 101.990563ms)","trace[79219605] 'applied index is now lower than readState.Index' (duration: 29.321257ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:12:53.634089Z","caller":"traceutil/trace.go:171","msg":"trace[146554426] transaction","detail":"{read_only:false; response_revision:3915; number_of_response:1; }","duration":"131.389373ms","start":"2026-09-04T16:12:53.502639Z","end":"2026-09-04T16:12:53.634028Z","steps":["trace[146554426] 'process raft request' (duration: 102.107816ms)","trace[146554426] 'compare' (duration: 29.060532ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:12:53.634173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.485914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:12:53.634215Z","caller":"traceutil/trace.go:171","msg":"trace[364653150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.secretgen.k14s.io; range_end:; response_count:0; response_revision:3915; }","duration":"131.548056ms","start":"2026-09-04T16:12:53.502649Z","end":"2026-09-04T16:12:53.634197Z","steps":["trace[364653150] 'agreement among raft nodes before linearized reading' (duration: 131.455214ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:14:58.016239Z","caller":"traceutil/trace.go:171","msg":"trace[1402735945] linearizableReadLoop","detail":"{readStateIndex:5398; appliedIndex:5397; }","duration":"199.845937ms","start":"2026-09-04T16:14:57.816364Z","end":"2026-09-04T16:14:58.01621Z","steps":["trace[1402735945] 'read index received' (duration: 199.299654ms)","trace[1402735945] 'applied index is now lower than readState.Index' (duration: 545.563µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:14:58.016341Z","caller":"traceutil/trace.go:171","msg":"trace[1683861115] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"207.691085ms","start":"2026-09-04T16:14:57.808617Z","end":"2026-09-04T16:14:58.016308Z","steps":["trace[1683861115] 'process raft request' (duration: 207.137772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:14:58.016534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.130304ms","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-09-04T16:14:58.016573Z","caller":"traceutil/trace.go:171","msg":"trace[1308136975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5196; }","duration":"200.213265ms","start":"2026-09-04T16:14:57.816351Z","end":"2026-09-04T16:14:58.016564Z","steps":["trace[1308136975] 'agreement among raft nodes before linearized reading' (duration: 200.039562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:14:58.016651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.1101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2debdfd3-123b-40f7-8afc-7321089d2fb2\" ","response":"range_response_count:1 size:3774"} {"level":"warn","ts":"2026-09-04T16:14:58.016699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.284966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-04T16:14:58.016756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.523512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-04T16:14:58.016793Z","caller":"traceutil/trace.go:171","msg":"trace[1707668332] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5196; }","duration":"168.584003ms","start":"2026-09-04T16:14:57.848201Z","end":"2026-09-04T16:14:58.016786Z","steps":["trace[1707668332] 'agreement among raft nodes before linearized reading' (duration: 168.499932ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:14:58.016795Z","caller":"traceutil/trace.go:171","msg":"trace[1149111655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5196; }","duration":"175.45602ms","start":"2026-09-04T16:14:57.841318Z","end":"2026-09-04T16:14:58.016774Z","steps":["trace[1149111655] 'agreement among raft nodes before linearized reading' (duration: 175.125713ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:14:58.016738Z","caller":"traceutil/trace.go:171","msg":"trace[393529203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2debdfd3-123b-40f7-8afc-7321089d2fb2; range_end:; response_count:1; response_revision:5196; }","duration":"167.230453ms","start":"2026-09-04T16:14:57.849486Z","end":"2026-09-04T16:14:58.016716Z","steps":["trace[393529203] 'agreement among raft nodes before linearized reading' (duration: 167.032368ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:04.07858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.832216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:15:04.078728Z","caller":"traceutil/trace.go:171","msg":"trace[1264110877] linearizableReadLoop","detail":"{readStateIndex:5614; appliedIndex:5613; }","duration":"150.139513ms","start":"2026-09-04T16:15:03.928564Z","end":"2026-09-04T16:15:04.078703Z","steps":["trace[1264110877] 'read index received' (duration: 38.961µs)","trace[1264110877] 'applied index is now lower than readState.Index' (duration: 150.098592ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:04.078814Z","caller":"traceutil/trace.go:171","msg":"trace[1537373463] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"239.840348ms","start":"2026-09-04T16:15:03.838939Z","end":"2026-09-04T16:15:04.078779Z","steps":["trace[1537373463] 'process raft request' (duration: 35.686374ms)","trace[1537373463] 'compare' (duration: 203.52735ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:15:04.078984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.410419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f28c10b-09ec-4a1f-8da8-e74399808a34\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-04T16:15:04.079039Z","caller":"traceutil/trace.go:171","msg":"trace[265139640] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f28c10b-09ec-4a1f-8da8-e74399808a34; range_end:; response_count:1; response_revision:5404; }","duration":"150.504532ms","start":"2026-09-04T16:15:03.928517Z","end":"2026-09-04T16:15:04.079022Z","steps":["trace[265139640] 'agreement among raft nodes before linearized reading' (duration: 150.306337ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:04.295065Z","caller":"traceutil/trace.go:171","msg":"trace[649906419] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5614; }","duration":"199.480298ms","start":"2026-09-04T16:15:04.095544Z","end":"2026-09-04T16:15:04.295024Z","steps":["trace[649906419] 'read index received' (duration: 162.303551ms)","trace[649906419] 'applied index is now lower than readState.Index' (duration: 37.175027ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:04.295153Z","caller":"traceutil/trace.go:171","msg":"trace[1145227228] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"200.397048ms","start":"2026-09-04T16:15:04.094708Z","end":"2026-09-04T16:15:04.295105Z","steps":["trace[1145227228] 'process raft request' (duration: 163.416865ms)","trace[1145227228] 'compare' (duration: 36.600524ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:15:04.295321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.721193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:15:04.295388Z","caller":"traceutil/trace.go:171","msg":"trace[894771261] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:0; response_revision:5405; }","duration":"199.857816ms","start":"2026-09-04T16:15:04.095514Z","end":"2026-09-04T16:15:04.295371Z","steps":["trace[894771261] 'agreement among raft nodes before linearized reading' (duration: 199.701442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:04.295402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.378215ms","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-09-04T16:15:04.295451Z","caller":"traceutil/trace.go:171","msg":"trace[63877547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5405; }","duration":"149.480367ms","start":"2026-09-04T16:15:04.145957Z","end":"2026-09-04T16:15:04.295437Z","steps":["trace[63877547] 'agreement among raft nodes before linearized reading' (duration: 149.296443ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:04.726519Z","caller":"traceutil/trace.go:171","msg":"trace[33146001] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"209.497927ms","start":"2026-09-04T16:15:04.51696Z","end":"2026-09-04T16:15:04.726458Z","steps":["trace[33146001] 'process raft request' (duration: 160.680914ms)","trace[33146001] 'compare' (duration: 48.452224ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:12.912284Z","caller":"traceutil/trace.go:171","msg":"trace[2139730781] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"124.198121ms","start":"2026-09-04T16:15:12.788024Z","end":"2026-09-04T16:15:12.912222Z","steps":["trace[2139730781] 'process raft request' (duration: 123.996286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:12.938188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.878729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-04T16:15:12.938301Z","caller":"traceutil/trace.go:171","msg":"trace[743258625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5562; }","duration":"108.055903ms","start":"2026-09-04T16:15:12.830222Z","end":"2026-09-04T16:15:12.938277Z","steps":["trace[743258625] 'agreement among raft nodes before linearized reading' (duration: 107.693724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:13.127628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.608788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:15:13.127913Z","caller":"traceutil/trace.go:171","msg":"trace[1784659432] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"183.874942ms","start":"2026-09-04T16:15:12.944021Z","end":"2026-09-04T16:15:13.127896Z","steps":["trace[1784659432] 'process raft request' (duration: 183.80629ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:13.1279Z","caller":"traceutil/trace.go:171","msg":"trace[808245045] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"208.700778ms","start":"2026-09-04T16:15:12.919091Z","end":"2026-09-04T16:15:13.127792Z","steps":["trace[808245045] 'process raft request' (duration: 84.836864ms)","trace[808245045] 'compare' (duration: 123.423144ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:13.127929Z","caller":"traceutil/trace.go:171","msg":"trace[2026721900] linearizableReadLoop","detail":"{readStateIndex:5780; appliedIndex:5779; }","duration":"190.016462ms","start":"2026-09-04T16:15:12.937875Z","end":"2026-09-04T16:15:13.127891Z","steps":["trace[2026721900] 'read index received' (duration: 66.072556ms)","trace[2026721900] 'applied index is now lower than readState.Index' (duration: 123.940106ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:15:13.128242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.948563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62908"} {"level":"info","ts":"2026-09-04T16:15:13.128512Z","caller":"traceutil/trace.go:171","msg":"trace[487486267] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5564; }","duration":"209.28404ms","start":"2026-09-04T16:15:12.919214Z","end":"2026-09-04T16:15:13.128498Z","steps":["trace[487486267] 'agreement among raft nodes before linearized reading' (duration: 208.776549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:13.358192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.655204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:15:13.358315Z","caller":"traceutil/trace.go:171","msg":"trace[493622676] linearizableReadLoop","detail":"{readStateIndex:5782; appliedIndex:5781; }","duration":"225.960071ms","start":"2026-09-04T16:15:13.132336Z","end":"2026-09-04T16:15:13.358296Z","steps":["trace[493622676] 'read index received' (duration: 118.043921ms)","trace[493622676] 'applied index is now lower than readState.Index' (duration: 107.91498ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:15:13.358613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.271409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129868"} {"level":"info","ts":"2026-09-04T16:15:13.358582Z","caller":"traceutil/trace.go:171","msg":"trace[1240844988] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"232.887129ms","start":"2026-09-04T16:15:13.125662Z","end":"2026-09-04T16:15:13.358549Z","steps":["trace[1240844988] 'process raft request' (duration: 124.784685ms)","trace[1240844988] 'compare' (duration: 107.466549ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:13.358665Z","caller":"traceutil/trace.go:171","msg":"trace[426937484] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5565; }","duration":"226.359971ms","start":"2026-09-04T16:15:13.132293Z","end":"2026-09-04T16:15:13.358653Z","steps":["trace[426937484] 'agreement among raft nodes before linearized reading' (duration: 226.049453ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:13.52176Z","caller":"traceutil/trace.go:171","msg":"trace[1509525302] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"160.070639ms","start":"2026-09-04T16:15:13.36163Z","end":"2026-09-04T16:15:13.521701Z","steps":["trace[1509525302] 'process raft request' (duration: 143.90284ms)","trace[1509525302] 'compare' (duration: 15.846432ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:15.565838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2955} {"level":"info","ts":"2026-09-04T16:15:15.652669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2955,"took":"85.06126ms","hash":3248856591,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T16:15:15.652751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248856591,"revision":2955,"compact-revision":-1} {"level":"info","ts":"2026-09-04T16:15:19.619256Z","caller":"traceutil/trace.go:171","msg":"trace[1053593700] linearizableReadLoop","detail":"{readStateIndex:5896; appliedIndex:5895; }","duration":"126.264638ms","start":"2026-09-04T16:15:19.492966Z","end":"2026-09-04T16:15:19.61923Z","steps":["trace[1053593700] 'read index received' (duration: 125.980942ms)","trace[1053593700] 'applied index is now lower than readState.Index' (duration: 282.566µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:19.619324Z","caller":"traceutil/trace.go:171","msg":"trace[1112730374] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"212.320609ms","start":"2026-09-04T16:15:19.40696Z","end":"2026-09-04T16:15:19.61928Z","steps":["trace[1112730374] 'process raft request' (duration: 212.010562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:19.619513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.541935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-6mmk8\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-09-04T16:15:19.619551Z","caller":"traceutil/trace.go:171","msg":"trace[1434397846] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-6mmk8; range_end:; response_count:1; response_revision:5675; }","duration":"126.653557ms","start":"2026-09-04T16:15:19.492886Z","end":"2026-09-04T16:15:19.61954Z","steps":["trace[1434397846] 'agreement among raft nodes before linearized reading' (duration: 126.451613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:19.833392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.315954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:15:19.833529Z","caller":"traceutil/trace.go:171","msg":"trace[1179238512] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"212.021844ms","start":"2026-09-04T16:15:19.621482Z","end":"2026-09-04T16:15:19.833504Z","steps":["trace[1179238512] 'process raft request' (duration: 94.525995ms)","trace[1179238512] 'compare' (duration: 117.146261ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:19.839636Z","caller":"traceutil/trace.go:171","msg":"trace[738570766] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"208.042783ms","start":"2026-09-04T16:15:19.631565Z","end":"2026-09-04T16:15:19.839608Z","steps":["trace[738570766] 'process raft request' (duration: 207.678355ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:28.056685Z","caller":"traceutil/trace.go:171","msg":"trace[895485049] linearizableReadLoop","detail":"{readStateIndex:6071; appliedIndex:6070; }","duration":"104.42231ms","start":"2026-09-04T16:15:27.952237Z","end":"2026-09-04T16:15:28.05666Z","steps":["trace[895485049] 'read index received' (duration: 104.148594ms)","trace[895485049] 'applied index is now lower than readState.Index' (duration: 272.796µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:28.056811Z","caller":"traceutil/trace.go:171","msg":"trace[972849532] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"140.090327ms","start":"2026-09-04T16:15:27.916695Z","end":"2026-09-04T16:15:28.056786Z","steps":["trace[972849532] 'process raft request' (duration: 139.799401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:15:28.056915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.648225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd57f90e-a236-450f-8575-77487c925fe8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-04T16:15:28.056949Z","caller":"traceutil/trace.go:171","msg":"trace[590913227] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd57f90e-a236-450f-8575-77487c925fe8; range_end:; response_count:1; response_revision:5849; }","duration":"104.705307ms","start":"2026-09-04T16:15:27.952233Z","end":"2026-09-04T16:15:28.056939Z","steps":["trace[590913227] 'agreement among raft nodes before linearized reading' (duration: 104.523583ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:28.69074Z","caller":"traceutil/trace.go:171","msg":"trace[502200879] linearizableReadLoop","detail":"{readStateIndex:6075; appliedIndex:6074; }","duration":"116.61164ms","start":"2026-09-04T16:15:28.574103Z","end":"2026-09-04T16:15:28.690714Z","steps":["trace[502200879] 'read index received' (duration: 98.442103ms)","trace[502200879] 'applied index is now lower than readState.Index' (duration: 18.168367ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:15:28.690816Z","caller":"traceutil/trace.go:171","msg":"trace[1276864557] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"135.190596ms","start":"2026-09-04T16:15:28.555571Z","end":"2026-09-04T16:15:28.690761Z","steps":["trace[1276864557] 'process raft request' (duration: 116.994419ms)","trace[1276864557] 'compare' (duration: 18.005613ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:15:28.690959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.639913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-04T16:15:28.690965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.839156ms","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-09-04T16:15:28.690995Z","caller":"traceutil/trace.go:171","msg":"trace[90637830] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5853; }","duration":"113.715124ms","start":"2026-09-04T16:15:28.577267Z","end":"2026-09-04T16:15:28.690982Z","steps":["trace[90637830] 'agreement among raft nodes before linearized reading' (duration: 113.601381ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:15:28.691001Z","caller":"traceutil/trace.go:171","msg":"trace[537214088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5853; }","duration":"116.934667ms","start":"2026-09-04T16:15:28.574056Z","end":"2026-09-04T16:15:28.69099Z","steps":["trace[537214088] 'agreement among raft nodes before linearized reading' (duration: 116.778053ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:20:07.820645Z","caller":"traceutil/trace.go:171","msg":"trace[1432730996] transaction","detail":"{read_only:false; response_revision:7948; number_of_response:1; }","duration":"100.735091ms","start":"2026-09-04T16:20:07.719866Z","end":"2026-09-04T16:20:07.820601Z","steps":["trace[1432730996] 'process raft request' (duration: 63.917759ms)","trace[1432730996] 'compare' (duration: 36.647448ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:20:08.506074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.415977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:20:08.506484Z","caller":"traceutil/trace.go:171","msg":"trace[672050684] transaction","detail":"{read_only:false; response_revision:7951; number_of_response:1; }","duration":"220.800421ms","start":"2026-09-04T16:20:08.285657Z","end":"2026-09-04T16:20:08.506458Z","steps":["trace[672050684] 'process raft request' (duration: 220.544435ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:20:08.506522Z","caller":"traceutil/trace.go:171","msg":"trace[642675510] transaction","detail":"{read_only:false; response_revision:7950; number_of_response:1; }","duration":"222.241142ms","start":"2026-09-04T16:20:08.284226Z","end":"2026-09-04T16:20:08.506467Z","steps":["trace[642675510] 'process raft request' (duration: 110.322994ms)","trace[642675510] 'compare' (duration: 111.231293ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:20:15.572413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5615} {"level":"info","ts":"2026-09-04T16:20:15.660898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5615,"took":"86.598279ms","hash":3218598655,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":28942336,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-04T16:20:15.660972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3218598655,"revision":5615,"compact-revision":2955} {"level":"info","ts":"2026-09-04T16:21:45.314455Z","caller":"traceutil/trace.go:171","msg":"trace[5590519] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"151.442009ms","start":"2026-09-04T16:21:45.162977Z","end":"2026-09-04T16:21:45.314419Z","steps":["trace[5590519] 'process raft request' (duration: 151.279856ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:45.316894Z","caller":"traceutil/trace.go:171","msg":"trace[1890291737] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"150.57171ms","start":"2026-09-04T16:21:45.166283Z","end":"2026-09-04T16:21:45.316855Z","steps":["trace[1890291737] 'process raft request' (duration: 150.522439ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:45.316939Z","caller":"traceutil/trace.go:171","msg":"trace[1510808027] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"152.618057ms","start":"2026-09-04T16:21:45.164298Z","end":"2026-09-04T16:21:45.316916Z","steps":["trace[1510808027] 'process raft request' (duration: 152.3156ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:56.361504Z","caller":"traceutil/trace.go:171","msg":"trace[2122430742] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"168.289771ms","start":"2026-09-04T16:21:56.193187Z","end":"2026-09-04T16:21:56.361477Z","steps":["trace[2122430742] 'process raft request' (duration: 168.077127ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:57.080212Z","caller":"traceutil/trace.go:171","msg":"trace[1939677432] linearizableReadLoop","detail":"{readStateIndex:9120; appliedIndex:9119; }","duration":"301.38367ms","start":"2026-09-04T16:21:56.778804Z","end":"2026-09-04T16:21:57.080188Z","steps":["trace[1939677432] 'read index received' (duration: 301.120884ms)","trace[1939677432] 'applied index is now lower than readState.Index' (duration: 261.876µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:21:57.080574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.563523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T16:21:57.080597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.561488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.080628Z","caller":"traceutil/trace.go:171","msg":"trace[1513007477] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"154.621979ms","start":"2026-09-04T16:21:56.925996Z","end":"2026-09-04T16:21:57.080618Z","steps":["trace[1513007477] 'agreement among raft nodes before linearized reading' (duration: 154.569178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.080672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.645172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.080697Z","caller":"traceutil/trace.go:171","msg":"trace[1762496332] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"155.738675ms","start":"2026-09-04T16:21:56.92495Z","end":"2026-09-04T16:21:57.080689Z","steps":["trace[1762496332] 'agreement among raft nodes before linearized reading' (duration: 155.695464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.080836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.790906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.080887Z","caller":"traceutil/trace.go:171","msg":"trace[1532874375] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"334.128607ms","start":"2026-09-04T16:21:56.746747Z","end":"2026-09-04T16:21:57.080876Z","steps":["trace[1532874375] 'process raft request' (duration: 333.260468ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:57.080875Z","caller":"traceutil/trace.go:171","msg":"trace[857728815] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8801; }","duration":"155.889508ms","start":"2026-09-04T16:21:56.924973Z","end":"2026-09-04T16:21:57.080862Z","steps":["trace[857728815] 'agreement among raft nodes before linearized reading' (duration: 155.798276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.080992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:21:56.74672Z","time spent":"334.193219ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:21:57.081132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.327411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.081163Z","caller":"traceutil/trace.go:171","msg":"trace[2011332465] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8801; }","duration":"302.401342ms","start":"2026-09-04T16:21:56.778753Z","end":"2026-09-04T16:21:57.081154Z","steps":["trace[2011332465] 'agreement among raft nodes before linearized reading' (duration: 302.354981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.081176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.873628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T16:21:57.0812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:21:56.778725Z","time spent":"302.464675ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 "} {"level":"info","ts":"2026-09-04T16:21:57.081205Z","caller":"traceutil/trace.go:171","msg":"trace[837323348] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8801; }","duration":"176.95252ms","start":"2026-09-04T16:21:56.904242Z","end":"2026-09-04T16:21:57.081195Z","steps":["trace[837323348] 'agreement among raft nodes before linearized reading' (duration: 176.908658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.081379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.145605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.081409Z","caller":"traceutil/trace.go:171","msg":"trace[385956504] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8801; }","duration":"177.224937ms","start":"2026-09-04T16:21:56.904174Z","end":"2026-09-04T16:21:57.081399Z","steps":["trace[385956504] 'agreement among raft nodes before linearized reading' (duration: 177.167065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.08154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.841365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.081589Z","caller":"traceutil/trace.go:171","msg":"trace[307784366] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8801; }","duration":"201.03773ms","start":"2026-09-04T16:21:56.880541Z","end":"2026-09-04T16:21:57.081579Z","steps":["trace[307784366] 'agreement among raft nodes before linearized reading' (duration: 200.968048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.081752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.262624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.081785Z","caller":"traceutil/trace.go:171","msg":"trace[1744693148] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8801; }","duration":"201.313135ms","start":"2026-09-04T16:21:56.880459Z","end":"2026-09-04T16:21:57.081773Z","steps":["trace[1744693148] 'agreement among raft nodes before linearized reading' (duration: 201.261514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.081929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.681643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.081958Z","caller":"traceutil/trace.go:171","msg":"trace[930662954] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8801; }","duration":"201.757845ms","start":"2026-09-04T16:21:56.880191Z","end":"2026-09-04T16:21:57.081948Z","steps":["trace[930662954] 'agreement among raft nodes before linearized reading' (duration: 201.706384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.166002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082125Z","caller":"traceutil/trace.go:171","msg":"trace[365165123] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8801; }","duration":"233.197813ms","start":"2026-09-04T16:21:56.848918Z","end":"2026-09-04T16:21:57.082116Z","steps":["trace[365165123] 'agreement among raft nodes before linearized reading' (duration: 233.146712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.688458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082326Z","caller":"traceutil/trace.go:171","msg":"trace[1767591405] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8801; }","duration":"234.790779ms","start":"2026-09-04T16:21:56.847524Z","end":"2026-09-04T16:21:57.082314Z","steps":["trace[1767591405] 'agreement among raft nodes before linearized reading' (duration: 234.740028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.950273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082485Z","caller":"traceutil/trace.go:171","msg":"trace[2121535263] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8801; }","duration":"234.981234ms","start":"2026-09-04T16:21:56.847494Z","end":"2026-09-04T16:21:57.082476Z","steps":["trace[2121535263] 'agreement among raft nodes before linearized reading' (duration: 234.931283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.221791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.0826Z","caller":"traceutil/trace.go:171","msg":"trace[2092631467] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8801; }","duration":"108.321573ms","start":"2026-09-04T16:21:56.974267Z","end":"2026-09-04T16:21:57.082588Z","steps":["trace[2092631467] 'agreement among raft nodes before linearized reading' (duration: 108.245042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.885447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082666Z","caller":"traceutil/trace.go:171","msg":"trace[1713056769] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8801; }","duration":"264.969519ms","start":"2026-09-04T16:21:56.817688Z","end":"2026-09-04T16:21:57.082657Z","steps":["trace[1713056769] 'agreement among raft nodes before linearized reading' (duration: 264.919178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.492477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082788Z","caller":"traceutil/trace.go:171","msg":"trace[2012362224] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8801; }","duration":"108.547348ms","start":"2026-09-04T16:21:56.97423Z","end":"2026-09-04T16:21:57.082778Z","steps":["trace[2012362224] 'agreement among raft nodes before linearized reading' (duration: 108.498627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.218874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082849Z","caller":"traceutil/trace.go:171","msg":"trace[1024126574] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8801; }","duration":"265.324627ms","start":"2026-09-04T16:21:56.817516Z","end":"2026-09-04T16:21:57.082841Z","steps":["trace[1024126574] 'agreement among raft nodes before linearized reading' (duration: 265.278276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.082926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.690792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.082954Z","caller":"traceutil/trace.go:171","msg":"trace[1715454303] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8801; }","duration":"108.788633ms","start":"2026-09-04T16:21:56.974157Z","end":"2026-09-04T16:21:57.082946Z","steps":["trace[1715454303] 'agreement among raft nodes before linearized reading' (duration: 108.740432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.637293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083036Z","caller":"traceutil/trace.go:171","msg":"trace[88448158] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8801; }","duration":"265.700655ms","start":"2026-09-04T16:21:56.817324Z","end":"2026-09-04T16:21:57.083025Z","steps":["trace[88448158] 'agreement among raft nodes before linearized reading' (duration: 265.627504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.0831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.851901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083131Z","caller":"traceutil/trace.go:171","msg":"trace[742238321] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"135.905622ms","start":"2026-09-04T16:21:56.947214Z","end":"2026-09-04T16:21:57.08312Z","steps":["trace[742238321] 'agreement among raft nodes before linearized reading' (duration: 135.854021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.157641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083207Z","caller":"traceutil/trace.go:171","msg":"trace[986631767] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8801; }","duration":"282.218832ms","start":"2026-09-04T16:21:56.800976Z","end":"2026-09-04T16:21:57.083195Z","steps":["trace[986631767] 'agreement among raft nodes before linearized reading' (duration: 282.165531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.907645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083331Z","caller":"traceutil/trace.go:171","msg":"trace[707768706] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"136.994767ms","start":"2026-09-04T16:21:56.946327Z","end":"2026-09-04T16:21:57.083321Z","steps":["trace[707768706] 'agreement among raft nodes before linearized reading' (duration: 136.939256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.060851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083466Z","caller":"traceutil/trace.go:171","msg":"trace[1483829480] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8801; }","duration":"283.144373ms","start":"2026-09-04T16:21:56.800311Z","end":"2026-09-04T16:21:57.083455Z","steps":["trace[1483829480] 'agreement among raft nodes before linearized reading' (duration: 283.044601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.081132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.743991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083622Z","caller":"traceutil/trace.go:171","msg":"trace[589634957] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8801; }","duration":"178.285749ms","start":"2026-09-04T16:21:56.905325Z","end":"2026-09-04T16:21:57.083611Z","steps":["trace[589634957] 'agreement among raft nodes before linearized reading' (duration: 175.770352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.44862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083664Z","caller":"traceutil/trace.go:171","msg":"trace[1264984189] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8801; }","duration":"283.526291ms","start":"2026-09-04T16:21:56.800128Z","end":"2026-09-04T16:21:57.083654Z","steps":["trace[1264984189] 'agreement among raft nodes before linearized reading' (duration: 283.47348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.445889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.083868Z","caller":"traceutil/trace.go:171","msg":"trace[1150730150] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8801; }","duration":"304.542711ms","start":"2026-09-04T16:21:56.779316Z","end":"2026-09-04T16:21:57.083858Z","steps":["trace[1150730150] 'agreement among raft nodes before linearized reading' (duration: 304.449699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.083901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:21:56.779306Z","time spent":"304.580553ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 "} {"level":"warn","ts":"2026-09-04T16:21:57.083466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.211162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:21:57.084142Z","caller":"traceutil/trace.go:171","msg":"trace[942483237] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8801; }","duration":"137.907549ms","start":"2026-09-04T16:21:56.946222Z","end":"2026-09-04T16:21:57.08413Z","steps":["trace[942483237] 'agreement among raft nodes before linearized reading' (duration: 137.215702ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:57.080627Z","caller":"traceutil/trace.go:171","msg":"trace[628007007] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8801; }","duration":"301.660185ms","start":"2026-09-04T16:21:56.778953Z","end":"2026-09-04T16:21:57.080613Z","steps":["trace[628007007] 'agreement among raft nodes before linearized reading' (duration: 301.526632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:57.084335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:21:56.778844Z","time spent":"305.472483ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-09-04T16:21:57.551177Z","caller":"traceutil/trace.go:171","msg":"trace[1271134711] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"117.471051ms","start":"2026-09-04T16:21:57.43367Z","end":"2026-09-04T16:21:57.551141Z","steps":["trace[1271134711] 'process raft request' (duration: 33.104666ms)","trace[1271134711] 'compare' (duration: 84.208142ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:21:58.059429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.817671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67hl5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T16:21:58.059523Z","caller":"traceutil/trace.go:171","msg":"trace[1690216359] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67hl5; range_end:; response_count:1; response_revision:8806; }","duration":"351.984925ms","start":"2026-09-04T16:21:57.707513Z","end":"2026-09-04T16:21:58.059497Z","steps":["trace[1690216359] 'range keys from in-memory index tree' (duration: 351.627167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:21:58.059606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:21:57.707491Z","time spent":"352.099487ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-67hl5\" "} {"level":"info","ts":"2026-09-04T16:21:58.13199Z","caller":"traceutil/trace.go:171","msg":"trace[1772645923] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"124.973263ms","start":"2026-09-04T16:21:58.00697Z","end":"2026-09-04T16:21:58.131944Z","steps":["trace[1772645923] 'process raft request' (duration: 124.670205ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:58.567468Z","caller":"traceutil/trace.go:171","msg":"trace[1050288024] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"187.241475ms","start":"2026-09-04T16:21:58.380182Z","end":"2026-09-04T16:21:58.567423Z","steps":["trace[1050288024] 'process raft request' (duration: 109.109941ms)","trace[1050288024] 'compare' (duration: 77.746125ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:21:59.24682Z","caller":"traceutil/trace.go:171","msg":"trace[482827418] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"157.060656ms","start":"2026-09-04T16:21:59.089747Z","end":"2026-09-04T16:21:59.246808Z","steps":["trace[482827418] 'process raft request' (duration: 156.784829ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:21:59.246748Z","caller":"traceutil/trace.go:171","msg":"trace[604645102] linearizableReadLoop","detail":"{readStateIndex:9129; appliedIndex:9128; }","duration":"133.279543ms","start":"2026-09-04T16:21:59.113444Z","end":"2026-09-04T16:21:59.246724Z","steps":["trace[604645102] 'read index received' (duration: 133.026827ms)","trace[604645102] 'applied index is now lower than readState.Index' (duration: 251.736µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:21:59.247132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.650761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.211\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-04T16:21:59.247221Z","caller":"traceutil/trace.go:171","msg":"trace[1080330621] range","detail":"{range_begin:/registry/masterleases/162.253.55.211; range_end:; response_count:1; response_revision:8810; }","duration":"133.815675ms","start":"2026-09-04T16:21:59.113385Z","end":"2026-09-04T16:21:59.247201Z","steps":["trace[1080330621] 'agreement among raft nodes before linearized reading' (duration: 133.508998ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:25.56317Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002474.wal"} {"level":"info","ts":"2026-09-04T16:22:28.655866Z","caller":"traceutil/trace.go:171","msg":"trace[1768180626] linearizableReadLoop","detail":"{readStateIndex:9352; appliedIndex:9351; }","duration":"329.077992ms","start":"2026-09-04T16:22:28.326699Z","end":"2026-09-04T16:22:28.655777Z","steps":["trace[1768180626] 'read index received' (duration: 328.842877ms)","trace[1768180626] 'applied index is now lower than readState.Index' (duration: 234.065µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:22:28.656142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.422541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67hl5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T16:22:28.656189Z","caller":"traceutil/trace.go:171","msg":"trace[1688801640] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67hl5; range_end:; response_count:1; response_revision:9024; }","duration":"329.526953ms","start":"2026-09-04T16:22:28.326646Z","end":"2026-09-04T16:22:28.656173Z","steps":["trace[1688801640] 'agreement among raft nodes before linearized reading' (duration: 329.310088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:28.656236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:22:28.326622Z","time spent":"329.602044ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-67hl5\" "} {"level":"info","ts":"2026-09-04T16:22:28.656688Z","caller":"traceutil/trace.go:171","msg":"trace[1742583989] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"394.322271ms","start":"2026-09-04T16:22:28.262344Z","end":"2026-09-04T16:22:28.656666Z","steps":["trace[1742583989] 'process raft request' (duration: 393.288898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:28.656833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:22:28.262316Z","time spent":"394.421284ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T16:22:28.657467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.395733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:22:28.65755Z","caller":"traceutil/trace.go:171","msg":"trace[549206841] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:9024; }","duration":"104.535776ms","start":"2026-09-04T16:22:28.552998Z","end":"2026-09-04T16:22:28.657534Z","steps":["trace[549206841] 'agreement among raft nodes before linearized reading' (duration: 104.411413ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:28.852248Z","caller":"traceutil/trace.go:171","msg":"trace[855884830] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"190.15691ms","start":"2026-09-04T16:22:28.662069Z","end":"2026-09-04T16:22:28.852226Z","steps":["trace[855884830] 'process raft request' (duration: 190.008617ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:28.852674Z","caller":"traceutil/trace.go:171","msg":"trace[1912086670] transaction","detail":"{read_only:false; response_revision:9026; number_of_response:1; }","duration":"161.18667ms","start":"2026-09-04T16:22:28.691473Z","end":"2026-09-04T16:22:28.85266Z","steps":["trace[1912086670] 'process raft request' (duration: 161.132409ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:30.832608Z","caller":"traceutil/trace.go:171","msg":"trace[2089505608] linearizableReadLoop","detail":"{readStateIndex:9364; appliedIndex:9363; }","duration":"163.031152ms","start":"2026-09-04T16:22:30.669544Z","end":"2026-09-04T16:22:30.832575Z","steps":["trace[2089505608] 'read index received' (duration: 162.716195ms)","trace[2089505608] 'applied index is now lower than readState.Index' (duration: 313.777µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:22:30.832667Z","caller":"traceutil/trace.go:171","msg":"trace[1464940062] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"170.37211ms","start":"2026-09-04T16:22:30.662263Z","end":"2026-09-04T16:22:30.832635Z","steps":["trace[1464940062] 'process raft request' (duration: 170.071093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:30.832841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.272308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67hl5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-04T16:22:30.832882Z","caller":"traceutil/trace.go:171","msg":"trace[248265818] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67hl5; range_end:; response_count:1; response_revision:9035; }","duration":"163.400811ms","start":"2026-09-04T16:22:30.669471Z","end":"2026-09-04T16:22:30.832871Z","steps":["trace[248265818] 'agreement among raft nodes before linearized reading' (duration: 163.219537ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:38.256964Z","caller":"traceutil/trace.go:171","msg":"trace[1310944642] linearizableReadLoop","detail":"{readStateIndex:9407; appliedIndex:9406; }","duration":"170.362629ms","start":"2026-09-04T16:22:38.086574Z","end":"2026-09-04T16:22:38.256936Z","steps":["trace[1310944642] 'read index received' (duration: 138.973383ms)","trace[1310944642] 'applied index is now lower than readState.Index' (duration: 31.388256ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:22:38.257012Z","caller":"traceutil/trace.go:171","msg":"trace[1403793855] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"335.907888ms","start":"2026-09-04T16:22:37.921063Z","end":"2026-09-04T16:22:38.256971Z","steps":["trace[1403793855] 'process raft request' (duration: 304.609954ms)","trace[1403793855] 'compare' (duration: 31.11909ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:22:38.257096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.518273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:22:38.257129Z","caller":"traceutil/trace.go:171","msg":"trace[599993311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9077; }","duration":"170.631755ms","start":"2026-09-04T16:22:38.086487Z","end":"2026-09-04T16:22:38.257118Z","steps":["trace[599993311] 'agreement among raft nodes before linearized reading' (duration: 170.553784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:38.257181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T16:22:37.921034Z","time spent":"336.045231ms","remote":"127.0.0.1:44946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T16:22:42.82065Z","caller":"traceutil/trace.go:171","msg":"trace[973184246] linearizableReadLoop","detail":"{readStateIndex:9443; appliedIndex:9442; }","duration":"125.957376ms","start":"2026-09-04T16:22:42.69467Z","end":"2026-09-04T16:22:42.820627Z","steps":["trace[973184246] 'read index received' (duration: 125.747381ms)","trace[973184246] 'applied index is now lower than readState.Index' (duration: 209.265µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:22:42.820779Z","caller":"traceutil/trace.go:171","msg":"trace[1122399034] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"138.157624ms","start":"2026-09-04T16:22:42.68261Z","end":"2026-09-04T16:22:42.820767Z","steps":["trace[1122399034] 'process raft request' (duration: 137.834577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:42.821204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.491897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:22:42.821284Z","caller":"traceutil/trace.go:171","msg":"trace[1229778645] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:9111; }","duration":"126.644271ms","start":"2026-09-04T16:22:42.694617Z","end":"2026-09-04T16:22:42.821261Z","steps":["trace[1229778645] 'agreement among raft nodes before linearized reading' (duration: 126.283643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T16:22:43.108713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.861641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T16:22:43.108922Z","caller":"traceutil/trace.go:171","msg":"trace[1955210080] transaction","detail":"{read_only:false; response_revision:9113; number_of_response:1; }","duration":"149.928203ms","start":"2026-09-04T16:22:42.958975Z","end":"2026-09-04T16:22:43.108903Z","steps":["trace[1955210080] 'process raft request' (duration: 149.854722ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:43.109124Z","caller":"traceutil/trace.go:171","msg":"trace[270144932] linearizableReadLoop","detail":"{readStateIndex:9444; appliedIndex:9443; }","duration":"183.315944ms","start":"2026-09-04T16:22:42.925752Z","end":"2026-09-04T16:22:43.109068Z","steps":["trace[270144932] 'read index received' (duration: 11.927002ms)","trace[270144932] 'applied index is now lower than readState.Index' (duration: 171.385722ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:22:43.10914Z","caller":"traceutil/trace.go:171","msg":"trace[1896491686] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"224.507295ms","start":"2026-09-04T16:22:42.884582Z","end":"2026-09-04T16:22:43.109089Z","steps":["trace[1896491686] 'process raft request' (duration: 53.166553ms)","trace[1896491686] 'compare' (duration: 170.688567ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:22:43.109368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.59474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-67hl5\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-09-04T16:22:43.109411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.681ms","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-09-04T16:22:43.109455Z","caller":"traceutil/trace.go:171","msg":"trace[559827905] range","detail":"{range_begin:/registry/pods/openstack/keepalived-67hl5; range_end:; response_count:1; response_revision:9113; }","duration":"183.737363ms","start":"2026-09-04T16:22:42.925696Z","end":"2026-09-04T16:22:43.109434Z","steps":["trace[559827905] 'agreement among raft nodes before linearized reading' (duration: 183.512778ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:22:43.109471Z","caller":"traceutil/trace.go:171","msg":"trace[1281954503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9113; }","duration":"136.782363ms","start":"2026-09-04T16:22:42.972668Z","end":"2026-09-04T16:22:43.109451Z","steps":["trace[1281954503] 'agreement among raft nodes before linearized reading' (duration: 136.631099ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:23:40.601302Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T16:23:40.608858Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-09-04T16:23:40.608964Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-09-04T16:23:47.316397Z","caller":"traceutil/trace.go:171","msg":"trace[920385178] transaction","detail":"{read_only:false; response_revision:9799; number_of_response:1; }","duration":"127.327997ms","start":"2026-09-04T16:23:47.18904Z","end":"2026-09-04T16:23:47.316368Z","steps":["trace[920385178] 'process raft request' (duration: 121.33911ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:24:07.713923Z","caller":"traceutil/trace.go:171","msg":"trace[893465779] transaction","detail":"{read_only:false; response_revision:9938; number_of_response:1; }","duration":"229.0433ms","start":"2026-09-04T16:24:07.48485Z","end":"2026-09-04T16:24:07.713893Z","steps":["trace[893465779] 'process raft request' (duration: 228.838445ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:24:07.71402Z","caller":"traceutil/trace.go:171","msg":"trace[1491286202] linearizableReadLoop","detail":"{readStateIndex:10293; appliedIndex:10293; }","duration":"197.807878ms","start":"2026-09-04T16:24:07.516173Z","end":"2026-09-04T16:24:07.713981Z","steps":["trace[1491286202] 'read index received' (duration: 197.791528ms)","trace[1491286202] 'applied index is now lower than readState.Index' (duration: 13.56µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:24:07.714471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.256328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:24:07.71456Z","caller":"traceutil/trace.go:171","msg":"trace[1577154042] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:9938; }","duration":"198.435053ms","start":"2026-09-04T16:24:07.516106Z","end":"2026-09-04T16:24:07.714541Z","steps":["trace[1577154042] 'agreement among raft nodes before linearized reading' (duration: 198.000073ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:24:07.731019Z","caller":"traceutil/trace.go:171","msg":"trace[76317160] transaction","detail":"{read_only:false; response_revision:9939; number_of_response:1; }","duration":"167.836228ms","start":"2026-09-04T16:24:07.563147Z","end":"2026-09-04T16:24:07.730983Z","steps":["trace[76317160] 'process raft request' (duration: 167.636163ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:25:15.578868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8012} {"level":"info","ts":"2026-09-04T16:25:15.666568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8012,"took":"85.50905ms","hash":3001145620,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":25518080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T16:25:15.66664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3001145620,"revision":8012,"compact-revision":5615} {"level":"info","ts":"2026-09-04T16:30:15.586348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10411} {"level":"info","ts":"2026-09-04T16:30:15.690439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10411,"took":"101.442489ms","hash":3657977123,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":25800704,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T16:30:15.69053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3657977123,"revision":10411,"compact-revision":8012} {"level":"warn","ts":"2026-09-04T16:30:28.755684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.593264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-04T16:30:28.755856Z","caller":"traceutil/trace.go:171","msg":"trace[1386681992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:12669; }","duration":"156.82093ms","start":"2026-09-04T16:30:28.599008Z","end":"2026-09-04T16:30:28.755829Z","steps":["trace[1386681992] 'range keys from in-memory index tree' (duration: 156.342169ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:30:28.756367Z","caller":"traceutil/trace.go:171","msg":"trace[531171004] transaction","detail":"{read_only:false; response_revision:12670; number_of_response:1; }","duration":"143.490343ms","start":"2026-09-04T16:30:28.612859Z","end":"2026-09-04T16:30:28.756349Z","steps":["trace[531171004] 'process raft request' (duration: 84.670623ms)","trace[531171004] 'compare' (duration: 58.404441ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T16:30:28.756508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.541081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T16:30:28.756602Z","caller":"traceutil/trace.go:171","msg":"trace[1579710305] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:12670; }","duration":"118.706695ms","start":"2026-09-04T16:30:28.637875Z","end":"2026-09-04T16:30:28.756582Z","steps":["trace[1579710305] 'agreement among raft nodes before linearized reading' (duration: 118.545241ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T16:30:28.756146Z","caller":"traceutil/trace.go:171","msg":"trace[1753332694] linearizableReadLoop","detail":"{readStateIndex:13115; appliedIndex:13114; }","duration":"118.175332ms","start":"2026-09-04T16:30:28.637937Z","end":"2026-09-04T16:30:28.756112Z","steps":["trace[1753332694] 'read index received' (duration: 59.513135ms)","trace[1753332694] 'applied index is now lower than readState.Index' (duration: 58.661107ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T16:35:15.592541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12578} {"level":"info","ts":"2026-09-04T16:35:15.688079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12578,"took":"92.788483ms","hash":3414053073,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":28082176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T16:35:15.688143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3414053073,"revision":12578,"compact-revision":10411} {"level":"info","ts":"2026-09-04T16:40:15.599895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14611} {"level":"info","ts":"2026-09-04T16:40:15.683656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14611,"took":"81.071612ms","hash":1679287775,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":23748608,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-09-04T16:40:15.683723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1679287775,"revision":14611,"compact-revision":12578} {"level":"info","ts":"2026-09-04T16:45:15.609353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16186} {"level":"info","ts":"2026-09-04T16:45:15.680698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16186,"took":"69.195899ms","hash":254310379,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20549632,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-04T16:45:15.680778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":254310379,"revision":16186,"compact-revision":14611} {"level":"info","ts":"2026-09-04T16:50:15.619231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17712} {"level":"info","ts":"2026-09-04T16:50:15.693737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17712,"took":"71.497741ms","hash":2038052884,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20516864,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-04T16:50:15.693805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2038052884,"revision":17712,"compact-revision":16186} {"level":"info","ts":"2026-09-04T16:50:27.947257Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T16:50:27.952978Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-09-04T16:50:27.953058Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-09-04T16:55:15.637362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19231} {"level":"info","ts":"2026-09-04T16:55:15.729919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19231,"took":"90.140899ms","hash":3866708470,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20602880,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-04T16:55:15.729992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3866708470,"revision":19231,"compact-revision":17712} {"level":"info","ts":"2026-09-04T16:58:03.876738Z","caller":"traceutil/trace.go:171","msg":"trace[135503691] transaction","detail":"{read_only:false; response_revision:21624; number_of_response:1; }","duration":"145.228533ms","start":"2026-09-04T16:58:03.731478Z","end":"2026-09-04T16:58:03.876706Z","steps":["trace[135503691] 'process raft request' (duration: 140.626189ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T17:00:15.645866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20774} {"level":"info","ts":"2026-09-04T17:00:15.719007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20774,"took":"70.762021ms","hash":3556735292,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20553728,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-04T17:00:15.719085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3556735292,"revision":20774,"compact-revision":19231} {"level":"info","ts":"2026-09-04T17:05:15.652189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22296} {"level":"info","ts":"2026-09-04T17:05:15.722395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22296,"took":"67.51829ms","hash":2109058824,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20733952,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-04T17:05:15.722461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2109058824,"revision":22296,"compact-revision":20774} {"level":"info","ts":"2026-09-04T17:10:15.658607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23848} {"level":"info","ts":"2026-09-04T17:10:15.731268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23848,"took":"70.008389ms","hash":3329991567,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":20914176,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-04T17:10:15.731377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3329991567,"revision":23848,"compact-revision":22296} {"level":"info","ts":"2026-09-04T17:15:15.666398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25402} {"level":"info","ts":"2026-09-04T17:15:15.766844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25402,"took":"97.631661ms","hash":735700098,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":19701760,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-04T17:15:15.766917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":735700098,"revision":25402,"compact-revision":23848} {"level":"info","ts":"2026-09-04T17:20:15.676578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26929} {"level":"info","ts":"2026-09-04T17:20:15.760886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26929,"took":"81.763918ms","hash":1624734361,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":19726336,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-04T17:20:15.760957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1624734361,"revision":26929,"compact-revision":25402} {"level":"info","ts":"2026-09-04T17:21:39.648868Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T17:21:39.658629Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-09-04T17:21:39.658773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-09-04T17:25:15.683198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28454} {"level":"info","ts":"2026-09-04T17:25:15.767209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28454,"took":"81.81915ms","hash":3245556781,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":19218432,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-04T17:25:15.767275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3245556781,"revision":28454,"compact-revision":26929} {"level":"info","ts":"2026-09-04T17:30:15.694919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30002} {"level":"info","ts":"2026-09-04T17:30:15.773146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30002,"took":"76.17148ms","hash":1786637608,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":18624512,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-04T17:30:15.773238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1786637608,"revision":30002,"compact-revision":28454} {"level":"info","ts":"2026-09-04T17:35:15.701235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31530} {"level":"info","ts":"2026-09-04T17:35:15.765106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31530,"took":"61.997009ms","hash":2473116909,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":18866176,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-04T17:35:15.76517Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2473116909,"revision":31530,"compact-revision":30002} {"level":"info","ts":"2026-09-04T17:40:15.710964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33057} {"level":"info","ts":"2026-09-04T17:40:15.771351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33057,"took":"56.833387ms","hash":2449240409,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":18468864,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-04T17:40:15.771419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2449240409,"revision":33057,"compact-revision":31530} {"level":"info","ts":"2026-09-04T17:45:15.718804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34612} {"level":"info","ts":"2026-09-04T17:45:15.779413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34612,"took":"58.818042ms","hash":821838312,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":18092032,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-04T17:45:15.779483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":821838312,"revision":34612,"compact-revision":33057} {"level":"info","ts":"2026-09-04T17:50:15.726538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36133} {"level":"info","ts":"2026-09-04T17:50:15.784103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36133,"took":"55.881055ms","hash":2775635430,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":17920000,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-04T17:50:15.784161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2775635430,"revision":36133,"compact-revision":34612} {"level":"info","ts":"2026-09-04T17:53:03.524371Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"931f6a422b4c426f","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T17:53:03.530469Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-09-04T17:53:03.530605Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-09-04T17:55:15.735036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37653} {"level":"info","ts":"2026-09-04T17:55:15.790427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37653,"took":"53.790228ms","hash":3396470723,"current-db-size-bytes":55001088,"current-db-size":"55 MB","current-db-size-in-use-bytes":17952768,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-04T17:55:15.790507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3396470723,"revision":37653,"compact-revision":36133}