{"level":"warn","ts":"2026-05-12T11:25:56.784751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:25:56.78485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.202: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.202:2380","--initial-cluster=instance=https://199.19.213.202:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.202:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.202: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-05-12T11:25:56.784924Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:25:56.784936Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-05-12T11:25:56.784956Z","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-05-12T11:25:56.785749Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"]} {"level":"info","ts":"2026-05-12T11:25:56.785839Z","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.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.202: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-05-12T11:25:56.791329Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.148247ms"} {"level":"info","ts":"2026-05-12T11:25:56.800615Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9b4049ccf4190cf","cluster-id":"2bc66bd50b26302c"} {"level":"info","ts":"2026-05-12T11:25:56.800702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T11:25:56.800725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 0"} {"level":"info","ts":"2026-05-12T11:25:56.800736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9b4049ccf4190cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T11:25:56.800745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 1"} {"level":"info","ts":"2026-05-12T11:25:56.800781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"warn","ts":"2026-05-12T11:25:56.808135Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T11:25:56.809337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T11:25:56.811192Z","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-05-12T11:25:56.813009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9b4049ccf4190cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T11:25:56.813437Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9b4049ccf4190cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T11:25:56.813517Z","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-05-12T11:25:56.813557Z","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-05-12T11:25:56.813564Z","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-05-12T11:25:56.81425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"info","ts":"2026-05-12T11:25:56.81432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","added-peer-id":"c9b4049ccf4190cf","added-peer-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-05-12T11:25:56.817972Z","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-05-12T11:25:56.818052Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-05-12T11:25:56.818074Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-05-12T11:25:56.818145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9b4049ccf4190cf","initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T11:25:56.818176Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T11:25:56.901923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T11:25:56.902048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T11:25:56.90213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgPreVoteResp from c9b4049ccf4190cf at term 1"} {"level":"info","ts":"2026-05-12T11:25:56.902207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became candidate at term 2"} {"level":"info","ts":"2026-05-12T11:25:56.902251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgVoteResp from c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-05-12T11:25:56.902329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became leader at term 2"} {"level":"info","ts":"2026-05-12T11:25:56.902373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9b4049ccf4190cf elected leader c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-05-12T11:25:56.903864Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9b4049ccf4190cf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.202:2379]}","request-path":"/0/members/c9b4049ccf4190cf/attributes","cluster-id":"2bc66bd50b26302c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T11:25:56.9044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:25:56.904643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:56.904625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:25:56.90494Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T11:25:56.904971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T11:25:56.906224Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:56.90643Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:56.906616Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:56.907972Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T11:25:56.914556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.202:2379"} {"level":"info","ts":"2026-05-12T11:26:26.443847Z","caller":"traceutil/trace.go:171","msg":"trace[1942479578] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"176.990571ms","start":"2026-05-12T11:26:26.266766Z","end":"2026-05-12T11:26:26.443757Z","steps":["trace[1942479578] 'process raft request' (duration: 176.790126ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.533966Z","caller":"traceutil/trace.go:171","msg":"trace[1438985612] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"264.60703ms","start":"2026-05-12T11:26:26.269269Z","end":"2026-05-12T11:26:26.533876Z","steps":["trace[1438985612] 'process raft request' (duration: 264.271442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.534028Z","caller":"traceutil/trace.go:171","msg":"trace[1267923469] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"264.649512ms","start":"2026-05-12T11:26:26.269351Z","end":"2026-05-12T11:26:26.534001Z","steps":["trace[1267923469] 'process raft request' (duration: 264.437806ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.534513Z","caller":"traceutil/trace.go:171","msg":"trace[877611184] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"259.013622ms","start":"2026-05-12T11:26:26.275468Z","end":"2026-05-12T11:26:26.534482Z","steps":["trace[877611184] 'process raft request' (duration: 258.422978ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.534678Z","caller":"traceutil/trace.go:171","msg":"trace[1356842057] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"259.127655ms","start":"2026-05-12T11:26:26.275505Z","end":"2026-05-12T11:26:26.534633Z","steps":["trace[1356842057] 'process raft request' (duration: 258.870009ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.534888Z","caller":"traceutil/trace.go:171","msg":"trace[2027006637] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"256.078388ms","start":"2026-05-12T11:26:26.278784Z","end":"2026-05-12T11:26:26.534862Z","steps":["trace[2027006637] 'process raft request' (duration: 255.73565ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.53504Z","caller":"traceutil/trace.go:171","msg":"trace[2114404806] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"256.214591ms","start":"2026-05-12T11:26:26.27881Z","end":"2026-05-12T11:26:26.535025Z","steps":["trace[2114404806] 'process raft request' (duration: 255.75351ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.535222Z","caller":"traceutil/trace.go:171","msg":"trace[922125799] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"251.956347ms","start":"2026-05-12T11:26:26.28325Z","end":"2026-05-12T11:26:26.535207Z","steps":["trace[922125799] 'process raft request' (duration: 251.383613ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.535536Z","caller":"traceutil/trace.go:171","msg":"trace[1839807244] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"235.12714ms","start":"2026-05-12T11:26:26.300383Z","end":"2026-05-12T11:26:26.53551Z","steps":["trace[1839807244] 'process raft request' (duration: 234.947976ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.535642Z","caller":"traceutil/trace.go:171","msg":"trace[315051637] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"216.642872ms","start":"2026-05-12T11:26:26.31899Z","end":"2026-05-12T11:26:26.535633Z","steps":["trace[315051637] 'process raft request' (duration: 216.476568ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.535891Z","caller":"traceutil/trace.go:171","msg":"trace[548381250] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"211.947885ms","start":"2026-05-12T11:26:26.32393Z","end":"2026-05-12T11:26:26.535878Z","steps":["trace[548381250] 'process raft request' (duration: 211.623187ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.536168Z","caller":"traceutil/trace.go:171","msg":"trace[515565422] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"106.935437ms","start":"2026-05-12T11:26:26.429223Z","end":"2026-05-12T11:26:26.536158Z","steps":["trace[515565422] 'process raft request' (duration: 106.824464ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.665494Z","caller":"traceutil/trace.go:171","msg":"trace[989565101] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"216.453389ms","start":"2026-05-12T11:26:26.449021Z","end":"2026-05-12T11:26:26.665474Z","steps":["trace[989565101] 'process raft request' (duration: 216.298895ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.665523Z","caller":"traceutil/trace.go:171","msg":"trace[641881476] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"214.627792ms","start":"2026-05-12T11:26:26.450871Z","end":"2026-05-12T11:26:26.665498Z","steps":["trace[641881476] 'process raft request' (duration: 214.52025ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.665698Z","caller":"traceutil/trace.go:171","msg":"trace[934975535] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"216.9317ms","start":"2026-05-12T11:26:26.448476Z","end":"2026-05-12T11:26:26.665408Z","steps":["trace[934975535] 'process raft request' (duration: 190.726911ms)","trace[934975535] 'compare' (duration: 25.981443ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:26.665862Z","caller":"traceutil/trace.go:171","msg":"trace[883217580] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"116.530875ms","start":"2026-05-12T11:26:26.549321Z","end":"2026-05-12T11:26:26.665852Z","steps":["trace[883217580] 'process raft request' (duration: 116.106645ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.666034Z","caller":"traceutil/trace.go:171","msg":"trace[2104359837] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"116.583626ms","start":"2026-05-12T11:26:26.549397Z","end":"2026-05-12T11:26:26.665981Z","steps":["trace[2104359837] 'process raft request' (duration: 116.31961ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.666125Z","caller":"traceutil/trace.go:171","msg":"trace[183113728] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"116.71689ms","start":"2026-05-12T11:26:26.549399Z","end":"2026-05-12T11:26:26.666116Z","steps":["trace[183113728] 'process raft request' (duration: 116.281059ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.666231Z","caller":"traceutil/trace.go:171","msg":"trace[544312070] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"112.508505ms","start":"2026-05-12T11:26:26.553713Z","end":"2026-05-12T11:26:26.666222Z","steps":["trace[544312070] 'process raft request' (duration: 112.168447ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.666227Z","caller":"traceutil/trace.go:171","msg":"trace[1180351254] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"115.423007ms","start":"2026-05-12T11:26:26.550782Z","end":"2026-05-12T11:26:26.666205Z","steps":["trace[1180351254] 'process raft request' (duration: 114.975846ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.667864Z","caller":"traceutil/trace.go:171","msg":"trace[1053509079] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"112.290619ms","start":"2026-05-12T11:26:26.555523Z","end":"2026-05-12T11:26:26.667814Z","steps":["trace[1053509079] 'process raft request' (duration: 110.579607ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.668274Z","caller":"traceutil/trace.go:171","msg":"trace[842915075] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"112.067824ms","start":"2026-05-12T11:26:26.556189Z","end":"2026-05-12T11:26:26.668257Z","steps":["trace[842915075] 'process raft request' (duration: 110.000512ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:26.671974Z","caller":"traceutil/trace.go:171","msg":"trace[480391744] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"110.986118ms","start":"2026-05-12T11:26:26.560972Z","end":"2026-05-12T11:26:26.671958Z","steps":["trace[480391744] 'process raft request' (duration: 105.83426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:32.090534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.932668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:26:32.090698Z","caller":"traceutil/trace.go:171","msg":"trace[1757556392] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"141.291817ms","start":"2026-05-12T11:26:31.949389Z","end":"2026-05-12T11:26:32.090681Z","steps":["trace[1757556392] 'read index received' (duration: 75.522µs)","trace[1757556392] 'applied index is now lower than readState.Index' (duration: 141.214825ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:32.090752Z","caller":"traceutil/trace.go:171","msg":"trace[2052102054] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"286.02861ms","start":"2026-05-12T11:26:31.804665Z","end":"2026-05-12T11:26:32.090693Z","steps":["trace[2052102054] 'process raft request' (duration: 140.486207ms)","trace[2052102054] 'compare' (duration: 144.793195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:32.090802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.40469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:32.09083Z","caller":"traceutil/trace.go:171","msg":"trace[1891401559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"141.449591ms","start":"2026-05-12T11:26:31.949372Z","end":"2026-05-12T11:26:32.090822Z","steps":["trace[1891401559] 'agreement among raft nodes before linearized reading' (duration: 141.356449ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:33.107998Z","caller":"traceutil/trace.go:171","msg":"trace[1535930355] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"157.348834ms","start":"2026-05-12T11:26:32.950625Z","end":"2026-05-12T11:26:33.107974Z","steps":["trace[1535930355] 'read index received' (duration: 92.860568ms)","trace[1535930355] 'applied index is now lower than readState.Index' (duration: 64.486456ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:33.108027Z","caller":"traceutil/trace.go:171","msg":"trace[790841510] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"354.686608ms","start":"2026-05-12T11:26:32.753321Z","end":"2026-05-12T11:26:33.108008Z","steps":["trace[790841510] 'process raft request' (duration: 354.485833ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:33.108074Z","caller":"traceutil/trace.go:171","msg":"trace[37122993] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"354.794021ms","start":"2026-05-12T11:26:32.753274Z","end":"2026-05-12T11:26:33.108068Z","steps":["trace[37122993] 'process raft request' (duration: 290.265954ms)","trace[37122993] 'compare' (duration: 64.058075ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:33.108161Z","caller":"traceutil/trace.go:171","msg":"trace[1134714533] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"354.791731ms","start":"2026-05-12T11:26:32.753349Z","end":"2026-05-12T11:26:33.108141Z","steps":["trace[1134714533] 'process raft request' (duration: 354.555976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:33.108455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:32.753249Z","time spent":"354.853062ms","remote":"127.0.0.1:40442","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-05-12T11:26:33.108198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.555899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:33.108679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:32.753332Z","time spent":"354.904444ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:33.108676Z","caller":"traceutil/trace.go:171","msg":"trace[295753674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"158.042452ms","start":"2026-05-12T11:26:32.950621Z","end":"2026-05-12T11:26:33.108663Z","steps":["trace[295753674] 'agreement among raft nodes before linearized reading' (duration: 157.526199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:33.108871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:32.753302Z","time spent":"354.786561ms","remote":"127.0.0.1:40442","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-05-12T11:26:34.301596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.988332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:26:34.301931Z","caller":"traceutil/trace.go:171","msg":"trace[658904956] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"350.808714ms","start":"2026-05-12T11:26:33.951103Z","end":"2026-05-12T11:26:34.301911Z","steps":["trace[658904956] 'read index received' (duration: 23.43666ms)","trace[658904956] 'applied index is now lower than readState.Index' (duration: 327.370973ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:34.301919Z","caller":"traceutil/trace.go:171","msg":"trace[205978671] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.088266516s","start":"2026-05-12T11:26:33.213618Z","end":"2026-05-12T11:26:34.301885Z","steps":["trace[205978671] 'process raft request' (duration: 760.901273ms)","trace[205978671] 'compare' (duration: 326.502881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:34.302028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.918827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:34.302057Z","caller":"traceutil/trace.go:171","msg":"trace[172655692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"350.985958ms","start":"2026-05-12T11:26:33.95106Z","end":"2026-05-12T11:26:34.302046Z","steps":["trace[172655692] 'agreement among raft nodes before linearized reading' (duration: 350.912846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:34.302072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:33.213593Z","time spent":"1.088396449s","remote":"127.0.0.1:40368","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-05-12T11:26:34.302087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:33.95104Z","time spent":"351.04062ms","remote":"127.0.0.1:40190","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-05-12T11:26:34.302336Z","caller":"traceutil/trace.go:171","msg":"trace[94675324] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.051570398s","start":"2026-05-12T11:26:33.250755Z","end":"2026-05-12T11:26:34.302325Z","steps":["trace[94675324] 'process raft request' (duration: 1.050990392s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:34.302389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:33.250714Z","time spent":"1.05164799s","remote":"127.0.0.1:40408","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-05-12T11:26:35.590427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.285322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.590509Z","caller":"traceutil/trace.go:171","msg":"trace[1400159913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:643; }","duration":"266.393504ms","start":"2026-05-12T11:26:35.324098Z","end":"2026-05-12T11:26:35.590491Z","steps":["trace[1400159913] 'count revisions from in-memory index tree' (duration: 266.259861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:35.59079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.682972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.590892Z","caller":"traceutil/trace.go:171","msg":"trace[2092370691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:643; }","duration":"266.815435ms","start":"2026-05-12T11:26:35.324058Z","end":"2026-05-12T11:26:35.590874Z","steps":["trace[2092370691] 'range keys from in-memory index tree' (duration: 266.605311ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:35.593597Z","caller":"traceutil/trace.go:171","msg":"trace[566748032] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"240.254057ms","start":"2026-05-12T11:26:35.353323Z","end":"2026-05-12T11:26:35.593578Z","steps":["trace[566748032] 'read index received' (duration: 235.180201ms)","trace[566748032] 'applied index is now lower than readState.Index' (duration: 5.071996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:35.593718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.389491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:35.593735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.37239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.593763Z","caller":"traceutil/trace.go:171","msg":"trace[845550742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:643; }","duration":"240.467542ms","start":"2026-05-12T11:26:35.353285Z","end":"2026-05-12T11:26:35.593753Z","steps":["trace[845550742] 'agreement among raft nodes before linearized reading' (duration: 240.38629ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:35.593771Z","caller":"traceutil/trace.go:171","msg":"trace[1893265009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:643; }","duration":"240.415971ms","start":"2026-05-12T11:26:35.353346Z","end":"2026-05-12T11:26:35.593762Z","steps":["trace[1893265009] 'agreement among raft nodes before linearized reading' (duration: 240.35212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:35.593813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.520732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:35.593867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.65177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.593908Z","caller":"traceutil/trace.go:171","msg":"trace[72744971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:643; }","duration":"213.70254ms","start":"2026-05-12T11:26:35.380193Z","end":"2026-05-12T11:26:35.593895Z","steps":["trace[72744971] 'agreement among raft nodes before linearized reading' (duration: 213.652909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:35.594078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.382637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:35.59411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.026101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.594164Z","caller":"traceutil/trace.go:171","msg":"trace[32749137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:643; }","duration":"240.083883ms","start":"2026-05-12T11:26:35.354071Z","end":"2026-05-12T11:26:35.594155Z","steps":["trace[32749137] 'agreement among raft nodes before linearized reading' (duration: 240.005971ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:35.594114Z","caller":"traceutil/trace.go:171","msg":"trace[915841452] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:643; }","duration":"214.446879ms","start":"2026-05-12T11:26:35.379659Z","end":"2026-05-12T11:26:35.594105Z","steps":["trace[915841452] 'agreement among raft nodes before linearized reading' (duration: 214.391847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:35.594189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.478629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:35.594286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.779158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.593902Z","caller":"traceutil/trace.go:171","msg":"trace[701959899] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:643; }","duration":"187.651115ms","start":"2026-05-12T11:26:35.406231Z","end":"2026-05-12T11:26:35.593882Z","steps":["trace[701959899] 'agreement among raft nodes before linearized reading' (duration: 187.485851ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:35.594292Z","caller":"traceutil/trace.go:171","msg":"trace[943963157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:643; }","duration":"214.635904ms","start":"2026-05-12T11:26:35.379636Z","end":"2026-05-12T11:26:35.594272Z","steps":["trace[943963157] 'agreement among raft nodes before linearized reading' (duration: 214.461679ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:35.594333Z","caller":"traceutil/trace.go:171","msg":"trace[1797487210] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:643; }","duration":"187.84602ms","start":"2026-05-12T11:26:35.406476Z","end":"2026-05-12T11:26:35.594322Z","steps":["trace[1797487210] 'agreement among raft nodes before linearized reading' (duration: 187.783408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:35.594472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.175107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:35.594506Z","caller":"traceutil/trace.go:171","msg":"trace[1756144848] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:643; }","duration":"188.228179ms","start":"2026-05-12T11:26:35.40627Z","end":"2026-05-12T11:26:35.594498Z","steps":["trace[1756144848] 'agreement among raft nodes before linearized reading' (duration: 188.174218ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:39.519598Z","caller":"traceutil/trace.go:171","msg":"trace[1966917318] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"122.853501ms","start":"2026-05-12T11:26:39.396721Z","end":"2026-05-12T11:26:39.519575Z","steps":["trace[1966917318] 'process raft request' (duration: 122.709357ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:49.338001Z","caller":"traceutil/trace.go:171","msg":"trace[1894149930] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"100.926458ms","start":"2026-05-12T11:26:49.237046Z","end":"2026-05-12T11:26:49.337973Z","steps":["trace[1894149930] 'process raft request' (duration: 99.967974ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:49.338622Z","caller":"traceutil/trace.go:171","msg":"trace[1644964] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"100.974549ms","start":"2026-05-12T11:26:49.237628Z","end":"2026-05-12T11:26:49.338602Z","steps":["trace[1644964] 'process raft request' (duration: 100.881867ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:51.048076Z","caller":"traceutil/trace.go:171","msg":"trace[2043959974] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"119.2148ms","start":"2026-05-12T11:26:50.928837Z","end":"2026-05-12T11:26:51.048051Z","steps":["trace[2043959974] 'process raft request' (duration: 119.080387ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:51.481778Z","caller":"traceutil/trace.go:171","msg":"trace[1478052033] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"134.809326ms","start":"2026-05-12T11:26:51.346938Z","end":"2026-05-12T11:26:51.481747Z","steps":["trace[1478052033] 'process raft request' (duration: 134.654982ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:51.650132Z","caller":"traceutil/trace.go:171","msg":"trace[1262015569] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"302.912998ms","start":"2026-05-12T11:26:51.347176Z","end":"2026-05-12T11:26:51.650089Z","steps":["trace[1262015569] 'process raft request' (duration: 299.574866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:51.650367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:51.347157Z","time spent":"303.086772ms","remote":"127.0.0.1:40442","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-05-12T11:26:54.507937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464160426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:55.008716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464160426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:55.455129Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.797031151s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:26:55.45577Z","caller":"traceutil/trace.go:171","msg":"trace[1312950082] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.797730758s","start":"2026-05-12T11:26:53.65799Z","end":"2026-05-12T11:26:55.455721Z","steps":["trace[1312950082] 'process raft request' (duration: 1.797530063s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:55.455968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:53.657969Z","time spent":"1.797903272s","remote":"127.0.0.1:40442","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-05-12T11:26:56.097176Z","caller":"traceutil/trace.go:171","msg":"trace[2114118501] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.766999777s","start":"2026-05-12T11:26:54.330109Z","end":"2026-05-12T11:26:56.097109Z","steps":["trace[2114118501] 'process raft request' (duration: 1.766731781s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:56.097646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:54.33008Z","time spent":"1.767223763s","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:26:56.208168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.1279ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T11:26:56.208263Z","caller":"traceutil/trace.go:171","msg":"trace[1720205583] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"2.200627559s","start":"2026-05-12T11:26:54.007617Z","end":"2026-05-12T11:26:56.208245Z","steps":["trace[1720205583] 'read index received' (duration: 1.448100993s)","trace[1720205583] 'applied index is now lower than readState.Index' (duration: 752.525046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:56.208484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.200864666s","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-05-12T11:26:56.208505Z","caller":"traceutil/trace.go:171","msg":"trace[1701732683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"2.200925616s","start":"2026-05-12T11:26:54.007571Z","end":"2026-05-12T11:26:56.208497Z","steps":["trace[1701732683] 'agreement among raft nodes before linearized reading' (duration: 2.200787653s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:56.20853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:54.007554Z","time spent":"2.200970408s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T11:26:56.208623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256772478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:56.208709Z","caller":"traceutil/trace.go:171","msg":"trace[1958517703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.256895091s","start":"2026-05-12T11:26:54.951791Z","end":"2026-05-12T11:26:56.208686Z","steps":["trace[1958517703] 'agreement among raft nodes before linearized reading' (duration: 1.256764148s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:56.208739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.08414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-12T11:26:56.208762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:54.951772Z","time spent":"1.256978633s","remote":"127.0.0.1:40190","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-05-12T11:26:56.208794Z","caller":"traceutil/trace.go:171","msg":"trace[698352273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"957.220583ms","start":"2026-05-12T11:26:55.251556Z","end":"2026-05-12T11:26:56.208777Z","steps":["trace[698352273] 'agreement among raft nodes before linearized reading' (duration: 957.002968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:56.208838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:55.251512Z","time spent":"957.313895ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T11:26:56.208817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147708619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T11:26:56.208817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.662135ms","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-05-12T11:26:56.208899Z","caller":"traceutil/trace.go:171","msg":"trace[440317690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"715.792838ms","start":"2026-05-12T11:26:55.493089Z","end":"2026-05-12T11:26:56.208882Z","steps":["trace[440317690] 'agreement among raft nodes before linearized reading' (duration: 715.540662ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:56.208899Z","caller":"traceutil/trace.go:171","msg":"trace[731414676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.147821872s","start":"2026-05-12T11:26:55.061054Z","end":"2026-05-12T11:26:56.208876Z","steps":["trace[731414676] 'agreement among raft nodes before linearized reading' (duration: 1.147574256s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:56.208942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:55.493066Z","time spent":"715.86655ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T11:26:56.208942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:55.061038Z","time spent":"1.147895303s","remote":"127.0.0.1:40442","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":"info","ts":"2026-05-12T11:27:04.659076Z","caller":"traceutil/trace.go:171","msg":"trace[971732024] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"161.629081ms","start":"2026-05-12T11:27:04.497292Z","end":"2026-05-12T11:27:04.658921Z","steps":["trace[971732024] 'process raft request' (duration: 114.396783ms)","trace[971732024] 'compare' (duration: 47.127236ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:04.675491Z","caller":"traceutil/trace.go:171","msg":"trace[2084146725] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"177.797124ms","start":"2026-05-12T11:27:04.497666Z","end":"2026-05-12T11:27:04.675463Z","steps":["trace[2084146725] 'process raft request' (duration: 177.61964ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.675773Z","caller":"traceutil/trace.go:171","msg":"trace[214268944] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"177.721851ms","start":"2026-05-12T11:27:04.498033Z","end":"2026-05-12T11:27:04.675755Z","steps":["trace[214268944] 'process raft request' (duration: 177.378003ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.675948Z","caller":"traceutil/trace.go:171","msg":"trace[858973343] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"176.659465ms","start":"2026-05-12T11:27:04.49927Z","end":"2026-05-12T11:27:04.67593Z","steps":["trace[858973343] 'process raft request' (duration: 176.414519ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.784609Z","caller":"traceutil/trace.go:171","msg":"trace[114229772] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"102.775113ms","start":"2026-05-12T11:27:04.681804Z","end":"2026-05-12T11:27:04.784579Z","steps":["trace[114229772] 'process raft request' (duration: 102.601969ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.784618Z","caller":"traceutil/trace.go:171","msg":"trace[1384951449] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"103.155272ms","start":"2026-05-12T11:27:04.681436Z","end":"2026-05-12T11:27:04.784592Z","steps":["trace[1384951449] 'process raft request' (duration: 84.197249ms)","trace[1384951449] 'compare' (duration: 18.682586ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:04.784531Z","caller":"traceutil/trace.go:171","msg":"trace[920147417] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:935; }","duration":"102.528957ms","start":"2026-05-12T11:27:04.681983Z","end":"2026-05-12T11:27:04.784512Z","steps":["trace[920147417] 'read index received' (duration: 12.466012ms)","trace[920147417] 'applied index is now lower than readState.Index' (duration: 90.061705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:04.785074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.03767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-05-12T11:27:04.785129Z","caller":"traceutil/trace.go:171","msg":"trace[707210625] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:912; }","duration":"103.157643ms","start":"2026-05-12T11:27:04.681957Z","end":"2026-05-12T11:27:04.785115Z","steps":["trace[707210625] 'agreement among raft nodes before linearized reading' (duration: 102.922487ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.908613Z","caller":"traceutil/trace.go:171","msg":"trace[1726894324] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"116.003223ms","start":"2026-05-12T11:27:04.792587Z","end":"2026-05-12T11:27:04.908591Z","steps":["trace[1726894324] 'process raft request' (duration: 115.932521ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.908673Z","caller":"traceutil/trace.go:171","msg":"trace[1636188575] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"116.132125ms","start":"2026-05-12T11:27:04.792511Z","end":"2026-05-12T11:27:04.908643Z","steps":["trace[1636188575] 'process raft request' (duration: 98.27186ms)","trace[1636188575] 'compare' (duration: 17.563448ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:04.908771Z","caller":"traceutil/trace.go:171","msg":"trace[1643544934] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"116.129436ms","start":"2026-05-12T11:27:04.792573Z","end":"2026-05-12T11:27:04.908703Z","steps":["trace[1643544934] 'process raft request' (duration: 115.89624ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.910033Z","caller":"traceutil/trace.go:171","msg":"trace[1958204901] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:940; }","duration":"114.541826ms","start":"2026-05-12T11:27:04.795469Z","end":"2026-05-12T11:27:04.910011Z","steps":["trace[1958204901] 'read index received' (duration: 2.011699ms)","trace[1958204901] 'applied index is now lower than readState.Index' (duration: 112.528897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:04.910264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.788502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-05-12T11:27:04.910306Z","caller":"traceutil/trace.go:171","msg":"trace[688824183] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:918; }","duration":"114.893555ms","start":"2026-05-12T11:27:04.795401Z","end":"2026-05-12T11:27:04.910294Z","steps":["trace[688824183] 'agreement among raft nodes before linearized reading' (duration: 114.70226ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.910497Z","caller":"traceutil/trace.go:171","msg":"trace[1630407345] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"117.570031ms","start":"2026-05-12T11:27:04.792916Z","end":"2026-05-12T11:27:04.910486Z","steps":["trace[1630407345] 'process raft request' (duration: 115.643543ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:04.91076Z","caller":"traceutil/trace.go:171","msg":"trace[1563039408] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"115.50539ms","start":"2026-05-12T11:27:04.795243Z","end":"2026-05-12T11:27:04.910749Z","steps":["trace[1563039408] 'process raft request' (duration: 114.497625ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:28.574404Z","caller":"traceutil/trace.go:171","msg":"trace[889568646] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"117.09539ms","start":"2026-05-12T11:27:28.457291Z","end":"2026-05-12T11:27:28.574387Z","steps":["trace[889568646] 'process raft request' (duration: 110.964347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:30.921481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464160881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:27:31.422671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464160881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:27:31.577798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.263990028s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:27:31.578235Z","caller":"traceutil/trace.go:171","msg":"trace[788142853] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.264513252s","start":"2026-05-12T11:27:30.313697Z","end":"2026-05-12T11:27:31.578211Z","steps":["trace[788142853] 'process raft request' (duration: 1.264327187s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:31.5784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.313672Z","time spent":"1.264640374s","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:27:32.225404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.641068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:27:32.225674Z","caller":"traceutil/trace.go:171","msg":"trace[1903247731] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.751650009s","start":"2026-05-12T11:27:30.473992Z","end":"2026-05-12T11:27:32.225642Z","steps":["trace[1903247731] 'process raft request' (duration: 1.239695103s)","trace[1903247731] 'compare' (duration: 511.492404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:32.225815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.47396Z","time spent":"1.751778962s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:27:32.226076Z","caller":"traceutil/trace.go:171","msg":"trace[1743086115] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1049; }","duration":"1.805457861s","start":"2026-05-12T11:27:30.420598Z","end":"2026-05-12T11:27:32.226056Z","steps":["trace[1743086115] 'read index received' (duration: 1.157448092s)","trace[1743086115] 'applied index is now lower than readState.Index' (duration: 648.008739ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:32.226252Z","caller":"traceutil/trace.go:171","msg":"trace[1315338360] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.336498986s","start":"2026-05-12T11:27:30.889742Z","end":"2026-05-12T11:27:32.226241Z","steps":["trace[1315338360] 'process raft request' (duration: 1.336199609s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.226331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.889723Z","time spent":"1.336555998s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:27:32.226435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805828199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T11:27:32.226467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.155967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:27:32.226507Z","caller":"traceutil/trace.go:171","msg":"trace[1610869356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"1.805943612s","start":"2026-05-12T11:27:30.420544Z","end":"2026-05-12T11:27:32.226488Z","steps":["trace[1610869356] 'agreement among raft nodes before linearized reading' (duration: 1.805741047s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.226557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274959092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:27:32.226559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.420518Z","time spent":"1.806029745s","remote":"127.0.0.1:40442","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-05-12T11:27:32.22657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678647438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T11:27:32.226653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.192285ms","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-05-12T11:27:32.226658Z","caller":"traceutil/trace.go:171","msg":"trace[1274528732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.678783171s","start":"2026-05-12T11:27:30.547859Z","end":"2026-05-12T11:27:32.226642Z","steps":["trace[1274528732] 'agreement among raft nodes before linearized reading' (duration: 1.678429362s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.22666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.311516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-12T11:27:32.226667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648754894s","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-05-12T11:27:32.226681Z","caller":"traceutil/trace.go:171","msg":"trace[1502499762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"543.261587ms","start":"2026-05-12T11:27:31.683411Z","end":"2026-05-12T11:27:32.226673Z","steps":["trace[1502499762] 'agreement among raft nodes before linearized reading' (duration: 543.167915ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:32.226699Z","caller":"traceutil/trace.go:171","msg":"trace[1303538949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.648808064s","start":"2026-05-12T11:27:30.577883Z","end":"2026-05-12T11:27:32.226691Z","steps":["trace[1303538949] 'agreement among raft nodes before linearized reading' (duration: 1.648731432s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:32.226698Z","caller":"traceutil/trace.go:171","msg":"trace[1853089225] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1017; }","duration":"646.435589ms","start":"2026-05-12T11:27:31.580253Z","end":"2026-05-12T11:27:32.226688Z","steps":["trace[1853089225] 'agreement among raft nodes before linearized reading' (duration: 646.368068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.226712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:31.683391Z","time spent":"543.313548ms","remote":"127.0.0.1:40442","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-05-12T11:27:32.226728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.577871Z","time spent":"1.648851716s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T11:27:32.226733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:31.580236Z","time spent":"646.491211ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-12T11:27:32.226553Z","caller":"traceutil/trace.go:171","msg":"trace[902883413] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1017; }","duration":"971.29929ms","start":"2026-05-12T11:27:31.255235Z","end":"2026-05-12T11:27:32.226534Z","steps":["trace[902883413] 'agreement among raft nodes before linearized reading' (duration: 971.146127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.226812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:31.255215Z","time spent":"971.585017ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-12T11:27:32.226731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.547831Z","time spent":"1.678887315s","remote":"127.0.0.1:40442","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-05-12T11:27:32.226593Z","caller":"traceutil/trace.go:171","msg":"trace[791553259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.275017134s","start":"2026-05-12T11:27:30.951565Z","end":"2026-05-12T11:27:32.226582Z","steps":["trace[791553259] 'agreement among raft nodes before linearized reading' (duration: 1.274972333s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.226928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.951548Z","time spent":"1.275367062s","remote":"127.0.0.1:40190","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-05-12T11:27:32.22651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647694537s","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-05-12T11:27:32.227029Z","caller":"traceutil/trace.go:171","msg":"trace[1682353096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"1.648238859s","start":"2026-05-12T11:27:30.578778Z","end":"2026-05-12T11:27:32.227017Z","steps":["trace[1682353096] 'agreement among raft nodes before linearized reading' (duration: 1.647626314s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:32.227069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:30.578766Z","time spent":"1.648292131s","remote":"127.0.0.1:40442","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-05-12T11:27:36.486055Z","caller":"traceutil/trace.go:171","msg":"trace[519752766] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"132.166375ms","start":"2026-05-12T11:27:36.353857Z","end":"2026-05-12T11:27:36.486024Z","steps":["trace[519752766] 'process raft request' (duration: 74.317173ms)","trace[519752766] 'compare' (duration: 57.732589ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:36.50424Z","caller":"traceutil/trace.go:171","msg":"trace[248810328] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"149.323414ms","start":"2026-05-12T11:27:36.354901Z","end":"2026-05-12T11:27:36.504224Z","steps":["trace[248810328] 'process raft request' (duration: 149.289224ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:36.504265Z","caller":"traceutil/trace.go:171","msg":"trace[216130253] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"149.55958ms","start":"2026-05-12T11:27:36.354678Z","end":"2026-05-12T11:27:36.504237Z","steps":["trace[216130253] 'process raft request' (duration: 149.390206ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:36.504347Z","caller":"traceutil/trace.go:171","msg":"trace[1589611807] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"149.515559ms","start":"2026-05-12T11:27:36.354741Z","end":"2026-05-12T11:27:36.504256Z","steps":["trace[1589611807] 'process raft request' (duration: 149.409347ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:44.17201Z","caller":"traceutil/trace.go:171","msg":"trace[68292695] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"106.67205ms","start":"2026-05-12T11:27:44.06532Z","end":"2026-05-12T11:27:44.171992Z","steps":["trace[68292695] 'process raft request' (duration: 106.537606ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:44.790461Z","caller":"traceutil/trace.go:171","msg":"trace[404104180] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"221.409501ms","start":"2026-05-12T11:27:44.569018Z","end":"2026-05-12T11:27:44.790427Z","steps":["trace[404104180] 'process raft request' (duration: 196.758296ms)","trace[404104180] 'compare' (duration: 24.194893ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:44.790789Z","caller":"traceutil/trace.go:171","msg":"trace[171238371] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"221.621316ms","start":"2026-05-12T11:27:44.569136Z","end":"2026-05-12T11:27:44.790757Z","steps":["trace[171238371] 'process raft request' (duration: 221.495893ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:44.791059Z","caller":"traceutil/trace.go:171","msg":"trace[630714607] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"221.398311ms","start":"2026-05-12T11:27:44.569626Z","end":"2026-05-12T11:27:44.791024Z","steps":["trace[630714607] 'process raft request' (duration: 221.092043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:54.88337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464161083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:27:55.348813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.937285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-12T11:27:55.349759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.242449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:27:55.34907Z","caller":"traceutil/trace.go:171","msg":"trace[1555246142] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"966.708745ms","start":"2026-05-12T11:27:54.382343Z","end":"2026-05-12T11:27:55.349052Z","steps":["trace[1555246142] 'read index received' (duration: 509.36402ms)","trace[1555246142] 'applied index is now lower than readState.Index' (duration: 457.343115ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:55.349853Z","caller":"traceutil/trace.go:171","msg":"trace[672713961] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1090; }","duration":"967.547936ms","start":"2026-05-12T11:27:54.382289Z","end":"2026-05-12T11:27:55.349837Z","steps":["trace[672713961] 'agreement among raft nodes before linearized reading' (duration: 967.255719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.349956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.382271Z","time spent":"967.671259ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-12T11:27:55.349999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.160448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:27:55.350047Z","caller":"traceutil/trace.go:171","msg":"trace[2012974066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1090; }","duration":"937.266712ms","start":"2026-05-12T11:27:54.412762Z","end":"2026-05-12T11:27:55.350029Z","steps":["trace[2012974066] 'agreement among raft nodes before linearized reading' (duration: 937.170339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.350081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.412743Z","time spent":"937.327693ms","remote":"127.0.0.1:44722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-12T11:27:55.34952Z","caller":"traceutil/trace.go:171","msg":"trace[440632606] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.018251372s","start":"2026-05-12T11:27:54.331247Z","end":"2026-05-12T11:27:55.349499Z","steps":["trace[440632606] 'process raft request' (duration: 560.534018ms)","trace[440632606] 'compare' (duration: 456.822892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:55.350254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.964042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T11:27:55.350284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.331222Z","time spent":"1.018952618s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:27:55.350339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.750421ms","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-05-12T11:27:55.35036Z","caller":"traceutil/trace.go:171","msg":"trace[1438704776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"742.804962ms","start":"2026-05-12T11:27:54.607548Z","end":"2026-05-12T11:27:55.350353Z","steps":["trace[1438704776] 'agreement among raft nodes before linearized reading' (duration: 742.759421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.350382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.607528Z","time spent":"742.850733ms","remote":"127.0.0.1:40442","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-05-12T11:27:55.350506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.334196ms","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-05-12T11:27:55.350578Z","caller":"traceutil/trace.go:171","msg":"trace[1772841219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"877.439919ms","start":"2026-05-12T11:27:54.473126Z","end":"2026-05-12T11:27:55.350566Z","steps":["trace[1772841219] 'agreement among raft nodes before linearized reading' (duration: 877.278295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.350643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.473104Z","time spent":"877.528192ms","remote":"127.0.0.1:40442","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-05-12T11:27:55.350612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.131266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T11:27:55.350295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.937ms","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-05-12T11:27:55.350717Z","caller":"traceutil/trace.go:171","msg":"trace[19505765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"424.256298ms","start":"2026-05-12T11:27:54.926445Z","end":"2026-05-12T11:27:55.350701Z","steps":["trace[19505765] 'agreement among raft nodes before linearized reading' (duration: 424.023483ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:55.350745Z","caller":"traceutil/trace.go:171","msg":"trace[414634045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"511.410552ms","start":"2026-05-12T11:27:54.839316Z","end":"2026-05-12T11:27:55.350727Z","steps":["trace[414634045] 'agreement among raft nodes before linearized reading' (duration: 510.90502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.350765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.92644Z","time spent":"424.31664ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T11:27:55.350798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.839295Z","time spent":"511.490673ms","remote":"127.0.0.1:40442","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-05-12T11:27:55.350331Z","caller":"traceutil/trace.go:171","msg":"trace[1803705836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"424.077874ms","start":"2026-05-12T11:27:54.926237Z","end":"2026-05-12T11:27:55.350314Z","steps":["trace[1803705836] 'agreement among raft nodes before linearized reading' (duration: 423.741256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:55.350891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:54.926223Z","time spent":"424.656189ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T11:27:56.220039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464161087,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:27:56.67699Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322744184s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:27:56.677604Z","caller":"traceutil/trace.go:171","msg":"trace[1530137594] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"1.32344028s","start":"2026-05-12T11:27:55.354138Z","end":"2026-05-12T11:27:56.677579Z","steps":["trace[1530137594] 'process raft request' (duration: 1.323125032s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.678312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.354116Z","time spent":"1.323551783s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:27:56.701951Z","caller":"traceutil/trace.go:171","msg":"trace[1820562004] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"1.346343563s","start":"2026-05-12T11:27:55.355578Z","end":"2026-05-12T11:27:56.701922Z","steps":["trace[1820562004] 'process raft request' (duration: 1.346181969s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:56.702046Z","caller":"traceutil/trace.go:171","msg":"trace[1020362866] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"1.346899696s","start":"2026-05-12T11:27:55.355127Z","end":"2026-05-12T11:27:56.702027Z","steps":["trace[1020362866] 'process raft request' (duration: 1.346492816s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:56.702187Z","caller":"traceutil/trace.go:171","msg":"trace[1162064880] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.3462432s","start":"2026-05-12T11:27:55.355898Z","end":"2026-05-12T11:27:56.702141Z","steps":["trace[1162064880] 'process raft request' (duration: 1.345914342s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:56.702195Z","caller":"traceutil/trace.go:171","msg":"trace[634755346] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.345329238s","start":"2026-05-12T11:27:55.356843Z","end":"2026-05-12T11:27:56.702173Z","steps":["trace[634755346] 'process raft request' (duration: 1.34501068s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.702211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.355111Z","time spent":"1.346981058s","remote":"127.0.0.1:40442","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-05-12T11:27:56.702209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.355558Z","time spent":"1.346459415s","remote":"127.0.0.1:40442","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-05-12T11:27:56.70227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.355889Z","time spent":"1.346338442s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:27:56.702332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.356819Z","time spent":"1.345423899s","remote":"127.0.0.1:40442","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-05-12T11:27:56.727158Z","caller":"traceutil/trace.go:171","msg":"trace[1442558124] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1129; }","duration":"1.007248118s","start":"2026-05-12T11:27:55.719891Z","end":"2026-05-12T11:27:56.727139Z","steps":["trace[1442558124] 'read index received' (duration: 957.560418ms)","trace[1442558124] 'applied index is now lower than readState.Index' (duration: 49.68645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:56.727343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007440392s","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-05-12T11:27:56.727401Z","caller":"traceutil/trace.go:171","msg":"trace[1727940849] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1095; }","duration":"1.007547895s","start":"2026-05-12T11:27:55.719839Z","end":"2026-05-12T11:27:56.727386Z","steps":["trace[1727940849] 'agreement among raft nodes before linearized reading' (duration: 1.007436442s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.727449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.719816Z","time spent":"1.007621877s","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"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-05-12T11:27:56.727786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.269692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:27:56.727836Z","caller":"traceutil/trace.go:171","msg":"trace[836813316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"775.346424ms","start":"2026-05-12T11:27:55.952471Z","end":"2026-05-12T11:27:56.727818Z","steps":["trace[836813316] 'agreement among raft nodes before linearized reading' (duration: 775.266052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.72786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:55.952455Z","time spent":"775.399816ms","remote":"127.0.0.1:40190","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-05-12T11:27:56.727908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.833888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:27:56.727958Z","caller":"traceutil/trace.go:171","msg":"trace[1474907475] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1095; }","duration":"141.93264ms","start":"2026-05-12T11:27:56.586011Z","end":"2026-05-12T11:27:56.727943Z","steps":["trace[1474907475] 'agreement among raft nodes before linearized reading' (duration: 141.843538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.728101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.618605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:27:56.728143Z","caller":"traceutil/trace.go:171","msg":"trace[362922600] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1095; }","duration":"401.859161ms","start":"2026-05-12T11:27:56.326271Z","end":"2026-05-12T11:27:56.72813Z","steps":["trace[362922600] 'agreement among raft nodes before linearized reading' (duration: 401.626145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:56.728202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:27:56.326251Z","time spent":"401.940592ms","remote":"127.0.0.1:40616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-12T11:27:58.925449Z","caller":"traceutil/trace.go:171","msg":"trace[1350038857] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"218.224262ms","start":"2026-05-12T11:27:58.707205Z","end":"2026-05-12T11:27:58.92543Z","steps":["trace[1350038857] 'read index received' (duration: 217.996446ms)","trace[1350038857] 'applied index is now lower than readState.Index' (duration: 227.056µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:58.925504Z","caller":"traceutil/trace.go:171","msg":"trace[1198946975] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"239.531872ms","start":"2026-05-12T11:27:58.68594Z","end":"2026-05-12T11:27:58.925471Z","steps":["trace[1198946975] 'process raft request' (duration: 239.335147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:58.925689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.422576ms","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-05-12T11:27:58.925739Z","caller":"traceutil/trace.go:171","msg":"trace[692981262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"218.522979ms","start":"2026-05-12T11:27:58.707206Z","end":"2026-05-12T11:27:58.925729Z","steps":["trace[692981262] 'agreement among raft nodes before linearized reading' (duration: 218.361155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:58.92571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.485268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T11:27:58.925825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.246782ms","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-05-12T11:27:58.925863Z","caller":"traceutil/trace.go:171","msg":"trace[1380338761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"218.297743ms","start":"2026-05-12T11:27:58.707554Z","end":"2026-05-12T11:27:58.925852Z","steps":["trace[1380338761] 'agreement among raft nodes before linearized reading' (duration: 218.180059ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:58.92587Z","caller":"traceutil/trace.go:171","msg":"trace[1740313965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"218.673324ms","start":"2026-05-12T11:27:58.707178Z","end":"2026-05-12T11:27:58.925851Z","steps":["trace[1740313965] 'agreement among raft nodes before linearized reading' (duration: 218.367546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:58.926055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.746635ms","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-05-12T11:27:58.926141Z","caller":"traceutil/trace.go:171","msg":"trace[1482631615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"218.889079ms","start":"2026-05-12T11:27:58.707233Z","end":"2026-05-12T11:27:58.926123Z","steps":["trace[1482631615] 'agreement among raft nodes before linearized reading' (duration: 218.622262ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:59.601911Z","caller":"traceutil/trace.go:171","msg":"trace[1251303190] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"163.355133ms","start":"2026-05-12T11:27:59.438519Z","end":"2026-05-12T11:27:59.601874Z","steps":["trace[1251303190] 'process raft request' (duration: 163.114437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:01.245384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.491104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:28:01.245802Z","caller":"traceutil/trace.go:171","msg":"trace[897101924] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"216.616783ms","start":"2026-05-12T11:28:01.029143Z","end":"2026-05-12T11:28:01.24576Z","steps":["trace[897101924] 'process raft request' (duration: 85.511663ms)","trace[897101924] 'compare' (duration: 130.253748ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:01.246088Z","caller":"traceutil/trace.go:171","msg":"trace[1279993515] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"216.186641ms","start":"2026-05-12T11:28:01.02988Z","end":"2026-05-12T11:28:01.246067Z","steps":["trace[1279993515] 'process raft request' (duration: 215.884024ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:01.246258Z","caller":"traceutil/trace.go:171","msg":"trace[797430353] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"216.165201ms","start":"2026-05-12T11:28:01.030075Z","end":"2026-05-12T11:28:01.246241Z","steps":["trace[797430353] 'process raft request' (duration: 215.837733ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:01.24638Z","caller":"traceutil/trace.go:171","msg":"trace[1784422056] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"215.30824ms","start":"2026-05-12T11:28:01.031056Z","end":"2026-05-12T11:28:01.246364Z","steps":["trace[1784422056] 'process raft request' (duration: 214.948011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:01.654133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.402226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:01.654255Z","caller":"traceutil/trace.go:171","msg":"trace[1082135658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1108; }","duration":"287.55032ms","start":"2026-05-12T11:28:01.366655Z","end":"2026-05-12T11:28:01.654205Z","steps":["trace[1082135658] 'count revisions from in-memory index tree' (duration: 287.310024ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:01.809982Z","caller":"traceutil/trace.go:171","msg":"trace[989744123] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"202.439868ms","start":"2026-05-12T11:28:01.607512Z","end":"2026-05-12T11:28:01.809951Z","steps":["trace[989744123] 'process raft request' (duration: 202.266273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:02.080206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.039033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:28:02.080219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.839642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:02.080334Z","caller":"traceutil/trace.go:171","msg":"trace[982372588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"128.200917ms","start":"2026-05-12T11:28:01.952079Z","end":"2026-05-12T11:28:02.08028Z","steps":["trace[982372588] 'range keys from in-memory index tree' (duration: 127.922649ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:02.080341Z","caller":"traceutil/trace.go:171","msg":"trace[1675197688] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1109; }","duration":"141.033647ms","start":"2026-05-12T11:28:01.939286Z","end":"2026-05-12T11:28:02.08032Z","steps":["trace[1675197688] 'count revisions from in-memory index tree' (duration: 140.681678ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:03.376453Z","caller":"traceutil/trace.go:171","msg":"trace[1544274387] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"120.013388ms","start":"2026-05-12T11:28:03.256417Z","end":"2026-05-12T11:28:03.376431Z","steps":["trace[1544274387] 'process raft request' (duration: 119.746722ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:03.376535Z","caller":"traceutil/trace.go:171","msg":"trace[717449746] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"120.036038ms","start":"2026-05-12T11:28:03.256486Z","end":"2026-05-12T11:28:03.376522Z","steps":["trace[717449746] 'process raft request' (duration: 119.889825ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:03.376451Z","caller":"traceutil/trace.go:171","msg":"trace[382864482] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"119.961497ms","start":"2026-05-12T11:28:03.256463Z","end":"2026-05-12T11:28:03.376425Z","steps":["trace[382864482] 'process raft request' (duration: 119.821143ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:13.204714Z","caller":"traceutil/trace.go:171","msg":"trace[1016638051] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"112.243415ms","start":"2026-05-12T11:28:13.092442Z","end":"2026-05-12T11:28:13.204685Z","steps":["trace[1016638051] 'process raft request' (duration: 111.999389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:13.445442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.434118ms","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-05-12T11:28:13.445501Z","caller":"traceutil/trace.go:171","msg":"trace[540365319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"109.53758ms","start":"2026-05-12T11:28:13.33595Z","end":"2026-05-12T11:28:13.445487Z","steps":["trace[540365319] 'range keys from in-memory index tree' (duration: 109.332945ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:14.828631Z","caller":"traceutil/trace.go:171","msg":"trace[888266808] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"466.407274ms","start":"2026-05-12T11:28:14.362197Z","end":"2026-05-12T11:28:14.828604Z","steps":["trace[888266808] 'process raft request' (duration: 466.255311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:14.828781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:14.362175Z","time spent":"466.532558ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:28:15.226662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.467853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:15.226844Z","caller":"traceutil/trace.go:171","msg":"trace[2010602406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"273.74563ms","start":"2026-05-12T11:28:14.953079Z","end":"2026-05-12T11:28:15.226824Z","steps":["trace[2010602406] 'range keys from in-memory index tree' (duration: 273.399351ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:27.645928Z","caller":"traceutil/trace.go:171","msg":"trace[644970751] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"116.981833ms","start":"2026-05-12T11:28:27.528917Z","end":"2026-05-12T11:28:27.645899Z","steps":["trace[644970751] 'read index received' (duration: 116.787168ms)","trace[644970751] 'applied index is now lower than readState.Index' (duration: 193.175µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:27.645998Z","caller":"traceutil/trace.go:171","msg":"trace[1446954298] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"118.375598ms","start":"2026-05-12T11:28:27.527602Z","end":"2026-05-12T11:28:27.645977Z","steps":["trace[1446954298] 'process raft request' (duration: 118.106512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:27.64617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239819ms","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-05-12T11:28:27.646202Z","caller":"traceutil/trace.go:171","msg":"trace[234530823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"117.292911ms","start":"2026-05-12T11:28:27.5289Z","end":"2026-05-12T11:28:27.646193Z","steps":["trace[234530823] 'agreement among raft nodes before linearized reading' (duration: 117.155188ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:27.816363Z","caller":"traceutil/trace.go:171","msg":"trace[1491091589] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"163.934983ms","start":"2026-05-12T11:28:27.652399Z","end":"2026-05-12T11:28:27.816334Z","steps":["trace[1491091589] 'process raft request' (duration: 163.850051ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:27.816392Z","caller":"traceutil/trace.go:171","msg":"trace[1082276438] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"165.517023ms","start":"2026-05-12T11:28:27.650837Z","end":"2026-05-12T11:28:27.816354Z","steps":["trace[1082276438] 'process raft request' (duration: 165.261077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:32.17222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464161501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:28:32.673017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464161501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:28:33.174067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732704464161501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:28:33.613421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.949873743s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:28:33.613884Z","caller":"traceutil/trace.go:171","msg":"trace[2056717320] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.950434667s","start":"2026-05-12T11:28:31.663425Z","end":"2026-05-12T11:28:33.61386Z","steps":["trace[2056717320] 'process raft request' (duration: 1.950290603s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.614135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:31.663404Z","time spent":"1.950550969s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:28:33.940406Z","caller":"traceutil/trace.go:171","msg":"trace[256881989] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.932327229s","start":"2026-05-12T11:28:32.008057Z","end":"2026-05-12T11:28:33.940384Z","steps":["trace[256881989] 'process raft request' (duration: 1.925167601s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.940544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:32.00804Z","time spent":"1.932428372s","remote":"127.0.0.1:40442","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-05-12T11:28:33.940866Z","caller":"traceutil/trace.go:171","msg":"trace[29058033] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"518.249917ms","start":"2026-05-12T11:28:33.422601Z","end":"2026-05-12T11:28:33.94085Z","steps":["trace[29058033] 'process raft request' (duration: 517.740474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.940951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:33.422572Z","time spent":"518.336959ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:28:33.941008Z","caller":"traceutil/trace.go:171","msg":"trace[1209775232] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1323; }","duration":"2.269096038s","start":"2026-05-12T11:28:31.671706Z","end":"2026-05-12T11:28:33.940802Z","steps":["trace[1209775232] 'read index received' (duration: 1.941959467s)","trace[1209775232] 'applied index is now lower than readState.Index' (duration: 327.135421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:28:33.941073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.269340984s","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-05-12T11:28:33.941134Z","caller":"traceutil/trace.go:171","msg":"trace[2117680325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"2.269443456s","start":"2026-05-12T11:28:31.671677Z","end":"2026-05-12T11:28:33.941121Z","steps":["trace[2117680325] 'agreement among raft nodes before linearized reading' (duration: 2.269255852s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.941171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:31.671666Z","time spent":"2.269499948s","remote":"127.0.0.1:40442","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-05-12T11:28:33.941179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.066682422s","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-05-12T11:28:33.94125Z","caller":"traceutil/trace.go:171","msg":"trace[843394325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"2.066780174s","start":"2026-05-12T11:28:31.874454Z","end":"2026-05-12T11:28:33.941234Z","steps":["trace[843394325] 'agreement among raft nodes before linearized reading' (duration: 2.066629831s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.941254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.680477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T11:28:33.941294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:31.874442Z","time spent":"2.066843165s","remote":"127.0.0.1:40442","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":"info","ts":"2026-05-12T11:28:33.941306Z","caller":"traceutil/trace.go:171","msg":"trace[1251773637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1277; }","duration":"632.769ms","start":"2026-05-12T11:28:33.308524Z","end":"2026-05-12T11:28:33.941293Z","steps":["trace[1251773637] 'agreement among raft nodes before linearized reading' (duration: 632.637176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.941345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:33.308507Z","time spent":"632.829641ms","remote":"127.0.0.1:40442","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-05-12T11:28:33.941369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990544789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:33.941387Z","caller":"traceutil/trace.go:171","msg":"trace[1199582818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"1.99058346s","start":"2026-05-12T11:28:31.950798Z","end":"2026-05-12T11:28:33.941381Z","steps":["trace[1199582818] 'agreement among raft nodes before linearized reading' (duration: 1.990555679s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.941401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:31.950787Z","time spent":"1.990611681s","remote":"127.0.0.1:40190","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-05-12T11:28:33.941484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.052180604s","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-05-12T11:28:33.941521Z","caller":"traceutil/trace.go:171","msg":"trace[543401043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"2.052236645s","start":"2026-05-12T11:28:31.889275Z","end":"2026-05-12T11:28:33.941512Z","steps":["trace[543401043] 'agreement among raft nodes before linearized reading' (duration: 2.052145173s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:33.941548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:31.889266Z","time spent":"2.052276226s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T11:28:35.13952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.845326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:28:35.139703Z","caller":"traceutil/trace.go:171","msg":"trace[1010766056] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"187.907247ms","start":"2026-05-12T11:28:34.951778Z","end":"2026-05-12T11:28:35.139685Z","steps":["trace[1010766056] 'read index received' (duration: 43.101µs)","trace[1010766056] 'applied index is now lower than readState.Index' (duration: 187.862586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:28:35.139817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.03346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:35.139817Z","caller":"traceutil/trace.go:171","msg":"trace[2102259276] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"441.677104ms","start":"2026-05-12T11:28:34.698096Z","end":"2026-05-12T11:28:35.139773Z","steps":["trace[2102259276] 'process raft request' (duration: 160.487389ms)","trace[2102259276] 'compare' (duration: 280.532358ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:35.139916Z","caller":"traceutil/trace.go:171","msg":"trace[1562976278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"188.128192ms","start":"2026-05-12T11:28:34.951734Z","end":"2026-05-12T11:28:35.139862Z","steps":["trace[1562976278] 'agreement among raft nodes before linearized reading' (duration: 188.03966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:35.139971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:34.698071Z","time spent":"441.815847ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:28:35.920427Z","caller":"traceutil/trace.go:171","msg":"trace[329859372] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"569.159026ms","start":"2026-05-12T11:28:35.351242Z","end":"2026-05-12T11:28:35.920401Z","steps":["trace[329859372] 'process raft request' (duration: 568.972822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:35.920572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:35.351216Z","time spent":"569.282529ms","remote":"127.0.0.1:40364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:28:36.059605Z","caller":"traceutil/trace.go:171","msg":"trace[1519051034] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1337; }","duration":"441.275943ms","start":"2026-05-12T11:28:35.618319Z","end":"2026-05-12T11:28:36.059595Z","steps":["trace[1519051034] 'read index received' (duration: 301.944867ms)","trace[1519051034] 'applied index is now lower than readState.Index' (duration: 139.330036ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:36.059623Z","caller":"traceutil/trace.go:171","msg":"trace[874185260] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"526.887481ms","start":"2026-05-12T11:28:35.532699Z","end":"2026-05-12T11:28:36.059586Z","steps":["trace[874185260] 'process raft request' (duration: 526.715557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:36.059771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.444927ms","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-05-12T11:28:36.059798Z","caller":"traceutil/trace.go:171","msg":"trace[567462950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"441.519479ms","start":"2026-05-12T11:28:35.61827Z","end":"2026-05-12T11:28:36.05979Z","steps":["trace[567462950] 'agreement among raft nodes before linearized reading' (duration: 441.411296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:36.059788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:35.532681Z","time spent":"527.009094ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:28:36.059818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:35.618251Z","time spent":"441.562481ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T11:28:36.059948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.74728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:36.059995Z","caller":"traceutil/trace.go:171","msg":"trace[1397727276] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1291; }","duration":"387.822261ms","start":"2026-05-12T11:28:35.67216Z","end":"2026-05-12T11:28:36.059982Z","steps":["trace[1397727276] 'agreement among raft nodes before linearized reading' (duration: 387.747519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:36.060031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:35.672141Z","time spent":"387.882333ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-12T11:28:36.059564Z","caller":"traceutil/trace.go:171","msg":"trace[672581023] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"114.162224ms","start":"2026-05-12T11:28:35.945385Z","end":"2026-05-12T11:28:36.059547Z","steps":["trace[672581023] 'process raft request' (duration: 114.128993ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:46.928648Z","caller":"traceutil/trace.go:171","msg":"trace[576799728] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"190.703906ms","start":"2026-05-12T11:28:46.737921Z","end":"2026-05-12T11:28:46.928625Z","steps":["trace[576799728] 'read index received' (duration: 190.520622ms)","trace[576799728] 'applied index is now lower than readState.Index' (duration: 182.014µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:28:46.928836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.900481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-12T11:28:46.928875Z","caller":"traceutil/trace.go:171","msg":"trace[268190923] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1368; }","duration":"190.958472ms","start":"2026-05-12T11:28:46.737905Z","end":"2026-05-12T11:28:46.928864Z","steps":["trace[268190923] 'agreement among raft nodes before linearized reading' (duration: 190.809709ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:46.929075Z","caller":"traceutil/trace.go:171","msg":"trace[141934237] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"592.437232ms","start":"2026-05-12T11:28:46.336531Z","end":"2026-05-12T11:28:46.928968Z","steps":["trace[141934237] 'process raft request' (duration: 591.94309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:46.929303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:46.336504Z","time spent":"592.668188ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:28:48.335015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.90408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:48.335124Z","caller":"traceutil/trace.go:171","msg":"trace[783267135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"383.027124ms","start":"2026-05-12T11:28:47.952059Z","end":"2026-05-12T11:28:48.335086Z","steps":["trace[783267135] 'range keys from in-memory index tree' (duration: 382.793718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:48.335182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:47.952045Z","time spent":"383.124136ms","remote":"127.0.0.1:40190","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-05-12T11:28:48.335045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.56788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-12T11:28:48.335272Z","caller":"traceutil/trace.go:171","msg":"trace[2121937272] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1369; }","duration":"464.831217ms","start":"2026-05-12T11:28:47.870419Z","end":"2026-05-12T11:28:48.33525Z","steps":["trace[2121937272] 'range keys from in-memory index tree' (duration: 464.272183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:48.335325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:47.870397Z","time spent":"464.911608ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-12T11:28:48.336176Z","caller":"traceutil/trace.go:171","msg":"trace[11090863] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"211.430509ms","start":"2026-05-12T11:28:48.124726Z","end":"2026-05-12T11:28:48.336157Z","steps":["trace[11090863] 'read index received' (duration: 207.042381ms)","trace[11090863] 'applied index is now lower than readState.Index' (duration: 4.386888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:28:48.336654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.55959ms","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-05-12T11:28:48.336745Z","caller":"traceutil/trace.go:171","msg":"trace[959906695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"168.698453ms","start":"2026-05-12T11:28:48.168025Z","end":"2026-05-12T11:28:48.336723Z","steps":["trace[959906695] 'agreement among raft nodes before linearized reading' (duration: 168.423815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:48.336747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.861323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T11:28:48.336744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.166567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T11:28:48.336826Z","caller":"traceutil/trace.go:171","msg":"trace[1423700551] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"217.877019ms","start":"2026-05-12T11:28:48.118908Z","end":"2026-05-12T11:28:48.336785Z","steps":["trace[1423700551] 'process raft request' (duration: 212.917286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:48.336942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.193446ms","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-05-12T11:28:48.336826Z","caller":"traceutil/trace.go:171","msg":"trace[538406026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"163.262548ms","start":"2026-05-12T11:28:48.173537Z","end":"2026-05-12T11:28:48.336799Z","steps":["trace[538406026] 'agreement among raft nodes before linearized reading' (duration: 163.099384ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:48.336862Z","caller":"traceutil/trace.go:171","msg":"trace[1049889576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"165.930024ms","start":"2026-05-12T11:28:48.170861Z","end":"2026-05-12T11:28:48.336791Z","steps":["trace[1049889576] 'agreement among raft nodes before linearized reading' (duration: 165.791351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:48.337086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.359942ms","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-05-12T11:28:48.33714Z","caller":"traceutil/trace.go:171","msg":"trace[1020420182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"212.441194ms","start":"2026-05-12T11:28:48.124684Z","end":"2026-05-12T11:28:48.337125Z","steps":["trace[1020420182] 'agreement among raft nodes before linearized reading' (duration: 212.300561ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:48.336977Z","caller":"traceutil/trace.go:171","msg":"trace[636173594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1370; }","duration":"167.239367ms","start":"2026-05-12T11:28:48.16973Z","end":"2026-05-12T11:28:48.336969Z","steps":["trace[636173594] 'agreement among raft nodes before linearized reading' (duration: 167.150434ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:14.227988Z","caller":"traceutil/trace.go:171","msg":"trace[545034286] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"116.04173ms","start":"2026-05-12T11:29:14.111921Z","end":"2026-05-12T11:29:14.227963Z","steps":["trace[545034286] 'process raft request' (duration: 115.914947ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:54.5072Z","caller":"traceutil/trace.go:171","msg":"trace[1606940529] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"103.911049ms","start":"2026-05-12T11:29:54.403265Z","end":"2026-05-12T11:29:54.507176Z","steps":["trace[1606940529] 'process raft request' (duration: 103.789136ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:59.684213Z","caller":"traceutil/trace.go:171","msg":"trace[2011436860] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"168.6195ms","start":"2026-05-12T11:29:59.515564Z","end":"2026-05-12T11:29:59.684183Z","steps":["trace[2011436860] 'process raft request' (duration: 168.442696ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:01.134055Z","caller":"traceutil/trace.go:171","msg":"trace[1938799336] linearizableReadLoop","detail":"{readStateIndex:1771; appliedIndex:1770; }","duration":"371.085498ms","start":"2026-05-12T11:30:00.762938Z","end":"2026-05-12T11:30:01.134024Z","steps":["trace[1938799336] 'read index received' (duration: 326.894505ms)","trace[1938799336] 'applied index is now lower than readState.Index' (duration: 44.189273ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:01.134082Z","caller":"traceutil/trace.go:171","msg":"trace[373273999] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"407.642592ms","start":"2026-05-12T11:30:00.726405Z","end":"2026-05-12T11:30:01.134048Z","steps":["trace[373273999] 'process raft request' (duration: 363.431208ms)","trace[373273999] 'compare' (duration: 43.887736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:01.134397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:00.726371Z","time spent":"407.789215ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:30:01.134442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.646535ms","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-05-12T11:30:01.134487Z","caller":"traceutil/trace.go:171","msg":"trace[966804863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1704; }","duration":"283.737317ms","start":"2026-05-12T11:30:00.850736Z","end":"2026-05-12T11:30:01.134473Z","steps":["trace[966804863] 'agreement among raft nodes before linearized reading' (duration: 283.561633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:01.134364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.412356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T11:30:01.134556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.53249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:30:01.134581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.419726ms","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-05-12T11:30:01.134634Z","caller":"traceutil/trace.go:171","msg":"trace[609192685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1704; }","duration":"183.656273ms","start":"2026-05-12T11:30:00.95096Z","end":"2026-05-12T11:30:01.134616Z","steps":["trace[609192685] 'agreement among raft nodes before linearized reading' (duration: 183.53469ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:01.134587Z","caller":"traceutil/trace.go:171","msg":"trace[1809796177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1704; }","duration":"371.684972ms","start":"2026-05-12T11:30:00.762883Z","end":"2026-05-12T11:30:01.134568Z","steps":["trace[1809796177] 'agreement among raft nodes before linearized reading' (duration: 371.334044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:01.134844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:00.762865Z","time spent":"371.959839ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T11:30:01.13472Z","caller":"traceutil/trace.go:171","msg":"trace[1446516303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1704; }","duration":"352.530229ms","start":"2026-05-12T11:30:00.782131Z","end":"2026-05-12T11:30:01.134661Z","steps":["trace[1446516303] 'agreement among raft nodes before linearized reading' (duration: 352.340714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:01.135129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:00.782116Z","time spent":"352.99837ms","remote":"127.0.0.1:40442","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-05-12T11:30:01.134775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.734428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-12T11:30:01.134443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.700562ms","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-05-12T11:30:01.135461Z","caller":"traceutil/trace.go:171","msg":"trace[122655597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1704; }","duration":"355.806879ms","start":"2026-05-12T11:30:00.779637Z","end":"2026-05-12T11:30:01.135444Z","steps":["trace[122655597] 'agreement among raft nodes before linearized reading' (duration: 354.63875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:01.135523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:00.779583Z","time spent":"355.923962ms","remote":"127.0.0.1:40442","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-05-12T11:30:01.135754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.015185ms","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-05-12T11:30:01.135803Z","caller":"traceutil/trace.go:171","msg":"trace[861103041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1704; }","duration":"354.127287ms","start":"2026-05-12T11:30:00.781665Z","end":"2026-05-12T11:30:01.135792Z","steps":["trace[861103041] 'agreement among raft nodes before linearized reading' (duration: 353.079822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:01.135838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:00.781644Z","time spent":"354.186519ms","remote":"127.0.0.1:40442","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":"info","ts":"2026-05-12T11:30:01.136532Z","caller":"traceutil/trace.go:171","msg":"trace[1483343197] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1704; }","duration":"280.24644ms","start":"2026-05-12T11:30:00.854971Z","end":"2026-05-12T11:30:01.135218Z","steps":["trace[1483343197] 'agreement among raft nodes before linearized reading' (duration: 279.726958ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:05.46362Z","caller":"traceutil/trace.go:171","msg":"trace[1833309032] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"162.900829ms","start":"2026-05-12T11:30:05.300679Z","end":"2026-05-12T11:30:05.46358Z","steps":["trace[1833309032] 'process raft request' (duration: 162.726185ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:05.527179Z","caller":"traceutil/trace.go:171","msg":"trace[535011354] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"226.140203ms","start":"2026-05-12T11:30:05.301007Z","end":"2026-05-12T11:30:05.527147Z","steps":["trace[535011354] 'process raft request' (duration: 225.932448ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:05.527618Z","caller":"traceutil/trace.go:171","msg":"trace[586345690] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"225.970058ms","start":"2026-05-12T11:30:05.301614Z","end":"2026-05-12T11:30:05.527584Z","steps":["trace[586345690] 'process raft request' (duration: 225.485996ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:05.528077Z","caller":"traceutil/trace.go:171","msg":"trace[1558578519] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"226.06305ms","start":"2026-05-12T11:30:05.301968Z","end":"2026-05-12T11:30:05.528031Z","steps":["trace[1558578519] 'process raft request' (duration: 225.614109ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:05.528019Z","caller":"traceutil/trace.go:171","msg":"trace[90157966] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"225.601079ms","start":"2026-05-12T11:30:05.302372Z","end":"2026-05-12T11:30:05.527973Z","steps":["trace[90157966] 'process raft request' (duration: 225.447495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:05.990717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.935098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T11:30:05.991028Z","caller":"traceutil/trace.go:171","msg":"trace[288279604] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"250.624218ms","start":"2026-05-12T11:30:05.740368Z","end":"2026-05-12T11:30:05.990992Z","steps":["trace[288279604] 'process raft request' (duration: 250.436614ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:07.478407Z","caller":"traceutil/trace.go:171","msg":"trace[2074053246] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"117.428264ms","start":"2026-05-12T11:30:07.360951Z","end":"2026-05-12T11:30:07.478379Z","steps":["trace[2074053246] 'process raft request' (duration: 117.199518ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:07.673793Z","caller":"traceutil/trace.go:171","msg":"trace[455241457] linearizableReadLoop","detail":"{readStateIndex:1801; appliedIndex:1800; }","duration":"140.926655ms","start":"2026-05-12T11:30:07.532847Z","end":"2026-05-12T11:30:07.673774Z","steps":["trace[455241457] 'read index received' (duration: 140.72803ms)","trace[455241457] 'applied index is now lower than readState.Index' (duration: 197.735µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:07.674106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.662074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T11:30:07.674119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.254403ms","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-05-12T11:30:07.674124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.272843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T11:30:07.674175Z","caller":"traceutil/trace.go:171","msg":"trace[1697048464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1733; }","duration":"141.370865ms","start":"2026-05-12T11:30:07.53279Z","end":"2026-05-12T11:30:07.67416Z","steps":["trace[1697048464] 'agreement among raft nodes before linearized reading' (duration: 141.131429ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:07.674205Z","caller":"traceutil/trace.go:171","msg":"trace[420419437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1733; }","duration":"141.390846ms","start":"2026-05-12T11:30:07.532803Z","end":"2026-05-12T11:30:07.674193Z","steps":["trace[420419437] 'agreement among raft nodes before linearized reading' (duration: 141.105159ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:07.67415Z","caller":"traceutil/trace.go:171","msg":"trace[1852720440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1733; }","duration":"139.742335ms","start":"2026-05-12T11:30:07.534399Z","end":"2026-05-12T11:30:07.674142Z","steps":["trace[1852720440] 'agreement among raft nodes before linearized reading' (duration: 139.643582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:07.674128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.531965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-12T11:30:07.674393Z","caller":"traceutil/trace.go:171","msg":"trace[1526374587] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1733; }","duration":"140.840954ms","start":"2026-05-12T11:30:07.533541Z","end":"2026-05-12T11:30:07.674382Z","steps":["trace[1526374587] 'agreement among raft nodes before linearized reading' (duration: 140.461673ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:07.674493Z","caller":"traceutil/trace.go:171","msg":"trace[2069067825] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"191.165988ms","start":"2026-05-12T11:30:07.482641Z","end":"2026-05-12T11:30:07.673807Z","steps":["trace[2069067825] 'process raft request' (duration: 190.994253ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:08.098989Z","caller":"traceutil/trace.go:171","msg":"trace[281332793] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"419.793692ms","start":"2026-05-12T11:30:07.679151Z","end":"2026-05-12T11:30:08.098945Z","steps":["trace[281332793] 'process raft request' (duration: 329.242413ms)","trace[281332793] 'compare' (duration: 90.287463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:08.099184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:07.679123Z","time spent":"419.959987ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:30:08.105243Z","caller":"traceutil/trace.go:171","msg":"trace[810530417] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"425.120353ms","start":"2026-05-12T11:30:07.680104Z","end":"2026-05-12T11:30:08.105225Z","steps":["trace[810530417] 'process raft request' (duration: 424.904608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:08.105346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:07.680097Z","time spent":"425.193645ms","remote":"127.0.0.1:40442","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-05-12T11:30:08.105369Z","caller":"traceutil/trace.go:171","msg":"trace[1529918986] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1801; }","duration":"153.255389ms","start":"2026-05-12T11:30:07.952092Z","end":"2026-05-12T11:30:08.105347Z","steps":["trace[1529918986] 'read index received' (duration: 56.320203ms)","trace[1529918986] 'applied index is now lower than readState.Index' (duration: 96.932806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:08.105471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.379043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:30:08.105531Z","caller":"traceutil/trace.go:171","msg":"trace[1374158676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1737; }","duration":"153.497505ms","start":"2026-05-12T11:30:07.952018Z","end":"2026-05-12T11:30:08.105516Z","steps":["trace[1374158676] 'agreement among raft nodes before linearized reading' (duration: 153.395413ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:08.105811Z","caller":"traceutil/trace.go:171","msg":"trace[1791505812] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"425.511743ms","start":"2026-05-12T11:30:07.680242Z","end":"2026-05-12T11:30:08.105754Z","steps":["trace[1791505812] 'process raft request' (duration: 424.926309ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:08.105274Z","caller":"traceutil/trace.go:171","msg":"trace[1018525867] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"425.067442ms","start":"2026-05-12T11:30:07.680145Z","end":"2026-05-12T11:30:08.105213Z","steps":["trace[1018525867] 'process raft request' (duration: 424.96895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:08.106141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:07.680141Z","time spent":"425.918224ms","remote":"127.0.0.1:40442","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-05-12T11:30:08.106206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:07.680218Z","time spent":"425.753399ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:30:09.605134Z","caller":"traceutil/trace.go:171","msg":"trace[208769651] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"118.886531ms","start":"2026-05-12T11:30:09.486221Z","end":"2026-05-12T11:30:09.605107Z","steps":["trace[208769651] 'process raft request' (duration: 118.718047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:10.921029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.834111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:30:10.921129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.327344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:30:10.921195Z","caller":"traceutil/trace.go:171","msg":"trace[2038494420] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1747; }","duration":"107.443818ms","start":"2026-05-12T11:30:10.813732Z","end":"2026-05-12T11:30:10.921176Z","steps":["trace[2038494420] 'count revisions from in-memory index tree' (duration: 107.252352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:10.921374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.881348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T11:30:10.921422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.489335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:30:10.921442Z","caller":"traceutil/trace.go:171","msg":"trace[1572391331] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1747; }","duration":"104.551236ms","start":"2026-05-12T11:30:10.816885Z","end":"2026-05-12T11:30:10.921436Z","steps":["trace[1572391331] 'count revisions from in-memory index tree' (duration: 104.405793ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:10.921445Z","caller":"traceutil/trace.go:171","msg":"trace[676348029] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1747; }","duration":"553.982281ms","start":"2026-05-12T11:30:10.36744Z","end":"2026-05-12T11:30:10.921423Z","steps":["trace[676348029] 'count revisions from in-memory index tree' (duration: 553.769076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:10.921482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:10.367417Z","time spent":"554.054643ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-05-12T11:30:10.92113Z","caller":"traceutil/trace.go:171","msg":"trace[875824803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1747; }","duration":"594.987135ms","start":"2026-05-12T11:30:10.326106Z","end":"2026-05-12T11:30:10.921093Z","steps":["trace[875824803] 'count revisions from in-memory index tree' (duration: 594.721408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:10.921611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:10.326082Z","time spent":"595.519858ms","remote":"127.0.0.1:44708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-12T11:30:10.921825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.760696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-12T11:30:10.921919Z","caller":"traceutil/trace.go:171","msg":"trace[2140049319] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1747; }","duration":"365.908451ms","start":"2026-05-12T11:30:10.555989Z","end":"2026-05-12T11:30:10.921898Z","steps":["trace[2140049319] 'range keys from in-memory index tree' (duration: 365.536442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:10.922033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:30:10.555968Z","time spent":"366.007862ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T11:30:11.49395Z","caller":"traceutil/trace.go:171","msg":"trace[639769969] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"246.695161ms","start":"2026-05-12T11:30:11.247229Z","end":"2026-05-12T11:30:11.493924Z","steps":["trace[639769969] 'process raft request' (duration: 246.522037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:26.380749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.934166ms","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-05-12T11:30:26.380837Z","caller":"traceutil/trace.go:171","msg":"trace[2059697545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1815; }","duration":"156.103621ms","start":"2026-05-12T11:30:26.224713Z","end":"2026-05-12T11:30:26.380816Z","steps":["trace[2059697545] 'range keys from in-memory index tree' (duration: 155.726071ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.49325Z","caller":"traceutil/trace.go:171","msg":"trace[1154492570] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"104.10175ms","start":"2026-05-12T11:30:26.389122Z","end":"2026-05-12T11:30:26.493224Z","steps":["trace[1154492570] 'process raft request' (duration: 104.027308ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.493271Z","caller":"traceutil/trace.go:171","msg":"trace[1361805785] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"106.226983ms","start":"2026-05-12T11:30:26.387018Z","end":"2026-05-12T11:30:26.493245Z","steps":["trace[1361805785] 'process raft request' (duration: 105.764351ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.493607Z","caller":"traceutil/trace.go:171","msg":"trace[1325549709] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"103.815324ms","start":"2026-05-12T11:30:26.389723Z","end":"2026-05-12T11:30:26.493538Z","steps":["trace[1325549709] 'process raft request' (duration: 103.464365ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.643068Z","caller":"traceutil/trace.go:171","msg":"trace[354747894] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"118.071655ms","start":"2026-05-12T11:30:26.52496Z","end":"2026-05-12T11:30:26.643032Z","steps":["trace[354747894] 'process raft request' (duration: 101.425065ms)","trace[354747894] 'compare' (duration: 16.506186ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:50.079968Z","caller":"traceutil/trace.go:171","msg":"trace[1813390382] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"128.826879ms","start":"2026-05-12T11:30:49.951116Z","end":"2026-05-12T11:30:50.079943Z","steps":["trace[1813390382] 'read index received' (duration: 85.731239ms)","trace[1813390382] 'applied index is now lower than readState.Index' (duration: 43.09448ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:50.08003Z","caller":"traceutil/trace.go:171","msg":"trace[1261471607] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"182.498059ms","start":"2026-05-12T11:30:49.8975Z","end":"2026-05-12T11:30:50.079998Z","steps":["trace[1261471607] 'process raft request' (duration: 139.397109ms)","trace[1261471607] 'compare' (duration: 42.935856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:50.080084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.952662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:30:50.080116Z","caller":"traceutil/trace.go:171","msg":"trace[637640385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1925; }","duration":"129.031874ms","start":"2026-05-12T11:30:49.951076Z","end":"2026-05-12T11:30:50.080108Z","steps":["trace[637640385] 'agreement among raft nodes before linearized reading' (duration: 128.957592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:31:24.955584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.0764ms","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-05-12T11:31:24.955707Z","caller":"traceutil/trace.go:171","msg":"trace[674692551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2086; }","duration":"110.238904ms","start":"2026-05-12T11:31:24.845453Z","end":"2026-05-12T11:31:24.955692Z","steps":["trace[674692551] 'range keys from in-memory index tree' (duration: 109.926546ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:32:01.935671Z","caller":"traceutil/trace.go:171","msg":"trace[2083027667] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"109.644029ms","start":"2026-05-12T11:32:01.826008Z","end":"2026-05-12T11:32:01.935652Z","steps":["trace[2083027667] 'process raft request' (duration: 109.529917ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:32:06.696931Z","caller":"traceutil/trace.go:171","msg":"trace[1143716061] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"148.181358ms","start":"2026-05-12T11:32:06.548724Z","end":"2026-05-12T11:32:06.696906Z","steps":["trace[1143716061] 'process raft request' (duration: 76.563174ms)","trace[1143716061] 'compare' (duration: 71.505741ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:32:07.362053Z","caller":"traceutil/trace.go:171","msg":"trace[88711848] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"317.475216ms","start":"2026-05-12T11:32:07.044554Z","end":"2026-05-12T11:32:07.362029Z","steps":["trace[88711848] 'process raft request' (duration: 317.333782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:32:07.362218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:32:07.044521Z","time spent":"317.603798ms","remote":"127.0.0.1:40442","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-05-12T11:32:07.427412Z","caller":"traceutil/trace.go:171","msg":"trace[1262754282] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"382.362322ms","start":"2026-05-12T11:32:07.045026Z","end":"2026-05-12T11:32:07.427389Z","steps":["trace[1262754282] 'process raft request' (duration: 382.214219ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:32:07.427562Z","caller":"traceutil/trace.go:171","msg":"trace[1847454789] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2401; }","duration":"180.135885ms","start":"2026-05-12T11:32:07.247408Z","end":"2026-05-12T11:32:07.427544Z","steps":["trace[1847454789] 'read index received' (duration: 114.435108ms)","trace[1847454789] 'applied index is now lower than readState.Index' (duration: 65.698477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:32:07.427615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:32:07.045001Z","time spent":"382.475916ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:32:07.427634Z","caller":"traceutil/trace.go:171","msg":"trace[1560815043] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"381.543933ms","start":"2026-05-12T11:32:07.046072Z","end":"2026-05-12T11:32:07.427616Z","steps":["trace[1560815043] 'process raft request' (duration: 381.278586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:32:07.427719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:32:07.046057Z","time spent":"381.621664ms","remote":"127.0.0.1:40442","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-05-12T11:32:07.427971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.566856ms","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-05-12T11:32:07.428095Z","caller":"traceutil/trace.go:171","msg":"trace[1988913396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2311; }","duration":"180.73738ms","start":"2026-05-12T11:32:07.247341Z","end":"2026-05-12T11:32:07.428078Z","steps":["trace[1988913396] 'agreement among raft nodes before linearized reading' (duration: 180.310299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:32:07.805552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.96415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:32:07.805844Z","caller":"traceutil/trace.go:171","msg":"trace[1263112632] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"316.4411ms","start":"2026-05-12T11:32:07.489385Z","end":"2026-05-12T11:32:07.805826Z","steps":["trace[1263112632] 'process raft request' (duration: 149.132191ms)","trace[1263112632] 'compare' (duration: 166.718414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:32:07.805958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:32:07.489366Z","time spent":"316.553273ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:32:07.806154Z","caller":"traceutil/trace.go:171","msg":"trace[228348810] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"241.510746ms","start":"2026-05-12T11:32:07.564624Z","end":"2026-05-12T11:32:07.806135Z","steps":["trace[228348810] 'process raft request' (duration: 241.151286ms)"],"step_count":1}