{"level":"warn","ts":"2026-09-07T08:35:23.095556Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T08:35:23.095699Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-07T08:35:23.095795Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T08:35:23.095811Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-07T08:35:23.095839Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T08:35:23.096762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-09-07T08:35:23.096971Z","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":8,"max-cpu-available":8,"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.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-07T08:35:23.101907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.649486ms"} {"level":"info","ts":"2026-09-07T08:35:23.111238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-09-07T08:35:23.111479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T08:35:23.11154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-09-07T08:35:23.111604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T08:35:23.111619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-09-07T08:35:23.111688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-09-07T08:35:23.121923Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T08:35:23.123527Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T08:35:23.124889Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-07T08:35:23.127385Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T08:35:23.127532Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T08:35:23.127817Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T08:35:23.127922Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T08:35:23.127935Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T08:35:23.129488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-09-07T08:35:23.129929Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-07T08:35:23.131349Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T08:35:23.131533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-07T08:35:23.131611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-07T08:35:23.131723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T08:35:23.131752Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T08:35:23.41234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T08:35:23.412527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T08:35:23.412564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-09-07T08:35:23.412611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-09-07T08:35:23.412651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-07T08:35:23.412699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-09-07T08:35:23.412727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-07T08:35:23.414179Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T08:35:23.41426Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T08:35:23.414434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T08:35:23.414511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T08:35:23.414851Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T08:35:23.41494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T08:35:23.416305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T08:35:23.41646Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T08:35:23.416539Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T08:35:23.417522Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"info","ts":"2026-09-07T08:35:23.417726Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T08:35:28.363492Z","caller":"traceutil/trace.go:171","msg":"trace[1996927763] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"117.656625ms","start":"2026-09-07T08:35:28.245799Z","end":"2026-09-07T08:35:28.363455Z","steps":["trace[1996927763] 'process raft request' (duration: 46.7891ms)","trace[1996927763] 'compare' (duration: 70.319523ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:35:42.916593Z","caller":"traceutil/trace.go:171","msg":"trace[1520203114] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"157.855598ms","start":"2026-09-07T08:35:42.758686Z","end":"2026-09-07T08:35:42.916542Z","steps":["trace[1520203114] 'process raft request' (duration: 157.57637ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:35:43.054865Z","caller":"traceutil/trace.go:171","msg":"trace[1735336273] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"133.38735ms","start":"2026-09-07T08:35:42.921444Z","end":"2026-09-07T08:35:43.054831Z","steps":["trace[1735336273] 'process raft request' (duration: 114.093055ms)","trace[1735336273] 'compare' (duration: 19.116939ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:35:59.850105Z","caller":"traceutil/trace.go:171","msg":"trace[1932005727] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"103.134347ms","start":"2026-09-07T08:35:59.746946Z","end":"2026-09-07T08:35:59.850081Z","steps":["trace[1932005727] 'process raft request' (duration: 100.055254ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:35:59.887118Z","caller":"traceutil/trace.go:171","msg":"trace[2062787524] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"100.551537ms","start":"2026-09-07T08:35:59.786527Z","end":"2026-09-07T08:35:59.887079Z","steps":["trace[2062787524] 'process raft request' (duration: 100.308661ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.048816Z","caller":"traceutil/trace.go:171","msg":"trace[1726892560] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"122.199562ms","start":"2026-09-07T08:35:59.926598Z","end":"2026-09-07T08:36:00.048797Z","steps":["trace[1726892560] 'read index received' (duration: 96.469481ms)","trace[1726892560] 'applied index is now lower than readState.Index' (duration: 25.729301ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:36:00.048896Z","caller":"traceutil/trace.go:171","msg":"trace[1540310354] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"146.112456ms","start":"2026-09-07T08:35:59.902739Z","end":"2026-09-07T08:36:00.048851Z","steps":["trace[1540310354] 'process raft request' (duration: 120.26951ms)","trace[1540310354] 'compare' (duration: 25.567878ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:36:00.049229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.605063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-09-07T08:36:00.049299Z","caller":"traceutil/trace.go:171","msg":"trace[363245770] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:508; }","duration":"122.730597ms","start":"2026-09-07T08:35:59.926554Z","end":"2026-09-07T08:36:00.049285Z","steps":["trace[363245770] 'agreement among raft nodes before linearized reading' (duration: 122.329046ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.049681Z","caller":"traceutil/trace.go:171","msg":"trace[1038484404] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"120.235151ms","start":"2026-09-07T08:35:59.929436Z","end":"2026-09-07T08:36:00.049671Z","steps":["trace[1038484404] 'process raft request' (duration: 119.80418ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.049934Z","caller":"traceutil/trace.go:171","msg":"trace[1249616743] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"107.713421ms","start":"2026-09-07T08:35:59.94221Z","end":"2026-09-07T08:36:00.049923Z","steps":["trace[1249616743] 'process raft request' (duration: 107.354531ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.049936Z","caller":"traceutil/trace.go:171","msg":"trace[1267980469] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"111.665475ms","start":"2026-09-07T08:35:59.938249Z","end":"2026-09-07T08:36:00.049915Z","steps":["trace[1267980469] 'process raft request' (duration: 111.265774ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.050129Z","caller":"traceutil/trace.go:171","msg":"trace[2037048572] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"129.128447ms","start":"2026-09-07T08:35:59.919803Z","end":"2026-09-07T08:36:00.048931Z","steps":["trace[2037048572] 'process raft request' (duration: 128.90423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:00.676523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.34308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:36:00.676754Z","caller":"traceutil/trace.go:171","msg":"trace[1726922769] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"385.253211ms","start":"2026-09-07T08:36:00.291482Z","end":"2026-09-07T08:36:00.676735Z","steps":["trace[1726922769] 'process raft request' (duration: 385.161198ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:00.676769Z","caller":"traceutil/trace.go:171","msg":"trace[372678903] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"386.293818ms","start":"2026-09-07T08:36:00.290448Z","end":"2026-09-07T08:36:00.676742Z","steps":["trace[372678903] 'process raft request' (duration: 257.590167ms)","trace[372678903] 'compare' (duration: 125.879176ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:36:00.676846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:00.291462Z","time spent":"385.343063ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T08:36:00.676876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:00.290427Z","time spent":"386.386811ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:36:00.678507Z","caller":"traceutil/trace.go:171","msg":"trace[1595338701] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"331.67682ms","start":"2026-09-07T08:36:00.346809Z","end":"2026-09-07T08:36:00.678486Z","steps":["trace[1595338701] 'process raft request' (duration: 331.231658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:00.678619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:00.346788Z","time spent":"331.775362ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:36:06.670954Z","caller":"traceutil/trace.go:171","msg":"trace[1869682740] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"121.345767ms","start":"2026-09-07T08:36:06.549562Z","end":"2026-09-07T08:36:06.670908Z","steps":["trace[1869682740] 'process raft request' (duration: 120.908665ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:06.859826Z","caller":"traceutil/trace.go:171","msg":"trace[2124679611] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"121.382857ms","start":"2026-09-07T08:36:06.738406Z","end":"2026-09-07T08:36:06.859788Z","steps":["trace[2124679611] 'process raft request' (duration: 39.078802ms)","trace[2124679611] 'compare' (duration: 82.181402ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:36:07.767189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.792084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:36:07.767901Z","caller":"traceutil/trace.go:171","msg":"trace[589406461] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"568.220651ms","start":"2026-09-07T08:36:07.199634Z","end":"2026-09-07T08:36:07.767855Z","steps":["trace[589406461] 'process raft request' (duration: 378.672717ms)","trace[589406461] 'compare' (duration: 188.441854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:36:07.768068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:07.199617Z","time spent":"568.379805ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:36:07.768455Z","caller":"traceutil/trace.go:171","msg":"trace[738910799] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"533.980497ms","start":"2026-09-07T08:36:07.234429Z","end":"2026-09-07T08:36:07.768409Z","steps":["trace[738910799] 'read index received' (duration: 343.9549ms)","trace[738910799] 'applied index is now lower than readState.Index' (duration: 190.021857ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:36:07.768641Z","caller":"traceutil/trace.go:171","msg":"trace[1208579489] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"476.30558ms","start":"2026-09-07T08:36:07.292319Z","end":"2026-09-07T08:36:07.768625Z","steps":["trace[1208579489] 'process raft request' (duration: 475.350985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:07.768711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:07.292291Z","time spent":"476.385273ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T08:36:07.768833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.388679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-07T08:36:07.768766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.588314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T08:36:07.768895Z","caller":"traceutil/trace.go:171","msg":"trace[909604897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"534.502112ms","start":"2026-09-07T08:36:07.234375Z","end":"2026-09-07T08:36:07.768877Z","steps":["trace[909604897] 'agreement among raft nodes before linearized reading' (duration: 534.233804ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:07.768909Z","caller":"traceutil/trace.go:171","msg":"trace[67842176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"173.797309ms","start":"2026-09-07T08:36:07.595092Z","end":"2026-09-07T08:36:07.76889Z","steps":["trace[67842176] 'agreement among raft nodes before linearized reading' (duration: 173.588803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:07.768947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:07.234353Z","time spent":"534.582013ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T08:36:07.769023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.540648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-07T08:36:07.769124Z","caller":"traceutil/trace.go:171","msg":"trace[1987366047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"519.719643ms","start":"2026-09-07T08:36:07.249381Z","end":"2026-09-07T08:36:07.769101Z","steps":["trace[1987366047] 'agreement among raft nodes before linearized reading' (duration: 519.25733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:07.769242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:07.249359Z","time spent":"519.868106ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-07T08:36:22.844473Z","caller":"traceutil/trace.go:171","msg":"trace[192825860] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"250.150115ms","start":"2026-09-07T08:36:22.594296Z","end":"2026-09-07T08:36:22.844446Z","steps":["trace[192825860] 'read index received' (duration: 249.98912ms)","trace[192825860] 'applied index is now lower than readState.Index' (duration: 160.005µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:36:22.84455Z","caller":"traceutil/trace.go:171","msg":"trace[343199401] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"318.744625ms","start":"2026-09-07T08:36:22.52577Z","end":"2026-09-07T08:36:22.844515Z","steps":["trace[343199401] 'process raft request' (duration: 318.514628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:22.844614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.298879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T08:36:22.84473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:22.525747Z","time spent":"318.879359ms","remote":"127.0.0.1:55152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:36:22.844789Z","caller":"traceutil/trace.go:171","msg":"trace[510400048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"250.532945ms","start":"2026-09-07T08:36:22.594241Z","end":"2026-09-07T08:36:22.844774Z","steps":["trace[510400048] 'agreement among raft nodes before linearized reading' (duration: 250.32037ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:27.376135Z","caller":"traceutil/trace.go:171","msg":"trace[1801137630] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"147.132503ms","start":"2026-09-07T08:36:27.228946Z","end":"2026-09-07T08:36:27.376078Z","steps":["trace[1801137630] 'process raft request' (duration: 111.326897ms)","trace[1801137630] 'compare' (duration: 35.667672ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:36:27.40166Z","caller":"traceutil/trace.go:171","msg":"trace[1610337675] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"172.31259ms","start":"2026-09-07T08:36:27.229318Z","end":"2026-09-07T08:36:27.40163Z","steps":["trace[1610337675] 'read index received' (duration: 110.969117ms)","trace[1610337675] 'applied index is now lower than readState.Index' (duration: 61.341643ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:36:27.401718Z","caller":"traceutil/trace.go:171","msg":"trace[378497780] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"170.214161ms","start":"2026-09-07T08:36:27.231477Z","end":"2026-09-07T08:36:27.401691Z","steps":["trace[378497780] 'process raft request' (duration: 170.067957ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:27.401857Z","caller":"traceutil/trace.go:171","msg":"trace[1204308999] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"172.762963ms","start":"2026-09-07T08:36:27.229074Z","end":"2026-09-07T08:36:27.401837Z","steps":["trace[1204308999] 'process raft request' (duration: 172.321291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:27.401907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.562067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T08:36:27.401975Z","caller":"traceutil/trace.go:171","msg":"trace[1686271894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"172.66933ms","start":"2026-09-07T08:36:27.229287Z","end":"2026-09-07T08:36:27.401956Z","steps":["trace[1686271894] 'agreement among raft nodes before linearized reading' (duration: 172.474145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:27.405414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.131894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-09-07T08:36:27.405481Z","caller":"traceutil/trace.go:171","msg":"trace[77090731] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:795; }","duration":"172.240327ms","start":"2026-09-07T08:36:27.233223Z","end":"2026-09-07T08:36:27.405464Z","steps":["trace[77090731] 'agreement among raft nodes before linearized reading' (duration: 172.019671ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:36:55.285708Z","caller":"traceutil/trace.go:171","msg":"trace[706217128] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"370.106528ms","start":"2026-09-07T08:36:54.915543Z","end":"2026-09-07T08:36:55.285649Z","steps":["trace[706217128] 'process raft request' (duration: 369.927143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:36:55.285886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:36:54.915514Z","time spent":"370.282884ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:37:46.864815Z","caller":"traceutil/trace.go:171","msg":"trace[722092988] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"380.515698ms","start":"2026-09-07T08:37:46.484276Z","end":"2026-09-07T08:37:46.864791Z","steps":["trace[722092988] 'read index received' (duration: 380.273781ms)","trace[722092988] 'applied index is now lower than readState.Index' (duration: 240.737µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:37:46.864915Z","caller":"traceutil/trace.go:171","msg":"trace[385994344] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"479.81341ms","start":"2026-09-07T08:37:46.385062Z","end":"2026-09-07T08:37:46.864876Z","steps":["trace[385994344] 'process raft request' (duration: 479.589743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:37:46.865076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.252198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T08:37:46.865113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:37:46.385041Z","time spent":"479.963684ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:37:46.865174Z","caller":"traceutil/trace.go:171","msg":"trace[1631485322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"271.388152ms","start":"2026-09-07T08:37:46.593761Z","end":"2026-09-07T08:37:46.865149Z","steps":["trace[1631485322] 'agreement among raft nodes before linearized reading' (duration: 271.243326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:37:46.865222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.908441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-07T08:37:46.865922Z","caller":"traceutil/trace.go:171","msg":"trace[1950015319] range","detail":"{range_begin:/registry/masterleases/162.253.55.166; range_end:; response_count:1; response_revision:1858; }","duration":"381.683353ms","start":"2026-09-07T08:37:46.484219Z","end":"2026-09-07T08:37:46.865903Z","steps":["trace[1950015319] 'agreement among raft nodes before linearized reading' (duration: 380.774966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:37:46.865988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:37:46.48419Z","time spent":"381.783227ms","remote":"127.0.0.1:55030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.166\" "} {"level":"warn","ts":"2026-09-07T08:37:46.865383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.192614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T08:37:46.86623Z","caller":"traceutil/trace.go:171","msg":"trace[443031296] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1858; }","duration":"176.03693ms","start":"2026-09-07T08:37:46.69012Z","end":"2026-09-07T08:37:46.866157Z","steps":["trace[443031296] 'agreement among raft nodes before linearized reading' (duration: 175.132503ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:37:50.040148Z","caller":"traceutil/trace.go:171","msg":"trace[1886974865] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"100.826001ms","start":"2026-09-07T08:37:49.939294Z","end":"2026-09-07T08:37:50.04012Z","steps":["trace[1886974865] 'process raft request' (duration: 100.338937ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:02.452903Z","caller":"traceutil/trace.go:171","msg":"trace[369570731] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"346.369254ms","start":"2026-09-07T08:38:02.106515Z","end":"2026-09-07T08:38:02.452884Z","steps":["trace[369570731] 'process raft request' (duration: 346.263541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:02.453046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:02.106503Z","time spent":"346.461296ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:38:02.45365Z","caller":"traceutil/trace.go:171","msg":"trace[138815811] linearizableReadLoop","detail":"{readStateIndex:2067; appliedIndex:2067; }","duration":"333.095821ms","start":"2026-09-07T08:38:02.120533Z","end":"2026-09-07T08:38:02.453629Z","steps":["trace[138815811] 'read index received' (duration: 333.07873ms)","trace[138815811] 'applied index is now lower than readState.Index' (duration: 15.411µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:38:02.453827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.288846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T08:38:02.45387Z","caller":"traceutil/trace.go:171","msg":"trace[122258578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1989; }","duration":"333.363378ms","start":"2026-09-07T08:38:02.120495Z","end":"2026-09-07T08:38:02.453858Z","steps":["trace[122258578] 'agreement among raft nodes before linearized reading' (duration: 333.221324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:02.453922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:02.120465Z","time spent":"333.43497ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-07T08:38:02.512571Z","caller":"traceutil/trace.go:171","msg":"trace[466289788] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"171.428563ms","start":"2026-09-07T08:38:02.34112Z","end":"2026-09-07T08:38:02.512548Z","steps":["trace[466289788] 'process raft request' (duration: 171.30687ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:02.512879Z","caller":"traceutil/trace.go:171","msg":"trace[409969442] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"174.097494ms","start":"2026-09-07T08:38:02.338416Z","end":"2026-09-07T08:38:02.512513Z","steps":["trace[409969442] 'process raft request' (duration: 173.884468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:02.513311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.163748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T08:38:02.513373Z","caller":"traceutil/trace.go:171","msg":"trace[2133608463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1991; }","duration":"103.26253ms","start":"2026-09-07T08:38:02.410099Z","end":"2026-09-07T08:38:02.513361Z","steps":["trace[2133608463] 'agreement among raft nodes before linearized reading' (duration: 102.403374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:25.359119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.387636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-09-07T08:38:25.359208Z","caller":"traceutil/trace.go:171","msg":"trace[1807990046] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2118; }","duration":"120.53167ms","start":"2026-09-07T08:38:25.238661Z","end":"2026-09-07T08:38:25.359192Z","steps":["trace[1807990046] 'range keys from in-memory index tree' (duration: 120.183229ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:25.790097Z","caller":"traceutil/trace.go:171","msg":"trace[1517531115] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"128.269077ms","start":"2026-09-07T08:38:25.661813Z","end":"2026-09-07T08:38:25.790083Z","steps":["trace[1517531115] 'process raft request' (duration: 128.065261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:25.790193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.99366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T08:38:25.790221Z","caller":"traceutil/trace.go:171","msg":"trace[320972536] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2119; }","duration":"114.075582ms","start":"2026-09-07T08:38:25.676137Z","end":"2026-09-07T08:38:25.790213Z","steps":["trace[320972536] 'agreement among raft nodes before linearized reading' (duration: 114.010541ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:25.790031Z","caller":"traceutil/trace.go:171","msg":"trace[422837957] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"113.827375ms","start":"2026-09-07T08:38:25.676182Z","end":"2026-09-07T08:38:25.79001Z","steps":["trace[422837957] 'read index received' (duration: 113.65666ms)","trace[422837957] 'applied index is now lower than readState.Index' (duration: 170.065µs)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:38:26.316057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.062895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T08:38:26.31612Z","caller":"traceutil/trace.go:171","msg":"trace[413954406] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"149.177089ms","start":"2026-09-07T08:38:26.166927Z","end":"2026-09-07T08:38:26.316104Z","steps":["trace[413954406] 'range keys from in-memory index tree' (duration: 148.885729ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:26.903532Z","caller":"traceutil/trace.go:171","msg":"trace[232692754] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2204; }","duration":"219.217023ms","start":"2026-09-07T08:38:26.684294Z","end":"2026-09-07T08:38:26.903511Z","steps":["trace[232692754] 'read index received' (duration: 197.831748ms)","trace[232692754] 'applied index is now lower than readState.Index' (duration: 21.384315ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:38:26.903748Z","caller":"traceutil/trace.go:171","msg":"trace[925140447] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"241.168855ms","start":"2026-09-07T08:38:26.662564Z","end":"2026-09-07T08:38:26.903733Z","steps":["trace[925140447] 'process raft request' (duration: 240.895927ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:26.903801Z","caller":"traceutil/trace.go:171","msg":"trace[203968082] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"243.738904ms","start":"2026-09-07T08:38:26.66003Z","end":"2026-09-07T08:38:26.903768Z","steps":["trace[203968082] 'process raft request' (duration: 221.939767ms)","trace[203968082] 'compare' (duration: 21.362384ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:38:26.904025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.034213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T08:38:26.904067Z","caller":"traceutil/trace.go:171","msg":"trace[1094372444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2122; }","duration":"309.085454ms","start":"2026-09-07T08:38:26.594968Z","end":"2026-09-07T08:38:26.904053Z","steps":["trace[1094372444] 'agreement among raft nodes before linearized reading' (duration: 308.94493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:26.904098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.594938Z","time spent":"309.152466ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-07T08:38:27.294071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.838413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:38:27.294314Z","caller":"traceutil/trace.go:171","msg":"trace[1383475386] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"385.523527ms","start":"2026-09-07T08:38:26.908768Z","end":"2026-09-07T08:38:27.294291Z","steps":["trace[1383475386] 'process raft request' (duration: 385.436265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.294374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.908743Z","time spent":"385.60318ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:38:27.294472Z","caller":"traceutil/trace.go:171","msg":"trace[256030686] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"390.795279ms","start":"2026-09-07T08:38:26.903629Z","end":"2026-09-07T08:38:27.294424Z","steps":["trace[256030686] 'read index received' (duration: 170.43564ms)","trace[256030686] 'applied index is now lower than readState.Index' (duration: 220.331539ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:38:27.294972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.825048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-07T08:38:27.295041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.085387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T08:38:27.295097Z","caller":"traceutil/trace.go:171","msg":"trace[1517266392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2124; }","duration":"359.16987ms","start":"2026-09-07T08:38:26.935909Z","end":"2026-09-07T08:38:27.295079Z","steps":["trace[1517266392] 'agreement among raft nodes before linearized reading' (duration: 359.097148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.294989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.572377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T08:38:27.295226Z","caller":"traceutil/trace.go:171","msg":"trace[705928799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2124; }","duration":"591.842186ms","start":"2026-09-07T08:38:26.703353Z","end":"2026-09-07T08:38:27.295195Z","steps":["trace[705928799] 'agreement among raft nodes before linearized reading' (duration: 591.387033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.295146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.935888Z","time spent":"359.244762ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-09-07T08:38:27.295259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.568755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T08:38:27.295242Z","caller":"traceutil/trace.go:171","msg":"trace[1826176426] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"610.168406ms","start":"2026-09-07T08:38:26.685047Z","end":"2026-09-07T08:38:27.295216Z","steps":["trace[1826176426] 'process raft request' (duration: 389.038833ms)","trace[1826176426] 'compare' (duration: 219.682319ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:38:27.295381Z","caller":"traceutil/trace.go:171","msg":"trace[316776061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"604.745982ms","start":"2026-09-07T08:38:26.690603Z","end":"2026-09-07T08:38:27.295349Z","steps":["trace[316776061] 'agreement among raft nodes before linearized reading' (duration: 604.208545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.295321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.703328Z","time spent":"591.982501ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-07T08:38:27.295462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.690566Z","time spent":"604.880556ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-07T08:38:27.295379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.674589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-07T08:38:27.295535Z","caller":"traceutil/trace.go:171","msg":"trace[2067187750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2124; }","duration":"596.416616ms","start":"2026-09-07T08:38:26.699106Z","end":"2026-09-07T08:38:27.295523Z","steps":["trace[2067187750] 'agreement among raft nodes before linearized reading' (duration: 595.691173ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:27.295647Z","caller":"traceutil/trace.go:171","msg":"trace[257134582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2124; }","duration":"139.912966ms","start":"2026-09-07T08:38:27.155657Z","end":"2026-09-07T08:38:27.29557Z","steps":["trace[257134582] 'agreement among raft nodes before linearized reading' (duration: 139.443031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.295399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.685027Z","time spent":"610.337142ms","remote":"127.0.0.1:55030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T08:38:27.29575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.699096Z","time spent":"596.469758ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T08:38:27.294962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.911249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T08:38:27.295864Z","caller":"traceutil/trace.go:171","msg":"trace[371050298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"610.870438ms","start":"2026-09-07T08:38:26.684985Z","end":"2026-09-07T08:38:27.295856Z","steps":["trace[371050298] 'agreement among raft nodes before linearized reading' (duration: 609.880527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.295945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:38:26.684975Z","time spent":"610.91094ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T08:38:27.562926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.923813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:38:27.563061Z","caller":"traceutil/trace.go:171","msg":"trace[395500414] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"260.617643ms","start":"2026-09-07T08:38:27.302428Z","end":"2026-09-07T08:38:27.563046Z","steps":["trace[395500414] 'process raft request' (duration: 141.498755ms)","trace[395500414] 'compare' (duration: 118.84205ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:38:27.563314Z","caller":"traceutil/trace.go:171","msg":"trace[370625096] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"260.793239ms","start":"2026-09-07T08:38:27.302509Z","end":"2026-09-07T08:38:27.563302Z","steps":["trace[370625096] 'process raft request' (duration: 260.50817ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:38:27.563321Z","caller":"traceutil/trace.go:171","msg":"trace[1933102369] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2209; }","duration":"189.134704ms","start":"2026-09-07T08:38:27.374166Z","end":"2026-09-07T08:38:27.563301Z","steps":["trace[1933102369] 'read index received' (duration: 69.808679ms)","trace[1933102369] 'applied index is now lower than readState.Index' (duration: 119.325095ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:38:27.563418Z","caller":"traceutil/trace.go:171","msg":"trace[149694124] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"260.798719ms","start":"2026-09-07T08:38:27.302599Z","end":"2026-09-07T08:38:27.563398Z","steps":["trace[149694124] 'process raft request' (duration: 260.631933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:38:27.563473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.561109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T08:38:27.563503Z","caller":"traceutil/trace.go:171","msg":"trace[896408077] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"240.677322ms","start":"2026-09-07T08:38:27.322814Z","end":"2026-09-07T08:38:27.563492Z","steps":["trace[896408077] 'agreement among raft nodes before linearized reading' (duration: 240.552708ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:07.516761Z","caller":"traceutil/trace.go:171","msg":"trace[436194494] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"151.56322ms","start":"2026-09-07T08:39:07.365166Z","end":"2026-09-07T08:39:07.516729Z","steps":["trace[436194494] 'process raft request' (duration: 151.155422ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:18.399115Z","caller":"traceutil/trace.go:171","msg":"trace[1708289429] transaction","detail":"{read_only:false; response_revision:2508; number_of_response:1; }","duration":"406.547232ms","start":"2026-09-07T08:39:17.992547Z","end":"2026-09-07T08:39:18.399094Z","steps":["trace[1708289429] 'process raft request' (duration: 405.971129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:39:18.399223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:39:17.992518Z","time spent":"406.648973ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:39:18.400202Z","caller":"traceutil/trace.go:171","msg":"trace[1557923032] linearizableReadLoop","detail":"{readStateIndex:2605; appliedIndex:2603; }","duration":"187.737535ms","start":"2026-09-07T08:39:18.212435Z","end":"2026-09-07T08:39:18.400173Z","steps":["trace[1557923032] 'read index received' (duration: 185.983417ms)","trace[1557923032] 'applied index is now lower than readState.Index' (duration: 1.753328ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:39:18.400428Z","caller":"traceutil/trace.go:171","msg":"trace[934375257] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"283.523859ms","start":"2026-09-07T08:39:18.116886Z","end":"2026-09-07T08:39:18.40041Z","steps":["trace[934375257] 'process raft request' (duration: 283.188222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:39:18.401013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.597843ms","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-09-07T08:39:18.401082Z","caller":"traceutil/trace.go:171","msg":"trace[122867695] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2509; }","duration":"188.675634ms","start":"2026-09-07T08:39:18.212394Z","end":"2026-09-07T08:39:18.40107Z","steps":["trace[122867695] 'agreement among raft nodes before linearized reading' (duration: 187.870047ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:19.957799Z","caller":"traceutil/trace.go:171","msg":"trace[436670674] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"121.871376ms","start":"2026-09-07T08:39:19.835908Z","end":"2026-09-07T08:39:19.957779Z","steps":["trace[436670674] 'process raft request' (duration: 121.747783ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:23.317776Z","caller":"traceutil/trace.go:171","msg":"trace[1172385203] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"141.677051ms","start":"2026-09-07T08:39:23.176076Z","end":"2026-09-07T08:39:23.317753Z","steps":["trace[1172385203] 'process raft request' (duration: 124.166247ms)","trace[1172385203] 'compare' (duration: 17.345441ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:39:23.317969Z","caller":"traceutil/trace.go:171","msg":"trace[2097063892] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"140.23715ms","start":"2026-09-07T08:39:23.177721Z","end":"2026-09-07T08:39:23.317958Z","steps":["trace[2097063892] 'process raft request' (duration: 139.980204ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:40.673766Z","caller":"traceutil/trace.go:171","msg":"trace[981418204] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"120.389251ms","start":"2026-09-07T08:39:40.553351Z","end":"2026-09-07T08:39:40.673741Z","steps":["trace[981418204] 'process raft request' (duration: 103.76215ms)","trace[981418204] 'compare' (duration: 16.111209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:39:50.345508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.653591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:39:50.345656Z","caller":"traceutil/trace.go:171","msg":"trace[2057967914] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2785; }","duration":"203.123553ms","start":"2026-09-07T08:39:50.142513Z","end":"2026-09-07T08:39:50.345637Z","steps":["trace[2057967914] 'read index received' (duration: 35.118243ms)","trace[2057967914] 'applied index is now lower than readState.Index' (duration: 168.00412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:39:50.345836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.324898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T08:39:50.345885Z","caller":"traceutil/trace.go:171","msg":"trace[1968597272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2683; }","duration":"203.402939ms","start":"2026-09-07T08:39:50.142468Z","end":"2026-09-07T08:39:50.345871Z","steps":["trace[1968597272] 'agreement among raft nodes before linearized reading' (duration: 203.220765ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:50.346003Z","caller":"traceutil/trace.go:171","msg":"trace[1490579776] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"235.081942ms","start":"2026-09-07T08:39:50.110822Z","end":"2026-09-07T08:39:50.345904Z","steps":["trace[1490579776] 'process raft request' (duration: 66.894218ms)","trace[1490579776] 'compare' (duration: 167.217721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:39:50.847845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.507035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T08:39:50.848002Z","caller":"traceutil/trace.go:171","msg":"trace[616868830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2684; }","duration":"253.769791ms","start":"2026-09-07T08:39:50.594208Z","end":"2026-09-07T08:39:50.847977Z","steps":["trace[616868830] 'range keys from in-memory index tree' (duration: 253.384832ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:50.848866Z","caller":"traceutil/trace.go:171","msg":"trace[460858465] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"232.713558ms","start":"2026-09-07T08:39:50.616134Z","end":"2026-09-07T08:39:50.848848Z","steps":["trace[460858465] 'process raft request' (duration: 232.570064ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:39:50.853162Z","caller":"traceutil/trace.go:171","msg":"trace[1582310839] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"112.740313ms","start":"2026-09-07T08:39:50.7404Z","end":"2026-09-07T08:39:50.85314Z","steps":["trace[1582310839] 'process raft request' (duration: 112.530908ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:21.072004Z","caller":"traceutil/trace.go:171","msg":"trace[79988689] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"112.77083ms","start":"2026-09-07T08:44:20.959213Z","end":"2026-09-07T08:44:21.071984Z","steps":["trace[79988689] 'process raft request' (duration: 112.648867ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:21.087993Z","caller":"traceutil/trace.go:171","msg":"trace[746331152] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"119.865246ms","start":"2026-09-07T08:44:20.968103Z","end":"2026-09-07T08:44:21.087968Z","steps":["trace[746331152] 'process raft request' (duration: 119.725852ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:21.352708Z","caller":"traceutil/trace.go:171","msg":"trace[2145857243] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"113.814491ms","start":"2026-09-07T08:44:21.238855Z","end":"2026-09-07T08:44:21.35267Z","steps":["trace[2145857243] 'process raft request' (duration: 79.30272ms)","trace[2145857243] 'compare' (duration: 34.325965ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:44:24.101132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.973292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbd262bb-b32d-4fed-b107-119e295739b8\" ","response":"range_response_count:1 size:3774"} {"level":"warn","ts":"2026-09-07T08:44:24.10124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.493783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T08:44:24.101309Z","caller":"traceutil/trace.go:171","msg":"trace[1478333337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4797; }","duration":"183.599677ms","start":"2026-09-07T08:44:23.917694Z","end":"2026-09-07T08:44:24.101294Z","steps":["trace[1478333337] 'range keys from in-memory index tree' (duration: 183.336339ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:24.101327Z","caller":"traceutil/trace.go:171","msg":"trace[1280813412] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbd262bb-b32d-4fed-b107-119e295739b8; range_end:; response_count:1; response_revision:4797; }","duration":"229.172558ms","start":"2026-09-07T08:44:23.872106Z","end":"2026-09-07T08:44:24.101278Z","steps":["trace[1280813412] 'range keys from in-memory index tree' (duration: 228.837219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:44:24.102118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.118489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-07T08:44:24.102595Z","caller":"traceutil/trace.go:171","msg":"trace[2083600992] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4797; }","duration":"137.243742ms","start":"2026-09-07T08:44:23.964944Z","end":"2026-09-07T08:44:24.102187Z","steps":["trace[2083600992] 'range keys from in-memory index tree' (duration: 135.718948ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:24.135791Z","caller":"traceutil/trace.go:171","msg":"trace[750657405] linearizableReadLoop","detail":"{readStateIndex:4983; appliedIndex:4982; }","duration":"101.751852ms","start":"2026-09-07T08:44:24.034012Z","end":"2026-09-07T08:44:24.135764Z","steps":["trace[750657405] 'read index received' (duration: 101.540226ms)","trace[750657405] 'applied index is now lower than readState.Index' (duration: 210.666µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:44:24.135887Z","caller":"traceutil/trace.go:171","msg":"trace[500536585] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"108.92136ms","start":"2026-09-07T08:44:24.02693Z","end":"2026-09-07T08:44:24.135851Z","steps":["trace[500536585] 'process raft request' (duration: 108.639962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:44:24.136191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.156454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T08:44:24.13624Z","caller":"traceutil/trace.go:171","msg":"trace[1938102158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4798; }","duration":"102.251816ms","start":"2026-09-07T08:44:24.033976Z","end":"2026-09-07T08:44:24.136228Z","steps":["trace[1938102158] 'agreement among raft nodes before linearized reading' (duration: 101.993569ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:28.865925Z","caller":"traceutil/trace.go:171","msg":"trace[226758052] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"106.366629ms","start":"2026-09-07T08:44:28.759537Z","end":"2026-09-07T08:44:28.865904Z","steps":["trace[226758052] 'process raft request' (duration: 65.241124ms)","trace[226758052] 'compare' (duration: 41.014771ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:44:28.896107Z","caller":"traceutil/trace.go:171","msg":"trace[82830125] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"111.699404ms","start":"2026-09-07T08:44:28.78439Z","end":"2026-09-07T08:44:28.896089Z","steps":["trace[82830125] 'process raft request' (duration: 111.668703ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:28.89613Z","caller":"traceutil/trace.go:171","msg":"trace[86098740] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"135.418922ms","start":"2026-09-07T08:44:28.760681Z","end":"2026-09-07T08:44:28.8961Z","steps":["trace[86098740] 'process raft request' (duration: 135.235457ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:28.896247Z","caller":"traceutil/trace.go:171","msg":"trace[709219991] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"133.221869ms","start":"2026-09-07T08:44:28.763014Z","end":"2026-09-07T08:44:28.896236Z","steps":["trace[709219991] 'process raft request' (duration: 133.010123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:44:29.239231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.415656ms","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:5"} {"level":"info","ts":"2026-09-07T08:44:29.239329Z","caller":"traceutil/trace.go:171","msg":"trace[1061548289] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4932; }","duration":"100.57023ms","start":"2026-09-07T08:44:29.138735Z","end":"2026-09-07T08:44:29.239305Z","steps":["trace[1061548289] 'count revisions from in-memory index tree' (duration: 100.337643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:44:43.688939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.863791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5d5ccb69fc-nd6gw\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-09-07T08:44:43.689004Z","caller":"traceutil/trace.go:171","msg":"trace[1372006398] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5d5ccb69fc-nd6gw; range_end:; response_count:1; response_revision:5120; }","duration":"469.964234ms","start":"2026-09-07T08:44:43.219026Z","end":"2026-09-07T08:44:43.68899Z","steps":["trace[1372006398] 'range keys from in-memory index tree' (duration: 469.712937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T08:44:43.689031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:44:43.219011Z","time spent":"470.013585ms","remote":"127.0.0.1:55152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11872,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5d5ccb69fc-nd6gw\" "} {"level":"warn","ts":"2026-09-07T08:44:43.689673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.09899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T08:44:43.689775Z","caller":"traceutil/trace.go:171","msg":"trace[1333341315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5120; }","duration":"174.250154ms","start":"2026-09-07T08:44:43.515509Z","end":"2026-09-07T08:44:43.689759Z","steps":["trace[1333341315] 'range keys from in-memory index tree' (duration: 173.06134ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:43.846348Z","caller":"traceutil/trace.go:171","msg":"trace[1502316428] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"114.35526ms","start":"2026-09-07T08:44:43.731972Z","end":"2026-09-07T08:44:43.846327Z","steps":["trace[1502316428] 'process raft request' (duration: 114.123943ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:43.846742Z","caller":"traceutil/trace.go:171","msg":"trace[289790279] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"105.504812ms","start":"2026-09-07T08:44:43.741158Z","end":"2026-09-07T08:44:43.846662Z","steps":["trace[289790279] 'process raft request' (duration: 105.106559ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:43.846824Z","caller":"traceutil/trace.go:171","msg":"trace[1510211005] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"102.576027ms","start":"2026-09-07T08:44:43.74421Z","end":"2026-09-07T08:44:43.846785Z","steps":["trace[1510211005] 'process raft request' (duration: 102.349411ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:44.105218Z","caller":"traceutil/trace.go:171","msg":"trace[53329843] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5327; }","duration":"137.194125ms","start":"2026-09-07T08:44:43.967995Z","end":"2026-09-07T08:44:44.105189Z","steps":["trace[53329843] 'read index received' (duration: 10.020581ms)","trace[53329843] 'applied index is now lower than readState.Index' (duration: 127.172294ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:44:44.105372Z","caller":"traceutil/trace.go:171","msg":"trace[403846103] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"160.276198ms","start":"2026-09-07T08:44:43.94506Z","end":"2026-09-07T08:44:44.105337Z","steps":["trace[403846103] 'process raft request' (duration: 124.877897ms)","trace[403846103] 'compare' (duration: 35.050231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:44:44.105623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.603226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T08:44:44.105673Z","caller":"traceutil/trace.go:171","msg":"trace[368520315] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5135; }","duration":"137.695659ms","start":"2026-09-07T08:44:43.967963Z","end":"2026-09-07T08:44:44.105658Z","steps":["trace[368520315] 'agreement among raft nodes before linearized reading' (duration: 137.318158ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:44:44.251146Z","caller":"traceutil/trace.go:171","msg":"trace[842080831] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"118.075658ms","start":"2026-09-07T08:44:44.133047Z","end":"2026-09-07T08:44:44.251123Z","steps":["trace[842080831] 'process raft request' (duration: 72.637695ms)","trace[842080831] 'compare' (duration: 45.212237ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T08:45:21.323785Z","caller":"traceutil/trace.go:171","msg":"trace[1683550288] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"117.13049ms","start":"2026-09-07T08:45:21.206629Z","end":"2026-09-07T08:45:21.32376Z","steps":["trace[1683550288] 'process raft request' (duration: 116.984206ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:45:23.621769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-09-07T08:45:23.795802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"171.965247ms","hash":3870425152,"current-db-size-bytes":53395456,"current-db-size":"53 MB","current-db-size-in-use-bytes":28405760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T08:45:23.795952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3870425152,"revision":2856,"compact-revision":-1} {"level":"warn","ts":"2026-09-07T08:45:24.947314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.668032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T08:45:24.947397Z","caller":"traceutil/trace.go:171","msg":"trace[1410421189] linearizableReadLoop","detail":"{readStateIndex:5667; appliedIndex:5666; }","duration":"153.758263ms","start":"2026-09-07T08:45:24.793622Z","end":"2026-09-07T08:45:24.94738Z","steps":["trace[1410421189] 'read index received' (duration: 33.961µs)","trace[1410421189] 'applied index is now lower than readState.Index' (duration: 153.723282ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:45:24.94747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.847857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T08:45:24.94762Z","caller":"traceutil/trace.go:171","msg":"trace[1904959214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5461; }","duration":"154.022881ms","start":"2026-09-07T08:45:24.793587Z","end":"2026-09-07T08:45:24.94761Z","steps":["trace[1904959214] 'agreement among raft nodes before linearized reading' (duration: 153.827535ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:45:24.947801Z","caller":"traceutil/trace.go:171","msg":"trace[1109907452] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"457.357075ms","start":"2026-09-07T08:45:24.490434Z","end":"2026-09-07T08:45:24.947791Z","steps":["trace[1109907452] 'process raft request' (duration: 93.139777ms)","trace[1109907452] 'compare' (duration: 363.369034ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T08:45:24.947871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T08:45:24.490414Z","time spent":"457.409808ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T08:45:25.08047Z","caller":"traceutil/trace.go:171","msg":"trace[82086196] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"196.61892ms","start":"2026-09-07T08:45:24.883826Z","end":"2026-09-07T08:45:25.080445Z","steps":["trace[82086196] 'process raft request' (duration: 189.981524ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:48:24.548713Z","caller":"traceutil/trace.go:171","msg":"trace[1892606565] transaction","detail":"{read_only:false; response_revision:6713; number_of_response:1; }","duration":"147.96003ms","start":"2026-09-07T08:48:24.400729Z","end":"2026-09-07T08:48:24.548689Z","steps":["trace[1892606565] 'process raft request' (duration: 147.827247ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T08:50:23.631431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5454} {"level":"info","ts":"2026-09-07T08:50:23.736463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5454,"took":"103.261372ms","hash":3232717189,"current-db-size-bytes":53395456,"current-db-size":"53 MB","current-db-size-in-use-bytes":18472960,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-07T08:50:23.736568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3232717189,"revision":5454,"compact-revision":2856}