Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.05.2022 10:37
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 16183668 kB
MemAvailable 31330904 kB
Buffers 0 kB
Cached 15487636 kB
SwapCached 2440 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-05-30 10:37:14
DTS_END                         2022-05-30 10:37:14.7670
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(68:17)
IP                              ::1/58344
TRN_ID                          218705
ATT_ID                          599
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: 8981.31 24372 1077757
customer order: creation 175.53 439 21063
customer order: refuse 15.14 455 1817
order to supplier: creation 46.53 1408 5584
order to supplier: removal 3.47 183006 416
invoice (draft): creation 46.34 3231 5561
invoice (draft): removal 48.83 1108 5860
invoice accept: apply 43.16 26859 5179
invoice accept: cancel 43.24 1359 5189
customer reserve: creation 4019.78 254 482374
customer reserve: removal 4247.43 118 509691
realization accept: apply 86.51 278 10381
realization accept: cancel 73.46 183 8815
payment from customer: creation 60.57 173 7268
payment from customer: removal 5.71 115 685
payment to supplier: creation 49.46 179 5935
payment to supplier: removal 3.35 105 402
service: total inventory turnovers 4.17 4010 500
service: total monetary turnovers 7.70 397 924
service: aggregate perf. data 0.91 17479 109
service: refresh index statistics 0.03 246274 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-05-2022 10:38:08 to 30-05-2022 10:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8621
TEST_TIME 2 7314
TEST_TIME 3 6850
TEST_TIME 4 6789
TEST_TIME 5 6986
TEST_TIME 6 6732
TEST_TIME 7 6605
TEST_TIME 8 6398
TEST_TIME 9 6060
TEST_TIME 10 5958
TEST_TIME 11 5926
TEST_TIME 12 6030
TEST_TIME 13 6276
TEST_TIME 14 6265
TEST_TIME 15 6595
TEST_TIME 16 6716
TEST_TIME 17 6700
TEST_TIME 18 7002
TEST_TIME 19 7042
TEST_TIME 20 7023
TEST_TIME 21 6984
TEST_TIME 22 7178
TEST_TIME 23 7268
TEST_TIME 24 7482
TEST_TIME 25 7434
TEST_TIME 26 7572
TEST_TIME 27 7840
TEST_TIME 28 8191
TEST_TIME 29 8458
TEST_TIME 30 8942
TEST_TIME 31 9045
TEST_TIME 32 9271
TEST_TIME 33 9618
TEST_TIME 34 9751
TEST_TIME 35 9939
TEST_TIME 36 10392
TEST_TIME 37 10529
TEST_TIME 38 10368
TEST_TIME 39 10368
TEST_TIME 40 10217
TEST_TIME 41 10146
TEST_TIME 42 10099
TEST_TIME 43 10138
TEST_TIME 44 10140
TEST_TIME 45 10161
TEST_TIME 46 9959
TEST_TIME 47 9892
TEST_TIME 48 9859
TEST_TIME 49 9696
TEST_TIME 50 9599
TEST_TIME 51 9615
TEST_TIME 52 9553
TEST_TIME 53 9498
TEST_TIME 54 9497
TEST_TIME 55 9490
TEST_TIME 56 9500
TEST_TIME 57 9526
TEST_TIME 58 9525
TEST_TIME 59 9461
TEST_TIME 60 9436
TEST_TIME 61 9406
TEST_TIME 62 9306
TEST_TIME 63 9252
TEST_TIME 64 9192
TEST_TIME 65 9253
TEST_TIME 66 9239
TEST_TIME 67 9228
TEST_TIME 68 9303
TEST_TIME 69 9245
TEST_TIME 70 9361
TEST_TIME 71 9306
TEST_TIME 72 9376
TEST_TIME 73 9380
TEST_TIME 74 9453
TEST_TIME 75 9432
TEST_TIME 76 9430
TEST_TIME 77 9394
TEST_TIME 78 9308
TEST_TIME 79 9293
TEST_TIME 80 9203
TEST_TIME 81 9182
TEST_TIME 82 9182
TEST_TIME 83 9142
TEST_TIME 84 9078
TEST_TIME 85 9057
TEST_TIME 86 9006
TEST_TIME 87 9021
TEST_TIME 88 9029
TEST_TIME 89 8997
TEST_TIME 90 8979
TEST_TIME 91 9049
TEST_TIME 92 9056
TEST_TIME 93 9066
TEST_TIME 94 9116
TEST_TIME 95 9080
TEST_TIME 96 9157
TEST_TIME 97 9176
TEST_TIME 98 9161
TEST_TIME 99 9141
TEST_TIME 100 9179
TEST_TIME 101 9189
TEST_TIME 102 9172
TEST_TIME 103 9102
TEST_TIME 104 9102
TEST_TIME 105 9057
TEST_TIME 106 9010
TEST_TIME 107 8998
TEST_TIME 108 8957
TEST_TIME 109 8960
TEST_TIME 110 8920
TEST_TIME 111 8906
TEST_TIME 112 8895
TEST_TIME 113 8905
TEST_TIME 114 8882
TEST_TIME 115 8881
TEST_TIME 116 8875
TEST_TIME 117 8877
TEST_TIME 118 8968
TEST_TIME 119 9022
TEST_TIME 120 9005

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-05-2022 10:38:11 to 30-05-2022 10:38:15.

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 1519659 1519659 0 0.00 0 29183 15 0 0
doc_list_biud 1519649 1519649 0 0.00 0 28983 46 0 0
sp_add_invoice_to_stock 5267 5179 88 1.67 38 177548 26859 0 88
sp_cancel_adding_invoice 5189 5189 0 0.00 7 107514 1360 0 0
sp_cancel_client_order 1817 1817 0 0.00 6 11137 455 0 0
sp_cancel_customer_reserve 509691 509691 0 0.00 1 26339 119 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 3589 115 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 1039 106 0 0
sp_cancel_supplier_invoice 5871 5860 11 0.18 3 29040 1109 0 11
sp_cancel_supplier_order 416 416 0 0.00 16 1548672 183006 0 0
sp_cancel_write_off 8815 8815 0 0.00 2 11177 184 0 0
sp_client_order 21063 21063 0 0.00 6 29360 440 0 0
sp_customer_reserve 482374 482374 0 0.00 1 42914 255 0 0
sp_fill_shopping_cart 32203 32203 0 0.00 1 12714 77 0 0
sp_fill_shopping_cart_clo_res 482343 482343 0 0.00 0 31482 19 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 30804 285 0 0
sp_kill_cost_storno 15091 15091 0 0.00 0 9720 82 0 0
sp_kill_qstorno_ret_qs2qd 1034473 1034473 0 0.00 0 28978 52 0 0
sp_kill_qty_storno 547298 547298 0 0.00 0 28981 117 0 0
sp_lock_selected_doc 21465 21465 0 0.00 0 8719 6 0 0
sp_make_cost_storno 28713 28713 0 0.00 0 15081 79 0 0
sp_multiply_rows_for_pdistr 28713 28713 0 0.00 0 8384 37 0 0
sp_multiply_rows_for_qdistr 21063 21063 0 0.00 0 11572 175 0 0
sp_pay_from_customer 7268 7268 0 0.00 6 14174 173 0 0
sp_pay_to_supplier 5935 5935 0 0.00 6 14860 179 0 0
sp_payment_common 13202 13202 0 0.00 2 12920 45 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 11111 375 0 0
sp_qd_handle_on_invoice_upd_sts 10318 10318 0 0.00 1 28164 529 0 0
sp_qd_handle_on_reserve_upd_sts 10381 10381 0 0.00 0 15102 68 0 0
sp_reserve_write_off 10381 10381 0 0.00 9 15226 278 0 0
sp_supplier_invoice 5561 5561 0 0.00 8 100565 3231 0 0
sp_supplier_order 5584 5584 0 0.00 8 66123 1408 0 0
srv_aggregate_perf_data 109 109 0 0.00 2593 165030 17480 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 711 145578 30283 0 0
srv_find_qd_qs_mism 1026343 1026343 0 0.00 0 29180 12 0 0
srv_make_invnt_saldo 500 500 0 0.00 41 110464 4010 0 0
srv_make_money_saldo 924 924 0 0.00 1 16634 397 0 0
srv_recalc_idx_stat 4 4 0 0.00 67965 479251 246275 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5179 5179 0 0.00 2 10906 953 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 9401 475 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 1894 26 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 1196 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1002 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 362 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 459 459 0 0.00 0 10415 58 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 80963 7622 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 695 16 0 0
t$perf-norm:sp_client_order 21063 21063 0 0.00 0 5718 37 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 611 15 0 0
t$perf-norm:sp_pay_from_customer 7268 7268 0 0.00 0 6588 20 0 0
t$perf-norm:sp_pay_to_supplier 5935 5935 0 0.00 0 5488 19 0 0
t$perf-norm:sp_reserve_write_off 10381 10381 0 0.00 0 6519 22 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 5765 31 0 0
t$perf-norm:sp_supplier_order 5584 5584 0 0.00 0 2511 31 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 315 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 211 9 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 4742 32 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 566 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 301 79 0 0
v_add_invoice_to_stock 10434 10258 176 1.68 0 7950 25 0 176
v_all_customers 38586 38586 0 0.00 0 96 4 0 0
v_all_suppliers 31250 31250 0 0.00 0 117 4 0 0
v_all_wares 42126 42126 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2600 16 0 0
v_cancel_client_order 3634 3634 0 0.00 0 3912 13 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 3540 13 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 1 2371 11 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 834 13 0 0
v_cancel_supplier_invoice 940 918 22 2.34 0 723 23 0 22
v_cancel_supplier_order 832 832 0 0.00 0 1036 12 0 0
v_cancel_write_off 2066 2066 0 0.00 0 2609 23 0 0
v_max_id_clo_ord 5584 5584 0 0.00 0 707 5 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1820 12 0 0
v_max_id_ord_sup 5556 5556 0 0.00 0 3628 7 0 0
v_max_non_paid_invoice 1450 1450 0 0.00 0 247 7 0 0
v_max_non_paid_realizn 3771 3771 0 0.00 0 1920 7 0 0
v_min_id_clo_ord 5584 5584 0 0.00 0 6637 9 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 2145 12 0 0
v_min_id_ord_sup 5556 5556 0 0.00 0 6192 7 0 0
v_min_non_paid_invoice 5935 5935 0 0.00 0 6471 15 0 0
v_min_non_paid_realizn 7267 7267 0 0.00 0 12915 19 0 0
v_our_firm 10532 10532 0 0.00 0 55 4 0 0
v_reserve_write_off 20762 20762 0 0.00 0 3952 11 0 0
x_lock_depdocs_on_canc_invoice 10590 10590 0 0.00 0 29603 136 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 3787 322 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 3 65881 1241 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 3 100524 3033 0 0
x_make_qstorno_2100_3300 482374 482374 0 0.00 0 39688 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-05-2022 10:38:16 to 30-05-2022 10:38:20.

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
08:07:18 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 34082 8192000000 9733362336 10035474432 811131968 9.009 100 88 176 0 840590544 8163552 21295024
08:10:00 27820 8192000000 9783706528 10094235648 850056656 9.401 100 90 180 1 884730320 13689424 20984240
08:11:31 23030 8192000000 9817770544 10140561408 875623856 9.656 100 82 164 0 916712992 22908816 18180320
08:12:58 10879 8192000000 9874238384 10208980992 916753280 10.064 100 80 160 0 980071664 46265312 17053072
08:14:15 3115 8192000000 9904769120 10249154560 952010784 10.411 100 77 155 0 1023667632 54875120 16781728
08:15:21 5674 8192000000 9933477600 10290835456 982321664 10.707 100 73 146 1 1056410704 58669680 15419360
08:16:29 2472 8192000000 9980891504 10348478464 1012358224 10.998 100 68 132 0 1108457360 83401072 12698064
08:17:35 1744 8192000000 10013044816 10380591104 1037626864 11.242 100 91 175 0 1130739904 75454032 17659008
08:18:39 952 8192000000 10023044752 10394996736 1052596496 11.386 100 91 176 2 1148740576 78153536 17990544
08:19:41 1129 8192000000 10018141856 10393911296 1058084640 11.438 100 93 181 1 1140318784 63443488 18790656
08:20:44 15669 8192000000 10036506688 10424418304 1065388656 11.508 100 74 142 0 1162263312 83601456 13273200
08:22:02 1157 8192000000 10048475792 10428018688 1070731648 11.559 100 87 170 2 1167580224 80029568 16819008
08:23:05 1035 8192000000 10005784464 10388594688 1076921168 11.618 100 91 171 3 1124078016 29515072 17641776
08:24:07 1157 8192000000 10037528416 10423078912 1078187824 11.630 100 94 183 1 1159734272 62868288 18678160
08:25:09 1292 8192000000 10062392432 10448781312 1086312752 11.708 100 96 189 2 1186127264 80464592 19349920
08:26:11 1367 8192000000 10081869312 10469969920 1093508176 11.776 100 94 180 1 1205942768 94146944 18287648
08:27:13 1488 8192000000 10070614160 10456641536 1098319408 11.822 100 95 188 1 1193013408 75331824 19362176
08:28:16 1263 8192000000 10068958000 10458959872 1098348336 11.822 100 97 177 1 1191444336 74472464 18623536
08:29:18 5318 8192000000 10020117392 10403725312 1064847776 11.503 98 56 103 3 1141035984 65414800 10773408
08:30:24 3805 8192000000 10054567296 10442752000 1089185472 11.735 100 66 129 2 1173397472 71891168 12320832
08:31:29 1110 8192000000 10031178912 10415230976 1076588336 11.615 100 92 172 0 1151914480 57250848 18075296
08:32:30 819 8192000000 10006134480 10384977920 1053916096 11.398 100 85 166 2 1127516720 55269200 18331424
08:33:32 884 8192000000 9988241024 10362429440 1035363200 11.220 100 89 165 0 1109293360 55947168 17982992
08:34:35 5491 8192000000 10007089744 10385141760 1042120048 11.285 100 71 134 2 1134578960 78557792 13901120
08:35:42 1816 8192000000 9984281088 10357628928 1031133824 11.179 99 82 149 5 1112884784 65213040 16537920
08:36:45 970 8192000000 9979812704 10359418880 1042397056 11.288 100 77 151 0 1103310720 43647792 17265872
08:37:56 1106 8192000000 9988896656 10359152640 1027608608 11.145 99 92 168 1 1107131856 62630704 16892544
08:39:01 2624 8192000000 10034323776 10416922624 1061193216 11.468 100 70 136 0 1158336480 83767392 13375872
08:40:17 2064 8192000000 10051617856 10434633728 1083398416 11.680 100 90 175 0 1183148992 79979792 19770784
08:41:24 10841 8192000000 10104132160 10494967808 1100507760 11.842 100 69 136 0 1226518816 111638960 14372096
08:42:39 1257 8192000000 10116624944 10504151040 1114524368 11.975 100 82 155 2 1231662912 102346864 14791680
08:43:44 7584 8192000000 10151403184 10546905088 1127830432 12.101 100 63 124 2 1265000400 124134464 13035504
08:44:56 3743 8192000000 10174186416 10574901248 1136532288 12.183 100 63 116 0 1290715632 141515152 12668192
08:46:14 2991 8192000000 10190502880 10591252480 1147218848 12.283 100 72 143 2 1305571280 142318592 16033840
08:47:23 3294 8192000000 10245008960 10644566016 1153844528 12.346 100 89 170 2 1354517456 180655264 20017664
08:48:34 1414 8192000000 10266227344 10684190720 1165199856 12.452 100 88 164 0 1379179040 194597632 19381552
08:49:39 1063 8192000000 10305555552 10717593600 1181456928 12.604 100 91 179 2 1416817088 214426096 20934064
08:50:43 1330 8192000000 10353963440 10754908160 1183759904 12.625 100 90 178 1 1463245600 258728352 20757344
08:51:48 1754 8192000000 10354807968 10774036480 1183518944 12.623 100 93 182 0 1473470688 268904960 21046784
08:52:55 2409 8192000000 10344147968 10767163392 1182224240 12.611 100 89 174 0 1455779200 254526528 19028432
08:54:01 1471 8192000000 10393767136 10811002880 1190049888 12.684 100 99 196 1 1500311824 287774112 22487824
08:55:06 774 8192000000 10418706032 10847858688 1199829744 12.775 100 98 191 0 1526983536 304480512 22673280
08:56:12 1464 8192000000 10421059184 10844999680 1208772560 12.858 100 90 174 0 1532925312 303899792 20252960
08:57:17 1690 8192000000 10499939968 10907590656 1213934640 12.906 100 97 190 0 1604623712 368812080 21876992
08:58:22 1583 8192000000 10541838064 10969063424 1221507488 12.976 100 98 195 0 1647419696 401699360 24212848
08:59:28 1344 8192000000 10548758176 10975698944 1218732208 12.950 100 96 191 0 1657751664 417028144 21991312
09:00:35 834 8192000000 10573030560 10992447488 1207236576 12.843 100 99 189 0 1678801744 449236432 22328736
09:01:39 704 8192000000 10657782880 11085066240 1223980592 12.998 100 98 197 0 1760018848 512280352 23757904
09:02:43 1900 8192000000 10617378032 11061366784 1203880544 12.812 100 81 162 2 1723299232 499653760 19764928
09:03:49 4849 8192000000 10593292192 11060518912 1187633136 12.661 100 64 125 2 1694206672 490341632 16231904
09:04:57 2367 8192000000 10572641200 11049504768 1182357264 12.612 100 67 132 0 1679956576 480895504 16703808
09:06:02 7240 8192000000 10525424672 10995200000 1182453184 12.613 100 54 103 0 1630844512 435492384 12898944
09:07:14 19839 8192000000 10425569088 10923757568 1132322896 12.143 98 40 80 0 1555981824 413081664 10577264
09:08:37 26968 8192000000 10402641856 10831237120 1110624480 11.938 96 38 74 0 1455747792 336207920 8915392
09:10:04 30249 8192000000 10314656768 10831572992 1145278880 12.265 99 31 62 0 1424063840 271404960 7380000
09:11:39 20981 8192000000 10253721312 10714517504 1141195792 12.227 100 41 82 0 1344168176 193297264 9675120
09:13:02 29956 8192000000 10135574816 10670546944 1127701088 12.100 99 13 26 0 1275255008 144510032 3043888
09:14:40 40091 8192000000 10038046608 10476871680 1092936576 11.771 96 19 37 2 1116678608 19696672 4045360
09:16:22 84334 8192000000 10074330384 10492227584 1129755984 12.119 99 31 59 0 1162713392 27115648 5841760
09:18:47 52950 8192000000 10130793392 10576617472 1146104208 12.273 100 22 44 2 1214959792 64117696 4737888
09:20:55 56866 8192000000 10133387296 10589667328 1129238112 12.114 98 14 28 0 1201163600 69089104 2836384
09:23:10 94941 8192000000 10092033920 10557501440 1131378160 12.134 99 41 73 3 1200568816 61678960 7511696
09:25:49 24951 8192000000 10064932512 10519404544 1134345648 12.162 99 14 28 1 1161114096 23923440 2845008
09:27:31 1081 8192000000 10099503104 10533167104 1150857536 12.318 100 61 113 8 1192987648 30413312 11716800
09:28:34 5597 8192000000 10129078304 10570461184 1137235008 12.190 100 59 104 3 1230355920 82548576 10572336
09:30:10 32249 8192000000 10144715360 10593882112 1135320832 12.171 99 43 86 1 1231749968 86726352 9702784
09:31:47 6861 8192000000 10146592800 10582425600 1128771728 12.110 98 72 136 2 1234275520 89552608 15951184
09:32:57 53240 8192000000 10127573520 10622775296 1147046576 12.282 100 17 34 1 1258624400 107304464 4273360
09:34:55 75460 8192000000 10101876960 10555490304 1123187776 12.057 98 21 42 1 1184430384 57203248 4039360
09:37:13 21425 8192000000 10106323296 10556637184 1140222528 12.218 100 20 40 1 1208027264 63614864 4189872
09:38:40 54947 8192000000 10046047984 10487447552 1121480800 12.041 98 22 44 0 1145421792 20328608 3612384
09:40:35 91438 8192000000 10101122080 10584662016 1115190784 11.982 97 17 34 0 1176375072 57594912 3589376
09:43:14 18061 8192000000 10090810400 10550079488 1138728976 12.204 100 33 66 0 1186224000 41187616 6307408
09:44:34 102060 8192000000 10124832208 10585985024 1152632224 12.334 100 13 26 1 1228649504 73277232 2740048
09:47:17 47807 8192000000 10121013856 10621206528 1121166032 12.038 97 32 64 0 1216847008 88969904 6711072
09:49:09 145577 8192000000 10093525408 10585780224 1071045440 11.562 93 28 56 1 1144912784 68210688 5656656
09:52:35 29429 8192000000 10104672384 10564268032 1113213792 11.963 99 36 71 1 1202963264 82550928 7198544
09:54:13 34108 8192000000 10125789216 10557890560 1132269408 12.143 100 52 100 0 1184611008 42612320 9729280
09:55:51 49760 8192000000 10037483280 10480164864 1123550976 12.061 99 26 45 2 1158406816 29515072 5340768
09:57:43 7718 8192000000 10058392704 10487701504 1123333808 12.058 100 57 105 2 1163135232 27945312 11856112
09:58:53 65387 8192000000 10072845776 10552754176 1128771152 12.110 100 20 40 0 1193184160 60122544 4290464
10:01:07 43330 8192000000 10065701520 10514931712 1097886832 11.818 97 26 53 2 1154422272 50707328 5828112
10:02:54 76534 8192000000 10107618848 10570534912 1125076768 12.075 100 14 28 3 1213026016 84144768 3804480
10:05:10 43065 8192000000 10047109392 10485411840 1088815328 11.731 97 25 45 1 1128197168 34194256 5187584
10:06:58 23077 8192000000 10117445696 10544971776 1109898080 11.931 99 50 98 0 1190277600 70359168 10020352
10:08:22 39939 8192000000 10096078624 10586189824 1109305872 11.926 99 30 59 1 1203124560 88118080 5700608
10:10:03 11306 8192000000 10069828880 10520600576 1120442080 12.031 100 43 77 2 1174567456 45034112 9091264
10:11:16 35876 8192000000 10102750528 10582163456 1114725280 11.977 99 39 76 1 1208755856 86264656 7765920
10:12:54 11939 8192000000 10125430224 10553237504 1131173328 12.132 100 64 123 1 1206094384 62388560 12532496
10:14:06 45781 8192000000 10072530272 10534375424 1094786304 11.788 97 35 68 2 1149726128 47130816 7809008
10:16:25 68485 8192000000 10105024320 10582712320 1080397312 11.651 96 26 50 1 1179008000 93160608 5450080
10:18:40 123539 8192000000 10044554000 10520031232 1102942176 11.866 99 27 54 1 1156940128 48553392 5444560
10:21:45 71086 8192000000 10063830240 10533646336 1113249120 11.963 100 15 30 1 1167745696 51388944 3107632
10:25:36 87217 8192000000 10052142480 10537943040 1096435072 11.804 98 9 18 0 1125352688 26840672 2076944
10:28:10 24014 8192000000 10113869120 10563112960 1126681648 12.090 99 33 66 2 1215988800 82291360 7015792
10:29:42 22812 8192000000 10166151040 10619998208 1145444096 12.267 100 43 86 0 1256687792 101548384 9695312
10:31:12 11833 8192000000 10160309472 10595110912 1135437648 12.173 99 40 79 4 1243336608 98766992 9131968
10:32:25 22099 8192000000 10192854704 10628788224 1129649744 12.118 99 39 78 1 1278642448 139681408 9311296
10:33:49 30368 8192000000 10232501072 10667503616 1135372640 12.172 99 47 92 2 1302160016 156300720 10486656
10:35:22 54278 8192000000 10136620672 10670149632 1115146128 11.981 98 28 56 2 1290548848 168649248 6753472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-05-2022 10:38:22 to 30-05-2022 10:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-05-2022 10:38:39 to 30-05-2022 10:38:41.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218704
MON$OLDEST_ACTIVE               221733
MON$OLDEST_SNAPSHOT             221733
MON$NEXT_TRANSACTION            221733
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-30 07:30:08.6030 GMT
MON$PAGES                       2056544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Mon May 30 10:37:18 2022
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Mon May 30 10:37:20 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Mon May 30 10:37:21 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Mon May 30 10:37:30 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
--- end of diff output ---

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

30.05.22 10:38:42. There are 3 logs in /var/tmp/logs-oltp40 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
2022-05-30 10:38:45 No severe PSQL-related problems occured
30.05.22 10:38:46. End of report.