{"level":"warn","ts":"2026-10-13T12:40:21.00639Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T12:40:21.006493Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.31: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.31:2380","--initial-cluster=instance=https://162.253.55.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.31: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-10-13T12:40:21.006574Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T12:40:21.006591Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-10-13T12:40:21.00661Z","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-10-13T12:40:21.007369Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"]} {"level":"info","ts":"2026-10-13T12:40:21.007588Z","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.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.31: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-10-13T12:40:21.012479Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.614468ms"} {"level":"info","ts":"2026-10-13T12:40:21.018638Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de05d1e64231de42","cluster-id":"4f2999739a68ff64"} {"level":"info","ts":"2026-10-13T12:40:21.018707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T12:40:21.018722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 0"} {"level":"info","ts":"2026-10-13T12:40:21.01874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de05d1e64231de42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T12:40:21.018749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 1"} {"level":"info","ts":"2026-10-13T12:40:21.018781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"warn","ts":"2026-10-13T12:40:21.028143Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T12:40:21.029905Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T12:40:21.031214Z","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-10-13T12:40:21.033597Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de05d1e64231de42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T12:40:21.033832Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de05d1e64231de42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T12:40:21.033886Z","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-10-13T12:40:21.03398Z","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-10-13T12:40:21.033991Z","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-10-13T12:40:21.038789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"info","ts":"2026-10-13T12:40:21.038938Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","added-peer-id":"de05d1e64231de42","added-peer-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-10-13T12:40:21.041757Z","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-10-13T12:40:21.041895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-10-13T12:40:21.041919Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-10-13T12:40:21.042189Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de05d1e64231de42","initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T12:40:21.042236Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T12:40:21.519459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T12:40:21.519541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T12:40:21.519583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgPreVoteResp from de05d1e64231de42 at term 1"} {"level":"info","ts":"2026-10-13T12:40:21.519607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became candidate at term 2"} {"level":"info","ts":"2026-10-13T12:40:21.519773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgVoteResp from de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-10-13T12:40:21.519972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became leader at term 2"} {"level":"info","ts":"2026-10-13T12:40:21.520007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de05d1e64231de42 elected leader de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-10-13T12:40:21.521247Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:40:21.522344Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de05d1e64231de42","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.31:2379]}","request-path":"/0/members/de05d1e64231de42/attributes","cluster-id":"4f2999739a68ff64","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T12:40:21.522415Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:40:21.522436Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:40:21.523236Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:40:21.523553Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:40:21.523783Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:40:21.524744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T12:40:21.528408Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T12:40:21.528461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T12:40:21.529616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.31:2379"} {"level":"warn","ts":"2026-10-13T12:40:23.282072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.147662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-13T12:40:23.282306Z","caller":"traceutil/trace.go:171","msg":"trace[2111251628] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"173.957311ms","start":"2026-10-13T12:40:23.108328Z","end":"2026-10-13T12:40:23.282285Z","steps":["trace[2111251628] 'process raft request' (duration: 49.645412ms)","trace[2111251628] 'compare' (duration: 122.953247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:40:23.282468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.47431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T12:40:23.282524Z","caller":"traceutil/trace.go:171","msg":"trace[1700371020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4; }","duration":"173.551122ms","start":"2026-10-13T12:40:23.108953Z","end":"2026-10-13T12:40:23.282504Z","steps":["trace[1700371020] 'agreement among raft nodes before linearized reading' (duration: 173.424099ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.282245Z","caller":"traceutil/trace.go:171","msg":"trace[1188483543] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"173.247134ms","start":"2026-10-13T12:40:23.108977Z","end":"2026-10-13T12:40:23.282224Z","steps":["trace[1188483543] 'read index received' (duration: 48.960206ms)","trace[1188483543] 'applied index is now lower than readState.Index' (duration: 124.285518ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:40:23.295511Z","caller":"traceutil/trace.go:171","msg":"trace[759765117] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"184.437137ms","start":"2026-10-13T12:40:23.111051Z","end":"2026-10-13T12:40:23.295488Z","steps":["trace[759765117] 'process raft request' (duration: 184.262163ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.295525Z","caller":"traceutil/trace.go:171","msg":"trace[169360282] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"184.453497ms","start":"2026-10-13T12:40:23.111049Z","end":"2026-10-13T12:40:23.295503Z","steps":["trace[169360282] 'process raft request' (duration: 184.365895ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.29591Z","caller":"traceutil/trace.go:171","msg":"trace[602690676] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"184.516528ms","start":"2026-10-13T12:40:23.111363Z","end":"2026-10-13T12:40:23.29588Z","steps":["trace[602690676] 'process raft request' (duration: 184.082658ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.296016Z","caller":"traceutil/trace.go:171","msg":"trace[629302457] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"184.466317ms","start":"2026-10-13T12:40:23.111488Z","end":"2026-10-13T12:40:23.295954Z","steps":["trace[629302457] 'process raft request' (duration: 183.981785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:23.297084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.935069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T12:40:23.297157Z","caller":"traceutil/trace.go:171","msg":"trace[957263887] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"188.016281ms","start":"2026-10-13T12:40:23.109119Z","end":"2026-10-13T12:40:23.297135Z","steps":["trace[957263887] 'agreement among raft nodes before linearized reading' (duration: 187.865617ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.297471Z","caller":"traceutil/trace.go:171","msg":"trace[1978196773] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"170.847299ms","start":"2026-10-13T12:40:23.126612Z","end":"2026-10-13T12:40:23.297459Z","steps":["trace[1978196773] 'process raft request' (duration: 169.362034ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.297672Z","caller":"traceutil/trace.go:171","msg":"trace[1082188558] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"166.739932ms","start":"2026-10-13T12:40:23.130922Z","end":"2026-10-13T12:40:23.297662Z","steps":["trace[1082188558] 'process raft request' (duration: 166.017035ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:23.296072Z","caller":"traceutil/trace.go:171","msg":"trace[1950956509] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"184.557989ms","start":"2026-10-13T12:40:23.111499Z","end":"2026-10-13T12:40:23.296057Z","steps":["trace[1950956509] 'process raft request' (duration: 184.376605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:23.29811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.658381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T12:40:23.298221Z","caller":"traceutil/trace.go:171","msg":"trace[489781659] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"172.792624ms","start":"2026-10-13T12:40:23.125401Z","end":"2026-10-13T12:40:23.298193Z","steps":["trace[489781659] 'agreement among raft nodes before linearized reading' (duration: 172.60951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:23.298253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.628162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T12:40:23.298295Z","caller":"traceutil/trace.go:171","msg":"trace[1525213426] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"137.684092ms","start":"2026-10-13T12:40:23.160594Z","end":"2026-10-13T12:40:23.298278Z","steps":["trace[1525213426] 'agreement among raft nodes before linearized reading' (duration: 137.635571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:23.298088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.641975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T12:40:23.298402Z","caller":"traceutil/trace.go:171","msg":"trace[1698730816] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"185.954242ms","start":"2026-10-13T12:40:23.112412Z","end":"2026-10-13T12:40:23.298366Z","steps":["trace[1698730816] 'agreement among raft nodes before linearized reading' (duration: 185.621725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:24.737423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.401943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:40:24.737768Z","caller":"traceutil/trace.go:171","msg":"trace[1690434891] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:0; response_revision:164; }","duration":"119.756242ms","start":"2026-10-13T12:40:24.617944Z","end":"2026-10-13T12:40:24.7377Z","steps":["trace[1690434891] 'range keys from in-memory index tree' (duration: 119.345752ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:24.843656Z","caller":"traceutil/trace.go:171","msg":"trace[398486722] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"102.098029ms","start":"2026-10-13T12:40:24.741538Z","end":"2026-10-13T12:40:24.843636Z","steps":["trace[398486722] 'process raft request' (duration: 101.961906ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:40:25.202829Z","caller":"traceutil/trace.go:171","msg":"trace[197650393] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:169; }","duration":"116.861495ms","start":"2026-10-13T12:40:25.085932Z","end":"2026-10-13T12:40:25.202794Z","steps":["trace[197650393] 'read index received' (duration: 57.302701ms)","trace[197650393] 'applied index is now lower than readState.Index' (duration: 59.557344ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:40:25.2029Z","caller":"traceutil/trace.go:171","msg":"trace[149952744] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"352.844698ms","start":"2026-10-13T12:40:24.850003Z","end":"2026-10-13T12:40:25.202848Z","steps":["trace[149952744] 'process raft request' (duration: 293.182052ms)","trace[149952744] 'compare' (duration: 59.422491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:40:25.203048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.09405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:40:25.203136Z","caller":"traceutil/trace.go:171","msg":"trace[456651322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:166; }","duration":"117.233903ms","start":"2026-10-13T12:40:25.08588Z","end":"2026-10-13T12:40:25.203114Z","steps":["trace[456651322] 'agreement among raft nodes before linearized reading' (duration: 117.026339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:40:25.203257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:40:24.84998Z","time spent":"353.041102ms","remote":"127.0.0.1:50788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:40:25.476402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.078453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:40:25.476759Z","caller":"traceutil/trace.go:171","msg":"trace[368594658] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"268.027773ms","start":"2026-10-13T12:40:25.208532Z","end":"2026-10-13T12:40:25.47656Z","steps":["trace[368594658] 'process raft request' (duration: 102.697804ms)","trace[368594658] 'compare' (duration: 164.892699ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:41:04.628242Z","caller":"traceutil/trace.go:171","msg":"trace[2009070410] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"150.854941ms","start":"2026-10-13T12:41:04.477364Z","end":"2026-10-13T12:41:04.628219Z","steps":["trace[2009070410] 'process raft request' (duration: 150.488782ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.575086Z","caller":"traceutil/trace.go:171","msg":"trace[1170563072] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"205.14848ms","start":"2026-10-13T12:41:05.369914Z","end":"2026-10-13T12:41:05.575063Z","steps":["trace[1170563072] 'process raft request' (duration: 205.001087ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.580755Z","caller":"traceutil/trace.go:171","msg":"trace[751090691] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"206.303387ms","start":"2026-10-13T12:41:05.374419Z","end":"2026-10-13T12:41:05.580723Z","steps":["trace[751090691] 'process raft request' (duration: 206.083302ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.58087Z","caller":"traceutil/trace.go:171","msg":"trace[1232277988] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"190.079068ms","start":"2026-10-13T12:41:05.390761Z","end":"2026-10-13T12:41:05.58084Z","steps":["trace[1232277988] 'process raft request' (duration: 189.972186ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.580829Z","caller":"traceutil/trace.go:171","msg":"trace[491505507] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"193.785525ms","start":"2026-10-13T12:41:05.387001Z","end":"2026-10-13T12:41:05.580787Z","steps":["trace[491505507] 'process raft request' (duration: 193.639511ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.580896Z","caller":"traceutil/trace.go:171","msg":"trace[1378008000] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"191.04639ms","start":"2026-10-13T12:41:05.389813Z","end":"2026-10-13T12:41:05.580859Z","steps":["trace[1378008000] 'process raft request' (duration: 190.882237ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.581085Z","caller":"traceutil/trace.go:171","msg":"trace[240381987] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"188.668706ms","start":"2026-10-13T12:41:05.392407Z","end":"2026-10-13T12:41:05.581075Z","steps":["trace[240381987] 'process raft request' (duration: 188.357368ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.581157Z","caller":"traceutil/trace.go:171","msg":"trace[805927623] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"188.636855ms","start":"2026-10-13T12:41:05.392486Z","end":"2026-10-13T12:41:05.581123Z","steps":["trace[805927623] 'process raft request' (duration: 188.311177ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.580814Z","caller":"traceutil/trace.go:171","msg":"trace[1622890917] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"202.99045ms","start":"2026-10-13T12:41:05.37779Z","end":"2026-10-13T12:41:05.580781Z","steps":["trace[1622890917] 'process raft request' (duration: 202.813605ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.581252Z","caller":"traceutil/trace.go:171","msg":"trace[1806631135] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"185.195673ms","start":"2026-10-13T12:41:05.396037Z","end":"2026-10-13T12:41:05.581233Z","steps":["trace[1806631135] 'process raft request' (duration: 184.796164ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.581418Z","caller":"traceutil/trace.go:171","msg":"trace[1893690433] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"140.095429ms","start":"2026-10-13T12:41:05.441282Z","end":"2026-10-13T12:41:05.581378Z","steps":["trace[1893690433] 'process raft request' (duration: 139.900924ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.581509Z","caller":"traceutil/trace.go:171","msg":"trace[577771331] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"130.643187ms","start":"2026-10-13T12:41:05.450847Z","end":"2026-10-13T12:41:05.58149Z","steps":["trace[577771331] 'process raft request' (duration: 130.473423ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:05.58385Z","caller":"traceutil/trace.go:171","msg":"trace[1249748468] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:556; }","duration":"112.100014ms","start":"2026-10-13T12:41:05.470316Z","end":"2026-10-13T12:41:05.582416Z","steps":["trace[1249748468] 'read index received' (duration: 104.835094ms)","trace[1249748468] 'applied index is now lower than readState.Index' (duration: 7.26236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:41:05.584205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.739772ms","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-10-13T12:41:05.584334Z","caller":"traceutil/trace.go:171","msg":"trace[40230445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"113.961037ms","start":"2026-10-13T12:41:05.470281Z","end":"2026-10-13T12:41:05.584242Z","steps":["trace[40230445] 'agreement among raft nodes before linearized reading' (duration: 112.141734ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:41:12.145409Z","caller":"traceutil/trace.go:171","msg":"trace[136049679] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"432.687152ms","start":"2026-10-13T12:41:11.712697Z","end":"2026-10-13T12:41:12.145384Z","steps":["trace[136049679] 'read index received' (duration: 431.897514ms)","trace[136049679] 'applied index is now lower than readState.Index' (duration: 788.798µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:41:12.145662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.931929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-njzbl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-13T12:41:12.145705Z","caller":"traceutil/trace.go:171","msg":"trace[299231779] range","detail":"{range_begin:/registry/pods/kube-system/cilium-njzbl; range_end:; response_count:1; response_revision:646; }","duration":"433.062702ms","start":"2026-10-13T12:41:11.712629Z","end":"2026-10-13T12:41:12.145691Z","steps":["trace[299231779] 'agreement among raft nodes before linearized reading' (duration: 432.898828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:12.145739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:41:11.712588Z","time spent":"433.142684ms","remote":"127.0.0.1:50626","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-njzbl\" "} {"level":"info","ts":"2026-10-13T12:41:12.145666Z","caller":"traceutil/trace.go:171","msg":"trace[157184998] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"455.783538ms","start":"2026-10-13T12:41:11.689775Z","end":"2026-10-13T12:41:12.145558Z","steps":["trace[157184998] 'process raft request' (duration: 455.074091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:12.145957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.325868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:41:12.14654Z","caller":"traceutil/trace.go:171","msg":"trace[1294581836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"261.941453ms","start":"2026-10-13T12:41:11.88456Z","end":"2026-10-13T12:41:12.146501Z","steps":["trace[1294581836] 'agreement among raft nodes before linearized reading' (duration: 261.257217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:12.146085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.665186ms","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-10-13T12:41:12.146793Z","caller":"traceutil/trace.go:171","msg":"trace[303957458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"420.449343ms","start":"2026-10-13T12:41:11.726332Z","end":"2026-10-13T12:41:12.146781Z","steps":["trace[303957458] 'agreement among raft nodes before linearized reading' (duration: 419.45827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:12.14683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:41:11.726313Z","time spent":"420.502315ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T12:41:12.146947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:41:11.689748Z","time spent":"456.624138ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T12:41:12.81192Z","caller":"traceutil/trace.go:171","msg":"trace[1120324738] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"587.63516ms","start":"2026-10-13T12:41:12.22423Z","end":"2026-10-13T12:41:12.811865Z","steps":["trace[1120324738] 'process raft request' (duration: 587.411295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:12.812027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:41:12.22421Z","time spent":"587.764873ms","remote":"127.0.0.1:50932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:41:28.952219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.098908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:41:28.952289Z","caller":"traceutil/trace.go:171","msg":"trace[76226526] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"178.855992ms","start":"2026-10-13T12:41:28.773422Z","end":"2026-10-13T12:41:28.952278Z","steps":["trace[76226526] 'read index received' (duration: 7.576389ms)","trace[76226526] 'applied index is now lower than readState.Index' (duration: 171.278943ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:41:28.952314Z","caller":"traceutil/trace.go:171","msg":"trace[445837031] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"250.41747ms","start":"2026-10-13T12:41:28.701877Z","end":"2026-10-13T12:41:28.952294Z","steps":["trace[445837031] 'process raft request' (duration: 79.188299ms)","trace[445837031] 'compare' (duration: 171.007206ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:41:28.952351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.929343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:41:28.95238Z","caller":"traceutil/trace.go:171","msg":"trace[155658440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"178.984254ms","start":"2026-10-13T12:41:28.773388Z","end":"2026-10-13T12:41:28.952372Z","steps":["trace[155658440] 'agreement among raft nodes before linearized reading' (duration: 178.932423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:28.952397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.204494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jb5qt\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-13T12:41:28.952411Z","caller":"traceutil/trace.go:171","msg":"trace[1565939512] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jb5qt; range_end:; response_count:1; response_revision:815; }","duration":"133.250815ms","start":"2026-10-13T12:41:28.819156Z","end":"2026-10-13T12:41:28.952406Z","steps":["trace[1565939512] 'agreement among raft nodes before linearized reading' (duration: 133.178763ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:42:10.920756Z","caller":"traceutil/trace.go:171","msg":"trace[930813241] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"430.716306ms","start":"2026-10-13T12:42:10.490021Z","end":"2026-10-13T12:42:10.920737Z","steps":["trace[930813241] 'read index received' (duration: 430.592113ms)","trace[930813241] 'applied index is now lower than readState.Index' (duration: 123.423µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:42:10.920838Z","caller":"traceutil/trace.go:171","msg":"trace[277310255] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"478.981415ms","start":"2026-10-13T12:42:10.441822Z","end":"2026-10-13T12:42:10.920803Z","steps":["trace[277310255] 'process raft request' (duration: 478.796291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:42:10.920901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.87442ms","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-10-13T12:42:10.920927Z","caller":"traceutil/trace.go:171","msg":"trace[1119500568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"430.950942ms","start":"2026-10-13T12:42:10.489967Z","end":"2026-10-13T12:42:10.920918Z","steps":["trace[1119500568] 'agreement among raft nodes before linearized reading' (duration: 430.85663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:42:10.920952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:42:10.489947Z","time spent":"430.996763ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T12:42:10.920989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:42:10.441788Z","time spent":"479.122249ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T12:42:10.921104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.747817ms","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-10-13T12:42:10.921185Z","caller":"traceutil/trace.go:171","msg":"trace[1841463517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"197.873841ms","start":"2026-10-13T12:42:10.723291Z","end":"2026-10-13T12:42:10.921165Z","steps":["trace[1841463517] 'agreement among raft nodes before linearized reading' (duration: 197.602304ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:42:41.664326Z","caller":"traceutil/trace.go:171","msg":"trace[436473656] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"128.620223ms","start":"2026-10-13T12:42:41.535681Z","end":"2026-10-13T12:42:41.664301Z","steps":["trace[436473656] 'read index received' (duration: 128.454929ms)","trace[436473656] 'applied index is now lower than readState.Index' (duration: 164.404µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:42:41.664436Z","caller":"traceutil/trace.go:171","msg":"trace[1609514097] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"166.032ms","start":"2026-10-13T12:42:41.498378Z","end":"2026-10-13T12:42:41.66441Z","steps":["trace[1609514097] 'process raft request' (duration: 165.797565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:42:41.664575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.885289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-13T12:42:41.664658Z","caller":"traceutil/trace.go:171","msg":"trace[967542191] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1276; }","duration":"129.009562ms","start":"2026-10-13T12:42:41.5356Z","end":"2026-10-13T12:42:41.66461Z","steps":["trace[967542191] 'agreement among raft nodes before linearized reading' (duration: 128.816218ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:42:42.13495Z","caller":"traceutil/trace.go:171","msg":"trace[1346318779] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"360.075807ms","start":"2026-10-13T12:42:41.774847Z","end":"2026-10-13T12:42:42.134923Z","steps":["trace[1346318779] 'read index received' (duration: 359.876072ms)","trace[1346318779] 'applied index is now lower than readState.Index' (duration: 198.615µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:42:42.135103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.24402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:42:42.135173Z","caller":"traceutil/trace.go:171","msg":"trace[1372932506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"360.355643ms","start":"2026-10-13T12:42:41.774797Z","end":"2026-10-13T12:42:42.135153Z","steps":["trace[1372932506] 'agreement among raft nodes before linearized reading' (duration: 360.23381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:42:42.135223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:42:41.774776Z","time spent":"360.434255ms","remote":"127.0.0.1:50430","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-10-13T12:42:42.135212Z","caller":"traceutil/trace.go:171","msg":"trace[1291876582] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"466.630516ms","start":"2026-10-13T12:42:41.668562Z","end":"2026-10-13T12:42:42.135193Z","steps":["trace[1291876582] 'process raft request' (duration: 466.213676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:42:42.135337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:42:41.668542Z","time spent":"466.728188ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}