{"level":"warn","ts":"2026-07-21T05:22:21.062644Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:22:21.062773Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.216: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.19.213.216:2380","--initial-cluster=instance=https://199.19.213.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.216: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-07-21T05:22:21.062876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:22:21.062895Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.216:2380"]} {"level":"info","ts":"2026-07-21T05:22:21.062922Z","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-07-21T05:22:21.063856Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.216:2379"]} {"level":"info","ts":"2026-07-21T05:22:21.063957Z","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.19.213.216:2380"],"listen-peer-urls":["https://199.19.213.216:2380"],"advertise-client-urls":["https://199.19.213.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.216: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-07-21T05:22:21.069664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.386845ms"} {"level":"info","ts":"2026-07-21T05:22:21.07767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"58fa9386873601ed","cluster-id":"b3ae27d84cfabdf7"} {"level":"info","ts":"2026-07-21T05:22:21.077805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T05:22:21.077835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed became follower at term 0"} {"level":"info","ts":"2026-07-21T05:22:21.077854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 58fa9386873601ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T05:22:21.077866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed became follower at term 1"} {"level":"info","ts":"2026-07-21T05:22:21.077946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed switched to configuration voters=(6411599225518686701)"} {"level":"warn","ts":"2026-07-21T05:22:21.094022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T05:22:21.099155Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T05:22:21.101683Z","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-07-21T05:22:21.106776Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"58fa9386873601ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T05:22:21.106885Z","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-07-21T05:22:21.106977Z","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-07-21T05:22:21.106987Z","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-07-21T05:22:21.107878Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"58fa9386873601ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T05:22:21.109409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed switched to configuration voters=(6411599225518686701)"} {"level":"info","ts":"2026-07-21T05:22:21.111541Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b3ae27d84cfabdf7","local-member-id":"58fa9386873601ed","added-peer-id":"58fa9386873601ed","added-peer-peer-urls":["https://199.19.213.216:2380"]} {"level":"info","ts":"2026-07-21T05:22:21.114837Z","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-07-21T05:22:21.115067Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"58fa9386873601ed","initial-advertise-peer-urls":["https://199.19.213.216:2380"],"listen-peer-urls":["https://199.19.213.216:2380"],"advertise-client-urls":["https://199.19.213.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T05:22:21.115104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T05:22:21.115209Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.216:2380"} {"level":"info","ts":"2026-07-21T05:22:21.115224Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.216:2380"} {"level":"info","ts":"2026-07-21T05:22:21.77848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T05:22:21.778649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T05:22:21.778738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed received MsgPreVoteResp from 58fa9386873601ed at term 1"} {"level":"info","ts":"2026-07-21T05:22:21.778813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed became candidate at term 2"} {"level":"info","ts":"2026-07-21T05:22:21.77887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed received MsgVoteResp from 58fa9386873601ed at term 2"} {"level":"info","ts":"2026-07-21T05:22:21.778932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"58fa9386873601ed became leader at term 2"} {"level":"info","ts":"2026-07-21T05:22:21.779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 58fa9386873601ed elected leader 58fa9386873601ed at term 2"} {"level":"info","ts":"2026-07-21T05:22:21.781454Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:22:21.782363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"58fa9386873601ed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.216:2379]}","request-path":"/0/members/58fa9386873601ed/attributes","cluster-id":"b3ae27d84cfabdf7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T05:22:21.782487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:22:21.782871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T05:22:21.782905Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T05:22:21.782536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:22:21.782676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b3ae27d84cfabdf7","local-member-id":"58fa9386873601ed","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:22:21.783454Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:22:21.783523Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:22:21.784743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.216:2379"} {"level":"info","ts":"2026-07-21T05:22:21.787185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T05:22:23.19417Z","caller":"traceutil/trace.go:171","msg":"trace[118175552] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"158.757653ms","start":"2026-07-21T05:22:23.035368Z","end":"2026-07-21T05:22:23.194126Z","steps":["trace[118175552] 'process raft request' (duration: 126.610817ms)","trace[118175552] 'compare' (duration: 31.869538ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:23.194207Z","caller":"traceutil/trace.go:171","msg":"trace[1559813081] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"148.664103ms","start":"2026-07-21T05:22:23.045517Z","end":"2026-07-21T05:22:23.194181Z","steps":["trace[1559813081] 'process raft request' (duration: 148.512249ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.194374Z","caller":"traceutil/trace.go:171","msg":"trace[1708001458] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"137.211152ms","start":"2026-07-21T05:22:23.057154Z","end":"2026-07-21T05:22:23.194365Z","steps":["trace[1708001458] 'process raft request' (duration: 136.930215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.194615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.710737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:23.194731Z","caller":"traceutil/trace.go:171","msg":"trace[1557471987] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"161.838423ms","start":"2026-07-21T05:22:23.032864Z","end":"2026-07-21T05:22:23.194702Z","steps":["trace[1557471987] 'agreement among raft nodes before linearized reading' (duration: 161.663118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.19486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.811377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:23.194921Z","caller":"traceutil/trace.go:171","msg":"trace[1056196098] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"149.291851ms","start":"2026-07-21T05:22:23.045614Z","end":"2026-07-21T05:22:23.194906Z","steps":["trace[1056196098] 'agreement among raft nodes before linearized reading' (duration: 148.772236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.194882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.690727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:23.195024Z","caller":"traceutil/trace.go:171","msg":"trace[1076078356] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"162.78785ms","start":"2026-07-21T05:22:23.032176Z","end":"2026-07-21T05:22:23.194964Z","steps":["trace[1076078356] 'agreement among raft nodes before linearized reading' (duration: 162.653435ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.195203Z","caller":"traceutil/trace.go:171","msg":"trace[1427806543] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:12; }","duration":"120.715258ms","start":"2026-07-21T05:22:23.073636Z","end":"2026-07-21T05:22:23.194352Z","steps":["trace[1427806543] 'read index received' (duration: 88.481069ms)","trace[1427806543] 'applied index is now lower than readState.Index' (duration: 32.233099ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:23.206327Z","caller":"traceutil/trace.go:171","msg":"trace[1612076872] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"130.687894ms","start":"2026-07-21T05:22:23.075621Z","end":"2026-07-21T05:22:23.206309Z","steps":["trace[1612076872] 'process raft request' (duration: 130.496128ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.206601Z","caller":"traceutil/trace.go:171","msg":"trace[319219001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"130.577822ms","start":"2026-07-21T05:22:23.076011Z","end":"2026-07-21T05:22:23.206589Z","steps":["trace[319219001] 'process raft request' (duration: 130.312293ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.206609Z","caller":"traceutil/trace.go:171","msg":"trace[2094687766] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"130.661055ms","start":"2026-07-21T05:22:23.075929Z","end":"2026-07-21T05:22:23.20659Z","steps":["trace[2094687766] 'process raft request' (duration: 130.355246ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.206649Z","caller":"traceutil/trace.go:171","msg":"trace[2011647261] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"129.419938ms","start":"2026-07-21T05:22:23.077182Z","end":"2026-07-21T05:22:23.206602Z","steps":["trace[2011647261] 'process raft request' (duration: 129.213382ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.206646Z","caller":"traceutil/trace.go:171","msg":"trace[318933868] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"129.404639ms","start":"2026-07-21T05:22:23.07723Z","end":"2026-07-21T05:22:23.206635Z","steps":["trace[318933868] 'process raft request' (duration: 129.189772ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.206921Z","caller":"traceutil/trace.go:171","msg":"trace[449603273] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"130.484889ms","start":"2026-07-21T05:22:23.076425Z","end":"2026-07-21T05:22:23.20691Z","steps":["trace[449603273] 'process raft request' (duration: 129.916602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.207038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.852091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-21T05:22:23.207081Z","caller":"traceutil/trace.go:171","msg":"trace[1244061579] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"129.982854ms","start":"2026-07-21T05:22:23.077086Z","end":"2026-07-21T05:22:23.207069Z","steps":["trace[1244061579] 'agreement among raft nodes before linearized reading' (duration: 129.853591ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.207569Z","caller":"traceutil/trace.go:171","msg":"trace[2025505210] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"130.731136ms","start":"2026-07-21T05:22:23.075823Z","end":"2026-07-21T05:22:23.206554Z","steps":["trace[2025505210] 'process raft request' (duration: 130.378416ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.352867Z","caller":"traceutil/trace.go:171","msg":"trace[251560562] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"143.364311ms","start":"2026-07-21T05:22:23.209474Z","end":"2026-07-21T05:22:23.352838Z","steps":["trace[251560562] 'process raft request' (duration: 84.971428ms)","trace[251560562] 'compare' (duration: 58.004141ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:23.352978Z","caller":"traceutil/trace.go:171","msg":"trace[993186431] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"143.071482ms","start":"2026-07-21T05:22:23.209878Z","end":"2026-07-21T05:22:23.352949Z","steps":["trace[993186431] 'process raft request' (duration: 142.762593ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.35288Z","caller":"traceutil/trace.go:171","msg":"trace[1424457190] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"142.514886ms","start":"2026-07-21T05:22:23.210315Z","end":"2026-07-21T05:22:23.35283Z","steps":["trace[1424457190] 'process raft request' (duration: 142.395171ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.353087Z","caller":"traceutil/trace.go:171","msg":"trace[1493776431] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"143.178845ms","start":"2026-07-21T05:22:23.209885Z","end":"2026-07-21T05:22:23.353064Z","steps":["trace[1493776431] 'process raft request' (duration: 142.718481ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.353099Z","caller":"traceutil/trace.go:171","msg":"trace[963643610] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"142.747393ms","start":"2026-07-21T05:22:23.210334Z","end":"2026-07-21T05:22:23.353081Z","steps":["trace[963643610] 'process raft request' (duration: 142.402013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.353496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:23.353571Z","caller":"traceutil/trace.go:171","msg":"trace[1303977328] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"143.501074ms","start":"2026-07-21T05:22:23.21005Z","end":"2026-07-21T05:22:23.353551Z","steps":["trace[1303977328] 'agreement among raft nodes before linearized reading' (duration: 143.35431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:23.353632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.295755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-21T05:22:23.353695Z","caller":"traceutil/trace.go:171","msg":"trace[1509244802] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:29; }","duration":"145.380618ms","start":"2026-07-21T05:22:23.208297Z","end":"2026-07-21T05:22:23.353677Z","steps":["trace[1509244802] 'agreement among raft nodes before linearized reading' (duration: 145.234785ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:23.353778Z","caller":"traceutil/trace.go:171","msg":"trace[1448492837] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"143.597427ms","start":"2026-07-21T05:22:23.210154Z","end":"2026-07-21T05:22:23.353751Z","steps":["trace[1448492837] 'process raft request' (duration: 142.523746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:24.34522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.47929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-21T05:22:24.345343Z","caller":"traceutil/trace.go:171","msg":"trace[1970834916] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:100; }","duration":"419.862901ms","start":"2026-07-21T05:22:23.925463Z","end":"2026-07-21T05:22:24.345326Z","steps":["trace[1970834916] 'read index received' (duration: 91.800564ms)","trace[1970834916] 'applied index is now lower than readState.Index' (duration: 328.060657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:22:24.345463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.992515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:24.345441Z","caller":"traceutil/trace.go:171","msg":"trace[1569327111] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"529.493149ms","start":"2026-07-21T05:22:23.815894Z","end":"2026-07-21T05:22:24.345387Z","steps":["trace[1569327111] 'process raft request' (duration: 201.36908ms)","trace[1569327111] 'compare' (duration: 327.309105ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:22:24.345501Z","caller":"traceutil/trace.go:171","msg":"trace[1364701579] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:96; }","duration":"420.068818ms","start":"2026-07-21T05:22:23.925423Z","end":"2026-07-21T05:22:24.345492Z","steps":["trace[1364701579] 'agreement among raft nodes before linearized reading' (duration: 419.985145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:24.345536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:23.925402Z","time spent":"420.126639ms","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-07-21T05:22:24.345623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:23.815881Z","time spent":"529.640614ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":740,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:22:24.345648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.568693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:24.345748Z","caller":"traceutil/trace.go:171","msg":"trace[189453115] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:96; }","duration":"419.729637ms","start":"2026-07-21T05:22:23.925997Z","end":"2026-07-21T05:22:24.345727Z","steps":["trace[189453115] 'agreement among raft nodes before linearized reading' (duration: 419.49582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:24.345745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.29775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:22:24.345824Z","caller":"traceutil/trace.go:171","msg":"trace[664055264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:96; }","duration":"313.417334ms","start":"2026-07-21T05:22:24.032388Z","end":"2026-07-21T05:22:24.345805Z","steps":["trace[664055264] 'agreement among raft nodes before linearized reading' (duration: 313.252589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:22:24.345825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:23.925272Z","time spent":"420.523001ms","remote":"127.0.0.1:46254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-07-21T05:22:24.345937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:22:24.032373Z","time spent":"313.505337ms","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T05:22:51.302302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.540773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:22:51.302445Z","caller":"traceutil/trace.go:171","msg":"trace[153108361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"125.733159ms","start":"2026-07-21T05:22:51.176683Z","end":"2026-07-21T05:22:51.302416Z","steps":["trace[153108361] 'range keys from in-memory index tree' (duration: 125.429538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:22:52.869977Z","caller":"traceutil/trace.go:171","msg":"trace[1165087818] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"108.876387ms","start":"2026-07-21T05:22:52.760519Z","end":"2026-07-21T05:22:52.869395Z","steps":["trace[1165087818] 'process raft request' (duration: 108.725733ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:20.34275Z","caller":"traceutil/trace.go:171","msg":"trace[429417606] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"252.874431ms","start":"2026-07-21T05:23:20.089854Z","end":"2026-07-21T05:23:20.342728Z","steps":["trace[429417606] 'process raft request' (duration: 244.845914ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:20.343253Z","caller":"traceutil/trace.go:171","msg":"trace[1319761791] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"248.818622ms","start":"2026-07-21T05:23:20.094425Z","end":"2026-07-21T05:23:20.343244Z","steps":["trace[1319761791] 'process raft request' (duration: 248.538413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:20.343321Z","caller":"traceutil/trace.go:171","msg":"trace[1575873172] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"246.364199ms","start":"2026-07-21T05:23:20.09695Z","end":"2026-07-21T05:23:20.343315Z","steps":["trace[1575873172] 'process raft request' (duration: 246.168273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:20.529072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.850583ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T05:23:20.529173Z","caller":"traceutil/trace.go:171","msg":"trace[1158872972] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"352.795707ms","start":"2026-07-21T05:23:20.176355Z","end":"2026-07-21T05:23:20.529151Z","steps":["trace[1158872972] 'read index received' (duration: 158.358529ms)","trace[1158872972] 'applied index is now lower than readState.Index' (duration: 194.435648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:23:20.529313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.92955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:23:20.529369Z","caller":"traceutil/trace.go:171","msg":"trace[472032808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"353.011063ms","start":"2026-07-21T05:23:20.17634Z","end":"2026-07-21T05:23:20.529351Z","steps":["trace[472032808] 'agreement among raft nodes before linearized reading' (duration: 352.892899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:20.529422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:20.176294Z","time spent":"353.115366ms","remote":"127.0.0.1:45876","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-07-21T05:23:20.538013Z","caller":"traceutil/trace.go:171","msg":"trace[472662827] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"153.317218ms","start":"2026-07-21T05:23:20.384674Z","end":"2026-07-21T05:23:20.537991Z","steps":["trace[472662827] 'process raft request' (duration: 153.121032ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:20.545461Z","caller":"traceutil/trace.go:171","msg":"trace[1370237042] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"145.346912ms","start":"2026-07-21T05:23:20.400093Z","end":"2026-07-21T05:23:20.545439Z","steps":["trace[1370237042] 'process raft request' (duration: 144.875558ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:20.545466Z","caller":"traceutil/trace.go:171","msg":"trace[329897732] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"131.009246ms","start":"2026-07-21T05:23:20.414432Z","end":"2026-07-21T05:23:20.545441Z","steps":["trace[329897732] 'process raft request' (duration: 130.697677ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:21.503327Z","caller":"traceutil/trace.go:171","msg":"trace[1065287160] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"115.033695ms","start":"2026-07-21T05:23:21.38827Z","end":"2026-07-21T05:23:21.503304Z","steps":["trace[1065287160] 'process raft request' (duration: 114.009305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:23.78393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.434502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:23:23.784028Z","caller":"traceutil/trace.go:171","msg":"trace[130736201] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:658; }","duration":"137.579306ms","start":"2026-07-21T05:23:23.646427Z","end":"2026-07-21T05:23:23.784006Z","steps":["trace[130736201] 'range keys from in-memory index tree' (duration: 137.357391ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:24.951764Z","caller":"traceutil/trace.go:171","msg":"trace[1120576880] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"117.241864ms","start":"2026-07-21T05:23:24.834496Z","end":"2026-07-21T05:23:24.951738Z","steps":["trace[1120576880] 'process raft request' (duration: 116.715739ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:27.977432Z","caller":"traceutil/trace.go:171","msg":"trace[482025506] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"126.004739ms","start":"2026-07-21T05:23:27.851396Z","end":"2026-07-21T05:23:27.977401Z","steps":["trace[482025506] 'read index received' (duration: 125.160334ms)","trace[482025506] 'applied index is now lower than readState.Index' (duration: 843.125µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:27.97758Z","caller":"traceutil/trace.go:171","msg":"trace[1076290143] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"337.938486ms","start":"2026-07-21T05:23:27.639595Z","end":"2026-07-21T05:23:27.977533Z","steps":["trace[1076290143] 'process raft request' (duration: 337.229675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:27.977737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.310948ms","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-07-21T05:23:27.977833Z","caller":"traceutil/trace.go:171","msg":"trace[1925068445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"126.460104ms","start":"2026-07-21T05:23:27.851353Z","end":"2026-07-21T05:23:27.977813Z","steps":["trace[1925068445] 'agreement among raft nodes before linearized reading' (duration: 126.196045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:27.978486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:27.639571Z","time spent":"338.086201ms","remote":"127.0.0.1:45964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:23:28.37032Z","caller":"traceutil/trace.go:171","msg":"trace[2051281822] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"386.671602ms","start":"2026-07-21T05:23:27.983616Z","end":"2026-07-21T05:23:28.370287Z","steps":["trace[2051281822] 'process raft request' (duration: 347.554587ms)","trace[2051281822] 'compare' (duration: 38.763175ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:28.370356Z","caller":"traceutil/trace.go:171","msg":"trace[2073488910] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"386.392064ms","start":"2026-07-21T05:23:27.983937Z","end":"2026-07-21T05:23:28.370329Z","steps":["trace[2073488910] 'process raft request' (duration: 386.23765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:28.3705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:27.983588Z","time spent":"386.814856ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:23:28.370531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:27.983925Z","time spent":"386.495557ms","remote":"127.0.0.1:46174","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-07-21T05:23:28.370332Z","caller":"traceutil/trace.go:171","msg":"trace[1979007762] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:718; }","duration":"310.590537ms","start":"2026-07-21T05:23:28.059711Z","end":"2026-07-21T05:23:28.370302Z","steps":["trace[1979007762] 'read index received' (duration: 271.437091ms)","trace[1979007762] 'applied index is now lower than readState.Index' (duration: 39.152466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:23:28.37065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.089848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:23:28.370684Z","caller":"traceutil/trace.go:171","msg":"trace[1464577869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"194.162651ms","start":"2026-07-21T05:23:28.17651Z","end":"2026-07-21T05:23:28.370673Z","steps":["trace[1464577869] 'agreement among raft nodes before linearized reading' (duration: 194.11554ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:28.370709Z","caller":"traceutil/trace.go:171","msg":"trace[871663500] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"333.367225ms","start":"2026-07-21T05:23:28.03733Z","end":"2026-07-21T05:23:28.370697Z","steps":["trace[871663500] 'process raft request' (duration: 332.931482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:28.370764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:28.037299Z","time spent":"333.441987ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:23:28.370519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.791003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mx7x8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T05:23:28.371102Z","caller":"traceutil/trace.go:171","msg":"trace[1072263293] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mx7x8; range_end:; response_count:1; response_revision:688; }","duration":"311.38612ms","start":"2026-07-21T05:23:28.059706Z","end":"2026-07-21T05:23:28.371092Z","steps":["trace[1072263293] 'agreement among raft nodes before linearized reading' (duration: 310.67424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:28.371127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:28.05961Z","time spent":"311.509105ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-mx7x8\" "} {"level":"warn","ts":"2026-07-21T05:23:28.890084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":138942549044960212,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T05:23:29.205865Z","caller":"traceutil/trace.go:171","msg":"trace[2087788151] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"816.750409ms","start":"2026-07-21T05:23:28.389083Z","end":"2026-07-21T05:23:29.205833Z","steps":["trace[2087788151] 'read index received' (duration: 800.403942ms)","trace[2087788151] 'applied index is now lower than readState.Index' (duration: 16.344847ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:29.206016Z","caller":"traceutil/trace.go:171","msg":"trace[1869265189] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"820.253294ms","start":"2026-07-21T05:23:28.385749Z","end":"2026-07-21T05:23:29.206002Z","steps":["trace[1869265189] 'process raft request' (duration: 803.726481ms)","trace[1869265189] 'compare' (duration: 16.128041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:23:29.206118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:28.385721Z","time spent":"820.325395ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:23:29.206196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.100439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-21T05:23:29.206258Z","caller":"traceutil/trace.go:171","msg":"trace[381167961] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:690; }","duration":"817.187162ms","start":"2026-07-21T05:23:28.389054Z","end":"2026-07-21T05:23:29.206241Z","steps":["trace[381167961] 'agreement among raft nodes before linearized reading' (duration: 817.045578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:29.206313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:28.389043Z","time spent":"817.256944ms","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-07-21T05:23:33.372731Z","caller":"traceutil/trace.go:171","msg":"trace[338890841] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"121.476724ms","start":"2026-07-21T05:23:33.25123Z","end":"2026-07-21T05:23:33.372707Z","steps":["trace[338890841] 'process raft request' (duration: 121.34236ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:33.41002Z","caller":"traceutil/trace.go:171","msg":"trace[1291225816] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"144.050409ms","start":"2026-07-21T05:23:33.265935Z","end":"2026-07-21T05:23:33.409985Z","steps":["trace[1291225816] 'process raft request' (duration: 143.883643ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:34.203876Z","caller":"traceutil/trace.go:171","msg":"trace[1576411001] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"112.988982ms","start":"2026-07-21T05:23:34.090851Z","end":"2026-07-21T05:23:34.20384Z","steps":["trace[1576411001] 'process raft request' (duration: 109.222709ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:34.225099Z","caller":"traceutil/trace.go:171","msg":"trace[1492080784] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"123.004492ms","start":"2026-07-21T05:23:34.102076Z","end":"2026-07-21T05:23:34.225081Z","steps":["trace[1492080784] 'read index received' (duration: 97.80946ms)","trace[1492080784] 'applied index is now lower than readState.Index' (duration: 25.194392ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:34.225121Z","caller":"traceutil/trace.go:171","msg":"trace[959940544] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"124.124775ms","start":"2026-07-21T05:23:34.100977Z","end":"2026-07-21T05:23:34.225102Z","steps":["trace[959940544] 'process raft request' (duration: 124.002671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:34.225499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.410254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mx7x8\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-07-21T05:23:34.225557Z","caller":"traceutil/trace.go:171","msg":"trace[545883727] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mx7x8; range_end:; response_count:1; response_revision:762; }","duration":"123.499427ms","start":"2026-07-21T05:23:34.102042Z","end":"2026-07-21T05:23:34.225541Z","steps":["trace[545883727] 'agreement among raft nodes before linearized reading' (duration: 123.107785ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:34.498851Z","caller":"traceutil/trace.go:171","msg":"trace[944648910] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"100.18335ms","start":"2026-07-21T05:23:34.398641Z","end":"2026-07-21T05:23:34.498824Z","steps":["trace[944648910] 'process raft request' (duration: 98.714247ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:34.552642Z","caller":"traceutil/trace.go:171","msg":"trace[1097184343] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"133.017512ms","start":"2026-07-21T05:23:34.419589Z","end":"2026-07-21T05:23:34.552607Z","steps":["trace[1097184343] 'process raft request' (duration: 126.93625ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:34.552671Z","caller":"traceutil/trace.go:171","msg":"trace[633259800] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"125.748973ms","start":"2026-07-21T05:23:34.42689Z","end":"2026-07-21T05:23:34.552639Z","steps":["trace[633259800] 'process raft request' (duration: 125.580408ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:46.999345Z","caller":"traceutil/trace.go:171","msg":"trace[1913586783] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"319.553246ms","start":"2026-07-21T05:23:46.67976Z","end":"2026-07-21T05:23:46.999313Z","steps":["trace[1913586783] 'read index received' (duration: 319.303449ms)","trace[1913586783] 'applied index is now lower than readState.Index' (duration: 248.587µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:46.999446Z","caller":"traceutil/trace.go:171","msg":"trace[620391117] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"395.236896ms","start":"2026-07-21T05:23:46.604188Z","end":"2026-07-21T05:23:46.999425Z","steps":["trace[620391117] 'process raft request' (duration: 394.936427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:46.99961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.829754ms","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-07-21T05:23:46.999689Z","caller":"traceutil/trace.go:171","msg":"trace[1931128925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"319.956127ms","start":"2026-07-21T05:23:46.679716Z","end":"2026-07-21T05:23:46.999672Z","steps":["trace[1931128925] 'agreement among raft nodes before linearized reading' (duration: 319.739321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:46.999743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:46.679694Z","time spent":"320.03863ms","remote":"127.0.0.1:46174","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-07-21T05:23:46.999799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:46.604161Z","time spent":"395.414942ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:23:46.999917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.306958ms","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-07-21T05:23:47.000058Z","caller":"traceutil/trace.go:171","msg":"trace[1065381686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"188.524126ms","start":"2026-07-21T05:23:46.811505Z","end":"2026-07-21T05:23:47.000029Z","steps":["trace[1065381686] 'agreement among raft nodes before linearized reading' (duration: 188.138604ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:47.334239Z","caller":"traceutil/trace.go:171","msg":"trace[45521571] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"329.590591ms","start":"2026-07-21T05:23:47.004616Z","end":"2026-07-21T05:23:47.334207Z","steps":["trace[45521571] 'process raft request' (duration: 329.445516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:47.334385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:47.00459Z","time spent":"329.711535ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:23:47.345763Z","caller":"traceutil/trace.go:171","msg":"trace[1734217118] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"171.506657ms","start":"2026-07-21T05:23:47.174233Z","end":"2026-07-21T05:23:47.345739Z","steps":["trace[1734217118] 'read index received' (duration: 159.793685ms)","trace[1734217118] 'applied index is now lower than readState.Index' (duration: 11.711882ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:23:47.345857Z","caller":"traceutil/trace.go:171","msg":"trace[381769066] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"340.856939ms","start":"2026-07-21T05:23:47.004975Z","end":"2026-07-21T05:23:47.345831Z","steps":["trace[381769066] 'process raft request' (duration: 340.55752ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:23:47.345973Z","caller":"traceutil/trace.go:171","msg":"trace[302415856] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"142.467195ms","start":"2026-07-21T05:23:47.20349Z","end":"2026-07-21T05:23:47.345957Z","steps":["trace[302415856] 'process raft request' (duration: 142.179026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:47.346051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.803687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:23:47.34609Z","caller":"traceutil/trace.go:171","msg":"trace[1208074862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"171.894729ms","start":"2026-07-21T05:23:47.174183Z","end":"2026-07-21T05:23:47.346078Z","steps":["trace[1208074862] 'agreement among raft nodes before linearized reading' (duration: 171.802596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:23:47.346457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:23:47.004956Z","time spent":"341.002972ms","remote":"127.0.0.1:46174","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-07-21T05:24:20.698715Z","caller":"traceutil/trace.go:171","msg":"trace[290886944] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"353.950644ms","start":"2026-07-21T05:24:20.344738Z","end":"2026-07-21T05:24:20.698688Z","steps":["trace[290886944] 'process raft request' (duration: 351.445738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:24:20.698899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:24:20.344713Z","time spent":"354.086259ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:24:54.458997Z","caller":"traceutil/trace.go:171","msg":"trace[68386357] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"157.332894ms","start":"2026-07-21T05:24:54.301647Z","end":"2026-07-21T05:24:54.45898Z","steps":["trace[68386357] 'process raft request' (duration: 156.988154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:24:54.4592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.026921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1082"} {"level":"info","ts":"2026-07-21T05:24:54.459274Z","caller":"traceutil/trace.go:171","msg":"trace[883025627] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1743; }","duration":"152.135153ms","start":"2026-07-21T05:24:54.30712Z","end":"2026-07-21T05:24:54.459256Z","steps":["trace[883025627] 'agreement among raft nodes before linearized reading' (duration: 151.962168ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:54.459275Z","caller":"traceutil/trace.go:171","msg":"trace[276709783] linearizableReadLoop","detail":"{readStateIndex:1818; appliedIndex:1817; }","duration":"151.771223ms","start":"2026-07-21T05:24:54.307152Z","end":"2026-07-21T05:24:54.458923Z","steps":["trace[276709783] 'read index received' (duration: 151.498595ms)","trace[276709783] 'applied index is now lower than readState.Index' (duration: 271.968µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:24:54.464469Z","caller":"traceutil/trace.go:171","msg":"trace[558445295] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1744; }","duration":"146.847551ms","start":"2026-07-21T05:24:54.317554Z","end":"2026-07-21T05:24:54.464401Z","steps":["trace[558445295] 'process raft request' (duration: 146.78794ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:54.464507Z","caller":"traceutil/trace.go:171","msg":"trace[1821093802] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"151.832145ms","start":"2026-07-21T05:24:54.312651Z","end":"2026-07-21T05:24:54.464483Z","steps":["trace[1821093802] 'process raft request' (duration: 151.598448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:24:54.464653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.502526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:149341"} {"level":"info","ts":"2026-07-21T05:24:54.464688Z","caller":"traceutil/trace.go:171","msg":"trace[468978922] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1744; }","duration":"154.566208ms","start":"2026-07-21T05:24:54.310113Z","end":"2026-07-21T05:24:54.464679Z","steps":["trace[468978922] 'agreement among raft nodes before linearized reading' (duration: 154.278809ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:54.857059Z","caller":"traceutil/trace.go:171","msg":"trace[1499944785] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"120.708748ms","start":"2026-07-21T05:24:54.736323Z","end":"2026-07-21T05:24:54.857032Z","steps":["trace[1499944785] 'process raft request' (duration: 120.560224ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:54.968599Z","caller":"traceutil/trace.go:171","msg":"trace[19272518] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"103.120938ms","start":"2026-07-21T05:24:54.865446Z","end":"2026-07-21T05:24:54.968567Z","steps":["trace[19272518] 'process raft request' (duration: 101.918061ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:24:55.532518Z","caller":"traceutil/trace.go:171","msg":"trace[1613942702] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"164.895137ms","start":"2026-07-21T05:24:55.367584Z","end":"2026-07-21T05:24:55.532479Z","steps":["trace[1613942702] 'process raft request' (duration: 148.150372ms)","trace[1613942702] 'compare' (duration: 16.565449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:25:12.764334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.615728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:25:12.764454Z","caller":"traceutil/trace.go:171","msg":"trace[2136707159] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"457.245711ms","start":"2026-07-21T05:25:12.30719Z","end":"2026-07-21T05:25:12.764435Z","steps":["trace[2136707159] 'process raft request' (duration: 94.464307ms)","trace[2136707159] 'compare' (duration: 362.497955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:25:12.764523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:12.307154Z","time spent":"457.346235ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:25:12.811266Z","caller":"traceutil/trace.go:171","msg":"trace[839191527] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2048; }","duration":"255.525132ms","start":"2026-07-21T05:25:12.555718Z","end":"2026-07-21T05:25:12.811243Z","steps":["trace[839191527] 'read index received' (duration: 255.287675ms)","trace[839191527] 'applied index is now lower than readState.Index' (duration: 236.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:12.811348Z","caller":"traceutil/trace.go:171","msg":"trace[1411980122] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"488.268469ms","start":"2026-07-21T05:25:12.323053Z","end":"2026-07-21T05:25:12.811321Z","steps":["trace[1411980122] 'process raft request' (duration: 488.018941ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:12.811389Z","caller":"traceutil/trace.go:171","msg":"trace[912567944] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"478.902499ms","start":"2026-07-21T05:25:12.332472Z","end":"2026-07-21T05:25:12.811374Z","steps":["trace[912567944] 'process raft request' (duration: 478.731444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:12.811492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:12.323029Z","time spent":"488.382293ms","remote":"127.0.0.1:46174","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-07-21T05:25:12.811599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.956154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:25:12.811643Z","caller":"traceutil/trace.go:171","msg":"trace[422748035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1966; }","duration":"233.029537ms","start":"2026-07-21T05:25:12.578606Z","end":"2026-07-21T05:25:12.811635Z","steps":["trace[422748035] 'agreement among raft nodes before linearized reading' (duration: 232.861321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:12.811551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:12.332453Z","time spent":"478.980783ms","remote":"127.0.0.1:46174","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-07-21T05:25:12.811719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.977796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7723316b-4dc3-41d2-8770-3bae394daf54\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-21T05:25:12.811869Z","caller":"traceutil/trace.go:171","msg":"trace[2023311797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7723316b-4dc3-41d2-8770-3bae394daf54; range_end:; response_count:1; response_revision:1966; }","duration":"256.182091ms","start":"2026-07-21T05:25:12.555677Z","end":"2026-07-21T05:25:12.811859Z","steps":["trace[2023311797] 'agreement among raft nodes before linearized reading' (duration: 255.870632ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:13.036424Z","caller":"traceutil/trace.go:171","msg":"trace[2039267461] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"220.036766ms","start":"2026-07-21T05:25:12.816373Z","end":"2026-07-21T05:25:13.03641Z","steps":["trace[2039267461] 'process raft request' (duration: 219.86422ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:13.036421Z","caller":"traceutil/trace.go:171","msg":"trace[1779182394] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2051; }","duration":"116.659803ms","start":"2026-07-21T05:25:12.919737Z","end":"2026-07-21T05:25:13.036397Z","steps":["trace[1779182394] 'read index received' (duration: 116.427626ms)","trace[1779182394] 'applied index is now lower than readState.Index' (duration: 231.097µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:25:13.036618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.857959ms","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-07-21T05:25:13.036681Z","caller":"traceutil/trace.go:171","msg":"trace[307089326] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"116.965452ms","start":"2026-07-21T05:25:12.9197Z","end":"2026-07-21T05:25:13.036666Z","steps":["trace[307089326] 'agreement among raft nodes before linearized reading' (duration: 116.766986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.050765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.638184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7723316b-4dc3-41d2-8770-3bae394daf54\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-21T05:25:13.050827Z","caller":"traceutil/trace.go:171","msg":"trace[177593578] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7723316b-4dc3-41d2-8770-3bae394daf54; range_end:; response_count:1; response_revision:1969; }","duration":"106.733157ms","start":"2026-07-21T05:25:12.94408Z","end":"2026-07-21T05:25:13.050813Z","steps":["trace[177593578] 'agreement among raft nodes before linearized reading' (duration: 106.612793ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:13.050909Z","caller":"traceutil/trace.go:171","msg":"trace[617151368] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"112.882526ms","start":"2026-07-21T05:25:12.938002Z","end":"2026-07-21T05:25:13.050884Z","steps":["trace[617151368] 'process raft request' (duration: 112.568607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.704852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.951169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:25:13.704941Z","caller":"traceutil/trace.go:171","msg":"trace[1507972807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1969; }","duration":"529.084163ms","start":"2026-07-21T05:25:13.175833Z","end":"2026-07-21T05:25:13.704917Z","steps":["trace[1507972807] 'range keys from in-memory index tree' (duration: 528.868837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.704982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:13.175809Z","time spent":"529.163607ms","remote":"127.0.0.1:45876","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-07-21T05:25:13.704856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.695067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:25:13.705383Z","caller":"traceutil/trace.go:171","msg":"trace[286479560] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1969; }","duration":"602.222814ms","start":"2026-07-21T05:25:13.103097Z","end":"2026-07-21T05:25:13.70532Z","steps":["trace[286479560] 'count revisions from in-memory index tree' (duration: 601.597584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.705477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:13.103079Z","time spent":"602.361238ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:25:13.705568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.816225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:25:13.705992Z","caller":"traceutil/trace.go:171","msg":"trace[338052180] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"261.276441ms","start":"2026-07-21T05:25:13.444682Z","end":"2026-07-21T05:25:13.705959Z","steps":["trace[338052180] 'process raft request' (duration: 159.006631ms)","trace[338052180] 'compare' (duration: 101.199257ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:13.976642Z","caller":"traceutil/trace.go:171","msg":"trace[1631157712] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"266.727319ms","start":"2026-07-21T05:25:13.709892Z","end":"2026-07-21T05:25:13.976619Z","steps":["trace[1631157712] 'process raft request' (duration: 266.591515ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:13.987426Z","caller":"traceutil/trace.go:171","msg":"trace[1638500731] linearizableReadLoop","detail":"{readStateIndex:2056; appliedIndex:2054; }","duration":"153.974526ms","start":"2026-07-21T05:25:13.833432Z","end":"2026-07-21T05:25:13.987407Z","steps":["trace[1638500731] 'read index received' (duration: 143.064349ms)","trace[1638500731] 'applied index is now lower than readState.Index' (duration: 10.909457ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:13.987524Z","caller":"traceutil/trace.go:171","msg":"trace[731547868] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"183.037423ms","start":"2026-07-21T05:25:13.804476Z","end":"2026-07-21T05:25:13.987513Z","steps":["trace[731547868] 'process raft request' (duration: 182.825537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.987695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.888106ms","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-07-21T05:25:13.9878Z","caller":"traceutil/trace.go:171","msg":"trace[51195583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1972; }","duration":"134.035811ms","start":"2026-07-21T05:25:13.853754Z","end":"2026-07-21T05:25:13.987789Z","steps":["trace[51195583] 'agreement among raft nodes before linearized reading' (duration: 133.846345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:13.988047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.614026ms","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-07-21T05:25:13.988076Z","caller":"traceutil/trace.go:171","msg":"trace[1327859419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1972; }","duration":"154.666768ms","start":"2026-07-21T05:25:13.833397Z","end":"2026-07-21T05:25:13.988064Z","steps":["trace[1327859419] 'agreement among raft nodes before linearized reading' (duration: 154.600877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:14.84598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.63427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:25:14.846389Z","caller":"traceutil/trace.go:171","msg":"trace[649606402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1975; }","duration":"669.140986ms","start":"2026-07-21T05:25:14.177218Z","end":"2026-07-21T05:25:14.846359Z","steps":["trace[649606402] 'range keys from in-memory index tree' (duration: 668.488117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:14.846551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:14.177194Z","time spent":"669.334782ms","remote":"127.0.0.1:45876","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-07-21T05:25:19.844955Z","caller":"traceutil/trace.go:171","msg":"trace[1134524056] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"118.993472ms","start":"2026-07-21T05:25:19.725937Z","end":"2026-07-21T05:25:19.844931Z","steps":["trace[1134524056] 'process raft request' (duration: 118.807336ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:26.219038Z","caller":"traceutil/trace.go:171","msg":"trace[1734247826] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"331.143453ms","start":"2026-07-21T05:25:25.887875Z","end":"2026-07-21T05:25:26.219019Z","steps":["trace[1734247826] 'read index received' (duration: 330.973977ms)","trace[1734247826] 'applied index is now lower than readState.Index' (duration: 168.836µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:26.219144Z","caller":"traceutil/trace.go:171","msg":"trace[937427370] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"357.869822ms","start":"2026-07-21T05:25:25.861239Z","end":"2026-07-21T05:25:26.219109Z","steps":["trace[937427370] 'process raft request' (duration: 357.659745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:26.219185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.300428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:25:26.219223Z","caller":"traceutil/trace.go:171","msg":"trace[597192074] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2082; }","duration":"331.357569ms","start":"2026-07-21T05:25:25.887855Z","end":"2026-07-21T05:25:26.219212Z","steps":["trace[597192074] 'agreement among raft nodes before linearized reading' (duration: 331.271857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:26.219288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:25.861212Z","time spent":"357.996405ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:25:26.219299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:25.887781Z","time spent":"331.467213ms","remote":"127.0.0.1:46098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:25:26.219361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.20403ms","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-07-21T05:25:26.219442Z","caller":"traceutil/trace.go:171","msg":"trace[1806763892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2082; }","duration":"184.323253ms","start":"2026-07-21T05:25:26.0351Z","end":"2026-07-21T05:25:26.219423Z","steps":["trace[1806763892] 'agreement among raft nodes before linearized reading' (duration: 184.115716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:26.219559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.236551ms","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-07-21T05:25:26.219643Z","caller":"traceutil/trace.go:171","msg":"trace[23603491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2082; }","duration":"115.363635ms","start":"2026-07-21T05:25:26.104265Z","end":"2026-07-21T05:25:26.219629Z","steps":["trace[23603491] 'agreement among raft nodes before linearized reading' (duration: 115.126118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:26.219808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.340892ms","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-07-21T05:25:26.219812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.705559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T05:25:26.219847Z","caller":"traceutil/trace.go:171","msg":"trace[1379113468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2082; }","duration":"112.408525ms","start":"2026-07-21T05:25:26.107429Z","end":"2026-07-21T05:25:26.219838Z","steps":["trace[1379113468] 'agreement among raft nodes before linearized reading' (duration: 112.308122ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:26.219879Z","caller":"traceutil/trace.go:171","msg":"trace[844582278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2082; }","duration":"147.7997ms","start":"2026-07-21T05:25:26.072062Z","end":"2026-07-21T05:25:26.219862Z","steps":["trace[844582278] 'agreement among raft nodes before linearized reading' (duration: 147.593514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:26.219842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.626907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:25:26.220526Z","caller":"traceutil/trace.go:171","msg":"trace[228674640] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2082; }","duration":"150.35384ms","start":"2026-07-21T05:25:26.070138Z","end":"2026-07-21T05:25:26.220492Z","steps":["trace[228674640] 'agreement among raft nodes before linearized reading' (duration: 149.493163ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:29.105159Z","caller":"traceutil/trace.go:171","msg":"trace[1235491646] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"111.876731ms","start":"2026-07-21T05:25:28.99326Z","end":"2026-07-21T05:25:29.105137Z","steps":["trace[1235491646] 'process raft request' (duration: 110.548119ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:30.691115Z","caller":"traceutil/trace.go:171","msg":"trace[1616833793] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"447.920239ms","start":"2026-07-21T05:25:30.243169Z","end":"2026-07-21T05:25:30.691089Z","steps":["trace[1616833793] 'read index received' (duration: 447.690372ms)","trace[1616833793] 'applied index is now lower than readState.Index' (duration: 228.907µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:30.691234Z","caller":"traceutil/trace.go:171","msg":"trace[32184733] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"460.077626ms","start":"2026-07-21T05:25:30.231145Z","end":"2026-07-21T05:25:30.691223Z","steps":["trace[32184733] 'process raft request' (duration: 459.757086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.691362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.231128Z","time spent":"460.149319ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:25:30.691426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.241679ms","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-07-21T05:25:30.691521Z","caller":"traceutil/trace.go:171","msg":"trace[969786895] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2105; }","duration":"448.380554ms","start":"2026-07-21T05:25:30.243116Z","end":"2026-07-21T05:25:30.691497Z","steps":["trace[969786895] 'agreement among raft nodes before linearized reading' (duration: 448.146226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.6916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.243097Z","time spent":"448.489706ms","remote":"127.0.0.1:60986","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-07-21T05:25:30.697821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.084858ms","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-07-21T05:25:30.697864Z","caller":"traceutil/trace.go:171","msg":"trace[1996648421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2106; }","duration":"442.169971ms","start":"2026-07-21T05:25:30.255684Z","end":"2026-07-21T05:25:30.697854Z","steps":["trace[1996648421] 'agreement among raft nodes before linearized reading' (duration: 442.047776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.697924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.255667Z","time spent":"442.224852ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T05:25:30.698078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.363409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T05:25:30.698128Z","caller":"traceutil/trace.go:171","msg":"trace[1422783683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2106; }","duration":"434.437481ms","start":"2026-07-21T05:25:30.263679Z","end":"2026-07-21T05:25:30.698117Z","steps":["trace[1422783683] 'agreement among raft nodes before linearized reading' (duration: 434.310967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.698162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.263661Z","time spent":"434.493402ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T05:25:30.698186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.844884ms","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-07-21T05:25:30.69824Z","caller":"traceutil/trace.go:171","msg":"trace[148468425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"435.943257ms","start":"2026-07-21T05:25:30.262284Z","end":"2026-07-21T05:25:30.698227Z","steps":["trace[148468425] 'agreement among raft nodes before linearized reading' (duration: 435.788373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.698199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.90861ms","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-07-21T05:25:30.698282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.262262Z","time spent":"436.01227ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T05:25:30.698288Z","caller":"traceutil/trace.go:171","msg":"trace[786583850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2106; }","duration":"431.023865ms","start":"2026-07-21T05:25:30.267255Z","end":"2026-07-21T05:25:30.698279Z","steps":["trace[786583850] 'agreement among raft nodes before linearized reading' (duration: 430.89499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:30.698324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:30.26724Z","time spent":"431.073026ms","remote":"127.0.0.1:46174","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-07-21T05:25:30.69824Z","caller":"traceutil/trace.go:171","msg":"trace[995403089] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"102.525142ms","start":"2026-07-21T05:25:30.595689Z","end":"2026-07-21T05:25:30.698214Z","steps":["trace[995403089] 'process raft request' (duration: 101.968075ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:52.988994Z","caller":"traceutil/trace.go:171","msg":"trace[1643393199] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"131.835213ms","start":"2026-07-21T05:25:52.857103Z","end":"2026-07-21T05:25:52.988938Z","steps":["trace[1643393199] 'process raft request' (duration: 55.620865ms)","trace[1643393199] 'compare' (duration: 76.090904ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:53.039763Z","caller":"traceutil/trace.go:171","msg":"trace[1235207665] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2322; }","duration":"182.33885ms","start":"2026-07-21T05:25:52.857406Z","end":"2026-07-21T05:25:53.039745Z","steps":["trace[1235207665] 'read index received' (duration: 55.328397ms)","trace[1235207665] 'applied index is now lower than readState.Index' (duration: 127.009503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:25:53.03998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.558437ms","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-07-21T05:25:53.040047Z","caller":"traceutil/trace.go:171","msg":"trace[508898433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2232; }","duration":"182.646759ms","start":"2026-07-21T05:25:52.857372Z","end":"2026-07-21T05:25:53.040018Z","steps":["trace[508898433] 'agreement among raft nodes before linearized reading' (duration: 182.466903ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:53.040333Z","caller":"traceutil/trace.go:171","msg":"trace[2089831208] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"183.00119ms","start":"2026-07-21T05:25:52.85732Z","end":"2026-07-21T05:25:53.040321Z","steps":["trace[2089831208] 'process raft request' (duration: 182.285967ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:53.040456Z","caller":"traceutil/trace.go:171","msg":"trace[879503823] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"183.065852ms","start":"2026-07-21T05:25:52.857382Z","end":"2026-07-21T05:25:53.040447Z","steps":["trace[879503823] 'process raft request' (duration: 182.321599ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:54.977833Z","caller":"traceutil/trace.go:171","msg":"trace[824128110] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2330; }","duration":"111.186081ms","start":"2026-07-21T05:25:54.866622Z","end":"2026-07-21T05:25:54.977808Z","steps":["trace[824128110] 'read index received' (duration: 111.032237ms)","trace[824128110] 'applied index is now lower than readState.Index' (duration: 152.744µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:54.97789Z","caller":"traceutil/trace.go:171","msg":"trace[530283139] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"120.817372ms","start":"2026-07-21T05:25:54.857043Z","end":"2026-07-21T05:25:54.97786Z","steps":["trace[530283139] 'process raft request' (duration: 120.602545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:54.978138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.50712ms","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-07-21T05:25:54.978235Z","caller":"traceutil/trace.go:171","msg":"trace[21513082] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2238; }","duration":"111.644586ms","start":"2026-07-21T05:25:54.866569Z","end":"2026-07-21T05:25:54.978214Z","steps":["trace[21513082] 'agreement among raft nodes before linearized reading' (duration: 111.325236ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:55.095931Z","caller":"traceutil/trace.go:171","msg":"trace[477135443] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"101.388327ms","start":"2026-07-21T05:25:54.994517Z","end":"2026-07-21T05:25:55.095906Z","steps":["trace[477135443] 'process raft request' (duration: 89.094584ms)","trace[477135443] 'compare' (duration: 11.985874ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:55.408319Z","caller":"traceutil/trace.go:171","msg":"trace[1465884903] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2331; }","duration":"364.662377ms","start":"2026-07-21T05:25:55.04362Z","end":"2026-07-21T05:25:55.408282Z","steps":["trace[1465884903] 'read index received' (duration: 40.00438ms)","trace[1465884903] 'applied index is now lower than readState.Index' (duration: 324.656337ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:25:55.408569Z","caller":"traceutil/trace.go:171","msg":"trace[185417633] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"357.022588ms","start":"2026-07-21T05:25:55.051505Z","end":"2026-07-21T05:25:55.408528Z","steps":["trace[185417633] 'process raft request' (duration: 356.657706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.408743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.051483Z","time spent":"357.170664ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:25:55.408903Z","caller":"traceutil/trace.go:171","msg":"trace[2093644042] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"411.436947ms","start":"2026-07-21T05:25:54.997448Z","end":"2026-07-21T05:25:55.408885Z","steps":["trace[2093644042] 'process raft request' (duration: 410.482067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.408966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:54.997432Z","time spent":"411.50208ms","remote":"127.0.0.1:46174","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-07-21T05:25:55.409002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.361239ms","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-07-21T05:25:55.409066Z","caller":"traceutil/trace.go:171","msg":"trace[24175796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2242; }","duration":"365.460443ms","start":"2026-07-21T05:25:55.043591Z","end":"2026-07-21T05:25:55.409051Z","steps":["trace[24175796] 'agreement among raft nodes before linearized reading' (duration: 365.258836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.409103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.043575Z","time spent":"365.522734ms","remote":"127.0.0.1:46174","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":"info","ts":"2026-07-21T05:25:55.409182Z","caller":"traceutil/trace.go:171","msg":"trace[834249992] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"355.393297ms","start":"2026-07-21T05:25:55.053507Z","end":"2026-07-21T05:25:55.4089Z","steps":["trace[834249992] 'process raft request' (duration: 354.719007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.409391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.053499Z","time spent":"355.820781ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:25:55.447069Z","caller":"traceutil/trace.go:171","msg":"trace[1251444270] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"151.981886ms","start":"2026-07-21T05:25:55.295041Z","end":"2026-07-21T05:25:55.447023Z","steps":["trace[1251444270] 'process raft request' (duration: 151.748868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.447163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.088137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T05:25:55.447269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.476317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:25:55.447265Z","caller":"traceutil/trace.go:171","msg":"trace[422796637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2243; }","duration":"403.248931ms","start":"2026-07-21T05:25:55.043991Z","end":"2026-07-21T05:25:55.44724Z","steps":["trace[422796637] 'agreement among raft nodes before linearized reading' (duration: 402.989034ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:25:55.447351Z","caller":"traceutil/trace.go:171","msg":"trace[2100049819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2243; }","duration":"271.60017ms","start":"2026-07-21T05:25:55.175731Z","end":"2026-07-21T05:25:55.447331Z","steps":["trace[2100049819] 'agreement among raft nodes before linearized reading' (duration: 271.448476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.447351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.402901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:25:55.447519Z","caller":"traceutil/trace.go:171","msg":"trace[1435854366] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2243; }","duration":"147.740223ms","start":"2026-07-21T05:25:55.299717Z","end":"2026-07-21T05:25:55.447457Z","steps":["trace[1435854366] 'agreement among raft nodes before linearized reading' (duration: 147.366792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.447716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.816523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T05:25:55.448293Z","caller":"traceutil/trace.go:171","msg":"trace[538872867] range","detail":"{range_begin:/registry/masterleases/199.19.213.216; range_end:; response_count:1; response_revision:2243; }","duration":"302.441164ms","start":"2026-07-21T05:25:55.145838Z","end":"2026-07-21T05:25:55.448279Z","steps":["trace[538872867] 'agreement among raft nodes before linearized reading' (duration: 301.283328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.448361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.145809Z","time spent":"302.540317ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.216\" "} {"level":"warn","ts":"2026-07-21T05:25:55.447765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.478558ms","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-07-21T05:25:55.448459Z","caller":"traceutil/trace.go:171","msg":"trace[85159925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2243; }","duration":"395.197909ms","start":"2026-07-21T05:25:55.053235Z","end":"2026-07-21T05:25:55.448433Z","steps":["trace[85159925] 'agreement among raft nodes before linearized reading' (duration: 394.433336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.447468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.04397Z","time spent":"403.441187ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T05:25:55.448559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.053227Z","time spent":"395.303103ms","remote":"127.0.0.1:46174","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-07-21T05:25:55.447887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.945856ms","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-07-21T05:25:55.448655Z","caller":"traceutil/trace.go:171","msg":"trace[68448267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2243; }","duration":"390.771371ms","start":"2026-07-21T05:25:55.057874Z","end":"2026-07-21T05:25:55.448646Z","steps":["trace[68448267] 'agreement among raft nodes before linearized reading' (duration: 389.680197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:25:55.448688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:25:55.057858Z","time spent":"390.821203ms","remote":"127.0.0.1:46174","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-07-21T05:25:55.447942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.790371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c436bd94c0fabc\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-21T05:25:55.448766Z","caller":"traceutil/trace.go:171","msg":"trace[1675373696] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c436bd94c0fabc; range_end:; response_count:1; response_revision:2243; }","duration":"227.657929ms","start":"2026-07-21T05:25:55.221096Z","end":"2026-07-21T05:25:55.448754Z","steps":["trace[1675373696] 'agreement among raft nodes before linearized reading' (duration: 226.688248ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:25.2711Z","caller":"traceutil/trace.go:171","msg":"trace[1816531167] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"119.353064ms","start":"2026-07-21T05:27:25.151722Z","end":"2026-07-21T05:27:25.271075Z","steps":["trace[1816531167] 'read index received' (duration: 119.189919ms)","trace[1816531167] 'applied index is now lower than readState.Index' (duration: 162.435µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:27:25.271228Z","caller":"traceutil/trace.go:171","msg":"trace[1422906121] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"132.041826ms","start":"2026-07-21T05:27:25.139176Z","end":"2026-07-21T05:27:25.271218Z","steps":["trace[1422906121] 'process raft request' (duration: 131.784699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:27:25.271525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.781108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T05:27:25.271643Z","caller":"traceutil/trace.go:171","msg":"trace[275365975] range","detail":"{range_begin:/registry/masterleases/199.19.213.216; range_end:; response_count:1; response_revision:2835; }","duration":"119.906171ms","start":"2026-07-21T05:27:25.151676Z","end":"2026-07-21T05:27:25.271582Z","steps":["trace[275365975] 'agreement among raft nodes before linearized reading' (duration: 119.687904ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:27:25.404355Z","caller":"traceutil/trace.go:171","msg":"trace[114571972] linearizableReadLoop","detail":"{readStateIndex:2949; appliedIndex:2948; }","duration":"116.523064ms","start":"2026-07-21T05:27:25.287796Z","end":"2026-07-21T05:27:25.404319Z","steps":["trace[114571972] 'read index received' (duration: 62.9187ms)","trace[114571972] 'applied index is now lower than readState.Index' (duration: 53.602904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:27:25.404771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.960647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c436d16332d071\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-21T05:27:25.404895Z","caller":"traceutil/trace.go:171","msg":"trace[261413638] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c436d16332d071; range_end:; response_count:1; response_revision:2836; }","duration":"117.123091ms","start":"2026-07-21T05:27:25.287755Z","end":"2026-07-21T05:27:25.404878Z","steps":["trace[261413638] 'agreement among raft nodes before linearized reading' (duration: 116.74545ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:30:22.395622Z","caller":"traceutil/trace.go:171","msg":"trace[512884378] linearizableReadLoop","detail":"{readStateIndex:4059; appliedIndex:4057; }","duration":"221.590857ms","start":"2026-07-21T05:30:22.174005Z","end":"2026-07-21T05:30:22.395595Z","steps":["trace[512884378] 'read index received' (duration: 24.495544ms)","trace[512884378] 'applied index is now lower than readState.Index' (duration: 197.094043ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:30:22.395791Z","caller":"traceutil/trace.go:171","msg":"trace[1721134797] transaction","detail":"{read_only:false; response_revision:3901; number_of_response:1; }","duration":"223.406077ms","start":"2026-07-21T05:30:22.17237Z","end":"2026-07-21T05:30:22.395776Z","steps":["trace[1721134797] 'process raft request' (duration: 152.711474ms)","trace[1721134797] 'compare' (duration: 70.400704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:30:22.396138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.117995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:30:22.396248Z","caller":"traceutil/trace.go:171","msg":"trace[1343754798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3901; }","duration":"222.25317ms","start":"2026-07-21T05:30:22.173971Z","end":"2026-07-21T05:30:22.396224Z","steps":["trace[1343754798] 'agreement among raft nodes before linearized reading' (duration: 222.088044ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:30:22.399762Z","caller":"traceutil/trace.go:171","msg":"trace[1546881542] transaction","detail":"{read_only:false; response_revision:3902; number_of_response:1; }","duration":"166.46072ms","start":"2026-07-21T05:30:22.233275Z","end":"2026-07-21T05:30:22.399736Z","steps":["trace[1546881542] 'process raft request' (duration: 166.107848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:30:22.413145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.255498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:30:22.413226Z","caller":"traceutil/trace.go:171","msg":"trace[1243756343] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3904; }","duration":"205.393193ms","start":"2026-07-21T05:30:22.207814Z","end":"2026-07-21T05:30:22.413207Z","steps":["trace[1243756343] 'agreement among raft nodes before linearized reading' (duration: 205.264718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:30:44.725588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.041486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:8 size:11767"} {"level":"info","ts":"2026-07-21T05:30:44.725712Z","caller":"traceutil/trace.go:171","msg":"trace[1776391613] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:8; response_revision:4055; }","duration":"126.232532ms","start":"2026-07-21T05:30:44.599461Z","end":"2026-07-21T05:30:44.725694Z","steps":["trace[1776391613] 'range keys from in-memory index tree' (duration: 125.796217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:11.865864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.344272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:31:11.866445Z","caller":"traceutil/trace.go:171","msg":"trace[2005013979] linearizableReadLoop","detail":"{readStateIndex:4769; appliedIndex:4767; }","duration":"114.227459ms","start":"2026-07-21T05:31:11.752199Z","end":"2026-07-21T05:31:11.866427Z","steps":["trace[2005013979] 'read index received' (duration: 113.602149ms)","trace[2005013979] 'applied index is now lower than readState.Index' (duration: 624.31µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:11.867223Z","caller":"traceutil/trace.go:171","msg":"trace[1530507821] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"308.355337ms","start":"2026-07-21T05:31:11.558803Z","end":"2026-07-21T05:31:11.867158Z","steps":["trace[1530507821] 'process raft request' (duration: 59.574969ms)","trace[1530507821] 'compare' (duration: 247.068302ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:11.86728Z","caller":"traceutil/trace.go:171","msg":"trace[733036259] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"143.984917ms","start":"2026-07-21T05:31:11.723256Z","end":"2026-07-21T05:31:11.867241Z","steps":["trace[733036259] 'process raft request' (duration: 143.079747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:11.867377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.558785Z","time spent":"308.521614ms","remote":"127.0.0.1:45964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":635,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:11.867575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.367177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954238"} {"level":"info","ts":"2026-07-21T05:31:11.867629Z","caller":"traceutil/trace.go:171","msg":"trace[783885693] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4582; }","duration":"115.455009ms","start":"2026-07-21T05:31:11.752161Z","end":"2026-07-21T05:31:11.867616Z","steps":["trace[783885693] 'agreement among raft nodes before linearized reading' (duration: 114.324783ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.046134Z","caller":"traceutil/trace.go:171","msg":"trace[1538778271] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"151.988318ms","start":"2026-07-21T05:31:11.894109Z","end":"2026-07-21T05:31:12.046097Z","steps":["trace[1538778271] 'process raft request' (duration: 150.046665ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.48635Z","caller":"traceutil/trace.go:171","msg":"trace[1842189838] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"131.757669ms","start":"2026-07-21T05:31:12.354568Z","end":"2026-07-21T05:31:12.486325Z","steps":["trace[1842189838] 'process raft request' (duration: 131.671436ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.486723Z","caller":"traceutil/trace.go:171","msg":"trace[388236508] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"135.45588ms","start":"2026-07-21T05:31:12.351248Z","end":"2026-07-21T05:31:12.486704Z","steps":["trace[388236508] 'process raft request' (duration: 99.402176ms)","trace[388236508] 'compare' (duration: 35.422353ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:12.486864Z","caller":"traceutil/trace.go:171","msg":"trace[1175958146] linearizableReadLoop","detail":"{readStateIndex:4811; appliedIndex:4808; }","duration":"121.33391ms","start":"2026-07-21T05:31:12.365492Z","end":"2026-07-21T05:31:12.486826Z","steps":["trace[1175958146] 'read index received' (duration: 85.211393ms)","trace[1175958146] 'applied index is now lower than readState.Index' (duration: 36.121997ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:12.486971Z","caller":"traceutil/trace.go:171","msg":"trace[1341013480] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"128.833694ms","start":"2026-07-21T05:31:12.358129Z","end":"2026-07-21T05:31:12.486963Z","steps":["trace[1341013480] 'process raft request' (duration: 128.580876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.487158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.64797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-21T05:31:12.487199Z","caller":"traceutil/trace.go:171","msg":"trace[894119548] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"113.029309ms","start":"2026-07-21T05:31:12.374153Z","end":"2026-07-21T05:31:12.487182Z","steps":["trace[894119548] 'process raft request' (duration: 112.634446ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.487222Z","caller":"traceutil/trace.go:171","msg":"trace[1180761211] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4625; }","duration":"121.757283ms","start":"2026-07-21T05:31:12.36545Z","end":"2026-07-21T05:31:12.487208Z","steps":["trace[1180761211] 'agreement among raft nodes before linearized reading' (duration: 121.589508ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.665506Z","caller":"traceutil/trace.go:171","msg":"trace[715680168] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"124.628058ms","start":"2026-07-21T05:31:12.540851Z","end":"2026-07-21T05:31:12.665479Z","steps":["trace[715680168] 'process raft request' (duration: 124.449772ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.665956Z","caller":"traceutil/trace.go:171","msg":"trace[528898416] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"132.112712ms","start":"2026-07-21T05:31:12.533818Z","end":"2026-07-21T05:31:12.66593Z","steps":["trace[528898416] 'process raft request' (duration: 112.504023ms)","trace[528898416] 'compare' (duration: 18.74137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:12.665964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.637991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-21T05:31:12.666021Z","caller":"traceutil/trace.go:171","msg":"trace[1670573089] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4633; }","duration":"129.712733ms","start":"2026-07-21T05:31:12.536294Z","end":"2026-07-21T05:31:12.666007Z","steps":["trace[1670573089] 'agreement among raft nodes before linearized reading' (duration: 129.573438ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:14.449197Z","caller":"traceutil/trace.go:171","msg":"trace[1257038469] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"112.610677ms","start":"2026-07-21T05:31:14.336555Z","end":"2026-07-21T05:31:14.449166Z","steps":["trace[1257038469] 'process raft request' (duration: 66.066721ms)","trace[1257038469] 'compare' (duration: 46.330859ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:17.776097Z","caller":"traceutil/trace.go:171","msg":"trace[1272183585] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4938; }","duration":"135.953178ms","start":"2026-07-21T05:31:17.640118Z","end":"2026-07-21T05:31:17.776072Z","steps":["trace[1272183585] 'read index received' (duration: 135.72646ms)","trace[1272183585] 'applied index is now lower than readState.Index' (duration: 225.778µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:17.776305Z","caller":"traceutil/trace.go:171","msg":"trace[1921291158] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"170.298927ms","start":"2026-07-21T05:31:17.605983Z","end":"2026-07-21T05:31:17.776282Z","steps":["trace[1921291158] 'process raft request' (duration: 169.938194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:17.784947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.770185ms","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-07-21T05:31:17.785062Z","caller":"traceutil/trace.go:171","msg":"trace[1689787020] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4747; }","duration":"144.951741ms","start":"2026-07-21T05:31:17.640086Z","end":"2026-07-21T05:31:17.785038Z","steps":["trace[1689787020] 'agreement among raft nodes before linearized reading' (duration: 136.398372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:17.78508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.967807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-07-21T05:31:17.785166Z","caller":"traceutil/trace.go:171","msg":"trace[1350582776] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4747; }","duration":"116.098161ms","start":"2026-07-21T05:31:17.669046Z","end":"2026-07-21T05:31:17.785144Z","steps":["trace[1350582776] 'agreement among raft nodes before linearized reading' (duration: 107.419898ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:25.609733Z","caller":"traceutil/trace.go:171","msg":"trace[884458982] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"214.464428ms","start":"2026-07-21T05:31:25.395242Z","end":"2026-07-21T05:31:25.609707Z","steps":["trace[884458982] 'process raft request' (duration: 214.273112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:28.588807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.220954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-21T05:31:28.588901Z","caller":"traceutil/trace.go:171","msg":"trace[1286549609] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4909; }","duration":"184.364108ms","start":"2026-07-21T05:31:28.404513Z","end":"2026-07-21T05:31:28.588877Z","steps":["trace[1286549609] 'range keys from in-memory index tree' (duration: 184.036328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:28.589253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.067988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-07-21T05:31:28.589316Z","caller":"traceutil/trace.go:171","msg":"trace[140347383] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4909; }","duration":"219.14677ms","start":"2026-07-21T05:31:28.370156Z","end":"2026-07-21T05:31:28.589302Z","steps":["trace[140347383] 'range keys from in-memory index tree' (duration: 218.640555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:28.589301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.746329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-54b57ccd47-pwtgd.18c4370e67f90947\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-21T05:31:28.589372Z","caller":"traceutil/trace.go:171","msg":"trace[1740772029] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-54b57ccd47-pwtgd.18c4370e67f90947; range_end:; response_count:1; response_revision:4909; }","duration":"218.838721ms","start":"2026-07-21T05:31:28.370519Z","end":"2026-07-21T05:31:28.589358Z","steps":["trace[1740772029] 'range keys from in-memory index tree' (duration: 218.627456ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.729853Z","caller":"traceutil/trace.go:171","msg":"trace[368282775] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"319.957206ms","start":"2026-07-21T05:31:28.409876Z","end":"2026-07-21T05:31:28.729834Z","steps":["trace[368282775] 'process raft request' (duration: 319.827192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:28.730049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:28.409861Z","time spent":"320.074649ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:28.759236Z","caller":"traceutil/trace.go:171","msg":"trace[63408198] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5105; }","duration":"166.620699ms","start":"2026-07-21T05:31:28.592595Z","end":"2026-07-21T05:31:28.759216Z","steps":["trace[63408198] 'read index received' (duration: 137.843451ms)","trace[63408198] 'applied index is now lower than readState.Index' (duration: 28.776038ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:28.759502Z","caller":"traceutil/trace.go:171","msg":"trace[2084763693] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"159.685193ms","start":"2026-07-21T05:31:28.599791Z","end":"2026-07-21T05:31:28.759476Z","steps":["trace[2084763693] 'process raft request' (duration: 159.28737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:28.759634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.027792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-74266dfc-9c75-4c09-9e84-3ceb455fd8ab\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-21T05:31:28.759662Z","caller":"traceutil/trace.go:171","msg":"trace[1563186649] range","detail":"{range_begin:/registry/persistentvolumes/pvc-74266dfc-9c75-4c09-9e84-3ceb455fd8ab; range_end:; response_count:1; response_revision:4913; }","duration":"167.082564ms","start":"2026-07-21T05:31:28.59257Z","end":"2026-07-21T05:31:28.759652Z","steps":["trace[1563186649] 'agreement among raft nodes before linearized reading' (duration: 166.970251ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.759727Z","caller":"traceutil/trace.go:171","msg":"trace[186710929] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"168.683277ms","start":"2026-07-21T05:31:28.591002Z","end":"2026-07-21T05:31:28.759686Z","steps":["trace[186710929] 'process raft request' (duration: 167.965763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.915784Z","caller":"traceutil/trace.go:171","msg":"trace[1956967174] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"149.471961ms","start":"2026-07-21T05:31:28.766293Z","end":"2026-07-21T05:31:28.915765Z","steps":["trace[1956967174] 'process raft request' (duration: 149.410968ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.915993Z","caller":"traceutil/trace.go:171","msg":"trace[183251192] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"149.833182ms","start":"2026-07-21T05:31:28.766077Z","end":"2026-07-21T05:31:28.91591Z","steps":["trace[183251192] 'process raft request' (duration: 149.524712ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.91571Z","caller":"traceutil/trace.go:171","msg":"trace[126907514] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"149.824332ms","start":"2026-07-21T05:31:28.765852Z","end":"2026-07-21T05:31:28.915676Z","steps":["trace[126907514] 'process raft request' (duration: 148.294802ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.92401Z","caller":"traceutil/trace.go:171","msg":"trace[1874299170] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"126.697338ms","start":"2026-07-21T05:31:28.797292Z","end":"2026-07-21T05:31:28.923989Z","steps":["trace[1874299170] 'process raft request' (duration: 126.668908ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.924352Z","caller":"traceutil/trace.go:171","msg":"trace[2133075921] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"127.438353ms","start":"2026-07-21T05:31:28.796901Z","end":"2026-07-21T05:31:28.924339Z","steps":["trace[2133075921] 'process raft request' (duration: 127.02056ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:28.92452Z","caller":"traceutil/trace.go:171","msg":"trace[108311060] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"150.199594ms","start":"2026-07-21T05:31:28.7743Z","end":"2026-07-21T05:31:28.9245Z","steps":["trace[108311060] 'process raft request' (duration: 149.532283ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:36.957285Z","caller":"traceutil/trace.go:171","msg":"trace[1481252403] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"165.773455ms","start":"2026-07-21T05:31:36.791475Z","end":"2026-07-21T05:31:36.957249Z","steps":["trace[1481252403] 'read index received' (duration: 165.441484ms)","trace[1481252403] 'applied index is now lower than readState.Index' (duration: 330.431µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:36.957491Z","caller":"traceutil/trace.go:171","msg":"trace[72922] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"170.653814ms","start":"2026-07-21T05:31:36.786783Z","end":"2026-07-21T05:31:36.957437Z","steps":["trace[72922] 'process raft request' (duration: 170.267412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:36.957546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.043254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-54b57ccd47-pwtgd\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-07-21T05:31:36.957721Z","caller":"traceutil/trace.go:171","msg":"trace[1806304796] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-54b57ccd47-pwtgd; range_end:; response_count:1; response_revision:4975; }","duration":"166.309893ms","start":"2026-07-21T05:31:36.791379Z","end":"2026-07-21T05:31:36.957689Z","steps":["trace[1806304796] 'agreement among raft nodes before linearized reading' (duration: 166.005653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:37.39441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.464874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:31:37.395471Z","caller":"traceutil/trace.go:171","msg":"trace[771875374] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"418.452693ms","start":"2026-07-21T05:31:36.977003Z","end":"2026-07-21T05:31:37.395455Z","steps":["trace[771875374] 'process raft request' (duration: 418.324788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:37.395581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:36.976982Z","time spent":"418.538745ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:37.395572Z","caller":"traceutil/trace.go:171","msg":"trace[1181927674] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"427.027752ms","start":"2026-07-21T05:31:36.968457Z","end":"2026-07-21T05:31:37.395485Z","steps":["trace[1181927674] 'process raft request' (duration: 64.408509ms)","trace[1181927674] 'compare' (duration: 361.165715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:37.395785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.65138ms","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-07-21T05:31:37.395857Z","caller":"traceutil/trace.go:171","msg":"trace[319850641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4978; }","duration":"265.777786ms","start":"2026-07-21T05:31:37.130065Z","end":"2026-07-21T05:31:37.395843Z","steps":["trace[319850641] 'agreement among raft nodes before linearized reading' (duration: 265.504988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:37.395874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:36.968439Z","time spent":"427.28933ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:37.395417Z","caller":"traceutil/trace.go:171","msg":"trace[1143370872] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"265.268799ms","start":"2026-07-21T05:31:37.130132Z","end":"2026-07-21T05:31:37.395401Z","steps":["trace[1143370872] 'read index received' (duration: 220.318254ms)","trace[1143370872] 'applied index is now lower than readState.Index' (duration: 44.949695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:37.396215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.332129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:31:37.396278Z","caller":"traceutil/trace.go:171","msg":"trace[1045696329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4978; }","duration":"172.435942ms","start":"2026-07-21T05:31:37.223833Z","end":"2026-07-21T05:31:37.396269Z","steps":["trace[1045696329] 'agreement among raft nodes before linearized reading' (duration: 172.320998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:37.396275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.937816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T05:31:37.396198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.923164ms","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-07-21T05:31:37.396407Z","caller":"traceutil/trace.go:171","msg":"trace[444365260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4978; }","duration":"260.174743ms","start":"2026-07-21T05:31:37.136225Z","end":"2026-07-21T05:31:37.3964Z","steps":["trace[444365260] 'agreement among raft nodes before linearized reading' (duration: 259.811401ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:37.396353Z","caller":"traceutil/trace.go:171","msg":"trace[993930486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4978; }","duration":"222.031139ms","start":"2026-07-21T05:31:37.174301Z","end":"2026-07-21T05:31:37.396332Z","steps":["trace[993930486] 'agreement among raft nodes before linearized reading' (duration: 221.948936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.142195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.139456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-21T05:32:04.142284Z","caller":"traceutil/trace.go:171","msg":"trace[709897213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5301; }","duration":"336.274691ms","start":"2026-07-21T05:32:03.805993Z","end":"2026-07-21T05:32:04.142268Z","steps":["trace[709897213] 'range keys from in-memory index tree' (duration: 335.96861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.142314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:03.805977Z","time spent":"336.330132ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T05:32:04.142712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.866783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:32:04.1428Z","caller":"traceutil/trace.go:171","msg":"trace[1931514434] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"336.23826ms","start":"2026-07-21T05:32:03.806547Z","end":"2026-07-21T05:32:04.142785Z","steps":["trace[1931514434] 'process raft request' (duration: 137.240522ms)","trace[1931514434] 'compare' (duration: 198.359787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:32:04.14278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.54308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:62012"} {"level":"warn","ts":"2026-07-21T05:32:04.142845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:03.806536Z","time spent":"336.294251ms","remote":"127.0.0.1:57004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2317,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:32:04.142855Z","caller":"traceutil/trace.go:171","msg":"trace[1063579232] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5301; }","duration":"336.667783ms","start":"2026-07-21T05:32:03.80617Z","end":"2026-07-21T05:32:04.142838Z","steps":["trace[1063579232] 'range keys from in-memory index tree' (duration: 336.288131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.142895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:03.806145Z","time spent":"336.741425ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":62035,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-07-21T05:32:04.621432Z","caller":"traceutil/trace.go:171","msg":"trace[676212438] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"468.989195ms","start":"2026-07-21T05:32:04.152417Z","end":"2026-07-21T05:32:04.621407Z","steps":["trace[676212438] 'process raft request' (duration: 468.745356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.62158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:04.152398Z","time spent":"469.104598ms","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:32:04.62955Z","caller":"traceutil/trace.go:171","msg":"trace[1191084657] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5509; }","duration":"451.927837ms","start":"2026-07-21T05:32:04.177602Z","end":"2026-07-21T05:32:04.62953Z","steps":["trace[1191084657] 'read index received' (duration: 443.640476ms)","trace[1191084657] 'applied index is now lower than readState.Index' (duration: 8.286401ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:32:04.629661Z","caller":"traceutil/trace.go:171","msg":"trace[264707354] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"114.297622ms","start":"2026-07-21T05:32:04.515347Z","end":"2026-07-21T05:32:04.629645Z","steps":["trace[264707354] 'process raft request' (duration: 114.122247ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:04.629698Z","caller":"traceutil/trace.go:171","msg":"trace[1220651857] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"476.413157ms","start":"2026-07-21T05:32:04.153273Z","end":"2026-07-21T05:32:04.629686Z","steps":["trace[1220651857] 'process raft request' (duration: 476.070655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.629753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:04.153252Z","time spent":"476.476678ms","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2879,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:32:04.629824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.619725ms","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-07-21T05:32:04.629915Z","caller":"traceutil/trace.go:171","msg":"trace[1735864586] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5305; }","duration":"431.734547ms","start":"2026-07-21T05:32:04.198145Z","end":"2026-07-21T05:32:04.62988Z","steps":["trace[1735864586] 'agreement among raft nodes before linearized reading' (duration: 431.583462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.629961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:04.198128Z","time spent":"431.823232ms","remote":"127.0.0.1:46380","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-07-21T05:32:04.630043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.426903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:32:04.630082Z","caller":"traceutil/trace.go:171","msg":"trace[782116497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5305; }","duration":"452.514356ms","start":"2026-07-21T05:32:04.177557Z","end":"2026-07-21T05:32:04.630072Z","steps":["trace[782116497] 'agreement among raft nodes before linearized reading' (duration: 452.399203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:04.63011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:04.177537Z","time spent":"452.567378ms","remote":"127.0.0.1:45876","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-07-21T05:32:17.494706Z","caller":"traceutil/trace.go:171","msg":"trace[342755428] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"100.060929ms","start":"2026-07-21T05:32:17.394625Z","end":"2026-07-21T05:32:17.494686Z","steps":["trace[342755428] 'process raft request' (duration: 99.943395ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:21.822182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2819} {"level":"info","ts":"2026-07-21T05:32:21.911274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2819,"took":"87.916484ms","hash":463205272,"current-db-size-bytes":52576256,"current-db-size":"53 MB","current-db-size-in-use-bytes":28643328,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-21T05:32:21.911374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463205272,"revision":2819,"compact-revision":-1} {"level":"info","ts":"2026-07-21T05:32:59.196647Z","caller":"traceutil/trace.go:171","msg":"trace[1031067069] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"126.915524ms","start":"2026-07-21T05:32:59.069707Z","end":"2026-07-21T05:32:59.196622Z","steps":["trace[1031067069] 'process raft request' (duration: 126.719368ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:17.459028Z","caller":"traceutil/trace.go:171","msg":"trace[1112316990] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"121.109238ms","start":"2026-07-21T05:33:17.337899Z","end":"2026-07-21T05:33:17.459008Z","steps":["trace[1112316990] 'process raft request' (duration: 120.957873ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:19.631778Z","caller":"traceutil/trace.go:171","msg":"trace[1464369665] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"167.339854ms","start":"2026-07-21T05:33:19.464273Z","end":"2026-07-21T05:33:19.631613Z","steps":["trace[1464369665] 'process raft request' (duration: 167.166008ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:19.679854Z","caller":"traceutil/trace.go:171","msg":"trace[1284329970] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"121.75086ms","start":"2026-07-21T05:33:19.558083Z","end":"2026-07-21T05:33:19.679834Z","steps":["trace[1284329970] 'process raft request' (duration: 121.686897ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:19.680023Z","caller":"traceutil/trace.go:171","msg":"trace[1331294374] transaction","detail":"{read_only:false; response_revision:5840; number_of_response:1; }","duration":"141.521017ms","start":"2026-07-21T05:33:19.538476Z","end":"2026-07-21T05:33:19.679997Z","steps":["trace[1331294374] 'process raft request' (duration: 138.978495ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:01.233878Z","caller":"traceutil/trace.go:171","msg":"trace[420879773] transaction","detail":"{read_only:false; response_revision:6508; number_of_response:1; }","duration":"138.361805ms","start":"2026-07-21T05:35:01.095491Z","end":"2026-07-21T05:35:01.233852Z","steps":["trace[420879773] 'process raft request' (duration: 138.201361ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:10.297014Z","caller":"traceutil/trace.go:171","msg":"trace[398948597] transaction","detail":"{read_only:false; response_revision:6596; number_of_response:1; }","duration":"109.071086ms","start":"2026-07-21T05:35:10.187923Z","end":"2026-07-21T05:35:10.296994Z","steps":["trace[398948597] 'process raft request' (duration: 109.028314ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:10.29704Z","caller":"traceutil/trace.go:171","msg":"trace[338964782] transaction","detail":"{read_only:false; response_revision:6595; number_of_response:1; }","duration":"112.192496ms","start":"2026-07-21T05:35:10.184814Z","end":"2026-07-21T05:35:10.297006Z","steps":["trace[338964782] 'process raft request' (duration: 112.003972ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:12.384034Z","caller":"traceutil/trace.go:171","msg":"trace[1506267457] transaction","detail":"{read_only:false; response_revision:6607; number_of_response:1; }","duration":"115.883413ms","start":"2026-07-21T05:35:12.268127Z","end":"2026-07-21T05:35:12.384011Z","steps":["trace[1506267457] 'process raft request' (duration: 115.761421ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:12.609515Z","caller":"traceutil/trace.go:171","msg":"trace[1334764036] transaction","detail":"{read_only:false; response_revision:6608; number_of_response:1; }","duration":"306.637573ms","start":"2026-07-21T05:35:12.302834Z","end":"2026-07-21T05:35:12.609472Z","steps":["trace[1334764036] 'process raft request' (duration: 306.373477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:12.609688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:12.302782Z","time spent":"306.814998ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:12.609497Z","caller":"traceutil/trace.go:171","msg":"trace[1266474192] transaction","detail":"{read_only:false; response_revision:6610; number_of_response:1; }","duration":"292.915172ms","start":"2026-07-21T05:35:12.316558Z","end":"2026-07-21T05:35:12.609474Z","steps":["trace[1266474192] 'process raft request' (duration: 292.841261ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:12.609687Z","caller":"traceutil/trace.go:171","msg":"trace[359475222] transaction","detail":"{read_only:false; response_revision:6609; number_of_response:1; }","duration":"305.812615ms","start":"2026-07-21T05:35:12.30373Z","end":"2026-07-21T05:35:12.609543Z","steps":["trace[359475222] 'process raft request' (duration: 305.62426ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:12.609959Z","caller":"traceutil/trace.go:171","msg":"trace[1470265262] transaction","detail":"{read_only:false; response_revision:6611; number_of_response:1; }","duration":"228.072764ms","start":"2026-07-21T05:35:12.381784Z","end":"2026-07-21T05:35:12.609857Z","steps":["trace[1470265262] 'process raft request' (duration: 227.649524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:12.61007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:12.30365Z","time spent":"306.201913ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:12.624486Z","caller":"traceutil/trace.go:171","msg":"trace[1513028808] linearizableReadLoop","detail":"{readStateIndex:6864; appliedIndex:6859; }","duration":"184.441196ms","start":"2026-07-21T05:35:12.440008Z","end":"2026-07-21T05:35:12.624449Z","steps":["trace[1513028808] 'read index received' (duration: 169.258402ms)","trace[1513028808] 'applied index is now lower than readState.Index' (duration: 15.181484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:12.624653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.622671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:12.624711Z","caller":"traceutil/trace.go:171","msg":"trace[1398101198] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:6612; }","duration":"184.704813ms","start":"2026-07-21T05:35:12.43999Z","end":"2026-07-21T05:35:12.624695Z","steps":["trace[1398101198] 'agreement among raft nodes before linearized reading' (duration: 184.58315ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:12.625025Z","caller":"traceutil/trace.go:171","msg":"trace[1043637195] transaction","detail":"{read_only:false; response_revision:6612; number_of_response:1; }","duration":"227.834619ms","start":"2026-07-21T05:35:12.397152Z","end":"2026-07-21T05:35:12.624987Z","steps":["trace[1043637195] 'process raft request' (duration: 227.032331ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:13.010142Z","caller":"traceutil/trace.go:171","msg":"trace[676566454] linearizableReadLoop","detail":"{readStateIndex:6865; appliedIndex:6864; }","duration":"306.429339ms","start":"2026-07-21T05:35:12.703686Z","end":"2026-07-21T05:35:13.010115Z","steps":["trace[676566454] 'read index received' (duration: 306.142162ms)","trace[676566454] 'applied index is now lower than readState.Index' (duration: 286.087µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:13.010372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.665224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-21T05:35:13.010434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.092461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-21T05:35:13.010456Z","caller":"traceutil/trace.go:171","msg":"trace[504134498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6613; }","duration":"306.787047ms","start":"2026-07-21T05:35:12.703649Z","end":"2026-07-21T05:35:13.010436Z","steps":["trace[504134498] 'agreement among raft nodes before linearized reading' (duration: 306.560291ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:13.010475Z","caller":"traceutil/trace.go:171","msg":"trace[91814666] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6613; }","duration":"227.163875ms","start":"2026-07-21T05:35:12.7833Z","end":"2026-07-21T05:35:13.010464Z","steps":["trace[91814666] 'agreement among raft nodes before linearized reading' (duration: 227.016281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:13.010511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:12.703635Z","time spent":"306.862018ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T05:35:13.010721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.739404ms","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-07-21T05:35:13.01085Z","caller":"traceutil/trace.go:171","msg":"trace[1156227197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6613; }","duration":"116.998939ms","start":"2026-07-21T05:35:12.893832Z","end":"2026-07-21T05:35:13.010831Z","steps":["trace[1156227197] 'agreement among raft nodes before linearized reading' (duration: 116.57607ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:13.010793Z","caller":"traceutil/trace.go:171","msg":"trace[656866005] transaction","detail":"{read_only:false; response_revision:6613; number_of_response:1; }","duration":"318.136654ms","start":"2026-07-21T05:35:12.692027Z","end":"2026-07-21T05:35:13.010164Z","steps":["trace[656866005] 'process raft request' (duration: 317.862797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:13.011122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:12.692003Z","time spent":"319.043514ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:35:13.203103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.742993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-21T05:35:13.203165Z","caller":"traceutil/trace.go:171","msg":"trace[450906009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6616; }","duration":"100.852165ms","start":"2026-07-21T05:35:13.1023Z","end":"2026-07-21T05:35:13.203152Z","steps":["trace[450906009] 'range keys from in-memory index tree' (duration: 100.59484ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:14.082145Z","caller":"traceutil/trace.go:171","msg":"trace[1278082984] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"111.316034ms","start":"2026-07-21T05:35:13.970799Z","end":"2026-07-21T05:35:14.082115Z","steps":["trace[1278082984] 'process raft request' (duration: 70.225101ms)","trace[1278082984] 'compare' (duration: 40.593601ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:14.729703Z","caller":"traceutil/trace.go:171","msg":"trace[6059410] transaction","detail":"{read_only:false; response_revision:6622; number_of_response:1; }","duration":"114.733623ms","start":"2026-07-21T05:35:14.61494Z","end":"2026-07-21T05:35:14.729674Z","steps":["trace[6059410] 'process raft request' (duration: 114.556999ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:14.769372Z","caller":"traceutil/trace.go:171","msg":"trace[1950822878] transaction","detail":"{read_only:false; response_revision:6623; number_of_response:1; }","duration":"153.88737ms","start":"2026-07-21T05:35:14.61546Z","end":"2026-07-21T05:35:14.769347Z","steps":["trace[1950822878] 'process raft request' (duration: 153.675875ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:14.769685Z","caller":"traceutil/trace.go:171","msg":"trace[658914138] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"153.8987ms","start":"2026-07-21T05:35:14.615747Z","end":"2026-07-21T05:35:14.769646Z","steps":["trace[658914138] 'process raft request' (duration: 153.546623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:15.217746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.775277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:15.21785Z","caller":"traceutil/trace.go:171","msg":"trace[131053538] linearizableReadLoop","detail":"{readStateIndex:6879; appliedIndex:6878; }","duration":"117.376843ms","start":"2026-07-21T05:35:15.100457Z","end":"2026-07-21T05:35:15.217834Z","steps":["trace[131053538] 'read index received' (duration: 9.386603ms)","trace[131053538] 'applied index is now lower than readState.Index' (duration: 107.98906ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:15.217945Z","caller":"traceutil/trace.go:171","msg":"trace[678267520] transaction","detail":"{read_only:false; response_revision:6626; number_of_response:1; }","duration":"155.350875ms","start":"2026-07-21T05:35:15.06258Z","end":"2026-07-21T05:35:15.217931Z","steps":["trace[678267520] 'process raft request' (duration: 47.311823ms)","trace[678267520] 'compare' (duration: 107.629794ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:15.218421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.944718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T05:35:15.218478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.716359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T05:35:15.218517Z","caller":"traceutil/trace.go:171","msg":"trace[691405593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6626; }","duration":"116.772651ms","start":"2026-07-21T05:35:15.101733Z","end":"2026-07-21T05:35:15.218506Z","steps":["trace[691405593] 'agreement among raft nodes before linearized reading' (duration: 116.618557ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:15.218521Z","caller":"traceutil/trace.go:171","msg":"trace[1710161011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6626; }","duration":"118.093261ms","start":"2026-07-21T05:35:15.100403Z","end":"2026-07-21T05:35:15.218496Z","steps":["trace[1710161011] 'agreement among raft nodes before linearized reading' (duration: 117.847805ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:16.913964Z","caller":"traceutil/trace.go:171","msg":"trace[1540247431] transaction","detail":"{read_only:false; response_revision:6633; number_of_response:1; }","duration":"140.534392ms","start":"2026-07-21T05:35:16.773402Z","end":"2026-07-21T05:35:16.913937Z","steps":["trace[1540247431] 'process raft request' (duration: 139.100579ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:16.915264Z","caller":"traceutil/trace.go:171","msg":"trace[1539020328] linearizableReadLoop","detail":"{readStateIndex:6888; appliedIndex:6886; }","duration":"126.487364ms","start":"2026-07-21T05:35:16.788755Z","end":"2026-07-21T05:35:16.915242Z","steps":["trace[1539020328] 'read index received' (duration: 123.773113ms)","trace[1539020328] 'applied index is now lower than readState.Index' (duration: 2.713191ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:16.915396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.638477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:16.915438Z","caller":"traceutil/trace.go:171","msg":"trace[1413284628] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:6634; }","duration":"126.74809ms","start":"2026-07-21T05:35:16.788679Z","end":"2026-07-21T05:35:16.915427Z","steps":["trace[1413284628] 'agreement among raft nodes before linearized reading' (duration: 126.657187ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:16.915561Z","caller":"traceutil/trace.go:171","msg":"trace[1211452277] transaction","detail":"{read_only:false; response_revision:6634; number_of_response:1; }","duration":"140.763198ms","start":"2026-07-21T05:35:16.774549Z","end":"2026-07-21T05:35:16.915312Z","steps":["trace[1211452277] 'process raft request' (duration: 140.531393ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:17.399463Z","caller":"traceutil/trace.go:171","msg":"trace[738638443] transaction","detail":"{read_only:false; response_revision:6636; number_of_response:1; }","duration":"103.364189ms","start":"2026-07-21T05:35:17.296072Z","end":"2026-07-21T05:35:17.399436Z","steps":["trace[738638443] 'process raft request' (duration: 102.842667ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:17.454714Z","caller":"traceutil/trace.go:171","msg":"trace[1077987859] transaction","detail":"{read_only:false; response_revision:6637; number_of_response:1; }","duration":"158.546565ms","start":"2026-07-21T05:35:17.296143Z","end":"2026-07-21T05:35:17.45469Z","steps":["trace[1077987859] 'process raft request' (duration: 158.36676ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:17.455093Z","caller":"traceutil/trace.go:171","msg":"trace[1553369832] linearizableReadLoop","detail":"{readStateIndex:6892; appliedIndex:6889; }","duration":"145.046418ms","start":"2026-07-21T05:35:17.310014Z","end":"2026-07-21T05:35:17.45506Z","steps":["trace[1553369832] 'read index received' (duration: 89.044804ms)","trace[1553369832] 'applied index is now lower than readState.Index' (duration: 55.894021ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:17.455139Z","caller":"traceutil/trace.go:171","msg":"trace[1093651222] transaction","detail":"{read_only:false; response_revision:6638; number_of_response:1; }","duration":"158.709548ms","start":"2026-07-21T05:35:17.296397Z","end":"2026-07-21T05:35:17.455106Z","steps":["trace[1093651222] 'process raft request' (duration: 158.251887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:17.455355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.318494ms","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-07-21T05:35:17.45542Z","caller":"traceutil/trace.go:171","msg":"trace[499578516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6638; }","duration":"145.426355ms","start":"2026-07-21T05:35:17.309976Z","end":"2026-07-21T05:35:17.455402Z","steps":["trace[499578516] 'agreement among raft nodes before linearized reading' (duration: 145.21034ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:17.660593Z","caller":"traceutil/trace.go:171","msg":"trace[1511167902] transaction","detail":"{read_only:false; response_revision:6639; number_of_response:1; }","duration":"201.064681ms","start":"2026-07-21T05:35:17.459504Z","end":"2026-07-21T05:35:17.660569Z","steps":["trace[1511167902] 'process raft request' (duration: 200.887137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.328798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":138942549044974045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T05:35:19.56458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.930479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:19.564664Z","caller":"traceutil/trace.go:171","msg":"trace[1297516678] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:6640; }","duration":"869.058913ms","start":"2026-07-21T05:35:18.695576Z","end":"2026-07-21T05:35:19.564635Z","steps":["trace[1297516678] 'count revisions from in-memory index tree' (duration: 868.779175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.564704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.695528Z","time spent":"869.165904ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":40,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:35:19.564754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.086543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:19.564822Z","caller":"traceutil/trace.go:171","msg":"trace[139696134] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:6640; }","duration":"851.207385ms","start":"2026-07-21T05:35:18.713597Z","end":"2026-07-21T05:35:19.564805Z","steps":["trace[139696134] 'count revisions from in-memory index tree' (duration: 851.007582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.564864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.713574Z","time spent":"851.277068ms","remote":"127.0.0.1:32784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-07-21T05:35:19.565064Z","caller":"traceutil/trace.go:171","msg":"trace[1163609302] transaction","detail":"{read_only:false; response_revision:6641; number_of_response:1; }","duration":"801.736771ms","start":"2026-07-21T05:35:18.763299Z","end":"2026-07-21T05:35:19.565036Z","steps":["trace[1163609302] 'process raft request' (duration: 773.643441ms)","trace[1163609302] 'compare' (duration: 27.911626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:19.565195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.763271Z","time spent":"801.853972ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:19.631457Z","caller":"traceutil/trace.go:171","msg":"trace[72782260] transaction","detail":"{read_only:false; response_revision:6642; number_of_response:1; }","duration":"711.201252ms","start":"2026-07-21T05:35:18.920204Z","end":"2026-07-21T05:35:19.631405Z","steps":["trace[72782260] 'process raft request' (duration: 710.993737ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:19.631542Z","caller":"traceutil/trace.go:171","msg":"trace[133893836] transaction","detail":"{read_only:false; response_revision:6643; number_of_response:1; }","duration":"711.05173ms","start":"2026-07-21T05:35:18.920457Z","end":"2026-07-21T05:35:19.631509Z","steps":["trace[133893836] 'process raft request' (duration: 710.872905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.631641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.920177Z","time spent":"711.356776ms","remote":"127.0.0.1:46174","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-07-21T05:35:19.631694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.920447Z","time spent":"711.165472ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:19.631467Z","caller":"traceutil/trace.go:171","msg":"trace[1974331390] linearizableReadLoop","detail":"{readStateIndex:6897; appliedIndex:6894; }","duration":"803.284938ms","start":"2026-07-21T05:35:18.828086Z","end":"2026-07-21T05:35:19.631371Z","steps":["trace[1974331390] 'read index received' (duration: 708.853347ms)","trace[1974331390] 'applied index is now lower than readState.Index' (duration: 94.430731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:19.631848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.767868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:19.631878Z","caller":"traceutil/trace.go:171","msg":"trace[524182100] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:6643; }","duration":"571.83736ms","start":"2026-07-21T05:35:19.060033Z","end":"2026-07-21T05:35:19.63187Z","steps":["trace[524182100] 'agreement among raft nodes before linearized reading' (duration: 571.750718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.6319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.060014Z","time spent":"571.880321ms","remote":"127.0.0.1:46246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":42,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:35:19.631911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.332614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T05:35:19.631954Z","caller":"traceutil/trace.go:171","msg":"trace[990937048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6643; }","duration":"172.393715ms","start":"2026-07-21T05:35:19.45955Z","end":"2026-07-21T05:35:19.631944Z","steps":["trace[990937048] 'agreement among raft nodes before linearized reading' (duration: 172.260623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.631816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.702327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:19.632116Z","caller":"traceutil/trace.go:171","msg":"trace[691982520] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:6643; }","duration":"804.099646ms","start":"2026-07-21T05:35:18.828Z","end":"2026-07-21T05:35:19.6321Z","steps":["trace[691982520] 'agreement among raft nodes before linearized reading' (duration: 803.641865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.632187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.214689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T05:35:19.632216Z","caller":"traceutil/trace.go:171","msg":"trace[708923642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6643; }","duration":"229.26933ms","start":"2026-07-21T05:35:19.40294Z","end":"2026-07-21T05:35:19.632209Z","steps":["trace[708923642] 'agreement among raft nodes before linearized reading' (duration: 229.210029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.632273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:18.827979Z","time spent":"804.213428ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:35:19.632137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.821505ms","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-07-21T05:35:19.632371Z","caller":"traceutil/trace.go:171","msg":"trace[873675345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6643; }","duration":"173.086522ms","start":"2026-07-21T05:35:19.459272Z","end":"2026-07-21T05:35:19.632358Z","steps":["trace[873675345] 'agreement among raft nodes before linearized reading' (duration: 172.783195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.632304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.115997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:35:19.632442Z","caller":"traceutil/trace.go:171","msg":"trace[945608270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6643; }","duration":"529.313712ms","start":"2026-07-21T05:35:19.10312Z","end":"2026-07-21T05:35:19.632434Z","steps":["trace[945608270] 'agreement among raft nodes before linearized reading' (duration: 529.145539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.632467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.103102Z","time spent":"529.357973ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T05:35:19.917752Z","caller":"traceutil/trace.go:171","msg":"trace[679255145] transaction","detail":"{read_only:false; response_revision:6644; number_of_response:1; }","duration":"281.424468ms","start":"2026-07-21T05:35:19.636302Z","end":"2026-07-21T05:35:19.917727Z","steps":["trace[679255145] 'process raft request' (duration: 281.258614ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.006383Z","caller":"traceutil/trace.go:171","msg":"trace[1158379135] transaction","detail":"{read_only:false; response_revision:6646; number_of_response:1; }","duration":"369.349789ms","start":"2026-07-21T05:35:19.637009Z","end":"2026-07-21T05:35:20.006359Z","steps":["trace[1158379135] 'process raft request' (duration: 369.257268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.006534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.637Z","time spent":"369.458541ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:20.006629Z","caller":"traceutil/trace.go:171","msg":"trace[1327949814] transaction","detail":"{read_only:false; response_revision:6647; number_of_response:1; }","duration":"369.220537ms","start":"2026-07-21T05:35:19.637395Z","end":"2026-07-21T05:35:20.006615Z","steps":["trace[1327949814] 'process raft request' (duration: 368.9282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.006783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.637387Z","time spent":"369.298519ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:20.006934Z","caller":"traceutil/trace.go:171","msg":"trace[1708094579] linearizableReadLoop","detail":"{readStateIndex:6901; appliedIndex:6897; }","duration":"361.118883ms","start":"2026-07-21T05:35:19.645794Z","end":"2026-07-21T05:35:20.006913Z","steps":["trace[1708094579] 'read index received' (duration: 271.794459ms)","trace[1708094579] 'applied index is now lower than readState.Index' (duration: 89.323404ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:20.007004Z","caller":"traceutil/trace.go:171","msg":"trace[974083544] transaction","detail":"{read_only:false; response_revision:6648; number_of_response:1; }","duration":"359.393633ms","start":"2026-07-21T05:35:19.64756Z","end":"2026-07-21T05:35:20.006954Z","steps":["trace[974083544] 'process raft request' (duration: 358.880311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.007524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.408195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"warn","ts":"2026-07-21T05:35:20.007561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.479969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-21T05:35:20.00757Z","caller":"traceutil/trace.go:171","msg":"trace[404400258] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6648; }","duration":"342.448547ms","start":"2026-07-21T05:35:19.665109Z","end":"2026-07-21T05:35:20.007558Z","steps":["trace[404400258] 'agreement among raft nodes before linearized reading' (duration: 342.354035ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.006569Z","caller":"traceutil/trace.go:171","msg":"trace[1366084400] transaction","detail":"{read_only:false; response_revision:6645; number_of_response:1; }","duration":"370.175298ms","start":"2026-07-21T05:35:19.63636Z","end":"2026-07-21T05:35:20.006535Z","steps":["trace[1366084400] 'process raft request' (duration: 305.040715ms)","trace[1366084400] 'compare' (duration: 64.727544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:20.00761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.665074Z","time spent":"342.52861ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-07-21T05:35:20.007607Z","caller":"traceutil/trace.go:171","msg":"trace[1198133163] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6648; }","duration":"342.541039ms","start":"2026-07-21T05:35:19.665052Z","end":"2026-07-21T05:35:20.007593Z","steps":["trace[1198133163] 'agreement among raft nodes before linearized reading' (duration: 342.341995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.007142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.319877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:20.007765Z","caller":"traceutil/trace.go:171","msg":"trace[1742311372] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:6648; }","duration":"361.998513ms","start":"2026-07-21T05:35:19.645752Z","end":"2026-07-21T05:35:20.007751Z","steps":["trace[1742311372] 'agreement among raft nodes before linearized reading' (duration: 361.278597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.007776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.64754Z","time spent":"359.997976ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:35:20.007763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.665045Z","time spent":"342.705203ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-21T05:35:20.00742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.657145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-21T05:35:20.008209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.645732Z","time spent":"362.460403ms","remote":"127.0.0.1:46254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":96,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-21T05:35:20.008244Z","caller":"traceutil/trace.go:171","msg":"trace[1561821464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6648; }","duration":"344.499064ms","start":"2026-07-21T05:35:19.663716Z","end":"2026-07-21T05:35:20.008215Z","steps":["trace[1561821464] 'agreement among raft nodes before linearized reading' (duration: 343.595163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.008294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.663699Z","time spent":"344.579486ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T05:35:20.007466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.350115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-21T05:35:20.008365Z","caller":"traceutil/trace.go:171","msg":"trace[306834584] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6648; }","duration":"343.277027ms","start":"2026-07-21T05:35:19.665075Z","end":"2026-07-21T05:35:20.008352Z","steps":["trace[306834584] 'agreement among raft nodes before linearized reading' (duration: 342.266264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.008415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.66506Z","time spent":"343.341757ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4880,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-21T05:35:20.008572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.636338Z","time spent":"371.364055ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:20.335145Z","caller":"traceutil/trace.go:171","msg":"trace[379108731] linearizableReadLoop","detail":"{readStateIndex:6904; appliedIndex:6903; }","duration":"159.016053ms","start":"2026-07-21T05:35:20.176113Z","end":"2026-07-21T05:35:20.335129Z","steps":["trace[379108731] 'read index received' (duration: 158.957872ms)","trace[379108731] 'applied index is now lower than readState.Index' (duration: 57.481µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:20.335251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.122375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:20.335277Z","caller":"traceutil/trace.go:171","msg":"trace[1540604545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6650; }","duration":"159.163757ms","start":"2026-07-21T05:35:20.176106Z","end":"2026-07-21T05:35:20.33527Z","steps":["trace[1540604545] 'agreement among raft nodes before linearized reading' (duration: 159.090225ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.335399Z","caller":"traceutil/trace.go:171","msg":"trace[591031355] transaction","detail":"{read_only:false; response_revision:6650; number_of_response:1; }","duration":"219.89399ms","start":"2026-07-21T05:35:20.11548Z","end":"2026-07-21T05:35:20.335374Z","steps":["trace[591031355] 'process raft request' (duration: 219.497451ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.644768Z","caller":"traceutil/trace.go:171","msg":"trace[2112622437] transaction","detail":"{read_only:false; response_revision:6651; number_of_response:1; }","duration":"101.161695ms","start":"2026-07-21T05:35:20.543585Z","end":"2026-07-21T05:35:20.644746Z","steps":["trace[2112622437] 'process raft request' (duration: 101.012932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:21.005769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.565626ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-21T05:35:22.283546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.107835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:22.283937Z","caller":"traceutil/trace.go:171","msg":"trace[594974225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6659; }","duration":"107.32341ms","start":"2026-07-21T05:35:22.176398Z","end":"2026-07-21T05:35:22.283721Z","steps":["trace[594974225] 'range keys from in-memory index tree' (duration: 107.018852ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:24.269545Z","caller":"traceutil/trace.go:171","msg":"trace[1981737337] transaction","detail":"{read_only:false; response_revision:6666; number_of_response:1; }","duration":"123.948943ms","start":"2026-07-21T05:35:24.145576Z","end":"2026-07-21T05:35:24.269525Z","steps":["trace[1981737337] 'process raft request' (duration: 123.833001ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:24.320681Z","caller":"traceutil/trace.go:171","msg":"trace[2073070695] linearizableReadLoop","detail":"{readStateIndex:6924; appliedIndex:6920; }","duration":"144.883421ms","start":"2026-07-21T05:35:24.175781Z","end":"2026-07-21T05:35:24.320665Z","steps":["trace[2073070695] 'read index received' (duration: 93.664381ms)","trace[2073070695] 'applied index is now lower than readState.Index' (duration: 51.21836ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:24.320715Z","caller":"traceutil/trace.go:171","msg":"trace[179057016] transaction","detail":"{read_only:false; response_revision:6667; number_of_response:1; }","duration":"174.860336ms","start":"2026-07-21T05:35:24.145827Z","end":"2026-07-21T05:35:24.320688Z","steps":["trace[179057016] 'process raft request' (duration: 174.674122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:24.320794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.990934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:24.320825Z","caller":"traceutil/trace.go:171","msg":"trace[29749400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6669; }","duration":"145.080716ms","start":"2026-07-21T05:35:24.175739Z","end":"2026-07-21T05:35:24.32082Z","steps":["trace[29749400] 'agreement among raft nodes before linearized reading' (duration: 144.995244ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:24.320881Z","caller":"traceutil/trace.go:171","msg":"trace[1095039722] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"174.223432ms","start":"2026-07-21T05:35:24.146634Z","end":"2026-07-21T05:35:24.320858Z","steps":["trace[1095039722] 'process raft request' (duration: 173.981107ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:24.320962Z","caller":"traceutil/trace.go:171","msg":"trace[579709263] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"173.28714ms","start":"2026-07-21T05:35:24.147668Z","end":"2026-07-21T05:35:24.320955Z","steps":["trace[579709263] 'process raft request' (duration: 172.976123ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:27.873599Z","caller":"traceutil/trace.go:171","msg":"trace[1459773520] transaction","detail":"{read_only:false; response_revision:6684; number_of_response:1; }","duration":"113.92692ms","start":"2026-07-21T05:35:27.759638Z","end":"2026-07-21T05:35:27.873565Z","steps":["trace[1459773520] 'process raft request' (duration: 113.677384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.448942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.214971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:29.449116Z","caller":"traceutil/trace.go:171","msg":"trace[1694269824] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"952.209128ms","start":"2026-07-21T05:35:28.496886Z","end":"2026-07-21T05:35:29.449095Z","steps":["trace[1694269824] 'process raft request' (duration: 125.769572ms)","trace[1694269824] 'compare' (duration: 825.523076ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:29.449178Z","caller":"traceutil/trace.go:171","msg":"trace[318041793] transaction","detail":"{read_only:false; response_revision:6694; number_of_response:1; }","duration":"624.112337ms","start":"2026-07-21T05:35:28.825052Z","end":"2026-07-21T05:35:29.449165Z","steps":["trace[318041793] 'process raft request' (duration: 624.071756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.449234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:28.825029Z","time spent":"624.180038ms","remote":"127.0.0.1:46174","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-07-21T05:35:29.449248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:28.496861Z","time spent":"952.307362ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:29.449322Z","caller":"traceutil/trace.go:171","msg":"trace[475495389] transaction","detail":"{read_only:false; response_revision:6693; number_of_response:1; }","duration":"951.795279ms","start":"2026-07-21T05:35:28.49752Z","end":"2026-07-21T05:35:29.449316Z","steps":["trace[475495389] 'process raft request' (duration: 951.493943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.44935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:28.49751Z","time spent":"951.828421ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:35:29.449395Z","caller":"traceutil/trace.go:171","msg":"trace[1230874538] linearizableReadLoop","detail":"{readStateIndex:6948; appliedIndex:6946; }","duration":"952.019865ms","start":"2026-07-21T05:35:28.497362Z","end":"2026-07-21T05:35:29.449382Z","steps":["trace[1230874538] 'read index received' (duration: 34.091942ms)","trace[1230874538] 'applied index is now lower than readState.Index' (duration: 917.926603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:29.44956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.208489ms","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-07-21T05:35:29.449587Z","caller":"traceutil/trace.go:171","msg":"trace[293411113] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6694; }","duration":"952.265801ms","start":"2026-07-21T05:35:28.497315Z","end":"2026-07-21T05:35:29.449581Z","steps":["trace[293411113] 'agreement among raft nodes before linearized reading' (duration: 952.221339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.449589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.473051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T05:35:29.449606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:28.497302Z","time spent":"952.297852ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-21T05:35:29.449616Z","caller":"traceutil/trace.go:171","msg":"trace[1113960546] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:6694; }","duration":"421.545762ms","start":"2026-07-21T05:35:29.028062Z","end":"2026-07-21T05:35:29.449608Z","steps":["trace[1113960546] 'agreement among raft nodes before linearized reading' (duration: 421.499311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.449649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:29.02804Z","time spent":"421.602433ms","remote":"127.0.0.1:56748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T05:35:29.449818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.488071ms","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-07-21T05:35:29.449842Z","caller":"traceutil/trace.go:171","msg":"trace[1315179188] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:6694; }","duration":"129.572443ms","start":"2026-07-21T05:35:29.320263Z","end":"2026-07-21T05:35:29.449835Z","steps":["trace[1315179188] 'agreement among raft nodes before linearized reading' (duration: 129.493942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.449934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.748713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-sync-729s4\" ","response":"range_response_count:1 size:6548"} {"level":"info","ts":"2026-07-21T05:35:29.449957Z","caller":"traceutil/trace.go:171","msg":"trace[1532671078] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-sync-729s4; range_end:; response_count:1; response_revision:6694; }","duration":"386.808865ms","start":"2026-07-21T05:35:29.063142Z","end":"2026-07-21T05:35:29.449951Z","steps":["trace[1532671078] 'agreement among raft nodes before linearized reading' (duration: 386.702452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:29.449973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:29.06312Z","time spent":"386.849257ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6571,"request content":"key:\"/registry/pods/openstack/horizon-db-sync-729s4\" "} {"level":"warn","ts":"2026-07-21T05:35:29.450073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.879766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:29.450105Z","caller":"traceutil/trace.go:171","msg":"trace[1677142713] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:6694; }","duration":"182.931208ms","start":"2026-07-21T05:35:29.267157Z","end":"2026-07-21T05:35:29.450089Z","steps":["trace[1677142713] 'agreement among raft nodes before linearized reading' (duration: 182.897656ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:29.645663Z","caller":"traceutil/trace.go:171","msg":"trace[1044276121] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"121.874536ms","start":"2026-07-21T05:35:29.523756Z","end":"2026-07-21T05:35:29.64563Z","steps":["trace[1044276121] 'process raft request' (duration: 48.283367ms)","trace[1044276121] 'compare' (duration: 73.313113ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:29.849579Z","caller":"traceutil/trace.go:171","msg":"trace[591334703] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"138.790495ms","start":"2026-07-21T05:35:29.710754Z","end":"2026-07-21T05:35:29.849544Z","steps":["trace[591334703] 'process raft request' (duration: 125.936989ms)","trace[591334703] 'compare' (duration: 12.739793ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:30.030923Z","caller":"traceutil/trace.go:171","msg":"trace[851610182] transaction","detail":"{read_only:false; response_revision:6699; number_of_response:1; }","duration":"152.761196ms","start":"2026-07-21T05:35:29.878141Z","end":"2026-07-21T05:35:30.030902Z","steps":["trace[851610182] 'process raft request' (duration: 96.542916ms)","trace[851610182] 'compare' (duration: 55.852841ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:32.988702Z","caller":"traceutil/trace.go:171","msg":"trace[171168836] linearizableReadLoop","detail":"{readStateIndex:6970; appliedIndex:6969; }","duration":"406.334524ms","start":"2026-07-21T05:35:32.582348Z","end":"2026-07-21T05:35:32.988682Z","steps":["trace[171168836] 'read index received' (duration: 406.145531ms)","trace[171168836] 'applied index is now lower than readState.Index' (duration: 188.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:32.988727Z","caller":"traceutil/trace.go:171","msg":"trace[1164096609] transaction","detail":"{read_only:false; response_revision:6713; number_of_response:1; }","duration":"441.984094ms","start":"2026-07-21T05:35:32.546725Z","end":"2026-07-21T05:35:32.988709Z","steps":["trace[1164096609] 'process raft request' (duration: 441.795279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:32.988854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:32.546713Z","time spent":"442.075007ms","remote":"127.0.0.1:46174","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-07-21T05:35:32.988898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.506859ms","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-07-21T05:35:32.988985Z","caller":"traceutil/trace.go:171","msg":"trace[1409636999] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6713; }","duration":"406.654122ms","start":"2026-07-21T05:35:32.582311Z","end":"2026-07-21T05:35:32.988965Z","steps":["trace[1409636999] 'agreement among raft nodes before linearized reading' (duration: 406.451567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:32.989043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:32.582292Z","time spent":"406.739704ms","remote":"127.0.0.1:60946","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":"info","ts":"2026-07-21T05:35:33.174745Z","caller":"traceutil/trace.go:171","msg":"trace[1868541953] linearizableReadLoop","detail":"{readStateIndex:6971; appliedIndex:6970; }","duration":"185.96514ms","start":"2026-07-21T05:35:32.988759Z","end":"2026-07-21T05:35:33.174724Z","steps":["trace[1868541953] 'read index received' (duration: 157.392522ms)","trace[1868541953] 'applied index is now lower than readState.Index' (duration: 28.571658ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:33.17488Z","caller":"traceutil/trace.go:171","msg":"trace[1285456929] transaction","detail":"{read_only:false; response_revision:6714; number_of_response:1; }","duration":"209.317807ms","start":"2026-07-21T05:35:32.965536Z","end":"2026-07-21T05:35:33.174854Z","steps":["trace[1285456929] 'process raft request' (duration: 180.572045ms)","trace[1285456929] 'compare' (duration: 28.439185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:33.174951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.624625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T05:35:33.174993Z","caller":"traceutil/trace.go:171","msg":"trace[2058171749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6714; }","duration":"493.707306ms","start":"2026-07-21T05:35:32.681272Z","end":"2026-07-21T05:35:33.174979Z","steps":["trace[2058171749] 'agreement among raft nodes before linearized reading' (duration: 493.553183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:33.175031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:32.681252Z","time spent":"493.768578ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T05:35:33.175173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.219198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-21T05:35:33.175388Z","caller":"traceutil/trace.go:171","msg":"trace[6953671] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6714; }","duration":"207.401601ms","start":"2026-07-21T05:35:32.967915Z","end":"2026-07-21T05:35:33.175317Z","steps":["trace[6953671] 'agreement among raft nodes before linearized reading' (duration: 207.063213ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:35.73784Z","caller":"traceutil/trace.go:171","msg":"trace[1616978904] transaction","detail":"{read_only:false; response_revision:6728; number_of_response:1; }","duration":"193.741566ms","start":"2026-07-21T05:35:35.544077Z","end":"2026-07-21T05:35:35.737818Z","steps":["trace[1616978904] 'process raft request' (duration: 135.512743ms)","trace[1616978904] 'compare' (duration: 57.802223ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:38.197675Z","caller":"traceutil/trace.go:171","msg":"trace[524178513] linearizableReadLoop","detail":"{readStateIndex:6999; appliedIndex:6998; }","duration":"100.88569ms","start":"2026-07-21T05:35:38.096748Z","end":"2026-07-21T05:35:38.197634Z","steps":["trace[524178513] 'read index received' (duration: 27.559306ms)","trace[524178513] 'applied index is now lower than readState.Index' (duration: 73.324854ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:38.197727Z","caller":"traceutil/trace.go:171","msg":"trace[136312717] transaction","detail":"{read_only:false; response_revision:6741; number_of_response:1; }","duration":"101.348431ms","start":"2026-07-21T05:35:38.096335Z","end":"2026-07-21T05:35:38.197684Z","steps":["trace[136312717] 'process raft request' (duration: 28.031527ms)","trace[136312717] 'compare' (duration: 73.001556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:38.198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.227859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-21T05:35:38.19821Z","caller":"traceutil/trace.go:171","msg":"trace[1777204919] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6741; }","duration":"101.471764ms","start":"2026-07-21T05:35:38.096716Z","end":"2026-07-21T05:35:38.198188Z","steps":["trace[1777204919] 'agreement among raft nodes before linearized reading' (duration: 101.080825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:38.19991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.670138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-07-21T05:35:38.199958Z","caller":"traceutil/trace.go:171","msg":"trace[206694253] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6742; }","duration":"101.73365ms","start":"2026-07-21T05:35:38.098212Z","end":"2026-07-21T05:35:38.199946Z","steps":["trace[206694253] 'agreement among raft nodes before linearized reading' (duration: 101.645588ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:43.34937Z","caller":"traceutil/trace.go:171","msg":"trace[1897300575] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"183.172927ms","start":"2026-07-21T05:35:43.166167Z","end":"2026-07-21T05:35:43.34934Z","steps":["trace[1897300575] 'process raft request' (duration: 145.312419ms)","trace[1897300575] 'compare' (duration: 37.663193ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:43.349567Z","caller":"traceutil/trace.go:171","msg":"trace[1783083966] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"173.925153ms","start":"2026-07-21T05:35:43.175609Z","end":"2026-07-21T05:35:43.349534Z","steps":["trace[1783083966] 'process raft request' (duration: 173.735509ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:43.349738Z","caller":"traceutil/trace.go:171","msg":"trace[682204701] linearizableReadLoop","detail":"{readStateIndex:7065; appliedIndex:7064; }","duration":"174.909696ms","start":"2026-07-21T05:35:43.174817Z","end":"2026-07-21T05:35:43.349727Z","steps":["trace[682204701] 'read index received' (duration: 136.776081ms)","trace[682204701] 'applied index is now lower than readState.Index' (duration: 38.132115ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:43.349856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.002488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:43.349914Z","caller":"traceutil/trace.go:171","msg":"trace[1369153259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6806; }","duration":"175.11026ms","start":"2026-07-21T05:35:43.174778Z","end":"2026-07-21T05:35:43.349888Z","steps":["trace[1369153259] 'agreement among raft nodes before linearized reading' (duration: 175.015618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:43.349929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.610289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T05:35:43.349962Z","caller":"traceutil/trace.go:171","msg":"trace[1418559653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6806; }","duration":"102.706091ms","start":"2026-07-21T05:35:43.247247Z","end":"2026-07-21T05:35:43.349953Z","steps":["trace[1418559653] 'agreement among raft nodes before linearized reading' (duration: 102.568618ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:43.66269Z","caller":"traceutil/trace.go:171","msg":"trace[710066203] transaction","detail":"{read_only:false; response_revision:6811; number_of_response:1; }","duration":"133.594877ms","start":"2026-07-21T05:35:43.529071Z","end":"2026-07-21T05:35:43.662666Z","steps":["trace[710066203] 'process raft request' (duration: 109.075478ms)","trace[710066203] 'compare' (duration: 24.141541ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:44.45629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.12746ms","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-07-21T05:35:44.456362Z","caller":"traceutil/trace.go:171","msg":"trace[1001257431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6816; }","duration":"152.207882ms","start":"2026-07-21T05:35:44.304137Z","end":"2026-07-21T05:35:44.456345Z","steps":["trace[1001257431] 'range keys from in-memory index tree' (duration: 151.913315ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:44.779056Z","caller":"traceutil/trace.go:171","msg":"trace[510882638] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"126.574337ms","start":"2026-07-21T05:35:44.65246Z","end":"2026-07-21T05:35:44.779035Z","steps":["trace[510882638] 'process raft request' (duration: 125.529372ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:45.380987Z","caller":"traceutil/trace.go:171","msg":"trace[342403249] transaction","detail":"{read_only:false; response_revision:6826; number_of_response:1; }","duration":"108.465248ms","start":"2026-07-21T05:35:45.272479Z","end":"2026-07-21T05:35:45.380945Z","steps":["trace[342403249] 'process raft request' (duration: 107.750162ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:48.646508Z","caller":"traceutil/trace.go:171","msg":"trace[1205186268] transaction","detail":"{read_only:false; response_revision:6845; number_of_response:1; }","duration":"103.669193ms","start":"2026-07-21T05:35:48.542823Z","end":"2026-07-21T05:35:48.646493Z","steps":["trace[1205186268] 'process raft request' (duration: 103.249273ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:49.237648Z","caller":"traceutil/trace.go:171","msg":"trace[775158692] transaction","detail":"{read_only:false; response_revision:6849; number_of_response:1; }","duration":"109.408306ms","start":"2026-07-21T05:35:49.12821Z","end":"2026-07-21T05:35:49.237618Z","steps":["trace[775158692] 'process raft request' (duration: 109.233683ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:50.500033Z","caller":"traceutil/trace.go:171","msg":"trace[698353349] transaction","detail":"{read_only:false; response_revision:6855; number_of_response:1; }","duration":"223.554978ms","start":"2026-07-21T05:35:50.276454Z","end":"2026-07-21T05:35:50.500009Z","steps":["trace[698353349] 'process raft request' (duration: 223.387275ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:50.508878Z","caller":"traceutil/trace.go:171","msg":"trace[2099905972] transaction","detail":"{read_only:false; response_revision:6856; number_of_response:1; }","duration":"191.319919ms","start":"2026-07-21T05:35:50.317536Z","end":"2026-07-21T05:35:50.508855Z","steps":["trace[2099905972] 'process raft request' (duration: 191.131635ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:36:59.979229Z","caller":"traceutil/trace.go:171","msg":"trace[297410304] linearizableReadLoop","detail":"{readStateIndex:7511; appliedIndex:7510; }","duration":"122.667171ms","start":"2026-07-21T05:36:59.856538Z","end":"2026-07-21T05:36:59.979205Z","steps":["trace[297410304] 'read index received' (duration: 120.757694ms)","trace[297410304] 'applied index is now lower than readState.Index' (duration: 1.908547ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:36:59.9793Z","caller":"traceutil/trace.go:171","msg":"trace[1898760255] transaction","detail":"{read_only:false; response_revision:7234; number_of_response:1; }","duration":"123.073081ms","start":"2026-07-21T05:36:59.856203Z","end":"2026-07-21T05:36:59.979276Z","steps":["trace[1898760255] 'process raft request' (duration: 121.161075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:36:59.979467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.913976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-79c48d47db-45m42\" ","response":"range_response_count:1 size:5038"} {"level":"info","ts":"2026-07-21T05:36:59.979496Z","caller":"traceutil/trace.go:171","msg":"trace[53594626] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-79c48d47db-45m42; range_end:; response_count:1; response_revision:7234; }","duration":"122.997419ms","start":"2026-07-21T05:36:59.85649Z","end":"2026-07-21T05:36:59.979488Z","steps":["trace[53594626] 'agreement among raft nodes before linearized reading' (duration: 122.850836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:36:59.979565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.128468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/auth-system/keycloak\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-21T05:36:59.979602Z","caller":"traceutil/trace.go:171","msg":"trace[1997459785] range","detail":"{range_begin:/registry/networkpolicies/auth-system/keycloak; range_end:; response_count:1; response_revision:7234; }","duration":"122.20683ms","start":"2026-07-21T05:36:59.857383Z","end":"2026-07-21T05:36:59.97959Z","steps":["trace[1997459785] 'agreement among raft nodes before linearized reading' (duration: 122.067547ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:37:21.830765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5434} {"level":"info","ts":"2026-07-21T05:37:21.976155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5434,"took":"144.006686ms","hash":2205031417,"current-db-size-bytes":52576256,"current-db-size":"53 MB","current-db-size-in-use-bytes":18849792,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-21T05:37:21.976207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2205031417,"revision":5434,"compact-revision":2819}