{"level":"warn","ts":"2026-09-07T03:34:05.014134Z","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-07T03:34:05.014244Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10: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-07T03:34:05.014365Z","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-07T03:34:05.014381Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-07T03:34:05.014411Z","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-07T03:34:05.015024Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-09-07T03:34:05.015096Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10: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-07T03:34:05.018044Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.742579ms"} {"level":"info","ts":"2026-09-07T03:34:05.023292Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-09-07T03:34:05.023378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T03:34:05.023397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-09-07T03:34:05.023406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T03:34:05.023412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-09-07T03:34:05.023461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-09-07T03:34:05.028241Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T03:34:05.030204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T03:34:05.032289Z","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-07T03:34:05.035061Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T03:34:05.035227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T03:34:05.035266Z","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-07T03:34:05.035359Z","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-07T03:34:05.035377Z","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-07T03:34:05.037134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-09-07T03:34:05.037636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-09-07T03:34:05.038731Z","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-07T03:34:05.038845Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-07T03:34:05.038916Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-09-07T03:34:05.038955Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T03:34:05.038994Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T03:34:05.824218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T03:34:05.824272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T03:34:05.824293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-09-07T03:34:05.824308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-09-07T03:34:05.824318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-07T03:34:05.82433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-09-07T03:34:05.824358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-09-07T03:34:05.825278Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T03:34:05.82531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T03:34:05.825417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T03:34:05.825458Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:34:05.82568Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T03:34:05.825728Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T03:34:05.826376Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:34:05.826448Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:34:05.826475Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:34:05.827394Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-09-07T03:34:05.827503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T03:34:26.787572Z","caller":"traceutil/trace.go:171","msg":"trace[1526839855] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"231.546272ms","start":"2026-09-07T03:34:26.556Z","end":"2026-09-07T03:34:26.787547Z","steps":["trace[1526839855] 'process raft request' (duration: 231.397338ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:31.999287Z","caller":"traceutil/trace.go:171","msg":"trace[551200514] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"234.260158ms","start":"2026-09-07T03:34:31.764993Z","end":"2026-09-07T03:34:31.999253Z","steps":["trace[551200514] 'read index received' (duration: 234.027093ms)","trace[551200514] 'applied index is now lower than readState.Index' (duration: 231.825µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:34:31.999409Z","caller":"traceutil/trace.go:171","msg":"trace[2079394969] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"255.972415ms","start":"2026-09-07T03:34:31.743391Z","end":"2026-09-07T03:34:31.999364Z","steps":["trace[2079394969] 'process raft request' (duration: 255.717418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:31.999477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.456705ms","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-07T03:34:31.999751Z","caller":"traceutil/trace.go:171","msg":"trace[1896158162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"234.771252ms","start":"2026-09-07T03:34:31.764964Z","end":"2026-09-07T03:34:31.999736Z","steps":["trace[1896158162] 'agreement among raft nodes before linearized reading' (duration: 234.417123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.281437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.106286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:34:44.281704Z","caller":"traceutil/trace.go:171","msg":"trace[1317006468] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"252.169882ms","start":"2026-09-07T03:34:44.029497Z","end":"2026-09-07T03:34:44.281667Z","steps":["trace[1317006468] 'process raft request' (duration: 128.346738ms)","trace[1317006468] 'compare' (duration: 122.807609ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:34:44.281936Z","caller":"traceutil/trace.go:171","msg":"trace[132066967] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"248.134289ms","start":"2026-09-07T03:34:44.033748Z","end":"2026-09-07T03:34:44.281882Z","steps":["trace[132066967] 'process raft request' (duration: 247.7968ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.303085Z","caller":"traceutil/trace.go:171","msg":"trace[537979840] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"255.419283ms","start":"2026-09-07T03:34:44.047647Z","end":"2026-09-07T03:34:44.303066Z","steps":["trace[537979840] 'read index received' (duration: 110.236642ms)","trace[537979840] 'applied index is now lower than readState.Index' (duration: 145.181791ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:34:44.303353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.993755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.303383Z","caller":"traceutil/trace.go:171","msg":"trace[416855376] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:573; }","duration":"209.094637ms","start":"2026-09-07T03:34:44.094281Z","end":"2026-09-07T03:34:44.303375Z","steps":["trace[416855376] 'agreement among raft nodes before linearized reading' (duration: 209.002655ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.303418Z","caller":"traceutil/trace.go:171","msg":"trace[1550132628] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"195.015313ms","start":"2026-09-07T03:34:44.108387Z","end":"2026-09-07T03:34:44.303403Z","steps":["trace[1550132628] 'process raft request' (duration: 193.647258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.303797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.063866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.303826Z","caller":"traceutil/trace.go:171","msg":"trace[398414500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:573; }","duration":"137.117648ms","start":"2026-09-07T03:34:44.166699Z","end":"2026-09-07T03:34:44.303817Z","steps":["trace[398414500] 'agreement among raft nodes before linearized reading' (duration: 137.073897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.303836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.469446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.303864Z","caller":"traceutil/trace.go:171","msg":"trace[1192859689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:573; }","duration":"209.509518ms","start":"2026-09-07T03:34:44.094348Z","end":"2026-09-07T03:34:44.303857Z","steps":["trace[1192859689] 'agreement among raft nodes before linearized reading' (duration: 209.466997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.303869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.474507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.303361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.688559ms","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-07T03:34:44.303927Z","caller":"traceutil/trace.go:171","msg":"trace[2102112006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:573; }","duration":"209.544728ms","start":"2026-09-07T03:34:44.094367Z","end":"2026-09-07T03:34:44.303912Z","steps":["trace[2102112006] 'agreement among raft nodes before linearized reading' (duration: 209.442426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.30394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.781335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.30392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.648352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.30396Z","caller":"traceutil/trace.go:171","msg":"trace[2069640647] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"106.804905ms","start":"2026-09-07T03:34:44.197149Z","end":"2026-09-07T03:34:44.303954Z","steps":["trace[2069640647] 'agreement among raft nodes before linearized reading' (duration: 106.771324ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.30398Z","caller":"traceutil/trace.go:171","msg":"trace[597193671] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"106.757433ms","start":"2026-09-07T03:34:44.197206Z","end":"2026-09-07T03:34:44.303963Z","steps":["trace[597193671] 'agreement among raft nodes before linearized reading' (duration: 106.63201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.304059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.383363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.304097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.683661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.304153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.742981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.304175Z","caller":"traceutil/trace.go:171","msg":"trace[343005682] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:573; }","duration":"123.784942ms","start":"2026-09-07T03:34:44.180381Z","end":"2026-09-07T03:34:44.304166Z","steps":["trace[343005682] 'agreement among raft nodes before linearized reading' (duration: 123.701ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304184Z","caller":"traceutil/trace.go:171","msg":"trace[1906847155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:573; }","duration":"154.781853ms","start":"2026-09-07T03:34:44.149392Z","end":"2026-09-07T03:34:44.304174Z","steps":["trace[1906847155] 'agreement among raft nodes before linearized reading' (duration: 154.747912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.304214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.809026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.304228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.658448ms","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-07T03:34:44.304242Z","caller":"traceutil/trace.go:171","msg":"trace[824483166] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"106.861107ms","start":"2026-09-07T03:34:44.197375Z","end":"2026-09-07T03:34:44.304236Z","steps":["trace[824483166] 'agreement among raft nodes before linearized reading' (duration: 106.817826ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304257Z","caller":"traceutil/trace.go:171","msg":"trace[1789494981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"189.693679ms","start":"2026-09-07T03:34:44.114553Z","end":"2026-09-07T03:34:44.304246Z","steps":["trace[1789494981] 'agreement among raft nodes before linearized reading' (duration: 189.597927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.304246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.471278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.30407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.437405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.30435Z","caller":"traceutil/trace.go:171","msg":"trace[571668885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:573; }","duration":"137.57637ms","start":"2026-09-07T03:34:44.166736Z","end":"2026-09-07T03:34:44.304312Z","steps":["trace[571668885] 'agreement among raft nodes before linearized reading' (duration: 137.429256ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.30437Z","caller":"traceutil/trace.go:171","msg":"trace[770037450] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:573; }","duration":"123.745282ms","start":"2026-09-07T03:34:44.180615Z","end":"2026-09-07T03:34:44.304361Z","steps":["trace[770037450] 'agreement among raft nodes before linearized reading' (duration: 123.436525ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.303942Z","caller":"traceutil/trace.go:171","msg":"trace[1600946507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"256.324014ms","start":"2026-09-07T03:34:44.047611Z","end":"2026-09-07T03:34:44.303935Z","steps":["trace[1600946507] 'agreement among raft nodes before linearized reading' (duration: 255.600226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.304095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.966249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:34:44.304443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.308795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.304095Z","caller":"traceutil/trace.go:171","msg":"trace[730603366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:573; }","duration":"154.443234ms","start":"2026-09-07T03:34:44.149642Z","end":"2026-09-07T03:34:44.304085Z","steps":["trace[730603366] 'agreement among raft nodes before linearized reading' (duration: 154.391723ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304461Z","caller":"traceutil/trace.go:171","msg":"trace[1638661555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:573; }","duration":"173.353379ms","start":"2026-09-07T03:34:44.131099Z","end":"2026-09-07T03:34:44.304452Z","steps":["trace[1638661555] 'agreement among raft nodes before linearized reading' (duration: 172.96238ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304497Z","caller":"traceutil/trace.go:171","msg":"trace[1712811112] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:573; }","duration":"124.394208ms","start":"2026-09-07T03:34:44.180071Z","end":"2026-09-07T03:34:44.304465Z","steps":["trace[1712811112] 'agreement among raft nodes before linearized reading' (duration: 124.310586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:44.304129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.628798ms","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":"warn","ts":"2026-09-07T03:34:44.304131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.01638ms","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":"warn","ts":"2026-09-07T03:34:44.304552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.618679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:34:44.304558Z","caller":"traceutil/trace.go:171","msg":"trace[944192401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:573; }","duration":"155.055719ms","start":"2026-09-07T03:34:44.149494Z","end":"2026-09-07T03:34:44.30455Z","steps":["trace[944192401] 'agreement among raft nodes before linearized reading' (duration: 154.620428ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304562Z","caller":"traceutil/trace.go:171","msg":"trace[1621545801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:573; }","duration":"173.469142ms","start":"2026-09-07T03:34:44.131085Z","end":"2026-09-07T03:34:44.304554Z","steps":["trace[1621545801] 'agreement among raft nodes before linearized reading' (duration: 173.026661ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:44.304576Z","caller":"traceutil/trace.go:171","msg":"trace[654229200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:573; }","duration":"137.656081ms","start":"2026-09-07T03:34:44.166913Z","end":"2026-09-07T03:34:44.304569Z","steps":["trace[654229200] 'agreement among raft nodes before linearized reading' (duration: 137.61643ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:51.136056Z","caller":"traceutil/trace.go:171","msg":"trace[813554064] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"336.640385ms","start":"2026-09-07T03:34:50.799399Z","end":"2026-09-07T03:34:51.136039Z","steps":["trace[813554064] 'process raft request' (duration: 336.524922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:51.136159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:34:50.799378Z","time spent":"336.733147ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:34:51.160759Z","caller":"traceutil/trace.go:171","msg":"trace[1908687658] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"244.522448ms","start":"2026-09-07T03:34:50.916202Z","end":"2026-09-07T03:34:51.160725Z","steps":["trace[1908687658] 'read index received' (duration: 219.757266ms)","trace[1908687658] 'applied index is now lower than readState.Index' (duration: 24.764062ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:34:51.16079Z","caller":"traceutil/trace.go:171","msg":"trace[713666316] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"320.640561ms","start":"2026-09-07T03:34:50.840117Z","end":"2026-09-07T03:34:51.160758Z","steps":["trace[713666316] 'process raft request' (duration: 320.477407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:51.160926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:34:50.8401Z","time spent":"320.764235ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T03:34:51.161018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.795905ms","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-07T03:34:51.161059Z","caller":"traceutil/trace.go:171","msg":"trace[1517881003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"244.895837ms","start":"2026-09-07T03:34:50.916155Z","end":"2026-09-07T03:34:51.161051Z","steps":["trace[1517881003] 'agreement among raft nodes before linearized reading' (duration: 244.704193ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:34:51.787835Z","caller":"traceutil/trace.go:171","msg":"trace[956625053] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"606.093551ms","start":"2026-09-07T03:34:51.181693Z","end":"2026-09-07T03:34:51.787786Z","steps":["trace[956625053] 'process raft request' (duration: 605.235429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:51.788044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:34:51.181681Z","time spent":"606.252495ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T03:34:51.810877Z","caller":"traceutil/trace.go:171","msg":"trace[1574933583] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"216.481253ms","start":"2026-09-07T03:34:51.594371Z","end":"2026-09-07T03:34:51.810853Z","steps":["trace[1574933583] 'read index received' (duration: 192.46278ms)","trace[1574933583] 'applied index is now lower than readState.Index' (duration: 24.017203ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:34:51.811038Z","caller":"traceutil/trace.go:171","msg":"trace[1970541282] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"382.060036ms","start":"2026-09-07T03:34:51.428927Z","end":"2026-09-07T03:34:51.810987Z","steps":["trace[1970541282] 'process raft request' (duration: 381.79928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:34:51.811085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.691629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-07T03:34:51.811387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:34:51.428904Z","time spent":"382.351134ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:34:51.811404Z","caller":"traceutil/trace.go:171","msg":"trace[289766987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"217.047868ms","start":"2026-09-07T03:34:51.594342Z","end":"2026-09-07T03:34:51.81139Z","steps":["trace[289766987] 'agreement among raft nodes before linearized reading' (duration: 216.603566ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:35:08.065363Z","caller":"traceutil/trace.go:171","msg":"trace[1517167493] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"163.05667ms","start":"2026-09-07T03:35:07.902255Z","end":"2026-09-07T03:35:08.065312Z","steps":["trace[1517167493] 'process raft request' (duration: 162.894037ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:35:08.073314Z","caller":"traceutil/trace.go:171","msg":"trace[1372252574] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"164.281401ms","start":"2026-09-07T03:35:07.909001Z","end":"2026-09-07T03:35:08.073282Z","steps":["trace[1372252574] 'process raft request' (duration: 164.066645ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:28.23836Z","caller":"traceutil/trace.go:171","msg":"trace[2005596037] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"124.461623ms","start":"2026-09-07T03:36:28.113814Z","end":"2026-09-07T03:36:28.238276Z","steps":["trace[2005596037] 'process raft request' (duration: 124.256239ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:28.59598Z","caller":"traceutil/trace.go:171","msg":"trace[1049081228] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"206.550311ms","start":"2026-09-07T03:36:28.389397Z","end":"2026-09-07T03:36:28.595947Z","steps":["trace[1049081228] 'process raft request' (duration: 206.263334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:36:28.950385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.915565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:36:28.950529Z","caller":"traceutil/trace.go:171","msg":"trace[268098493] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"400.077533ms","start":"2026-09-07T03:36:28.550409Z","end":"2026-09-07T03:36:28.950486Z","steps":["trace[268098493] 'process raft request' (duration: 253.949603ms)","trace[268098493] 'compare' (duration: 145.813472ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:36:28.950655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.550361Z","time spent":"400.249368ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:36:28.950955Z","caller":"traceutil/trace.go:171","msg":"trace[2015851100] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"350.154316ms","start":"2026-09-07T03:36:28.600788Z","end":"2026-09-07T03:36:28.950942Z","steps":["trace[2015851100] 'read index received' (duration: 203.71285ms)","trace[2015851100] 'applied index is now lower than readState.Index' (duration: 146.439526ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:36:28.950974Z","caller":"traceutil/trace.go:171","msg":"trace[1942806934] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"348.324779ms","start":"2026-09-07T03:36:28.602633Z","end":"2026-09-07T03:36:28.950958Z","steps":["trace[1942806934] 'process raft request' (duration: 348.261258ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:28.951009Z","caller":"traceutil/trace.go:171","msg":"trace[1363199783] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"348.365331ms","start":"2026-09-07T03:36:28.602638Z","end":"2026-09-07T03:36:28.951003Z","steps":["trace[1363199783] 'process raft request' (duration: 348.276078ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:28.950991Z","caller":"traceutil/trace.go:171","msg":"trace[1534480103] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"348.480603ms","start":"2026-09-07T03:36:28.602479Z","end":"2026-09-07T03:36:28.95096Z","steps":["trace[1534480103] 'process raft request' (duration: 348.381881ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:28.950993Z","caller":"traceutil/trace.go:171","msg":"trace[1676760994] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"350.942395ms","start":"2026-09-07T03:36:28.600028Z","end":"2026-09-07T03:36:28.950971Z","steps":["trace[1676760994] 'process raft request' (duration: 350.76148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:36:28.951052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.602621Z","time spent":"348.402932ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:36:28.951046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.602615Z","time spent":"348.390722ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:36:28.95104Z","caller":"traceutil/trace.go:171","msg":"trace[1081203313] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"349.762725ms","start":"2026-09-07T03:36:28.601227Z","end":"2026-09-07T03:36:28.95099Z","steps":["trace[1081203313] 'process raft request' (duration: 349.603451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:36:28.951099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.600004Z","time spent":"351.045219ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:36:28.951103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.602446Z","time spent":"348.598297ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:36:28.951234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.601216Z","time spent":"349.913161ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:36:28.951251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.307286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T03:36:28.951299Z","caller":"traceutil/trace.go:171","msg":"trace[1036125863] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1893; }","duration":"131.393538ms","start":"2026-09-07T03:36:28.81989Z","end":"2026-09-07T03:36:28.951284Z","steps":["trace[1036125863] 'agreement among raft nodes before linearized reading' (duration: 131.322267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:36:28.951355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.571277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e06f477-83ec-4063-bc97-6f99580878ae\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-07T03:36:28.951301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.120392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.10\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-07T03:36:28.951386Z","caller":"traceutil/trace.go:171","msg":"trace[138338411] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e06f477-83ec-4063-bc97-6f99580878ae; range_end:; response_count:1; response_revision:1893; }","duration":"350.630669ms","start":"2026-09-07T03:36:28.600747Z","end":"2026-09-07T03:36:28.951378Z","steps":["trace[138338411] 'agreement among raft nodes before linearized reading' (duration: 350.532736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:36:28.951406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:36:28.600737Z","time spent":"350.663709ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e06f477-83ec-4063-bc97-6f99580878ae\" "} {"level":"info","ts":"2026-09-07T03:36:28.951416Z","caller":"traceutil/trace.go:171","msg":"trace[1066983334] range","detail":"{range_begin:/registry/masterleases/162.253.55.10; range_end:; response_count:1; response_revision:1893; }","duration":"195.281776ms","start":"2026-09-07T03:36:28.756116Z","end":"2026-09-07T03:36:28.951398Z","steps":["trace[1066983334] 'agreement among raft nodes before linearized reading' (duration: 194.987909ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:36:40.206515Z","caller":"traceutil/trace.go:171","msg":"trace[130894475] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"259.971676ms","start":"2026-09-07T03:36:39.946473Z","end":"2026-09-07T03:36:40.206444Z","steps":["trace[130894475] 'process raft request' (duration: 259.808901ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:37:18.020972Z","caller":"traceutil/trace.go:171","msg":"trace[432043238] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"104.112483ms","start":"2026-09-07T03:37:17.91683Z","end":"2026-09-07T03:37:18.020943Z","steps":["trace[432043238] 'process raft request' (duration: 103.931809ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:37:18.683359Z","caller":"traceutil/trace.go:171","msg":"trace[105366855] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"199.157478ms","start":"2026-09-07T03:37:18.484146Z","end":"2026-09-07T03:37:18.683304Z","steps":["trace[105366855] 'process raft request' (duration: 198.979063ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:37:18.683711Z","caller":"traceutil/trace.go:171","msg":"trace[1210471242] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2298; }","duration":"172.820644ms","start":"2026-09-07T03:37:18.510864Z","end":"2026-09-07T03:37:18.683685Z","steps":["trace[1210471242] 'read index received' (duration: 172.210308ms)","trace[1210471242] 'applied index is now lower than readState.Index' (duration: 608.866µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:37:18.683921Z","caller":"traceutil/trace.go:171","msg":"trace[1990433221] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"196.365108ms","start":"2026-09-07T03:37:18.487486Z","end":"2026-09-07T03:37:18.683851Z","steps":["trace[1990433221] 'process raft request' (duration: 195.759162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:37:18.683962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.073231ms","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-07T03:37:18.684115Z","caller":"traceutil/trace.go:171","msg":"trace[1047005629] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2216; }","duration":"173.273525ms","start":"2026-09-07T03:37:18.510822Z","end":"2026-09-07T03:37:18.684096Z","steps":["trace[1047005629] 'agreement among raft nodes before linearized reading' (duration: 172.954087ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:38:11.662668Z","caller":"traceutil/trace.go:171","msg":"trace[1858335465] linearizableReadLoop","detail":"{readStateIndex:2772; appliedIndex:2771; }","duration":"178.877796ms","start":"2026-09-07T03:38:11.483768Z","end":"2026-09-07T03:38:11.662646Z","steps":["trace[1858335465] 'read index received' (duration: 178.651631ms)","trace[1858335465] 'applied index is now lower than readState.Index' (duration: 224.595µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:38:11.662746Z","caller":"traceutil/trace.go:171","msg":"trace[1136095957] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"180.269862ms","start":"2026-09-07T03:38:11.482429Z","end":"2026-09-07T03:38:11.662698Z","steps":["trace[1136095957] 'process raft request' (duration: 180.073448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:38:11.662995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.213276ms","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-07T03:38:11.663051Z","caller":"traceutil/trace.go:171","msg":"trace[502618334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2669; }","duration":"179.307808ms","start":"2026-09-07T03:38:11.483731Z","end":"2026-09-07T03:38:11.663039Z","steps":["trace[502618334] 'agreement among raft nodes before linearized reading' (duration: 179.058661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:38:11.663064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.110027ms","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-07T03:38:11.663175Z","caller":"traceutil/trace.go:171","msg":"trace[897615666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2669; }","duration":"174.266561ms","start":"2026-09-07T03:38:11.488886Z","end":"2026-09-07T03:38:11.663153Z","steps":["trace[897615666] 'agreement among raft nodes before linearized reading' (duration: 173.968914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:38:44.308413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.076082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:38:44.308515Z","caller":"traceutil/trace.go:171","msg":"trace[1950591547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2870; }","duration":"127.254166ms","start":"2026-09-07T03:38:44.181239Z","end":"2026-09-07T03:38:44.308493Z","steps":["trace[1950591547] 'range keys from in-memory index tree' (duration: 126.935468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:38:44.308901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.143541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:38:44.309062Z","caller":"traceutil/trace.go:171","msg":"trace[1758309314] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2870; }","duration":"111.362386ms","start":"2026-09-07T03:38:44.197671Z","end":"2026-09-07T03:38:44.309033Z","steps":["trace[1758309314] 'count revisions from in-memory index tree' (duration: 111.021297ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:38:44.661685Z","caller":"traceutil/trace.go:171","msg":"trace[1192949020] transaction","detail":"{read_only:false; response_revision:2871; number_of_response:1; }","duration":"170.23389ms","start":"2026-09-07T03:38:44.491406Z","end":"2026-09-07T03:38:44.66164Z","steps":["trace[1192949020] 'process raft request' (duration: 169.970003ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:38:44.683253Z","caller":"traceutil/trace.go:171","msg":"trace[470182159] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"189.337381ms","start":"2026-09-07T03:38:44.493882Z","end":"2026-09-07T03:38:44.68322Z","steps":["trace[470182159] 'process raft request' (duration: 189.190668ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:41:44.860459Z","caller":"traceutil/trace.go:171","msg":"trace[345386718] transaction","detail":"{read_only:false; response_revision:3962; number_of_response:1; }","duration":"122.011692ms","start":"2026-09-07T03:41:44.73841Z","end":"2026-09-07T03:41:44.860421Z","steps":["trace[345386718] 'process raft request' (duration: 84.34333ms)","trace[345386718] 'compare' (duration: 37.396585ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:41:44.860744Z","caller":"traceutil/trace.go:171","msg":"trace[1563233326] transaction","detail":"{read_only:false; response_revision:3963; number_of_response:1; }","duration":"116.827661ms","start":"2026-09-07T03:41:44.743876Z","end":"2026-09-07T03:41:44.860704Z","steps":["trace[1563233326] 'process raft request' (duration: 116.474963ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:41:44.861008Z","caller":"traceutil/trace.go:171","msg":"trace[378065788] linearizableReadLoop","detail":"{readStateIndex:4117; appliedIndex:4114; }","duration":"105.465364ms","start":"2026-09-07T03:41:44.75552Z","end":"2026-09-07T03:41:44.860986Z","steps":["trace[378065788] 'read index received' (duration: 67.226658ms)","trace[378065788] 'applied index is now lower than readState.Index' (duration: 38.236006ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:41:44.861007Z","caller":"traceutil/trace.go:171","msg":"trace[1549345637] transaction","detail":"{read_only:false; response_revision:3964; number_of_response:1; }","duration":"116.602706ms","start":"2026-09-07T03:41:44.744376Z","end":"2026-09-07T03:41:44.860979Z","steps":["trace[1549345637] 'process raft request' (duration: 116.241297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:41:44.861229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.673899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:41:44.861362Z","caller":"traceutil/trace.go:171","msg":"trace[1804171428] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:3965; }","duration":"105.848884ms","start":"2026-09-07T03:41:44.755488Z","end":"2026-09-07T03:41:44.861318Z","steps":["trace[1804171428] 'agreement among raft nodes before linearized reading' (duration: 105.544396ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:43:47.398084Z","caller":"traceutil/trace.go:171","msg":"trace[900433371] linearizableReadLoop","detail":"{readStateIndex:5400; appliedIndex:5399; }","duration":"100.080328ms","start":"2026-09-07T03:43:47.297975Z","end":"2026-09-07T03:43:47.398055Z","steps":["trace[900433371] 'read index received' (duration: 100.053198ms)","trace[900433371] 'applied index is now lower than readState.Index' (duration: 25.86µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:43:47.398294Z","caller":"traceutil/trace.go:171","msg":"trace[1736055039] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"125.925641ms","start":"2026-09-07T03:43:47.272305Z","end":"2026-09-07T03:43:47.398231Z","steps":["trace[1736055039] 'process raft request' (duration: 125.535582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:43:47.398754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.755435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526833"} {"level":"info","ts":"2026-09-07T03:43:47.398824Z","caller":"traceutil/trace.go:171","msg":"trace[418573308] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5203; }","duration":"100.879498ms","start":"2026-09-07T03:43:47.297929Z","end":"2026-09-07T03:43:47.398808Z","steps":["trace[418573308] 'agreement among raft nodes before linearized reading' (duration: 100.238572ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:43:52.771705Z","caller":"traceutil/trace.go:171","msg":"trace[610171730] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"170.053456ms","start":"2026-09-07T03:43:52.601612Z","end":"2026-09-07T03:43:52.771666Z","steps":["trace[610171730] 'process raft request' (duration: 169.767268ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:43:52.921305Z","caller":"traceutil/trace.go:171","msg":"trace[2070043255] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"142.009497ms","start":"2026-09-07T03:43:52.779265Z","end":"2026-09-07T03:43:52.921275Z","steps":["trace[2070043255] 'process raft request' (duration: 141.791922ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:43:52.980851Z","caller":"traceutil/trace.go:171","msg":"trace[1091155090] linearizableReadLoop","detail":"{readStateIndex:5634; appliedIndex:5632; }","duration":"181.386973ms","start":"2026-09-07T03:43:52.799443Z","end":"2026-09-07T03:43:52.98083Z","steps":["trace[1091155090] 'read index received' (duration: 121.558302ms)","trace[1091155090] 'applied index is now lower than readState.Index' (duration: 59.828061ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:43:52.98092Z","caller":"traceutil/trace.go:171","msg":"trace[701119509] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"199.627703ms","start":"2026-09-07T03:43:52.781246Z","end":"2026-09-07T03:43:52.980874Z","steps":["trace[701119509] 'process raft request' (duration: 199.469019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:43:52.981016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.550397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c2f1608-8450-47ea-81e4-89b3bf3ddb1d\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-09-07T03:43:52.981048Z","caller":"traceutil/trace.go:171","msg":"trace[1956563563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c2f1608-8450-47ea-81e4-89b3bf3ddb1d; range_end:; response_count:1; response_revision:5430; }","duration":"181.642639ms","start":"2026-09-07T03:43:52.799396Z","end":"2026-09-07T03:43:52.981039Z","steps":["trace[1956563563] 'agreement among raft nodes before linearized reading' (duration: 181.519686ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:05.290578Z","caller":"traceutil/trace.go:171","msg":"trace[210838656] linearizableReadLoop","detail":"{readStateIndex:5873; appliedIndex:5872; }","duration":"112.770029ms","start":"2026-09-07T03:44:05.17779Z","end":"2026-09-07T03:44:05.29056Z","steps":["trace[210838656] 'read index received' (duration: 112.533323ms)","trace[210838656] 'applied index is now lower than readState.Index' (duration: 235.936µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:44:05.290677Z","caller":"traceutil/trace.go:171","msg":"trace[1633196499] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"172.441456ms","start":"2026-09-07T03:44:05.118176Z","end":"2026-09-07T03:44:05.290617Z","steps":["trace[1633196499] 'process raft request' (duration: 172.19739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:05.290757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.825467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T03:44:05.290807Z","caller":"traceutil/trace.go:171","msg":"trace[209033215] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5660; }","duration":"100.925019ms","start":"2026-09-07T03:44:05.18987Z","end":"2026-09-07T03:44:05.290795Z","steps":["trace[209033215] 'agreement among raft nodes before linearized reading' (duration: 100.821417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:05.290722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.916343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:44:05.291187Z","caller":"traceutil/trace.go:171","msg":"trace[69822517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5660; }","duration":"113.315253ms","start":"2026-09-07T03:44:05.177748Z","end":"2026-09-07T03:44:05.291063Z","steps":["trace[69822517] 'agreement among raft nodes before linearized reading' (duration: 112.907562ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:05.861653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2987} {"level":"info","ts":"2026-09-07T03:44:05.947667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2987,"took":"84.953586ms","hash":1116322785,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-07T03:44:05.947737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1116322785,"revision":2987,"compact-revision":-1} {"level":"info","ts":"2026-09-07T03:44:11.862303Z","caller":"traceutil/trace.go:171","msg":"trace[1424272136] linearizableReadLoop","detail":"{readStateIndex:5989; appliedIndex:5988; }","duration":"110.078451ms","start":"2026-09-07T03:44:11.752205Z","end":"2026-09-07T03:44:11.862284Z","steps":["trace[1424272136] 'read index received' (duration: 109.941558ms)","trace[1424272136] 'applied index is now lower than readState.Index' (duration: 135.883µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:44:11.862387Z","caller":"traceutil/trace.go:171","msg":"trace[908158867] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"177.131774ms","start":"2026-09-07T03:44:11.685226Z","end":"2026-09-07T03:44:11.862358Z","steps":["trace[908158867] 'process raft request' (duration: 176.932089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:11.862509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.295277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T03:44:11.862541Z","caller":"traceutil/trace.go:171","msg":"trace[563000486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5774; }","duration":"110.376329ms","start":"2026-09-07T03:44:11.752155Z","end":"2026-09-07T03:44:11.862531Z","steps":["trace[563000486] 'agreement among raft nodes before linearized reading' (duration: 110.253415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:11.862683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.46287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:44:11.862782Z","caller":"traceutil/trace.go:171","msg":"trace[1326751288] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:5774; }","duration":"104.601172ms","start":"2026-09-07T03:44:11.758157Z","end":"2026-09-07T03:44:11.862758Z","steps":["trace[1326751288] 'agreement among raft nodes before linearized reading' (duration: 104.363307ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:15.090205Z","caller":"traceutil/trace.go:171","msg":"trace[1150554829] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"156.130784ms","start":"2026-09-07T03:44:14.93405Z","end":"2026-09-07T03:44:15.09018Z","steps":["trace[1150554829] 'process raft request' (duration: 155.950819ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:15.264813Z","caller":"traceutil/trace.go:171","msg":"trace[981696536] linearizableReadLoop","detail":"{readStateIndex:6110; appliedIndex:6108; }","duration":"292.12468ms","start":"2026-09-07T03:44:14.972668Z","end":"2026-09-07T03:44:15.264793Z","steps":["trace[981696536] 'read index received' (duration: 117.449237ms)","trace[981696536] 'applied index is now lower than readState.Index' (duration: 174.674383ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:44:15.264889Z","caller":"traceutil/trace.go:171","msg":"trace[397206424] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"329.680518ms","start":"2026-09-07T03:44:14.935175Z","end":"2026-09-07T03:44:15.264856Z","steps":["trace[397206424] 'process raft request' (duration: 246.154018ms)","trace[397206424] 'compare' (duration: 83.354116ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:44:15.264998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.200279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-wfr2r\" ","response":"range_response_count:1 size:8470"} {"level":"warn","ts":"2026-09-07T03:44:15.265013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.338095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-09-07T03:44:15.265032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:44:14.935155Z","time spent":"329.801801ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:44:15.265067Z","caller":"traceutil/trace.go:171","msg":"trace[1832555884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70; range_end:; response_count:1; response_revision:5892; }","duration":"292.424547ms","start":"2026-09-07T03:44:14.972626Z","end":"2026-09-07T03:44:15.26505Z","steps":["trace[1832555884] 'agreement among raft nodes before linearized reading' (duration: 292.259833ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:15.265031Z","caller":"traceutil/trace.go:171","msg":"trace[166683902] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-wfr2r; range_end:; response_count:1; response_revision:5892; }","duration":"170.273211ms","start":"2026-09-07T03:44:15.094747Z","end":"2026-09-07T03:44:15.26502Z","steps":["trace[166683902] 'agreement among raft nodes before linearized reading' (duration: 170.181479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:15.265849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.171723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-07T03:44:15.265886Z","caller":"traceutil/trace.go:171","msg":"trace[1981878027] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5892; }","duration":"112.247925ms","start":"2026-09-07T03:44:15.15363Z","end":"2026-09-07T03:44:15.265878Z","steps":["trace[1981878027] 'agreement among raft nodes before linearized reading' (duration: 111.345252ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:15.600455Z","caller":"traceutil/trace.go:171","msg":"trace[223667839] linearizableReadLoop","detail":"{readStateIndex:6111; appliedIndex:6110; }","duration":"301.65523ms","start":"2026-09-07T03:44:15.298763Z","end":"2026-09-07T03:44:15.600418Z","steps":["trace[223667839] 'read index received' (duration: 301.491286ms)","trace[223667839] 'applied index is now lower than readState.Index' (duration: 163.034µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:44:15.600522Z","caller":"traceutil/trace.go:171","msg":"trace[2054106151] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"327.77521ms","start":"2026-09-07T03:44:15.272707Z","end":"2026-09-07T03:44:15.600482Z","steps":["trace[2054106151] 'process raft request' (duration: 327.545565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:15.600601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.811713ms","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":"info","ts":"2026-09-07T03:44:15.60064Z","caller":"traceutil/trace.go:171","msg":"trace[100314610] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5893; }","duration":"301.901777ms","start":"2026-09-07T03:44:15.298728Z","end":"2026-09-07T03:44:15.60063Z","steps":["trace[100314610] 'agreement among raft nodes before linearized reading' (duration: 301.813954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:15.600679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:44:15.29871Z","time spent":"301.959318ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-07T03:44:15.600686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:44:15.272689Z","time spent":"327.914164ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:44:15.704011Z","caller":"traceutil/trace.go:171","msg":"trace[510741582] linearizableReadLoop","detail":"{readStateIndex:6112; appliedIndex:6111; }","duration":"103.474704ms","start":"2026-09-07T03:44:15.600514Z","end":"2026-09-07T03:44:15.703989Z","steps":["trace[510741582] 'read index received' (duration: 103.273019ms)","trace[510741582] 'applied index is now lower than readState.Index' (duration: 200.895µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:44:15.704241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.439707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-07T03:44:15.704295Z","caller":"traceutil/trace.go:171","msg":"trace[663175624] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5894; }","duration":"404.521389ms","start":"2026-09-07T03:44:15.29976Z","end":"2026-09-07T03:44:15.704281Z","steps":["trace[663175624] 'agreement among raft nodes before linearized reading' (duration: 404.342854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:44:15.70433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:44:15.299754Z","time spent":"404.56799ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-09-07T03:44:15.704366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.492039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-07T03:44:15.704447Z","caller":"traceutil/trace.go:171","msg":"trace[2118949648] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70; range_end:; response_count:1; response_revision:5894; }","duration":"100.618362ms","start":"2026-09-07T03:44:15.60381Z","end":"2026-09-07T03:44:15.704428Z","steps":["trace[2118949648] 'agreement among raft nodes before linearized reading' (duration: 100.352194ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:44:15.704172Z","caller":"traceutil/trace.go:171","msg":"trace[1003886623] transaction","detail":"{read_only:false; response_revision:5894; number_of_response:1; }","duration":"189.761423ms","start":"2026-09-07T03:44:15.514331Z","end":"2026-09-07T03:44:15.704092Z","steps":["trace[1003886623] 'process raft request' (duration: 189.519708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:24.653234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.633082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-07T03:45:24.653536Z","caller":"traceutil/trace.go:171","msg":"trace[96226744] transaction","detail":"{read_only:false; response_revision:6467; number_of_response:1; }","duration":"597.216275ms","start":"2026-09-07T03:45:24.056306Z","end":"2026-09-07T03:45:24.653523Z","steps":["trace[96226744] 'process raft request' (duration: 597.113183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:24.653758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.056288Z","time spent":"597.416752ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:24.735629Z","caller":"traceutil/trace.go:171","msg":"trace[1051655840] linearizableReadLoop","detail":"{readStateIndex:6702; appliedIndex:6699; }","duration":"557.577335ms","start":"2026-09-07T03:45:24.178029Z","end":"2026-09-07T03:45:24.735607Z","steps":["trace[1051655840] 'read index received' (duration: 197.91995ms)","trace[1051655840] 'applied index is now lower than readState.Index' (duration: 359.656165ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:45:24.735792Z","caller":"traceutil/trace.go:171","msg":"trace[853357108] transaction","detail":"{read_only:false; response_revision:6468; number_of_response:1; }","duration":"624.492145ms","start":"2026-09-07T03:45:24.111236Z","end":"2026-09-07T03:45:24.735729Z","steps":["trace[853357108] 'process raft request' (duration: 624.238789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:24.735928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.855053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:45:24.736045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.111216Z","time spent":"624.709251ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:24.736055Z","caller":"traceutil/trace.go:171","msg":"trace[759331316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6468; }","duration":"558.033337ms","start":"2026-09-07T03:45:24.177988Z","end":"2026-09-07T03:45:24.736021Z","steps":["trace[759331316] 'agreement among raft nodes before linearized reading' (duration: 557.757871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:24.736109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.177968Z","time spent":"558.13269ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T03:45:25.236205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780508064876337406,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-07T03:45:25.236847Z","caller":"traceutil/trace.go:171","msg":"trace[697154459] linearizableReadLoop","detail":"{readStateIndex:6704; appliedIndex:6702; }","duration":"501.12903ms","start":"2026-09-07T03:45:24.735689Z","end":"2026-09-07T03:45:25.236818Z","steps":["trace[697154459] 'read index received' (duration: 500.750631ms)","trace[697154459] 'applied index is now lower than readState.Index' (duration: 377.519µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:45:25.23733Z","caller":"traceutil/trace.go:171","msg":"trace[1201703192] transaction","detail":"{read_only:false; response_revision:6469; number_of_response:1; }","duration":"678.685615ms","start":"2026-09-07T03:45:24.558625Z","end":"2026-09-07T03:45:25.23731Z","steps":["trace[1201703192] 'process raft request' (duration: 677.907645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.237536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.558606Z","time spent":"678.807888ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:25.23781Z","caller":"traceutil/trace.go:171","msg":"trace[1642892517] transaction","detail":"{read_only:false; response_revision:6470; number_of_response:1; }","duration":"510.964747ms","start":"2026-09-07T03:45:24.72683Z","end":"2026-09-07T03:45:25.237795Z","steps":["trace[1642892517] 'process raft request' (duration: 509.922651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.237882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.726811Z","time spent":"511.028829ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:45:25.238168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.237952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-07T03:45:25.238216Z","caller":"traceutil/trace.go:171","msg":"trace[1080643206] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6470; }","duration":"556.310233ms","start":"2026-09-07T03:45:24.681887Z","end":"2026-09-07T03:45:25.238198Z","steps":["trace[1080643206] 'agreement among raft nodes before linearized reading' (duration: 556.134779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.238254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.681871Z","time spent":"556.373895ms","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-07T03:45:25.238566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.244084ms","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-07T03:45:25.238604Z","caller":"traceutil/trace.go:171","msg":"trace[2051955576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6470; }","duration":"872.316246ms","start":"2026-09-07T03:45:24.366275Z","end":"2026-09-07T03:45:25.238591Z","steps":["trace[2051955576] 'agreement among raft nodes before linearized reading' (duration: 872.202963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.238638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.366245Z","time spent":"872.385018ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T03:45:25.238861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.642138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T03:45:25.238893Z","caller":"traceutil/trace.go:171","msg":"trace[42335466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6470; }","duration":"924.7166ms","start":"2026-09-07T03:45:24.314166Z","end":"2026-09-07T03:45:25.238883Z","steps":["trace[42335466] 'agreement among raft nodes before linearized reading' (duration: 924.614987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.238925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.314144Z","time spent":"924.77278ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T03:45:25.239425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.631654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:45:25.239477Z","caller":"traceutil/trace.go:171","msg":"trace[262465124] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:6470; }","duration":"948.697936ms","start":"2026-09-07T03:45:24.290763Z","end":"2026-09-07T03:45:25.239461Z","steps":["trace[262465124] 'agreement among raft nodes before linearized reading' (duration: 948.514261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.239511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.290757Z","time spent":"948.745047ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vrwlm\" "} {"level":"warn","ts":"2026-09-07T03:45:25.239512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.245831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:45:25.239623Z","caller":"traceutil/trace.go:171","msg":"trace[1996220030] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:6470; }","duration":"972.403124ms","start":"2026-09-07T03:45:24.267197Z","end":"2026-09-07T03:45:25.2396Z","steps":["trace[1996220030] 'agreement among raft nodes before linearized reading' (duration: 972.135738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.239676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.267191Z","time spent":"972.474576ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-09-07T03:45:25.239907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.966789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:45:25.239987Z","caller":"traceutil/trace.go:171","msg":"trace[1840612975] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6470; }","duration":"973.089903ms","start":"2026-09-07T03:45:24.266877Z","end":"2026-09-07T03:45:25.239967Z","steps":["trace[1840612975] 'agreement among raft nodes before linearized reading' (duration: 972.928488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.240041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.26686Z","time spent":"973.172494ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-09-07T03:45:25.240396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.833215ms","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-07T03:45:25.240435Z","caller":"traceutil/trace.go:171","msg":"trace[2017582300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6470; }","duration":"951.897727ms","start":"2026-09-07T03:45:24.288528Z","end":"2026-09-07T03:45:25.240426Z","steps":["trace[2017582300] 'agreement among raft nodes before linearized reading' (duration: 951.730283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.240458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.288512Z","time spent":"951.940229ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T03:45:25.977618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.193686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:45:25.977966Z","caller":"traceutil/trace.go:171","msg":"trace[456683186] transaction","detail":"{read_only:false; response_revision:6474; number_of_response:1; }","duration":"728.458922ms","start":"2026-09-07T03:45:25.249484Z","end":"2026-09-07T03:45:25.977943Z","steps":["trace[456683186] 'process raft request' (duration: 728.404991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.978062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.249467Z","time spent":"728.550435ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:25.978057Z","caller":"traceutil/trace.go:171","msg":"trace[1535507068] transaction","detail":"{read_only:false; response_revision:6472; number_of_response:1; }","duration":"728.756039ms","start":"2026-09-07T03:45:25.249269Z","end":"2026-09-07T03:45:25.978025Z","steps":["trace[1535507068] 'process raft request' (duration: 728.502463ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:45:25.978104Z","caller":"traceutil/trace.go:171","msg":"trace[1798081152] linearizableReadLoop","detail":"{readStateIndex:6705; appliedIndex:6704; }","duration":"741.122722ms","start":"2026-09-07T03:45:25.236902Z","end":"2026-09-07T03:45:25.978025Z","steps":["trace[1798081152] 'read index received' (duration: 50.402083ms)","trace[1798081152] 'applied index is now lower than readState.Index' (duration: 690.709079ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:45:25.97825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.249235Z","time spent":"728.920483ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:25.978312Z","caller":"traceutil/trace.go:171","msg":"trace[1717281649] transaction","detail":"{read_only:false; response_revision:6473; number_of_response:1; }","duration":"728.857701ms","start":"2026-09-07T03:45:25.249441Z","end":"2026-09-07T03:45:25.978299Z","steps":["trace[1717281649] 'process raft request' (duration: 728.3925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.9784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.249407Z","time spent":"728.959025ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:45:25.978416Z","caller":"traceutil/trace.go:171","msg":"trace[1775512471] transaction","detail":"{read_only:false; response_revision:6471; number_of_response:1; }","duration":"1.22158166s","start":"2026-09-07T03:45:24.756821Z","end":"2026-09-07T03:45:25.978402Z","steps":["trace[1775512471] 'process raft request' (duration: 530.483791ms)","trace[1775512471] 'compare' (duration: 690.058942ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:45:25.978467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.7568Z","time spent":"1.221636001s","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:45:25.97848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.553432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-07T03:45:25.978496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.042204ms","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":"warn","ts":"2026-09-07T03:45:25.978545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117621603s","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-07T03:45:25.978557Z","caller":"traceutil/trace.go:171","msg":"trace[1477850457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6474; }","duration":"851.664795ms","start":"2026-09-07T03:45:25.126871Z","end":"2026-09-07T03:45:25.978536Z","steps":["trace[1477850457] 'agreement among raft nodes before linearized reading' (duration: 851.391688ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:45:25.978563Z","caller":"traceutil/trace.go:171","msg":"trace[1713282430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:6474; }","duration":"677.220028ms","start":"2026-09-07T03:45:25.301322Z","end":"2026-09-07T03:45:25.978542Z","steps":["trace[1713282430] 'agreement among raft nodes before linearized reading' (duration: 677.084555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.97855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.578817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-19f944da-8845-4e27-8924-df9bf626eaab\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-09-07T03:45:25.978591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.126855Z","time spent":"851.730156ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T03:45:25.978599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.301306Z","time spent":"677.282829ms","remote":"127.0.0.1:58530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-09-07T03:45:25.978583Z","caller":"traceutil/trace.go:171","msg":"trace[693924800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6474; }","duration":"1.117711355s","start":"2026-09-07T03:45:24.860862Z","end":"2026-09-07T03:45:25.978574Z","steps":["trace[693924800] 'agreement among raft nodes before linearized reading' (duration: 1.117452578s)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:45:25.97864Z","caller":"traceutil/trace.go:171","msg":"trace[1899051771] range","detail":"{range_begin:/registry/persistentvolumes/pvc-19f944da-8845-4e27-8924-df9bf626eaab; range_end:; response_count:1; response_revision:6474; }","duration":"732.715169ms","start":"2026-09-07T03:45:25.245906Z","end":"2026-09-07T03:45:25.978621Z","steps":["trace[1899051771] 'agreement among raft nodes before linearized reading' (duration: 732.516454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.978651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.860837Z","time spent":"1.117806159s","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T03:45:25.978664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241251696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:45:25.978683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.245886Z","time spent":"732.772781ms","remote":"127.0.0.1:48254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1457,"request content":"key:\"/registry/persistentvolumes/pvc-19f944da-8845-4e27-8924-df9bf626eaab\" "} {"level":"info","ts":"2026-09-07T03:45:25.979787Z","caller":"traceutil/trace.go:171","msg":"trace[1336590800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6474; }","duration":"1.242305263s","start":"2026-09-07T03:45:24.737204Z","end":"2026-09-07T03:45:25.979509Z","steps":["trace[1336590800] 'agreement among raft nodes before linearized reading' (duration: 1.241165294s)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.979837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:24.737184Z","time spent":"1.242642641s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T03:45:25.978631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.971035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T03:45:25.980261Z","caller":"traceutil/trace.go:171","msg":"trace[2116000972] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:6474; }","duration":"232.646068ms","start":"2026-09-07T03:45:25.747587Z","end":"2026-09-07T03:45:25.980233Z","steps":["trace[2116000972] 'agreement among raft nodes before linearized reading' (duration: 230.834072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.980769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.858913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:12 size:109128"} {"level":"info","ts":"2026-09-07T03:45:25.981142Z","caller":"traceutil/trace.go:171","msg":"trace[1757507790] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:12; response_revision:6474; }","duration":"732.942375ms","start":"2026-09-07T03:45:25.247869Z","end":"2026-09-07T03:45:25.980812Z","steps":["trace[1757507790] 'agreement among raft nodes before linearized reading' (duration: 730.565336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:25.981393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.247854Z","time spent":"733.331606ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":12,"response size":109150,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-07T03:45:26.221915Z","caller":"traceutil/trace.go:171","msg":"trace[601973130] transaction","detail":"{read_only:false; response_revision:6475; number_of_response:1; }","duration":"235.203252ms","start":"2026-09-07T03:45:25.986688Z","end":"2026-09-07T03:45:26.221891Z","steps":["trace[601973130] 'process raft request' (duration: 228.261877ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:45:26.366101Z","caller":"traceutil/trace.go:171","msg":"trace[896949] linearizableReadLoop","detail":"{readStateIndex:6710; appliedIndex:6708; }","duration":"376.278526ms","start":"2026-09-07T03:45:25.989801Z","end":"2026-09-07T03:45:26.36608Z","steps":["trace[896949] 'read index received' (duration: 225.163008ms)","trace[896949] 'applied index is now lower than readState.Index' (duration: 151.114568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:45:26.366222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.409639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:45:26.366248Z","caller":"traceutil/trace.go:171","msg":"trace[1165325324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6476; }","duration":"376.497151ms","start":"2026-09-07T03:45:25.989741Z","end":"2026-09-07T03:45:26.366239Z","steps":["trace[1165325324] 'agreement among raft nodes before linearized reading' (duration: 376.414999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:45:26.366277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.989717Z","time spent":"376.553533ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T03:45:26.366248Z","caller":"traceutil/trace.go:171","msg":"trace[1885554365] transaction","detail":"{read_only:false; response_revision:6476; number_of_response:1; }","duration":"377.497916ms","start":"2026-09-07T03:45:25.988701Z","end":"2026-09-07T03:45:26.366199Z","steps":["trace[1885554365] 'process raft request' (duration: 348.471813ms)","trace[1885554365] 'compare' (duration: 28.792107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:45:26.367569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:45:25.988677Z","time spent":"378.745928ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:49:05.869437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5678} {"level":"info","ts":"2026-09-07T03:49:05.95122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5678,"took":"80.357887ms","hash":522495079,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":28868608,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-07T03:49:05.951282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522495079,"revision":5678,"compact-revision":2987} {"level":"info","ts":"2026-09-07T03:50:53.992615Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b9.wal"} {"level":"info","ts":"2026-09-07T03:50:58.308981Z","caller":"traceutil/trace.go:171","msg":"trace[326698721] linearizableReadLoop","detail":"{readStateIndex:9169; appliedIndex:9168; }","duration":"212.163337ms","start":"2026-09-07T03:50:58.09679Z","end":"2026-09-07T03:50:58.308954Z","steps":["trace[326698721] 'read index received' (duration: 211.833629ms)","trace[326698721] 'applied index is now lower than readState.Index' (duration: 328.628µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:50:58.309108Z","caller":"traceutil/trace.go:171","msg":"trace[1823785553] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"215.248596ms","start":"2026-09-07T03:50:58.093773Z","end":"2026-09-07T03:50:58.309022Z","steps":["trace[1823785553] 'process raft request' (duration: 214.98904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:50:58.309312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.502795ms","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-07T03:50:58.309374Z","caller":"traceutil/trace.go:171","msg":"trace[957036380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8856; }","duration":"212.625548ms","start":"2026-09-07T03:50:58.096732Z","end":"2026-09-07T03:50:58.309358Z","steps":["trace[957036380] 'agreement among raft nodes before linearized reading' (duration: 212.403043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:50:58.30942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.099837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:50:58.309507Z","caller":"traceutil/trace.go:171","msg":"trace[1548607900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8856; }","duration":"130.226181ms","start":"2026-09-07T03:50:58.179265Z","end":"2026-09-07T03:50:58.309491Z","steps":["trace[1548607900] 'agreement among raft nodes before linearized reading' (duration: 130.088616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:50:58.309521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.266235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:50:58.309577Z","caller":"traceutil/trace.go:171","msg":"trace[457848139] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:8856; }","duration":"110.347117ms","start":"2026-09-07T03:50:58.199217Z","end":"2026-09-07T03:50:58.309565Z","steps":["trace[457848139] 'agreement among raft nodes before linearized reading' (duration: 110.164352ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:50:58.53886Z","caller":"traceutil/trace.go:171","msg":"trace[1837000298] linearizableReadLoop","detail":"{readStateIndex:9170; appliedIndex:9169; }","duration":"196.420698ms","start":"2026-09-07T03:50:58.342388Z","end":"2026-09-07T03:50:58.538809Z","steps":["trace[1837000298] 'read index received' (duration: 149.467808ms)","trace[1837000298] 'applied index is now lower than readState.Index' (duration: 46.95077ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:50:58.538953Z","caller":"traceutil/trace.go:171","msg":"trace[286197288] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"223.567686ms","start":"2026-09-07T03:50:58.315324Z","end":"2026-09-07T03:50:58.538892Z","steps":["trace[286197288] 'process raft request' (duration: 176.539394ms)","trace[286197288] 'compare' (duration: 46.739035ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:50:58.539304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.87016ms","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-07T03:50:58.539385Z","caller":"traceutil/trace.go:171","msg":"trace[1212182940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8857; }","duration":"197.030413ms","start":"2026-09-07T03:50:58.342334Z","end":"2026-09-07T03:50:58.539364Z","steps":["trace[1212182940] 'agreement among raft nodes before linearized reading' (duration: 196.661183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:10.716507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.452042ms","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-07T03:51:10.716631Z","caller":"traceutil/trace.go:171","msg":"trace[61307863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8921; }","duration":"118.639686ms","start":"2026-09-07T03:51:10.597961Z","end":"2026-09-07T03:51:10.7166Z","steps":["trace[61307863] 'range keys from in-memory index tree' (duration: 118.196486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:10.716921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.36437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224108"} {"level":"info","ts":"2026-09-07T03:51:10.716975Z","caller":"traceutil/trace.go:171","msg":"trace[491339276] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8921; }","duration":"277.443082ms","start":"2026-09-07T03:51:10.439518Z","end":"2026-09-07T03:51:10.716961Z","steps":["trace[491339276] 'range keys from in-memory index tree' (duration: 276.954319ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:10.717606Z","caller":"traceutil/trace.go:171","msg":"trace[1254291345] transaction","detail":"{read_only:false; response_revision:8922; number_of_response:1; }","duration":"101.213645ms","start":"2026-09-07T03:51:10.616361Z","end":"2026-09-07T03:51:10.717574Z","steps":["trace[1254291345] 'process raft request' (duration: 89.097238ms)","trace[1254291345] 'compare' (duration: 10.538777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:51:37.208924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.065759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:51:37.20902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.158306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-09-07T03:51:37.2091Z","caller":"traceutil/trace.go:171","msg":"trace[1892870817] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:9118; }","duration":"121.288954ms","start":"2026-09-07T03:51:37.087752Z","end":"2026-09-07T03:51:37.209041Z","steps":["trace[1892870817] 'count revisions from in-memory index tree' (duration: 120.995177ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:37.209178Z","caller":"traceutil/trace.go:171","msg":"trace[626750713] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:9118; }","duration":"122.379961ms","start":"2026-09-07T03:51:37.086766Z","end":"2026-09-07T03:51:37.209146Z","steps":["trace[626750713] 'range keys from in-memory index tree' (duration: 121.867989ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:41.380362Z","caller":"traceutil/trace.go:171","msg":"trace[1430969077] transaction","detail":"{read_only:false; response_revision:9136; number_of_response:1; }","duration":"527.755765ms","start":"2026-09-07T03:51:40.852569Z","end":"2026-09-07T03:51:41.380325Z","steps":["trace[1430969077] 'process raft request' (duration: 527.481858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.380636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:40.852533Z","time spent":"527.96529ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:51:41.441538Z","caller":"traceutil/trace.go:171","msg":"trace[1623005011] linearizableReadLoop","detail":"{readStateIndex:9462; appliedIndex:9460; }","duration":"544.558172ms","start":"2026-09-07T03:51:40.896954Z","end":"2026-09-07T03:51:41.441512Z","steps":["trace[1623005011] 'read index received' (duration: 483.149835ms)","trace[1623005011] 'applied index is now lower than readState.Index' (duration: 61.406317ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:51:41.441647Z","caller":"traceutil/trace.go:171","msg":"trace[606667945] transaction","detail":"{read_only:false; response_revision:9137; number_of_response:1; }","duration":"588.304539ms","start":"2026-09-07T03:51:40.853269Z","end":"2026-09-07T03:51:41.441573Z","steps":["trace[606667945] 'process raft request' (duration: 588.029772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.441936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:40.853254Z","time spent":"588.530926ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:51:41.441966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.142005ms","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-07T03:51:41.442052Z","caller":"traceutil/trace.go:171","msg":"trace[109269837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9137; }","duration":"528.283079ms","start":"2026-09-07T03:51:40.913723Z","end":"2026-09-07T03:51:41.442006Z","steps":["trace[109269837] 'agreement among raft nodes before linearized reading' (duration: 528.002392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.442044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.355596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:51:41.442141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:40.913693Z","time spent":"528.436262ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T03:51:41.442151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.957268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5d8946f6d5-6m5ft.18d2ed6c46408b3a\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-09-07T03:51:41.442257Z","caller":"traceutil/trace.go:171","msg":"trace[1499036598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9137; }","duration":"256.607142ms","start":"2026-09-07T03:51:41.185565Z","end":"2026-09-07T03:51:41.442172Z","steps":["trace[1499036598] 'agreement among raft nodes before linearized reading' (duration: 256.319265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.44236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.320754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:51:41.44253Z","caller":"traceutil/trace.go:171","msg":"trace[1369911430] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:9137; }","duration":"480.540899ms","start":"2026-09-07T03:51:40.961971Z","end":"2026-09-07T03:51:41.442512Z","steps":["trace[1369911430] 'agreement among raft nodes before linearized reading' (duration: 479.965114ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:41.442283Z","caller":"traceutil/trace.go:171","msg":"trace[282608653] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5d8946f6d5-6m5ft.18d2ed6c46408b3a; range_end:; response_count:1; response_revision:9137; }","duration":"129.209854ms","start":"2026-09-07T03:51:41.313037Z","end":"2026-09-07T03:51:41.442247Z","steps":["trace[282608653] 'agreement among raft nodes before linearized reading' (duration: 128.700011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.442648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:40.961946Z","time spent":"480.690392ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vrwlm\" "} {"level":"warn","ts":"2026-09-07T03:51:41.443124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.180002ms","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-07T03:51:41.443169Z","caller":"traceutil/trace.go:171","msg":"trace[146570018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9137; }","duration":"546.288075ms","start":"2026-09-07T03:51:40.896869Z","end":"2026-09-07T03:51:41.443157Z","steps":["trace[146570018] 'agreement among raft nodes before linearized reading' (duration: 544.781627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:41.443196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:40.896833Z","time spent":"546.356647ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T03:51:43.585413Z","caller":"traceutil/trace.go:171","msg":"trace[207937401] linearizableReadLoop","detail":"{readStateIndex:9473; appliedIndex:9472; }","duration":"120.74465ms","start":"2026-09-07T03:51:43.464634Z","end":"2026-09-07T03:51:43.585379Z","steps":["trace[207937401] 'read index received' (duration: 34.739171ms)","trace[207937401] 'applied index is now lower than readState.Index' (duration: 86.003999ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:51:43.585558Z","caller":"traceutil/trace.go:171","msg":"trace[247395728] transaction","detail":"{read_only:false; response_revision:9148; number_of_response:1; }","duration":"135.33782ms","start":"2026-09-07T03:51:43.450165Z","end":"2026-09-07T03:51:43.585503Z","steps":["trace[247395728] 'process raft request' (duration: 49.397572ms)","trace[247395728] 'compare' (duration: 85.60782ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:51:43.585823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.15445ms","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":"warn","ts":"2026-09-07T03:51:43.58585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.915459ms","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-07T03:51:43.58589Z","caller":"traceutil/trace.go:171","msg":"trace[654453210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9148; }","duration":"121.312394ms","start":"2026-09-07T03:51:43.46456Z","end":"2026-09-07T03:51:43.585872Z","steps":["trace[654453210] 'agreement among raft nodes before linearized reading' (duration: 121.043537ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:43.585934Z","caller":"traceutil/trace.go:171","msg":"trace[621130493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9148; }","duration":"120.045643ms","start":"2026-09-07T03:51:43.465866Z","end":"2026-09-07T03:51:43.585912Z","steps":["trace[621130493] 'agreement among raft nodes before linearized reading' (duration: 119.771676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:43.586134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.254153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-07T03:51:43.586158Z","caller":"traceutil/trace.go:171","msg":"trace[1394330423] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:9148; }","duration":"119.325904ms","start":"2026-09-07T03:51:43.466824Z","end":"2026-09-07T03:51:43.58615Z","steps":["trace[1394330423] 'agreement among raft nodes before linearized reading' (duration: 119.239462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:51:44.165508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.060879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:51:44.165679Z","caller":"traceutil/trace.go:171","msg":"trace[1224454579] transaction","detail":"{read_only:false; response_revision:9152; number_of_response:1; }","duration":"308.447058ms","start":"2026-09-07T03:51:43.857201Z","end":"2026-09-07T03:51:44.165648Z","steps":["trace[1224454579] 'process raft request' (duration: 11.071261ms)","trace[1224454579] 'compare' (duration: 296.854043ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:51:44.165776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:51:43.857169Z","time spent":"308.561541ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:51:53.598798Z","caller":"traceutil/trace.go:171","msg":"trace[645930153] transaction","detail":"{read_only:false; response_revision:9203; number_of_response:1; }","duration":"174.846902ms","start":"2026-09-07T03:51:53.423913Z","end":"2026-09-07T03:51:53.598759Z","steps":["trace[645930153] 'process raft request' (duration: 174.638606ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:51:53.699251Z","caller":"traceutil/trace.go:171","msg":"trace[1355196656] transaction","detail":"{read_only:false; response_revision:9204; number_of_response:1; }","duration":"253.279909ms","start":"2026-09-07T03:51:53.445936Z","end":"2026-09-07T03:51:53.699216Z","steps":["trace[1355196656] 'process raft request' (duration: 253.061424ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:52:42.869376Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T03:52:42.876542Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-07T03:52:42.876782Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-07T03:53:40.426735Z","caller":"traceutil/trace.go:171","msg":"trace[1468117138] transaction","detail":"{read_only:false; response_revision:10155; number_of_response:1; }","duration":"276.386965ms","start":"2026-09-07T03:53:40.150306Z","end":"2026-09-07T03:53:40.426693Z","steps":["trace[1468117138] 'process raft request' (duration: 276.133368ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:53:40.472343Z","caller":"traceutil/trace.go:171","msg":"trace[811579815] transaction","detail":"{read_only:false; response_revision:10156; number_of_response:1; }","duration":"264.925274ms","start":"2026-09-07T03:53:40.207311Z","end":"2026-09-07T03:53:40.472237Z","steps":["trace[811579815] 'process raft request' (duration: 247.235376ms)","trace[811579815] 'compare' (duration: 17.38183ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:54:05.88029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7992} {"level":"info","ts":"2026-09-07T03:54:05.955605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7992,"took":"72.521412ms","hash":2088648409,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T03:54:05.955684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2088648409,"revision":7992,"compact-revision":5678} {"level":"info","ts":"2026-09-07T03:59:05.890049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10342} {"level":"info","ts":"2026-09-07T03:59:05.982429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10342,"took":"89.607452ms","hash":1217528776,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":25591808,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-07T03:59:05.982534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1217528776,"revision":10342,"compact-revision":7992} {"level":"info","ts":"2026-09-07T03:59:36.054446Z","caller":"traceutil/trace.go:171","msg":"trace[1603085890] transaction","detail":"{read_only:false; response_revision:12668; number_of_response:1; }","duration":"225.958162ms","start":"2026-09-07T03:59:35.82846Z","end":"2026-09-07T03:59:36.054418Z","steps":["trace[1603085890] 'process raft request' (duration: 216.71373ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:59:36.226734Z","caller":"traceutil/trace.go:171","msg":"trace[68458943] transaction","detail":"{read_only:false; response_revision:12669; number_of_response:1; }","duration":"115.728911ms","start":"2026-09-07T03:59:36.110975Z","end":"2026-09-07T03:59:36.226704Z","steps":["trace[68458943] 'process raft request' (duration: 54.521136ms)","trace[68458943] 'compare' (duration: 60.956429ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:59:36.227257Z","caller":"traceutil/trace.go:171","msg":"trace[2012260751] transaction","detail":"{read_only:false; response_revision:12670; number_of_response:1; }","duration":"114.637704ms","start":"2026-09-07T03:59:36.112204Z","end":"2026-09-07T03:59:36.226842Z","steps":["trace[2012260751] 'process raft request' (duration: 114.442999ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:04:05.898229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12510} {"level":"info","ts":"2026-09-07T04:04:05.982538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12510,"took":"80.83025ms","hash":3487615047,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":27693056,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T04:04:05.982631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3487615047,"revision":12510,"compact-revision":10342} {"level":"info","ts":"2026-09-07T04:05:52.594021Z","caller":"traceutil/trace.go:171","msg":"trace[800978243] transaction","detail":"{read_only:false; response_revision:15231; number_of_response:1; }","duration":"129.923769ms","start":"2026-09-07T04:05:52.464078Z","end":"2026-09-07T04:05:52.594002Z","steps":["trace[800978243] 'process raft request' (duration: 125.052486ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:06:12.735563Z","caller":"traceutil/trace.go:171","msg":"trace[1392200204] transaction","detail":"{read_only:false; response_revision:15349; number_of_response:1; }","duration":"143.525373ms","start":"2026-09-07T04:06:12.592014Z","end":"2026-09-07T04:06:12.735539Z","steps":["trace[1392200204] 'process raft request' (duration: 142.937888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:06:12.938228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.287689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-07T04:06:12.938371Z","caller":"traceutil/trace.go:171","msg":"trace[1301772029] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3cf607f7-1e61-42f1-a2d3-9d54a8739b70; range_end:; response_count:1; response_revision:15349; }","duration":"199.529176ms","start":"2026-09-07T04:06:12.738812Z","end":"2026-09-07T04:06:12.938342Z","steps":["trace[1301772029] 'range keys from in-memory index tree' (duration: 199.010522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:06:12.938359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.5492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-07T04:06:12.938497Z","caller":"traceutil/trace.go:171","msg":"trace[1579021569] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15349; }","duration":"171.752064ms","start":"2026-09-07T04:06:12.7667Z","end":"2026-09-07T04:06:12.938452Z","steps":["trace[1579021569] 'range keys from in-memory index tree' (duration: 171.310334ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:06:39.386841Z","caller":"traceutil/trace.go:171","msg":"trace[1075770339] transaction","detail":"{read_only:false; response_revision:15680; number_of_response:1; }","duration":"115.750561ms","start":"2026-09-07T04:06:39.271025Z","end":"2026-09-07T04:06:39.386776Z","steps":["trace[1075770339] 'process raft request' (duration: 115.499685ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:16.059167Z","caller":"traceutil/trace.go:171","msg":"trace[1136234904] transaction","detail":"{read_only:false; response_revision:16540; number_of_response:1; }","duration":"131.565389ms","start":"2026-09-07T04:08:15.927535Z","end":"2026-09-07T04:08:16.059101Z","steps":["trace[1136234904] 'process raft request' (duration: 131.359444ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:21.577525Z","caller":"traceutil/trace.go:171","msg":"trace[368399616] linearizableReadLoop","detail":"{readStateIndex:17147; appliedIndex:17146; }","duration":"163.00622ms","start":"2026-09-07T04:08:21.414474Z","end":"2026-09-07T04:08:21.577481Z","steps":["trace[368399616] 'read index received' (duration: 162.713883ms)","trace[368399616] 'applied index is now lower than readState.Index' (duration: 291.267µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:08:21.577946Z","caller":"traceutil/trace.go:171","msg":"trace[1155061948] transaction","detail":"{read_only:false; response_revision:16571; number_of_response:1; }","duration":"192.240324ms","start":"2026-09-07T04:08:21.385689Z","end":"2026-09-07T04:08:21.577929Z","steps":["trace[1155061948] 'process raft request' (duration: 191.611549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:21.578426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.916292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zzf5s\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-09-07T04:08:21.578486Z","caller":"traceutil/trace.go:171","msg":"trace[342617629] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zzf5s; range_end:; response_count:1; response_revision:16571; }","duration":"164.047805ms","start":"2026-09-07T04:08:21.414423Z","end":"2026-09-07T04:08:21.578471Z","steps":["trace[342617629] 'agreement among raft nodes before linearized reading' (duration: 163.782949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:21.578964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.098231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:08:21.579084Z","caller":"traceutil/trace.go:171","msg":"trace[298250288] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:16571; }","duration":"161.269965ms","start":"2026-09-07T04:08:21.417784Z","end":"2026-09-07T04:08:21.579054Z","steps":["trace[298250288] 'agreement among raft nodes before linearized reading' (duration: 161.038109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:21.579765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.705457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T04:08:21.579808Z","caller":"traceutil/trace.go:171","msg":"trace[1428706148] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16571; }","duration":"136.773749ms","start":"2026-09-07T04:08:21.443023Z","end":"2026-09-07T04:08:21.579797Z","steps":["trace[1428706148] 'agreement among raft nodes before linearized reading' (duration: 136.579825ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:22.576271Z","caller":"traceutil/trace.go:171","msg":"trace[52615646] linearizableReadLoop","detail":"{readStateIndex:17151; appliedIndex:17150; }","duration":"160.176328ms","start":"2026-09-07T04:08:22.416049Z","end":"2026-09-07T04:08:22.576225Z","steps":["trace[52615646] 'read index received' (duration: 159.923311ms)","trace[52615646] 'applied index is now lower than readState.Index' (duration: 251.287µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:08:22.57634Z","caller":"traceutil/trace.go:171","msg":"trace[1746097127] transaction","detail":"{read_only:false; response_revision:16575; number_of_response:1; }","duration":"162.803725ms","start":"2026-09-07T04:08:22.413499Z","end":"2026-09-07T04:08:22.576303Z","steps":["trace[1746097127] 'process raft request' (duration: 162.489296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.576591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.520597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T04:08:22.57665Z","caller":"traceutil/trace.go:171","msg":"trace[1179784303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16575; }","duration":"160.64142ms","start":"2026-09-07T04:08:22.415994Z","end":"2026-09-07T04:08:22.576635Z","steps":["trace[1179784303] 'agreement among raft nodes before linearized reading' (duration: 160.387924ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:22.929118Z","caller":"traceutil/trace.go:171","msg":"trace[142886067] transaction","detail":"{read_only:false; response_revision:16577; number_of_response:1; }","duration":"364.598069ms","start":"2026-09-07T04:08:22.564449Z","end":"2026-09-07T04:08:22.929047Z","steps":["trace[142886067] 'process raft request' (duration: 364.411494ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:22.929266Z","caller":"traceutil/trace.go:171","msg":"trace[848750064] transaction","detail":"{read_only:false; response_revision:16576; number_of_response:1; }","duration":"511.026422ms","start":"2026-09-07T04:08:22.418187Z","end":"2026-09-07T04:08:22.929213Z","steps":["trace[848750064] 'process raft request' (duration: 505.866272ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:22.92928Z","caller":"traceutil/trace.go:171","msg":"trace[653445255] transaction","detail":"{read_only:false; response_revision:16578; number_of_response:1; }","duration":"364.6215ms","start":"2026-09-07T04:08:22.564606Z","end":"2026-09-07T04:08:22.929227Z","steps":["trace[653445255] 'process raft request' (duration: 364.363794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.929451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.418156Z","time spent":"511.204267ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T04:08:22.929495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.564569Z","time spent":"364.811874ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T04:08:22.930292Z","caller":"traceutil/trace.go:171","msg":"trace[51895945] linearizableReadLoop","detail":"{readStateIndex:17155; appliedIndex:17151; }","duration":"343.408696ms","start":"2026-09-07T04:08:22.586846Z","end":"2026-09-07T04:08:22.930254Z","steps":["trace[51895945] 'read index received' (duration: 337.21731ms)","trace[51895945] 'applied index is now lower than readState.Index' (duration: 6.190426ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:08:22.930425Z","caller":"traceutil/trace.go:171","msg":"trace[184936459] transaction","detail":"{read_only:false; response_revision:16579; number_of_response:1; }","duration":"348.773882ms","start":"2026-09-07T04:08:22.581635Z","end":"2026-09-07T04:08:22.930409Z","steps":["trace[184936459] 'process raft request' (duration: 348.413953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.930366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.564428Z","time spent":"364.784514ms","remote":"127.0.0.1:48384","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-07T04:08:22.930526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.581617Z","time spent":"348.867684ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T04:08:22.930634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.758434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:08:22.930707Z","caller":"traceutil/trace.go:171","msg":"trace[1744570849] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16579; }","duration":"343.877598ms","start":"2026-09-07T04:08:22.586817Z","end":"2026-09-07T04:08:22.930694Z","steps":["trace[1744570849] 'agreement among raft nodes before linearized reading' (duration: 343.717344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.930757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.426831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T04:08:22.930817Z","caller":"traceutil/trace.go:171","msg":"trace[606179178] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16579; }","duration":"340.538013ms","start":"2026-09-07T04:08:22.590266Z","end":"2026-09-07T04:08:22.930804Z","steps":["trace[606179178] 'agreement among raft nodes before linearized reading' (duration: 340.338539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.930874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.590225Z","time spent":"340.640507ms","remote":"127.0.0.1:48280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-09-07T04:08:22.930882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.352502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"warn","ts":"2026-09-07T04:08:22.931161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.679957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-07T04:08:22.931292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.915472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-92s5q\" ","response":"range_response_count:1 size:22097"} {"level":"info","ts":"2026-09-07T04:08:22.931406Z","caller":"traceutil/trace.go:171","msg":"trace[1249018207] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-92s5q; range_end:; response_count:1; response_revision:16579; }","duration":"302.082836ms","start":"2026-09-07T04:08:22.629298Z","end":"2026-09-07T04:08:22.931381Z","steps":["trace[1249018207] 'agreement among raft nodes before linearized reading' (duration: 301.851201ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:08:22.931381Z","caller":"traceutil/trace.go:171","msg":"trace[1298657791] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16579; }","duration":"165.901022ms","start":"2026-09-07T04:08:22.765463Z","end":"2026-09-07T04:08:22.931364Z","steps":["trace[1298657791] 'agreement among raft nodes before linearized reading' (duration: 165.501512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.931453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.629282Z","time spent":"302.160839ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22119,"request content":"key:\"/registry/pods/openstack/nova-compute-default-92s5q\" "} {"level":"info","ts":"2026-09-07T04:08:22.931333Z","caller":"traceutil/trace.go:171","msg":"trace[1005129121] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16579; }","duration":"152.870845ms","start":"2026-09-07T04:08:22.778447Z","end":"2026-09-07T04:08:22.931318Z","steps":["trace[1005129121] 'agreement among raft nodes before linearized reading' (duration: 152.31172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.931439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.791375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-07T04:08:22.931545Z","caller":"traceutil/trace.go:171","msg":"trace[1002159273] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16579; }","duration":"308.918149ms","start":"2026-09-07T04:08:22.622605Z","end":"2026-09-07T04:08:22.931523Z","steps":["trace[1002159273] 'agreement among raft nodes before linearized reading' (duration: 308.602011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.931607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.622592Z","time spent":"309.00053ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-07T04:08:22.930994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.656996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:08:22.931664Z","caller":"traceutil/trace.go:171","msg":"trace[1443714519] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16579; }","duration":"193.342592ms","start":"2026-09-07T04:08:22.738306Z","end":"2026-09-07T04:08:22.931648Z","steps":["trace[1443714519] 'agreement among raft nodes before linearized reading' (duration: 192.648345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.93109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.263968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-92s5q\" ","response":"range_response_count:1 size:22097"} {"level":"info","ts":"2026-09-07T04:08:22.9318Z","caller":"traceutil/trace.go:171","msg":"trace[1659908371] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-92s5q; range_end:; response_count:1; response_revision:16579; }","duration":"179.962136ms","start":"2026-09-07T04:08:22.751799Z","end":"2026-09-07T04:08:22.931761Z","steps":["trace[1659908371] 'agreement among raft nodes before linearized reading' (duration: 179.219618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.93117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.975269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vrwlm\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-07T04:08:22.931994Z","caller":"traceutil/trace.go:171","msg":"trace[1407641631] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vrwlm; range_end:; response_count:1; response_revision:16579; }","duration":"132.828662ms","start":"2026-09-07T04:08:22.799151Z","end":"2026-09-07T04:08:22.931979Z","steps":["trace[1407641631] 'agreement among raft nodes before linearized reading' (duration: 131.96746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.931268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.447916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-07T04:08:22.932167Z","caller":"traceutil/trace.go:171","msg":"trace[1986093786] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16579; }","duration":"200.39027ms","start":"2026-09-07T04:08:22.731766Z","end":"2026-09-07T04:08:22.932156Z","steps":["trace[1986093786] 'agreement among raft nodes before linearized reading' (duration: 199.430866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:08:22.930774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:08:22.586796Z","time spent":"343.967139ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-09-07T04:09:05.90742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14543} {"level":"info","ts":"2026-09-07T04:09:05.995424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14543,"took":"84.540655ms","hash":2584716936,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":29818880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T04:09:05.995583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2584716936,"revision":14543,"compact-revision":12510} {"level":"info","ts":"2026-09-07T04:14:05.915602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16818} {"level":"info","ts":"2026-09-07T04:14:06.028147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16818,"took":"108.750045ms","hash":2608609139,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":31080448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-07T04:14:06.02825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2608609139,"revision":16818,"compact-revision":14543} {"level":"info","ts":"2026-09-07T04:14:52.321735Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T04:14:52.332613Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-07T04:14:52.332795Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-07T04:15:10.384384Z","caller":"traceutil/trace.go:171","msg":"trace[1175746947] transaction","detail":"{read_only:false; response_revision:19478; number_of_response:1; }","duration":"230.804742ms","start":"2026-09-07T04:15:10.152916Z","end":"2026-09-07T04:15:10.383721Z","steps":["trace[1175746947] 'process raft request' (duration: 203.299743ms)","trace[1175746947] 'compare' (duration: 26.868543ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:15:10.418295Z","caller":"traceutil/trace.go:171","msg":"trace[1855864329] linearizableReadLoop","detail":"{readStateIndex:20155; appliedIndex:20153; }","duration":"263.963794ms","start":"2026-09-07T04:15:10.154305Z","end":"2026-09-07T04:15:10.418269Z","steps":["trace[1855864329] 'read index received' (duration: 201.92682ms)","trace[1855864329] 'applied index is now lower than readState.Index' (duration: 62.035854ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:15:10.418588Z","caller":"traceutil/trace.go:171","msg":"trace[1128333943] transaction","detail":"{read_only:false; response_revision:19479; number_of_response:1; }","duration":"264.408974ms","start":"2026-09-07T04:15:10.15417Z","end":"2026-09-07T04:15:10.418578Z","steps":["trace[1128333943] 'process raft request' (duration: 263.79902ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:15:10.418885Z","caller":"traceutil/trace.go:171","msg":"trace[96970205] transaction","detail":"{read_only:false; response_revision:19480; number_of_response:1; }","duration":"242.439404ms","start":"2026-09-07T04:15:10.176437Z","end":"2026-09-07T04:15:10.418877Z","steps":["trace[96970205] 'process raft request' (duration: 241.744367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.419149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.819115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-09-07T04:15:10.419189Z","caller":"traceutil/trace.go:171","msg":"trace[116509532] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:19480; }","duration":"264.877546ms","start":"2026-09-07T04:15:10.154297Z","end":"2026-09-07T04:15:10.419175Z","steps":["trace[116509532] 'agreement among raft nodes before linearized reading' (duration: 264.689912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.419324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.239369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:15:10.419348Z","caller":"traceutil/trace.go:171","msg":"trace[1729637369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19480; }","duration":"240.33004ms","start":"2026-09-07T04:15:10.179012Z","end":"2026-09-07T04:15:10.419342Z","steps":["trace[1729637369] 'agreement among raft nodes before linearized reading' (duration: 240.286499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.419651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.469035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T04:15:10.419678Z","caller":"traceutil/trace.go:171","msg":"trace[559810911] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:19480; }","duration":"224.529945ms","start":"2026-09-07T04:15:10.19514Z","end":"2026-09-07T04:15:10.41967Z","steps":["trace[559810911] 'agreement among raft nodes before linearized reading' (duration: 224.464624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.821259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.594463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-09-07T04:15:10.821479Z","caller":"traceutil/trace.go:171","msg":"trace[37782753] linearizableReadLoop","detail":"{readStateIndex:20160; appliedIndex:20158; }","duration":"350.598703ms","start":"2026-09-07T04:15:10.470861Z","end":"2026-09-07T04:15:10.821459Z","steps":["trace[37782753] 'read index received' (duration: 91.00837ms)","trace[37782753] 'applied index is now lower than readState.Index' (duration: 259.589243ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:15:10.8216Z","caller":"traceutil/trace.go:171","msg":"trace[1569381888] transaction","detail":"{read_only:false; response_revision:19483; number_of_response:1; }","duration":"369.541198ms","start":"2026-09-07T04:15:10.452011Z","end":"2026-09-07T04:15:10.821552Z","steps":["trace[1569381888] 'process raft request' (duration: 109.556805ms)","trace[1569381888] 'compare' (duration: 259.363337ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:15:10.821774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.451999Z","time spent":"369.700752ms","remote":"127.0.0.1:48318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T04:15:10.821798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.918941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-09-07T04:15:10.82185Z","caller":"traceutil/trace.go:171","msg":"trace[1976044453] transaction","detail":"{read_only:false; response_revision:19484; number_of_response:1; }","duration":"352.571903ms","start":"2026-09-07T04:15:10.468982Z","end":"2026-09-07T04:15:10.821554Z","steps":["trace[1976044453] 'process raft request' (duration: 352.404199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.821908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.468962Z","time spent":"352.920022ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T04:15:10.821906Z","caller":"traceutil/trace.go:171","msg":"trace[765491524] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:19484; }","duration":"351.026794ms","start":"2026-09-07T04:15:10.470843Z","end":"2026-09-07T04:15:10.82187Z","steps":["trace[765491524] 'agreement among raft nodes before linearized reading' (duration: 350.716937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.821979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.470834Z","time spent":"351.124996ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-09-07T04:15:10.862783Z","caller":"traceutil/trace.go:171","msg":"trace[1754702150] transaction","detail":"{read_only:false; response_revision:19486; number_of_response:1; }","duration":"170.492391ms","start":"2026-09-07T04:15:10.692255Z","end":"2026-09-07T04:15:10.862747Z","steps":["trace[1754702150] 'process raft request' (duration: 170.427919ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:15:10.863397Z","caller":"traceutil/trace.go:171","msg":"trace[849845747] transaction","detail":"{read_only:false; response_revision:19485; number_of_response:1; }","duration":"171.319743ms","start":"2026-09-07T04:15:10.692054Z","end":"2026-09-07T04:15:10.863374Z","steps":["trace[849845747] 'process raft request' (duration: 170.477182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.863703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.562413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:15:10.863829Z","caller":"traceutil/trace.go:171","msg":"trace[675771896] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:19486; }","duration":"180.755928ms","start":"2026-09-07T04:15:10.683044Z","end":"2026-09-07T04:15:10.8638Z","steps":["trace[675771896] 'agreement among raft nodes before linearized reading' (duration: 180.532443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.863958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.749957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T04:15:10.864119Z","caller":"traceutil/trace.go:171","msg":"trace[1705082934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19486; }","duration":"182.940513ms","start":"2026-09-07T04:15:10.681148Z","end":"2026-09-07T04:15:10.864089Z","steps":["trace[1705082934] 'agreement among raft nodes before linearized reading' (duration: 182.46385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.864252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.135748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-07T04:15:10.864337Z","caller":"traceutil/trace.go:171","msg":"trace[341851177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19486; }","duration":"177.288691ms","start":"2026-09-07T04:15:10.687026Z","end":"2026-09-07T04:15:10.864315Z","steps":["trace[341851177] 'agreement among raft nodes before linearized reading' (duration: 177.029545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.865254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.014082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-09-07T04:15:10.865332Z","caller":"traceutil/trace.go:171","msg":"trace[2007442263] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:19486; }","duration":"321.154416ms","start":"2026-09-07T04:15:10.544158Z","end":"2026-09-07T04:15:10.865312Z","steps":["trace[2007442263] 'agreement among raft nodes before linearized reading' (duration: 319.71749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:10.865377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.544129Z","time spent":"321.230967ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225424,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-09-07T04:15:11.463539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780508064876377518,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-07T04:15:11.554835Z","caller":"traceutil/trace.go:171","msg":"trace[750301333] transaction","detail":"{read_only:false; response_revision:19490; number_of_response:1; }","duration":"594.707179ms","start":"2026-09-07T04:15:10.960091Z","end":"2026-09-07T04:15:11.554798Z","steps":["trace[750301333] 'process raft request' (duration: 573.133038ms)","trace[750301333] 'compare' (duration: 21.366896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:15:11.555242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.960047Z","time spent":"595.107149ms","remote":"127.0.0.1:48448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T04:15:11.562366Z","caller":"traceutil/trace.go:171","msg":"trace[1485323250] linearizableReadLoop","detail":"{readStateIndex:20167; appliedIndex:20165; }","duration":"599.920659ms","start":"2026-09-07T04:15:10.962413Z","end":"2026-09-07T04:15:11.562334Z","steps":["trace[1485323250] 'read index received' (duration: 570.893441ms)","trace[1485323250] 'applied index is now lower than readState.Index' (duration: 29.025688ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:15:11.562699Z","caller":"traceutil/trace.go:171","msg":"trace[352133603] transaction","detail":"{read_only:false; response_revision:19491; number_of_response:1; }","duration":"279.942623ms","start":"2026-09-07T04:15:11.282742Z","end":"2026-09-07T04:15:11.562685Z","steps":["trace[352133603] 'process raft request' (duration: 279.386409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.563032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.597176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-07T04:15:11.563099Z","caller":"traceutil/trace.go:171","msg":"trace[1480225138] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:19491; }","duration":"600.701888ms","start":"2026-09-07T04:15:10.962381Z","end":"2026-09-07T04:15:11.563083Z","steps":["trace[1480225138] 'agreement among raft nodes before linearized reading' (duration: 600.516604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.563135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.962365Z","time spent":"600.760439ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-09-07T04:15:11.570419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.563649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-07T04:15:11.570579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.317645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-92s5q\" ","response":"range_response_count:1 size:22097"} {"level":"info","ts":"2026-09-07T04:15:11.570641Z","caller":"traceutil/trace.go:171","msg":"trace[557322718] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-92s5q; range_end:; response_count:1; response_revision:19492; }","duration":"534.429259ms","start":"2026-09-07T04:15:11.036195Z","end":"2026-09-07T04:15:11.570624Z","steps":["trace[557322718] 'agreement among raft nodes before linearized reading' (duration: 534.259935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.570672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:11.036168Z","time spent":"534.49669ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22119,"request content":"key:\"/registry/pods/openstack/nova-compute-default-92s5q\" "} {"level":"info","ts":"2026-09-07T04:15:11.570764Z","caller":"traceutil/trace.go:171","msg":"trace[343583814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19492; }","duration":"474.687032ms","start":"2026-09-07T04:15:11.095779Z","end":"2026-09-07T04:15:11.570466Z","steps":["trace[343583814] 'agreement among raft nodes before linearized reading' (duration: 474.489337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.570842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:11.095751Z","time spent":"475.077322ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T04:15:11.570913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.395355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-07T04:15:11.570958Z","caller":"traceutil/trace.go:171","msg":"trace[1212226628] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:19492; }","duration":"579.475717ms","start":"2026-09-07T04:15:10.991469Z","end":"2026-09-07T04:15:11.570945Z","steps":["trace[1212226628] 'agreement among raft nodes before linearized reading' (duration: 579.363885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.570984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:10.991451Z","time spent":"579.527259ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3707,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-09-07T04:15:11.571367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.235905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T04:15:11.57141Z","caller":"traceutil/trace.go:171","msg":"trace[1686871247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19492; }","duration":"180.297767ms","start":"2026-09-07T04:15:11.391101Z","end":"2026-09-07T04:15:11.571399Z","steps":["trace[1686871247] 'agreement among raft nodes before linearized reading' (duration: 180.189955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.57158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.698996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-07T04:15:11.571617Z","caller":"traceutil/trace.go:171","msg":"trace[2100740767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19492; }","duration":"182.770128ms","start":"2026-09-07T04:15:11.388835Z","end":"2026-09-07T04:15:11.571605Z","steps":["trace[2100740767] 'agreement among raft nodes before linearized reading' (duration: 182.682636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.571747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.981231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:15:11.571784Z","caller":"traceutil/trace.go:171","msg":"trace[607085734] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:19492; }","duration":"200.063483ms","start":"2026-09-07T04:15:11.371707Z","end":"2026-09-07T04:15:11.57177Z","steps":["trace[607085734] 'agreement among raft nodes before linearized reading' (duration: 200.009342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.572004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.469122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zzf5s\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-09-07T04:15:11.572046Z","caller":"traceutil/trace.go:171","msg":"trace[1630115677] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zzf5s; range_end:; response_count:1; response_revision:19492; }","duration":"297.550784ms","start":"2026-09-07T04:15:11.274482Z","end":"2026-09-07T04:15:11.572033Z","steps":["trace[1630115677] 'agreement among raft nodes before linearized reading' (duration: 297.427861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.570401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.177027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-07T04:15:11.572255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.713668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:15:11.57229Z","caller":"traceutil/trace.go:171","msg":"trace[743768590] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:19492; }","duration":"531.081885ms","start":"2026-09-07T04:15:11.041159Z","end":"2026-09-07T04:15:11.572241Z","steps":["trace[743768590] 'agreement among raft nodes before linearized reading' (duration: 529.153997ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:15:11.572343Z","caller":"traceutil/trace.go:171","msg":"trace[1447028530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19492; }","duration":"390.867381ms","start":"2026-09-07T04:15:11.181456Z","end":"2026-09-07T04:15:11.572324Z","steps":["trace[1447028530] 'agreement among raft nodes before linearized reading' (duration: 390.683607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:15:11.572389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:11.181431Z","time spent":"390.950554ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T04:15:11.572442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:15:11.04115Z","time spent":"531.231459ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-09-07T04:15:11.680955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.250927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:953661"} {"level":"info","ts":"2026-09-07T04:15:11.681046Z","caller":"traceutil/trace.go:171","msg":"trace[1201282430] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19495; }","duration":"101.37085ms","start":"2026-09-07T04:15:11.579657Z","end":"2026-09-07T04:15:11.681027Z","steps":["trace[1201282430] 'agreement among raft nodes before linearized reading' (duration: 100.5659ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:19:05.923778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19017} {"level":"info","ts":"2026-09-07T04:19:06.020669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19017,"took":"93.81824ms","hash":4096312069,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":33955840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-07T04:19:06.020732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4096312069,"revision":19017,"compact-revision":16818} {"level":"info","ts":"2026-09-07T04:21:36.768018Z","caller":"traceutil/trace.go:171","msg":"trace[368570250] transaction","detail":"{read_only:false; response_revision:22377; number_of_response:1; }","duration":"156.547952ms","start":"2026-09-07T04:21:36.611438Z","end":"2026-09-07T04:21:36.767986Z","steps":["trace[368570250] 'process raft request' (duration: 156.311106ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:21:36.998224Z","caller":"traceutil/trace.go:171","msg":"trace[451193255] linearizableReadLoop","detail":"{readStateIndex:23151; appliedIndex:23149; }","duration":"304.543491ms","start":"2026-09-07T04:21:36.693649Z","end":"2026-09-07T04:21:36.998192Z","steps":["trace[451193255] 'read index received' (duration: 74.078852ms)","trace[451193255] 'applied index is now lower than readState.Index' (duration: 230.447029ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:21:36.998366Z","caller":"traceutil/trace.go:171","msg":"trace[378525080] transaction","detail":"{read_only:false; response_revision:22378; number_of_response:1; }","duration":"385.360101ms","start":"2026-09-07T04:21:36.612949Z","end":"2026-09-07T04:21:36.998309Z","steps":["trace[378525080] 'process raft request' (duration: 313.780602ms)","trace[378525080] 'compare' (duration: 71.285642ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:21:36.99846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.760686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:21:36.998535Z","caller":"traceutil/trace.go:171","msg":"trace[1106570338] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:22378; }","duration":"304.888859ms","start":"2026-09-07T04:21:36.693627Z","end":"2026-09-07T04:21:36.998516Z","steps":["trace[1106570338] 'agreement among raft nodes before linearized reading' (duration: 304.694264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:21:36.998589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:21:36.693542Z","time spent":"305.032052ms","remote":"127.0.0.1:56564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-07T04:21:36.998742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:21:36.612921Z","time spent":"385.564815ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T04:21:37.353434Z","caller":"traceutil/trace.go:171","msg":"trace[488416756] transaction","detail":"{read_only:false; response_revision:22379; number_of_response:1; }","duration":"115.047485ms","start":"2026-09-07T04:21:37.238328Z","end":"2026-09-07T04:21:37.353375Z","steps":["trace[488416756] 'process raft request' (duration: 114.732538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:21:37.558622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.991224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T04:21:37.55985Z","caller":"traceutil/trace.go:171","msg":"trace[824834712] transaction","detail":"{read_only:false; response_revision:22380; number_of_response:1; }","duration":"194.503161ms","start":"2026-09-07T04:21:37.365268Z","end":"2026-09-07T04:21:37.559772Z","steps":["trace[824834712] 'process raft request' (duration: 92.259055ms)","trace[824834712] 'compare' (duration: 100.857021ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:21:37.560444Z","caller":"traceutil/trace.go:171","msg":"trace[2077048516] linearizableReadLoop","detail":"{readStateIndex:23153; appliedIndex:23152; }","duration":"178.945992ms","start":"2026-09-07T04:21:37.381425Z","end":"2026-09-07T04:21:37.560371Z","steps":["trace[2077048516] 'read index received' (duration: 75.958299ms)","trace[2077048516] 'applied index is now lower than readState.Index' (duration: 102.985253ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:21:37.560704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.26888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-07T04:21:37.560754Z","caller":"traceutil/trace.go:171","msg":"trace[28985965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:22380; }","duration":"179.330172ms","start":"2026-09-07T04:21:37.381396Z","end":"2026-09-07T04:21:37.560726Z","steps":["trace[28985965] 'agreement among raft nodes before linearized reading' (duration: 179.179177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:21:37.560836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.952148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T04:21:37.560939Z","caller":"traceutil/trace.go:171","msg":"trace[1237581553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22380; }","duration":"144.096241ms","start":"2026-09-07T04:21:37.416808Z","end":"2026-09-07T04:21:37.560904Z","steps":["trace[1237581553] 'agreement among raft nodes before linearized reading' (duration: 143.805823ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:21:37.762091Z","caller":"traceutil/trace.go:171","msg":"trace[33150116] transaction","detail":"{read_only:false; response_revision:22381; number_of_response:1; }","duration":"191.387183ms","start":"2026-09-07T04:21:37.570654Z","end":"2026-09-07T04:21:37.762041Z","steps":["trace[33150116] 'process raft request' (duration: 156.343888ms)","trace[33150116] 'compare' (duration: 34.82427ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:21:38.060361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.587554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-07T04:21:38.060505Z","caller":"traceutil/trace.go:171","msg":"trace[97803479] linearizableReadLoop","detail":"{readStateIndex:23155; appliedIndex:23154; }","duration":"164.939742ms","start":"2026-09-07T04:21:37.895542Z","end":"2026-09-07T04:21:38.060482Z","steps":["trace[97803479] 'read index received' (duration: 62.068332ms)","trace[97803479] 'applied index is now lower than readState.Index' (duration: 102.86996ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:21:38.06061Z","caller":"traceutil/trace.go:171","msg":"trace[154894500] transaction","detail":"{read_only:false; response_revision:22382; number_of_response:1; }","duration":"468.760415ms","start":"2026-09-07T04:21:37.591835Z","end":"2026-09-07T04:21:38.060596Z","steps":["trace[154894500] 'process raft request' (duration: 365.796452ms)","trace[154894500] 'compare' (duration: 102.328157ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:21:38.060684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:21:37.591802Z","time spent":"468.848637ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T04:21:38.060942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.42441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-07T04:21:38.060944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.387203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:21:38.061046Z","caller":"traceutil/trace.go:171","msg":"trace[1328719686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22382; }","duration":"108.568143ms","start":"2026-09-07T04:21:37.952451Z","end":"2026-09-07T04:21:38.061019Z","steps":["trace[1328719686] 'agreement among raft nodes before linearized reading' (duration: 108.318997ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:21:38.06106Z","caller":"traceutil/trace.go:171","msg":"trace[1099717901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22382; }","duration":"165.564847ms","start":"2026-09-07T04:21:37.89547Z","end":"2026-09-07T04:21:38.061035Z","steps":["trace[1099717901] 'agreement among raft nodes before linearized reading' (duration: 165.358152ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:21:38.258457Z","caller":"traceutil/trace.go:171","msg":"trace[756444850] transaction","detail":"{read_only:false; response_revision:22384; number_of_response:1; }","duration":"192.637374ms","start":"2026-09-07T04:21:38.065782Z","end":"2026-09-07T04:21:38.25842Z","steps":["trace[756444850] 'process raft request' (duration: 188.767857ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:21:38.258746Z","caller":"traceutil/trace.go:171","msg":"trace[1075721031] transaction","detail":"{read_only:false; response_revision:22385; number_of_response:1; }","duration":"191.274751ms","start":"2026-09-07T04:21:38.067435Z","end":"2026-09-07T04:21:38.25871Z","steps":["trace[1075721031] 'process raft request' (duration: 190.922472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:22:24.633759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.669264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-07T04:22:24.633885Z","caller":"traceutil/trace.go:171","msg":"trace[196896667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22856; }","duration":"220.825698ms","start":"2026-09-07T04:22:24.413014Z","end":"2026-09-07T04:22:24.63384Z","steps":["trace[196896667] 'range keys from in-memory index tree' (duration: 220.454849ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:24:05.935785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21343} {"level":"info","ts":"2026-09-07T04:24:06.059591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21343,"took":"119.472686ms","hash":2246249297,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T04:24:06.05969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2246249297,"revision":21343,"compact-revision":19017} {"level":"info","ts":"2026-09-07T04:26:05.906352Z","caller":"traceutil/trace.go:171","msg":"trace[1851379583] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"329.847469ms","start":"2026-09-07T04:26:05.576483Z","end":"2026-09-07T04:26:05.90633Z","steps":["trace[1851379583] 'process raft request' (duration: 329.53645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:26:05.906487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T04:26:05.576448Z","time spent":"329.966732ms","remote":"127.0.0.1:48384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T04:26:05.9063Z","caller":"traceutil/trace.go:171","msg":"trace[984730610] linearizableReadLoop","detail":"{readStateIndex:25857; appliedIndex:25856; }","duration":"231.64753ms","start":"2026-09-07T04:26:05.674627Z","end":"2026-09-07T04:26:05.906274Z","steps":["trace[984730610] 'read index received' (duration: 231.435674ms)","trace[984730610] 'applied index is now lower than readState.Index' (duration: 209.925µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:26:05.906543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.905795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-07T04:26:05.906613Z","caller":"traceutil/trace.go:171","msg":"trace[1011861841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25005; }","duration":"232.044239ms","start":"2026-09-07T04:26:05.67455Z","end":"2026-09-07T04:26:05.906594Z","steps":["trace[1011861841] 'agreement among raft nodes before linearized reading' (duration: 231.848664ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:26:05.922111Z","caller":"traceutil/trace.go:171","msg":"trace[300868079] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"170.827679ms","start":"2026-09-07T04:26:05.751268Z","end":"2026-09-07T04:26:05.922096Z","steps":["trace[300868079] 'process raft request' (duration: 170.4626ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:27:18.217169Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006833.wal"} {"level":"info","ts":"2026-09-07T04:27:28.825419Z","caller":"traceutil/trace.go:171","msg":"trace[546352880] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"119.865793ms","start":"2026-09-07T04:27:28.705498Z","end":"2026-09-07T04:27:28.825364Z","steps":["trace[546352880] 'process raft request' (duration: 119.609727ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:27:28.878642Z","caller":"traceutil/trace.go:171","msg":"trace[1914329972] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"170.619155ms","start":"2026-09-07T04:27:28.707993Z","end":"2026-09-07T04:27:28.878613Z","steps":["trace[1914329972] 'process raft request' (duration: 154.304034ms)","trace[1914329972] 'compare' (duration: 16.117977ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:27:28.878789Z","caller":"traceutil/trace.go:171","msg":"trace[898564195] linearizableReadLoop","detail":"{readStateIndex:26766; appliedIndex:26765; }","duration":"170.678796ms","start":"2026-09-07T04:27:28.708086Z","end":"2026-09-07T04:27:28.878765Z","steps":["trace[898564195] 'read index received' (duration: 117.910086ms)","trace[898564195] 'applied index is now lower than readState.Index' (duration: 52.76781ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:27:28.878999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.893261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-07T04:27:28.879033Z","caller":"traceutil/trace.go:171","msg":"trace[720197488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25893; }","duration":"170.959834ms","start":"2026-09-07T04:27:28.708065Z","end":"2026-09-07T04:27:28.879025Z","steps":["trace[720197488] 'agreement among raft nodes before linearized reading' (duration: 170.788659ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:29:05.943849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23532} {"level":"info","ts":"2026-09-07T04:29:06.071861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23532,"took":"122.595216ms","hash":4002372528,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":43634688,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-07T04:29:06.071951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4002372528,"revision":23532,"compact-revision":21343} {"level":"info","ts":"2026-09-07T04:34:05.95085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26639} {"level":"info","ts":"2026-09-07T04:34:06.088105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26639,"took":"133.583051ms","hash":2263035893,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-07T04:34:06.08819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2263035893,"revision":26639,"compact-revision":23532} {"level":"info","ts":"2026-09-07T04:34:29.025236Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"373d5d49ce4adaff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-07T04:34:29.034269Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-07T04:34:29.034509Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-07T04:36:00.518029Z","caller":"traceutil/trace.go:171","msg":"trace[777698955] transaction","detail":"{read_only:false; response_revision:29769; number_of_response:1; }","duration":"102.942655ms","start":"2026-09-07T04:36:00.415047Z","end":"2026-09-07T04:36:00.51799Z","steps":["trace[777698955] 'process raft request' (duration: 102.635547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:36:01.290014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.468388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-07T04:36:01.290124Z","caller":"traceutil/trace.go:171","msg":"trace[1567568527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29774; }","duration":"113.622851ms","start":"2026-09-07T04:36:01.176476Z","end":"2026-09-07T04:36:01.290099Z","steps":["trace[1567568527] 'range keys from in-memory index tree' (duration: 113.381115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:36:01.294575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.955471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:29774 ","response":"range_response_count:395 size:302606"} {"level":"info","ts":"2026-09-07T04:36:01.294697Z","caller":"traceutil/trace.go:171","msg":"trace[374384440] range","detail":"{range_begin:/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\u0000; range_end:/registry/events/openstack0; response_count:395; response_revision:29774; }","duration":"112.130414ms","start":"2026-09-07T04:36:01.182543Z","end":"2026-09-07T04:36:01.294673Z","steps":["trace[374384440] 'range keys from in-memory index tree' (duration: 107.479188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T04:36:01.305472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.578077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:29774 ","response":"range_response_count:395 size:302606"} {"level":"info","ts":"2026-09-07T04:36:01.305608Z","caller":"traceutil/trace.go:171","msg":"trace[841635597] range","detail":"{range_begin:/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\u0000; range_end:/registry/events/openstack0; response_count:395; response_revision:29774; }","duration":"122.971776ms","start":"2026-09-07T04:36:01.182602Z","end":"2026-09-07T04:36:01.305574Z","steps":["trace[841635597] 'range keys from in-memory index tree' (duration: 107.633383ms)","trace[841635597] 'range keys from bolt db' (duration: 14.81363ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T04:36:09.201544Z","caller":"traceutil/trace.go:171","msg":"trace[309378330] transaction","detail":"{read_only:false; response_revision:29832; number_of_response:1; }","duration":"118.72383ms","start":"2026-09-07T04:36:09.082786Z","end":"2026-09-07T04:36:09.20151Z","steps":["trace[309378330] 'process raft request' (duration: 109.078748ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:36:09.201538Z","caller":"traceutil/trace.go:171","msg":"trace[1233543450] linearizableReadLoop","detail":"{readStateIndex:30819; appliedIndex:30818; }","duration":"109.081659ms","start":"2026-09-07T04:36:09.092416Z","end":"2026-09-07T04:36:09.201498Z","steps":["trace[1233543450] 'read index received' (duration: 99.468527ms)","trace[1233543450] 'applied index is now lower than readState.Index' (duration: 9.607332ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:36:09.203697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.241072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:29831 ","response":"range_response_count:395 size:302606"} {"level":"info","ts":"2026-09-07T04:36:09.203799Z","caller":"traceutil/trace.go:171","msg":"trace[1491893117] range","detail":"{range_begin:/registry/events/openstack/placement-ks-endpoints-vh4kg.18d2ee315ff19a64\u0000; range_end:/registry/events/openstack0; response_count:395; response_revision:29832; }","duration":"111.403035ms","start":"2026-09-07T04:36:09.092378Z","end":"2026-09-07T04:36:09.203781Z","steps":["trace[1491893117] 'agreement among raft nodes before linearized reading' (duration: 109.199021ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:36:09.865797Z","caller":"traceutil/trace.go:171","msg":"trace[984196755] linearizableReadLoop","detail":"{readStateIndex:30825; appliedIndex:30824; }","duration":"108.050323ms","start":"2026-09-07T04:36:09.757717Z","end":"2026-09-07T04:36:09.865768Z","steps":["trace[984196755] 'read index received' (duration: 107.821178ms)","trace[984196755] 'applied index is now lower than readState.Index' (duration: 227.735µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T04:36:09.866341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.603127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/octavia-ks-service\" ","response":"range_response_count:1 size:542"} {"level":"info","ts":"2026-09-07T04:36:09.866399Z","caller":"traceutil/trace.go:171","msg":"trace[1630692631] range","detail":"{range_begin:/registry/serviceaccounts/openstack/octavia-ks-service; range_end:; response_count:1; response_revision:29838; }","duration":"108.700419ms","start":"2026-09-07T04:36:09.757682Z","end":"2026-09-07T04:36:09.866383Z","steps":["trace[1630692631] 'agreement among raft nodes before linearized reading' (duration: 108.510954ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T04:36:09.865959Z","caller":"traceutil/trace.go:171","msg":"trace[1272388660] transaction","detail":"{read_only:false; response_revision:29838; number_of_response:1; }","duration":"110.235877ms","start":"2026-09-07T04:36:09.755709Z","end":"2026-09-07T04:36:09.865944Z","steps":["trace[1272388660] 'process raft request' (duration: 109.794237ms)"],"step_count":1}