{"level":"warn","ts":"2026-03-12T19:09:28.871759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-12T19:09:28.871827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-03-12T19:09:28.871884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-12T19:09:28.871896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-12T19:09:28.871913Z","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-03-12T19:09:28.872496Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-12T19:09:28.872564Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-03-12T19:09:29.273282Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"400.516891ms"} {"level":"info","ts":"2026-03-12T19:09:29.472717Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-12T19:09:29.472892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-12T19:09:29.472929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-12T19:09:29.472949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-12T19:09:29.472968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-12T19:09:29.473026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-12T19:09:29.505914Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-12T19:09:29.540099Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-12T19:09:29.644818Z","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-03-12T19:09:29.656439Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-12T19:09:29.656864Z","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-03-12T19:09:29.656867Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-12T19:09:29.658154Z","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-03-12T19:09:29.658365Z","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-03-12T19:09:29.661782Z","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-03-12T19:09:29.662015Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-12T19:09:29.662097Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-12T19:09:29.662098Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-12T19:09:29.662167Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-12T19:09:29.665854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-12T19:09:29.66602Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-12T19:09:29.783619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-12T19:09:29.783686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-12T19:09:29.783706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-12T19:09:29.783723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-12T19:09:29.783733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-12T19:09:29.783746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-12T19:09:29.783757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-12T19:09:29.858032Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-12T19:09:29.858074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:09:29.858097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-12T19:09:29.858282Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:09:29.858595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-12T19:09:29.858632Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-12T19:09:29.860284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-12T19:09:29.861402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-12T19:09:29.945884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:09:29.946136Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-12T19:09:29.946224Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-12T19:09:30.116156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.141856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:30.116248Z","caller":"traceutil/trace.go:171","msg":"trace[380657597] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"115.285448ms","start":"2026-03-12T19:09:30.000948Z","end":"2026-03-12T19:09:30.116233Z","steps":["trace[380657597] 'count revisions from in-memory index tree' (duration: 115.092595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:30.116441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.541482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:30.116478Z","caller":"traceutil/trace.go:171","msg":"trace[1412145551] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"115.597203ms","start":"2026-03-12T19:09:30.000872Z","end":"2026-03-12T19:09:30.116469Z","steps":["trace[1412145551] 'range keys from in-memory index tree' (duration: 115.40784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:30.116602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.481344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:30.116635Z","caller":"traceutil/trace.go:171","msg":"trace[350551035] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"103.524915ms","start":"2026-03-12T19:09:30.013102Z","end":"2026-03-12T19:09:30.116627Z","steps":["trace[350551035] 'range keys from in-memory index tree' (duration: 103.384593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:30.117022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.803739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:30.117069Z","caller":"traceutil/trace.go:171","msg":"trace[1151380966] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"103.86602ms","start":"2026-03-12T19:09:30.013187Z","end":"2026-03-12T19:09:30.117053Z","steps":["trace[1151380966] 'count revisions from in-memory index tree' (duration: 102.532111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.7058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588445823,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-12T19:09:31.897222Z","caller":"traceutil/trace.go:171","msg":"trace[193470151] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"720.101125ms","start":"2026-03-12T19:09:31.177081Z","end":"2026-03-12T19:09:31.897182Z","steps":["trace[193470151] 'process raft request' (duration: 648.188701ms)","trace[193470151] 'compare' (duration: 71.795631ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:31.898032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.177069Z","time spent":"720.369089ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:31.921631Z","caller":"traceutil/trace.go:171","msg":"trace[1499804220] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"744.413179ms","start":"2026-03-12T19:09:31.177197Z","end":"2026-03-12T19:09:31.92161Z","steps":["trace[1499804220] 'process raft request' (duration: 744.260137ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:31.921802Z","caller":"traceutil/trace.go:171","msg":"trace[1022188885] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"744.528151ms","start":"2026-03-12T19:09:31.177254Z","end":"2026-03-12T19:09:31.921782Z","steps":["trace[1022188885] 'process raft request' (duration: 744.274977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.921855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.177187Z","time spent":"744.612702ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:31.921964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.177246Z","time spent":"744.642042ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:31.922389Z","caller":"traceutil/trace.go:171","msg":"trace[1826776388] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"745.044489ms","start":"2026-03-12T19:09:31.177316Z","end":"2026-03-12T19:09:31.92236Z","steps":["trace[1826776388] 'process raft request' (duration: 744.254467ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:31.92251Z","caller":"traceutil/trace.go:171","msg":"trace[178974947] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"745.10596ms","start":"2026-03-12T19:09:31.177338Z","end":"2026-03-12T19:09:31.922444Z","steps":["trace[178974947] 'process raft request' (duration: 744.938957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.922543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.177295Z","time spent":"745.201892ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:31.922626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.17732Z","time spent":"745.265882ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:31.922869Z","caller":"traceutil/trace.go:171","msg":"trace[1992167487] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:6; }","duration":"717.523356ms","start":"2026-03-12T19:09:31.205333Z","end":"2026-03-12T19:09:31.922856Z","steps":["trace[1992167487] 'read index received' (duration: 620.026727ms)","trace[1992167487] 'applied index is now lower than readState.Index' (duration: 97.495689ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:31.92291Z","caller":"traceutil/trace.go:171","msg":"trace[1533121004] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"744.866567ms","start":"2026-03-12T19:09:31.17802Z","end":"2026-03-12T19:09:31.922886Z","steps":["trace[1533121004] 'process raft request' (duration: 744.37088ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:31.923044Z","caller":"traceutil/trace.go:171","msg":"trace[745549458] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"744.39221ms","start":"2026-03-12T19:09:31.17864Z","end":"2026-03-12T19:09:31.923032Z","steps":["trace[745549458] 'process raft request' (duration: 744.101226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.923138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.178621Z","time spent":"744.485532ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:31.923197Z","caller":"traceutil/trace.go:171","msg":"trace[2020788408] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"732.175541ms","start":"2026-03-12T19:09:31.191014Z","end":"2026-03-12T19:09:31.92319Z","steps":["trace[2020788408] 'process raft request' (duration: 731.800506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.92325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.190988Z","time spent":"732.238973ms","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:31.923053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.178002Z","time spent":"744.998119ms","remote":"127.0.0.1:51662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:31.923162Z","caller":"traceutil/trace.go:171","msg":"trace[943884041] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"743.806362ms","start":"2026-03-12T19:09:31.179347Z","end":"2026-03-12T19:09:31.923153Z","steps":["trace[943884041] 'process raft request' (duration: 743.436976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.923526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.179337Z","time spent":"744.159936ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:31.923612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.279117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:31.92387Z","caller":"traceutil/trace.go:171","msg":"trace[991502185] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"718.558181ms","start":"2026-03-12T19:09:31.205297Z","end":"2026-03-12T19:09:31.923855Z","steps":["trace[991502185] 'agreement among raft nodes before linearized reading' (duration: 718.249816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.923957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.205286Z","time spent":"718.645472ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-03-12T19:09:31.954612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.034973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:31.954712Z","caller":"traceutil/trace.go:171","msg":"trace[205606799] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"748.148745ms","start":"2026-03-12T19:09:31.206535Z","end":"2026-03-12T19:09:31.954684Z","steps":["trace[205606799] 'agreement among raft nodes before linearized reading' (duration: 748.026123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.954612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.116478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-12T19:09:31.954654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.918456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:31.954848Z","caller":"traceutil/trace.go:171","msg":"trace[94086472] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"601.17398ms","start":"2026-03-12T19:09:31.353659Z","end":"2026-03-12T19:09:31.954833Z","steps":["trace[94086472] 'agreement among raft nodes before linearized reading' (duration: 600.861085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.954988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.353642Z","time spent":"601.325143ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-03-12T19:09:31.954766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.206521Z","time spent":"748.232007ms","remote":"127.0.0.1:51586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-12T19:09:31.954785Z","caller":"traceutil/trace.go:171","msg":"trace[272477723] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"723.327041ms","start":"2026-03-12T19:09:31.231446Z","end":"2026-03-12T19:09:31.954773Z","steps":["trace[272477723] 'agreement among raft nodes before linearized reading' (duration: 723.061547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:31.955182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:31.231435Z","time spent":"723.720436ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-12T19:09:32.028922Z","caller":"traceutil/trace.go:171","msg":"trace[647076699] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"102.358939ms","start":"2026-03-12T19:09:31.926536Z","end":"2026-03-12T19:09:32.028895Z","steps":["trace[647076699] 'process raft request' (duration: 89.503082ms)","trace[647076699] 'compare' (duration: 12.40746ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:32.028989Z","caller":"traceutil/trace.go:171","msg":"trace[453421759] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"101.71949ms","start":"2026-03-12T19:09:31.927227Z","end":"2026-03-12T19:09:32.028947Z","steps":["trace[453421759] 'process raft request' (duration: 101.495946ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.029071Z","caller":"traceutil/trace.go:171","msg":"trace[1238368280] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"101.944823ms","start":"2026-03-12T19:09:31.927096Z","end":"2026-03-12T19:09:32.02904Z","steps":["trace[1238368280] 'process raft request' (duration: 101.602757ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.029136Z","caller":"traceutil/trace.go:171","msg":"trace[1583741200] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"100.902637ms","start":"2026-03-12T19:09:31.928226Z","end":"2026-03-12T19:09:32.029129Z","steps":["trace[1583741200] 'process raft request' (duration: 100.534511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:32.029561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.580476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-12T19:09:32.029593Z","caller":"traceutil/trace.go:171","msg":"trace[504313633] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"103.657288ms","start":"2026-03-12T19:09:31.925929Z","end":"2026-03-12T19:09:32.029586Z","steps":["trace[504313633] 'agreement among raft nodes before linearized reading' (duration: 103.594997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:32.029632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.920697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:32.02968Z","caller":"traceutil/trace.go:171","msg":"trace[2007303530] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:20; }","duration":"100.988358ms","start":"2026-03-12T19:09:31.92868Z","end":"2026-03-12T19:09:32.029668Z","steps":["trace[2007303530] 'agreement among raft nodes before linearized reading' (duration: 100.547212ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.028949Z","caller":"traceutil/trace.go:171","msg":"trace[2123800512] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"102.193487ms","start":"2026-03-12T19:09:31.926742Z","end":"2026-03-12T19:09:32.028935Z","steps":["trace[2123800512] 'process raft request' (duration: 101.906212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:32.620491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.604455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:32.620729Z","caller":"traceutil/trace.go:171","msg":"trace[1208941460] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"229.932513ms","start":"2026-03-12T19:09:32.390761Z","end":"2026-03-12T19:09:32.620694Z","steps":["trace[1208941460] 'process raft request' (duration: 109.805041ms)","trace[1208941460] 'compare' (duration: 119.512394ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:32.620784Z","caller":"traceutil/trace.go:171","msg":"trace[1275865236] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"223.786684ms","start":"2026-03-12T19:09:32.396957Z","end":"2026-03-12T19:09:32.620743Z","steps":["trace[1275865236] 'process raft request' (duration: 223.740913ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.621095Z","caller":"traceutil/trace.go:171","msg":"trace[1286799971] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"228.770867ms","start":"2026-03-12T19:09:32.392314Z","end":"2026-03-12T19:09:32.621085Z","steps":["trace[1286799971] 'process raft request' (duration: 228.326691ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.621144Z","caller":"traceutil/trace.go:171","msg":"trace[1368454857] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"228.95334ms","start":"2026-03-12T19:09:32.392167Z","end":"2026-03-12T19:09:32.62112Z","steps":["trace[1368454857] 'process raft request' (duration: 228.415523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:32.915148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.399602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:32.915398Z","caller":"traceutil/trace.go:171","msg":"trace[1553334239] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"287.549644ms","start":"2026-03-12T19:09:32.62783Z","end":"2026-03-12T19:09:32.91538Z","steps":["trace[1553334239] 'process raft request' (duration: 287.484043ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.915478Z","caller":"traceutil/trace.go:171","msg":"trace[1142476179] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"287.99011ms","start":"2026-03-12T19:09:32.627466Z","end":"2026-03-12T19:09:32.915456Z","steps":["trace[1142476179] 'process raft request' (duration: 287.782897ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:32.915512Z","caller":"traceutil/trace.go:171","msg":"trace[873268694] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"288.67013ms","start":"2026-03-12T19:09:32.626782Z","end":"2026-03-12T19:09:32.915452Z","steps":["trace[873268694] 'process raft request' (duration: 146.874252ms)","trace[873268694] 'compare' (duration: 141.2826ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:33.007761Z","caller":"traceutil/trace.go:171","msg":"trace[1864673296] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"369.355157ms","start":"2026-03-12T19:09:32.638361Z","end":"2026-03-12T19:09:33.007716Z","steps":["trace[1864673296] 'process raft request' (duration: 369.106954ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.007789Z","caller":"traceutil/trace.go:171","msg":"trace[1170296704] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"276.571004ms","start":"2026-03-12T19:09:32.731197Z","end":"2026-03-12T19:09:33.007768Z","steps":["trace[1170296704] 'process raft request' (duration: 276.381631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.007937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:32.63834Z","time spent":"369.51042ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1244,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:33.211248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.95622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:33.21149Z","caller":"traceutil/trace.go:171","msg":"trace[481607331] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:72; }","duration":"203.730707ms","start":"2026-03-12T19:09:33.007738Z","end":"2026-03-12T19:09:33.211469Z","steps":["trace[481607331] 'read index received' (duration: 39.425992ms)","trace[481607331] 'applied index is now lower than readState.Index' (duration: 164.301705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:33.211654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.213182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c53b5\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-12T19:09:33.211629Z","caller":"traceutil/trace.go:171","msg":"trace[1776621040] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"200.452159ms","start":"2026-03-12T19:09:33.011146Z","end":"2026-03-12T19:09:33.211598Z","steps":["trace[1776621040] 'process raft request' (duration: 200.206515ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.211507Z","caller":"traceutil/trace.go:171","msg":"trace[669151075] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"199.379362ms","start":"2026-03-12T19:09:33.012114Z","end":"2026-03-12T19:09:33.211493Z","steps":["trace[669151075] 'process raft request' (duration: 199.325761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.211749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.930876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-5jbqn\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-12T19:09:33.211797Z","caller":"traceutil/trace.go:171","msg":"trace[2137919289] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-5jbqn; range_end:; response_count:1; response_revision:70; }","duration":"201.018527ms","start":"2026-03-12T19:09:33.010767Z","end":"2026-03-12T19:09:33.211786Z","steps":["trace[2137919289] 'agreement among raft nodes before linearized reading' (duration: 200.938706ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.211697Z","caller":"traceutil/trace.go:171","msg":"trace[1413059682] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c53b5; range_end:; response_count:1; response_revision:70; }","duration":"293.289023ms","start":"2026-03-12T19:09:32.918396Z","end":"2026-03-12T19:09:33.211685Z","steps":["trace[1413059682] 'agreement among raft nodes before linearized reading' (duration: 293.179541ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.211754Z","caller":"traceutil/trace.go:171","msg":"trace[121335075] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"293.02538ms","start":"2026-03-12T19:09:32.918495Z","end":"2026-03-12T19:09:33.211521Z","steps":["trace[121335075] 'process raft request' (duration: 128.724145ms)","trace[121335075] 'compare' (duration: 163.719355ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:33.460288Z","caller":"traceutil/trace.go:171","msg":"trace[1852547088] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"149.891538ms","start":"2026-03-12T19:09:33.310379Z","end":"2026-03-12T19:09:33.46027Z","steps":["trace[1852547088] 'read index received' (duration: 92.848259ms)","trace[1852547088] 'applied index is now lower than readState.Index' (duration: 57.042039ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:33.460305Z","caller":"traceutil/trace.go:171","msg":"trace[170822576] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"243.955532ms","start":"2026-03-12T19:09:33.216304Z","end":"2026-03-12T19:09:33.46026Z","steps":["trace[170822576] 'process raft request' (duration: 243.7693ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.460316Z","caller":"traceutil/trace.go:171","msg":"trace[375618024] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"244.147106ms","start":"2026-03-12T19:09:33.216141Z","end":"2026-03-12T19:09:33.460288Z","steps":["trace[375618024] 'process raft request' (duration: 186.990735ms)","trace[375618024] 'compare' (duration: 56.787335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:33.461018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.916522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:33.461104Z","caller":"traceutil/trace.go:171","msg":"trace[2049256019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"180.040163ms","start":"2026-03-12T19:09:33.28105Z","end":"2026-03-12T19:09:33.461091Z","steps":["trace[2049256019] 'agreement among raft nodes before linearized reading' (duration: 179.276292ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.461106Z","caller":"traceutil/trace.go:171","msg":"trace[2034314182] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"147.782177ms","start":"2026-03-12T19:09:33.313285Z","end":"2026-03-12T19:09:33.461067Z","steps":["trace[2034314182] 'process raft request' (duration: 146.889193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.461419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.652884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259be5a1\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-03-12T19:09:33.46149Z","caller":"traceutil/trace.go:171","msg":"trace[1656294469] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259be5a1; range_end:; response_count:1; response_revision:74; }","duration":"148.235563ms","start":"2026-03-12T19:09:33.313241Z","end":"2026-03-12T19:09:33.461477Z","steps":["trace[1656294469] 'agreement among raft nodes before linearized reading' (duration: 147.608894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.767247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.581164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:33.767525Z","caller":"traceutil/trace.go:171","msg":"trace[1944832256] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"302.39718ms","start":"2026-03-12T19:09:33.465099Z","end":"2026-03-12T19:09:33.767496Z","steps":["trace[1944832256] 'process raft request' (duration: 302.243108ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:33.767526Z","caller":"traceutil/trace.go:171","msg":"trace[1089095251] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:80; }","duration":"295.817364ms","start":"2026-03-12T19:09:33.471679Z","end":"2026-03-12T19:09:33.767496Z","steps":["trace[1089095251] 'read index received' (duration: 119.063968ms)","trace[1089095251] 'applied index is now lower than readState.Index' (duration: 176.750186ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:33.767639Z","caller":"traceutil/trace.go:171","msg":"trace[1238057603] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"295.717752ms","start":"2026-03-12T19:09:33.471915Z","end":"2026-03-12T19:09:33.767633Z","steps":["trace[1238057603] 'process raft request' (duration: 295.499509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.767764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:33.465093Z","time spent":"302.538442ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:33.767772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.080668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c38c1\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-12T19:09:33.767563Z","caller":"traceutil/trace.go:171","msg":"trace[1230721386] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"302.892117ms","start":"2026-03-12T19:09:33.464656Z","end":"2026-03-12T19:09:33.767548Z","steps":["trace[1230721386] 'process raft request' (duration: 125.948149ms)","trace[1230721386] 'compare' (duration: 176.41725ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:33.767864Z","caller":"traceutil/trace.go:171","msg":"trace[1913870012] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c38c1; range_end:; response_count:1; response_revision:78; }","duration":"296.214039ms","start":"2026-03-12T19:09:33.471634Z","end":"2026-03-12T19:09:33.767848Z","steps":["trace[1913870012] 'agreement among raft nodes before linearized reading' (duration: 296.011396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:33.767933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:33.464646Z","time spent":"303.226712ms","remote":"127.0.0.1:51662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":622,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:34.024387Z","caller":"traceutil/trace.go:171","msg":"trace[576770031] linearizableReadLoop","detail":"{readStateIndex:85; appliedIndex:84; }","duration":"253.4914ms","start":"2026-03-12T19:09:33.770875Z","end":"2026-03-12T19:09:34.024366Z","steps":["trace[576770031] 'read index received' (duration: 39.987531ms)","trace[576770031] 'applied index is now lower than readState.Index' (duration: 213.502819ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:34.024468Z","caller":"traceutil/trace.go:171","msg":"trace[1462577273] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"253.784464ms","start":"2026-03-12T19:09:33.770648Z","end":"2026-03-12T19:09:34.024433Z","steps":["trace[1462577273] 'process raft request' (duration: 157.520347ms)","trace[1462577273] 'compare' (duration: 95.999903ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:34.024547Z","caller":"traceutil/trace.go:171","msg":"trace[2071999908] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"252.543335ms","start":"2026-03-12T19:09:33.771966Z","end":"2026-03-12T19:09:34.024509Z","steps":["trace[2071999908] 'process raft request' (duration: 252.335242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.024624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.708303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-12T19:09:34.024667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.085683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:34.02474Z","caller":"traceutil/trace.go:171","msg":"trace[822835430] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:81; }","duration":"253.844885ms","start":"2026-03-12T19:09:33.770872Z","end":"2026-03-12T19:09:34.024717Z","steps":["trace[822835430] 'agreement among raft nodes before linearized reading' (duration: 253.650012ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:34.024742Z","caller":"traceutil/trace.go:171","msg":"trace[676210639] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:81; }","duration":"120.197334ms","start":"2026-03-12T19:09:33.904522Z","end":"2026-03-12T19:09:34.02472Z","steps":["trace[676210639] 'agreement among raft nodes before linearized reading' (duration: 120.063862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.024719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.766964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c53b5\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-12T19:09:34.024818Z","caller":"traceutil/trace.go:171","msg":"trace[2093321102] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c53b5; range_end:; response_count:1; response_revision:81; }","duration":"211.905346ms","start":"2026-03-12T19:09:33.812896Z","end":"2026-03-12T19:09:34.024801Z","steps":["trace[2093321102] 'agreement among raft nodes before linearized reading' (duration: 211.627322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.411137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.883701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:34.41142Z","caller":"traceutil/trace.go:171","msg":"trace[154823555] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"343.764389ms","start":"2026-03-12T19:09:34.067631Z","end":"2026-03-12T19:09:34.411395Z","steps":["trace[154823555] 'process raft request' (duration: 343.659518ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:34.411465Z","caller":"traceutil/trace.go:171","msg":"trace[972303943] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"332.005809ms","start":"2026-03-12T19:09:34.079438Z","end":"2026-03-12T19:09:34.411444Z","steps":["trace[972303943] 'read index received' (duration: 160.707852ms)","trace[972303943] 'applied index is now lower than readState.Index' (duration: 171.294987ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:34.41153Z","caller":"traceutil/trace.go:171","msg":"trace[448066708] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"331.986598ms","start":"2026-03-12T19:09:34.079534Z","end":"2026-03-12T19:09:34.41152Z","steps":["trace[448066708] 'process raft request' (duration: 331.828496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.411583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.067623Z","time spent":"343.873631ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:34.411607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.07952Z","time spent":"332.044959ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:34.411431Z","caller":"traceutil/trace.go:171","msg":"trace[1771861338] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"345.048858ms","start":"2026-03-12T19:09:34.066315Z","end":"2026-03-12T19:09:34.411364Z","steps":["trace[1771861338] 'process raft request' (duration: 173.812592ms)","trace[1771861338] 'compare' (duration: 170.743909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:34.411737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.066304Z","time spent":"345.394452ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:34.411832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.321463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c38c1\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-12T19:09:34.411948Z","caller":"traceutil/trace.go:171","msg":"trace[1017050998] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c38c1; range_end:; response_count:1; response_revision:90; }","duration":"332.501945ms","start":"2026-03-12T19:09:34.079407Z","end":"2026-03-12T19:09:34.411909Z","steps":["trace[1017050998] 'agreement among raft nodes before linearized reading' (duration: 332.171501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.411996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.079393Z","time spent":"332.591957ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":673,"request content":"key:\"/registry/events/default/instance.189c2dab259c38c1\" "} {"level":"warn","ts":"2026-03-12T19:09:34.828335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.998834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:34.828607Z","caller":"traceutil/trace.go:171","msg":"trace[937635382] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"412.032576ms","start":"2026-03-12T19:09:34.416538Z","end":"2026-03-12T19:09:34.82857Z","steps":["trace[937635382] 'process raft request' (duration: 278.710008ms)","trace[937635382] 'compare' (duration: 132.875032ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:34.828638Z","caller":"traceutil/trace.go:171","msg":"trace[1388377794] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:96; }","duration":"320.375702ms","start":"2026-03-12T19:09:34.508234Z","end":"2026-03-12T19:09:34.828609Z","steps":["trace[1388377794] 'read index received' (duration: 187.149705ms)","trace[1388377794] 'applied index is now lower than readState.Index' (duration: 133.222737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:34.828922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.926484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:34.829123Z","caller":"traceutil/trace.go:171","msg":"trace[98043037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:93; }","duration":"349.157598ms","start":"2026-03-12T19:09:34.479952Z","end":"2026-03-12T19:09:34.82911Z","steps":["trace[98043037] 'agreement among raft nodes before linearized reading' (duration: 348.743212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.82916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.479939Z","time spent":"349.210398ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-12T19:09:34.828797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.416518Z","time spent":"412.173339ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1043,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:34.829341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.966692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:34.829474Z","caller":"traceutil/trace.go:171","msg":"trace[1791591739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:93; }","duration":"216.161036ms","start":"2026-03-12T19:09:34.613293Z","end":"2026-03-12T19:09:34.829454Z","steps":["trace[1791591739] 'agreement among raft nodes before linearized reading' (duration: 215.963533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.829316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.091929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c53b5\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-12T19:09:34.829743Z","caller":"traceutil/trace.go:171","msg":"trace[1992642737] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c53b5; range_end:; response_count:1; response_revision:93; }","duration":"318.545405ms","start":"2026-03-12T19:09:34.511183Z","end":"2026-03-12T19:09:34.829728Z","steps":["trace[1992642737] 'agreement among raft nodes before linearized reading' (duration: 318.076038ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:34.828834Z","caller":"traceutil/trace.go:171","msg":"trace[1197952992] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"317.300177ms","start":"2026-03-12T19:09:34.511514Z","end":"2026-03-12T19:09:34.828814Z","steps":["trace[1197952992] 'process raft request' (duration: 316.955802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:34.829789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.511171Z","time spent":"318.604375ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":671,"request content":"key:\"/registry/events/default/instance.189c2dab259c53b5\" "} {"level":"warn","ts":"2026-03-12T19:09:34.829825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:34.511497Z","time spent":"318.28812ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2012,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:09:34.8294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.388889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:34.829954Z","caller":"traceutil/trace.go:171","msg":"trace[1633857802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:93; }","duration":"216.980878ms","start":"2026-03-12T19:09:34.612955Z","end":"2026-03-12T19:09:34.829936Z","steps":["trace[1633857802] 'agreement among raft nodes before linearized reading' (duration: 216.41752ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.092831Z","caller":"traceutil/trace.go:171","msg":"trace[1582755972] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"260.240173ms","start":"2026-03-12T19:09:34.832566Z","end":"2026-03-12T19:09:35.092806Z","steps":["trace[1582755972] 'process raft request' (duration: 166.818351ms)","trace[1582755972] 'compare' (duration: 93.26121ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:35.093049Z","caller":"traceutil/trace.go:171","msg":"trace[1719403064] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:99; }","duration":"256.206915ms","start":"2026-03-12T19:09:34.836797Z","end":"2026-03-12T19:09:35.093004Z","steps":["trace[1719403064] 'read index received' (duration: 162.60812ms)","trace[1719403064] 'applied index is now lower than readState.Index' (duration: 93.597835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:35.09317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.365037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259be5a1\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-03-12T19:09:35.093207Z","caller":"traceutil/trace.go:171","msg":"trace[59612317] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259be5a1; range_end:; response_count:1; response_revision:98; }","duration":"256.443828ms","start":"2026-03-12T19:09:34.836753Z","end":"2026-03-12T19:09:35.093197Z","steps":["trace[59612317] 'agreement among raft nodes before linearized reading' (duration: 256.345357ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.093261Z","caller":"traceutil/trace.go:171","msg":"trace[1920620186] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"256.789813ms","start":"2026-03-12T19:09:34.836397Z","end":"2026-03-12T19:09:35.093186Z","steps":["trace[1920620186] 'process raft request' (duration: 256.57677ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.09303Z","caller":"traceutil/trace.go:171","msg":"trace[1527305977] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"258.743792ms","start":"2026-03-12T19:09:34.834254Z","end":"2026-03-12T19:09:35.092998Z","steps":["trace[1527305977] 'process raft request' (duration: 258.510999ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.093074Z","caller":"traceutil/trace.go:171","msg":"trace[449255723] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"258.551429ms","start":"2026-03-12T19:09:34.834514Z","end":"2026-03-12T19:09:35.093066Z","steps":["trace[449255723] 'process raft request' (duration: 258.424897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:35.404852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.572803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-12T19:09:35.404926Z","caller":"traceutil/trace.go:171","msg":"trace[908868687] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"308.245521ms","start":"2026-03-12T19:09:35.09667Z","end":"2026-03-12T19:09:35.404916Z","steps":["trace[908868687] 'read index received' (duration: 144.576518ms)","trace[908868687] 'applied index is now lower than readState.Index' (duration: 163.668333ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:35.404958Z","caller":"traceutil/trace.go:171","msg":"trace[328342004] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"309.543441ms","start":"2026-03-12T19:09:35.09538Z","end":"2026-03-12T19:09:35.404924Z","steps":["trace[328342004] 'process raft request' (duration: 145.836027ms)","trace[328342004] 'compare' (duration: 163.498561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:35.405104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:35.095362Z","time spent":"309.664203ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:35.405137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.464664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:35.405199Z","caller":"traceutil/trace.go:171","msg":"trace[1126392749] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:0; response_revision:99; }","duration":"308.558036ms","start":"2026-03-12T19:09:35.096635Z","end":"2026-03-12T19:09:35.405193Z","steps":["trace[1126392749] 'agreement among raft nodes before linearized reading' (duration: 308.411214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:35.405226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:35.096619Z","time spent":"308.601657ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:heapster\" "} {"level":"warn","ts":"2026-03-12T19:09:35.405331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.835156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-12T19:09:35.405381Z","caller":"traceutil/trace.go:171","msg":"trace[584484629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:99; }","duration":"307.884627ms","start":"2026-03-12T19:09:35.097483Z","end":"2026-03-12T19:09:35.405367Z","steps":["trace[584484629] 'agreement among raft nodes before linearized reading' (duration: 307.798526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:35.405419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:35.097423Z","time spent":"307.989098ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-12T19:09:35.405361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.541885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-12T19:09:35.405553Z","caller":"traceutil/trace.go:171","msg":"trace[202062115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:99; }","duration":"308.755699ms","start":"2026-03-12T19:09:35.096779Z","end":"2026-03-12T19:09:35.405535Z","steps":["trace[202062115] 'agreement among raft nodes before linearized reading' (duration: 308.342453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:35.405624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:35.096773Z","time spent":"308.83793ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-12T19:09:35.808666Z","caller":"traceutil/trace.go:171","msg":"trace[421105748] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"190.653922ms","start":"2026-03-12T19:09:35.617978Z","end":"2026-03-12T19:09:35.808632Z","steps":["trace[421105748] 'process raft request' (duration: 190.556231ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.808792Z","caller":"traceutil/trace.go:171","msg":"trace[649035094] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"196.740299ms","start":"2026-03-12T19:09:35.612034Z","end":"2026-03-12T19:09:35.808774Z","steps":["trace[649035094] 'process raft request' (duration: 108.041723ms)","trace[649035094] 'compare' (duration: 88.27292ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:35.808862Z","caller":"traceutil/trace.go:171","msg":"trace[592203971] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:112; }","duration":"191.318592ms","start":"2026-03-12T19:09:35.617492Z","end":"2026-03-12T19:09:35.80881Z","steps":["trace[592203971] 'read index received' (duration: 102.518954ms)","trace[592203971] 'applied index is now lower than readState.Index' (duration: 88.797578ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:35.809095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.493934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:35.80917Z","caller":"traceutil/trace.go:171","msg":"trace[783975854] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:0; response_revision:109; }","duration":"191.694957ms","start":"2026-03-12T19:09:35.617462Z","end":"2026-03-12T19:09:35.809157Z","steps":["trace[783975854] 'agreement among raft nodes before linearized reading' (duration: 191.443943ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:35.962155Z","caller":"traceutil/trace.go:171","msg":"trace[132745559] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"114.419197ms","start":"2026-03-12T19:09:35.84769Z","end":"2026-03-12T19:09:35.962109Z","steps":["trace[132745559] 'read index received' (duration: 63.100708ms)","trace[132745559] 'applied index is now lower than readState.Index' (duration: 51.316739ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:35.96224Z","caller":"traceutil/trace.go:171","msg":"trace[1267866971] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"114.618209ms","start":"2026-03-12T19:09:35.84759Z","end":"2026-03-12T19:09:35.962209Z","steps":["trace[1267866971] 'process raft request' (duration: 63.24649ms)","trace[1267866971] 'compare' (duration: 51.078555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:35.962336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.62155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c2dab259c38c1\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-12T19:09:35.962378Z","caller":"traceutil/trace.go:171","msg":"trace[1577841624] range","detail":"{range_begin:/registry/events/default/instance.189c2dab259c38c1; range_end:; response_count:1; response_revision:112; }","duration":"114.693671ms","start":"2026-03-12T19:09:35.847671Z","end":"2026-03-12T19:09:35.962365Z","steps":["trace[1577841624] 'agreement among raft nodes before linearized reading' (duration: 114.564319ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:36.155867Z","caller":"traceutil/trace.go:171","msg":"trace[341843060] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:119; }","duration":"102.977555ms","start":"2026-03-12T19:09:36.052864Z","end":"2026-03-12T19:09:36.155841Z","steps":["trace[341843060] 'read index received' (duration: 32.435275ms)","trace[341843060] 'applied index is now lower than readState.Index' (duration: 70.54111ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:36.155899Z","caller":"traceutil/trace.go:171","msg":"trace[875100230] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"103.034535ms","start":"2026-03-12T19:09:36.052832Z","end":"2026-03-12T19:09:36.155866Z","steps":["trace[875100230] 'process raft request' (duration: 32.443355ms)","trace[875100230] 'compare' (duration: 70.449968ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:36.156251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.36527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-12T19:09:36.156308Z","caller":"traceutil/trace.go:171","msg":"trace[899581010] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:115; }","duration":"103.470101ms","start":"2026-03-12T19:09:36.052826Z","end":"2026-03-12T19:09:36.156296Z","steps":["trace[899581010] 'agreement among raft nodes before linearized reading' (duration: 103.133816ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:36.345047Z","caller":"traceutil/trace.go:171","msg":"trace[1218911898] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"101.737127ms","start":"2026-03-12T19:09:36.243286Z","end":"2026-03-12T19:09:36.345023Z","steps":["trace[1218911898] 'process raft request' (duration: 101.569635ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:36.564369Z","caller":"traceutil/trace.go:171","msg":"trace[1107466425] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"132.53469ms","start":"2026-03-12T19:09:36.431802Z","end":"2026-03-12T19:09:36.564337Z","steps":["trace[1107466425] 'process raft request' (duration: 132.299565ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:36.564691Z","caller":"traceutil/trace.go:171","msg":"trace[1545465767] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"132.855274ms","start":"2026-03-12T19:09:36.431809Z","end":"2026-03-12T19:09:36.564664Z","steps":["trace[1545465767] 'process raft request' (duration: 132.436968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:36.805925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.186409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:36.806104Z","caller":"traceutil/trace.go:171","msg":"trace[1662217466] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"185.190803ms","start":"2026-03-12T19:09:36.620897Z","end":"2026-03-12T19:09:36.806087Z","steps":["trace[1662217466] 'process raft request' (duration: 185.136462ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:36.806346Z","caller":"traceutil/trace.go:171","msg":"trace[903356719] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"185.532528ms","start":"2026-03-12T19:09:36.620779Z","end":"2026-03-12T19:09:36.806312Z","steps":["trace[903356719] 'process raft request' (duration: 67.890572ms)","trace[903356719] 'compare' (duration: 117.044137ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:38.011167Z","caller":"traceutil/trace.go:171","msg":"trace[872201582] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"114.22905ms","start":"2026-03-12T19:09:37.896914Z","end":"2026-03-12T19:09:38.011143Z","steps":["trace[872201582] 'process raft request' (duration: 28.874992ms)","trace[872201582] 'compare' (duration: 85.237146ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:39.570646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.866695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:39.57081Z","caller":"traceutil/trace.go:171","msg":"trace[264869790] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"125.613897ms","start":"2026-03-12T19:09:39.445156Z","end":"2026-03-12T19:09:39.570769Z","steps":["trace[264869790] 'process raft request' (duration: 22.530029ms)","trace[264869790] 'compare' (duration: 102.638812ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:39.794372Z","caller":"traceutil/trace.go:171","msg":"trace[172704206] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"156.389173ms","start":"2026-03-12T19:09:39.637959Z","end":"2026-03-12T19:09:39.794348Z","steps":["trace[172704206] 'process raft request' (duration: 77.978144ms)","trace[172704206] 'compare' (duration: 78.288628ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:39.816063Z","caller":"traceutil/trace.go:171","msg":"trace[2074211334] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"134.439162ms","start":"2026-03-12T19:09:39.681605Z","end":"2026-03-12T19:09:39.816044Z","steps":["trace[2074211334] 'process raft request' (duration: 134.320201ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:40.064175Z","caller":"traceutil/trace.go:171","msg":"trace[832604385] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"119.546308ms","start":"2026-03-12T19:09:39.944599Z","end":"2026-03-12T19:09:40.064145Z","steps":["trace[832604385] 'read index received' (duration: 99.309472ms)","trace[832604385] 'applied index is now lower than readState.Index' (duration: 20.235836ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:40.064272Z","caller":"traceutil/trace.go:171","msg":"trace[4836095] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"210.661767ms","start":"2026-03-12T19:09:39.853571Z","end":"2026-03-12T19:09:40.064233Z","steps":["trace[4836095] 'process raft request' (duration: 190.423112ms)","trace[4836095] 'compare' (duration: 19.990971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:40.06432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.69639ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:40.064429Z","caller":"traceutil/trace.go:171","msg":"trace[19487114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:234; }","duration":"119.822221ms","start":"2026-03-12T19:09:39.944593Z","end":"2026-03-12T19:09:40.064415Z","steps":["trace[19487114] 'agreement among raft nodes before linearized reading' (duration: 119.655909ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:40.31788Z","caller":"traceutil/trace.go:171","msg":"trace[2035314816] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"245.005921ms","start":"2026-03-12T19:09:40.072832Z","end":"2026-03-12T19:09:40.317838Z","steps":["trace[2035314816] 'process raft request' (duration: 237.679068ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:40.317892Z","caller":"traceutil/trace.go:171","msg":"trace[1904514147] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"244.21678ms","start":"2026-03-12T19:09:40.073652Z","end":"2026-03-12T19:09:40.317868Z","steps":["trace[1904514147] 'process raft request' (duration: 244.089589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:40.573805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.253458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:40.573918Z","caller":"traceutil/trace.go:171","msg":"trace[1312745664] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:243; }","duration":"226.315751ms","start":"2026-03-12T19:09:40.347585Z","end":"2026-03-12T19:09:40.5739Z","steps":["trace[1312745664] 'read index received' (duration: 92.820549ms)","trace[1312745664] 'applied index is now lower than readState.Index' (duration: 133.492652ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:40.574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.405522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:40.573967Z","caller":"traceutil/trace.go:171","msg":"trace[370951488] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"245.39988ms","start":"2026-03-12T19:09:40.328516Z","end":"2026-03-12T19:09:40.573915Z","steps":["trace[370951488] 'process raft request' (duration: 111.956269ms)","trace[370951488] 'compare' (duration: 133.123857ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:40.574184Z","caller":"traceutil/trace.go:171","msg":"trace[1536126516] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:239; }","duration":"226.621214ms","start":"2026-03-12T19:09:40.34754Z","end":"2026-03-12T19:09:40.574161Z","steps":["trace[1536126516] 'agreement among raft nodes before linearized reading' (duration: 226.405751ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:40.788589Z","caller":"traceutil/trace.go:171","msg":"trace[570298312] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"131.721714ms","start":"2026-03-12T19:09:40.656841Z","end":"2026-03-12T19:09:40.788563Z","steps":["trace[570298312] 'process raft request' (duration: 34.805451ms)","trace[570298312] 'compare' (duration: 96.68365ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:41.183802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.932437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:41.183905Z","caller":"traceutil/trace.go:171","msg":"trace[69740819] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"358.440453ms","start":"2026-03-12T19:09:40.825447Z","end":"2026-03-12T19:09:41.183887Z","steps":["trace[69740819] 'process raft request' (duration: 202.353331ms)","trace[69740819] 'compare' (duration: 155.745299ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:41.183957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:40.82543Z","time spent":"358.507084ms","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:09:41.577583Z","caller":"traceutil/trace.go:171","msg":"trace[734974080] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"214.172165ms","start":"2026-03-12T19:09:41.363382Z","end":"2026-03-12T19:09:41.577554Z","steps":["trace[734974080] 'read index received' (duration: 133.697863ms)","trace[734974080] 'applied index is now lower than readState.Index' (duration: 80.472162ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:41.577624Z","caller":"traceutil/trace.go:171","msg":"trace[2136564169] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"215.805523ms","start":"2026-03-12T19:09:41.361798Z","end":"2026-03-12T19:09:41.577603Z","steps":["trace[2136564169] 'process raft request' (duration: 135.339251ms)","trace[2136564169] 'compare' (duration: 80.223864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:41.577826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.895387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-12T19:09:41.57783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.431574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:09:41.577858Z","caller":"traceutil/trace.go:171","msg":"trace[1723561725] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:251; }","duration":"109.97255ms","start":"2026-03-12T19:09:41.467877Z","end":"2026-03-12T19:09:41.577849Z","steps":["trace[1723561725] 'agreement among raft nodes before linearized reading' (duration: 109.910407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:41.577853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.046991ms","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-03-12T19:09:41.57794Z","caller":"traceutil/trace.go:171","msg":"trace[1667153494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:251; }","duration":"212.166615ms","start":"2026-03-12T19:09:41.365739Z","end":"2026-03-12T19:09:41.577905Z","steps":["trace[1667153494] 'agreement among raft nodes before linearized reading' (duration: 212.011679ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:41.577875Z","caller":"traceutil/trace.go:171","msg":"trace[1666829121] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:251; }","duration":"214.510237ms","start":"2026-03-12T19:09:41.363349Z","end":"2026-03-12T19:09:41.57786Z","steps":["trace[1666829121] 'agreement among raft nodes before linearized reading' (duration: 214.341121ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:41.727624Z","caller":"traceutil/trace.go:171","msg":"trace[1594640037] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"144.325018ms","start":"2026-03-12T19:09:41.583277Z","end":"2026-03-12T19:09:41.727602Z","steps":["trace[1594640037] 'process raft request' (duration: 144.121891ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:41.727637Z","caller":"traceutil/trace.go:171","msg":"trace[1760299784] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:256; }","duration":"126.814192ms","start":"2026-03-12T19:09:41.600796Z","end":"2026-03-12T19:09:41.72761Z","steps":["trace[1760299784] 'read index received' (duration: 47.445939ms)","trace[1760299784] 'applied index is now lower than readState.Index' (duration: 79.367393ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:41.727733Z","caller":"traceutil/trace.go:171","msg":"trace[1274088550] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"144.135681ms","start":"2026-03-12T19:09:41.583588Z","end":"2026-03-12T19:09:41.727724Z","steps":["trace[1274088550] 'process raft request' (duration: 143.975846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:41.727818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.01012ms","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-03-12T19:09:41.727871Z","caller":"traceutil/trace.go:171","msg":"trace[1191149037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:255; }","duration":"127.095832ms","start":"2026-03-12T19:09:41.600761Z","end":"2026-03-12T19:09:41.727857Z","steps":["trace[1191149037] 'agreement among raft nodes before linearized reading' (duration: 126.964377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:41.980925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.254983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:41.981048Z","caller":"traceutil/trace.go:171","msg":"trace[165565719] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"188.599476ms","start":"2026-03-12T19:09:41.792436Z","end":"2026-03-12T19:09:41.981036Z","steps":["trace[165565719] 'process raft request' (duration: 188.559944ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:41.981193Z","caller":"traceutil/trace.go:171","msg":"trace[345234377] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"189.232087ms","start":"2026-03-12T19:09:41.791907Z","end":"2026-03-12T19:09:41.981139Z","steps":["trace[345234377] 'process raft request' (duration: 58.706005ms)","trace[345234377] 'compare' (duration: 130.154839ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:42.171681Z","caller":"traceutil/trace.go:171","msg":"trace[912215902] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"112.211442ms","start":"2026-03-12T19:09:42.059442Z","end":"2026-03-12T19:09:42.171653Z","steps":["trace[912215902] 'process raft request' (duration: 64.717607ms)","trace[912215902] 'compare' (duration: 47.353641ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:42.442168Z","caller":"traceutil/trace.go:171","msg":"trace[359293935] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"166.649931ms","start":"2026-03-12T19:09:42.275473Z","end":"2026-03-12T19:09:42.442123Z","steps":["trace[359293935] 'read index received' (duration: 74.519379ms)","trace[359293935] 'applied index is now lower than readState.Index' (duration: 92.128862ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:42.442311Z","caller":"traceutil/trace.go:171","msg":"trace[1954610762] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"216.257581ms","start":"2026-03-12T19:09:42.226015Z","end":"2026-03-12T19:09:42.442273Z","steps":["trace[1954610762] 'process raft request' (duration: 124.068477ms)","trace[1954610762] 'compare' (duration: 91.76863ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:42.442427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.934351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:09:42.442531Z","caller":"traceutil/trace.go:171","msg":"trace[1910904335] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"167.071125ms","start":"2026-03-12T19:09:42.275432Z","end":"2026-03-12T19:09:42.442504Z","steps":["trace[1910904335] 'agreement among raft nodes before linearized reading' (duration: 166.902979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:42.442544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.475771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3473"} {"level":"info","ts":"2026-03-12T19:09:42.442609Z","caller":"traceutil/trace.go:171","msg":"trace[1511456516] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:272; }","duration":"112.611406ms","start":"2026-03-12T19:09:42.32998Z","end":"2026-03-12T19:09:42.442591Z","steps":["trace[1511456516] 'agreement among raft nodes before linearized reading' (duration: 112.399709ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:42.607276Z","caller":"traceutil/trace.go:171","msg":"trace[1269770480] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"104.407931ms","start":"2026-03-12T19:09:42.502849Z","end":"2026-03-12T19:09:42.607257Z","steps":["trace[1269770480] 'read index received' (duration: 42.880285ms)","trace[1269770480] 'applied index is now lower than readState.Index' (duration: 61.526626ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:42.607342Z","caller":"traceutil/trace.go:171","msg":"trace[996570085] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"154.168914ms","start":"2026-03-12T19:09:42.453128Z","end":"2026-03-12T19:09:42.607297Z","steps":["trace[996570085] 'process raft request' (duration: 92.712861ms)","trace[996570085] 'compare' (duration: 61.315208ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:42.607471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.596078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-12T19:09:42.607562Z","caller":"traceutil/trace.go:171","msg":"trace[1342842043] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:274; }","duration":"104.734273ms","start":"2026-03-12T19:09:42.502806Z","end":"2026-03-12T19:09:42.607541Z","steps":["trace[1342842043] 'agreement among raft nodes before linearized reading' (duration: 104.540836ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:42.749097Z","caller":"traceutil/trace.go:171","msg":"trace[1495404454] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"124.19446ms","start":"2026-03-12T19:09:42.624879Z","end":"2026-03-12T19:09:42.749073Z","steps":["trace[1495404454] 'read index received' (duration: 88.842338ms)","trace[1495404454] 'applied index is now lower than readState.Index' (duration: 35.350852ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:42.749218Z","caller":"traceutil/trace.go:171","msg":"trace[1794609469] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"125.698433ms","start":"2026-03-12T19:09:42.623487Z","end":"2026-03-12T19:09:42.749186Z","steps":["trace[1794609469] 'process raft request' (duration: 90.298949ms)","trace[1794609469] 'compare' (duration: 35.116505ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:42.749402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.357976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:09:42.749456Z","caller":"traceutil/trace.go:171","msg":"trace[16259651] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:277; }","duration":"124.577763ms","start":"2026-03-12T19:09:42.624866Z","end":"2026-03-12T19:09:42.749443Z","steps":["trace[16259651] 'agreement among raft nodes before linearized reading' (duration: 124.303204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:42.951929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.354044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-03-12T19:09:42.952021Z","caller":"traceutil/trace.go:171","msg":"trace[1357289774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:283; }","duration":"101.462898ms","start":"2026-03-12T19:09:42.850537Z","end":"2026-03-12T19:09:42.951999Z","steps":["trace[1357289774] 'range keys from in-memory index tree' (duration: 101.268861ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:43.625829Z","caller":"traceutil/trace.go:171","msg":"trace[182305619] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"124.225642ms","start":"2026-03-12T19:09:43.501569Z","end":"2026-03-12T19:09:43.625794Z","steps":["trace[182305619] 'process raft request' (duration: 68.054361ms)","trace[182305619] 'compare' (duration: 56.004905ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:43.913891Z","caller":"traceutil/trace.go:171","msg":"trace[942345532] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"114.271559ms","start":"2026-03-12T19:09:43.799601Z","end":"2026-03-12T19:09:43.913873Z","steps":["trace[942345532] 'process raft request' (duration: 114.138644ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:43.913876Z","caller":"traceutil/trace.go:171","msg":"trace[1744313435] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"114.940871ms","start":"2026-03-12T19:09:43.79889Z","end":"2026-03-12T19:09:43.913831Z","steps":["trace[1744313435] 'process raft request' (duration: 103.124593ms)","trace[1744313435] 'compare' (duration: 11.591561ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:43.914138Z","caller":"traceutil/trace.go:171","msg":"trace[1907279911] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"114.421823ms","start":"2026-03-12T19:09:43.799706Z","end":"2026-03-12T19:09:43.914128Z","steps":["trace[1907279911] 'process raft request' (duration: 114.081592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:44.157203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.23052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:09:44.157299Z","caller":"traceutil/trace.go:171","msg":"trace[1147302471] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:306; }","duration":"123.345874ms","start":"2026-03-12T19:09:44.03393Z","end":"2026-03-12T19:09:44.157276Z","steps":["trace[1147302471] 'range keys from in-memory index tree' (duration: 123.091696ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.448858Z","caller":"traceutil/trace.go:171","msg":"trace[2088442877] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"260.995909ms","start":"2026-03-12T19:09:44.18782Z","end":"2026-03-12T19:09:44.448816Z","steps":["trace[2088442877] 'read index received' (duration: 173.198942ms)","trace[2088442877] 'applied index is now lower than readState.Index' (duration: 87.795707ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:44.448983Z","caller":"traceutil/trace.go:171","msg":"trace[225230871] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"262.983997ms","start":"2026-03-12T19:09:44.185987Z","end":"2026-03-12T19:09:44.448971Z","steps":["trace[225230871] 'process raft request' (duration: 174.946612ms)","trace[225230871] 'compare' (duration: 87.730034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:44.449235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.33274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-12T19:09:44.449585Z","caller":"traceutil/trace.go:171","msg":"trace[263826216] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"261.717213ms","start":"2026-03-12T19:09:44.187791Z","end":"2026-03-12T19:09:44.449508Z","steps":["trace[263826216] 'agreement among raft nodes before linearized reading' (duration: 261.264018ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.456625Z","caller":"traceutil/trace.go:171","msg":"trace[2140851389] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"251.0947ms","start":"2026-03-12T19:09:44.205517Z","end":"2026-03-12T19:09:44.456612Z","steps":["trace[2140851389] 'process raft request' (duration: 250.882683ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.456668Z","caller":"traceutil/trace.go:171","msg":"trace[1683945768] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"148.421583ms","start":"2026-03-12T19:09:44.308202Z","end":"2026-03-12T19:09:44.456623Z","steps":["trace[1683945768] 'process raft request' (duration: 148.303409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:44.4568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.40985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-03-12T19:09:44.456847Z","caller":"traceutil/trace.go:171","msg":"trace[628478844] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"132.384213ms","start":"2026-03-12T19:09:44.324453Z","end":"2026-03-12T19:09:44.456837Z","steps":["trace[628478844] 'process raft request' (duration: 132.114474ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.456871Z","caller":"traceutil/trace.go:171","msg":"trace[119897518] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:310; }","duration":"251.507894ms","start":"2026-03-12T19:09:44.205344Z","end":"2026-03-12T19:09:44.456852Z","steps":["trace[119897518] 'agreement among raft nodes before linearized reading' (duration: 251.38324ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.45691Z","caller":"traceutil/trace.go:171","msg":"trace[1646494292] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"132.789878ms","start":"2026-03-12T19:09:44.324115Z","end":"2026-03-12T19:09:44.456905Z","steps":["trace[1646494292] 'process raft request' (duration: 132.427165ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.456963Z","caller":"traceutil/trace.go:171","msg":"trace[945816919] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"132.238319ms","start":"2026-03-12T19:09:44.324719Z","end":"2026-03-12T19:09:44.456958Z","steps":["trace[945816919] 'process raft request' (duration: 131.872866ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.457084Z","caller":"traceutil/trace.go:171","msg":"trace[110127919] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"131.3565ms","start":"2026-03-12T19:09:44.32572Z","end":"2026-03-12T19:09:44.457077Z","steps":["trace[110127919] 'process raft request' (duration: 130.965636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:44.457122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.574156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-12T19:09:44.457153Z","caller":"traceutil/trace.go:171","msg":"trace[1668269658] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:310; }","duration":"251.633058ms","start":"2026-03-12T19:09:44.205511Z","end":"2026-03-12T19:09:44.457144Z","steps":["trace[1668269658] 'agreement among raft nodes before linearized reading' (duration: 251.578316ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.457224Z","caller":"traceutil/trace.go:171","msg":"trace[262008960] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"131.768404ms","start":"2026-03-12T19:09:44.325423Z","end":"2026-03-12T19:09:44.457191Z","steps":["trace[262008960] 'process raft request' (duration: 131.214135ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:44.657596Z","caller":"traceutil/trace.go:171","msg":"trace[808665500] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"115.749526ms","start":"2026-03-12T19:09:44.541813Z","end":"2026-03-12T19:09:44.657563Z","steps":["trace[808665500] 'process raft request' (duration: 115.556759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:45.508124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.189901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-03-12T19:09:45.508319Z","caller":"traceutil/trace.go:171","msg":"trace[2057783984] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:346; }","duration":"250.458832ms","start":"2026-03-12T19:09:45.25784Z","end":"2026-03-12T19:09:45.508299Z","steps":["trace[2057783984] 'read index received' (duration: 102.057314ms)","trace[2057783984] 'applied index is now lower than readState.Index' (duration: 148.400238ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:45.508369Z","caller":"traceutil/trace.go:171","msg":"trace[386877509] transaction","detail":"{read_only:false; number_of_response:0; response_revision:334; }","duration":"269.431456ms","start":"2026-03-12T19:09:45.238891Z","end":"2026-03-12T19:09:45.508322Z","steps":["trace[386877509] 'process raft request' (duration: 269.321383ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:45.508493Z","caller":"traceutil/trace.go:171","msg":"trace[1493216763] transaction","detail":"{read_only:false; number_of_response:0; response_revision:334; }","duration":"269.748227ms","start":"2026-03-12T19:09:45.238733Z","end":"2026-03-12T19:09:45.508482Z","steps":["trace[1493216763] 'process raft request' (duration: 121.113491ms)","trace[1493216763] 'compare' (duration: 148.145379ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:45.508669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.802683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-03-12T19:09:45.508432Z","caller":"traceutil/trace.go:171","msg":"trace[1861575688] transaction","detail":"{read_only:false; number_of_response:0; response_revision:334; }","duration":"268.632249ms","start":"2026-03-12T19:09:45.239788Z","end":"2026-03-12T19:09:45.50842Z","steps":["trace[1861575688] 'process raft request' (duration: 268.480244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:45.508766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.991379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:45.508791Z","caller":"traceutil/trace.go:171","msg":"trace[721767393] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:335; }","duration":"250.914347ms","start":"2026-03-12T19:09:45.25783Z","end":"2026-03-12T19:09:45.508745Z","steps":["trace[721767393] 'agreement among raft nodes before linearized reading' (duration: 250.622997ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:45.508928Z","caller":"traceutil/trace.go:171","msg":"trace[251167146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:335; }","duration":"228.191706ms","start":"2026-03-12T19:09:45.280711Z","end":"2026-03-12T19:09:45.508903Z","steps":["trace[251167146] 'agreement among raft nodes before linearized reading' (duration: 227.821384ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:45.50886Z","caller":"traceutil/trace.go:171","msg":"trace[483697480] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"241.922662ms","start":"2026-03-12T19:09:45.266924Z","end":"2026-03-12T19:09:45.508847Z","steps":["trace[483697480] 'process raft request' (duration: 241.433966ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:45.750822Z","caller":"traceutil/trace.go:171","msg":"trace[1997889340] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"140.234991ms","start":"2026-03-12T19:09:45.610556Z","end":"2026-03-12T19:09:45.750791Z","steps":["trace[1997889340] 'read index received' (duration: 79.675955ms)","trace[1997889340] 'applied index is now lower than readState.Index' (duration: 60.557586ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:45.751017Z","caller":"traceutil/trace.go:171","msg":"trace[1787423991] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"210.189075ms","start":"2026-03-12T19:09:45.540813Z","end":"2026-03-12T19:09:45.751002Z","steps":["trace[1787423991] 'process raft request' (duration: 149.486004ms)","trace[1787423991] 'compare' (duration: 60.211824ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:45.751407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.83337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:45.751496Z","caller":"traceutil/trace.go:171","msg":"trace[1674112832] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:337; }","duration":"140.982676ms","start":"2026-03-12T19:09:45.610491Z","end":"2026-03-12T19:09:45.751473Z","steps":["trace[1674112832] 'agreement among raft nodes before linearized reading' (duration: 140.850841ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:46.382437Z","caller":"traceutil/trace.go:171","msg":"trace[619212822] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"108.789481ms","start":"2026-03-12T19:09:46.273625Z","end":"2026-03-12T19:09:46.382414Z","steps":["trace[619212822] 'process raft request' (duration: 108.726289ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:46.382658Z","caller":"traceutil/trace.go:171","msg":"trace[1363916005] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"126.625531ms","start":"2026-03-12T19:09:46.256005Z","end":"2026-03-12T19:09:46.38263Z","steps":["trace[1363916005] 'process raft request' (duration: 83.597664ms)","trace[1363916005] 'compare' (duration: 42.633764ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:46.382678Z","caller":"traceutil/trace.go:171","msg":"trace[628695771] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"126.588109ms","start":"2026-03-12T19:09:46.256068Z","end":"2026-03-12T19:09:46.382656Z","steps":["trace[628695771] 'read index received' (duration: 15.108469ms)","trace[628695771] 'applied index is now lower than readState.Index' (duration: 111.47766ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:46.383113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.205159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-12T19:09:46.383176Z","caller":"traceutil/trace.go:171","msg":"trace[1533511328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:364; }","duration":"102.305993ms","start":"2026-03-12T19:09:46.280854Z","end":"2026-03-12T19:09:46.38316Z","steps":["trace[1533511328] 'agreement among raft nodes before linearized reading' (duration: 102.174128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:46.383147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.019395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-03-12T19:09:46.383455Z","caller":"traceutil/trace.go:171","msg":"trace[1533762419] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:364; }","duration":"127.390907ms","start":"2026-03-12T19:09:46.256044Z","end":"2026-03-12T19:09:46.383435Z","steps":["trace[1533762419] 'agreement among raft nodes before linearized reading' (duration: 126.832797ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:46.807764Z","caller":"traceutil/trace.go:171","msg":"trace[427766472] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"175.94272ms","start":"2026-03-12T19:09:46.631799Z","end":"2026-03-12T19:09:46.807742Z","steps":["trace[427766472] 'read index received' (duration: 175.757104ms)","trace[427766472] 'applied index is now lower than readState.Index' (duration: 184.986µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:46.808117Z","caller":"traceutil/trace.go:171","msg":"trace[1710618163] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"215.469641ms","start":"2026-03-12T19:09:46.592626Z","end":"2026-03-12T19:09:46.808096Z","steps":["trace[1710618163] 'process raft request' (duration: 215.000445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:46.808418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.602853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-12T19:09:46.808493Z","caller":"traceutil/trace.go:171","msg":"trace[1875022429] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:369; }","duration":"176.702456ms","start":"2026-03-12T19:09:46.631757Z","end":"2026-03-12T19:09:46.808459Z","steps":["trace[1875022429] 'agreement among raft nodes before linearized reading' (duration: 176.592482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:46.808606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.61221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-12T19:09:46.808765Z","caller":"traceutil/trace.go:171","msg":"trace[2035699190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:369; }","duration":"126.789656ms","start":"2026-03-12T19:09:46.681862Z","end":"2026-03-12T19:09:46.808652Z","steps":["trace[2035699190] 'agreement among raft nodes before linearized reading' (duration: 126.58848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:47.028624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.224861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:09:47.028947Z","caller":"traceutil/trace.go:171","msg":"trace[1760444784] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"187.146331ms","start":"2026-03-12T19:09:46.841781Z","end":"2026-03-12T19:09:47.028927Z","steps":["trace[1760444784] 'process raft request' (duration: 187.10628ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:47.029102Z","caller":"traceutil/trace.go:171","msg":"trace[1799925280] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"211.319913ms","start":"2026-03-12T19:09:46.817763Z","end":"2026-03-12T19:09:47.029083Z","steps":["trace[1799925280] 'process raft request' (duration: 81.569344ms)","trace[1799925280] 'compare' (duration: 129.093547ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:47.02915Z","caller":"traceutil/trace.go:171","msg":"trace[1229637271] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"210.62231ms","start":"2026-03-12T19:09:46.818516Z","end":"2026-03-12T19:09:47.029138Z","steps":["trace[1229637271] 'process raft request' (duration: 210.219917ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:47.029427Z","caller":"traceutil/trace.go:171","msg":"trace[1818111228] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"208.582141ms","start":"2026-03-12T19:09:46.820829Z","end":"2026-03-12T19:09:47.029411Z","steps":["trace[1818111228] 'process raft request' (duration: 207.964141ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:09:47.315245Z","caller":"traceutil/trace.go:171","msg":"trace[2032294753] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"103.036417ms","start":"2026-03-12T19:09:47.21218Z","end":"2026-03-12T19:09:47.315217Z","steps":["trace[2032294753] 'process raft request' (duration: 90.578502ms)","trace[2032294753] 'compare' (duration: 12.247479ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:09:48.064953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.221201ms","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-03-12T19:09:48.065042Z","caller":"traceutil/trace.go:171","msg":"trace[235763124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"122.365125ms","start":"2026-03-12T19:09:47.942658Z","end":"2026-03-12T19:09:48.065023Z","steps":["trace[235763124] 'range keys from in-memory index tree' (duration: 122.049845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.016673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-12T19:09:50.056866Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.227052156s","expected-duration":"1s"} {"level":"info","ts":"2026-03-12T19:09:50.0572Z","caller":"traceutil/trace.go:171","msg":"trace[734443374] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"540.90948ms","start":"2026-03-12T19:09:49.516269Z","end":"2026-03-12T19:09:50.057179Z","steps":["trace[734443374] 'read index received' (duration: 540.759135ms)","trace[734443374] 'applied index is now lower than readState.Index' (duration: 149.325µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:50.057316Z","caller":"traceutil/trace.go:171","msg":"trace[180953689] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.227582093s","start":"2026-03-12T19:09:48.829714Z","end":"2026-03-12T19:09:50.057296Z","steps":["trace[180953689] 'process raft request' (duration: 1.227340045s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.05734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.057794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:50.057372Z","caller":"traceutil/trace.go:171","msg":"trace[1002619647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:391; }","duration":"541.143517ms","start":"2026-03-12T19:09:49.516219Z","end":"2026-03-12T19:09:50.057362Z","steps":["trace[1002619647] 'agreement among raft nodes before linearized reading' (duration: 541.073525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.057404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:49.516199Z","time spent":"541.198029ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-12T19:09:50.057438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:48.829695Z","time spent":"1.227674266s","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:50.05751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.35362ms","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-03-12T19:09:50.057572Z","caller":"traceutil/trace.go:171","msg":"trace[1652190523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"220.491855ms","start":"2026-03-12T19:09:49.837064Z","end":"2026-03-12T19:09:50.057556Z","steps":["trace[1652190523] 'agreement among raft nodes before linearized reading' (duration: 220.304709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.562614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446569,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-12T19:09:50.661256Z","caller":"traceutil/trace.go:171","msg":"trace[2035180610] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"599.804926ms","start":"2026-03-12T19:09:50.061425Z","end":"2026-03-12T19:09:50.66123Z","steps":["trace[2035180610] 'process raft request' (duration: 593.45894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.661381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:50.061403Z","time spent":"599.922211ms","remote":"127.0.0.1:51502","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-03-12T19:09:50.678086Z","caller":"traceutil/trace.go:171","msg":"trace[2019604184] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:406; }","duration":"615.9082ms","start":"2026-03-12T19:09:50.062163Z","end":"2026-03-12T19:09:50.678071Z","steps":["trace[2019604184] 'read index received' (duration: 592.812569ms)","trace[2019604184] 'applied index is now lower than readState.Index' (duration: 23.094691ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:09:50.678149Z","caller":"traceutil/trace.go:171","msg":"trace[99414851] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"616.019564ms","start":"2026-03-12T19:09:50.062104Z","end":"2026-03-12T19:09:50.678124Z","steps":["trace[99414851] 'process raft request' (duration: 615.878419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.678196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.025753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-12T19:09:50.678235Z","caller":"traceutil/trace.go:171","msg":"trace[650363151] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:393; }","duration":"616.113937ms","start":"2026-03-12T19:09:50.062111Z","end":"2026-03-12T19:09:50.678225Z","steps":["trace[650363151] 'agreement among raft nodes before linearized reading' (duration: 616.040305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.678274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:50.062087Z","time spent":"616.178129ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-12T19:09:50.678314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:50.062083Z","time spent":"616.145188ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:09:50.719063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.419301ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:09:50.719161Z","caller":"traceutil/trace.go:171","msg":"trace[1859581476] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:396; }","duration":"510.530234ms","start":"2026-03-12T19:09:50.208611Z","end":"2026-03-12T19:09:50.719141Z","steps":["trace[1859581476] 'agreement among raft nodes before linearized reading' (duration: 510.304597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.719093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.156052ms","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-03-12T19:09:50.719376Z","caller":"traceutil/trace.go:171","msg":"trace[505627220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"558.454782ms","start":"2026-03-12T19:09:50.16088Z","end":"2026-03-12T19:09:50.719335Z","steps":["trace[505627220] 'agreement among raft nodes before linearized reading' (duration: 558.071019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.719433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:50.160866Z","time spent":"558.550105ms","remote":"127.0.0.1:51502","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-03-12T19:09:50.719536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.195583ms","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-03-12T19:09:50.719582Z","caller":"traceutil/trace.go:171","msg":"trace[644441471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"602.230944ms","start":"2026-03-12T19:09:50.117333Z","end":"2026-03-12T19:09:50.719564Z","steps":["trace[644441471] 'agreement among raft nodes before linearized reading' (duration: 601.543132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:09:50.719613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:09:50.117287Z","time spent":"602.321128ms","remote":"127.0.0.1:51502","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-03-12T19:09:51.53675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.777269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-12T19:09:51.536838Z","caller":"traceutil/trace.go:171","msg":"trace[1023610647] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:406; }","duration":"253.927585ms","start":"2026-03-12T19:09:51.282891Z","end":"2026-03-12T19:09:51.536819Z","steps":["trace[1023610647] 'range keys from in-memory index tree' (duration: 253.592694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.483399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446830,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-12T19:10:11.830938Z","caller":"traceutil/trace.go:171","msg":"trace[1458549659] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"848.244587ms","start":"2026-03-12T19:10:10.982662Z","end":"2026-03-12T19:10:11.830907Z","steps":["trace[1458549659] 'read index received' (duration: 848.159803ms)","trace[1458549659] 'applied index is now lower than readState.Index' (duration: 83.094µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:11.831269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.453463ms","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-03-12T19:10:11.83132Z","caller":"traceutil/trace.go:171","msg":"trace[2089345402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"848.684949ms","start":"2026-03-12T19:10:10.982621Z","end":"2026-03-12T19:10:11.831306Z","steps":["trace[2089345402] 'agreement among raft nodes before linearized reading' (duration: 848.447842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.831454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.943156ms","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-03-12T19:10:11.831497Z","caller":"traceutil/trace.go:171","msg":"trace[1628087588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"836.003317ms","start":"2026-03-12T19:10:10.99548Z","end":"2026-03-12T19:10:11.831483Z","steps":["trace[1628087588] 'agreement among raft nodes before linearized reading' (duration: 835.932596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.831524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:10.995469Z","time spent":"836.049389ms","remote":"127.0.0.1:51502","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-03-12T19:10:11.831398Z","caller":"traceutil/trace.go:171","msg":"trace[774671931] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"940.251111ms","start":"2026-03-12T19:10:10.89096Z","end":"2026-03-12T19:10:11.831211Z","steps":["trace[774671931] 'process raft request' (duration: 939.773307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.831699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.273509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:11.831783Z","caller":"traceutil/trace.go:171","msg":"trace[1334631493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"269.356911ms","start":"2026-03-12T19:10:11.562384Z","end":"2026-03-12T19:10:11.83174Z","steps":["trace[1334631493] 'agreement among raft nodes before linearized reading' (duration: 269.29336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.831817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.132445ms","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-03-12T19:10:11.831852Z","caller":"traceutil/trace.go:171","msg":"trace[741322800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"817.165906ms","start":"2026-03-12T19:10:11.014676Z","end":"2026-03-12T19:10:11.831842Z","steps":["trace[741322800] 'agreement among raft nodes before linearized reading' (duration: 817.109025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.831892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:11.014623Z","time spent":"817.262628ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-12T19:10:11.831362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:10.982603Z","time spent":"848.748781ms","remote":"127.0.0.1:51502","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-03-12T19:10:11.831601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.172977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-12T19:10:11.832544Z","caller":"traceutil/trace.go:171","msg":"trace[1970265579] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:523; }","duration":"548.172515ms","start":"2026-03-12T19:10:11.284348Z","end":"2026-03-12T19:10:11.832521Z","steps":["trace[1970265579] 'agreement among raft nodes before linearized reading' (duration: 547.122966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:11.832611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:11.284325Z","time spent":"548.266197ms","remote":"127.0.0.1:51278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-03-12T19:10:11.832597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:10.890948Z","time spent":"940.822896ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:10:14.088024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.480748ms","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-03-12T19:10:14.087961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.711754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:14.088106Z","caller":"traceutil/trace.go:171","msg":"trace[612986751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"250.599131ms","start":"2026-03-12T19:10:13.837479Z","end":"2026-03-12T19:10:14.088078Z","steps":["trace[612986751] 'range keys from in-memory index tree' (duration: 250.309284ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:14.088137Z","caller":"traceutil/trace.go:171","msg":"trace[1400835593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"525.93009ms","start":"2026-03-12T19:10:13.562193Z","end":"2026-03-12T19:10:14.088123Z","steps":["trace[1400835593] 'range keys from in-memory index tree' (duration: 525.603171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.088168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:13.562173Z","time spent":"525.986961ms","remote":"127.0.0.1:51218","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-03-12T19:10:14.088339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.12095ms","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-03-12T19:10:14.088368Z","caller":"traceutil/trace.go:171","msg":"trace[493864459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"239.169191ms","start":"2026-03-12T19:10:13.84919Z","end":"2026-03-12T19:10:14.088359Z","steps":["trace[493864459] 'range keys from in-memory index tree' (duration: 239.037317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.088537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.237112ms","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-03-12T19:10:14.088562Z","caller":"traceutil/trace.go:171","msg":"trace[760171411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"239.292884ms","start":"2026-03-12T19:10:13.849262Z","end":"2026-03-12T19:10:14.088555Z","steps":["trace[760171411] 'range keys from in-memory index tree' (duration: 239.094339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.088705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.405947ms","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-03-12T19:10:14.088739Z","caller":"traceutil/trace.go:171","msg":"trace[1852587490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"239.4839ms","start":"2026-03-12T19:10:13.849248Z","end":"2026-03-12T19:10:14.088732Z","steps":["trace[1852587490] 'range keys from in-memory index tree' (duration: 239.262323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.853956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.490051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:14.854383Z","caller":"traceutil/trace.go:171","msg":"trace[1403662555] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"760.090391ms","start":"2026-03-12T19:10:14.094272Z","end":"2026-03-12T19:10:14.854363Z","steps":["trace[1403662555] 'process raft request' (duration: 759.781073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.8545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:14.094263Z","time spent":"760.159862ms","remote":"127.0.0.1:51502","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-03-12T19:10:14.8545Z","caller":"traceutil/trace.go:171","msg":"trace[384032357] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"761.019236ms","start":"2026-03-12T19:10:14.093417Z","end":"2026-03-12T19:10:14.854437Z","steps":["trace[384032357] 'process raft request' (duration: 108.915048ms)","trace[384032357] 'compare' (duration: 651.361858ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:14.854639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:14.093393Z","time spent":"761.169089ms","remote":"127.0.0.1:51502","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-03-12T19:10:14.854711Z","caller":"traceutil/trace.go:171","msg":"trace[1653479484] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"759.048542ms","start":"2026-03-12T19:10:14.095648Z","end":"2026-03-12T19:10:14.854697Z","steps":["trace[1653479484] 'process raft request' (duration: 758.471736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:14.854779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:14.095637Z","time spent":"759.105284ms","remote":"127.0.0.1:51502","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-03-12T19:10:21.364521Z","caller":"traceutil/trace.go:171","msg":"trace[1043223863] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"485.068113ms","start":"2026-03-12T19:10:20.879433Z","end":"2026-03-12T19:10:21.364501Z","steps":["trace[1043223863] 'process raft request' (duration: 484.967011ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:21.364619Z","caller":"traceutil/trace.go:171","msg":"trace[1131011489] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:593; }","duration":"485.141275ms","start":"2026-03-12T19:10:20.879457Z","end":"2026-03-12T19:10:21.364599Z","steps":["trace[1131011489] 'read index received' (duration: 485.132985ms)","trace[1131011489] 'applied index is now lower than readState.Index' (duration: 6.54µs)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:21.36467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:20.879418Z","time spent":"485.177726ms","remote":"127.0.0.1:51502","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-03-12T19:10:21.364785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.316159ms","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-03-12T19:10:21.364818Z","caller":"traceutil/trace.go:171","msg":"trace[43789173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"485.377491ms","start":"2026-03-12T19:10:20.879431Z","end":"2026-03-12T19:10:21.364808Z","steps":["trace[43789173] 'agreement among raft nodes before linearized reading' (duration: 485.250428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:21.364848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:20.879415Z","time spent":"485.425972ms","remote":"127.0.0.1:51502","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-03-12T19:10:21.865358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446933,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-12T19:10:22.228855Z","caller":"traceutil/trace.go:171","msg":"trace[14988466] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.349226564s","start":"2026-03-12T19:10:20.879603Z","end":"2026-03-12T19:10:22.228829Z","steps":["trace[14988466] 'process raft request' (duration: 1.34904884s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:22.229003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:20.879594Z","time spent":"1.349334518s","remote":"127.0.0.1:51502","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-03-12T19:10:22.517726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.899799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:22.517878Z","caller":"traceutil/trace.go:171","msg":"trace[1294248508] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"1.153197085s","start":"2026-03-12T19:10:21.364661Z","end":"2026-03-12T19:10:22.517858Z","steps":["trace[1294248508] 'read index received' (duration: 864.038119ms)","trace[1294248508] 'applied index is now lower than readState.Index' (duration: 289.157706ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:22.517985Z","caller":"traceutil/trace.go:171","msg":"trace[193908038] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.149125779s","start":"2026-03-12T19:10:21.368844Z","end":"2026-03-12T19:10:22.517969Z","steps":["trace[193908038] 'process raft request' (duration: 960.863831ms)","trace[193908038] 'compare' (duration: 187.705994ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:22.518042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:21.368825Z","time spent":"1.149187982s","remote":"127.0.0.1:51502","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-03-12T19:10:22.518137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233541631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-12T19:10:22.518229Z","caller":"traceutil/trace.go:171","msg":"trace[1786467743] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:573; }","duration":"1.233683794s","start":"2026-03-12T19:10:21.28452Z","end":"2026-03-12T19:10:22.518204Z","steps":["trace[1786467743] 'agreement among raft nodes before linearized reading' (duration: 1.233459368s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:22.518282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:21.284499Z","time spent":"1.233771267s","remote":"127.0.0.1:51278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-03-12T19:10:22.518283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201356393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-txqpv\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-03-12T19:10:22.518285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.967793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-12T19:10:22.518338Z","caller":"traceutil/trace.go:171","msg":"trace[1052659799] range","detail":"{range_begin:/registry/pods/kube-system/cilium-txqpv; range_end:; response_count:1; response_revision:573; }","duration":"1.201412064s","start":"2026-03-12T19:10:21.31691Z","end":"2026-03-12T19:10:22.518322Z","steps":["trace[1052659799] 'agreement among raft nodes before linearized reading' (duration: 1.201170427s)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:22.518362Z","caller":"traceutil/trace.go:171","msg":"trace[2113600854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"392.099056ms","start":"2026-03-12T19:10:22.126238Z","end":"2026-03-12T19:10:22.518337Z","steps":["trace[2113600854] 'agreement among raft nodes before linearized reading' (duration: 391.854739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:22.518383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:21.316865Z","time spent":"1.201509417s","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-txqpv\" "} {"level":"warn","ts":"2026-03-12T19:10:22.518411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:22.126214Z","time spent":"392.187688ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-12T19:10:23.062001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-12T19:10:23.562198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707763285588446936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-12T19:10:23.579148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.059162229s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-12T19:10:23.657245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:22.519829Z","time spent":"1.137401445s","remote":"127.0.0.1:51278","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-12T19:10:23.861928Z","caller":"traceutil/trace.go:171","msg":"trace[175043229] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.338363824s","start":"2026-03-12T19:10:22.523543Z","end":"2026-03-12T19:10:23.861906Z","steps":["trace[175043229] 'process raft request' (duration: 1.25069298s)","trace[175043229] 'compare' (duration: 87.49635ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:23.862054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:22.523477Z","time spent":"1.338511958s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:10:23.86235Z","caller":"traceutil/trace.go:171","msg":"trace[1628335428] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:595; }","duration":"1.301114021s","start":"2026-03-12T19:10:22.561205Z","end":"2026-03-12T19:10:23.86232Z","steps":["trace[1628335428] 'read index received' (duration: 1.018148511s)","trace[1628335428] 'applied index is now lower than readState.Index' (duration: 282.96454ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:23.862396Z","caller":"traceutil/trace.go:171","msg":"trace[1205962389] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.330882063s","start":"2026-03-12T19:10:22.531506Z","end":"2026-03-12T19:10:23.862388Z","steps":["trace[1205962389] 'process raft request' (duration: 1.33035747s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:23.862474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:22.531487Z","time spent":"1.330955945s","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:10:23.862511Z","caller":"traceutil/trace.go:171","msg":"trace[1356308531] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"435.965363ms","start":"2026-03-12T19:10:23.426534Z","end":"2026-03-12T19:10:23.862499Z","steps":["trace[1356308531] 'process raft request' (duration: 435.647665ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:23.862531Z","caller":"traceutil/trace.go:171","msg":"trace[58355573] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"204.098809ms","start":"2026-03-12T19:10:23.658427Z","end":"2026-03-12T19:10:23.862525Z","steps":["trace[58355573] 'process raft request' (duration: 203.855583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:23.862621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:23.4265Z","time spent":"436.041205ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-12T19:10:23.862925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301717867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:23.862961Z","caller":"traceutil/trace.go:171","msg":"trace[1552538311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.301789199s","start":"2026-03-12T19:10:22.56116Z","end":"2026-03-12T19:10:23.862949Z","steps":["trace[1552538311] 'agreement among raft nodes before linearized reading' (duration: 1.301735498s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:23.862988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:22.561141Z","time spent":"1.301841651s","remote":"127.0.0.1:51218","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-03-12T19:10:23.862981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.875444ms","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-03-12T19:10:23.863049Z","caller":"traceutil/trace.go:171","msg":"trace[2127714655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"493.990467ms","start":"2026-03-12T19:10:23.369038Z","end":"2026-03-12T19:10:23.863029Z","steps":["trace[2127714655] 'agreement among raft nodes before linearized reading' (duration: 493.590156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:23.863092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:23.36902Z","time spent":"494.064089ms","remote":"127.0.0.1:51502","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-03-12T19:10:25.889683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000141358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-12T19:10:25.889814Z","caller":"traceutil/trace.go:171","msg":"trace[1147876617] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000287412s","start":"2026-03-12T19:10:23.889503Z","end":"2026-03-12T19:10:25.88979Z","steps":["trace[1147876617] 'agreement among raft nodes before linearized reading' (duration: 2.000138878s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:25.889883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:23.889471Z","time spent":"2.000396745s","remote":"127.0.0.1:51218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/12 19:10:25 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-12T19:10:27.889977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999904437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-12T19:10:27.890063Z","caller":"traceutil/trace.go:171","msg":"trace[1484140072] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000045651s","start":"2026-03-12T19:10:25.889998Z","end":"2026-03-12T19:10:27.890044Z","steps":["trace[1484140072] 'agreement among raft nodes before linearized reading' (duration: 1.999933378s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:27.890123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:25.889978Z","time spent":"2.000136033s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-12T19:10:28.153573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110282874s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:28.153704Z","caller":"traceutil/trace.go:171","msg":"trace[689635665] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:577; }","duration":"1.110433878s","start":"2026-03-12T19:10:27.04325Z","end":"2026-03-12T19:10:28.153684Z","steps":["trace[689635665] 'range keys from in-memory index tree' (duration: 1.110261024s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.154369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.188308471s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:28.15449Z","caller":"traceutil/trace.go:171","msg":"trace[493678948] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"4.264966156s","start":"2026-03-12T19:10:23.889507Z","end":"2026-03-12T19:10:28.154473Z","steps":["trace[493678948] 'read index received' (duration: 76.388798ms)","trace[493678948] 'applied index is now lower than readState.Index' (duration: 4.188575838s)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:28.154684Z","caller":"traceutil/trace.go:171","msg":"trace[1838960618] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"4.286247647s","start":"2026-03-12T19:10:23.868423Z","end":"2026-03-12T19:10:28.154671Z","steps":["trace[1838960618] 'process raft request' (duration: 97.548445ms)","trace[1838960618] 'compare' (duration: 4.187178704s)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:28.154745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:23.868411Z","time spent":"4.286301398s","remote":"127.0.0.1:51502","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-03-12T19:10:28.331535Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.356280705s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-12T19:10:28.436946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.037742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-12T19:10:28.437111Z","caller":"traceutil/trace.go:171","msg":"trace[768194624] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"3.461915642s","start":"2026-03-12T19:10:24.975142Z","end":"2026-03-12T19:10:28.437058Z","steps":["trace[768194624] 'process raft request' (duration: 3.356703865s)","trace[768194624] 'compare' (duration: 104.92729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:28.437285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:24.975115Z","time spent":"3.462070705s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:10:28.445261Z","caller":"traceutil/trace.go:171","msg":"trace[1246383182] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:601; }","duration":"290.726128ms","start":"2026-03-12T19:10:28.154517Z","end":"2026-03-12T19:10:28.445243Z","steps":["trace[1246383182] 'read index received' (duration: 177.29242ms)","trace[1246383182] 'applied index is now lower than readState.Index' (duration: 113.432538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:28.445517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545241826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:28.445638Z","caller":"traceutil/trace.go:171","msg":"trace[251656278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"1.54540661s","start":"2026-03-12T19:10:26.900204Z","end":"2026-03-12T19:10:28.445611Z","steps":["trace[251656278] 'agreement among raft nodes before linearized reading' (duration: 1.545238156s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.445659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.579073795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-12T19:10:28.445679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:26.900183Z","time spent":"1.545489711s","remote":"127.0.0.1:51218","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-03-12T19:10:28.445697Z","caller":"traceutil/trace.go:171","msg":"trace[2140505692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"2.579133625s","start":"2026-03-12T19:10:25.866551Z","end":"2026-03-12T19:10:28.445685Z","steps":["trace[2140505692] 'agreement among raft nodes before linearized reading' (duration: 2.579048033s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.445746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:25.866529Z","time spent":"2.579208588s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-12T19:10:28.445872Z","caller":"traceutil/trace.go:171","msg":"trace[1104004476] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"551.579627ms","start":"2026-03-12T19:10:27.894282Z","end":"2026-03-12T19:10:28.445862Z","steps":["trace[1104004476] 'process raft request' (duration: 550.770787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.445961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:27.894262Z","time spent":"551.640859ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-12T19:10:28.446079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.924280485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-12T19:10:28.446103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.684772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:28.446123Z","caller":"traceutil/trace.go:171","msg":"trace[327516922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"3.924355386s","start":"2026-03-12T19:10:24.521757Z","end":"2026-03-12T19:10:28.446113Z","steps":["trace[327516922] 'agreement among raft nodes before linearized reading' (duration: 3.924221963s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.446162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:24.521738Z","time spent":"3.924417568s","remote":"127.0.0.1:51502","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-03-12T19:10:28.445569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.212386788s","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-03-12T19:10:28.44658Z","caller":"traceutil/trace.go:171","msg":"trace[1440594225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"547.752873ms","start":"2026-03-12T19:10:27.898368Z","end":"2026-03-12T19:10:28.446121Z","steps":["trace[1440594225] 'agreement among raft nodes before linearized reading' (duration: 547.715472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.446614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:27.898352Z","time spent":"548.256746ms","remote":"127.0.0.1:51234","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-03-12T19:10:28.446618Z","caller":"traceutil/trace.go:171","msg":"trace[1638633391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"4.21320849s","start":"2026-03-12T19:10:24.233103Z","end":"2026-03-12T19:10:28.446311Z","steps":["trace[1638633391] 'agreement among raft nodes before linearized reading' (duration: 4.212209094s)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:28.446671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:24.233056Z","time spent":"4.213601849s","remote":"127.0.0.1:51502","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-03-12T19:10:30.107749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.534039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:30.107847Z","caller":"traceutil/trace.go:171","msg":"trace[1897088491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"546.673133ms","start":"2026-03-12T19:10:29.56115Z","end":"2026-03-12T19:10:30.107823Z","steps":["trace[1897088491] 'range keys from in-memory index tree' (duration: 546.447917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:30.107898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:29.561133Z","time spent":"546.752214ms","remote":"127.0.0.1:51218","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-03-12T19:10:34.281781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.724975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-12T19:10:34.281873Z","caller":"traceutil/trace.go:171","msg":"trace[1666837648] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:652; }","duration":"544.832187ms","start":"2026-03-12T19:10:33.737018Z","end":"2026-03-12T19:10:34.28185Z","steps":["trace[1666837648] 'range keys from in-memory index tree' (duration: 544.52503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:34.28192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:33.736944Z","time spent":"544.9656ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-03-12T19:10:34.28273Z","caller":"traceutil/trace.go:171","msg":"trace[893604015] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"318.623421ms","start":"2026-03-12T19:10:33.964073Z","end":"2026-03-12T19:10:34.282696Z","steps":["trace[893604015] 'process raft request' (duration: 315.864636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:34.282873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:10:33.96404Z","time spent":"318.770255ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-12T19:10:34.301175Z","caller":"traceutil/trace.go:171","msg":"trace[480586414] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"164.02995ms","start":"2026-03-12T19:10:34.137124Z","end":"2026-03-12T19:10:34.301153Z","steps":["trace[480586414] 'read index received' (duration: 142.846168ms)","trace[480586414] 'applied index is now lower than readState.Index' (duration: 21.182792ms)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:34.301424Z","caller":"traceutil/trace.go:171","msg":"trace[1415349603] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"181.780711ms","start":"2026-03-12T19:10:34.119599Z","end":"2026-03-12T19:10:34.30138Z","steps":["trace[1415349603] 'process raft request' (duration: 181.412292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:34.301527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.290878ms","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-03-12T19:10:34.301559Z","caller":"traceutil/trace.go:171","msg":"trace[514260014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"124.36737ms","start":"2026-03-12T19:10:34.17718Z","end":"2026-03-12T19:10:34.301547Z","steps":["trace[514260014] 'agreement among raft nodes before linearized reading' (duration: 124.249287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:34.301735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.566943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-12T19:10:34.30184Z","caller":"traceutil/trace.go:171","msg":"trace[451449261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"164.701326ms","start":"2026-03-12T19:10:34.137112Z","end":"2026-03-12T19:10:34.301813Z","steps":["trace[451449261] 'agreement among raft nodes before linearized reading' (duration: 164.189254ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:35.366674Z","caller":"traceutil/trace.go:171","msg":"trace[2004030680] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"108.607521ms","start":"2026-03-12T19:10:35.258046Z","end":"2026-03-12T19:10:35.366654Z","steps":["trace[2004030680] 'process raft request' (duration: 52.17393ms)","trace[2004030680] 'compare' (duration: 56.174325ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:35.366877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.920703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:35.366932Z","caller":"traceutil/trace.go:171","msg":"trace[1453116949] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"105.001936ms","start":"2026-03-12T19:10:35.261916Z","end":"2026-03-12T19:10:35.366918Z","steps":["trace[1453116949] 'agreement among raft nodes before linearized reading' (duration: 104.912444ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:35.367237Z","caller":"traceutil/trace.go:171","msg":"trace[732523765] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"104.659897ms","start":"2026-03-12T19:10:35.261938Z","end":"2026-03-12T19:10:35.366598Z","steps":["trace[732523765] 'read index received' (duration: 48.297139ms)","trace[732523765] 'applied index is now lower than readState.Index' (duration: 56.361198ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:10:35.367318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.304133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:35.367335Z","caller":"traceutil/trace.go:171","msg":"trace[419362428] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:671; }","duration":"105.353673ms","start":"2026-03-12T19:10:35.261974Z","end":"2026-03-12T19:10:35.367328Z","steps":["trace[419362428] 'agreement among raft nodes before linearized reading' (duration: 105.324653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:35.367423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.031566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-12T19:10:35.36744Z","caller":"traceutil/trace.go:171","msg":"trace[1822118830] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:671; }","duration":"105.069177ms","start":"2026-03-12T19:10:35.262367Z","end":"2026-03-12T19:10:35.367436Z","steps":["trace[1822118830] 'agreement among raft nodes before linearized reading' (duration: 105.040956ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:10:54.730198Z","caller":"traceutil/trace.go:171","msg":"trace[978560292] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"140.000318ms","start":"2026-03-12T19:10:54.590178Z","end":"2026-03-12T19:10:54.730179Z","steps":["trace[978560292] 'read index received' (duration: 139.871955ms)","trace[978560292] 'applied index is now lower than readState.Index' (duration: 127.593µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:10:54.730275Z","caller":"traceutil/trace.go:171","msg":"trace[1675983816] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"143.481251ms","start":"2026-03-12T19:10:54.586768Z","end":"2026-03-12T19:10:54.73025Z","steps":["trace[1675983816] 'process raft request' (duration: 143.291407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:54.730398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.201952ms","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-03-12T19:10:54.730419Z","caller":"traceutil/trace.go:171","msg":"trace[529080249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"140.287054ms","start":"2026-03-12T19:10:54.590126Z","end":"2026-03-12T19:10:54.730413Z","steps":["trace[529080249] 'agreement among raft nodes before linearized reading' (duration: 140.154331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:54.730532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.889643ms","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-03-12T19:10:54.730648Z","caller":"traceutil/trace.go:171","msg":"trace[1787756080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"139.035576ms","start":"2026-03-12T19:10:54.591576Z","end":"2026-03-12T19:10:54.730612Z","steps":["trace[1787756080] 'agreement among raft nodes before linearized reading' (duration: 138.784251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:10:54.730857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.844341ms","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-03-12T19:10:54.731002Z","caller":"traceutil/trace.go:171","msg":"trace[494298250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"138.022145ms","start":"2026-03-12T19:10:54.592956Z","end":"2026-03-12T19:10:54.730978Z","steps":["trace[494298250] 'agreement among raft nodes before linearized reading' (duration: 137.576515ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:43.237719Z","caller":"traceutil/trace.go:171","msg":"trace[1281436275] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"138.836783ms","start":"2026-03-12T19:11:43.098858Z","end":"2026-03-12T19:11:43.237695Z","steps":["trace[1281436275] 'read index received' (duration: 133.694991ms)","trace[1281436275] 'applied index is now lower than readState.Index' (duration: 5.140822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-12T19:11:43.237918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.043907ms","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-03-12T19:11:43.237853Z","caller":"traceutil/trace.go:171","msg":"trace[995243445] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"140.961691ms","start":"2026-03-12T19:11:43.096858Z","end":"2026-03-12T19:11:43.23782Z","steps":["trace[995243445] 'process raft request' (duration: 135.745847ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:43.237965Z","caller":"traceutil/trace.go:171","msg":"trace[894128129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"139.104078ms","start":"2026-03-12T19:11:43.09885Z","end":"2026-03-12T19:11:43.237954Z","steps":["trace[894128129] 'agreement among raft nodes before linearized reading' (duration: 138.955525ms)"],"step_count":1} {"level":"info","ts":"2026-03-12T19:11:43.557299Z","caller":"traceutil/trace.go:171","msg":"trace[1867067949] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"315.33122ms","start":"2026-03-12T19:11:43.24194Z","end":"2026-03-12T19:11:43.557271Z","steps":["trace[1867067949] 'process raft request' (duration: 315.192938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:43.557468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:43.241918Z","time spent":"315.467073ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-12T19:11:53.450645Z","caller":"traceutil/trace.go:171","msg":"trace[1283409722] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"432.029342ms","start":"2026-03-12T19:11:53.018582Z","end":"2026-03-12T19:11:53.450611Z","steps":["trace[1283409722] 'read index received' (duration: 431.666615ms)","trace[1283409722] 'applied index is now lower than readState.Index' (duration: 361.367µs)"],"step_count":2} {"level":"info","ts":"2026-03-12T19:11:53.451149Z","caller":"traceutil/trace.go:171","msg":"trace[1916446431] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"591.335257ms","start":"2026-03-12T19:11:52.859766Z","end":"2026-03-12T19:11:53.451101Z","steps":["trace[1916446431] 'process raft request' (duration: 590.624794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:53.451288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.079736ms","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-03-12T19:11:53.451451Z","caller":"traceutil/trace.go:171","msg":"trace[62530360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"163.324651ms","start":"2026-03-12T19:11:53.288095Z","end":"2026-03-12T19:11:53.45142Z","steps":["trace[62530360] 'agreement among raft nodes before linearized reading' (duration: 162.915354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:53.451504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:52.85974Z","time spent":"591.478019ms","remote":"127.0.0.1:51502","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-03-12T19:11:53.451278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.92975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-12T19:11:53.451709Z","caller":"traceutil/trace.go:171","msg":"trace[905805024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"365.398638ms","start":"2026-03-12T19:11:53.086248Z","end":"2026-03-12T19:11:53.451647Z","steps":["trace[905805024] 'agreement among raft nodes before linearized reading' (duration: 364.736347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:53.451813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:53.086224Z","time spent":"365.568691ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-12T19:11:53.451288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.705283ms","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-03-12T19:11:53.45197Z","caller":"traceutil/trace.go:171","msg":"trace[1780882732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"433.498477ms","start":"2026-03-12T19:11:53.018451Z","end":"2026-03-12T19:11:53.45195Z","steps":["trace[1780882732] 'agreement among raft nodes before linearized reading' (duration: 432.488829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-12T19:11:53.452084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-12T19:11:53.018416Z","time spent":"433.627068ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}