{"level":"warn","ts":"2026-06-08T22:06:58.847303Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T22:06:58.847437Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-06-08T22:06:58.847511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T22:06:58.847525Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-08T22:06:58.847548Z","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-06-08T22:06:58.848126Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-06-08T22:06:58.848285Z","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://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-06-08T22:06:58.852474Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.89881ms"} {"level":"info","ts":"2026-06-08T22:06:58.861197Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-06-08T22:06:58.861311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T22:06:58.86135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-06-08T22:06:58.86138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T22:06:58.861398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-06-08T22:06:58.861457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-06-08T22:06:58.86784Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T22:06:58.869106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T22:06:58.870096Z","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-06-08T22:06:58.872217Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T22:06:58.87247Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T22:06:58.872609Z","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-06-08T22:06:58.872825Z","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-06-08T22:06:58.872915Z","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-06-08T22:06:58.873879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-06-08T22:06:58.874187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-08T22:06:58.876484Z","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-06-08T22:06:58.876594Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-08T22:06:58.876639Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-08T22:06:58.876739Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T22:06:58.876771Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T22:06:59.761892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T22:06:59.761951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T22:06:59.761975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-06-08T22:06:59.761989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-06-08T22:06:59.761996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-08T22:06:59.762006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-06-08T22:06:59.762016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-08T22:06:59.763181Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T22:06:59.763349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:06:59.763383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:06:59.763625Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:06:59.764802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:06:59.764918Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:06:59.764957Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:06:59.766605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-06-08T22:06:59.766809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T22:06:59.766836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T22:06:59.767265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T22:07:01.537612Z","caller":"traceutil/trace.go:171","msg":"trace[56805843] linearizableReadLoop","detail":"{readStateIndex:106; appliedIndex:105; }","duration":"185.749586ms","start":"2026-06-08T22:07:01.351839Z","end":"2026-06-08T22:07:01.537588Z","steps":["trace[56805843] 'read index received' (duration: 177.463552ms)","trace[56805843] 'applied index is now lower than readState.Index' (duration: 8.285214ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:07:01.537672Z","caller":"traceutil/trace.go:171","msg":"trace[1334022098] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"222.256273ms","start":"2026-06-08T22:07:01.315376Z","end":"2026-06-08T22:07:01.537632Z","steps":["trace[1334022098] 'process raft request' (duration: 213.940029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:01.537856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.98577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:07:01.537951Z","caller":"traceutil/trace.go:171","msg":"trace[84708315] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:101; }","duration":"186.138122ms","start":"2026-06-08T22:07:01.351803Z","end":"2026-06-08T22:07:01.537941Z","steps":["trace[84708315] 'agreement among raft nodes before linearized reading' (duration: 185.935439ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:04.295628Z","caller":"traceutil/trace.go:171","msg":"trace[546977772] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"120.343367ms","start":"2026-06-08T22:07:04.175264Z","end":"2026-06-08T22:07:04.295607Z","steps":["trace[546977772] 'read index received' (duration: 120.156914ms)","trace[546977772] 'applied index is now lower than readState.Index' (duration: 185.653µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:07:04.295754Z","caller":"traceutil/trace.go:171","msg":"trace[954419334] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"358.067185ms","start":"2026-06-08T22:07:03.937641Z","end":"2026-06-08T22:07:04.295708Z","steps":["trace[954419334] 'process raft request' (duration: 357.833172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:04.296418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:03.93762Z","time spent":"358.239127ms","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:07:04.296825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.553741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-06-08T22:07:04.296899Z","caller":"traceutil/trace.go:171","msg":"trace[1156650574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:255; }","duration":"121.686032ms","start":"2026-06-08T22:07:04.175195Z","end":"2026-06-08T22:07:04.296881Z","steps":["trace[1156650574] 'agreement among raft nodes before linearized reading' (duration: 120.6271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:05.043744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.034642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:07:05.043862Z","caller":"traceutil/trace.go:171","msg":"trace[299085465] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"518.413496ms","start":"2026-06-08T22:07:04.525432Z","end":"2026-06-08T22:07:05.043845Z","steps":["trace[299085465] 'read index received' (duration: 236.824628ms)","trace[299085465] 'applied index is now lower than readState.Index' (duration: 281.587468ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:07:05.04391Z","caller":"traceutil/trace.go:171","msg":"trace[354654471] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"740.149571ms","start":"2026-06-08T22:07:04.303726Z","end":"2026-06-08T22:07:05.043876Z","steps":["trace[354654471] 'process raft request' (duration: 458.516383ms)","trace[354654471] 'compare' (duration: 280.90149ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:07:05.043966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.528507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:07:05.044036Z","caller":"traceutil/trace.go:171","msg":"trace[352195888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:256; }","duration":"518.604798ms","start":"2026-06-08T22:07:04.525413Z","end":"2026-06-08T22:07:05.044018Z","steps":["trace[352195888] 'agreement among raft nodes before linearized reading' (duration: 518.489856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:05.04408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:04.525337Z","time spent":"518.732449ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-08T22:07:05.044184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:04.303703Z","time spent":"740.295302ms","remote":"127.0.0.1:54254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:07:05.2883Z","caller":"traceutil/trace.go:171","msg":"trace[176256170] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"239.792014ms","start":"2026-06-08T22:07:05.048436Z","end":"2026-06-08T22:07:05.288228Z","steps":["trace[176256170] 'process raft request' (duration: 234.028406ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:05.288414Z","caller":"traceutil/trace.go:171","msg":"trace[1353943624] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:262; }","duration":"148.112627ms","start":"2026-06-08T22:07:05.140279Z","end":"2026-06-08T22:07:05.288392Z","steps":["trace[1353943624] 'read index received' (duration: 142.181227ms)","trace[1353943624] 'applied index is now lower than readState.Index' (duration: 5.93049ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:07:05.288522Z","caller":"traceutil/trace.go:171","msg":"trace[1291859181] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"238.042514ms","start":"2026-06-08T22:07:05.05045Z","end":"2026-06-08T22:07:05.288493Z","steps":["trace[1291859181] 'process raft request' (duration: 237.843831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:05.288536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.246319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-08T22:07:05.289067Z","caller":"traceutil/trace.go:171","msg":"trace[642906588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:258; }","duration":"148.808196ms","start":"2026-06-08T22:07:05.140236Z","end":"2026-06-08T22:07:05.289044Z","steps":["trace[642906588] 'agreement among raft nodes before linearized reading' (duration: 148.224348ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:37.953356Z","caller":"traceutil/trace.go:171","msg":"trace[506804512] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"303.736346ms","start":"2026-06-08T22:07:37.649597Z","end":"2026-06-08T22:07:37.953333Z","steps":["trace[506804512] 'process raft request' (duration: 303.595535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:37.953527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:37.649575Z","time spent":"303.873627ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:07:38.601314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.48576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:07:38.601435Z","caller":"traceutil/trace.go:171","msg":"trace[12550895] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"119.316526ms","start":"2026-06-08T22:07:38.482101Z","end":"2026-06-08T22:07:38.601418Z","steps":["trace[12550895] 'read index received' (duration: 16.745726ms)","trace[12550895] 'applied index is now lower than readState.Index' (duration: 102.5694ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:07:38.601595Z","caller":"traceutil/trace.go:171","msg":"trace[1389819503] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"129.468054ms","start":"2026-06-08T22:07:38.472089Z","end":"2026-06-08T22:07:38.601557Z","steps":["trace[1389819503] 'process raft request' (duration: 26.681322ms)","trace[1389819503] 'compare' (duration: 102.355879ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:07:38.601685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.568138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:07:38.6018Z","caller":"traceutil/trace.go:171","msg":"trace[1896766688] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:516; }","duration":"119.7352ms","start":"2026-06-08T22:07:38.482048Z","end":"2026-06-08T22:07:38.601784Z","steps":["trace[1896766688] 'agreement among raft nodes before linearized reading' (duration: 119.416978ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:41.25254Z","caller":"traceutil/trace.go:171","msg":"trace[306811680] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"158.866535ms","start":"2026-06-08T22:07:41.093653Z","end":"2026-06-08T22:07:41.25252Z","steps":["trace[306811680] 'process raft request' (duration: 158.697443ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:41.255456Z","caller":"traceutil/trace.go:171","msg":"trace[1377230020] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"161.68611ms","start":"2026-06-08T22:07:41.093743Z","end":"2026-06-08T22:07:41.255429Z","steps":["trace[1377230020] 'process raft request' (duration: 161.477078ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:43.492125Z","caller":"traceutil/trace.go:171","msg":"trace[551958382] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"559.658984ms","start":"2026-06-08T22:07:42.932439Z","end":"2026-06-08T22:07:43.492098Z","steps":["trace[551958382] 'process raft request' (duration: 557.123343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:43.492268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:42.932394Z","time spent":"559.797625ms","remote":"127.0.0.1:54102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:07:48.151987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.462354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x29kf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-08T22:07:48.152065Z","caller":"traceutil/trace.go:171","msg":"trace[46664669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x29kf; range_end:; response_count:1; response_revision:653; }","duration":"197.602976ms","start":"2026-06-08T22:07:47.954445Z","end":"2026-06-08T22:07:48.152048Z","steps":["trace[46664669] 'range keys from in-memory index tree' (duration: 197.277123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:48.152219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.098371ms","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-06-08T22:07:48.15267Z","caller":"traceutil/trace.go:171","msg":"trace[1203348154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"124.628476ms","start":"2026-06-08T22:07:48.028015Z","end":"2026-06-08T22:07:48.152644Z","steps":["trace[1203348154] 'range keys from in-memory index tree' (duration: 123.749587ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:48.690106Z","caller":"traceutil/trace.go:171","msg":"trace[1834331674] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"540.715794ms","start":"2026-06-08T22:07:48.149358Z","end":"2026-06-08T22:07:48.690074Z","steps":["trace[1834331674] 'process raft request' (duration: 538.98154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:48.697989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.149292Z","time spent":"540.951457ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:07:48.721933Z","caller":"traceutil/trace.go:171","msg":"trace[137843913] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"564.43753ms","start":"2026-06-08T22:07:48.157435Z","end":"2026-06-08T22:07:48.721872Z","steps":["trace[137843913] 'process raft request' (duration: 563.802585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:48.722401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.157415Z","time spent":"564.849913ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:07:48.722439Z","caller":"traceutil/trace.go:171","msg":"trace[1243306077] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"556.824927ms","start":"2026-06-08T22:07:48.165571Z","end":"2026-06-08T22:07:48.722395Z","steps":["trace[1243306077] 'process raft request' (duration: 555.89598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:48.722635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.165561Z","time spent":"556.963668ms","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:07:48.78313Z","caller":"traceutil/trace.go:171","msg":"trace[549992802] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:675; }","duration":"446.647743ms","start":"2026-06-08T22:07:48.336401Z","end":"2026-06-08T22:07:48.783049Z","steps":["trace[549992802] 'read index received' (duration: 351.361299ms)","trace[549992802] 'applied index is now lower than readState.Index' (duration: 95.284453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:07:48.78329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.863614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:07:48.783332Z","caller":"traceutil/trace.go:171","msg":"trace[207823755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"446.982375ms","start":"2026-06-08T22:07:48.336338Z","end":"2026-06-08T22:07:48.783321Z","steps":["trace[207823755] 'agreement among raft nodes before linearized reading' (duration: 446.878354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:48.783368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.336312Z","time spent":"447.048435ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-08T22:07:49.220563Z","caller":"traceutil/trace.go:171","msg":"trace[1502480101] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"435.786704ms","start":"2026-06-08T22:07:48.784745Z","end":"2026-06-08T22:07:49.220531Z","steps":["trace[1502480101] 'read index received' (duration: 435.529252ms)","trace[1502480101] 'applied index is now lower than readState.Index' (duration: 256.132µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:07:49.220722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.955055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:07:49.220762Z","caller":"traceutil/trace.go:171","msg":"trace[2073772172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"436.075656ms","start":"2026-06-08T22:07:48.784675Z","end":"2026-06-08T22:07:49.220751Z","steps":["trace[2073772172] 'agreement among raft nodes before linearized reading' (duration: 435.963326ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:49.220688Z","caller":"traceutil/trace.go:171","msg":"trace[879075272] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"460.730469ms","start":"2026-06-08T22:07:48.759874Z","end":"2026-06-08T22:07:49.220605Z","steps":["trace[879075272] 'process raft request' (duration: 460.479127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:07:49.2208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.784645Z","time spent":"436.146727ms","remote":"127.0.0.1:54056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:07:49.220991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:07:48.759855Z","time spent":"460.966061ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:07:49.545825Z","caller":"traceutil/trace.go:171","msg":"trace[628443954] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"289.061355ms","start":"2026-06-08T22:07:49.256731Z","end":"2026-06-08T22:07:49.545792Z","steps":["trace[628443954] 'process raft request' (duration: 288.715522ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:49.5459Z","caller":"traceutil/trace.go:171","msg":"trace[1219388088] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"290.863879ms","start":"2026-06-08T22:07:49.255023Z","end":"2026-06-08T22:07:49.545887Z","steps":["trace[1219388088] 'process raft request' (duration: 290.304035ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:49.545825Z","caller":"traceutil/trace.go:171","msg":"trace[1064793676] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"288.838493ms","start":"2026-06-08T22:07:49.256942Z","end":"2026-06-08T22:07:49.545781Z","steps":["trace[1064793676] 'process raft request' (duration: 288.581381ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:49.545819Z","caller":"traceutil/trace.go:171","msg":"trace[915829459] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"290.931291ms","start":"2026-06-08T22:07:49.254852Z","end":"2026-06-08T22:07:49.545784Z","steps":["trace[915829459] 'process raft request' (duration: 285.674958ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:07:51.3004Z","caller":"traceutil/trace.go:171","msg":"trace[388928712] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"146.966391ms","start":"2026-06-08T22:07:51.153393Z","end":"2026-06-08T22:07:51.30036Z","steps":["trace[388928712] 'process raft request' (duration: 146.783019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:04.465455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.968227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:08:04.46557Z","caller":"traceutil/trace.go:171","msg":"trace[2103481269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"131.111484ms","start":"2026-06-08T22:08:04.334434Z","end":"2026-06-08T22:08:04.465545Z","steps":["trace[2103481269] 'range keys from in-memory index tree' (duration: 130.821171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:04.465549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.727518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c8p9h\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-08T22:08:04.465701Z","caller":"traceutil/trace.go:171","msg":"trace[1574910436] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c8p9h; range_end:; response_count:1; response_revision:818; }","duration":"405.940117ms","start":"2026-06-08T22:08:04.059719Z","end":"2026-06-08T22:08:04.465659Z","steps":["trace[1574910436] 'range keys from in-memory index tree' (duration: 405.453035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:04.46576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:08:04.059692Z","time spent":"406.057923ms","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-c8p9h\" "} {"level":"warn","ts":"2026-06-08T22:08:04.465762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.307597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T22:08:04.465878Z","caller":"traceutil/trace.go:171","msg":"trace[1468440015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"702.450413ms","start":"2026-06-08T22:08:03.763399Z","end":"2026-06-08T22:08:04.465849Z","steps":["trace[1468440015] 'range keys from in-memory index tree' (duration: 702.048535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:04.465935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:08:03.763326Z","time spent":"702.598319ms","remote":"127.0.0.1:54348","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-06-08T22:08:04.465577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.112591ms","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-06-08T22:08:04.466054Z","caller":"traceutil/trace.go:171","msg":"trace[1803921231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"673.622444ms","start":"2026-06-08T22:08:03.792382Z","end":"2026-06-08T22:08:04.466004Z","steps":["trace[1803921231] 'range keys from in-memory index tree' (duration: 672.868431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:04.466404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:08:03.792361Z","time spent":"673.735459ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T22:08:35.356086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.843574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T22:08:35.356181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.889069ms","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-06-08T22:08:35.356233Z","caller":"traceutil/trace.go:171","msg":"trace[416658104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"214.004112ms","start":"2026-06-08T22:08:35.142215Z","end":"2026-06-08T22:08:35.356219Z","steps":["trace[416658104] 'range keys from in-memory index tree' (duration: 213.740104ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:35.356197Z","caller":"traceutil/trace.go:171","msg":"trace[1493321278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"251.139703ms","start":"2026-06-08T22:08:35.105034Z","end":"2026-06-08T22:08:35.356173Z","steps":["trace[1493321278] 'range keys from in-memory index tree' (duration: 250.614517ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:53.793805Z","caller":"traceutil/trace.go:171","msg":"trace[1341863411] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"255.506614ms","start":"2026-06-08T22:08:53.538261Z","end":"2026-06-08T22:08:53.793768Z","steps":["trace[1341863411] 'read index received' (duration: 255.277118ms)","trace[1341863411] 'applied index is now lower than readState.Index' (duration: 227.726µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:08:53.79392Z","caller":"traceutil/trace.go:171","msg":"trace[1747220723] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"258.699899ms","start":"2026-06-08T22:08:53.535178Z","end":"2026-06-08T22:08:53.793878Z","steps":["trace[1747220723] 'process raft request' (duration: 258.37235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:08:53.794138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.833842ms","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-06-08T22:08:53.794193Z","caller":"traceutil/trace.go:171","msg":"trace[360447992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"255.949196ms","start":"2026-06-08T22:08:53.53823Z","end":"2026-06-08T22:08:53.794179Z","steps":["trace[360447992] 'agreement among raft nodes before linearized reading' (duration: 255.699749ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:57.296497Z","caller":"traceutil/trace.go:171","msg":"trace[1117630169] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"140.564228ms","start":"2026-06-08T22:08:57.155904Z","end":"2026-06-08T22:08:57.296469Z","steps":["trace[1117630169] 'process raft request' (duration: 140.518967ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:57.296598Z","caller":"traceutil/trace.go:171","msg":"trace[1781295088] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"163.226461ms","start":"2026-06-08T22:08:57.133335Z","end":"2026-06-08T22:08:57.296561Z","steps":["trace[1781295088] 'process raft request' (duration: 123.884351ms)","trace[1781295088] 'compare' (duration: 38.97031ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:08:57.297144Z","caller":"traceutil/trace.go:171","msg":"trace[1046137540] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"141.291958ms","start":"2026-06-08T22:08:57.155258Z","end":"2026-06-08T22:08:57.29655Z","steps":["trace[1046137540] 'process raft request' (duration: 141.113923ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:57.497876Z","caller":"traceutil/trace.go:171","msg":"trace[1566433818] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"112.085598ms","start":"2026-06-08T22:08:57.385751Z","end":"2026-06-08T22:08:57.497837Z","steps":["trace[1566433818] 'process raft request' (duration: 112.040146ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:08:57.497898Z","caller":"traceutil/trace.go:171","msg":"trace[355749335] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"113.263238ms","start":"2026-06-08T22:08:57.384608Z","end":"2026-06-08T22:08:57.497871Z","steps":["trace[355749335] 'process raft request' (duration: 113.108623ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:13.320539Z","caller":"traceutil/trace.go:171","msg":"trace[457256126] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"330.832567ms","start":"2026-06-08T22:09:12.989676Z","end":"2026-06-08T22:09:13.320509Z","steps":["trace[457256126] 'process raft request' (duration: 330.49536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:13.320761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:12.989649Z","time spent":"330.983521ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:13.321659Z","caller":"traceutil/trace.go:171","msg":"trace[2123877137] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"295.625652ms","start":"2026-06-08T22:09:13.026012Z","end":"2026-06-08T22:09:13.321638Z","steps":["trace[2123877137] 'process raft request' (duration: 295.053889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:26.836219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496598979230756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T22:09:27.246187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06799362s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T22:09:27.246952Z","caller":"traceutil/trace.go:171","msg":"trace[648150859] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"1.068898737s","start":"2026-06-08T22:09:26.178023Z","end":"2026-06-08T22:09:27.246921Z","steps":["trace[648150859] 'process raft request' (duration: 1.068681933s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.247133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.177993Z","time spent":"1.069029079s","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:27.47147Z","caller":"traceutil/trace.go:171","msg":"trace[1378151679] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"753.750727ms","start":"2026-06-08T22:09:26.717686Z","end":"2026-06-08T22:09:27.471436Z","steps":["trace[1378151679] 'process raft request' (duration: 723.9181ms)","trace[1378151679] 'compare' (duration: 29.415369ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:27.471513Z","caller":"traceutil/trace.go:171","msg":"trace[1547575331] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"423.70474ms","start":"2026-06-08T22:09:27.047784Z","end":"2026-06-08T22:09:27.471488Z","steps":["trace[1547575331] 'process raft request' (duration: 423.596568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.471651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.717657Z","time spent":"753.89371ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:09:27.471704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.047755Z","time spent":"423.827052ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:09:27.471722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136351675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:09:27.472916Z","caller":"traceutil/trace.go:171","msg":"trace[1924492210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"1.137636272s","start":"2026-06-08T22:09:26.335259Z","end":"2026-06-08T22:09:27.472895Z","steps":["trace[1924492210] 'agreement among raft nodes before linearized reading' (duration: 1.136324405s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.472966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.335222Z","time spent":"1.137730143s","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-08T22:09:27.471483Z","caller":"traceutil/trace.go:171","msg":"trace[595566863] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1991; }","duration":"1.13613263s","start":"2026-06-08T22:09:26.335318Z","end":"2026-06-08T22:09:27.471451Z","steps":["trace[595566863] 'read index received' (duration: 911.631588ms)","trace[595566863] 'applied index is now lower than readState.Index' (duration: 224.499562ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:27.472219Z","caller":"traceutil/trace.go:171","msg":"trace[403929456] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"699.061317ms","start":"2026-06-08T22:09:26.772406Z","end":"2026-06-08T22:09:27.471467Z","steps":["trace[403929456] 'process raft request' (duration: 698.913415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.473323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.77238Z","time spent":"700.790692ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:27.84195Z","caller":"traceutil/trace.go:171","msg":"trace[76395545] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"324.894692ms","start":"2026-06-08T22:09:27.517035Z","end":"2026-06-08T22:09:27.84193Z","steps":["trace[76395545] 'process raft request' (duration: 324.847751ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:27.841997Z","caller":"traceutil/trace.go:171","msg":"trace[881207197] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"588.033849ms","start":"2026-06-08T22:09:27.253912Z","end":"2026-06-08T22:09:27.841946Z","steps":["trace[881207197] 'process raft request' (duration: 552.526898ms)","trace[881207197] 'compare' (duration: 34.713165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:09:27.842126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.018098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-08T22:09:27.842179Z","caller":"traceutil/trace.go:171","msg":"trace[938676362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1923; }","duration":"772.10447ms","start":"2026-06-08T22:09:27.070059Z","end":"2026-06-08T22:09:27.842164Z","steps":["trace[938676362] 'agreement among raft nodes before linearized reading' (duration: 771.877165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.84222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.070044Z","time spent":"772.16289ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T22:09:27.842602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.357175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:09:27.842693Z","caller":"traceutil/trace.go:171","msg":"trace[60669200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1923; }","duration":"523.478347ms","start":"2026-06-08T22:09:27.3192Z","end":"2026-06-08T22:09:27.842678Z","steps":["trace[60669200] 'agreement among raft nodes before linearized reading' (duration: 523.267103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.843008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.319186Z","time spent":"523.809234ms","remote":"127.0.0.1:55348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:09:27.843014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.517016Z","time spent":"324.998584ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:09:27.84314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.253892Z","time spent":"588.226883ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:27.843115Z","caller":"traceutil/trace.go:171","msg":"trace[103813058] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"368.884695ms","start":"2026-06-08T22:09:27.473049Z","end":"2026-06-08T22:09:27.841934Z","steps":["trace[103813058] 'read index received' (duration: 333.403735ms)","trace[103813058] 'applied index is now lower than readState.Index' (duration: 35.47751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:09:27.843253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.582767ms","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":"warn","ts":"2026-06-08T22:09:27.843277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.761986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-08T22:09:27.843345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.635882ms","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-06-08T22:09:27.843369Z","caller":"traceutil/trace.go:171","msg":"trace[1983807524] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1923; }","duration":"959.715659ms","start":"2026-06-08T22:09:26.883612Z","end":"2026-06-08T22:09:27.843327Z","steps":["trace[1983807524] 'agreement among raft nodes before linearized reading' (duration: 959.467934ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:27.843374Z","caller":"traceutil/trace.go:171","msg":"trace[865435713] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100; range_end:; response_count:1; response_revision:1923; }","duration":"970.931809ms","start":"2026-06-08T22:09:26.872417Z","end":"2026-06-08T22:09:27.843349Z","steps":["trace[865435713] 'agreement among raft nodes before linearized reading' (duration: 970.606513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.843422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.883602Z","time spent":"959.806551ms","remote":"127.0.0.1:47136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-08T22:09:27.843433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.872395Z","time spent":"971.028161ms","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100\" "} {"level":"info","ts":"2026-06-08T22:09:27.843398Z","caller":"traceutil/trace.go:171","msg":"trace[1729554906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1923; }","duration":"713.727433ms","start":"2026-06-08T22:09:27.129659Z","end":"2026-06-08T22:09:27.843387Z","steps":["trace[1729554906] 'agreement among raft nodes before linearized reading' (duration: 713.539099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.843674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.129638Z","time spent":"713.996329ms","remote":"127.0.0.1:54348","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":"warn","ts":"2026-06-08T22:09:27.843755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.803184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:09:27.843735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336870476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-08T22:09:27.843844Z","caller":"traceutil/trace.go:171","msg":"trace[1801039546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1923; }","duration":"367.950177ms","start":"2026-06-08T22:09:27.475874Z","end":"2026-06-08T22:09:27.843824Z","steps":["trace[1801039546] 'agreement among raft nodes before linearized reading' (duration: 367.774853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.843853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.410815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-08T22:09:27.8439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:27.475848Z","time spent":"368.044258ms","remote":"127.0.0.1:54056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-08T22:09:27.8439Z","caller":"traceutil/trace.go:171","msg":"trace[358287318] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1923; }","duration":"209.458046ms","start":"2026-06-08T22:09:27.634431Z","end":"2026-06-08T22:09:27.843889Z","steps":["trace[358287318] 'agreement among raft nodes before linearized reading' (duration: 209.351064ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:27.843851Z","caller":"traceutil/trace.go:171","msg":"trace[2019721079] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100; range_end:; response_count:1; response_revision:1923; }","duration":"1.337125261s","start":"2026-06-08T22:09:26.506711Z","end":"2026-06-08T22:09:27.843836Z","steps":["trace[2019721079] 'agreement among raft nodes before linearized reading' (duration: 1.336603011s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.844696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.506688Z","time spent":"1.337945897s","remote":"127.0.0.1:54258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ee63b32-61ac-4b3c-8823-f15c8676b100\" "} {"level":"warn","ts":"2026-06-08T22:09:27.843812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203813653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-08T22:09:27.844832Z","caller":"traceutil/trace.go:171","msg":"trace[674218322] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1923; }","duration":"1.204861834s","start":"2026-06-08T22:09:26.63994Z","end":"2026-06-08T22:09:27.844802Z","steps":["trace[674218322] 'agreement among raft nodes before linearized reading' (duration: 1.203356184s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:27.844893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:26.639902Z","time spent":"1.204969347s","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-08T22:09:39.694257Z","caller":"traceutil/trace.go:171","msg":"trace[190539895] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"183.137732ms","start":"2026-06-08T22:09:39.510917Z","end":"2026-06-08T22:09:39.694055Z","steps":["trace[190539895] 'process raft request' (duration: 182.949559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:39.970204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.28903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:09:39.970442Z","caller":"traceutil/trace.go:171","msg":"trace[2089838486] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"428.310031ms","start":"2026-06-08T22:09:39.542059Z","end":"2026-06-08T22:09:39.970369Z","steps":["trace[2089838486] 'process raft request' (duration: 289.744636ms)","trace[2089838486] 'compare' (duration: 138.159537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:09:39.970843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:39.542045Z","time spent":"428.580786ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:40.526994Z","caller":"traceutil/trace.go:171","msg":"trace[453769470] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2102; }","duration":"463.781804ms","start":"2026-06-08T22:09:40.063189Z","end":"2026-06-08T22:09:40.526971Z","steps":["trace[453769470] 'read index received' (duration: 3.487795ms)","trace[453769470] 'applied index is now lower than readState.Index' (duration: 460.292589ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:40.527198Z","caller":"traceutil/trace.go:171","msg":"trace[1059963013] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"539.294757ms","start":"2026-06-08T22:09:39.987848Z","end":"2026-06-08T22:09:40.527142Z","steps":["trace[1059963013] 'process raft request' (duration: 538.938659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:40.527333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.129191ms","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-06-08T22:09:40.527379Z","caller":"traceutil/trace.go:171","msg":"trace[1739903647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2028; }","duration":"464.212672ms","start":"2026-06-08T22:09:40.063151Z","end":"2026-06-08T22:09:40.527364Z","steps":["trace[1739903647] 'agreement among raft nodes before linearized reading' (duration: 463.994118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:40.52743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:40.063138Z","time spent":"464.280203ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T22:09:40.527548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.078068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-08T22:09:40.527561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.825267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:09:40.527584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:39.987816Z","time spent":"539.502121ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:09:40.527656Z","caller":"traceutil/trace.go:171","msg":"trace[1290415610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2028; }","duration":"359.265811ms","start":"2026-06-08T22:09:40.168368Z","end":"2026-06-08T22:09:40.527633Z","steps":["trace[1290415610] 'agreement among raft nodes before linearized reading' (duration: 358.926735ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:40.527671Z","caller":"traceutil/trace.go:171","msg":"trace[1281194112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2028; }","duration":"226.97531ms","start":"2026-06-08T22:09:40.300672Z","end":"2026-06-08T22:09:40.527647Z","steps":["trace[1281194112] 'agreement among raft nodes before linearized reading' (duration: 226.828158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:40.52768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.163258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:09:40.527708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:09:40.168338Z","time spent":"359.358513ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-08T22:09:40.52774Z","caller":"traceutil/trace.go:171","msg":"trace[691339097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2028; }","duration":"193.439804ms","start":"2026-06-08T22:09:40.334284Z","end":"2026-06-08T22:09:40.527724Z","steps":["trace[691339097] 'agreement among raft nodes before linearized reading' (duration: 193.039126ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:41.459729Z","caller":"traceutil/trace.go:171","msg":"trace[589031738] linearizableReadLoop","detail":"{readStateIndex:2107; appliedIndex:2106; }","duration":"145.170015ms","start":"2026-06-08T22:09:41.31453Z","end":"2026-06-08T22:09:41.4597Z","steps":["trace[589031738] 'read index received' (duration: 144.990742ms)","trace[589031738] 'applied index is now lower than readState.Index' (duration: 178.033µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:41.45983Z","caller":"traceutil/trace.go:171","msg":"trace[754593081] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"275.497316ms","start":"2026-06-08T22:09:41.184302Z","end":"2026-06-08T22:09:41.4598Z","steps":["trace[754593081] 'process raft request' (duration: 275.201079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:41.46Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.568617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:09:41.460043Z","caller":"traceutil/trace.go:171","msg":"trace[1117018054] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2031; }","duration":"143.636958ms","start":"2026-06-08T22:09:41.316392Z","end":"2026-06-08T22:09:41.460029Z","steps":["trace[1117018054] 'agreement among raft nodes before linearized reading' (duration: 143.536436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:41.460322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.725677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:09:41.460391Z","caller":"traceutil/trace.go:171","msg":"trace[979987459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2031; }","duration":"124.842688ms","start":"2026-06-08T22:09:41.335529Z","end":"2026-06-08T22:09:41.460372Z","steps":["trace[979987459] 'agreement among raft nodes before linearized reading' (duration: 124.556583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:41.46003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.477991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-08T22:09:41.460749Z","caller":"traceutil/trace.go:171","msg":"trace[286352465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2031; }","duration":"146.222125ms","start":"2026-06-08T22:09:41.314512Z","end":"2026-06-08T22:09:41.460734Z","steps":["trace[286352465] 'agreement among raft nodes before linearized reading' (duration: 145.327638ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:41.66344Z","caller":"traceutil/trace.go:171","msg":"trace[1971343199] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"195.961459ms","start":"2026-06-08T22:09:41.467448Z","end":"2026-06-08T22:09:41.66341Z","steps":["trace[1971343199] 'process raft request' (duration: 119.341707ms)","trace[1971343199] 'compare' (duration: 76.363987ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:41.723398Z","caller":"traceutil/trace.go:171","msg":"trace[2021992409] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"255.291532ms","start":"2026-06-08T22:09:41.468059Z","end":"2026-06-08T22:09:41.723351Z","steps":["trace[2021992409] 'process raft request' (duration: 254.867943ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:41.723647Z","caller":"traceutil/trace.go:171","msg":"trace[149636096] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"252.977968ms","start":"2026-06-08T22:09:41.470507Z","end":"2026-06-08T22:09:41.723485Z","steps":["trace[149636096] 'process raft request' (duration: 252.598661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:09:41.723883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.719774ms","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-06-08T22:09:41.723949Z","caller":"traceutil/trace.go:171","msg":"trace[296983405] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2034; }","duration":"111.862877ms","start":"2026-06-08T22:09:41.612067Z","end":"2026-06-08T22:09:41.72393Z","steps":["trace[296983405] 'agreement among raft nodes before linearized reading' (duration: 111.699764ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:41.724028Z","caller":"traceutil/trace.go:171","msg":"trace[555972046] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2108; }","duration":"111.322447ms","start":"2026-06-08T22:09:41.612116Z","end":"2026-06-08T22:09:41.723438Z","steps":["trace[555972046] 'read index received' (duration: 110.734156ms)","trace[555972046] 'applied index is now lower than readState.Index' (duration: 587.281µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:41.988901Z","caller":"traceutil/trace.go:171","msg":"trace[1436633014] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"288.767682ms","start":"2026-06-08T22:09:41.700096Z","end":"2026-06-08T22:09:41.988864Z","steps":["trace[1436633014] 'process raft request' (duration: 243.453671ms)","trace[1436633014] 'compare' (duration: 44.908554ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:09:41.989791Z","caller":"traceutil/trace.go:171","msg":"trace[866176245] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"257.677884ms","start":"2026-06-08T22:09:41.732082Z","end":"2026-06-08T22:09:41.98976Z","steps":["trace[866176245] 'process raft request' (duration: 257.065522ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:09:45.937712Z","caller":"traceutil/trace.go:171","msg":"trace[704260906] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"257.52619ms","start":"2026-06-08T22:09:45.680159Z","end":"2026-06-08T22:09:45.937685Z","steps":["trace[704260906] 'process raft request' (duration: 257.351307ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:02.382521Z","caller":"traceutil/trace.go:171","msg":"trace[944441683] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"230.952676ms","start":"2026-06-08T22:10:02.151541Z","end":"2026-06-08T22:10:02.382494Z","steps":["trace[944441683] 'process raft request' (duration: 226.895739ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:02.390436Z","caller":"traceutil/trace.go:171","msg":"trace[34393943] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2237; }","duration":"206.083934ms","start":"2026-06-08T22:10:02.184323Z","end":"2026-06-08T22:10:02.390407Z","steps":["trace[34393943] 'read index received' (duration: 194.233868ms)","trace[34393943] 'applied index is now lower than readState.Index' (duration: 11.848916ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:10:02.390562Z","caller":"traceutil/trace.go:171","msg":"trace[1130016837] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"231.112378ms","start":"2026-06-08T22:10:02.159372Z","end":"2026-06-08T22:10:02.390485Z","steps":["trace[1130016837] 'process raft request' (duration: 230.832244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:02.39068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.262765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-08T22:10:02.390735Z","caller":"traceutil/trace.go:171","msg":"trace[1138899085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2161; }","duration":"198.369927ms","start":"2026-06-08T22:10:02.192355Z","end":"2026-06-08T22:10:02.390725Z","steps":["trace[1138899085] 'agreement among raft nodes before linearized reading' (duration: 198.218785ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:02.390507Z","caller":"traceutil/trace.go:171","msg":"trace[1870899827] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"229.421581ms","start":"2026-06-08T22:10:02.161052Z","end":"2026-06-08T22:10:02.390474Z","steps":["trace[1870899827] 'process raft request' (duration: 229.31862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:02.390643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.305038ms","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-06-08T22:10:02.390971Z","caller":"traceutil/trace.go:171","msg":"trace[1859117473] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"206.684524ms","start":"2026-06-08T22:10:02.184269Z","end":"2026-06-08T22:10:02.390954Z","steps":["trace[1859117473] 'agreement among raft nodes before linearized reading' (duration: 206.250597ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:02.390979Z","caller":"traceutil/trace.go:171","msg":"trace[698961204] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"230.133913ms","start":"2026-06-08T22:10:02.160295Z","end":"2026-06-08T22:10:02.390429Z","steps":["trace[698961204] 'process raft request' (duration: 230.037951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:08.470734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.879565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:10:08.470836Z","caller":"traceutil/trace.go:171","msg":"trace[1676247269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"137.044607ms","start":"2026-06-08T22:10:08.333774Z","end":"2026-06-08T22:10:08.470819Z","steps":["trace[1676247269] 'range keys from in-memory index tree' (duration: 136.731193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.300597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496598979231383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T22:10:09.367599Z","caller":"traceutil/trace.go:171","msg":"trace[130812477] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"567.436154ms","start":"2026-06-08T22:10:08.800131Z","end":"2026-06-08T22:10:09.367567Z","steps":["trace[130812477] 'read index received' (duration: 567.17874ms)","trace[130812477] 'applied index is now lower than readState.Index' (duration: 256.174µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:10:09.367709Z","caller":"traceutil/trace.go:171","msg":"trace[1215608101] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"628.333876ms","start":"2026-06-08T22:10:08.739349Z","end":"2026-06-08T22:10:09.367683Z","steps":["trace[1215608101] 'process raft request' (duration: 628.045192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.367931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:08.739332Z","time spent":"628.488018ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:10:09.367953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.179754ms","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-06-08T22:10:09.368001Z","caller":"traceutil/trace.go:171","msg":"trace[1304878649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2194; }","duration":"566.250935ms","start":"2026-06-08T22:10:08.801735Z","end":"2026-06-08T22:10:09.367986Z","steps":["trace[1304878649] 'agreement among raft nodes before linearized reading' (duration: 565.976671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.368041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:08.801726Z","time spent":"566.303876ms","remote":"127.0.0.1:54348","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-06-08T22:10:09.367996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.265254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:10:09.368106Z","caller":"traceutil/trace.go:171","msg":"trace[1455558720] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2194; }","duration":"111.436207ms","start":"2026-06-08T22:10:09.256652Z","end":"2026-06-08T22:10:09.368088Z","steps":["trace[1455558720] 'agreement among raft nodes before linearized reading' (duration: 111.199344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.368223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.018563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b73a962247d364\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-08T22:10:09.368288Z","caller":"traceutil/trace.go:171","msg":"trace[1866915312] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b73a962247d364; range_end:; response_count:1; response_revision:2194; }","duration":"229.100004ms","start":"2026-06-08T22:10:09.139162Z","end":"2026-06-08T22:10:09.368262Z","steps":["trace[1866915312] 'agreement among raft nodes before linearized reading' (duration: 228.971052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.369285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.11447ms","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-06-08T22:10:09.369349Z","caller":"traceutil/trace.go:171","msg":"trace[408515176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2194; }","duration":"569.237143ms","start":"2026-06-08T22:10:08.80009Z","end":"2026-06-08T22:10:09.369327Z","steps":["trace[408515176] 'agreement among raft nodes before linearized reading' (duration: 567.654338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.369384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:08.80007Z","time spent":"569.304544ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T22:10:09.778126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.51388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:10:09.778252Z","caller":"traceutil/trace.go:171","msg":"trace[1037087166] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"406.164263ms","start":"2026-06-08T22:10:09.372068Z","end":"2026-06-08T22:10:09.778232Z","steps":["trace[1037087166] 'process raft request' (duration: 201.45599ms)","trace[1037087166] 'compare' (duration: 204.351558ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:10:09.778326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:09.372041Z","time spent":"406.243925ms","remote":"127.0.0.1:54146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:10:09.811966Z","caller":"traceutil/trace.go:171","msg":"trace[898356988] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"438.275918ms","start":"2026-06-08T22:10:09.373664Z","end":"2026-06-08T22:10:09.81194Z","steps":["trace[898356988] 'process raft request' (duration: 438.064095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.812191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:09.373639Z","time spent":"438.41135ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:10:09.812224Z","caller":"traceutil/trace.go:171","msg":"trace[160538161] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"437.256312ms","start":"2026-06-08T22:10:09.374941Z","end":"2026-06-08T22:10:09.812197Z","steps":["trace[160538161] 'process raft request' (duration: 436.946807ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:09.812247Z","caller":"traceutil/trace.go:171","msg":"trace[509746884] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2277; }","duration":"433.758865ms","start":"2026-06-08T22:10:09.378454Z","end":"2026-06-08T22:10:09.812213Z","steps":["trace[509746884] 'read index received' (duration: 195.21779ms)","trace[509746884] 'applied index is now lower than readState.Index' (duration: 238.539215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:10:09.812378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:09.374933Z","time spent":"437.363345ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:10:09.812468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.01136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:10:09.812529Z","caller":"traceutil/trace.go:171","msg":"trace[1566345871] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2197; }","duration":"434.088722ms","start":"2026-06-08T22:10:09.378421Z","end":"2026-06-08T22:10:09.812509Z","steps":["trace[1566345871] 'agreement among raft nodes before linearized reading' (duration: 434.003331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.812564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:09.378386Z","time spent":"434.172003ms","remote":"127.0.0.1:54560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-08T22:10:09.812701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.007718ms","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-06-08T22:10:09.812781Z","caller":"traceutil/trace.go:171","msg":"trace[392526717] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2197; }","duration":"316.117489ms","start":"2026-06-08T22:10:09.496619Z","end":"2026-06-08T22:10:09.812736Z","steps":["trace[392526717] 'agreement among raft nodes before linearized reading' (duration: 315.853935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.812866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:10:09.496601Z","time spent":"316.222072ms","remote":"127.0.0.1:47136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-08T22:10:09.921533Z","caller":"traceutil/trace.go:171","msg":"trace[165029437] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"105.716795ms","start":"2026-06-08T22:10:09.815789Z","end":"2026-06-08T22:10:09.921506Z","steps":["trace[165029437] 'read index received' (duration: 99.19805ms)","trace[165029437] 'applied index is now lower than readState.Index' (duration: 6.517324ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:10:09.921586Z","caller":"traceutil/trace.go:171","msg":"trace[1878361709] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"107.309441ms","start":"2026-06-08T22:10:09.814255Z","end":"2026-06-08T22:10:09.921564Z","steps":["trace[1878361709] 'process raft request' (duration: 100.716595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:09.921762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.924488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:10:09.922092Z","caller":"traceutil/trace.go:171","msg":"trace[586905913] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2198; }","duration":"106.331665ms","start":"2026-06-08T22:10:09.815733Z","end":"2026-06-08T22:10:09.922064Z","steps":["trace[586905913] 'agreement among raft nodes before linearized reading' (duration: 105.931899ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:10:10.272528Z","caller":"traceutil/trace.go:171","msg":"trace[827118231] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"289.083657ms","start":"2026-06-08T22:10:09.983422Z","end":"2026-06-08T22:10:10.272506Z","steps":["trace[827118231] 'process raft request' (duration: 286.948942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:10:10.272884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.333397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-08T22:10:10.272544Z","caller":"traceutil/trace.go:171","msg":"trace[1731325904] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"140.954441ms","start":"2026-06-08T22:10:10.131533Z","end":"2026-06-08T22:10:10.272487Z","steps":["trace[1731325904] 'read index received' (duration: 138.851968ms)","trace[1731325904] 'applied index is now lower than readState.Index' (duration: 2.101023ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:10:10.272971Z","caller":"traceutil/trace.go:171","msg":"trace[1168083826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2199; }","duration":"141.454839ms","start":"2026-06-08T22:10:10.131472Z","end":"2026-06-08T22:10:10.272927Z","steps":["trace[1168083826] 'agreement among raft nodes before linearized reading' (duration: 141.127284ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:11:36.021693Z","caller":"traceutil/trace.go:171","msg":"trace[203004115] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"129.231233ms","start":"2026-06-08T22:11:35.892436Z","end":"2026-06-08T22:11:36.021667Z","steps":["trace[203004115] 'process raft request' (duration: 129.085201ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:12:09.00641Z","caller":"traceutil/trace.go:171","msg":"trace[293654936] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"106.500326ms","start":"2026-06-08T22:12:08.899857Z","end":"2026-06-08T22:12:09.006357Z","steps":["trace[293654936] 'process raft request' (duration: 106.326664ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:12:09.519288Z","caller":"traceutil/trace.go:171","msg":"trace[826570483] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"503.079375ms","start":"2026-06-08T22:12:09.016183Z","end":"2026-06-08T22:12:09.519262Z","steps":["trace[826570483] 'process raft request' (duration: 502.880283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.51969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.016167Z","time spent":"503.202577ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:12:09.537841Z","caller":"traceutil/trace.go:171","msg":"trace[1418151308] linearizableReadLoop","detail":"{readStateIndex:3052; appliedIndex:3048; }","duration":"474.766491ms","start":"2026-06-08T22:12:09.06306Z","end":"2026-06-08T22:12:09.537826Z","steps":["trace[1418151308] 'read index received' (duration: 455.997363ms)","trace[1418151308] 'applied index is now lower than readState.Index' (duration: 18.767638ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:12:09.537862Z","caller":"traceutil/trace.go:171","msg":"trace[207067755] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"492.986703ms","start":"2026-06-08T22:12:09.044851Z","end":"2026-06-08T22:12:09.537838Z","steps":["trace[207067755] 'process raft request' (duration: 492.73772ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:12:09.537928Z","caller":"traceutil/trace.go:171","msg":"trace[269548581] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"480.614222ms","start":"2026-06-08T22:12:09.057304Z","end":"2026-06-08T22:12:09.537919Z","steps":["trace[269548581] 'process raft request' (duration: 480.47392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.537981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.044819Z","time spent":"493.094484ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:12:09.537997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.05728Z","time spent":"480.672842ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:12:09.538295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.237676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:12:09.538323Z","caller":"traceutil/trace.go:171","msg":"trace[2138359457] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2943; }","duration":"475.304198ms","start":"2026-06-08T22:12:09.063012Z","end":"2026-06-08T22:12:09.538316Z","steps":["trace[2138359457] 'agreement among raft nodes before linearized reading' (duration: 475.249537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.538341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.062995Z","time spent":"475.341718ms","remote":"127.0.0.1:47050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:12:09.538483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.014987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:12:09.538528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.801214ms","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-06-08T22:12:09.53854Z","caller":"traceutil/trace.go:171","msg":"trace[216521192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2943; }","duration":"203.111758ms","start":"2026-06-08T22:12:09.335414Z","end":"2026-06-08T22:12:09.538526Z","steps":["trace[216521192] 'agreement among raft nodes before linearized reading' (duration: 203.032157ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:12:09.538545Z","caller":"traceutil/trace.go:171","msg":"trace[497131092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2943; }","duration":"378.856595ms","start":"2026-06-08T22:12:09.159683Z","end":"2026-06-08T22:12:09.53854Z","steps":["trace[497131092] 'agreement among raft nodes before linearized reading' (duration: 378.783404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.538575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.159663Z","time spent":"378.908446ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-08T22:12:09.537712Z","caller":"traceutil/trace.go:171","msg":"trace[861564850] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"497.955263ms","start":"2026-06-08T22:12:09.039738Z","end":"2026-06-08T22:12:09.537693Z","steps":["trace[861564850] 'process raft request' (duration: 497.763051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.538962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.039724Z","time spent":"499.194899ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:12:09.539216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.099937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b73aafbe6d24d9\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-08T22:12:09.539349Z","caller":"traceutil/trace.go:171","msg":"trace[1538901069] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b73aafbe6d24d9; range_end:; response_count:1; response_revision:2943; }","duration":"401.319149ms","start":"2026-06-08T22:12:09.138001Z","end":"2026-06-08T22:12:09.53932Z","steps":["trace[1538901069] 'agreement among raft nodes before linearized reading' (duration: 400.340477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:12:09.539593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:12:09.137972Z","time spent":"401.604462ms","remote":"127.0.0.1:54146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b73aafbe6d24d9\" "} {"level":"info","ts":"2026-06-08T22:12:09.676002Z","caller":"traceutil/trace.go:171","msg":"trace[379396341] transaction","detail":"{read_only:false; response_revision:2944; number_of_response:1; }","duration":"133.980988ms","start":"2026-06-08T22:12:09.541976Z","end":"2026-06-08T22:12:09.675957Z","steps":["trace[379396341] 'process raft request' (duration: 110.916568ms)","trace[379396341] 'compare' (duration: 22.849767ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:12:09.677438Z","caller":"traceutil/trace.go:171","msg":"trace[1774505559] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"133.812667ms","start":"2026-06-08T22:12:09.543577Z","end":"2026-06-08T22:12:09.67739Z","steps":["trace[1774505559] 'process raft request' (duration: 133.600914ms)"],"step_count":1}