{"level":"warn","ts":"2026-09-22T03:37:30.44564Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:37:30.445785Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-22T03:37:30.445891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:37:30.445906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-22T03:37:30.445949Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T03:37:30.446896Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-22T03:37:30.447006Z","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.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-22T03:37:30.453147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.789257ms"} {"level":"info","ts":"2026-09-22T03:37:30.465243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-22T03:37:30.465392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:37:30.46542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-22T03:37:30.465432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:37:30.465449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-22T03:37:30.465497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-22T03:37:30.478767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:37:30.480539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:37:30.482073Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-22T03:37:30.48446Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:37:30.48459Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:37:30.484792Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:37:30.485132Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:37:30.48515Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T03:37:30.486604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-22T03:37:30.488224Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-22T03:37:30.489383Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T03:37:30.48957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-22T03:37:30.489594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-22T03:37:30.49061Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:37:30.490632Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:37:30.566385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:37:30.566449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:37:30.566489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-22T03:37:30.56674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:37:30.566762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-22T03:37:30.566864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-22T03:37:30.566889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-22T03:37:30.568052Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:37:30.568999Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:37:30.569051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:37:30.569121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:37:30.56952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:37:30.569549Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:37:30.569604Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:37:30.569708Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:37:30.569747Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:37:30.572296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-22T03:37:30.573083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-22T03:37:36.987471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.844575ms","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-09-22T03:37:36.98761Z","caller":"traceutil/trace.go:171","msg":"trace[1451979371] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:302; }","duration":"130.064405ms","start":"2026-09-22T03:37:36.857525Z","end":"2026-09-22T03:37:36.987589Z","steps":["trace[1451979371] 'range keys from in-memory index tree' (duration: 129.674584ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.170678Z","caller":"traceutil/trace.go:171","msg":"trace[258943906] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"159.684677ms","start":"2026-09-22T03:37:37.010958Z","end":"2026-09-22T03:37:37.170643Z","steps":["trace[258943906] 'process raft request' (duration: 159.471246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:37:37.373975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.951164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-09-22T03:37:37.374009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.267737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:37:37.374067Z","caller":"traceutil/trace.go:171","msg":"trace[2101111780] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:303; }","duration":"146.116824ms","start":"2026-09-22T03:37:37.22793Z","end":"2026-09-22T03:37:37.374046Z","steps":["trace[2101111780] 'range keys from in-memory index tree' (duration: 145.840574ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.37412Z","caller":"traceutil/trace.go:171","msg":"trace[1006802230] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:303; }","duration":"166.439937ms","start":"2026-09-22T03:37:37.207656Z","end":"2026-09-22T03:37:37.374095Z","steps":["trace[1006802230] 'range keys from in-memory index tree' (duration: 166.198626ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.604234Z","caller":"traceutil/trace.go:171","msg":"trace[1550698467] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"372.706696ms","start":"2026-09-22T03:37:37.2315Z","end":"2026-09-22T03:37:37.604207Z","steps":["trace[1550698467] 'process raft request' (duration: 372.594136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:37:37.60488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:37:37.231466Z","time spent":"372.857247ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:37:37.66284Z","caller":"traceutil/trace.go:171","msg":"trace[11629381] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"284.931516ms","start":"2026-09-22T03:37:37.377879Z","end":"2026-09-22T03:37:37.66281Z","steps":["trace[11629381] 'read index received' (duration: 226.596001ms)","trace[11629381] 'applied index is now lower than readState.Index' (duration: 58.334445ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:37:37.662853Z","caller":"traceutil/trace.go:171","msg":"trace[1380526929] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"430.438468ms","start":"2026-09-22T03:37:37.232363Z","end":"2026-09-22T03:37:37.662801Z","steps":["trace[1380526929] 'process raft request' (duration: 430.178707ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.662983Z","caller":"traceutil/trace.go:171","msg":"trace[1883665719] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"429.620406ms","start":"2026-09-22T03:37:37.233335Z","end":"2026-09-22T03:37:37.662956Z","steps":["trace[1883665719] 'process raft request' (duration: 429.321514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:37:37.663044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.134068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-22T03:37:37.663082Z","caller":"traceutil/trace.go:171","msg":"trace[369916870] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:304; }","duration":"285.251048ms","start":"2026-09-22T03:37:37.377817Z","end":"2026-09-22T03:37:37.663068Z","steps":["trace[369916870] 'agreement among raft nodes before linearized reading' (duration: 285.152608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:37:37.663142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:37:37.233299Z","time spent":"429.750796ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:37:37.663281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.81845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-22T03:37:37.663251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.19193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T03:37:37.663371Z","caller":"traceutil/trace.go:171","msg":"trace[1176025055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:304; }","duration":"211.374652ms","start":"2026-09-22T03:37:37.451964Z","end":"2026-09-22T03:37:37.663339Z","steps":["trace[1176025055] 'agreement among raft nodes before linearized reading' (duration: 211.103501ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.663407Z","caller":"traceutil/trace.go:171","msg":"trace[1541694877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:304; }","duration":"210.92216ms","start":"2026-09-22T03:37:37.452399Z","end":"2026-09-22T03:37:37.663321Z","steps":["trace[1541694877] 'agreement among raft nodes before linearized reading' (duration: 210.703189ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:37:37.663628Z","caller":"traceutil/trace.go:171","msg":"trace[454726671] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"262.648715ms","start":"2026-09-22T03:37:37.40095Z","end":"2026-09-22T03:37:37.663599Z","steps":["trace[454726671] 'process raft request' (duration: 261.751721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:37:37.663965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:37:37.232345Z","time spent":"430.665271ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T03:38:14.004228Z","caller":"traceutil/trace.go:171","msg":"trace[485706772] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"105.63987ms","start":"2026-09-22T03:38:13.898554Z","end":"2026-09-22T03:38:14.004194Z","steps":["trace[485706772] 'process raft request' (duration: 92.592517ms)","trace[485706772] 'compare' (duration: 12.882805ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:38:18.628546Z","caller":"traceutil/trace.go:171","msg":"trace[1813630047] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"140.734506ms","start":"2026-09-22T03:38:18.487775Z","end":"2026-09-22T03:38:18.62851Z","steps":["trace[1813630047] 'process raft request' (duration: 140.090346ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:38:18.853161Z","caller":"traceutil/trace.go:171","msg":"trace[1442389195] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"156.487319ms","start":"2026-09-22T03:38:18.696641Z","end":"2026-09-22T03:38:18.853128Z","steps":["trace[1442389195] 'process raft request' (duration: 156.053198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:38:19.333302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.567812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:38:19.333382Z","caller":"traceutil/trace.go:171","msg":"trace[353393434] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"473.364605ms","start":"2026-09-22T03:38:18.860003Z","end":"2026-09-22T03:38:19.333368Z","steps":["trace[353393434] 'process raft request' (duration: 77.491258ms)","trace[353393434] 'compare' (duration: 395.474868ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:38:19.333421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:38:18.859967Z","time spent":"473.438178ms","remote":"127.0.0.1:35200","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-09-22T03:38:19.373004Z","caller":"traceutil/trace.go:171","msg":"trace[1422245887] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"509.941867ms","start":"2026-09-22T03:38:18.863029Z","end":"2026-09-22T03:38:19.37297Z","steps":["trace[1422245887] 'read index received' (duration: 74.476073ms)","trace[1422245887] 'applied index is now lower than readState.Index' (duration: 435.439813ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:38:19.373115Z","caller":"traceutil/trace.go:171","msg":"trace[248786390] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"510.182449ms","start":"2026-09-22T03:38:18.862877Z","end":"2026-09-22T03:38:19.373059Z","steps":["trace[248786390] 'process raft request' (duration: 509.884905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:38:19.373192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.127776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:38:19.373255Z","caller":"traceutil/trace.go:171","msg":"trace[1857480093] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:637; }","duration":"510.248812ms","start":"2026-09-22T03:38:18.86298Z","end":"2026-09-22T03:38:19.373229Z","steps":["trace[1857480093] 'agreement among raft nodes before linearized reading' (duration: 510.130526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:38:19.373304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:38:18.86296Z","time spent":"510.334146ms","remote":"127.0.0.1:35252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-09-22T03:38:19.373308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:38:18.862847Z","time spent":"510.362398ms","remote":"127.0.0.1:35200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:38:19.459935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.68951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:38:19.460043Z","caller":"traceutil/trace.go:171","msg":"trace[335401646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"123.865138ms","start":"2026-09-22T03:38:19.336154Z","end":"2026-09-22T03:38:19.460019Z","steps":["trace[335401646] 'agreement among raft nodes before linearized reading' (duration: 123.670949ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:38:20.691447Z","caller":"traceutil/trace.go:171","msg":"trace[1378979654] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"343.539321ms","start":"2026-09-22T03:38:20.347867Z","end":"2026-09-22T03:38:20.691407Z","steps":["trace[1378979654] 'read index received' (duration: 342.45095ms)","trace[1378979654] 'applied index is now lower than readState.Index' (duration: 1.087091ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:38:20.691518Z","caller":"traceutil/trace.go:171","msg":"trace[205872509] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"357.156855ms","start":"2026-09-22T03:38:20.334317Z","end":"2026-09-22T03:38:20.691474Z","steps":["trace[205872509] 'process raft request' (duration: 356.433581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:38:20.691643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.740479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zzczm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-22T03:38:20.691691Z","caller":"traceutil/trace.go:171","msg":"trace[1621139493] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zzczm; range_end:; response_count:1; response_revision:645; }","duration":"343.856585ms","start":"2026-09-22T03:38:20.347819Z","end":"2026-09-22T03:38:20.691675Z","steps":["trace[1621139493] 'agreement among raft nodes before linearized reading' (duration: 343.724819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:38:20.691717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:38:20.334274Z","time spent":"357.336213ms","remote":"127.0.0.1:35006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T03:38:20.691738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:38:20.347803Z","time spent":"343.923437ms","remote":"127.0.0.1:35114","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-zzczm\" "} {"level":"info","ts":"2026-09-22T03:38:37.675955Z","caller":"traceutil/trace.go:171","msg":"trace[112920021] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"160.944215ms","start":"2026-09-22T03:38:37.514975Z","end":"2026-09-22T03:38:37.675919Z","steps":["trace[112920021] 'process raft request' (duration: 110.574137ms)","trace[112920021] 'compare' (duration: 50.225843ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:38:37.679726Z","caller":"traceutil/trace.go:171","msg":"trace[197096589] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"160.79404ms","start":"2026-09-22T03:38:37.518907Z","end":"2026-09-22T03:38:37.679701Z","steps":["trace[197096589] 'process raft request' (duration: 160.655215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:08.620402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.876234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-22T03:39:08.620525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.049362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:39:08.620575Z","caller":"traceutil/trace.go:171","msg":"trace[1187915111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"290.169616ms","start":"2026-09-22T03:39:08.33039Z","end":"2026-09-22T03:39:08.62056Z","steps":["trace[1187915111] 'range keys from in-memory index tree' (duration: 289.98087ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:39:08.620666Z","caller":"traceutil/trace.go:171","msg":"trace[363226553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"116.043419ms","start":"2026-09-22T03:39:08.504442Z","end":"2026-09-22T03:39:08.620485Z","steps":["trace[363226553] 'range keys from in-memory index tree' (duration: 115.69681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:39:08.62108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.22087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T03:39:08.621138Z","caller":"traceutil/trace.go:171","msg":"trace[407612296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"256.277932ms","start":"2026-09-22T03:39:08.364846Z","end":"2026-09-22T03:39:08.621124Z","steps":["trace[407612296] 'range keys from in-memory index tree' (duration: 255.543632ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:00.561616Z","caller":"traceutil/trace.go:171","msg":"trace[288710597] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"217.049921ms","start":"2026-09-22T03:40:00.344543Z","end":"2026-09-22T03:40:00.561593Z","steps":["trace[288710597] 'process raft request' (duration: 216.877518ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:01.038841Z","caller":"traceutil/trace.go:171","msg":"trace[1465158573] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"124.449095ms","start":"2026-09-22T03:40:00.914351Z","end":"2026-09-22T03:40:01.0388Z","steps":["trace[1465158573] 'process raft request' (duration: 124.258672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:40:01.03916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.461925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-22T03:40:01.039301Z","caller":"traceutil/trace.go:171","msg":"trace[1372687929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"117.614858ms","start":"2026-09-22T03:40:00.921662Z","end":"2026-09-22T03:40:01.039276Z","steps":["trace[1372687929] 'agreement among raft nodes before linearized reading' (duration: 117.248001ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:01.039213Z","caller":"traceutil/trace.go:171","msg":"trace[1940389149] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"117.090199ms","start":"2026-09-22T03:40:00.921674Z","end":"2026-09-22T03:40:01.038764Z","steps":["trace[1940389149] 'read index received' (duration: 116.889496ms)","trace[1940389149] 'applied index is now lower than readState.Index' (duration: 199.513µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:01.039702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.683591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T03:40:01.039765Z","caller":"traceutil/trace.go:171","msg":"trace[194336015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"105.784883ms","start":"2026-09-22T03:40:00.933946Z","end":"2026-09-22T03:40:01.039731Z","steps":["trace[194336015] 'agreement among raft nodes before linearized reading' (duration: 105.515259ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:35.876271Z","caller":"traceutil/trace.go:171","msg":"trace[641968087] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"231.235196ms","start":"2026-09-22T03:40:35.644994Z","end":"2026-09-22T03:40:35.876229Z","steps":["trace[641968087] 'process raft request' (duration: 151.00112ms)","trace[641968087] 'compare' (duration: 80.101744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:40:35.876404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.766767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T03:40:35.876605Z","caller":"traceutil/trace.go:171","msg":"trace[1042606709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2146; }","duration":"124.02134ms","start":"2026-09-22T03:40:35.752561Z","end":"2026-09-22T03:40:35.876582Z","steps":["trace[1042606709] 'agreement among raft nodes before linearized reading' (duration: 123.728716ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:35.876232Z","caller":"traceutil/trace.go:171","msg":"trace[593810384] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"123.560844ms","start":"2026-09-22T03:40:35.752616Z","end":"2026-09-22T03:40:35.876177Z","steps":["trace[593810384] 'read index received' (duration: 43.331618ms)","trace[593810384] 'applied index is now lower than readState.Index' (duration: 80.228096ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:40:35.877203Z","caller":"traceutil/trace.go:171","msg":"trace[288378305] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"108.948979ms","start":"2026-09-22T03:40:35.768221Z","end":"2026-09-22T03:40:35.87717Z","steps":["trace[288378305] 'process raft request' (duration: 108.799017ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:40:36.254581Z","caller":"traceutil/trace.go:171","msg":"trace[110553488] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"127.042654ms","start":"2026-09-22T03:40:36.127428Z","end":"2026-09-22T03:40:36.254471Z","steps":["trace[110553488] 'process raft request' (duration: 126.900732ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:41.612017Z","caller":"traceutil/trace.go:171","msg":"trace[1811284083] linearizableReadLoop","detail":"{readStateIndex:2703; appliedIndex:2702; }","duration":"182.467103ms","start":"2026-09-22T03:41:41.429526Z","end":"2026-09-22T03:41:41.611993Z","steps":["trace[1811284083] 'read index received' (duration: 182.320482ms)","trace[1811284083] 'applied index is now lower than readState.Index' (duration: 145.591µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:41:41.612042Z","caller":"traceutil/trace.go:171","msg":"trace[1561654804] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"183.132959ms","start":"2026-09-22T03:41:41.428887Z","end":"2026-09-22T03:41:41.61202Z","steps":["trace[1561654804] 'process raft request' (duration: 182.949708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:41:41.612276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.714556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-22T03:41:41.612364Z","caller":"traceutil/trace.go:171","msg":"trace[668391293] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2603; }","duration":"182.840147ms","start":"2026-09-22T03:41:41.429505Z","end":"2026-09-22T03:41:41.612345Z","steps":["trace[668391293] 'agreement among raft nodes before linearized reading' (duration: 182.583804ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:41:41.626211Z","caller":"traceutil/trace.go:171","msg":"trace[419031999] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"135.128733ms","start":"2026-09-22T03:41:41.491022Z","end":"2026-09-22T03:41:41.626151Z","steps":["trace[419031999] 'process raft request' (duration: 134.958591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:42:12.797056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.362377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:42:12.797155Z","caller":"traceutil/trace.go:171","msg":"trace[1222006550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2776; }","duration":"160.5084ms","start":"2026-09-22T03:42:12.636626Z","end":"2026-09-22T03:42:12.797135Z","steps":["trace[1222006550] 'count revisions from in-memory index tree' (duration: 160.250437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:42:18.252232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.773371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:42:18.252396Z","caller":"traceutil/trace.go:171","msg":"trace[558835165] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"168.112696ms","start":"2026-09-22T03:42:18.084257Z","end":"2026-09-22T03:42:18.25237Z","steps":["trace[558835165] 'process raft request' (duration: 23.091703ms)","trace[558835165] 'compare' (duration: 144.616309ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:46.030631Z","caller":"traceutil/trace.go:171","msg":"trace[1444874769] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"111.934373ms","start":"2026-09-22T03:46:45.918678Z","end":"2026-09-22T03:46:46.030613Z","steps":["trace[1444874769] 'process raft request' (duration: 111.818411ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:46.03128Z","caller":"traceutil/trace.go:171","msg":"trace[1929388519] linearizableReadLoop","detail":"{readStateIndex:4981; appliedIndex:4981; }","duration":"109.030122ms","start":"2026-09-22T03:46:45.922218Z","end":"2026-09-22T03:46:46.031248Z","steps":["trace[1929388519] 'read index received' (duration: 109.011432ms)","trace[1929388519] 'applied index is now lower than readState.Index' (duration: 11.78µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:46:46.031564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.316347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-22T03:46:46.031593Z","caller":"traceutil/trace.go:171","msg":"trace[1418033758] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4794; }","duration":"109.424509ms","start":"2026-09-22T03:46:45.922161Z","end":"2026-09-22T03:46:46.031585Z","steps":["trace[1418033758] 'agreement among raft nodes before linearized reading' (duration: 109.221656ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:46.049762Z","caller":"traceutil/trace.go:171","msg":"trace[1227860347] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"124.343716ms","start":"2026-09-22T03:46:45.925389Z","end":"2026-09-22T03:46:46.049732Z","steps":["trace[1227860347] 'process raft request' (duration: 124.115733ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:46.049935Z","caller":"traceutil/trace.go:171","msg":"trace[1260501624] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"114.175651ms","start":"2026-09-22T03:46:45.935742Z","end":"2026-09-22T03:46:46.049917Z","steps":["trace[1260501624] 'process raft request' (duration: 113.899626ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:52.5408Z","caller":"traceutil/trace.go:171","msg":"trace[1157649761] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"124.20599ms","start":"2026-09-22T03:46:52.416546Z","end":"2026-09-22T03:46:52.540752Z","steps":["trace[1157649761] 'process raft request' (duration: 106.864395ms)","trace[1157649761] 'compare' (duration: 17.073081ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:52.757339Z","caller":"traceutil/trace.go:171","msg":"trace[250983233] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"205.64391ms","start":"2026-09-22T03:46:52.551632Z","end":"2026-09-22T03:46:52.757276Z","steps":["trace[250983233] 'process raft request' (duration: 121.968832ms)","trace[250983233] 'compare' (duration: 83.315792ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:52.757504Z","caller":"traceutil/trace.go:171","msg":"trace[13719457] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5123; }","duration":"129.452229ms","start":"2026-09-22T03:46:52.628023Z","end":"2026-09-22T03:46:52.757475Z","steps":["trace[13719457] 'read index received' (duration: 45.520837ms)","trace[13719457] 'applied index is now lower than readState.Index' (duration: 83.929822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:46:52.757675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.627822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:46:52.75772Z","caller":"traceutil/trace.go:171","msg":"trace[1806492110] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4933; }","duration":"129.744234ms","start":"2026-09-22T03:46:52.627964Z","end":"2026-09-22T03:46:52.757708Z","steps":["trace[1806492110] 'agreement among raft nodes before linearized reading' (duration: 129.637633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:58.778411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.972572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T03:46:58.778518Z","caller":"traceutil/trace.go:171","msg":"trace[1257408997] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5229; }","duration":"360.698427ms","start":"2026-09-22T03:46:58.417802Z","end":"2026-09-22T03:46:58.778501Z","steps":["trace[1257408997] 'read index received' (duration: 167.729025ms)","trace[1257408997] 'applied index is now lower than readState.Index' (duration: 192.968072ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:58.778559Z","caller":"traceutil/trace.go:171","msg":"trace[1298846206] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"367.874437ms","start":"2026-09-22T03:46:58.410643Z","end":"2026-09-22T03:46:58.778517Z","steps":["trace[1298846206] 'process raft request' (duration: 174.727252ms)","trace[1298846206] 'compare' (duration: 192.541944ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:46:58.778715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:46:58.410603Z","time spent":"368.04119ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T03:46:58.779906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.155908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-22T03:46:58.779962Z","caller":"traceutil/trace.go:171","msg":"trace[1709867948] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5036; }","duration":"295.2418ms","start":"2026-09-22T03:46:58.484706Z","end":"2026-09-22T03:46:58.779948Z","steps":["trace[1709867948] 'agreement among raft nodes before linearized reading' (duration: 294.322314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:58.784901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.066064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T03:46:58.784979Z","caller":"traceutil/trace.go:171","msg":"trace[949967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5036; }","duration":"367.197567ms","start":"2026-09-22T03:46:58.417763Z","end":"2026-09-22T03:46:58.784961Z","steps":["trace[949967] 'agreement among raft nodes before linearized reading' (duration: 360.820959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:58.785021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T03:46:58.417751Z","time spent":"367.260728ms","remote":"127.0.0.1:35200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T03:46:59.335005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.004947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T03:46:59.335353Z","caller":"traceutil/trace.go:171","msg":"trace[1249172887] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5240; }","duration":"153.886097ms","start":"2026-09-22T03:46:59.181434Z","end":"2026-09-22T03:46:59.33532Z","steps":["trace[1249172887] 'read index received' (duration: 37.556434ms)","trace[1249172887] 'applied index is now lower than readState.Index' (duration: 116.326093ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:46:59.335549Z","caller":"traceutil/trace.go:171","msg":"trace[1292187388] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"102.488638ms","start":"2026-09-22T03:46:59.233042Z","end":"2026-09-22T03:46:59.335531Z","steps":["trace[1292187388] 'process raft request' (duration: 102.089152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:46:59.335694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.232133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b5b658bd-e100-4284-a380-e86aaca43e26\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-22T03:46:59.335743Z","caller":"traceutil/trace.go:171","msg":"trace[191500642] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b5b658bd-e100-4284-a380-e86aaca43e26; range_end:; response_count:1; response_revision:5048; }","duration":"154.325175ms","start":"2026-09-22T03:46:59.181403Z","end":"2026-09-22T03:46:59.335728Z","steps":["trace[191500642] 'agreement among raft nodes before linearized reading' (duration: 154.07956ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:59.336457Z","caller":"traceutil/trace.go:171","msg":"trace[441326255] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"102.186144ms","start":"2026-09-22T03:46:59.23421Z","end":"2026-09-22T03:46:59.336396Z","steps":["trace[441326255] 'process raft request' (duration: 101.247848ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:46:59.336804Z","caller":"traceutil/trace.go:171","msg":"trace[598160047] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"102.479509ms","start":"2026-09-22T03:46:59.234313Z","end":"2026-09-22T03:46:59.336792Z","steps":["trace[598160047] 'process raft request' (duration: 101.561673ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:11.733398Z","caller":"traceutil/trace.go:171","msg":"trace[1644899915] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"246.246749ms","start":"2026-09-22T03:47:11.487098Z","end":"2026-09-22T03:47:11.733345Z","steps":["trace[1644899915] 'process raft request' (duration: 190.514376ms)","trace[1644899915] 'compare' (duration: 55.503939ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:47:11.733618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.771666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T03:47:11.733753Z","caller":"traceutil/trace.go:171","msg":"trace[1324665993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5209; }","duration":"203.95734ms","start":"2026-09-22T03:47:11.529773Z","end":"2026-09-22T03:47:11.733731Z","steps":["trace[1324665993] 'agreement among raft nodes before linearized reading' (duration: 203.662904ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:47:11.734339Z","caller":"traceutil/trace.go:171","msg":"trace[557091050] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5409; }","duration":"203.44128ms","start":"2026-09-22T03:47:11.529818Z","end":"2026-09-22T03:47:11.733259Z","steps":["trace[557091050] 'read index received' (duration: 147.741098ms)","trace[557091050] 'applied index is now lower than readState.Index' (duration: 55.699102ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:47:30.781929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-09-22T03:47:30.868435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"83.986235ms","hash":3010742020,"current-db-size-bytes":52318208,"current-db-size":"52 MB","current-db-size-in-use-bytes":27648000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T03:47:30.868501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3010742020,"revision":2873,"compact-revision":-1} {"level":"info","ts":"2026-09-22T03:52:30.789433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5348} {"level":"info","ts":"2026-09-22T03:52:30.873271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5348,"took":"82.378977ms","hash":3960940179,"current-db-size-bytes":52318208,"current-db-size":"52 MB","current-db-size-in-use-bytes":18821120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T03:52:30.873331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960940179,"revision":5348,"compact-revision":2873}