Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2022 13: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 16605940 kB
MemAvailable 31309724 kB
Buffers 0 kB
Cached 15060700 kB
SwapCached 1204 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.1
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-01-04 13:37:09
DTS_END                         2022-01-04 13:37:09.7490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51290
TRN_ID                          212195
ATT_ID                          631
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: 8525.19 21171 1023023
customer order: creation 163.89 481 19667
customer order: refuse 14.15 401 1698
order to supplier: creation 42.28 1452 5073
order to supplier: removal 3.49 150977 419
invoice (draft): creation 43.70 3049 5244
invoice (draft): removal 47.73 1058 5727
invoice accept: apply 39.32 26802 4718
invoice accept: cancel 42.47 1292 5096
customer reserve: creation 3626.44 258 435173
customer reserve: removal 4232.38 98 507885
realization accept: apply 78.28 257 9394
realization accept: cancel 71.51 169 8581
payment from customer: creation 53.71 167 6445
payment from customer: removal 5.47 124 656
payment to supplier: creation 45.04 192 5405
payment to supplier: removal 3.21 136 385
service: total inventory turnovers 4.18 3494 502
service: total monetary turnovers 7.04 345 845
service: aggregate perf. data 0.88 14179 106
service: refresh index statistics 0.03 218487 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2022 13:38:05 to 04-01-2022 13:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7565
TEST_TIME 2 5446
TEST_TIME 3 5922
TEST_TIME 4 5187
TEST_TIME 5 5253
TEST_TIME 6 5555
TEST_TIME 7 5951
TEST_TIME 8 6064
TEST_TIME 9 6133
TEST_TIME 10 6509
TEST_TIME 11 6777
TEST_TIME 12 6798
TEST_TIME 13 6917
TEST_TIME 14 7327
TEST_TIME 15 7331
TEST_TIME 16 7446
TEST_TIME 17 7420
TEST_TIME 18 7938
TEST_TIME 19 7851
TEST_TIME 20 7979
TEST_TIME 21 7943
TEST_TIME 22 8187
TEST_TIME 23 8270
TEST_TIME 24 8391
TEST_TIME 25 8956
TEST_TIME 26 9781
TEST_TIME 27 10415
TEST_TIME 28 10590
TEST_TIME 29 10677
TEST_TIME 30 11223
TEST_TIME 31 11030
TEST_TIME 32 11046
TEST_TIME 33 10713
TEST_TIME 34 10478
TEST_TIME 35 10257
TEST_TIME 36 10201
TEST_TIME 37 10241
TEST_TIME 38 10139
TEST_TIME 39 9958
TEST_TIME 40 9801
TEST_TIME 41 9684
TEST_TIME 42 9641
TEST_TIME 43 9662
TEST_TIME 44 9466
TEST_TIME 45 9505
TEST_TIME 46 9525
TEST_TIME 47 9462
TEST_TIME 48 9392
TEST_TIME 49 9391
TEST_TIME 50 9441
TEST_TIME 51 9340
TEST_TIME 52 9264
TEST_TIME 53 9215
TEST_TIME 54 9236
TEST_TIME 55 9314
TEST_TIME 56 9358
TEST_TIME 57 9352
TEST_TIME 58 9302
TEST_TIME 59 9279
TEST_TIME 60 9271
TEST_TIME 61 9234
TEST_TIME 62 9187
TEST_TIME 63 9089
TEST_TIME 64 9032
TEST_TIME 65 9030
TEST_TIME 66 8940
TEST_TIME 67 8869
TEST_TIME 68 8888
TEST_TIME 69 8944
TEST_TIME 70 8915
TEST_TIME 71 8862
TEST_TIME 72 8907
TEST_TIME 73 8878
TEST_TIME 74 8844
TEST_TIME 75 8941
TEST_TIME 76 8963
TEST_TIME 77 8920
TEST_TIME 78 8874
TEST_TIME 79 8930
TEST_TIME 80 9013
TEST_TIME 81 9013
TEST_TIME 82 8955
TEST_TIME 83 8864
TEST_TIME 84 8840
TEST_TIME 85 8783
TEST_TIME 86 8718
TEST_TIME 87 8670
TEST_TIME 88 8615
TEST_TIME 89 8574
TEST_TIME 90 8579
TEST_TIME 91 8550
TEST_TIME 92 8567
TEST_TIME 93 8546
TEST_TIME 94 8552
TEST_TIME 95 8562
TEST_TIME 96 8565
TEST_TIME 97 8599
TEST_TIME 98 8600
TEST_TIME 99 8670
TEST_TIME 100 8677
TEST_TIME 101 8707
TEST_TIME 102 8692
TEST_TIME 103 8720
TEST_TIME 104 8828
TEST_TIME 105 8750
TEST_TIME 106 8691
TEST_TIME 107 8679
TEST_TIME 108 8666
TEST_TIME 109 8625
TEST_TIME 110 8592
TEST_TIME 111 8551
TEST_TIME 112 8517
TEST_TIME 113 8544
TEST_TIME 114 8531
TEST_TIME 115 8585
TEST_TIME 116 8585
TEST_TIME 117 8560
TEST_TIME 118 8644
TEST_TIME 119 8623
TEST_TIME 120 8561

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2022 13:38:09 to 04-01-2022 13:38:12.

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 1425610 1425610 0 0.00 0 14037 13 0 0
doc_list_biud 1425605 1425605 0 0.00 0 35850 41 0 0
sp_add_invoice_to_stock 4834 4718 116 2.39 57 240704 26803 0 116
sp_cancel_adding_invoice 5096 5096 0 0.00 6 81961 1293 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 15958 401 0 0
sp_cancel_customer_reserve 507885 507885 0 0.00 1 35852 98 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 7776 125 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 4 10306 137 0 0
sp_cancel_supplier_invoice 5737 5727 10 0.17 3 15255 1059 0 10
sp_cancel_supplier_order 419 419 0 0.00 7 1555353 150978 0 0
sp_cancel_write_off 8581 8581 0 0.00 2 9665 170 0 0
sp_client_order 19667 19667 0 0.00 5 24639 482 0 0
sp_customer_reserve 435173 435173 0 0.00 1 51204 258 0 0
sp_fill_shopping_cart 29980 29980 0 0.00 2 12193 84 0 0
sp_fill_shopping_cart_clo_res 435142 435142 0 0.00 0 13427 21 0 0
sp_get_clo_for_invoice 4667 4667 0 0.00 0 35425 421 0 0
sp_kill_cost_storno 14718 14718 0 0.00 0 14027 72 0 0
sp_kill_qstorno_ret_qs2qd 1030497 1030497 0 0.00 0 35827 44 0 0
sp_kill_qty_storno 543466 543466 0 0.00 0 35850 100 0 0
sp_lock_selected_doc 19511 19511 0 0.00 0 11684 7 0 0
sp_make_cost_storno 25910 25910 0 0.00 0 12131 74 0 0
sp_multiply_rows_for_pdistr 25910 25910 0 0.00 0 12405 36 0 0
sp_multiply_rows_for_qdistr 19667 19667 0 0.00 0 11074 209 0 0
sp_pay_from_customer 6445 6445 0 0.00 5 17953 167 0 0
sp_pay_to_supplier 5405 5405 0 0.00 6 21700 192 0 0
sp_payment_common 11850 11850 0 0.00 2 10670 43 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 1 15945 344 0 0
sp_qd_handle_on_invoice_upd_sts 9762 9762 0 0.00 0 10155 497 0 0
sp_qd_handle_on_reserve_upd_sts 9394 9394 0 0.00 0 35702 66 0 0
sp_reserve_write_off 9394 9394 0 0.00 8 35735 257 0 0
sp_supplier_invoice 5244 5244 0 0.00 9 67094 3049 0 0
sp_supplier_order 5073 5073 0 0.00 7 50290 1452 0 0
srv_aggregate_perf_data 107 106 1 0.93 2399 143031 14180 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 749 140357 37900 0 0
srv_find_qd_qs_mism 978100 978100 0 0.00 0 9218 11 0 0
srv_make_invnt_saldo 502 502 0 0.00 65 104263 3494 0 0
srv_make_money_saldo 845 845 0 0.00 1 17600 346 0 0
srv_recalc_idx_stat 4 4 0 0.00 113732 293755 218488 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4724 4724 0 0.00 1 16332 834 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 2 9291 516 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3727 26 0 0
t$perf-norm:sp_cancel_customer_reserve 614 614 0 0.00 0 2689 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 741 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 1012 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 957 31 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 104248 6199 0 0
t$perf-norm:sp_cancel_write_off 903 903 0 0.00 0 10502 31 0 0
t$perf-norm:sp_client_order 19667 19667 0 0.00 0 13382 36 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1757 13 0 0
t$perf-norm:sp_pay_from_customer 6445 6445 0 0.00 0 5122 22 0 0
t$perf-norm:sp_pay_to_supplier 5405 5405 0 0.00 0 4670 20 0 0
t$perf-norm:sp_reserve_write_off 9394 9394 0 0.00 0 13660 25 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 11772 33 0 0
t$perf-norm:sp_supplier_order 5073 5073 0 0.00 0 12878 37 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 508 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 173 7 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 287 7 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 278 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 136 60 0 0
v_add_invoice_to_stock 9564 9332 232 2.42 0 3340 22 0 232
v_all_customers 35790 35790 0 0.00 0 110 3 0 0
v_all_suppliers 28722 28722 0 0.00 0 70 4 0 0
v_all_wares 39334 39334 0 0.00 0 113 3 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 791 14 0 0
v_cancel_client_order 3396 3396 0 0.00 0 901 8 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 1187 12 0 0
v_cancel_customer_reserve 1228 1228 0 0.00 0 578 9 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 1069 12 0 0
v_cancel_supplier_invoice 814 794 20 2.45 0 2765 33 0 20
v_cancel_supplier_order 838 838 0 0.00 0 1649 10 0 0
v_cancel_write_off 1806 1806 0 0.00 0 931 19 0 0
v_max_id_clo_ord 5073 5073 0 0.00 0 5636 6 0 0
v_max_id_clo_res 957 957 0 0.00 0 1200 10 0 0
v_max_id_ord_sup 5240 5240 0 0.00 0 6763 7 0 0
v_max_non_paid_invoice 1357 1357 0 0.00 0 69 7 0 0
v_max_non_paid_realizn 3323 3323 0 0.00 0 7749 11 0 0
v_min_id_clo_ord 5073 5073 0 0.00 0 2287 7 0 0
v_min_id_clo_res 957 957 0 0.00 0 2149 13 0 0
v_min_id_ord_sup 5240 5240 0 0.00 0 4560 7 0 0
v_min_non_paid_invoice 5405 5405 0 0.00 0 3598 14 0 0
v_min_non_paid_realizn 6445 6445 0 0.00 0 8636 18 0 0
v_our_firm 9788 9788 0 0.00 0 108 4 0 0
v_reserve_write_off 18788 18788 0 0.00 0 2111 9 0 0
x_lock_depdocs_on_canc_invoice 10426 10426 0 0.00 0 12409 132 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 28162 352 0 0
x_make_qstorno_1000_1200 5073 5073 0 0.00 2 50271 1295 0 0
x_make_qstorno_1200_2000 5244 5244 0 0.00 4 61756 2802 0 0
x_make_qstorno_2100_3300 435172 435172 0 0.00 0 50828 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2022 13:38:13 to 04-01-2022 13:38:17.

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
11:07:13 22 8192000000 8802308640 8814907392 7421248 0.090 3 1 2 0 7520672 63648 35776
11:08:14 27434 8192000000 9727857424 10027347968 814042336 9.038 100 87 174 0 842806240 7778912 20984992
11:09:43 25606 8192000000 9778672848 10088509440 853456848 9.435 100 88 176 2 887021856 13011136 20553872
11:11:16 14508 8192000000 9812284704 10131849216 878336416 9.683 100 86 172 0 918115040 20387840 19390784
11:12:35 11364 8192000000 9876966096 10210217984 925322192 10.149 100 79 158 0 984646672 42249104 17075376
11:13:48 7478 8192000000 9932863296 10277711872 958153360 10.471 100 84 162 1 1041893808 66951728 16788720
11:14:58 2545 8192000000 9965303728 10321190912 992510608 10.806 100 83 159 0 1079557216 70751040 16295568
11:16:04 1363 8192000000 10005289280 10363478016 1013179984 11.006 100 92 173 0 1115994496 86031920 16782592
11:17:07 893 8192000000 10017653520 10383712256 1040148512 11.266 100 97 190 0 1142175104 83349248 18677344
11:18:09 1260 8192000000 10022299136 10395586560 1057461248 11.432 100 99 195 0 1144114352 66615424 20037680
11:19:12 6217 8192000000 10046676992 10419765248 1067488384 11.528 100 81 160 1 1175662528 93225664 14948480
11:20:19 1021 8192000000 10054310512 10434224128 1080983456 11.657 100 91 179 1 1176821440 78475024 17362960
11:21:22 1308 8192000000 10057285408 10437406720 1081891296 11.665 100 97 191 1 1178643104 78205488 18546320
11:22:24 9863 8192000000 10034202336 10429358080 1083073456 11.677 100 67 125 2 1157651200 62842560 11735184
11:23:35 941 8192000000 10061479056 10450145280 1087390480 11.718 100 89 174 1 1185444896 79629104 18425312
11:24:37 1024 8192000000 10065111888 10449158144 1088475872 11.728 100 91 175 2 1190518912 84098688 17944352
11:25:39 1166 8192000000 10042340432 10434781184 1090789040 11.750 100 74 140 0 1164623584 59303280 14531264
11:26:41 5431 8192000000 10034778640 10422861824 1094135008 11.782 100 78 145 1 1156139760 47670320 14334432
11:27:47 1342 8192000000 10043509824 10435080192 1095339424 11.793 100 77 150 1 1168341696 57191216 15811056
11:28:49 1486 8192000000 10045614816 10431270912 1088392416 11.727 100 79 147 2 1164253056 59614144 16246496
11:29:51 1145 8192000000 10058530608 10438017024 1083188816 11.678 100 97 184 0 1177855312 76248736 18417760
11:30:53 1552 8192000000 10022395568 10400468992 1065035120 11.505 100 87 163 1 1145011392 63801232 16175040
11:31:55 986 8192000000 10019781520 10393251840 1049632608 11.357 100 92 184 0 1142277792 74331504 18313680
11:32:57 1249 8192000000 9991698080 10358829056 1034794928 11.215 100 96 179 1 1113367072 59785120 18787024
11:33:59 6845 8192000000 9990995168 10369011712 1039308448 11.258 100 69 135 1 1113081248 60038240 13734560
11:35:06 1024 8192000000 10010596512 10383736832 1038432624 11.250 100 95 185 1 1133282720 75752464 19097632
11:36:08 1197 8192000000 9992622864 10362277888 1041532976 11.279 100 98 178 2 1114172816 52958096 19681744
11:37:11 2741 8192000000 10007482576 10381799424 1034029872 11.207 99 74 145 2 1132468320 84256944 14181504
11:38:21 10915 8192000000 10014109344 10393174016 1064102560 11.496 100 69 123 0 1135592816 59327296 12162960
11:39:46 2394 8192000000 10077884512 10462384128 1089448112 11.737 100 74 142 2 1195312208 91350576 14513520
11:40:53 2623 8192000000 10108091008 10496151552 1099194608 11.830 100 81 160 0 1229099232 112237968 17666656
11:42:03 3162 8192000000 10171681728 10566082560 1115246528 11.982 100 87 170 1 1286637440 152456752 18934160
11:43:13 2175 8192000000 10199302864 10589483008 1116063072 11.990 100 73 146 0 1320293360 188629456 15600832
11:44:19 3252 8192000000 10227682016 10625150976 1131744000 12.138 100 77 153 0 1350288512 200726640 17817872
11:45:25 950 8192000000 10232925120 10631028736 1137582464 12.193 100 96 183 1 1341325072 183317472 20425136
11:46:29 741 8192000000 10255977328 10645790720 1142830144 12.242 100 95 184 2 1368678448 207034448 18813856
11:47:33 971 8192000000 10297018880 10699468800 1150632800 12.315 100 91 174 0 1414923232 243804176 20486256
11:48:38 1229 8192000000 10328355744 10738466816 1162873792 12.430 100 92 178 1 1443331984 259085088 21373104
11:49:42 1174 8192000000 10343486400 10764169216 1169295216 12.490 100 85 159 0 1451115808 263495216 18325376
11:50:47 994 8192000000 10370305344 10794987520 1179669552 12.587 100 95 189 1 1484444384 282322480 22452352
11:51:52 1058 8192000000 10398122864 10808025088 1187890864 12.664 100 98 190 0 1510255712 300973504 21391344
11:52:56 4697 8192000000 10407652944 10819289088 1172934416 12.524 100 90 170 0 1510492800 317696368 19862016
11:54:03 1242 8192000000 10467329472 10872700928 1179359856 12.584 100 96 186 0 1567234992 366085040 21790096
11:55:07 1278 8192000000 10463931168 10899259392 1182994192 12.618 100 86 172 1 1570599152 366872272 20732688
11:56:11 878 8192000000 10481354128 10888376320 1184255760 12.630 100 90 178 0 1587349232 382697552 20395920
11:57:13 2290 8192000000 10539339632 10970419200 1187434752 12.659 100 73 146 0 1641379808 435865936 18079120
11:58:20 5222 8192000000 10533224016 10962345984 1156537712 12.371 99 63 124 2 1643585712 470417792 16630208
11:59:41 2945 8192000000 10562004048 10998120448 1173513408 12.530 100 52 103 0 1666191632 479221744 13456480
12:00:46 14104 8192000000 10565838656 11035619328 1157032384 12.375 100 50 98 0 1667146096 497031216 13082496
12:02:24 6096 8192000000 10480432112 10957852672 1166605536 12.465 100 50 96 0 1590997808 411580896 12811376
12:03:31 23142 8192000000 10337611344 10904936448 1160574928 12.409 100 33 66 0 1504051472 335051536 8425008
12:04:55 47481 8192000000 10225784096 10717540352 1139704240 12.213 99 17 34 0 1334915808 190688592 4522976
12:06:43 55069 8192000000 10065437120 10514370560 1116284000 11.992 98 6 10 1 1197425408 80203632 937776
12:08:49 5001 8192000000 10084685488 10510741504 1133847680 12.158 99 59 118 2 1176713472 31063360 11802432
12:09:58 107358 8192000000 10106138144 10552750080 1136838752 12.186 99 35 71 1 1183226352 39328400 7059200
12:12:54 64446 8192000000 10115106832 10591506432 1138391904 12.200 99 20 40 0 1225034608 82489776 4152928
12:14:59 55291 8192000000 10072566544 10532540416 1113379632 11.964 97 9 18 1 1143023904 27778736 1865536
12:16:55 34676 8192000000 10120899728 10577444864 1146798288 12.279 100 22 44 0 1216504080 64756608 4949184
12:18:51 61801 8192000000 10157224560 10634604544 1145361680 12.266 100 15 30 0 1261319152 112375440 3582032
12:21:34 77078 8192000000 10106183008 10625634304 1127726592 12.100 100 17 34 0 1237896384 106360832 3808960
12:23:53 39756 8192000000 10060309600 10523316224 1106213712 11.897 98 18 36 1 1175076048 64536736 4325600
12:25:34 68885 8192000000 10063550480 10534535168 1113359184 11.964 98 18 36 0 1188987152 71667536 3960432
12:27:45 34756 8192000000 10076734352 10512535552 1137484896 12.192 100 33 62 0 1155159392 11412528 6261968
12:29:22 56424 8192000000 10081931008 10513739776 1077470128 11.623 95 37 75 0 1138586432 53565408 7550896
12:31:27 48805 8192000000 10084996944 10552524800 1109913312 11.932 99 33 66 1 1195551456 78971456 6666688
12:33:18 53962 8192000000 10059147008 10503135232 1103946848 11.875 98 20 40 0 1142223552 34014160 4262544
12:35:16 31959 8192000000 10067901328 10492088320 1116908480 11.998 99 51 95 1 1151807696 25236848 9662368
12:36:50 140357 8192000000 10067049344 10561961984 1085226032 11.697 96 33 66 0 1164893072 73138400 6528640
12:40:31 46837 8192000000 10093654704 10524205056 1110591200 11.938 98 42 84 2 1179471968 59842864 9037904
12:42:19 59326 8192000000 10088530224 10561851392 1101363392 11.851 97 18 36 0 1171538336 66323040 3851904
12:44:19 57581 8192000000 10109391424 10607542272 1122266288 12.048 99 9 18 0 1219478640 94963408 2248944
12:47:48 63200 8192000000 10096032496 10534105088 1113973040 11.970 98 18 36 3 1172860816 54881712 4006064
12:49:53 53451 8192000000 10101319472 10553110528 1126673056 12.090 98 28 56 1 1185747792 51987632 7087104
12:51:47 57875 8192000000 10110596144 10590203904 1121236624 12.039 99 30 60 2 1226339104 98602736 6499744
12:53:45 55080 8192000000 10055603200 10484039680 1082579168 11.672 95 30 59 1 1114776736 26008432 6189136
12:55:41 45741 8192000000 10157611936 10619731968 1138448592 12.201 100 33 66 1 1258117088 112813424 6855072
12:57:31 53172 8192000000 10099476960 10581594112 1137411712 12.191 100 16 32 0 1208835600 68428912 2994976
12:59:29 81040 8192000000 10088375344 10516160512 1140416880 12.219 100 55 104 2 1190221840 39996304 9808656
13:01:56 57105 8192000000 10059686160 10508652544 1133112576 12.151 99 21 42 0 1176250752 38982928 4155248
13:03:53 70037 8192000000 10050921584 10526949376 1121366128 12.040 99 4 8 0 1148764352 26557024 841200
13:06:04 64093 8192000000 10101029200 10545061888 1120547712 12.032 98 21 42 1 1180348928 55236960 4564256
13:08:12 44284 8192000000 10107594000 10556923904 1137948256 12.196 99 31 61 3 1199518432 55115024 6455152
13:10:05 35828 8192000000 10136313888 10577559552 1160021824 12.403 100 22 44 0 1216465472 51366496 5077152
13:11:41 40326 8192000000 10166296976 10603859968 1144325200 12.256 98 21 41 1 1237121744 88520928 4275616
13:13:29 57854 8192000000 10155427296 10632658944 1129510128 12.117 98 24 48 2 1239636208 104547968 5578112
13:15:51 51671 8192000000 10084067568 10562002944 1123565744 12.061 99 22 44 1 1194741632 66500800 4675088
13:17:45 35700 8192000000 10116275408 10585067520 1128200816 12.104 99 30 60 0 1217511856 83132496 6178544
13:19:30 60366 8192000000 10129233632 10620751872 1126780992 12.091 99 33 62 3 1206617584 73234176 6602416
13:21:34 66181 8192000000 10067229824 10584813568 1131091568 12.132 100 4 8 0 1175774464 43980448 702448
13:24:11 51203 8192000000 10084379808 10537226240 1112793568 11.959 98 39 78 2 1192132256 72050176 7288512
13:26:03 62072 8192000000 10071596720 10515681280 1110599120 11.938 98 19 38 2 1163245408 48698640 3947648
13:28:06 72506 8192000000 10115249456 10565464064 1099705248 11.835 97 14 28 2 1184987920 82129520 3153152
13:30:38 109966 8192000000 10100443648 10563698688 1072527120 11.576 96 11 22 0 1160247856 85192656 2528080
13:33:36 31951 8192000000 10082581776 10506444800 1095276768 11.793 98 31 56 2 1162109728 60987984 5844976
13:35:11 36422 8192000000 10086163440 10561679360 1114014288 11.970 100 42 82 2 1190255920 68312960 7928672
13:36:53 14637 8192000000 10087392912 10500825088 1104605408 11.881 99 56 112 1 1165775840 50277104 10893328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-01-2022 13:38:19 to 04-01-2022 13:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 116 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2022 13:38:36 to 04-01-2022 13:38:38.

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          207248
MON$OLDEST_ACTIVE               212570
MON$OLDEST_SNAPSHOT             212570
MON$NEXT_TRANSACTION            212570
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                       2022464
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2701 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2701 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2701 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2701 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Tue Jan  4 13:37:14 2022
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Tue Jan  4 13:37:14 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Jan  4 13:37:17 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

04.01.22 13:38:39. 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-01-04 13:38:41 No severe PSQL-related problems occured
04.01.22 13:38:43. End of report.