{"level":"warn","ts":"2026-10-07T03:47:26.202349Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T03:47:26.202436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.3: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.3:2380","--initial-cluster=instance=https://162.253.55.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.3: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-10-07T03:47:26.202507Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T03:47:26.202519Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.3:2380"]} {"level":"info","ts":"2026-10-07T03:47:26.202538Z","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-10-07T03:47:26.203271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"]} {"level":"info","ts":"2026-10-07T03:47:26.203363Z","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.3:2380"],"listen-peer-urls":["https://162.253.55.3:2380"],"advertise-client-urls":["https://162.253.55.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.3: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-10-07T03:47:26.207872Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.257138ms"} {"level":"info","ts":"2026-10-07T03:47:26.215393Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a99b527d1e65fdd9","cluster-id":"9bd8b4a41eaf5d40"} {"level":"info","ts":"2026-10-07T03:47:26.215472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=()"} {"level":"info","ts":"2026-10-07T03:47:26.215496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became follower at term 0"} {"level":"info","ts":"2026-10-07T03:47:26.215507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a99b527d1e65fdd9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-07T03:47:26.215514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became follower at term 1"} {"level":"info","ts":"2026-10-07T03:47:26.215554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=(12221452711134363097)"} {"level":"warn","ts":"2026-10-07T03:47:26.222885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-07T03:47:26.224492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-07T03:47:26.226084Z","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-10-07T03:47:26.228284Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a99b527d1e65fdd9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-07T03:47:26.229213Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a99b527d1e65fdd9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-07T03:47:26.229288Z","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-10-07T03:47:26.229477Z","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-10-07T03:47:26.229537Z","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-10-07T03:47:26.231264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 switched to configuration voters=(12221452711134363097)"} {"level":"info","ts":"2026-10-07T03:47:26.231523Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bd8b4a41eaf5d40","local-member-id":"a99b527d1e65fdd9","added-peer-id":"a99b527d1e65fdd9","added-peer-peer-urls":["https://162.253.55.3:2380"]} {"level":"info","ts":"2026-10-07T03:47:26.233307Z","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-10-07T03:47:26.233407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.3:2380"} {"level":"info","ts":"2026-10-07T03:47:26.23351Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.3:2380"} {"level":"info","ts":"2026-10-07T03:47:26.233781Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a99b527d1e65fdd9","initial-advertise-peer-urls":["https://162.253.55.3:2380"],"listen-peer-urls":["https://162.253.55.3:2380"],"advertise-client-urls":["https://162.253.55.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-07T03:47:26.233828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-07T03:47:26.716875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 is starting a new election at term 1"} {"level":"info","ts":"2026-10-07T03:47:26.716921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-07T03:47:26.716947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 received MsgPreVoteResp from a99b527d1e65fdd9 at term 1"} {"level":"info","ts":"2026-10-07T03:47:26.716961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became candidate at term 2"} {"level":"info","ts":"2026-10-07T03:47:26.716965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 received MsgVoteResp from a99b527d1e65fdd9 at term 2"} {"level":"info","ts":"2026-10-07T03:47:26.716974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a99b527d1e65fdd9 became leader at term 2"} {"level":"info","ts":"2026-10-07T03:47:26.716982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a99b527d1e65fdd9 elected leader a99b527d1e65fdd9 at term 2"} {"level":"info","ts":"2026-10-07T03:47:26.723511Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a99b527d1e65fdd9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.3:2379]}","request-path":"/0/members/a99b527d1e65fdd9/attributes","cluster-id":"9bd8b4a41eaf5d40","publish-timeout":"7s"} {"level":"info","ts":"2026-10-07T03:47:26.723687Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:47:26.723753Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:47:26.723941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-07T03:47:26.723923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:47:26.724005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-07T03:47:26.724965Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bd8b4a41eaf5d40","local-member-id":"a99b527d1e65fdd9","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:47:26.725098Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:47:26.725164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:47:26.726933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.3:2379"} {"level":"info","ts":"2026-10-07T03:47:26.727438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-07T03:47:29.223746Z","caller":"traceutil/trace.go:171","msg":"trace[684820872] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"123.021204ms","start":"2026-10-07T03:47:29.100704Z","end":"2026-10-07T03:47:29.223725Z","steps":["trace[684820872] 'read index received' (duration: 122.83961ms)","trace[684820872] 'applied index is now lower than readState.Index' (duration: 180.574µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:29.223821Z","caller":"traceutil/trace.go:171","msg":"trace[736979234] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"124.687915ms","start":"2026-10-07T03:47:29.099096Z","end":"2026-10-07T03:47:29.223783Z","steps":["trace[736979234] 'process raft request' (duration: 124.522362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:29.224056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.31941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-07T03:47:29.224169Z","caller":"traceutil/trace.go:171","msg":"trace[805892888] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"123.510504ms","start":"2026-10-07T03:47:29.100639Z","end":"2026-10-07T03:47:29.224149Z","steps":["trace[805892888] 'agreement among raft nodes before linearized reading' (duration: 123.220448ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.293846Z","caller":"traceutil/trace.go:171","msg":"trace[1254635020] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"149.935239ms","start":"2026-10-07T03:47:37.143885Z","end":"2026-10-07T03:47:37.29382Z","steps":["trace[1254635020] 'process raft request' (duration: 149.795866ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.528854Z","caller":"traceutil/trace.go:171","msg":"trace[2145750236] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:318; }","duration":"345.371616ms","start":"2026-10-07T03:47:37.183429Z","end":"2026-10-07T03:47:37.5288Z","steps":["trace[2145750236] 'read index received' (duration: 110.33049ms)","trace[2145750236] 'applied index is now lower than readState.Index' (duration: 235.040036ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:37.528985Z","caller":"traceutil/trace.go:171","msg":"trace[1858390885] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"381.859668ms","start":"2026-10-07T03:47:37.147096Z","end":"2026-10-07T03:47:37.528956Z","steps":["trace[1858390885] 'process raft request' (duration: 381.4601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.529036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.5891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-10-07T03:47:37.529079Z","caller":"traceutil/trace.go:171","msg":"trace[696705781] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:310; }","duration":"345.689071ms","start":"2026-10-07T03:47:37.183372Z","end":"2026-10-07T03:47:37.529061Z","steps":["trace[696705781] 'agreement among raft nodes before linearized reading' (duration: 345.59666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.529121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:47:37.183353Z","time spent":"345.759503ms","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-10-07T03:47:37.529182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.22635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-10-07T03:47:37.529257Z","caller":"traceutil/trace.go:171","msg":"trace[862109558] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"297.355453ms","start":"2026-10-07T03:47:37.23187Z","end":"2026-10-07T03:47:37.529226Z","steps":["trace[862109558] 'agreement among raft nodes before linearized reading' (duration: 297.185229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.529224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.837782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-07T03:47:37.529319Z","caller":"traceutil/trace.go:171","msg":"trace[63273202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"147.003695ms","start":"2026-10-07T03:47:37.382295Z","end":"2026-10-07T03:47:37.529299Z","steps":["trace[63273202] 'agreement among raft nodes before linearized reading' (duration: 146.826972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.529417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.634228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-10-07T03:47:37.529467Z","caller":"traceutil/trace.go:171","msg":"trace[869179102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:310; }","duration":"247.73441ms","start":"2026-10-07T03:47:37.281715Z","end":"2026-10-07T03:47:37.529449Z","steps":["trace[869179102] 'agreement among raft nodes before linearized reading' (duration: 247.637788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.52944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.265981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-10-07T03:47:37.52955Z","caller":"traceutil/trace.go:171","msg":"trace[201589181] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:310; }","duration":"197.448474ms","start":"2026-10-07T03:47:37.332078Z","end":"2026-10-07T03:47:37.529526Z","steps":["trace[201589181] 'agreement among raft nodes before linearized reading' (duration: 197.23353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.529862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:47:37.147086Z","time spent":"381.960059ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4773,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T03:47:37.64529Z","caller":"traceutil/trace.go:171","msg":"trace[1982132178] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"107.02524ms","start":"2026-10-07T03:47:37.538238Z","end":"2026-10-07T03:47:37.645264Z","steps":["trace[1982132178] 'process raft request' (duration: 106.954658ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.645344Z","caller":"traceutil/trace.go:171","msg":"trace[1282688617] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"107.096351ms","start":"2026-10-07T03:47:37.538228Z","end":"2026-10-07T03:47:37.645325Z","steps":["trace[1282688617] 'process raft request' (duration: 95.222992ms)","trace[1282688617] 'compare' (duration: 11.517242ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:37.73036Z","caller":"traceutil/trace.go:171","msg":"trace[95882176] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"190.107639ms","start":"2026-10-07T03:47:37.540225Z","end":"2026-10-07T03:47:37.730333Z","steps":["trace[95882176] 'process raft request' (duration: 189.948696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.730496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.868591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-07T03:47:37.730547Z","caller":"traceutil/trace.go:171","msg":"trace[1587152718] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:316; }","duration":"148.004983ms","start":"2026-10-07T03:47:37.582532Z","end":"2026-10-07T03:47:37.730537Z","steps":["trace[1587152718] 'agreement among raft nodes before linearized reading' (duration: 147.883041ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.730366Z","caller":"traceutil/trace.go:171","msg":"trace[1662476821] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:320; }","duration":"147.726369ms","start":"2026-10-07T03:47:37.582615Z","end":"2026-10-07T03:47:37.730342Z","steps":["trace[1662476821] 'read index received' (duration: 50.762515ms)","trace[1662476821] 'applied index is now lower than readState.Index' (duration: 96.962884ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:37.730553Z","caller":"traceutil/trace.go:171","msg":"trace[414189007] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"186.156625ms","start":"2026-10-07T03:47:37.544378Z","end":"2026-10-07T03:47:37.730534Z","steps":["trace[414189007] 'process raft request' (duration: 185.87131ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.730779Z","caller":"traceutil/trace.go:171","msg":"trace[856122369] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"190.986845ms","start":"2026-10-07T03:47:37.539683Z","end":"2026-10-07T03:47:37.730669Z","steps":["trace[856122369] 'process raft request' (duration: 190.382644ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.730426Z","caller":"traceutil/trace.go:171","msg":"trace[1805611219] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"189.271333ms","start":"2026-10-07T03:47:37.541093Z","end":"2026-10-07T03:47:37.730364Z","steps":["trace[1805611219] 'process raft request' (duration: 189.12119ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:48:16.587524Z","caller":"traceutil/trace.go:171","msg":"trace[1054268821] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"134.510517ms","start":"2026-10-07T03:48:16.452967Z","end":"2026-10-07T03:48:16.587477Z","steps":["trace[1054268821] 'process raft request' (duration: 128.242632ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:48:16.908201Z","caller":"traceutil/trace.go:171","msg":"trace[114953796] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"170.192523ms","start":"2026-10-07T03:48:16.737978Z","end":"2026-10-07T03:48:16.908171Z","steps":["trace[114953796] 'read index received' (duration: 170.06351ms)","trace[114953796] 'applied index is now lower than readState.Index' (duration: 128.003µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:48:16.908319Z","caller":"traceutil/trace.go:171","msg":"trace[1227868938] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"247.245238ms","start":"2026-10-07T03:48:16.661021Z","end":"2026-10-07T03:48:16.908267Z","steps":["trace[1227868938] 'process raft request' (duration: 247.030564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:48:16.908506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.496388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-07T03:48:16.90867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.586106ms","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-10-07T03:48:16.90871Z","caller":"traceutil/trace.go:171","msg":"trace[383605558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"114.687878ms","start":"2026-10-07T03:48:16.79401Z","end":"2026-10-07T03:48:16.908698Z","steps":["trace[383605558] 'agreement among raft nodes before linearized reading' (duration: 114.435233ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:48:16.908662Z","caller":"traceutil/trace.go:171","msg":"trace[1070837282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"170.702622ms","start":"2026-10-07T03:48:16.737934Z","end":"2026-10-07T03:48:16.908637Z","steps":["trace[1070837282] 'agreement among raft nodes before linearized reading' (duration: 170.424736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:48:17.387261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.612998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5xtjd\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-10-07T03:48:17.387377Z","caller":"traceutil/trace.go:171","msg":"trace[382352160] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5xtjd; range_end:; response_count:1; response_revision:609; }","duration":"333.776842ms","start":"2026-10-07T03:48:17.053572Z","end":"2026-10-07T03:48:17.387349Z","steps":["trace[382352160] 'range keys from in-memory index tree' (duration: 333.415335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:48:17.387652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:48:17.053545Z","time spent":"334.087457ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16981,"request content":"key:\"/registry/pods/kube-system/cilium-5xtjd\" "} {"level":"info","ts":"2026-10-07T03:48:17.400169Z","caller":"traceutil/trace.go:171","msg":"trace[424426075] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"101.203927ms","start":"2026-10-07T03:48:17.298938Z","end":"2026-10-07T03:48:17.400142Z","steps":["trace[424426075] 'process raft request' (duration: 100.703838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:48:33.441443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.066281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pqbx8\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-10-07T03:48:33.441545Z","caller":"traceutil/trace.go:171","msg":"trace[321465106] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pqbx8; range_end:; response_count:1; response_revision:777; }","duration":"301.223104ms","start":"2026-10-07T03:48:33.140297Z","end":"2026-10-07T03:48:33.44152Z","steps":["trace[321465106] 'range keys from in-memory index tree' (duration: 300.890098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:48:33.441609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:48:33.140273Z","time spent":"301.325946ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pqbx8\" "} {"level":"info","ts":"2026-10-07T03:50:01.689069Z","caller":"traceutil/trace.go:171","msg":"trace[258856020] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"236.359263ms","start":"2026-10-07T03:50:01.452679Z","end":"2026-10-07T03:50:01.689039Z","steps":["trace[258856020] 'process raft request' (duration: 200.41451ms)","trace[258856020] 'compare' (duration: 35.583876ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:50:01.689065Z","caller":"traceutil/trace.go:171","msg":"trace[42938857] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2022; }","duration":"193.653634ms","start":"2026-10-07T03:50:01.495343Z","end":"2026-10-07T03:50:01.688998Z","steps":["trace[42938857] 'read index received' (duration: 157.781283ms)","trace[42938857] 'applied index is now lower than readState.Index' (duration: 35.870651ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:50:01.689278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.916949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-07T03:50:01.68934Z","caller":"traceutil/trace.go:171","msg":"trace[653596283] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1948; }","duration":"194.02578ms","start":"2026-10-07T03:50:01.495298Z","end":"2026-10-07T03:50:01.689324Z","steps":["trace[653596283] 'agreement among raft nodes before linearized reading' (duration: 193.802296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:50:01.689515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.329411ms","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-10-07T03:50:01.689572Z","caller":"traceutil/trace.go:171","msg":"trace[1351430732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1948; }","duration":"113.410983ms","start":"2026-10-07T03:50:01.576152Z","end":"2026-10-07T03:50:01.689563Z","steps":["trace[1351430732] 'agreement among raft nodes before linearized reading' (duration: 113.290921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:50:01.689582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.689085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-07T03:50:01.689669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.038716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:50:01.689665Z","caller":"traceutil/trace.go:171","msg":"trace[140198673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1948; }","duration":"146.844298ms","start":"2026-10-07T03:50:01.542794Z","end":"2026-10-07T03:50:01.689638Z","steps":["trace[140198673] 'agreement among raft nodes before linearized reading' (duration: 146.592834ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:01.689696Z","caller":"traceutil/trace.go:171","msg":"trace[1141200549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1948; }","duration":"120.101437ms","start":"2026-10-07T03:50:01.569588Z","end":"2026-10-07T03:50:01.689689Z","steps":["trace[1141200549] 'agreement among raft nodes before linearized reading' (duration: 120.060907ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:01.858159Z","caller":"traceutil/trace.go:171","msg":"trace[745933385] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"164.11351ms","start":"2026-10-07T03:50:01.693996Z","end":"2026-10-07T03:50:01.858109Z","steps":["trace[745933385] 'process raft request' (duration: 105.696637ms)","trace[745933385] 'compare' (duration: 58.255571ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:50:04.938535Z","caller":"traceutil/trace.go:171","msg":"trace[957124313] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"181.813591ms","start":"2026-10-07T03:50:04.756694Z","end":"2026-10-07T03:50:04.938508Z","steps":["trace[957124313] 'process raft request' (duration: 181.655208ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:04.942393Z","caller":"traceutil/trace.go:171","msg":"trace[1584753658] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"162.603322ms","start":"2026-10-07T03:50:04.779762Z","end":"2026-10-07T03:50:04.942365Z","steps":["trace[1584753658] 'process raft request' (duration: 162.48735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:50:38.320425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.023166ms","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:5"} {"level":"info","ts":"2026-10-07T03:50:38.320603Z","caller":"traceutil/trace.go:171","msg":"trace[414930495] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2157; }","duration":"139.288631ms","start":"2026-10-07T03:50:38.18129Z","end":"2026-10-07T03:50:38.320578Z","steps":["trace[414930495] 'count revisions from in-memory index tree' (duration: 138.680589ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:38.586446Z","caller":"traceutil/trace.go:171","msg":"trace[900350809] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2240; }","duration":"259.2895ms","start":"2026-10-07T03:50:38.327133Z","end":"2026-10-07T03:50:38.586422Z","steps":["trace[900350809] 'read index received' (duration: 55.852291ms)","trace[900350809] 'applied index is now lower than readState.Index' (duration: 203.435789ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:50:38.586776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.613786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T03:50:38.586538Z","caller":"traceutil/trace.go:171","msg":"trace[1468697713] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"260.713287ms","start":"2026-10-07T03:50:38.325773Z","end":"2026-10-07T03:50:38.586486Z","steps":["trace[1468697713] 'process raft request' (duration: 192.532983ms)","trace[1468697713] 'compare' (duration: 68.008481ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:50:38.586856Z","caller":"traceutil/trace.go:171","msg":"trace[739329230] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2159; }","duration":"259.713118ms","start":"2026-10-07T03:50:38.327122Z","end":"2026-10-07T03:50:38.586835Z","steps":["trace[739329230] 'agreement among raft nodes before linearized reading' (duration: 259.542174ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:51:20.173626Z","caller":"traceutil/trace.go:171","msg":"trace[2045906634] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"158.515543ms","start":"2026-10-07T03:51:20.015086Z","end":"2026-10-07T03:51:20.173602Z","steps":["trace[2045906634] 'process raft request' (duration: 157.230028ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:51:20.173961Z","caller":"traceutil/trace.go:171","msg":"trace[1679192047] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"137.648681ms","start":"2026-10-07T03:51:20.036296Z","end":"2026-10-07T03:51:20.173945Z","steps":["trace[1679192047] 'process raft request' (duration: 137.233203ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:51:53.443754Z","caller":"traceutil/trace.go:171","msg":"trace[948566963] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"123.095387ms","start":"2026-10-07T03:51:53.320611Z","end":"2026-10-07T03:51:53.443706Z","steps":["trace[948566963] 'process raft request' (duration: 122.856603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:51:53.728909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.077616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:51:53.72899Z","caller":"traceutil/trace.go:171","msg":"trace[203905177] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2762; }","duration":"193.216328ms","start":"2026-10-07T03:51:53.535752Z","end":"2026-10-07T03:51:53.728968Z","steps":["trace[203905177] 'count revisions from in-memory index tree' (duration: 192.989814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:51:53.925324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.757064ms","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-10-07T03:51:53.925421Z","caller":"traceutil/trace.go:171","msg":"trace[1433881392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2764; }","duration":"129.882896ms","start":"2026-10-07T03:51:53.795514Z","end":"2026-10-07T03:51:53.925397Z","steps":["trace[1433881392] 'agreement among raft nodes before linearized reading' (duration: 129.628051ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:43.8915Z","caller":"traceutil/trace.go:171","msg":"trace[708786658] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"147.792976ms","start":"2026-10-07T03:56:43.743684Z","end":"2026-10-07T03:56:43.891477Z","steps":["trace[708786658] 'process raft request' (duration: 147.48273ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:43.892467Z","caller":"traceutil/trace.go:171","msg":"trace[1889340700] linearizableReadLoop","detail":"{readStateIndex:5289; appliedIndex:5287; }","duration":"123.432479ms","start":"2026-10-07T03:56:43.769009Z","end":"2026-10-07T03:56:43.892442Z","steps":["trace[1889340700] 'read index received' (duration: 122.264666ms)","trace[1889340700] 'applied index is now lower than readState.Index' (duration: 1.166453ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:43.892756Z","caller":"traceutil/trace.go:171","msg":"trace[2027232555] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"124.06131ms","start":"2026-10-07T03:56:43.768682Z","end":"2026-10-07T03:56:43.892744Z","steps":["trace[2027232555] 'process raft request' (duration: 123.629272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:43.893157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.134472ms","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-10-07T03:56:43.893208Z","caller":"traceutil/trace.go:171","msg":"trace[508092492] 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:5094; }","duration":"124.244044ms","start":"2026-10-07T03:56:43.768948Z","end":"2026-10-07T03:56:43.893192Z","steps":["trace[508092492] 'agreement among raft nodes before linearized reading' (duration: 124.04013ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:46.093457Z","caller":"traceutil/trace.go:171","msg":"trace[998961012] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"145.201298ms","start":"2026-10-07T03:56:45.948225Z","end":"2026-10-07T03:56:46.093427Z","steps":["trace[998961012] 'process raft request' (duration: 145.044365ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:48.253593Z","caller":"traceutil/trace.go:171","msg":"trace[313306948] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5441; }","duration":"105.115094ms","start":"2026-10-07T03:56:48.148446Z","end":"2026-10-07T03:56:48.253561Z","steps":["trace[313306948] 'read index received' (duration: 104.971101ms)","trace[313306948] 'applied index is now lower than readState.Index' (duration: 142.543µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:48.253793Z","caller":"traceutil/trace.go:171","msg":"trace[1850537328] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"108.545589ms","start":"2026-10-07T03:56:48.145195Z","end":"2026-10-07T03:56:48.253741Z","steps":["trace[1850537328] 'process raft request' (duration: 108.172852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:48.253964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.500172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62850"} {"level":"info","ts":"2026-10-07T03:56:48.254007Z","caller":"traceutil/trace.go:171","msg":"trace[425984923] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5242; }","duration":"105.598623ms","start":"2026-10-07T03:56:48.148396Z","end":"2026-10-07T03:56:48.253995Z","steps":["trace[425984923] 'agreement among raft nodes before linearized reading' (duration: 105.349428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:01.930728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.130628ms","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-10-07T03:57:01.930831Z","caller":"traceutil/trace.go:171","msg":"trace[1650391936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5482; }","duration":"230.291591ms","start":"2026-10-07T03:57:01.70052Z","end":"2026-10-07T03:57:01.930811Z","steps":["trace[1650391936] 'range keys from in-memory index tree' (duration: 229.887444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:01.931602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.527818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-07T03:57:01.931684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.466869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-07T03:57:01.931839Z","caller":"traceutil/trace.go:171","msg":"trace[2144834796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5482; }","duration":"263.627042ms","start":"2026-10-07T03:57:01.668145Z","end":"2026-10-07T03:57:01.931772Z","steps":["trace[2144834796] 'range keys from in-memory index tree' (duration: 262.521012ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:01.932746Z","caller":"traceutil/trace.go:171","msg":"trace[269932544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5482; }","duration":"228.666171ms","start":"2026-10-07T03:57:01.70301Z","end":"2026-10-07T03:57:01.931677Z","steps":["trace[269932544] 'range keys from in-memory index tree' (duration: 227.676502ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.15828Z","caller":"traceutil/trace.go:171","msg":"trace[1821467909] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"139.247524ms","start":"2026-10-07T03:57:10.019001Z","end":"2026-10-07T03:57:10.158249Z","steps":["trace[1821467909] 'process raft request' (duration: 139.02114ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.158459Z","caller":"traceutil/trace.go:171","msg":"trace[1262061472] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"139.415057ms","start":"2026-10-07T03:57:10.018993Z","end":"2026-10-07T03:57:10.158408Z","steps":["trace[1262061472] 'process raft request' (duration: 139.204483ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.184653Z","caller":"traceutil/trace.go:171","msg":"trace[514425273] linearizableReadLoop","detail":"{readStateIndex:5873; appliedIndex:5870; }","duration":"149.574937ms","start":"2026-10-07T03:57:10.03502Z","end":"2026-10-07T03:57:10.184595Z","steps":["trace[514425273] 'read index received' (duration: 122.945691ms)","trace[514425273] 'applied index is now lower than readState.Index' (duration: 26.627696ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:57:10.184782Z","caller":"traceutil/trace.go:171","msg":"trace[516439979] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"165.368251ms","start":"2026-10-07T03:57:10.019373Z","end":"2026-10-07T03:57:10.184741Z","steps":["trace[516439979] 'process raft request' (duration: 165.084196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.184947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.894583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-10-07T03:57:10.184991Z","caller":"traceutil/trace.go:171","msg":"trace[1857405354] range","detail":"{range_begin:/registry/masterleases/162.253.55.3; range_end:; response_count:1; response_revision:5662; }","duration":"150.001415ms","start":"2026-10-07T03:57:10.034976Z","end":"2026-10-07T03:57:10.184977Z","steps":["trace[1857405354] 'agreement among raft nodes before linearized reading' (duration: 149.780571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.185407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.211582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad1df1ad-9fdf-4588-978d-0aec6e6c04fb\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-07T03:57:10.185458Z","caller":"traceutil/trace.go:171","msg":"trace[659380556] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad1df1ad-9fdf-4588-978d-0aec6e6c04fb; range_end:; response_count:1; response_revision:5662; }","duration":"104.314904ms","start":"2026-10-07T03:57:10.081127Z","end":"2026-10-07T03:57:10.185442Z","steps":["trace[659380556] 'agreement among raft nodes before linearized reading' (duration: 104.1118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.367845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.425144ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-10-07T03:57:10.367983Z","caller":"traceutil/trace.go:171","msg":"trace[1759786174] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5873; }","duration":"162.812364ms","start":"2026-10-07T03:57:10.20515Z","end":"2026-10-07T03:57:10.367963Z","steps":["trace[1759786174] 'read index received' (duration: 42.044174ms)","trace[1759786174] 'applied index is now lower than readState.Index' (duration: 120.7662ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:57:10.368277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.117099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:4472"} {"level":"info","ts":"2026-10-07T03:57:10.368329Z","caller":"traceutil/trace.go:171","msg":"trace[1745651845] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5662; }","duration":"163.228632ms","start":"2026-10-07T03:57:10.205088Z","end":"2026-10-07T03:57:10.368316Z","steps":["trace[1745651845] 'agreement among raft nodes before linearized reading' (duration: 163.017488ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.532992Z","caller":"traceutil/trace.go:171","msg":"trace[913810237] linearizableReadLoop","detail":"{readStateIndex:5876; appliedIndex:5875; }","duration":"119.097438ms","start":"2026-10-07T03:57:10.413865Z","end":"2026-10-07T03:57:10.532963Z","steps":["trace[913810237] 'read index received' (duration: 110.022739ms)","trace[913810237] 'applied index is now lower than readState.Index' (duration: 9.072819ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:57:10.533071Z","caller":"traceutil/trace.go:171","msg":"trace[1723569914] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"154.842276ms","start":"2026-10-07T03:57:10.378198Z","end":"2026-10-07T03:57:10.533041Z","steps":["trace[1723569914] 'process raft request' (duration: 145.592183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.533282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.467597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvqhc\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-07T03:57:10.533329Z","caller":"traceutil/trace.go:171","msg":"trace[1268882200] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvqhc; range_end:; response_count:1; response_revision:5664; }","duration":"141.534368ms","start":"2026-10-07T03:57:10.391782Z","end":"2026-10-07T03:57:10.533316Z","steps":["trace[1268882200] 'agreement among raft nodes before linearized reading' (duration: 141.317654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.533512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.690424ms","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-10-07T03:57:10.533601Z","caller":"traceutil/trace.go:171","msg":"trace[8464337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5664; }","duration":"145.866748ms","start":"2026-10-07T03:57:10.387716Z","end":"2026-10-07T03:57:10.533582Z","steps":["trace[8464337] 'agreement among raft nodes before linearized reading' (duration: 145.508211ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.584651Z","caller":"traceutil/trace.go:171","msg":"trace[309493034] transaction","detail":"{read_only:false; response_revision:5665; number_of_response:1; }","duration":"165.427513ms","start":"2026-10-07T03:57:10.419159Z","end":"2026-10-07T03:57:10.584587Z","steps":["trace[309493034] 'process raft request' (duration: 165.133827ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.584922Z","caller":"traceutil/trace.go:171","msg":"trace[437782155] transaction","detail":"{read_only:false; response_revision:5666; number_of_response:1; }","duration":"112.180361ms","start":"2026-10-07T03:57:10.472715Z","end":"2026-10-07T03:57:10.584895Z","steps":["trace[437782155] 'process raft request' (duration: 111.816815ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.669348Z","caller":"traceutil/trace.go:171","msg":"trace[855249396] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"136.969172ms","start":"2026-10-07T03:57:10.532358Z","end":"2026-10-07T03:57:10.669328Z","steps":["trace[855249396] 'process raft request' (duration: 136.124867ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.669702Z","caller":"traceutil/trace.go:171","msg":"trace[437719161] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"128.737239ms","start":"2026-10-07T03:57:10.540946Z","end":"2026-10-07T03:57:10.669683Z","steps":["trace[437719161] 'process raft request' (duration: 128.621417ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.669699Z","caller":"traceutil/trace.go:171","msg":"trace[429658191] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"128.908562ms","start":"2026-10-07T03:57:10.540769Z","end":"2026-10-07T03:57:10.669677Z","steps":["trace[429658191] 'process raft request' (duration: 128.721498ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.669921Z","caller":"traceutil/trace.go:171","msg":"trace[697836529] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"130.529582ms","start":"2026-10-07T03:57:10.539358Z","end":"2026-10-07T03:57:10.669887Z","steps":["trace[697836529] 'process raft request' (duration: 129.9181ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.670165Z","caller":"traceutil/trace.go:171","msg":"trace[1410779010] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"128.891352ms","start":"2026-10-07T03:57:10.541253Z","end":"2026-10-07T03:57:10.670145Z","steps":["trace[1410779010] 'process raft request' (duration: 128.661637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.67051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.164096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-10-07T03:57:10.670575Z","caller":"traceutil/trace.go:171","msg":"trace[1918164648] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5674; }","duration":"254.272737ms","start":"2026-10-07T03:57:10.416289Z","end":"2026-10-07T03:57:10.670561Z","steps":["trace[1918164648] 'agreement among raft nodes before linearized reading' (duration: 254.132735ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.670214Z","caller":"traceutil/trace.go:171","msg":"trace[408901340] transaction","detail":"{read_only:false; response_revision:5673; number_of_response:1; }","duration":"128.952243ms","start":"2026-10-07T03:57:10.541243Z","end":"2026-10-07T03:57:10.670195Z","steps":["trace[408901340] 'process raft request' (duration: 128.775629ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.670279Z","caller":"traceutil/trace.go:171","msg":"trace[2077737197] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"129.096116ms","start":"2026-10-07T03:57:10.541167Z","end":"2026-10-07T03:57:10.670264Z","steps":["trace[2077737197] 'process raft request' (duration: 128.438613ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.670382Z","caller":"traceutil/trace.go:171","msg":"trace[1488728177] linearizableReadLoop","detail":"{readStateIndex:5879; appliedIndex:5876; }","duration":"137.269037ms","start":"2026-10-07T03:57:10.53307Z","end":"2026-10-07T03:57:10.670339Z","steps":["trace[1488728177] 'read index received' (duration: 51.324846ms)","trace[1488728177] 'applied index is now lower than readState.Index' (duration: 85.90064ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:57:10.670395Z","caller":"traceutil/trace.go:171","msg":"trace[1985830515] transaction","detail":"{read_only:false; response_revision:5674; number_of_response:1; }","duration":"124.654303ms","start":"2026-10-07T03:57:10.545727Z","end":"2026-10-07T03:57:10.670381Z","steps":["trace[1985830515] 'process raft request' (duration: 124.342837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:57:10.671185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.566941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80075"} {"level":"warn","ts":"2026-10-07T03:57:10.671163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.614491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ad1df1ad-9fdf-4588-978d-0aec6e6c04fb\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-07T03:57:10.671229Z","caller":"traceutil/trace.go:171","msg":"trace[2042713012] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5674; }","duration":"131.642303ms","start":"2026-10-07T03:57:10.539574Z","end":"2026-10-07T03:57:10.671216Z","steps":["trace[2042713012] 'agreement among raft nodes before linearized reading' (duration: 131.418509ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:10.67128Z","caller":"traceutil/trace.go:171","msg":"trace[1099617706] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ad1df1ad-9fdf-4588-978d-0aec6e6c04fb; range_end:; response_count:1; response_revision:5674; }","duration":"132.777085ms","start":"2026-10-07T03:57:10.538472Z","end":"2026-10-07T03:57:10.671249Z","steps":["trace[1099617706] 'agreement among raft nodes before linearized reading' (duration: 132.490809ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:21.579344Z","caller":"traceutil/trace.go:171","msg":"trace[102674006] transaction","detail":"{read_only:false; response_revision:5803; number_of_response:1; }","duration":"106.555965ms","start":"2026-10-07T03:57:21.47275Z","end":"2026-10-07T03:57:21.579306Z","steps":["trace[102674006] 'process raft request' (duration: 106.377392ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:21.579689Z","caller":"traceutil/trace.go:171","msg":"trace[756625322] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"106.681888ms","start":"2026-10-07T03:57:21.472976Z","end":"2026-10-07T03:57:21.579658Z","steps":["trace[756625322] 'process raft request' (duration: 106.25997ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:21.580221Z","caller":"traceutil/trace.go:171","msg":"trace[1365948485] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"107.221098ms","start":"2026-10-07T03:57:21.472966Z","end":"2026-10-07T03:57:21.580187Z","steps":["trace[1365948485] 'process raft request' (duration: 106.537625ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:57:26.756487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2947} {"level":"info","ts":"2026-10-07T03:57:26.835543Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2947,"took":"75.964256ms","hash":3081747925,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":32006144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T03:57:26.835658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3081747925,"revision":2947,"compact-revision":-1} {"level":"info","ts":"2026-10-07T04:02:26.764516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5846} {"level":"info","ts":"2026-10-07T04:02:26.858217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5846,"took":"91.102924ms","hash":1309466302,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":26378240,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T04:02:26.858291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1309466302,"revision":5846,"compact-revision":2947} {"level":"info","ts":"2026-10-07T04:03:25.075439Z","caller":"traceutil/trace.go:171","msg":"trace[1627470118] linearizableReadLoop","detail":"{readStateIndex:8838; appliedIndex:8837; }","duration":"243.885093ms","start":"2026-10-07T04:03:24.831528Z","end":"2026-10-07T04:03:25.075413Z","steps":["trace[1627470118] 'read index received' (duration: 243.71649ms)","trace[1627470118] 'applied index is now lower than readState.Index' (duration: 167.463µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:03:25.075756Z","caller":"traceutil/trace.go:171","msg":"trace[1566612435] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"281.779231ms","start":"2026-10-07T04:03:24.79396Z","end":"2026-10-07T04:03:25.07574Z","steps":["trace[1566612435] 'process raft request' (duration: 281.298462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:25.076056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.507704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-07T04:03:25.076093Z","caller":"traceutil/trace.go:171","msg":"trace[587036284] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8534; }","duration":"244.579306ms","start":"2026-10-07T04:03:24.831501Z","end":"2026-10-07T04:03:25.07608Z","steps":["trace[587036284] 'agreement among raft nodes before linearized reading' (duration: 244.437923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:25.076257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.455303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T04:03:25.076296Z","caller":"traceutil/trace.go:171","msg":"trace[1771613978] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8534; }","duration":"206.555175ms","start":"2026-10-07T04:03:24.869729Z","end":"2026-10-07T04:03:25.076284Z","steps":["trace[1771613978] 'agreement among raft nodes before linearized reading' (duration: 206.493433ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:25.240427Z","caller":"traceutil/trace.go:171","msg":"trace[1184626390] linearizableReadLoop","detail":"{readStateIndex:8839; appliedIndex:8838; }","duration":"138.947544ms","start":"2026-10-07T04:03:25.101451Z","end":"2026-10-07T04:03:25.240398Z","steps":["trace[1184626390] 'read index received' (duration: 137.157049ms)","trace[1184626390] 'applied index is now lower than readState.Index' (duration: 1.789265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:03:25.24078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.308331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvqhc\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-07T04:03:25.240904Z","caller":"traceutil/trace.go:171","msg":"trace[356815640] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvqhc; range_end:; response_count:1; response_revision:8535; }","duration":"139.499995ms","start":"2026-10-07T04:03:25.101385Z","end":"2026-10-07T04:03:25.240885Z","steps":["trace[356815640] 'agreement among raft nodes before linearized reading' (duration: 139.139238ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:25.241525Z","caller":"traceutil/trace.go:171","msg":"trace[1969075311] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"159.367361ms","start":"2026-10-07T04:03:25.081114Z","end":"2026-10-07T04:03:25.240482Z","steps":["trace[1969075311] 'process raft request' (duration: 157.577226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:37.486238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.102825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nvqhc\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-07T04:03:37.486341Z","caller":"traceutil/trace.go:171","msg":"trace[2002255366] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nvqhc; range_end:; response_count:1; response_revision:8598; }","duration":"160.255358ms","start":"2026-10-07T04:03:37.326049Z","end":"2026-10-07T04:03:37.486304Z","steps":["trace[2002255366] 'range keys from in-memory index tree' (duration: 159.908532ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:37.486549Z","caller":"traceutil/trace.go:171","msg":"trace[660057700] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"129.882472ms","start":"2026-10-07T04:03:37.356646Z","end":"2026-10-07T04:03:37.486528Z","steps":["trace[660057700] 'process raft request' (duration: 124.210164ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:38.988156Z","caller":"traceutil/trace.go:171","msg":"trace[243440320] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"204.476176ms","start":"2026-10-07T04:03:38.783641Z","end":"2026-10-07T04:03:38.988117Z","steps":["trace[243440320] 'process raft request' (duration: 204.221161ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:22.269424Z","caller":"traceutil/trace.go:171","msg":"trace[1960598693] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"161.053823ms","start":"2026-10-07T04:04:22.10834Z","end":"2026-10-07T04:04:22.269394Z","steps":["trace[1960598693] 'process raft request' (duration: 136.029619ms)","trace[1960598693] 'compare' (duration: 24.82097ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:04:22.362302Z","caller":"traceutil/trace.go:171","msg":"trace[1954215983] linearizableReadLoop","detail":"{readStateIndex:9244; appliedIndex:9242; }","duration":"245.250839ms","start":"2026-10-07T04:04:22.117019Z","end":"2026-10-07T04:04:22.36227Z","steps":["trace[1954215983] 'read index received' (duration: 127.527637ms)","trace[1954215983] 'applied index is now lower than readState.Index' (duration: 117.722102ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:04:22.3623Z","caller":"traceutil/trace.go:171","msg":"trace[1860176151] transaction","detail":"{read_only:false; response_revision:8924; number_of_response:1; }","duration":"252.754032ms","start":"2026-10-07T04:04:22.109488Z","end":"2026-10-07T04:04:22.362242Z","steps":["trace[1860176151] 'process raft request' (duration: 252.491277ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:22.362413Z","caller":"traceutil/trace.go:171","msg":"trace[1168630368] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"238.524991ms","start":"2026-10-07T04:04:22.123847Z","end":"2026-10-07T04:04:22.362372Z","steps":["trace[1168630368] 'process raft request' (duration: 238.322067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.36248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.428443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T04:04:22.362533Z","caller":"traceutil/trace.go:171","msg":"trace[1424567240] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:8925; }","duration":"245.523685ms","start":"2026-10-07T04:04:22.116995Z","end":"2026-10-07T04:04:22.362518Z","steps":["trace[1424567240] 'agreement among raft nodes before linearized reading' (duration: 245.389613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.50589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.91463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-07T04:04:22.506157Z","caller":"traceutil/trace.go:171","msg":"trace[1667342077] linearizableReadLoop","detail":"{readStateIndex:9246; appliedIndex:9245; }","duration":"143.745584ms","start":"2026-10-07T04:04:22.362387Z","end":"2026-10-07T04:04:22.506133Z","steps":["trace[1667342077] 'read index received' (duration: 20.421996ms)","trace[1667342077] 'applied index is now lower than readState.Index' (duration: 123.321668ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:04:22.506297Z","caller":"traceutil/trace.go:171","msg":"trace[742720965] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"213.11297ms","start":"2026-10-07T04:04:22.29308Z","end":"2026-10-07T04:04:22.506193Z","steps":["trace[742720965] 'process raft request' (duration: 89.779332ms)","trace[742720965] 'compare' (duration: 122.753186ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:04:22.506305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.296392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-07T04:04:22.506385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.589908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5bg4n\" ","response":"range_response_count:1 size:4646"} {"level":"info","ts":"2026-10-07T04:04:22.506397Z","caller":"traceutil/trace.go:171","msg":"trace[1460681433] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8927; }","duration":"166.445325ms","start":"2026-10-07T04:04:22.339928Z","end":"2026-10-07T04:04:22.506373Z","steps":["trace[1460681433] 'agreement among raft nodes before linearized reading' (duration: 166.228071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.506465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.508323ms","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:708"} {"level":"info","ts":"2026-10-07T04:04:22.506578Z","caller":"traceutil/trace.go:171","msg":"trace[991940837] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8927; }","duration":"140.634026ms","start":"2026-10-07T04:04:22.365899Z","end":"2026-10-07T04:04:22.506533Z","steps":["trace[991940837] 'agreement among raft nodes before linearized reading' (duration: 140.413352ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:22.506415Z","caller":"traceutil/trace.go:171","msg":"trace[1263606397] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5bg4n; range_end:; response_count:1; response_revision:8927; }","duration":"214.676249ms","start":"2026-10-07T04:04:22.291729Z","end":"2026-10-07T04:04:22.506405Z","steps":["trace[1263606397] 'agreement among raft nodes before linearized reading' (duration: 214.529316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.5068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.594066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T04:04:22.506843Z","caller":"traceutil/trace.go:171","msg":"trace[306420439] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:0; response_revision:8927; }","duration":"138.653228ms","start":"2026-10-07T04:04:22.36818Z","end":"2026-10-07T04:04:22.506834Z","steps":["trace[306420439] 'agreement among raft nodes before linearized reading' (duration: 138.592137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.507167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.145407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225394"} {"level":"info","ts":"2026-10-07T04:04:22.507195Z","caller":"traceutil/trace.go:171","msg":"trace[340259998] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8927; }","duration":"245.233548ms","start":"2026-10-07T04:04:22.261954Z","end":"2026-10-07T04:04:22.507188Z","steps":["trace[340259998] 'agreement among raft nodes before linearized reading' (duration: 244.518924ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:22.634813Z","caller":"traceutil/trace.go:171","msg":"trace[1706986328] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"120.019875ms","start":"2026-10-07T04:04:22.514762Z","end":"2026-10-07T04:04:22.634782Z","steps":["trace[1706986328] 'process raft request' (duration: 117.178211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:22.688821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.527287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-07T04:04:22.688926Z","caller":"traceutil/trace.go:171","msg":"trace[1272617142] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8931; }","duration":"108.67872ms","start":"2026-10-07T04:04:22.580228Z","end":"2026-10-07T04:04:22.688906Z","steps":["trace[1272617142] 'agreement among raft nodes before linearized reading' (duration: 108.430445ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:22.709409Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002426.wal"} {"level":"info","ts":"2026-10-07T04:05:34.808235Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a99b527d1e65fdd9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:05:34.814816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-07T04:05:34.814936Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-07T04:07:26.779025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8016} {"level":"info","ts":"2026-10-07T04:07:26.852951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8016,"took":"71.364009ms","hash":1918961024,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T04:07:26.853026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1918961024,"revision":8016,"compact-revision":5846} {"level":"info","ts":"2026-10-07T04:12:26.78729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10423} {"level":"info","ts":"2026-10-07T04:12:26.876598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10423,"took":"86.209815ms","hash":359551760,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":25935872,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T04:12:26.876673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":359551760,"revision":10423,"compact-revision":8016} {"level":"info","ts":"2026-10-07T04:17:26.795152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12588} {"level":"info","ts":"2026-10-07T04:17:26.880643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12588,"took":"82.400742ms","hash":2044285936,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":27152384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-07T04:17:26.880727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2044285936,"revision":12588,"compact-revision":10423} {"level":"info","ts":"2026-10-07T04:18:03.873382Z","caller":"traceutil/trace.go:171","msg":"trace[295585440] transaction","detail":"{read_only:false; response_revision:14817; number_of_response:1; }","duration":"180.337269ms","start":"2026-10-07T04:18:03.693007Z","end":"2026-10-07T04:18:03.873344Z","steps":["trace[295585440] 'process raft request' (duration: 180.092635ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:18:04.047734Z","caller":"traceutil/trace.go:171","msg":"trace[1990710052] linearizableReadLoop","detail":"{readStateIndex:15336; appliedIndex:15334; }","duration":"300.190773ms","start":"2026-10-07T04:18:03.747484Z","end":"2026-10-07T04:18:04.047674Z","steps":["trace[1990710052] 'read index received' (duration: 125.690353ms)","trace[1990710052] 'applied index is now lower than readState.Index' (duration: 174.498729ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:18:04.04778Z","caller":"traceutil/trace.go:171","msg":"trace[1724861017] transaction","detail":"{read_only:false; response_revision:14818; number_of_response:1; }","duration":"354.685676ms","start":"2026-10-07T04:18:03.693057Z","end":"2026-10-07T04:18:04.047743Z","steps":["trace[1724861017] 'process raft request' (duration: 285.934213ms)","trace[1724861017] 'compare' (duration: 67.926807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:18:04.047925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.431957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-07T04:18:04.04794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:18:03.693037Z","time spent":"354.810928ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:18:04.047968Z","caller":"traceutil/trace.go:171","msg":"trace[1356458643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14818; }","duration":"300.543929ms","start":"2026-10-07T04:18:03.747412Z","end":"2026-10-07T04:18:04.047956Z","steps":["trace[1356458643] 'agreement among raft nodes before linearized reading' (duration: 300.430857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:18:04.048001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:18:03.747385Z","time spent":"300.607351ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-07T04:18:04.052495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.988449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T04:18:04.052582Z","caller":"traceutil/trace.go:171","msg":"trace[2027626249] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:14819; }","duration":"126.169613ms","start":"2026-10-07T04:18:03.926393Z","end":"2026-10-07T04:18:04.052562Z","steps":["trace[2027626249] 'agreement among raft nodes before linearized reading' (duration: 126.0187ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:18:05.59006Z","caller":"traceutil/trace.go:171","msg":"trace[1465627354] transaction","detail":"{read_only:false; response_revision:14833; number_of_response:1; }","duration":"123.222177ms","start":"2026-10-07T04:18:05.466806Z","end":"2026-10-07T04:18:05.590028Z","steps":["trace[1465627354] 'process raft request' (duration: 123.043564ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:18:28.391293Z","caller":"traceutil/trace.go:171","msg":"trace[591759774] linearizableReadLoop","detail":"{readStateIndex:15670; appliedIndex:15669; }","duration":"297.942199ms","start":"2026-10-07T04:18:28.093317Z","end":"2026-10-07T04:18:28.391259Z","steps":["trace[591759774] 'read index received' (duration: 297.611424ms)","trace[591759774] 'applied index is now lower than readState.Index' (duration: 328.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:18:28.391444Z","caller":"traceutil/trace.go:171","msg":"trace[2112332909] transaction","detail":"{read_only:false; response_revision:15138; number_of_response:1; }","duration":"388.299763ms","start":"2026-10-07T04:18:28.003104Z","end":"2026-10-07T04:18:28.391404Z","steps":["trace[2112332909] 'process raft request' (duration: 387.852595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:18:28.391579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.249255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lrmp7\" ","response":"range_response_count:1 size:20652"} {"level":"warn","ts":"2026-10-07T04:18:28.391655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:18:28.003074Z","time spent":"388.453416ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:18:28.391662Z","caller":"traceutil/trace.go:171","msg":"trace[1248614818] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lrmp7; range_end:; response_count:1; response_revision:15138; }","duration":"298.379418ms","start":"2026-10-07T04:18:28.093264Z","end":"2026-10-07T04:18:28.391643Z","steps":["trace[1248614818] 'agreement among raft nodes before linearized reading' (duration: 298.154913ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:18:28.398207Z","caller":"traceutil/trace.go:171","msg":"trace[503060502] transaction","detail":"{read_only:false; response_revision:15139; number_of_response:1; }","duration":"252.528879ms","start":"2026-10-07T04:18:28.145639Z","end":"2026-10-07T04:18:28.398168Z","steps":["trace[503060502] 'process raft request' (duration: 252.269694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:18:28.398256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.822245ms","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-10-07T04:18:28.398388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.886831ms","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-10-07T04:18:28.398447Z","caller":"traceutil/trace.go:171","msg":"trace[452788448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15139; }","duration":"180.987312ms","start":"2026-10-07T04:18:28.217444Z","end":"2026-10-07T04:18:28.398432Z","steps":["trace[452788448] 'agreement among raft nodes before linearized reading' (duration: 180.788728ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:18:28.398331Z","caller":"traceutil/trace.go:171","msg":"trace[1108567075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15139; }","duration":"183.950747ms","start":"2026-10-07T04:18:28.214358Z","end":"2026-10-07T04:18:28.398308Z","steps":["trace[1108567075] 'agreement among raft nodes before linearized reading' (duration: 183.710483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:19:23.75391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.243596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-07T04:19:23.754022Z","caller":"traceutil/trace.go:171","msg":"trace[2070300415] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15786; }","duration":"114.42905ms","start":"2026-10-07T04:19:23.63957Z","end":"2026-10-07T04:19:23.753999Z","steps":["trace[2070300415] 'range keys from in-memory index tree' (duration: 114.041852ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:20:01.115183Z","caller":"traceutil/trace.go:171","msg":"trace[441651825] linearizableReadLoop","detail":"{readStateIndex:16539; appliedIndex:16538; }","duration":"131.562615ms","start":"2026-10-07T04:20:00.983593Z","end":"2026-10-07T04:20:01.115156Z","steps":["trace[441651825] 'read index received' (duration: 131.31515ms)","trace[441651825] 'applied index is now lower than readState.Index' (duration: 246.245µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:20:01.115307Z","caller":"traceutil/trace.go:171","msg":"trace[1471830478] transaction","detail":"{read_only:false; response_revision:15982; number_of_response:1; }","duration":"141.580025ms","start":"2026-10-07T04:20:00.97368Z","end":"2026-10-07T04:20:01.11526Z","steps":["trace[1471830478] 'process raft request' (duration: 141.297179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:20:01.115701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.080725ms","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-10-07T04:20:01.115794Z","caller":"traceutil/trace.go:171","msg":"trace[877260308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15982; }","duration":"132.174027ms","start":"2026-10-07T04:20:00.983575Z","end":"2026-10-07T04:20:01.115749Z","steps":["trace[877260308] 'agreement among raft nodes before linearized reading' (duration: 131.769449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:20:01.116473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.284975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-07T04:20:01.116469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.077608ms","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-10-07T04:20:01.116531Z","caller":"traceutil/trace.go:171","msg":"trace[211258646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15982; }","duration":"127.359586ms","start":"2026-10-07T04:20:00.989152Z","end":"2026-10-07T04:20:01.116512Z","steps":["trace[211258646] 'agreement among raft nodes before linearized reading' (duration: 127.158052ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:20:01.116534Z","caller":"traceutil/trace.go:171","msg":"trace[560827528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15982; }","duration":"130.182909ms","start":"2026-10-07T04:20:00.986336Z","end":"2026-10-07T04:20:01.116519Z","steps":["trace[560827528] 'agreement among raft nodes before linearized reading' (duration: 129.962755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:20:01.117248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.099361ms","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-10-07T04:20:01.118402Z","caller":"traceutil/trace.go:171","msg":"trace[1570558714] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15982; }","duration":"107.912497ms","start":"2026-10-07T04:20:01.009409Z","end":"2026-10-07T04:20:01.117321Z","steps":["trace[1570558714] 'agreement among raft nodes before linearized reading' (duration: 106.898938ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:20:01.712887Z","caller":"traceutil/trace.go:171","msg":"trace[1378120387] transaction","detail":"{read_only:false; response_revision:15987; number_of_response:1; }","duration":"121.474674ms","start":"2026-10-07T04:20:01.591377Z","end":"2026-10-07T04:20:01.712851Z","steps":["trace[1378120387] 'process raft request' (duration: 121.124767ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:22:26.803124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14612} {"level":"info","ts":"2026-10-07T04:22:26.896705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14612,"took":"89.999687ms","hash":115218934,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T04:22:26.896812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":115218934,"revision":14612,"compact-revision":12588} {"level":"info","ts":"2026-10-07T04:25:50.951822Z","caller":"traceutil/trace.go:171","msg":"trace[1097323040] transaction","detail":"{read_only:false; response_revision:18575; number_of_response:1; }","duration":"145.532069ms","start":"2026-10-07T04:25:50.806245Z","end":"2026-10-07T04:25:50.951777Z","steps":["trace[1097323040] 'process raft request' (duration: 145.084721ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:58.478182Z","caller":"traceutil/trace.go:171","msg":"trace[2127636758] transaction","detail":"{read_only:false; response_revision:18617; number_of_response:1; }","duration":"207.506984ms","start":"2026-10-07T04:25:58.270632Z","end":"2026-10-07T04:25:58.478139Z","steps":["trace[2127636758] 'process raft request' (duration: 194.520997ms)","trace[2127636758] 'compare' (duration: 12.493398ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:26:07.156185Z","caller":"traceutil/trace.go:171","msg":"trace[1268748070] transaction","detail":"{read_only:false; response_revision:18759; number_of_response:1; }","duration":"132.85419ms","start":"2026-10-07T04:26:07.023291Z","end":"2026-10-07T04:26:07.156145Z","steps":["trace[1268748070] 'process raft request' (duration: 122.485362ms)","trace[1268748070] 'compare' (duration: 10.084002ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:26:07.177573Z","caller":"traceutil/trace.go:171","msg":"trace[2071783560] transaction","detail":"{read_only:false; response_revision:18763; number_of_response:1; }","duration":"115.99285ms","start":"2026-10-07T04:26:07.061548Z","end":"2026-10-07T04:26:07.177541Z","steps":["trace[2071783560] 'process raft request' (duration: 115.945549ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:26:07.177598Z","caller":"traceutil/trace.go:171","msg":"trace[727704557] transaction","detail":"{read_only:false; response_revision:18761; number_of_response:1; }","duration":"130.75968ms","start":"2026-10-07T04:26:07.046802Z","end":"2026-10-07T04:26:07.177562Z","steps":["trace[727704557] 'process raft request' (duration: 130.554746ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:26:07.177781Z","caller":"traceutil/trace.go:171","msg":"trace[1031699523] transaction","detail":"{read_only:false; response_revision:18760; number_of_response:1; }","duration":"134.315687ms","start":"2026-10-07T04:26:07.043409Z","end":"2026-10-07T04:26:07.177725Z","steps":["trace[1031699523] 'process raft request' (duration: 133.778376ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:26:07.177853Z","caller":"traceutil/trace.go:171","msg":"trace[1525831648] transaction","detail":"{read_only:false; response_revision:18762; number_of_response:1; }","duration":"119.950834ms","start":"2026-10-07T04:26:07.057857Z","end":"2026-10-07T04:26:07.177808Z","steps":["trace[1525831648] 'process raft request' (duration: 119.555957ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:26:07.306177Z","caller":"traceutil/trace.go:171","msg":"trace[885549455] transaction","detail":"{read_only:false; response_revision:18765; number_of_response:1; }","duration":"105.522021ms","start":"2026-10-07T04:26:07.200629Z","end":"2026-10-07T04:26:07.306151Z","steps":["trace[885549455] 'process raft request' (duration: 86.875798ms)","trace[885549455] 'compare' (duration: 18.078662ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:26:07.467381Z","caller":"traceutil/trace.go:171","msg":"trace[1004421592] linearizableReadLoop","detail":"{readStateIndex:19415; appliedIndex:19413; }","duration":"150.959803ms","start":"2026-10-07T04:26:07.316397Z","end":"2026-10-07T04:26:07.467356Z","steps":["trace[1004421592] 'read index received' (duration: 24.122438ms)","trace[1004421592] 'applied index is now lower than readState.Index' (duration: 126.836365ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:26:07.467475Z","caller":"traceutil/trace.go:171","msg":"trace[699646007] transaction","detail":"{read_only:false; response_revision:18767; number_of_response:1; }","duration":"153.99681ms","start":"2026-10-07T04:26:07.313438Z","end":"2026-10-07T04:26:07.467435Z","steps":["trace[699646007] 'process raft request' (duration: 153.757166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:26:07.467672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.959233ms","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-10-07T04:26:07.467711Z","caller":"traceutil/trace.go:171","msg":"trace[2043592051] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:18767; }","duration":"151.037144ms","start":"2026-10-07T04:26:07.31666Z","end":"2026-10-07T04:26:07.467697Z","steps":["trace[2043592051] 'agreement among raft nodes before linearized reading' (duration: 150.83904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:26:07.46804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.542094ms","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:8"} {"level":"info","ts":"2026-10-07T04:26:07.46817Z","caller":"traceutil/trace.go:171","msg":"trace[1992188111] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18767; }","duration":"121.708148ms","start":"2026-10-07T04:26:07.346435Z","end":"2026-10-07T04:26:07.468143Z","steps":["trace[1992188111] 'agreement among raft nodes before linearized reading' (duration: 121.470133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:26:07.469696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.271697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-lrmp7-api\" ","response":"range_response_count:1 size:2047"} {"level":"info","ts":"2026-10-07T04:26:07.469781Z","caller":"traceutil/trace.go:171","msg":"trace[1548396174] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-lrmp7-api; range_end:; response_count:1; response_revision:18767; }","duration":"153.455151ms","start":"2026-10-07T04:26:07.316306Z","end":"2026-10-07T04:26:07.469761Z","steps":["trace[1548396174] 'agreement among raft nodes before linearized reading' (duration: 151.180417ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:27:02.555946Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a99b527d1e65fdd9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:27:02.564562Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-07T04:27:02.56472Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-07T04:27:26.812902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17058} {"level":"info","ts":"2026-10-07T04:27:26.919597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17058,"took":"102.375221ms","hash":318003307,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":33607680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-07T04:27:26.919674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":318003307,"revision":17058,"compact-revision":14612} {"level":"info","ts":"2026-10-07T04:31:48.979653Z","caller":"traceutil/trace.go:171","msg":"trace[1979119512] transaction","detail":"{read_only:false; response_revision:21318; number_of_response:1; }","duration":"264.898814ms","start":"2026-10-07T04:31:48.714706Z","end":"2026-10-07T04:31:48.979605Z","steps":["trace[1979119512] 'process raft request' (duration: 167.659159ms)","trace[1979119512] 'compare' (duration: 96.737596ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:31:48.979679Z","caller":"traceutil/trace.go:171","msg":"trace[75441084] transaction","detail":"{read_only:false; response_revision:21319; number_of_response:1; }","duration":"193.884687ms","start":"2026-10-07T04:31:48.785761Z","end":"2026-10-07T04:31:48.979646Z","steps":["trace[75441084] 'process raft request' (duration: 193.544471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.319369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.425893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-07T04:31:49.319462Z","caller":"traceutil/trace.go:171","msg":"trace[2032815894] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21319; }","duration":"236.570336ms","start":"2026-10-07T04:31:49.08287Z","end":"2026-10-07T04:31:49.31944Z","steps":["trace[2032815894] 'range keys from in-memory index tree' (duration: 236.129327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.319456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.116446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:31:49.319745Z","caller":"traceutil/trace.go:171","msg":"trace[1415257251] transaction","detail":"{read_only:false; response_revision:21320; number_of_response:1; }","duration":"229.477081ms","start":"2026-10-07T04:31:49.090227Z","end":"2026-10-07T04:31:49.319704Z","steps":["trace[1415257251] 'process raft request' (duration: 97.026579ms)","trace[1415257251] 'compare' (duration: 131.959083ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:31:49.489196Z","caller":"traceutil/trace.go:171","msg":"trace[2003932744] transaction","detail":"{read_only:false; response_revision:21321; number_of_response:1; }","duration":"165.140661ms","start":"2026-10-07T04:31:49.324017Z","end":"2026-10-07T04:31:49.489157Z","steps":["trace[2003932744] 'process raft request' (duration: 161.719266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.792225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.386317ms","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-10-07T04:31:49.792335Z","caller":"traceutil/trace.go:171","msg":"trace[1458416587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21321; }","duration":"184.52956ms","start":"2026-10-07T04:31:49.607778Z","end":"2026-10-07T04:31:49.792308Z","steps":["trace[1458416587] 'range keys from in-memory index tree' (duration: 184.159192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.792581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.464458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:31:49.792643Z","caller":"traceutil/trace.go:171","msg":"trace[2110691746] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:21321; }","duration":"117.542019ms","start":"2026-10-07T04:31:49.675085Z","end":"2026-10-07T04:31:49.792627Z","steps":["trace[2110691746] 'count revisions from in-memory index tree' (duration: 117.397876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.792779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.258964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T04:31:49.792847Z","caller":"traceutil/trace.go:171","msg":"trace[1176855601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21321; }","duration":"165.354617ms","start":"2026-10-07T04:31:49.627453Z","end":"2026-10-07T04:31:49.792808Z","steps":["trace[1176855601] 'range keys from in-memory index tree' (duration: 165.136912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:49.792781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.866423ms","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-10-07T04:31:49.79292Z","caller":"traceutil/trace.go:171","msg":"trace[735233990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21321; }","duration":"178.086437ms","start":"2026-10-07T04:31:49.614809Z","end":"2026-10-07T04:31:49.792895Z","steps":["trace[735233990] 'range keys from in-memory index tree' (duration: 177.664349ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:49.963649Z","caller":"traceutil/trace.go:171","msg":"trace[940467011] transaction","detail":"{read_only:false; response_revision:21324; number_of_response:1; }","duration":"164.530579ms","start":"2026-10-07T04:31:49.798817Z","end":"2026-10-07T04:31:49.963348Z","steps":["trace[940467011] 'process raft request' (duration: 164.336336ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:49.963713Z","caller":"traceutil/trace.go:171","msg":"trace[1875140951] transaction","detail":"{read_only:false; response_revision:21323; number_of_response:1; }","duration":"164.770915ms","start":"2026-10-07T04:31:49.798584Z","end":"2026-10-07T04:31:49.963355Z","steps":["trace[1875140951] 'process raft request' (duration: 140.149118ms)","trace[1875140951] 'compare' (duration: 24.136887ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:32:26.824366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19519} {"level":"info","ts":"2026-10-07T04:32:26.954779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19519,"took":"125.576941ms","hash":2224458766,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":28864512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-07T04:32:26.954866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2224458766,"revision":19519,"compact-revision":17058} {"level":"info","ts":"2026-10-07T04:35:55.147768Z","caller":"traceutil/trace.go:171","msg":"trace[743018637] transaction","detail":"{read_only:false; response_revision:23844; number_of_response:1; }","duration":"113.656255ms","start":"2026-10-07T04:35:55.034084Z","end":"2026-10-07T04:35:55.14774Z","steps":["trace[743018637] 'process raft request' (duration: 113.481762ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:35:55.148293Z","caller":"traceutil/trace.go:171","msg":"trace[1092354193] transaction","detail":"{read_only:false; response_revision:23845; number_of_response:1; }","duration":"108.760032ms","start":"2026-10-07T04:35:55.039506Z","end":"2026-10-07T04:35:55.148266Z","steps":["trace[1092354193] 'process raft request' (duration: 108.621719ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:37:26.831993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21596} {"level":"info","ts":"2026-10-07T04:37:26.954178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21596,"took":"117.548039ms","hash":1292063614,"current-db-size-bytes":55631872,"current-db-size":"56 MB","current-db-size-in-use-bytes":43323392,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-07T04:37:26.954261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1292063614,"revision":21596,"compact-revision":19519} {"level":"info","ts":"2026-10-07T04:39:23.312463Z","caller":"traceutil/trace.go:171","msg":"trace[803477917] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"119.764751ms","start":"2026-10-07T04:39:23.192653Z","end":"2026-10-07T04:39:23.312418Z","steps":["trace[803477917] 'process raft request' (duration: 53.221329ms)","trace[803477917] 'compare' (duration: 66.264836ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:23.654978Z","caller":"traceutil/trace.go:171","msg":"trace[910945705] transaction","detail":"{read_only:false; response_revision:25910; number_of_response:1; }","duration":"237.591785ms","start":"2026-10-07T04:39:23.417351Z","end":"2026-10-07T04:39:23.654943Z","steps":["trace[910945705] 'process raft request' (duration: 237.386741ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:23.655232Z","caller":"traceutil/trace.go:171","msg":"trace[1219048321] linearizableReadLoop","detail":"{readStateIndex:26775; appliedIndex:26775; }","duration":"184.070181ms","start":"2026-10-07T04:39:23.471064Z","end":"2026-10-07T04:39:23.655134Z","steps":["trace[1219048321] 'read index received' (duration: 184.049251ms)","trace[1219048321] 'applied index is now lower than readState.Index' (duration: 15.16µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:23.655627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.53811ms","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-10-07T04:39:23.655686Z","caller":"traceutil/trace.go:171","msg":"trace[1387908683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25910; }","duration":"184.667431ms","start":"2026-10-07T04:39:23.471002Z","end":"2026-10-07T04:39:23.655669Z","steps":["trace[1387908683] 'agreement among raft nodes before linearized reading' (duration: 184.381207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:23.709606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.883194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:23.709685Z","caller":"traceutil/trace.go:171","msg":"trace[805857728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25911; }","duration":"139.029517ms","start":"2026-10-07T04:39:23.570638Z","end":"2026-10-07T04:39:23.709668Z","steps":["trace[805857728] 'agreement among raft nodes before linearized reading' (duration: 138.869374ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:23.709696Z","caller":"traceutil/trace.go:171","msg":"trace[1074731295] transaction","detail":"{read_only:false; response_revision:25911; number_of_response:1; }","duration":"203.531589ms","start":"2026-10-07T04:39:23.506112Z","end":"2026-10-07T04:39:23.709644Z","steps":["trace[1074731295] 'process raft request' (duration: 203.212423ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:23.892916Z","caller":"traceutil/trace.go:171","msg":"trace[823636399] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"232.469218ms","start":"2026-10-07T04:39:23.660423Z","end":"2026-10-07T04:39:23.892892Z","steps":["trace[823636399] 'process raft request' (duration: 175.984607ms)","trace[823636399] 'compare' (duration: 56.211196ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:23.893035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.959741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:23.893082Z","caller":"traceutil/trace.go:171","msg":"trace[531797839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25912; }","duration":"182.085543ms","start":"2026-10-07T04:39:23.710984Z","end":"2026-10-07T04:39:23.893069Z","steps":["trace[531797839] 'agreement among raft nodes before linearized reading' (duration: 181.978611ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:23.892915Z","caller":"traceutil/trace.go:171","msg":"trace[461910035] linearizableReadLoop","detail":"{readStateIndex:26777; appliedIndex:26776; }","duration":"181.734746ms","start":"2026-10-07T04:39:23.711046Z","end":"2026-10-07T04:39:23.892781Z","steps":["trace[461910035] 'read index received' (duration: 125.364937ms)","trace[461910035] 'applied index is now lower than readState.Index' (duration: 56.368519ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:28.365083Z","caller":"traceutil/trace.go:171","msg":"trace[863404928] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"133.598612ms","start":"2026-10-07T04:39:28.231456Z","end":"2026-10-07T04:39:28.365054Z","steps":["trace[863404928] 'process raft request' (duration: 100.157238ms)","trace[863404928] 'compare' (duration: 33.332582ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:28.629762Z","caller":"traceutil/trace.go:171","msg":"trace[756245330] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"140.80179ms","start":"2026-10-07T04:39:28.488877Z","end":"2026-10-07T04:39:28.629679Z","steps":["trace[756245330] 'process raft request' (duration: 136.330555ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.817916Z","caller":"traceutil/trace.go:171","msg":"trace[563648334] linearizableReadLoop","detail":"{readStateIndex:26824; appliedIndex:26822; }","duration":"287.449071ms","start":"2026-10-07T04:39:28.530431Z","end":"2026-10-07T04:39:28.81788Z","steps":["trace[563648334] 'read index received' (duration: 94.793417ms)","trace[563648334] 'applied index is now lower than readState.Index' (duration: 192.654324ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:28.817993Z","caller":"traceutil/trace.go:171","msg":"trace[708783070] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"311.18311ms","start":"2026-10-07T04:39:28.506758Z","end":"2026-10-07T04:39:28.817941Z","steps":["trace[708783070] 'process raft request' (duration: 285.422242ms)","trace[708783070] 'compare' (duration: 25.553255ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:28.818198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.506719Z","time spent":"311.353404ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:28.818204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.194017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-07T04:39:28.818252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.576829ms","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-10-07T04:39:28.818297Z","caller":"traceutil/trace.go:171","msg":"trace[1840418445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25958; }","duration":"166.700901ms","start":"2026-10-07T04:39:28.651581Z","end":"2026-10-07T04:39:28.818282Z","steps":["trace[1840418445] 'agreement among raft nodes before linearized reading' (duration: 166.487917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.818293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.703519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-07T04:39:28.818315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.870209ms","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-10-07T04:39:28.818301Z","caller":"traceutil/trace.go:171","msg":"trace[2136467214] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25958; }","duration":"121.380031ms","start":"2026-10-07T04:39:28.696897Z","end":"2026-10-07T04:39:28.818277Z","steps":["trace[2136467214] 'agreement among raft nodes before linearized reading' (duration: 121.186397ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.818389Z","caller":"traceutil/trace.go:171","msg":"trace[785436940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25958; }","duration":"120.867881ms","start":"2026-10-07T04:39:28.697495Z","end":"2026-10-07T04:39:28.818363Z","steps":["trace[785436940] 'agreement among raft nodes before linearized reading' (duration: 120.578596ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.818416Z","caller":"traceutil/trace.go:171","msg":"trace[380204736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25958; }","duration":"288.018172ms","start":"2026-10-07T04:39:28.530373Z","end":"2026-10-07T04:39:28.818391Z","steps":["trace[380204736] 'agreement among raft nodes before linearized reading' (duration: 287.665665ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.970977Z","caller":"traceutil/trace.go:171","msg":"trace[193036234] linearizableReadLoop","detail":"{readStateIndex:26827; appliedIndex:26824; }","duration":"137.320624ms","start":"2026-10-07T04:39:28.833632Z","end":"2026-10-07T04:39:28.970953Z","steps":["trace[193036234] 'read index received' (duration: 95.40604ms)","trace[193036234] 'applied index is now lower than readState.Index' (duration: 41.913174ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:28.971129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.477007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:28.971191Z","caller":"traceutil/trace.go:171","msg":"trace[237095834] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25961; }","duration":"137.566379ms","start":"2026-10-07T04:39:28.833589Z","end":"2026-10-07T04:39:28.971155Z","steps":["trace[237095834] 'agreement among raft nodes before linearized reading' (duration: 137.468007ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.971156Z","caller":"traceutil/trace.go:171","msg":"trace[112766695] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"147.459267ms","start":"2026-10-07T04:39:28.823674Z","end":"2026-10-07T04:39:28.971133Z","steps":["trace[112766695] 'process raft request' (duration: 147.140091ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.971314Z","caller":"traceutil/trace.go:171","msg":"trace[2130552561] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"147.484197ms","start":"2026-10-07T04:39:28.823771Z","end":"2026-10-07T04:39:28.971255Z","steps":["trace[2130552561] 'process raft request' (duration: 147.13011ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.971333Z","caller":"traceutil/trace.go:171","msg":"trace[1566173202] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"148.475015ms","start":"2026-10-07T04:39:28.822824Z","end":"2026-10-07T04:39:28.971299Z","steps":["trace[1566173202] 'process raft request' (duration: 106.195373ms)","trace[1566173202] 'compare' (duration: 41.601529ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:29.253158Z","caller":"traceutil/trace.go:171","msg":"trace[1826133137] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"130.976464ms","start":"2026-10-07T04:39:29.122152Z","end":"2026-10-07T04:39:29.253129Z","steps":["trace[1826133137] 'process raft request' (duration: 130.80746ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.61174Z","caller":"traceutil/trace.go:171","msg":"trace[1674705903] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"266.323041ms","start":"2026-10-07T04:39:29.345386Z","end":"2026-10-07T04:39:29.611709Z","steps":["trace[1674705903] 'process raft request' (duration: 266.088996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.921663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.52995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:29.921805Z","caller":"traceutil/trace.go:171","msg":"trace[964726906] linearizableReadLoop","detail":"{readStateIndex:26830; appliedIndex:26829; }","duration":"133.056842ms","start":"2026-10-07T04:39:29.788725Z","end":"2026-10-07T04:39:29.921781Z","steps":["trace[964726906] 'read index received' (duration: 30.27µs)","trace[964726906] 'applied index is now lower than readState.Index' (duration: 133.024802ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:29.921971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.244347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:29.921939Z","caller":"traceutil/trace.go:171","msg":"trace[1403228010] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"244.589017ms","start":"2026-10-07T04:39:29.677302Z","end":"2026-10-07T04:39:29.921891Z","steps":["trace[1403228010] 'process raft request' (duration: 89.71342ms)","trace[1403228010] 'compare' (duration: 154.305476ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:29.922008Z","caller":"traceutil/trace.go:171","msg":"trace[1781821980] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25964; }","duration":"133.317637ms","start":"2026-10-07T04:39:29.788678Z","end":"2026-10-07T04:39:29.921995Z","steps":["trace[1781821980] 'agreement among raft nodes before linearized reading' (duration: 133.215385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.297732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.877015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:30.297854Z","caller":"traceutil/trace.go:171","msg":"trace[1051396079] linearizableReadLoop","detail":"{readStateIndex:26833; appliedIndex:26832; }","duration":"109.977755ms","start":"2026-10-07T04:39:30.187854Z","end":"2026-10-07T04:39:30.297832Z","steps":["trace[1051396079] 'read index received' (duration: 36.91µs)","trace[1051396079] 'applied index is now lower than readState.Index' (duration: 109.939235ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:30.298055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.20948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-07T04:39:30.298093Z","caller":"traceutil/trace.go:171","msg":"trace[1402009583] range","detail":"{range_begin:/registry/masterleases/162.253.55.3; range_end:; response_count:1; response_revision:25967; }","duration":"110.294522ms","start":"2026-10-07T04:39:30.187786Z","end":"2026-10-07T04:39:30.29808Z","steps":["trace[1402009583] 'agreement among raft nodes before linearized reading' (duration: 110.099498ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.29845Z","caller":"traceutil/trace.go:171","msg":"trace[1815015324] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"253.568179ms","start":"2026-10-07T04:39:30.044869Z","end":"2026-10-07T04:39:30.298437Z","steps":["trace[1815015324] 'process raft request' (duration: 114.860778ms)","trace[1815015324] 'compare' (duration: 137.396285ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:30.47955Z","caller":"traceutil/trace.go:171","msg":"trace[1209682760] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"246.727019ms","start":"2026-10-07T04:39:30.232783Z","end":"2026-10-07T04:39:30.47951Z","steps":["trace[1209682760] 'process raft request' (duration: 246.466994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.753079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.512292ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-10-07T04:39:30.753315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:30.299813Z","time spent":"453.49556ms","remote":"127.0.0.1:48050","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-07T04:39:30.753519Z","caller":"traceutil/trace.go:171","msg":"trace[407085715] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"117.200102ms","start":"2026-10-07T04:39:30.636292Z","end":"2026-10-07T04:39:30.753492Z","steps":["trace[407085715] 'process raft request' (duration: 116.968338ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.754037Z","caller":"traceutil/trace.go:171","msg":"trace[1488939225] linearizableReadLoop","detail":"{readStateIndex:26835; appliedIndex:26833; }","duration":"384.344318ms","start":"2026-10-07T04:39:30.369659Z","end":"2026-10-07T04:39:30.754004Z","steps":["trace[1488939225] 'read index received' (duration: 109.526777ms)","trace[1488939225] 'applied index is now lower than readState.Index' (duration: 274.815131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:30.754355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.122611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-07T04:39:30.754347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.659064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-07T04:39:30.754395Z","caller":"traceutil/trace.go:171","msg":"trace[1837922265] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25969; }","duration":"253.260953ms","start":"2026-10-07T04:39:30.501124Z","end":"2026-10-07T04:39:30.754385Z","steps":["trace[1837922265] 'agreement among raft nodes before linearized reading' (duration: 253.072379ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.754418Z","caller":"traceutil/trace.go:171","msg":"trace[124863532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25969; }","duration":"384.817777ms","start":"2026-10-07T04:39:30.369582Z","end":"2026-10-07T04:39:30.7544Z","steps":["trace[124863532] 'agreement among raft nodes before linearized reading' (duration: 384.550182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.754458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:30.36956Z","time spent":"384.888149ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-07T04:39:30.952396Z","caller":"traceutil/trace.go:171","msg":"trace[1242423395] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26837; }","duration":"154.751115ms","start":"2026-10-07T04:39:30.797617Z","end":"2026-10-07T04:39:30.952368Z","steps":["trace[1242423395] 'read index received' (duration: 112.85151ms)","trace[1242423395] 'applied index is now lower than readState.Index' (duration: 41.898695ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:30.952525Z","caller":"traceutil/trace.go:171","msg":"trace[904369701] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"127.508778ms","start":"2026-10-07T04:39:30.824995Z","end":"2026-10-07T04:39:30.952504Z","steps":["trace[904369701] 'process raft request' (duration: 127.327304ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.952747Z","caller":"traceutil/trace.go:171","msg":"trace[117056600] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"193.618722ms","start":"2026-10-07T04:39:30.759055Z","end":"2026-10-07T04:39:30.952674Z","steps":["trace[117056600] 'process raft request' (duration: 151.398121ms)","trace[117056600] 'compare' (duration: 41.537498ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:30.95296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.331066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-07T04:39:30.953133Z","caller":"traceutil/trace.go:171","msg":"trace[1589186077] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25972; }","duration":"155.57369ms","start":"2026-10-07T04:39:30.797546Z","end":"2026-10-07T04:39:30.953119Z","steps":["trace[1589186077] 'agreement among raft nodes before linearized reading' (duration: 154.947878ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:31.166274Z","caller":"traceutil/trace.go:171","msg":"trace[2110113504] linearizableReadLoop","detail":"{readStateIndex:26842; appliedIndex:26840; }","duration":"134.544171ms","start":"2026-10-07T04:39:31.031693Z","end":"2026-10-07T04:39:31.166237Z","steps":["trace[2110113504] 'read index received' (duration: 134.292377ms)","trace[2110113504] 'applied index is now lower than readState.Index' (duration: 249.994µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:31.166372Z","caller":"traceutil/trace.go:171","msg":"trace[646091848] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"185.058369ms","start":"2026-10-07T04:39:30.981281Z","end":"2026-10-07T04:39:31.166339Z","steps":["trace[646091848] 'process raft request' (duration: 184.863336ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:31.166542Z","caller":"traceutil/trace.go:171","msg":"trace[912336040] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"185.537838ms","start":"2026-10-07T04:39:30.980801Z","end":"2026-10-07T04:39:31.166339Z","steps":["trace[912336040] 'process raft request' (duration: 185.163761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:31.166611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.897868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-10-07T04:39:31.166823Z","caller":"traceutil/trace.go:171","msg":"trace[733818567] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:25975; }","duration":"135.164902ms","start":"2026-10-07T04:39:31.031626Z","end":"2026-10-07T04:39:31.166791Z","steps":["trace[733818567] 'agreement among raft nodes before linearized reading' (duration: 134.734765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:31.481826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.103712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:31.482193Z","caller":"traceutil/trace.go:171","msg":"trace[1840421031] transaction","detail":"{read_only:false; response_revision:25976; number_of_response:1; }","duration":"265.334092ms","start":"2026-10-07T04:39:31.216788Z","end":"2026-10-07T04:39:31.482122Z","steps":["trace[1840421031] 'process raft request' (duration: 130.816311ms)","trace[1840421031] 'compare' (duration: 133.97652ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:31.482325Z","caller":"traceutil/trace.go:171","msg":"trace[910506981] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"218.28323ms","start":"2026-10-07T04:39:31.264001Z","end":"2026-10-07T04:39:31.482285Z","steps":["trace[910506981] 'process raft request' (duration: 217.918553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:31.733509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.94775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-07T04:39:31.733603Z","caller":"traceutil/trace.go:171","msg":"trace[981889721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25977; }","duration":"143.060373ms","start":"2026-10-07T04:39:31.590522Z","end":"2026-10-07T04:39:31.733582Z","steps":["trace[981889721] 'range keys from in-memory index tree' (duration: 142.759726ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:31.735759Z","caller":"traceutil/trace.go:171","msg":"trace[1476842699] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"116.774244ms","start":"2026-10-07T04:39:31.618952Z","end":"2026-10-07T04:39:31.735726Z","steps":["trace[1476842699] 'process raft request' (duration: 116.56738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:32.046799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.605324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:32.046941Z","caller":"traceutil/trace.go:171","msg":"trace[619551679] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"254.838492ms","start":"2026-10-07T04:39:31.792073Z","end":"2026-10-07T04:39:32.046911Z","steps":["trace[619551679] 'process raft request' (duration: 141.008104ms)","trace[619551679] 'compare' (duration: 113.463481ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:32.091095Z","caller":"traceutil/trace.go:171","msg":"trace[537982509] linearizableReadLoop","detail":"{readStateIndex:26847; appliedIndex:26845; }","duration":"161.92478ms","start":"2026-10-07T04:39:31.929141Z","end":"2026-10-07T04:39:32.091066Z","steps":["trace[537982509] 'read index received' (duration: 4.019427ms)","trace[537982509] 'applied index is now lower than readState.Index' (duration: 157.904273ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:32.091443Z","caller":"traceutil/trace.go:171","msg":"trace[1085690182] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"162.548722ms","start":"2026-10-07T04:39:31.928877Z","end":"2026-10-07T04:39:32.091425Z","steps":["trace[1085690182] 'process raft request' (duration: 162.015172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:32.091524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.371688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:39:32.091615Z","caller":"traceutil/trace.go:171","msg":"trace[836222674] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25980; }","duration":"162.520561ms","start":"2026-10-07T04:39:31.929078Z","end":"2026-10-07T04:39:32.091598Z","steps":["trace[836222674] 'agreement among raft nodes before linearized reading' (duration: 162.276167ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:32.10627Z","caller":"traceutil/trace.go:171","msg":"trace[1632897378] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"150.796079ms","start":"2026-10-07T04:39:31.955426Z","end":"2026-10-07T04:39:32.106222Z","steps":["trace[1632897378] 'process raft request' (duration: 150.520204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:32.106435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.963915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-07T04:39:32.106444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.265535ms","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-10-07T04:39:32.106488Z","caller":"traceutil/trace.go:171","msg":"trace[805924306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25981; }","duration":"140.059646ms","start":"2026-10-07T04:39:31.96641Z","end":"2026-10-07T04:39:32.10647Z","steps":["trace[805924306] 'agreement among raft nodes before linearized reading' (duration: 139.856352ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:32.106549Z","caller":"traceutil/trace.go:171","msg":"trace[1686339659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25981; }","duration":"164.415278ms","start":"2026-10-07T04:39:31.942106Z","end":"2026-10-07T04:39:32.106522Z","steps":["trace[1686339659] 'agreement among raft nodes before linearized reading' (duration: 164.02025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:32.338615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.596732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:32.338735Z","caller":"traceutil/trace.go:171","msg":"trace[1128053359] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25983; }","duration":"173.776636ms","start":"2026-10-07T04:39:32.16493Z","end":"2026-10-07T04:39:32.338706Z","steps":["trace[1128053359] 'range keys from in-memory index tree' (duration: 173.47193ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:36.539688Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006901.wal"} {"level":"warn","ts":"2026-10-07T04:39:45.28454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.591781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:45.284681Z","caller":"traceutil/trace.go:171","msg":"trace[1374557591] linearizableReadLoop","detail":"{readStateIndex:27002; appliedIndex:27001; }","duration":"257.253959ms","start":"2026-10-07T04:39:45.027407Z","end":"2026-10-07T04:39:45.284661Z","steps":["trace[1374557591] 'read index received' (duration: 140.384762ms)","trace[1374557591] 'applied index is now lower than readState.Index' (duration: 116.867477ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:45.284906Z","caller":"traceutil/trace.go:171","msg":"trace[1052436488] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"275.705907ms","start":"2026-10-07T04:39:45.009159Z","end":"2026-10-07T04:39:45.284865Z","steps":["trace[1052436488] 'process raft request' (duration: 158.598597ms)","trace[1052436488] 'compare' (duration: 116.254835ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:45.285033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.614125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-07T04:39:45.285077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.72999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:45.285136Z","caller":"traceutil/trace.go:171","msg":"trace[2100655651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26133; }","duration":"257.717557ms","start":"2026-10-07T04:39:45.027387Z","end":"2026-10-07T04:39:45.285104Z","steps":["trace[2100655651] 'agreement among raft nodes before linearized reading' (duration: 257.465592ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:45.285213Z","caller":"traceutil/trace.go:171","msg":"trace[187624819] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26133; }","duration":"225.894073ms","start":"2026-10-07T04:39:45.059268Z","end":"2026-10-07T04:39:45.285162Z","steps":["trace[187624819] 'agreement among raft nodes before linearized reading' (duration: 225.647588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.285285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.448606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-10-07T04:39:45.285401Z","caller":"traceutil/trace.go:171","msg":"trace[2089336831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395; range_end:; response_count:1; response_revision:26133; }","duration":"195.65674ms","start":"2026-10-07T04:39:45.089713Z","end":"2026-10-07T04:39:45.285369Z","steps":["trace[2089336831] 'agreement among raft nodes before linearized reading' (duration: 195.264102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.28617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.861236ms","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-10-07T04:39:45.286245Z","caller":"traceutil/trace.go:171","msg":"trace[1136055405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26133; }","duration":"193.974668ms","start":"2026-10-07T04:39:45.092252Z","end":"2026-10-07T04:39:45.286227Z","steps":["trace[1136055405] 'agreement among raft nodes before linearized reading' (duration: 192.661013ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:45.592109Z","caller":"traceutil/trace.go:171","msg":"trace[487623970] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"299.909438ms","start":"2026-10-07T04:39:45.292164Z","end":"2026-10-07T04:39:45.592073Z","steps":["trace[487623970] 'process raft request' (duration: 299.642482ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:45.592115Z","caller":"traceutil/trace.go:171","msg":"trace[1067788535] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"297.337639ms","start":"2026-10-07T04:39:45.294749Z","end":"2026-10-07T04:39:45.592087Z","steps":["trace[1067788535] 'process raft request' (duration: 297.233607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.592317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:45.292151Z","time spent":"300.06192ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3744,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:45.592529Z","caller":"traceutil/trace.go:171","msg":"trace[787893784] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"297.106624ms","start":"2026-10-07T04:39:45.295386Z","end":"2026-10-07T04:39:45.592493Z","steps":["trace[787893784] 'process raft request' (duration: 296.657855ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:45.592689Z","caller":"traceutil/trace.go:171","msg":"trace[571952315] linearizableReadLoop","detail":"{readStateIndex:27007; appliedIndex:27003; }","duration":"100.382875ms","start":"2026-10-07T04:39:45.492267Z","end":"2026-10-07T04:39:45.59265Z","steps":["trace[571952315] 'read index received' (duration: 99.62582ms)","trace[571952315] 'applied index is now lower than readState.Index' (duration: 755.505µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:45.592754Z","caller":"traceutil/trace.go:171","msg":"trace[1741763503] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"297.090413ms","start":"2026-10-07T04:39:45.295591Z","end":"2026-10-07T04:39:45.592682Z","steps":["trace[1741763503] 'process raft request' (duration: 296.767047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.592909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.593517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:45.593014Z","caller":"traceutil/trace.go:171","msg":"trace[301965832] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26138; }","duration":"100.786631ms","start":"2026-10-07T04:39:45.492203Z","end":"2026-10-07T04:39:45.592989Z","steps":["trace[301965832] 'agreement among raft nodes before linearized reading' (duration: 100.581017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.838829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.240194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:45.838939Z","caller":"traceutil/trace.go:171","msg":"trace[1446038589] linearizableReadLoop","detail":"{readStateIndex:27008; appliedIndex:27007; }","duration":"246.151399ms","start":"2026-10-07T04:39:45.592772Z","end":"2026-10-07T04:39:45.838923Z","steps":["trace[1446038589] 'read index received' (duration: 99.66405ms)","trace[1446038589] 'applied index is now lower than readState.Index' (duration: 146.485929ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:45.839067Z","caller":"traceutil/trace.go:171","msg":"trace[310944163] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"297.151254ms","start":"2026-10-07T04:39:45.541866Z","end":"2026-10-07T04:39:45.839017Z","steps":["trace[310944163] 'process raft request' (duration: 150.623545ms)","trace[310944163] 'compare' (duration: 146.000159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:45.839149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.789998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:45.839231Z","caller":"traceutil/trace.go:171","msg":"trace[1385371718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26139; }","duration":"267.961761ms","start":"2026-10-07T04:39:45.571255Z","end":"2026-10-07T04:39:45.839216Z","steps":["trace[1385371718] 'agreement among raft nodes before linearized reading' (duration: 267.802589ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:45.930621Z","caller":"traceutil/trace.go:171","msg":"trace[1563832460] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"139.833922ms","start":"2026-10-07T04:39:45.790744Z","end":"2026-10-07T04:39:45.930578Z","steps":["trace[1563832460] 'process raft request' (duration: 139.486645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.930767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:45.930907Z","caller":"traceutil/trace.go:171","msg":"trace[730356960] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:26140; }","duration":"111.386873ms","start":"2026-10-07T04:39:45.819491Z","end":"2026-10-07T04:39:45.930877Z","steps":["trace[730356960] 'agreement among raft nodes before linearized reading' (duration: 111.113538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:45.93112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.990698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-10-07T04:39:45.931313Z","caller":"traceutil/trace.go:171","msg":"trace[325237297] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395; range_end:; response_count:1; response_revision:26140; }","duration":"214.229863ms","start":"2026-10-07T04:39:45.717049Z","end":"2026-10-07T04:39:45.931279Z","steps":["trace[325237297] 'agreement among raft nodes before linearized reading' (duration: 213.580981ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:46.371144Z","caller":"traceutil/trace.go:171","msg":"trace[246865383] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"158.442494ms","start":"2026-10-07T04:39:46.212667Z","end":"2026-10-07T04:39:46.37111Z","steps":["trace[246865383] 'process raft request' (duration: 157.13794ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:46.403729Z","caller":"traceutil/trace.go:171","msg":"trace[1515860948] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"187.978044ms","start":"2026-10-07T04:39:46.215733Z","end":"2026-10-07T04:39:46.403711Z","steps":["trace[1515860948] 'process raft request' (duration: 187.599397ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:46.717224Z","caller":"traceutil/trace.go:171","msg":"trace[632475474] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"221.348508ms","start":"2026-10-07T04:39:46.495803Z","end":"2026-10-07T04:39:46.717152Z","steps":["trace[632475474] 'process raft request' (duration: 136.437327ms)","trace[632475474] 'compare' (duration: 84.650716ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:46.720229Z","caller":"traceutil/trace.go:171","msg":"trace[101520008] linearizableReadLoop","detail":"{readStateIndex:27016; appliedIndex:27014; }","duration":"148.389324ms","start":"2026-10-07T04:39:46.571808Z","end":"2026-10-07T04:39:46.720197Z","steps":["trace[101520008] 'read index received' (duration: 60.499807ms)","trace[101520008] 'applied index is now lower than readState.Index' (duration: 87.887876ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:46.720328Z","caller":"traceutil/trace.go:171","msg":"trace[227380464] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"174.52065ms","start":"2026-10-07T04:39:46.545754Z","end":"2026-10-07T04:39:46.720274Z","steps":["trace[227380464] 'process raft request' (duration: 174.257045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:46.720447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.595077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:46.720565Z","caller":"traceutil/trace.go:171","msg":"trace[1465787974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26146; }","duration":"148.789891ms","start":"2026-10-07T04:39:46.571747Z","end":"2026-10-07T04:39:46.720537Z","steps":["trace[1465787974] 'agreement among raft nodes before linearized reading' (duration: 148.554837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.010402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.88172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:47.010526Z","caller":"traceutil/trace.go:171","msg":"trace[2045795822] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"194.158912ms","start":"2026-10-07T04:39:46.816352Z","end":"2026-10-07T04:39:47.010511Z","steps":["trace[2045795822] 'process raft request' (duration: 62.080808ms)","trace[2045795822] 'compare' (duration: 131.777838ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:47.268315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.216217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:47.268532Z","caller":"traceutil/trace.go:171","msg":"trace[1785897563] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"195.544448ms","start":"2026-10-07T04:39:47.072966Z","end":"2026-10-07T04:39:47.26851Z","steps":["trace[1785897563] 'process raft request' (duration: 195.473047ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:47.268533Z","caller":"traceutil/trace.go:171","msg":"trace[248098265] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"345.065553ms","start":"2026-10-07T04:39:46.923402Z","end":"2026-10-07T04:39:47.268467Z","steps":["trace[248098265] 'process raft request' (duration: 221.584522ms)","trace[248098265] 'compare' (duration: 123.036883ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:47.268548Z","caller":"traceutil/trace.go:171","msg":"trace[376206909] linearizableReadLoop","detail":"{readStateIndex:27018; appliedIndex:27017; }","duration":"258.029413ms","start":"2026-10-07T04:39:47.01048Z","end":"2026-10-07T04:39:47.268509Z","steps":["trace[376206909] 'read index received' (duration: 134.586862ms)","trace[376206909] 'applied index is now lower than readState.Index' (duration: 123.439481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:47.268769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:46.923371Z","time spent":"345.277608ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:47.2688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.852437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-10-07T04:39:47.268895Z","caller":"traceutil/trace.go:171","msg":"trace[2100795059] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395; range_end:; response_count:1; response_revision:26149; }","duration":"328.077241ms","start":"2026-10-07T04:39:46.940803Z","end":"2026-10-07T04:39:47.26888Z","steps":["trace[2100795059] 'agreement among raft nodes before linearized reading' (duration: 327.818746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.26893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:46.940775Z","time spent":"328.146073ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9ad0421-07cb-4cf7-a2c6-0301c77d8395\" "} {"level":"warn","ts":"2026-10-07T04:39:47.269133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.738765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:47.269241Z","caller":"traceutil/trace.go:171","msg":"trace[998511547] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26149; }","duration":"134.94467ms","start":"2026-10-07T04:39:47.134275Z","end":"2026-10-07T04:39:47.26922Z","steps":["trace[998511547] 'agreement among raft nodes before linearized reading' (duration: 134.779867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.269345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.864918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:47.269477Z","caller":"traceutil/trace.go:171","msg":"trace[1461146809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26149; }","duration":"251.027371ms","start":"2026-10-07T04:39:47.018426Z","end":"2026-10-07T04:39:47.269454Z","steps":["trace[1461146809] 'agreement among raft nodes before linearized reading' (duration: 250.654264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.269536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.126279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-07T04:39:47.269673Z","caller":"traceutil/trace.go:171","msg":"trace[541340178] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26149; }","duration":"255.525455ms","start":"2026-10-07T04:39:47.014125Z","end":"2026-10-07T04:39:47.26965Z","steps":["trace[541340178] 'agreement among raft nodes before linearized reading' (duration: 254.934395ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:47.415303Z","caller":"traceutil/trace.go:171","msg":"trace[1510627531] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"123.44724ms","start":"2026-10-07T04:39:47.291806Z","end":"2026-10-07T04:39:47.415254Z","steps":["trace[1510627531] 'process raft request' (duration: 84.724726ms)","trace[1510627531] 'compare' (duration: 38.303467ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:47.710643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.060215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-07T04:39:47.710729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.442313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:47.71083Z","caller":"traceutil/trace.go:171","msg":"trace[1311273356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26151; }","duration":"139.599307ms","start":"2026-10-07T04:39:47.571206Z","end":"2026-10-07T04:39:47.710805Z","steps":["trace[1311273356] 'range keys from in-memory index tree' (duration: 139.350122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.710933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.495374ms","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":"warn","ts":"2026-10-07T04:39:47.710998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.443653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-07T04:39:47.711038Z","caller":"traceutil/trace.go:171","msg":"trace[509226467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26151; }","duration":"112.641266ms","start":"2026-10-07T04:39:47.598372Z","end":"2026-10-07T04:39:47.711013Z","steps":["trace[509226467] 'range keys from in-memory index tree' (duration: 112.30148ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:47.711089Z","caller":"traceutil/trace.go:171","msg":"trace[1702836162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26151; }","duration":"112.556215ms","start":"2026-10-07T04:39:47.598505Z","end":"2026-10-07T04:39:47.711061Z","steps":["trace[1702836162] 'range keys from in-memory index tree' (duration: 112.171047ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:47.710772Z","caller":"traceutil/trace.go:171","msg":"trace[82146638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26151; }","duration":"112.261589ms","start":"2026-10-07T04:39:47.598482Z","end":"2026-10-07T04:39:47.710743Z","steps":["trace[82146638] 'range keys from in-memory index tree' (duration: 111.704289ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.025778Z","caller":"traceutil/trace.go:171","msg":"trace[757554366] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"308.595191ms","start":"2026-10-07T04:39:47.717145Z","end":"2026-10-07T04:39:48.02574Z","steps":["trace[757554366] 'process raft request' (duration: 308.53185ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.025812Z","caller":"traceutil/trace.go:171","msg":"trace[1933937527] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"308.711783ms","start":"2026-10-07T04:39:47.717058Z","end":"2026-10-07T04:39:48.02577Z","steps":["trace[1933937527] 'process raft request' (duration: 308.387088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.025984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:47.717107Z","time spent":"308.767874ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:48.026036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:47.717029Z","time spent":"308.880537ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:48.047397Z","caller":"traceutil/trace.go:171","msg":"trace[1787609496] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"201.867458ms","start":"2026-10-07T04:39:47.845506Z","end":"2026-10-07T04:39:48.047373Z","steps":["trace[1787609496] 'process raft request' (duration: 201.638504ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.047614Z","caller":"traceutil/trace.go:171","msg":"trace[49057868] linearizableReadLoop","detail":"{readStateIndex:27025; appliedIndex:27022; }","duration":"181.861859ms","start":"2026-10-07T04:39:47.8657Z","end":"2026-10-07T04:39:48.047562Z","steps":["trace[49057868] 'read index received' (duration: 159.785571ms)","trace[49057868] 'applied index is now lower than readState.Index' (duration: 22.071448ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:48.047625Z","caller":"traceutil/trace.go:171","msg":"trace[1714732751] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"109.882944ms","start":"2026-10-07T04:39:47.937702Z","end":"2026-10-07T04:39:48.047585Z","steps":["trace[1714732751] 'process raft request' (duration: 109.621099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.047792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.077783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:48.047859Z","caller":"traceutil/trace.go:171","msg":"trace[556387543] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26156; }","duration":"182.198455ms","start":"2026-10-07T04:39:47.865645Z","end":"2026-10-07T04:39:48.047843Z","steps":["trace[556387543] 'agreement among raft nodes before linearized reading' (duration: 182.086743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.047951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.106433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:48.048025Z","caller":"traceutil/trace.go:171","msg":"trace[1029901120] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26156; }","duration":"124.237545ms","start":"2026-10-07T04:39:47.923766Z","end":"2026-10-07T04:39:48.048004Z","steps":["trace[1029901120] 'agreement among raft nodes before linearized reading' (duration: 124.056083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.709345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.828441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-07T04:39:48.709439Z","caller":"traceutil/trace.go:171","msg":"trace[2129522433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26157; }","duration":"333.002395ms","start":"2026-10-07T04:39:48.376415Z","end":"2026-10-07T04:39:48.709417Z","steps":["trace[2129522433] 'range keys from in-memory index tree' (duration: 332.546526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.709479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.376382Z","time spent":"333.088146ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-07T04:39:48.709609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.032428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-07T04:39:48.709698Z","caller":"traceutil/trace.go:171","msg":"trace[54084832] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26157; }","duration":"301.199881ms","start":"2026-10-07T04:39:48.408475Z","end":"2026-10-07T04:39:48.709674Z","steps":["trace[54084832] 'range keys from in-memory index tree' (duration: 300.717492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.709738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.408441Z","time spent":"301.288003ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-07T04:39:48.709804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.734972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:48.709901Z","caller":"traceutil/trace.go:171","msg":"trace[1632678258] linearizableReadLoop","detail":"{readStateIndex:27028; appliedIndex:27027; }","duration":"255.555476ms","start":"2026-10-07T04:39:48.454327Z","end":"2026-10-07T04:39:48.709883Z","steps":["trace[1632678258] 'read index received' (duration: 79.583079ms)","trace[1632678258] 'applied index is now lower than readState.Index' (duration: 175.970157ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:48.710225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.910033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-07T04:39:48.710265Z","caller":"traceutil/trace.go:171","msg":"trace[2048598235] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26158; }","duration":"255.998535ms","start":"2026-10-07T04:39:48.454253Z","end":"2026-10-07T04:39:48.710252Z","steps":["trace[2048598235] 'agreement among raft nodes before linearized reading' (duration: 255.848912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.71032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.942034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:39:48.710335Z","caller":"traceutil/trace.go:171","msg":"trace[266081725] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"299.318886ms","start":"2026-10-07T04:39:48.410984Z","end":"2026-10-07T04:39:48.710303Z","steps":["trace[266081725] 'process raft request' (duration: 122.969122ms)","trace[266081725] 'compare' (duration: 175.497768ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:48.710371Z","caller":"traceutil/trace.go:171","msg":"trace[2006547266] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:26158; }","duration":"236.054086ms","start":"2026-10-07T04:39:48.474303Z","end":"2026-10-07T04:39:48.710357Z","steps":["trace[2006547266] 'agreement among raft nodes before linearized reading' (duration: 235.959244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.710429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.042045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:48.710895Z","caller":"traceutil/trace.go:171","msg":"trace[387507910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26158; }","duration":"140.553555ms","start":"2026-10-07T04:39:48.570325Z","end":"2026-10-07T04:39:48.710879Z","steps":["trace[387507910] 'agreement among raft nodes before linearized reading' (duration: 140.067996ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:50.880972Z","caller":"traceutil/trace.go:171","msg":"trace[798642935] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"100.810652ms","start":"2026-10-07T04:39:50.780124Z","end":"2026-10-07T04:39:50.880935Z","steps":["trace[798642935] 'process raft request' (duration: 100.18915ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:51.611777Z","caller":"traceutil/trace.go:171","msg":"trace[2074192520] linearizableReadLoop","detail":"{readStateIndex:27051; appliedIndex:27050; }","duration":"113.481081ms","start":"2026-10-07T04:39:51.498261Z","end":"2026-10-07T04:39:51.611742Z","steps":["trace[2074192520] 'read index received' (duration: 82.27278ms)","trace[2074192520] 'applied index is now lower than readState.Index' (duration: 31.206541ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:51.611801Z","caller":"traceutil/trace.go:171","msg":"trace[2012927811] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"130.602427ms","start":"2026-10-07T04:39:51.48116Z","end":"2026-10-07T04:39:51.611762Z","steps":["trace[2012927811] 'process raft request' (duration: 99.509418ms)","trace[2012927811] 'compare' (duration: 30.899596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:51.611968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.672746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:51.612011Z","caller":"traceutil/trace.go:171","msg":"trace[1634503029] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:26180; }","duration":"113.831628ms","start":"2026-10-07T04:39:51.498167Z","end":"2026-10-07T04:39:51.611998Z","steps":["trace[1634503029] 'agreement among raft nodes before linearized reading' (duration: 113.697546ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:54.249547Z","caller":"traceutil/trace.go:171","msg":"trace[380131421] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"188.358831ms","start":"2026-10-07T04:39:54.061151Z","end":"2026-10-07T04:39:54.249509Z","steps":["trace[380131421] 'process raft request' (duration: 187.79963ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:54.249828Z","caller":"traceutil/trace.go:171","msg":"trace[646465285] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"180.071315ms","start":"2026-10-07T04:39:54.069725Z","end":"2026-10-07T04:39:54.249797Z","steps":["trace[646465285] 'process raft request' (duration: 179.710568ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:54.249829Z","caller":"traceutil/trace.go:171","msg":"trace[430661484] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"178.587157ms","start":"2026-10-07T04:39:54.071212Z","end":"2026-10-07T04:39:54.2498Z","steps":["trace[430661484] 'process raft request' (duration: 178.443264ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:03.653074Z","caller":"traceutil/trace.go:171","msg":"trace[476876333] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"130.061326ms","start":"2026-10-07T04:40:03.522966Z","end":"2026-10-07T04:40:03.653027Z","steps":["trace[476876333] 'process raft request' (duration: 129.774921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:03.800303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.179037ms","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-10-07T04:40:03.800428Z","caller":"traceutil/trace.go:171","msg":"trace[1177726857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26315; }","duration":"122.391961ms","start":"2026-10-07T04:40:03.678012Z","end":"2026-10-07T04:40:03.800404Z","steps":["trace[1177726857] 'range keys from in-memory index tree' (duration: 121.918783ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:03.937523Z","caller":"traceutil/trace.go:171","msg":"trace[2143810414] linearizableReadLoop","detail":"{readStateIndex:27189; appliedIndex:27188; }","duration":"103.36747ms","start":"2026-10-07T04:40:03.834125Z","end":"2026-10-07T04:40:03.937492Z","steps":["trace[2143810414] 'read index received' (duration: 103.159966ms)","trace[2143810414] 'applied index is now lower than readState.Index' (duration: 205.954µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:03.937562Z","caller":"traceutil/trace.go:171","msg":"trace[918757669] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"131.407552ms","start":"2026-10-07T04:40:03.806117Z","end":"2026-10-07T04:40:03.937525Z","steps":["trace[918757669] 'process raft request' (duration: 131.193447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:03.937804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.647615ms","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-10-07T04:40:03.93788Z","caller":"traceutil/trace.go:171","msg":"trace[2023429489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26316; }","duration":"103.800798ms","start":"2026-10-07T04:40:03.834062Z","end":"2026-10-07T04:40:03.937863Z","steps":["trace[2023429489] 'agreement among raft nodes before linearized reading' (duration: 103.527533ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:04.379657Z","caller":"traceutil/trace.go:171","msg":"trace[527320683] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"122.568443ms","start":"2026-10-07T04:40:04.257055Z","end":"2026-10-07T04:40:04.379624Z","steps":["trace[527320683] 'process raft request' (duration: 122.353859ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:04.639906Z","caller":"traceutil/trace.go:171","msg":"trace[1041611798] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"350.101549ms","start":"2026-10-07T04:40:04.289781Z","end":"2026-10-07T04:40:04.639882Z","steps":["trace[1041611798] 'process raft request' (duration: 349.754722ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:04.640178Z","caller":"traceutil/trace.go:171","msg":"trace[1513768719] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"265.576466ms","start":"2026-10-07T04:40:04.374582Z","end":"2026-10-07T04:40:04.640159Z","steps":["trace[1513768719] 'process raft request' (duration: 265.225689ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:04.640014Z","caller":"traceutil/trace.go:171","msg":"trace[1971553173] linearizableReadLoop","detail":"{readStateIndex:27193; appliedIndex:27190; }","duration":"334.258689ms","start":"2026-10-07T04:40:04.30574Z","end":"2026-10-07T04:40:04.639998Z","steps":["trace[1971553173] 'read index received' (duration: 73.645077ms)","trace[1971553173] 'applied index is now lower than readState.Index' (duration: 260.612192ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:04.6399Z","caller":"traceutil/trace.go:171","msg":"trace[166746552] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"344.120525ms","start":"2026-10-07T04:40:04.295738Z","end":"2026-10-07T04:40:04.639859Z","steps":["trace[166746552] 'process raft request' (duration: 344.004973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:04.640242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:04.289754Z","time spent":"350.347114ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:40:04.640488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:04.29572Z","time spent":"344.632805ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:40:04.640623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.880661ms","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-10-07T04:40:04.640804Z","caller":"traceutil/trace.go:171","msg":"trace[549301810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26321; }","duration":"334.988263ms","start":"2026-10-07T04:40:04.305681Z","end":"2026-10-07T04:40:04.640669Z","steps":["trace[549301810] 'agreement among raft nodes before linearized reading' (duration: 334.657527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:04.640915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:04.305662Z","time spent":"335.236106ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-07T04:40:10.174627Z","caller":"traceutil/trace.go:171","msg":"trace[1981141276] linearizableReadLoop","detail":"{readStateIndex:27238; appliedIndex:27237; }","duration":"172.566802ms","start":"2026-10-07T04:40:10.002036Z","end":"2026-10-07T04:40:10.174603Z","steps":["trace[1981141276] 'read index received' (duration: 172.323698ms)","trace[1981141276] 'applied index is now lower than readState.Index' (duration: 242.304µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:10.174701Z","caller":"traceutil/trace.go:171","msg":"trace[943262231] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"204.144671ms","start":"2026-10-07T04:40:09.970499Z","end":"2026-10-07T04:40:10.174644Z","steps":["trace[943262231] 'process raft request' (duration: 203.957147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:10.174893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.851319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-07T04:40:10.174882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.048738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-07T04:40:10.17488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.44273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:10.174976Z","caller":"traceutil/trace.go:171","msg":"trace[1099423826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26364; }","duration":"172.95953ms","start":"2026-10-07T04:40:10.001988Z","end":"2026-10-07T04:40:10.174947Z","steps":["trace[1099423826] 'agreement among raft nodes before linearized reading' (duration: 172.730846ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:10.174976Z","caller":"traceutil/trace.go:171","msg":"trace[1723057531] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26364; }","duration":"138.226291ms","start":"2026-10-07T04:40:10.036729Z","end":"2026-10-07T04:40:10.174956Z","steps":["trace[1723057531] 'agreement among raft nodes before linearized reading' (duration: 138.050388ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:10.174994Z","caller":"traceutil/trace.go:171","msg":"trace[374270235] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26364; }","duration":"105.621863ms","start":"2026-10-07T04:40:10.069346Z","end":"2026-10-07T04:40:10.174967Z","steps":["trace[374270235] 'agreement among raft nodes before linearized reading' (duration: 105.43749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:14.503828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.462382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:14.503915Z","caller":"traceutil/trace.go:171","msg":"trace[313490454] linearizableReadLoop","detail":"{readStateIndex:27292; appliedIndex:27291; }","duration":"214.960845ms","start":"2026-10-07T04:40:14.288939Z","end":"2026-10-07T04:40:14.5039Z","steps":["trace[313490454] 'read index received' (duration: 92.31294ms)","trace[313490454] 'applied index is now lower than readState.Index' (duration: 122.646925ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:14.50399Z","caller":"traceutil/trace.go:171","msg":"trace[1360697039] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"234.313533ms","start":"2026-10-07T04:40:14.269659Z","end":"2026-10-07T04:40:14.503973Z","steps":["trace[1360697039] 'process raft request' (duration: 111.626546ms)","trace[1360697039] 'compare' (duration: 122.35704ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:14.504043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.121469ms","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-10-07T04:40:14.504065Z","caller":"traceutil/trace.go:171","msg":"trace[1409293749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26416; }","duration":"215.19139ms","start":"2026-10-07T04:40:14.288868Z","end":"2026-10-07T04:40:14.50406Z","steps":["trace[1409293749] 'agreement among raft nodes before linearized reading' (duration: 215.094548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:15.53074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.762655ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-07T04:40:17.207799Z","caller":"traceutil/trace.go:171","msg":"trace[1048033153] linearizableReadLoop","detail":"{readStateIndex:27317; appliedIndex:27316; }","duration":"112.772448ms","start":"2026-10-07T04:40:17.094995Z","end":"2026-10-07T04:40:17.207767Z","steps":["trace[1048033153] 'read index received' (duration: 112.602305ms)","trace[1048033153] 'applied index is now lower than readState.Index' (duration: 169.043µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:17.207858Z","caller":"traceutil/trace.go:171","msg":"trace[739237002] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"122.426002ms","start":"2026-10-07T04:40:17.085389Z","end":"2026-10-07T04:40:17.207815Z","steps":["trace[739237002] 'process raft request' (duration: 122.165297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:17.208098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.068573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-07T04:40:17.208165Z","caller":"traceutil/trace.go:171","msg":"trace[1576551284] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26440; }","duration":"113.180776ms","start":"2026-10-07T04:40:17.094964Z","end":"2026-10-07T04:40:17.208144Z","steps":["trace[1576551284] 'agreement among raft nodes before linearized reading' (duration: 112.950961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:17.231657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.180625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:17.231752Z","caller":"traceutil/trace.go:171","msg":"trace[643401299] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26441; }","duration":"133.319797ms","start":"2026-10-07T04:40:17.09841Z","end":"2026-10-07T04:40:17.23173Z","steps":["trace[643401299] 'agreement among raft nodes before linearized reading' (duration: 133.142504ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:20.364811Z","caller":"traceutil/trace.go:171","msg":"trace[140148900] transaction","detail":"{read_only:false; response_revision:26459; number_of_response:1; }","duration":"144.247656ms","start":"2026-10-07T04:40:20.220509Z","end":"2026-10-07T04:40:20.364757Z","steps":["trace[140148900] 'process raft request' (duration: 74.038514ms)","trace[140148900] 'compare' (duration: 69.857445ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:20.555686Z","caller":"traceutil/trace.go:171","msg":"trace[1833792438] transaction","detail":"{read_only:false; response_revision:26460; number_of_response:1; }","duration":"182.714635ms","start":"2026-10-07T04:40:20.372949Z","end":"2026-10-07T04:40:20.555664Z","steps":["trace[1833792438] 'process raft request' (duration: 122.934211ms)","trace[1833792438] 'compare' (duration: 59.465998ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:20.804262Z","caller":"traceutil/trace.go:171","msg":"trace[1118058538] transaction","detail":"{read_only:false; response_revision:26462; number_of_response:1; }","duration":"243.035048ms","start":"2026-10-07T04:40:20.561126Z","end":"2026-10-07T04:40:20.804161Z","steps":["trace[1118058538] 'process raft request' (duration: 151.461962ms)","trace[1118058538] 'compare' (duration: 91.094327ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:20.804541Z","caller":"traceutil/trace.go:171","msg":"trace[1552547112] linearizableReadLoop","detail":"{readStateIndex:27340; appliedIndex:27338; }","duration":"235.382244ms","start":"2026-10-07T04:40:20.56913Z","end":"2026-10-07T04:40:20.804513Z","steps":["trace[1552547112] 'read index received' (duration: 72.608977ms)","trace[1552547112] 'applied index is now lower than readState.Index' (duration: 162.771187ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:20.80482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.656989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:20.804954Z","caller":"traceutil/trace.go:171","msg":"trace[1934992554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26464; }","duration":"235.840253ms","start":"2026-10-07T04:40:20.569086Z","end":"2026-10-07T04:40:20.804926Z","steps":["trace[1934992554] 'agreement among raft nodes before linearized reading' (duration: 235.555357ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:21.050304Z","caller":"traceutil/trace.go:171","msg":"trace[286696883] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"151.790558ms","start":"2026-10-07T04:40:20.898476Z","end":"2026-10-07T04:40:21.050267Z","steps":["trace[286696883] 'process raft request' (duration: 99.472367ms)","trace[286696883] 'compare' (duration: 52.149278ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:22.939834Z","caller":"traceutil/trace.go:171","msg":"trace[133672437] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"128.65451ms","start":"2026-10-07T04:40:22.811144Z","end":"2026-10-07T04:40:22.939798Z","steps":["trace[133672437] 'process raft request' (duration: 128.455106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:23.10276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.526051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:23.103035Z","caller":"traceutil/trace.go:171","msg":"trace[1388268748] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"288.688794ms","start":"2026-10-07T04:40:22.814329Z","end":"2026-10-07T04:40:23.103018Z","steps":["trace[1388268748] 'process raft request' (duration: 288.542412ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:23.103083Z","caller":"traceutil/trace.go:171","msg":"trace[1839531573] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"290.932877ms","start":"2026-10-07T04:40:22.812119Z","end":"2026-10-07T04:40:23.103052Z","steps":["trace[1839531573] 'process raft request' (duration: 155.962108ms)","trace[1839531573] 'compare' (duration: 134.206044ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:23.103109Z","caller":"traceutil/trace.go:171","msg":"trace[302792897] linearizableReadLoop","detail":"{readStateIndex:27357; appliedIndex:27354; }","duration":"217.004666ms","start":"2026-10-07T04:40:22.886076Z","end":"2026-10-07T04:40:23.10308Z","steps":["trace[302792897] 'read index received' (duration: 53.846011ms)","trace[302792897] 'applied index is now lower than readState.Index' (duration: 163.157365ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:23.103182Z","caller":"traceutil/trace.go:171","msg":"trace[803888628] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"286.905891ms","start":"2026-10-07T04:40:22.816265Z","end":"2026-10-07T04:40:23.103171Z","steps":["trace[803888628] 'process raft request' (duration: 286.715978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:23.103367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.27627ms","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-10-07T04:40:23.103419Z","caller":"traceutil/trace.go:171","msg":"trace[430154418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26479; }","duration":"217.401583ms","start":"2026-10-07T04:40:22.886002Z","end":"2026-10-07T04:40:23.103403Z","steps":["trace[430154418] 'agreement among raft nodes before linearized reading' (duration: 217.179099ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:23.314294Z","caller":"traceutil/trace.go:171","msg":"trace[2115157313] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"258.206606ms","start":"2026-10-07T04:40:23.056048Z","end":"2026-10-07T04:40:23.314255Z","steps":["trace[2115157313] 'process raft request' (duration: 164.276215ms)","trace[2115157313] 'compare' (duration: 93.528293ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:23.314783Z","caller":"traceutil/trace.go:171","msg":"trace[1048240721] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"207.008345ms","start":"2026-10-07T04:40:23.107742Z","end":"2026-10-07T04:40:23.314751Z","steps":["trace[1048240721] 'process raft request' (duration: 206.863612ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:23.314907Z","caller":"traceutil/trace.go:171","msg":"trace[100476118] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"253.575398ms","start":"2026-10-07T04:40:23.061285Z","end":"2026-10-07T04:40:23.31486Z","steps":["trace[100476118] 'process raft request' (duration: 253.200111ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:23.315024Z","caller":"traceutil/trace.go:171","msg":"trace[1475473072] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"177.71294ms","start":"2026-10-07T04:40:23.137042Z","end":"2026-10-07T04:40:23.314755Z","steps":["trace[1475473072] 'process raft request' (duration: 177.611238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:29.337517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.917017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:29.337798Z","caller":"traceutil/trace.go:171","msg":"trace[1561582493] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27401; }","duration":"171.217207ms","start":"2026-10-07T04:40:29.16656Z","end":"2026-10-07T04:40:29.337777Z","steps":["trace[1561582493] 'read index received' (duration: 53.887322ms)","trace[1561582493] 'applied index is now lower than readState.Index' (duration: 117.328645ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:29.337914Z","caller":"traceutil/trace.go:171","msg":"trace[968613020] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"178.446234ms","start":"2026-10-07T04:40:29.159455Z","end":"2026-10-07T04:40:29.337902Z","steps":["trace[968613020] 'process raft request' (duration: 60.964176ms)","trace[968613020] 'compare' (duration: 116.54165ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:29.338331Z","caller":"traceutil/trace.go:171","msg":"trace[695524571] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"176.877913ms","start":"2026-10-07T04:40:29.161404Z","end":"2026-10-07T04:40:29.338282Z","steps":["trace[695524571] 'process raft request' (duration: 176.250182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:29.338383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.635744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-07T04:40:29.338445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.857399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-07T04:40:29.338489Z","caller":"traceutil/trace.go:171","msg":"trace[616642813] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26524; }","duration":"133.818828ms","start":"2026-10-07T04:40:29.204645Z","end":"2026-10-07T04:40:29.338464Z","steps":["trace[616642813] 'agreement among raft nodes before linearized reading' (duration: 133.601835ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.338547Z","caller":"traceutil/trace.go:171","msg":"trace[484289299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26524; }","duration":"172.026022ms","start":"2026-10-07T04:40:29.166497Z","end":"2026-10-07T04:40:29.338523Z","steps":["trace[484289299] 'agreement among raft nodes before linearized reading' (duration: 171.645506ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:33.497734Z","caller":"traceutil/trace.go:171","msg":"trace[1683295572] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"144.827447ms","start":"2026-10-07T04:40:33.352868Z","end":"2026-10-07T04:40:33.497696Z","steps":["trace[1683295572] 'process raft request' (duration: 144.538082ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:33.498197Z","caller":"traceutil/trace.go:171","msg":"trace[1907137923] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"141.104456ms","start":"2026-10-07T04:40:33.357055Z","end":"2026-10-07T04:40:33.498159Z","steps":["trace[1907137923] 'process raft request' (duration: 140.594236ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:33.498267Z","caller":"traceutil/trace.go:171","msg":"trace[186915098] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"125.4883ms","start":"2026-10-07T04:40:33.372728Z","end":"2026-10-07T04:40:33.498216Z","steps":["trace[186915098] 'process raft request' (duration: 125.297177ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:33.498531Z","caller":"traceutil/trace.go:171","msg":"trace[1335213951] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"124.249556ms","start":"2026-10-07T04:40:33.374266Z","end":"2026-10-07T04:40:33.498516Z","steps":["trace[1335213951] 'process raft request' (duration: 123.89964ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:33.498606Z","caller":"traceutil/trace.go:171","msg":"trace[2118789153] linearizableReadLoop","detail":"{readStateIndex:27436; appliedIndex:27431; }","duration":"124.181884ms","start":"2026-10-07T04:40:33.374392Z","end":"2026-10-07T04:40:33.498574Z","steps":["trace[2118789153] 'read index received' (duration: 21.668081ms)","trace[2118789153] 'applied index is now lower than readState.Index' (duration: 102.512023ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:33.498699Z","caller":"traceutil/trace.go:171","msg":"trace[109259248] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"122.073284ms","start":"2026-10-07T04:40:33.376614Z","end":"2026-10-07T04:40:33.498687Z","steps":["trace[109259248] 'process raft request' (duration: 121.801369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:33.498926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.509871ms","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-10-07T04:40:33.498965Z","caller":"traceutil/trace.go:171","msg":"trace[170161631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26557; }","duration":"124.628424ms","start":"2026-10-07T04:40:33.374324Z","end":"2026-10-07T04:40:33.498953Z","steps":["trace[170161631] 'agreement among raft nodes before linearized reading' (duration: 124.42748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:33.499044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.390908ms","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-10-07T04:40:33.499089Z","caller":"traceutil/trace.go:171","msg":"trace[636523024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26557; }","duration":"106.490089ms","start":"2026-10-07T04:40:33.392587Z","end":"2026-10-07T04:40:33.499077Z","steps":["trace[636523024] 'agreement among raft nodes before linearized reading' (duration: 106.295275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:46.970436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.393218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-07T04:40:46.970546Z","caller":"traceutil/trace.go:171","msg":"trace[183545797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26650; }","duration":"173.587802ms","start":"2026-10-07T04:40:46.796931Z","end":"2026-10-07T04:40:46.970519Z","steps":["trace[183545797] 'range keys from in-memory index tree' (duration: 173.145123ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:46.976198Z","caller":"traceutil/trace.go:171","msg":"trace[2021047051] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"121.639176ms","start":"2026-10-07T04:40:46.85453Z","end":"2026-10-07T04:40:46.976169Z","steps":["trace[2021047051] 'process raft request' (duration: 121.070165ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:52.899784Z","caller":"traceutil/trace.go:171","msg":"trace[570268884] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"142.014293ms","start":"2026-10-07T04:40:52.757741Z","end":"2026-10-07T04:40:52.899755Z","steps":["trace[570268884] 'process raft request' (duration: 141.799018ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:03.830359Z","caller":"traceutil/trace.go:171","msg":"trace[206769497] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"148.804939ms","start":"2026-10-07T04:41:03.68152Z","end":"2026-10-07T04:41:03.830325Z","steps":["trace[206769497] 'process raft request' (duration: 148.522713ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:04.072901Z","caller":"traceutil/trace.go:171","msg":"trace[651466939] linearizableReadLoop","detail":"{readStateIndex:27814; appliedIndex:27812; }","duration":"366.338968ms","start":"2026-10-07T04:41:03.706535Z","end":"2026-10-07T04:41:04.072874Z","steps":["trace[651466939] 'read index received' (duration: 124.473348ms)","trace[651466939] 'applied index is now lower than readState.Index' (duration: 241.86398ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:41:04.073217Z","caller":"traceutil/trace.go:171","msg":"trace[227636732] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"391.670837ms","start":"2026-10-07T04:41:03.681534Z","end":"2026-10-07T04:41:04.073204Z","steps":["trace[227636732] 'process raft request' (duration: 390.993954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.07337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.681493Z","time spent":"391.784569ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:41:04.073669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.419451ms","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-10-07T04:41:04.073709Z","caller":"traceutil/trace.go:171","msg":"trace[706888721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26928; }","duration":"337.501083ms","start":"2026-10-07T04:41:03.736198Z","end":"2026-10-07T04:41:04.073699Z","steps":["trace[706888721] 'agreement among raft nodes before linearized reading' (duration: 337.36111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.073738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.736181Z","time spent":"337.548713ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-07T04:41:04.073866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.768664ms","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-10-07T04:41:04.073904Z","caller":"traceutil/trace.go:171","msg":"trace[475732260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26928; }","duration":"331.822734ms","start":"2026-10-07T04:41:03.742071Z","end":"2026-10-07T04:41:04.073893Z","steps":["trace[475732260] 'agreement among raft nodes before linearized reading' (duration: 331.732753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.073928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.742056Z","time spent":"331.866236ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-07T04:41:04.073927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.530113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-07T04:41:04.073816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.231255ms","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-10-07T04:41:04.074064Z","caller":"traceutil/trace.go:171","msg":"trace[241658945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26928; }","duration":"367.557071ms","start":"2026-10-07T04:41:03.706495Z","end":"2026-10-07T04:41:04.074052Z","steps":["trace[241658945] 'agreement among raft nodes before linearized reading' (duration: 367.023961ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:04.074063Z","caller":"traceutil/trace.go:171","msg":"trace[835940602] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26928; }","duration":"298.715917ms","start":"2026-10-07T04:41:03.775299Z","end":"2026-10-07T04:41:04.074015Z","steps":["trace[835940602] 'agreement among raft nodes before linearized reading' (duration: 298.37681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.074103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.706477Z","time spent":"367.616742ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-07T04:41:04.073961Z","caller":"traceutil/trace.go:171","msg":"trace[1494669213] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"392.276069ms","start":"2026-10-07T04:41:03.681657Z","end":"2026-10-07T04:41:04.073933Z","steps":["trace[1494669213] 'process raft request' (duration: 391.035875ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:04.074309Z","caller":"traceutil/trace.go:171","msg":"trace[423948312] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"307.24255ms","start":"2026-10-07T04:41:03.767044Z","end":"2026-10-07T04:41:04.074286Z","steps":["trace[423948312] 'process raft request' (duration: 305.70183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.074364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.681604Z","time spent":"392.700427ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:41:04.074395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:41:03.767007Z","time spent":"307.344981ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:41:04.277192Z","caller":"traceutil/trace.go:171","msg":"trace[897554528] transaction","detail":"{read_only:false; response_revision:26931; number_of_response:1; }","duration":"195.402701ms","start":"2026-10-07T04:41:04.081766Z","end":"2026-10-07T04:41:04.277169Z","steps":["trace[897554528] 'process raft request' (duration: 195.295569ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:04.27748Z","caller":"traceutil/trace.go:171","msg":"trace[933820881] linearizableReadLoop","detail":"{readStateIndex:27818; appliedIndex:27815; }","duration":"194.930132ms","start":"2026-10-07T04:41:04.082527Z","end":"2026-10-07T04:41:04.277457Z","steps":["trace[933820881] 'read index received' (duration: 39.5992ms)","trace[933820881] 'applied index is now lower than readState.Index' (duration: 155.329972ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:41:04.277465Z","caller":"traceutil/trace.go:171","msg":"trace[269774493] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"195.809628ms","start":"2026-10-07T04:41:04.081634Z","end":"2026-10-07T04:41:04.277443Z","steps":["trace[269774493] 'process raft request' (duration: 159.48194ms)","trace[269774493] 'compare' (duration: 35.795198ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:41:04.277686Z","caller":"traceutil/trace.go:171","msg":"trace[693454631] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"194.34915ms","start":"2026-10-07T04:41:04.08329Z","end":"2026-10-07T04:41:04.277639Z","steps":["trace[693454631] 'process raft request' (duration: 193.82277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.277879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.333169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19026"} {"level":"info","ts":"2026-10-07T04:41:04.277936Z","caller":"traceutil/trace.go:171","msg":"trace[1254291605] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26932; }","duration":"195.423952ms","start":"2026-10-07T04:41:04.082494Z","end":"2026-10-07T04:41:04.277918Z","steps":["trace[1254291605] 'agreement among raft nodes before linearized reading' (duration: 195.059715ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:04.277926Z","caller":"traceutil/trace.go:171","msg":"trace[1801739782] transaction","detail":"{read_only:false; response_revision:26933; number_of_response:1; }","duration":"148.678796ms","start":"2026-10-07T04:41:04.129222Z","end":"2026-10-07T04:41:04.2779Z","steps":["trace[1801739782] 'process raft request' (duration: 148.133156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.487392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.940332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:41:04.487542Z","caller":"traceutil/trace.go:171","msg":"trace[1998321048] linearizableReadLoop","detail":"{readStateIndex:27821; appliedIndex:27820; }","duration":"111.771577ms","start":"2026-10-07T04:41:04.375749Z","end":"2026-10-07T04:41:04.487521Z","steps":["trace[1998321048] 'read index received' (duration: 9.990039ms)","trace[1998321048] 'applied index is now lower than readState.Index' (duration: 101.779608ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:41:04.487578Z","caller":"traceutil/trace.go:171","msg":"trace[642642120] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"160.711283ms","start":"2026-10-07T04:41:04.326816Z","end":"2026-10-07T04:41:04.487528Z","steps":["trace[642642120] 'process raft request' (duration: 59.478926ms)","trace[642642120] 'compare' (duration: 100.797689ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:41:04.487789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.037512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-07T04:41:04.487826Z","caller":"traceutil/trace.go:171","msg":"trace[1848495844] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26934; }","duration":"112.099773ms","start":"2026-10-07T04:41:04.375715Z","end":"2026-10-07T04:41:04.487815Z","steps":["trace[1848495844] 'agreement among raft nodes before linearized reading' (duration: 111.917599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:41:04.488529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.859207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-07T04:41:04.488587Z","caller":"traceutil/trace.go:171","msg":"trace[1961715432] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26934; }","duration":"105.000089ms","start":"2026-10-07T04:41:04.383572Z","end":"2026-10-07T04:41:04.488572Z","steps":["trace[1961715432] 'agreement among raft nodes before linearized reading' (duration: 104.659313ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:42:26.838989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24802} {"level":"info","ts":"2026-10-07T04:42:27.003032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24802,"took":"158.665425ms","hash":1154474121,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":36556800,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-07T04:42:27.003109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1154474121,"revision":24802,"compact-revision":21596} {"level":"info","ts":"2026-10-07T04:44:49.645231Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a99b527d1e65fdd9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:44:49.650831Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-07T04:44:49.650935Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-07T04:47:26.854822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27646} {"level":"info","ts":"2026-10-07T04:47:26.996984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27646,"took":"136.844212ms","hash":1440989541,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":36577280,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-07T04:47:26.997223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1440989541,"revision":27646,"compact-revision":24802} {"level":"info","ts":"2026-10-07T04:52:26.861796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30266} {"level":"info","ts":"2026-10-07T04:52:27.011865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30266,"took":"144.78295ms","hash":144908740,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":30535680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T04:52:27.011954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":144908740,"revision":30266,"compact-revision":27646} {"level":"info","ts":"2026-10-07T04:57:26.870377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32516} {"level":"info","ts":"2026-10-07T04:57:27.025983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32516,"took":"149.671273ms","hash":1540384581,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T04:57:27.026065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540384581,"revision":32516,"compact-revision":30266} {"level":"info","ts":"2026-10-07T05:02:26.879561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34746} {"level":"info","ts":"2026-10-07T05:02:27.013037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34746,"took":"127.982469ms","hash":879522547,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":31432704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T05:02:27.013135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":879522547,"revision":34746,"compact-revision":32516} {"level":"info","ts":"2026-10-07T05:06:02.551225Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a99b527d1e65fdd9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T05:06:02.558389Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-07T05:06:02.558643Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-07T05:07:26.887936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37097} {"level":"info","ts":"2026-10-07T05:07:27.030337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37097,"took":"134.778401ms","hash":2068749721,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":33222656,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-07T05:07:27.030414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2068749721,"revision":37097,"compact-revision":34746} {"level":"info","ts":"2026-10-07T05:12:26.896854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39406} {"level":"info","ts":"2026-10-07T05:12:27.037057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39406,"took":"135.344012ms","hash":482598940,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T05:12:27.037136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":482598940,"revision":39406,"compact-revision":37097}