Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15348764 kB
MemAvailable 30370896 kB
Buffers 0 kB
Cached 16338736 kB
SwapCached 3572 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-06 22:38:40
DTS_END                         2023-02-06 22:38:40.6980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33360
TRN_ID                          237412
ATT_ID                          710
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9429.28 23162 1131514
customer order: creation 171.83 350 20620
customer order: refuse 14.86 388 1783
order to supplier: creation 46.12 1230 5534
order to supplier: removal 3.42 102882 410
invoice (draft): creation 46.54 3148 5585
invoice (draft): removal 52.93 804 6351
invoice accept: apply 42.54 17534 5105
invoice accept: cancel 47.75 736 5730
customer reserve: creation 3975.28 171 477033
customer reserve: removal 4735.73 59 568287
realization accept: apply 82.18 176 9861
realization accept: cancel 79.27 112 9512
payment from customer: creation 60.53 105 7263
payment from customer: removal 5.58 82 669
payment to supplier: creation 48.87 129 5864
payment to supplier: removal 3.63 79 436
service: total inventory turnovers 4.90 1286 588
service: total monetary turnovers 6.45 48 774
service: aggregate perf. data 0.88 18494 105
service: refresh index statistics 0.03 315428 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2023 22:39:45 to 06-02-2023 22:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4649
TEST_TIME 2 4985
TEST_TIME 3 4984
TEST_TIME 4 5255
TEST_TIME 5 5663
TEST_TIME 6 6096
TEST_TIME 7 5892
TEST_TIME 8 6010
TEST_TIME 9 6266
TEST_TIME 10 6201
TEST_TIME 11 6252
TEST_TIME 12 6107
TEST_TIME 13 6111
TEST_TIME 14 6233
TEST_TIME 15 6589
TEST_TIME 16 6595
TEST_TIME 17 6747
TEST_TIME 18 7155
TEST_TIME 19 7213
TEST_TIME 20 7872
TEST_TIME 21 8004
TEST_TIME 22 8578
TEST_TIME 23 8921
TEST_TIME 24 8774
TEST_TIME 25 8975
TEST_TIME 26 9292
TEST_TIME 27 9416
TEST_TIME 28 10923
TEST_TIME 29 11250
TEST_TIME 30 10919
TEST_TIME 31 10856
TEST_TIME 32 10815
TEST_TIME 33 10729
TEST_TIME 34 10530
TEST_TIME 35 10497
TEST_TIME 36 10376
TEST_TIME 37 10255
TEST_TIME 38 10321
TEST_TIME 39 10207
TEST_TIME 40 10220
TEST_TIME 41 10131
TEST_TIME 42 10039
TEST_TIME 43 9891
TEST_TIME 44 10010
TEST_TIME 45 9856
TEST_TIME 46 9825
TEST_TIME 47 9766
TEST_TIME 48 9657
TEST_TIME 49 9610
TEST_TIME 50 9634
TEST_TIME 51 9502
TEST_TIME 52 9503
TEST_TIME 53 9466
TEST_TIME 54 9503
TEST_TIME 55 9520
TEST_TIME 56 9509
TEST_TIME 57 9543
TEST_TIME 58 9497
TEST_TIME 59 9678
TEST_TIME 60 9754
TEST_TIME 61 9824
TEST_TIME 62 9854
TEST_TIME 63 9837
TEST_TIME 64 9913
TEST_TIME 65 9833
TEST_TIME 66 9708
TEST_TIME 67 9669
TEST_TIME 68 9641
TEST_TIME 69 9618
TEST_TIME 70 9585
TEST_TIME 71 9529
TEST_TIME 72 9452
TEST_TIME 73 9443
TEST_TIME 74 9381
TEST_TIME 75 9428
TEST_TIME 76 9425
TEST_TIME 77 9393
TEST_TIME 78 9438
TEST_TIME 79 9474
TEST_TIME 80 9415
TEST_TIME 81 9602
TEST_TIME 82 9542
TEST_TIME 83 9640
TEST_TIME 84 9688
TEST_TIME 85 9681
TEST_TIME 86 9703
TEST_TIME 87 9703
TEST_TIME 88 9617
TEST_TIME 89 9571
TEST_TIME 90 9522
TEST_TIME 91 9512
TEST_TIME 92 9476
TEST_TIME 93 9488
TEST_TIME 94 9446
TEST_TIME 95 9435
TEST_TIME 96 9456
TEST_TIME 97 9493
TEST_TIME 98 9477
TEST_TIME 99 9513
TEST_TIME 100 9526
TEST_TIME 101 9599
TEST_TIME 102 9569
TEST_TIME 103 9539
TEST_TIME 104 9589
TEST_TIME 105 9633
TEST_TIME 106 9624
TEST_TIME 107 9615
TEST_TIME 108 9540
TEST_TIME 109 9507
TEST_TIME 110 9522
TEST_TIME 111 9497
TEST_TIME 112 9460
TEST_TIME 113 9440
TEST_TIME 114 9417
TEST_TIME 115 9402
TEST_TIME 116 9402
TEST_TIME 117 9402
TEST_TIME 118 9378
TEST_TIME 119 9419
TEST_TIME 120 9438

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2023 22:39:49 to 06-02-2023 22:39:52.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1572580 1572580 0 0.00 0 14337 5 0 0
doc_list_biud 1572580 1572580 0 0.00 0 50155 27 0 0
sp_add_invoice_to_stock 5249 5105 144 2.74 31 182022 17534 0 144
sp_cancel_adding_invoice 5730 5730 0 0.00 2 81376 736 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 20550 388 0 0
sp_cancel_customer_reserve 568287 568287 0 0.00 0 19810 59 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 9882 82 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 9092 79 0 0
sp_cancel_supplier_invoice 6367 6351 16 0.25 1 22038 805 0 16
sp_cancel_supplier_order 410 410 0 0.00 5 1188567 102882 0 0
sp_cancel_write_off 9512 9512 0 0.00 1 7614 113 0 0
sp_client_order 20620 20620 0 0.00 3 28229 351 0 0
sp_customer_reserve 477033 477033 0 0.00 0 43259 172 0 0
sp_fill_shopping_cart 31728 31728 0 0.00 1 24942 42 0 0
sp_fill_shopping_cart_clo_res 477013 477013 0 0.00 0 17791 15 0 0
sp_get_clo_for_invoice 5085 5085 0 0.00 0 20508 347 0 0
sp_kill_cost_storno 16347 16347 0 0.00 0 12543 54 0 0
sp_kill_qstorno_ret_qs2qd 1152847 1152847 0 0.00 0 22021 32 0 0
sp_kill_qty_storno 607019 607019 0 0.00 0 50154 69 0 0
sp_lock_selected_doc 20755 20755 0 0.00 0 9084 6 0 0
sp_make_cost_storno 28073 28073 0 0.00 0 14335 51 0 0
sp_multiply_rows_for_pdistr 28073 28073 0 0.00 0 5500 24 0 0
sp_multiply_rows_for_qdistr 20620 20620 0 0.00 0 21594 165 0 0
sp_pay_from_customer 7263 7263 0 0.00 4 9900 105 0 0
sp_pay_to_supplier 5864 5864 0 0.00 5 14403 130 0 0
sp_payment_common 13127 13127 0 0.00 2 9895 27 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 20542 353 0 0
sp_qd_handle_on_invoice_upd_sts 10815 10815 0 0.00 0 50153 314 0 0
sp_qd_handle_on_reserve_upd_sts 9861 9861 0 0.00 0 18924 60 0 0
sp_reserve_write_off 9861 9861 0 0.00 6 18936 177 0 0
sp_supplier_invoice 5585 5585 0 0.00 5 95242 3149 0 0
sp_supplier_order 5534 5534 0 0.00 5 45474 1231 0 0
srv_aggregate_perf_data 105 105 0 0.00 2610 204537 18494 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 659 144022 43300 0 0
srv_find_qd_qs_mism 1082697 1082697 0 0.00 0 8352 4 0 0
srv_make_invnt_saldo 588 588 0 0.00 40 116689 1287 0 0
srv_make_money_saldo 774 774 0 0.00 1 5203 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 228130 391516 315429 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5105 5105 0 0.00 0 8193 156 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2242 85 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3292 13 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 3785 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 3687 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 183 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 8934 45 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 20018 1605 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 1680 4 0 0
t$perf-norm:sp_client_order 20620 20620 0 0.00 0 6855 15 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 1707 7 0 0
t$perf-norm:sp_pay_from_customer 7263 7263 0 0.00 0 5783 9 0 0
t$perf-norm:sp_pay_to_supplier 5864 5864 0 0.00 0 6342 12 0 0
t$perf-norm:sp_reserve_write_off 9861 9861 0 0.00 0 6344 11 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 3861 14 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 3336 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 543 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 664 12 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 1953 21 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 964 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 18 6 0 0
v_add_invoice_to_stock 10458 10170 288 2.75 0 4756 21 0 288
v_all_customers 37984 37984 0 0.00 0 123 1 0 0
v_all_suppliers 30792 30792 0 0.00 0 124 1 0 0
v_all_wares 41234 41234 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 562 7 0 0
v_cancel_client_order 3566 3566 0 0.00 0 3058 9 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 9862 16 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 2557 8 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 201 2 0 0
v_cancel_supplier_invoice 824 792 32 3.88 0 8398 27 0 32
v_cancel_supplier_order 820 820 0 0.00 0 2519 11 0 0
v_cancel_write_off 1960 1960 0 0.00 0 7541 21 0 0
v_max_id_clo_ord 5534 5534 0 0.00 0 112 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 1190 9 0 0
v_max_id_ord_sup 5577 5577 0 0.00 0 6374 6 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 3699 7 0 0
v_max_non_paid_realizn 3813 3813 0 0.00 0 2733 5 0 0
v_min_id_clo_ord 5534 5534 0 0.00 0 2518 6 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 8050 30 0 0
v_min_id_ord_sup 5577 5577 0 0.00 0 4465 6 0 0
v_min_non_paid_invoice 5864 5864 0 0.00 0 5874 22 0 0
v_min_non_paid_realizn 7263 7263 0 0.00 0 9888 15 0 0
v_our_firm 10150 10150 0 0.00 0 40 1 0 0
v_reserve_write_off 19722 19722 0 0.00 0 7331 8 0 0
x_lock_depdocs_on_canc_invoice 11685 11685 0 0.00 0 10387 104 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 3313 347 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 45465 1157 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 2 95196 2987 0 0
x_make_qstorno_2100_3300 477033 477033 0 0.00 0 38545 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2023 22:39:53 to 06-02-2023 22:39:57.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:44 22 8192000000 8823673920 8843468800 25714080 0.312 8 6 6 0 25989808 104288 171440
20:09:46 22395 8192000000 9890188464 10199379968 976597856 10.651 100 89 178 0 1006591712 6419808 23574048
20:11:20 23267 8192000000 9947658560 10266509312 1024695104 11.117 100 89 178 0 1055756912 8281264 22780544
20:12:50 12976 8192000000 9977345104 10308866048 1058862144 11.446 100 76 152 1 1087327744 9210416 19255184
20:14:10 13468 8192000000 10032925984 10379100160 1113022400 11.961 100 75 150 0 1141937904 10719984 18195520
20:15:30 7289 8192000000 10088165232 10448629760 1174352944 12.537 100 74 148 0 1203637408 12475120 16809344
20:16:38 4058 8192000000 10118926128 10490105856 1216706816 12.931 100 75 148 0 1244682608 11730032 16245760
20:17:46 2472 8192000000 10166490992 10540523520 1252339408 13.260 100 78 154 2 1282163424 13069520 16754496
20:18:51 1246 8192000000 10187422192 10569883648 1279735984 13.511 100 85 166 1 1311765632 13485776 18543872
20:19:54 897 8192000000 10218460688 10604507136 1302917856 13.722 100 90 180 0 1337183888 13803184 20462848
20:20:57 10966 8192000000 10224895952 10617761792 1313593904 13.819 100 69 138 0 1341946672 13509056 14843712
20:22:09 3936 8192000000 10214437616 10624540672 1322092928 13.896 100 36 72 1 1337046224 7326368 7626928
20:23:15 7813 8192000000 10210943056 10630881280 1328622672 13.955 100 20 41 0 1339451424 6078752 4750000
20:24:24 9216 8192000000 10221722928 10643279872 1339067680 14.049 100 19 38 0 1349477776 6487232 3922864
20:25:37 10111 8192000000 10231806528 10646843392 1331958752 13.985 99 57 112 1 1350785088 5875264 12951072
20:26:47 20879 8192000000 10255479264 10659946496 1342688864 14.082 100 66 134 1 1364577728 6153840 15735024
20:28:09 6104 8192000000 10236653616 10658709504 1345507744 14.107 100 22 44 0 1356951120 6408192 5035184
20:29:19 32253 8192000000 10229746640 10656358400 1345284256 14.105 100 40 80 1 1360644800 6215232 9145312
20:30:53 5375 8192000000 10174925680 10577739776 1233983216 13.091 95 28 55 0 1245964400 6051024 5930160
20:31:59 8728 8192000000 10126253072 10525327360 1207331520 12.844 96 21 42 1 1221558352 9351456 4875376
20:33:08 52547 8192000000 10153023792 10536218624 1241458576 13.160 99 48 97 1 1258925648 5440368 12026704
20:35:01 6741 8192000000 10154266176 10559193088 1256673184 13.299 99 27 54 0 1268992352 5614016 6705152
20:36:10 5918 8192000000 10177559616 10586165248 1278730624 13.501 99 19 38 1 1288692720 5840816 4121280
20:37:16 12183 8192000000 10189498192 10605592576 1302989744 13.722 100 16 32 1 1311771856 5084464 3697648
20:38:31 80826 8192000000 10238729776 10647355392 1325734720 13.929 100 47 91 0 1345080448 8122992 11222736
20:40:58 20178 8192000000 10263906496 10676772864 1354820976 14.191 100 51 96 1 1380721152 15368624 10531552
20:42:20 14938 8192000000 10274836656 10698403840 1368479200 14.313 100 43 86 0 1393145088 14737904 9927984
20:43:40 6272 8192000000 10302364400 10718744576 1380787568 14.424 100 63 121 0 1410077600 14958688 14331344
20:44:50 15336 8192000000 10290259088 10720268288 1384634608 14.458 100 37 74 0 1408201248 15073408 8493232
20:46:10 4504 8192000000 10321142832 10739625984 1395821216 14.558 100 58 114 1 1428843664 19522896 13499552
20:47:26 13178 8192000000 10322930160 10748014592 1386337888 14.473 99 50 100 1 1421749280 23019040 12392352
20:48:44 17626 8192000000 10322861136 10756882432 1404447696 14.635 100 45 89 0 1439009104 23529568 11031840
20:50:08 6598 8192000000 10361469792 10782371840 1419432944 14.768 100 71 142 0 1464892880 28930112 16529824
20:51:19 4916 8192000000 10373014944 10795241472 1427904880 14.843 100 63 125 1 1477382512 34152528 15325104
20:52:28 5761 8192000000 10370941536 10790367232 1421464432 14.786 100 71 140 0 1476941680 37759008 17718240
20:53:43 5704 8192000000 10391106528 10812682240 1437171360 14.925 100 66 133 0 1494466288 40752320 16542608
20:54:56 12253 8192000000 10407896112 10831347712 1446547232 15.007 100 65 130 0 1510382832 47521632 16313968
20:56:18 4649 8192000000 10382630128 10793463808 1410857456 14.692 100 81 162 1 1485920064 54299456 20763152
20:57:33 2718 8192000000 10391604176 10811011072 1421691840 14.788 100 64 127 2 1493645840 54803152 17150848
20:58:42 12986 8192000000 10354966800 10786770944 1392899424 14.532 99 39 78 0 1463194976 59140144 11155408
21:00:08 9269 8192000000 10350277056 10780983296 1394707504 14.548 100 41 82 0 1460967200 55173040 11086656
21:01:20 18221 8192000000 10357392096 10802946048 1410445200 14.688 100 27 54 0 1470403472 52705280 7252992
21:02:54 19798 8192000000 10338128384 10770186240 1367852864 14.308 98 28 56 0 1428530976 52799808 7878304
21:04:15 29463 8192000000 10309141040 10746884096 1349984528 14.147 98 21 42 0 1405054416 48803120 6266768
21:06:00 658 8192000000 10347860208 10762539008 1362346912 14.258 100 88 168 1 1441061136 57100848 21613376
21:07:02 39814 8192000000 10260822160 10714284032 1319060480 13.868 98 9 18 0 1344579728 23293696 2225552
21:08:43 63983 8192000000 10271044336 10707607552 1335442000 14.016 99 26 51 0 1352383040 11628064 5312976
21:10:48 47129 8192000000 10269394464 10706837504 1349039280 14.139 99 36 70 2 1365428112 9031184 7357648
21:12:40 36358 8192000000 10256261360 10688262144 1333550416 13.999 99 21 41 0 1344468384 6677648 4240320
21:14:17 20211 8192000000 10275901664 10708889600 1360647792 14.243 100 27 54 0 1378835008 11611664 6575552
21:15:41 20694 8192000000 10283763264 10717274112 1353392736 14.178 99 49 93 1 1375806416 11711056 10702624
21:17:03 26377 8192000000 10270447632 10717569024 1355988288 14.201 99 10 18 0 1368980048 10543776 2447984
21:18:32 46423 8192000000 10272734800 10726338560 1355699168 14.199 99 16 33 0 1370276720 10526912 4050640
21:20:21 61052 8192000000 10289043824 10742697984 1383407824 14.447 100 11 22 1 1397727120 11853872 2465424
21:22:23 52003 8192000000 10282817552 10758586368 1384940560 14.461 100 10 20 1 1402275328 14981584 2353184
21:24:44 72858 8192000000 10278805424 10744680448 1385771024 14.468 100 23 44 1 1399010224 8382000 4857200
21:27:07 71900 8192000000 10282640432 10748518400 1390912944 14.514 100 9 18 1 1405474176 12584160 1977072
21:30:20 78692 8192000000 10292953088 10762817536 1384030832 14.453 99 9 17 0 1393893744 7762688 2100224
21:32:39 71846 8192000000 10312674752 10765733888 1364509280 14.278 97 14 26 0 1379310864 11082608 3718976
21:35:27 44968 8192000000 10306616256 10763210752 1363578512 14.269 97 25 48 1 1387245456 17529856 6137088
21:37:12 113235 8192000000 10285872048 10751328256 1306715104 13.756 94 10 20 0 1329829872 20853440 2261328
21:40:07 82976 8192000000 10304127424 10753658880 1352781392 14.172 97 15 31 0 1362683456 6881392 3020672
21:42:55 19608 8192000000 10272344416 10707238912 1340769232 14.064 98 42 82 0 1360183952 9951248 9463472
21:44:22 66640 8192000000 10255155168 10706980864 1348467520 14.134 98 37 74 0 1366382304 9702976 8211808
21:46:43 40996 8192000000 10272479824 10739732480 1366379264 14.295 99 11 24 1 1382827024 14060944 2386816
21:49:17 63339 8192000000 10254690144 10716663808 1343654608 14.090 98 6 12 0 1352946144 7886736 1404800
21:51:29 36113 8192000000 10267345168 10727927808 1369050208 14.319 100 28 56 0 1389736320 13861136 6824976
21:53:11 47034 8192000000 10266850560 10737004544 1365307904 14.285 100 4 8 0 1380552192 14241600 1002688
21:55:15 63746 8192000000 10271150128 10722148352 1328590400 13.954 97 17 34 0 1345738192 13055120 4092672
21:57:20 82677 8192000000 10271971520 10721419264 1305299072 13.743 95 17 34 0 1325281680 16682624 3299984
21:59:44 50867 8192000000 10261628656 10706558976 1310961936 13.795 96 18 36 0 1327032912 12102816 3968160
22:01:36 83052 8192000000 10268038912 10720485376 1312538208 13.809 96 14 28 0 1328348080 12736240 3073632
22:04:02 35763 8192000000 10276957840 10712961024 1320861920 13.885 97 39 78 0 1339348752 10439616 8047216
22:05:41 70174 8192000000 10244022928 10693468160 1349102544 14.139 100 17 34 0 1360662912 7684528 3875840
22:07:59 40922 8192000000 10258744720 10702299136 1353891280 14.182 100 22 44 0 1366663920 7997728 4774912
22:09:41 36748 8192000000 10267718288 10724986880 1376428544 14.385 100 22 44 0 1388142432 7350224 4363664
22:11:25 52142 8192000000 10272443440 10741690368 1366896720 14.299 99 7 14 0 1377847824 9452464 1498640
22:13:17 33361 8192000000 10299717968 10750734336 1390549744 14.511 100 28 56 0 1409600928 12768624 6282560
22:14:54 57668 8192000000 10274043104 10734759936 1358245152 14.222 98 14 28 0 1371436928 10109328 3082448
22:16:52 80378 8192000000 10289882864 10756120576 1373274512 14.356 99 19 38 0 1389765184 12256976 4233696
22:19:25 109882 8192000000 10310879792 10750169088 1337954800 14.039 96 26 52 2 1357131328 13206144 5970384
22:22:21 53214 8192000000 10289826592 10751770624 1371326224 14.339 99 12 24 0 1387017104 13227056 2463824
22:24:15 144022 8192000000 10300876016 10747465728 1363288736 14.267 98 21 42 2 1381880736 13640368 4951632
22:27:46 58581 8192000000 10302919216 10757529600 1378433488 14.403 99 28 56 0 1401385136 17042704 5908944
22:29:46 50644 8192000000 10279460864 10741665792 1369297200 14.321 98 7 14 0 1381553584 10706896 1549488
22:31:58 51465 8192000000 10256773888 10713944064 1348485664 14.134 98 8 16 0 1356698464 6385712 1827088
22:34:39 45254 8192000000 10268953232 10719744000 1352392192 14.169 99 21 42 0 1364228992 7276272 4560528
22:36:28 55738 8192000000 10266325344 10726400000 1326678784 13.937 97 22 44 0 1345615248 13466400 5470064
22:38:25 28008 8192000000 9010954912 9221263360 176782080 2.112 13 2 4 0 185303488 8436336 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-02-2023 22:39:59 to 06-02-2023 22:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2023 22:40:15 to 06-02-2023 22:40:17.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          238085
MON$OLDEST_ACTIVE               238086
MON$OLDEST_SNAPSHOT             238086
MON$NEXT_TRANSACTION            238086
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2019136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:124: 
:125: mscs-test-161.budzdorov.ru    Mon Feb  6 22:38:58 2023
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

06.02.23 22:40:18. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-06 22:40:21 No severe PSQL-related problems occured
06.02.23 22:40:22. End of report.