Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2022 04: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 16329524 kB
MemAvailable 31251644 kB
Buffers 20 kB
Cached 15267880 kB
SwapCached 1220 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-08 04:37:10
DTS_END                         2022-01-08 04:37:10.5640
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/50292
TRN_ID                          220485
ATT_ID                          608
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: 8615.04 21199 1033805
customer order: creation 169.36 470 20323
customer order: refuse 14.23 395 1708
order to supplier: creation 45.06 1386 5407
order to supplier: removal 3.34 160025 401
invoice (draft): creation 44.46 3432 5335
invoice (draft): removal 47.95 1070 5754
invoice accept: apply 40.99 25613 4919
invoice accept: cancel 43.18 1161 5182
customer reserve: creation 3709.65 249 445158
customer reserve: removal 4217.16 98 506059
realization accept: apply 83.41 290 10009
realization accept: cancel 66.63 172 7995
payment from customer: creation 58.08 172 6969
payment from customer: removal 6.41 141 769
payment to supplier: creation 48.70 192 5844
payment to supplier: removal 3.61 133 433
service: total inventory turnovers 4.18 3907 502
service: total monetary turnovers 7.77 337 932
service: aggregate perf. data 0.83 17050 100
service: refresh index statistics 0.05 207681 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-01-2022 04:38:17 to 08-01-2022 04:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9841
TEST_TIME 2 7911
TEST_TIME 3 7183
TEST_TIME 4 7017
TEST_TIME 5 6439
TEST_TIME 6 6382
TEST_TIME 7 6380
TEST_TIME 8 6596
TEST_TIME 9 6915
TEST_TIME 10 7286
TEST_TIME 11 7314
TEST_TIME 12 7320
TEST_TIME 13 7441
TEST_TIME 14 7537
TEST_TIME 15 7946
TEST_TIME 16 8006
TEST_TIME 17 8168
TEST_TIME 18 8353
TEST_TIME 19 8304
TEST_TIME 20 8437
TEST_TIME 21 8458
TEST_TIME 22 8388
TEST_TIME 23 8451
TEST_TIME 24 8901
TEST_TIME 25 8860
TEST_TIME 26 9005
TEST_TIME 27 9505
TEST_TIME 28 9817
TEST_TIME 29 10365
TEST_TIME 30 10673
TEST_TIME 31 10862
TEST_TIME 32 11393
TEST_TIME 33 11152
TEST_TIME 34 10988
TEST_TIME 35 10969
TEST_TIME 36 10734
TEST_TIME 37 10695
TEST_TIME 38 10583
TEST_TIME 39 10390
TEST_TIME 40 10217
TEST_TIME 41 10156
TEST_TIME 42 10135
TEST_TIME 43 10045
TEST_TIME 44 9978
TEST_TIME 45 9860
TEST_TIME 46 9741
TEST_TIME 47 9630
TEST_TIME 48 9554
TEST_TIME 49 9416
TEST_TIME 50 9387
TEST_TIME 51 9362
TEST_TIME 52 9301
TEST_TIME 53 9227
TEST_TIME 54 9263
TEST_TIME 55 9187
TEST_TIME 56 9072
TEST_TIME 57 9072
TEST_TIME 58 9032
TEST_TIME 59 8983
TEST_TIME 60 8937
TEST_TIME 61 8932
TEST_TIME 62 8867
TEST_TIME 63 8817
TEST_TIME 64 8832
TEST_TIME 65 8870
TEST_TIME 66 8822
TEST_TIME 67 8836
TEST_TIME 68 8884
TEST_TIME 69 8954
TEST_TIME 70 9013
TEST_TIME 71 9005
TEST_TIME 72 9036
TEST_TIME 73 9012
TEST_TIME 74 8981
TEST_TIME 75 9033
TEST_TIME 76 8975
TEST_TIME 77 8894
TEST_TIME 78 8896
TEST_TIME 79 8817
TEST_TIME 80 8850
TEST_TIME 81 8834
TEST_TIME 82 8764
TEST_TIME 83 8696
TEST_TIME 84 8636
TEST_TIME 85 8607
TEST_TIME 86 8590
TEST_TIME 87 8723
TEST_TIME 88 8817
TEST_TIME 89 8793
TEST_TIME 90 8843
TEST_TIME 91 8845
TEST_TIME 92 8837
TEST_TIME 93 8841
TEST_TIME 94 8829
TEST_TIME 95 8819
TEST_TIME 96 8809
TEST_TIME 97 8881
TEST_TIME 98 8913
TEST_TIME 99 8904
TEST_TIME 100 8878
TEST_TIME 101 8846
TEST_TIME 102 8834
TEST_TIME 103 8820
TEST_TIME 104 8787
TEST_TIME 105 8733
TEST_TIME 106 8690
TEST_TIME 107 8677
TEST_TIME 108 8653
TEST_TIME 109 8605
TEST_TIME 110 8611
TEST_TIME 111 8601
TEST_TIME 112 8624
TEST_TIME 113 8632
TEST_TIME 114 8662
TEST_TIME 115 8642
TEST_TIME 116 8623
TEST_TIME 117 8636
TEST_TIME 118 8683
TEST_TIME 119 8695
TEST_TIME 120 8632

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2022 04:38:21 to 08-01-2022 04:38:24.

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 1445505 1445505 0 0.00 0 25434 14 0 0
doc_list_biud 1445492 1445492 0 0.00 0 65128 40 0 0
sp_add_invoice_to_stock 5086 4919 167 3.28 34 201093 25614 0 167
sp_cancel_adding_invoice 5182 5182 0 0.00 6 89846 1161 0 0
sp_cancel_client_order 1708 1708 0 0.00 5 11876 396 0 0
sp_cancel_customer_reserve 506059 506059 0 0.00 1 18007 98 0 0
sp_cancel_pay_from_customer 769 769 0 0.00 4 5031 141 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 4 4786 134 0 0
sp_cancel_supplier_invoice 5771 5754 17 0.29 3 65132 1071 0 17
sp_cancel_supplier_order 401 401 0 0.00 17 1448153 160026 0 0
sp_cancel_write_off 7995 7995 0 0.00 2 13925 172 0 0
sp_client_order 20323 20323 0 0.00 5 17528 470 0 0
sp_customer_reserve 445158 445158 0 0.00 0 44551 250 0 0
sp_fill_shopping_cart 31047 31047 0 0.00 2 35068 90 0 0
sp_fill_shopping_cart_clo_res 445135 445135 0 0.00 0 16569 20 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 26565 339 0 0
sp_kill_cost_storno 14379 14379 0 0.00 0 25426 76 0 0
sp_kill_qstorno_ret_qs2qd 1026268 1026268 0 0.00 0 65127 43 0 0
sp_kill_qty_storno 541996 541996 0 0.00 0 65128 99 0 0
sp_lock_selected_doc 20653 20653 0 0.00 0 8539 8 0 0
sp_make_cost_storno 27715 27715 0 0.00 0 14357 80 0 0
sp_multiply_rows_for_pdistr 27710 27710 0 0.00 0 9455 39 0 0
sp_multiply_rows_for_qdistr 20322 20322 0 0.00 0 13561 182 0 0
sp_pay_from_customer 6969 6969 0 0.00 5 17575 172 0 0
sp_pay_to_supplier 5844 5844 0 0.00 6 17570 192 0 0
sp_payment_common 12810 12810 0 0.00 2 7933 50 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 0 11868 322 0 0
sp_qd_handle_on_invoice_upd_sts 10073 10073 0 0.00 0 45180 531 0 0
sp_qd_handle_on_reserve_upd_sts 10005 10005 0 0.00 0 18736 71 0 0
sp_reserve_write_off 10009 10009 0 0.00 8 18780 291 0 0
sp_supplier_invoice 5335 5335 0 0.00 10 77858 3433 0 0
sp_supplier_order 5407 5407 0 0.00 8 71422 1387 0 0
srv_aggregate_perf_data 100 100 0 0.00 2511 171680 17050 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 873 148644 39185 0 0
srv_find_qd_qs_mism 986751 986751 0 0.00 0 14667 11 0 0
srv_make_invnt_saldo 502 502 0 0.00 74 137769 3908 0 0
srv_make_money_saldo 932 932 0 0.00 1 19593 337 0 0
srv_recalc_idx_stat 6 6 0 0.00 62728 465843 207682 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4923 4923 0 0.00 1 17660 1009 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 5417 453 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 3818 30 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 5690 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 769 769 0 0.00 0 1771 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1053 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1062 42 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 90644 7073 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1183 20 0 0
t$perf-norm:sp_client_order 20324 20324 0 0.00 0 6476 36 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2219 15 0 0
t$perf-norm:sp_pay_from_customer 6970 6970 0 0.00 0 9536 21 0 0
t$perf-norm:sp_pay_to_supplier 5844 5844 0 0.00 0 6761 21 0 0
t$perf-norm:sp_reserve_write_off 10009 10009 0 0.00 0 6899 24 0 0
t$perf-norm:sp_supplier_invoice 5335 5335 0 0.00 0 15507 41 0 0
t$perf-norm:sp_supplier_order 5407 5407 0 0.00 0 17803 34 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 7146 91 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 6793 103 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 16102 89 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 4354 12 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 7 196 53 0 0
v_add_invoice_to_stock 10104 9770 334 3.30 0 5975 30 0 334
v_all_customers 36304 36304 0 0.00 0 67 4 0 0
v_all_suppliers 29853 29853 0 0.00 0 76 4 0 0
v_all_wares 40630 40630 0 0.00 0 198 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1905 16 0 0
v_cancel_client_order 3416 3416 0 0.00 0 5654 11 0 0
v_cancel_customer_prepayment 1538 1538 0 0.00 0 1604 13 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 5127 17 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 4742 16 0 0
v_cancel_supplier_invoice 842 808 34 4.03 0 1904 27 0 34
v_cancel_supplier_order 802 802 0 0.00 0 182 9 0 0
v_cancel_write_off 2016 2016 0 0.00 0 2509 22 0 0
v_max_id_clo_ord 5402 5402 0 0.00 0 4256 7 0 0
v_max_id_clo_res 986 986 0 0.00 0 6331 34 0 0
v_max_id_ord_sup 5330 5330 0 0.00 0 3709 7 0 0
v_max_non_paid_invoice 1646 1646 0 0.00 0 147 7 0 0
v_max_non_paid_realizn 3997 3997 0 0.00 0 1962 7 0 0
v_min_id_clo_ord 5402 5402 0 0.00 0 4603 11 0 0
v_min_id_clo_res 986 986 0 0.00 0 135 7 0 0
v_min_id_ord_sup 5330 5330 0 0.00 0 3756 9 0 0
v_min_non_paid_invoice 5842 5842 0 0.00 0 6725 16 0 0
v_min_non_paid_realizn 6967 6967 0 0.00 0 6808 24 0 0
v_our_firm 10264 10264 0 0.00 0 75 5 0 0
v_reserve_write_off 20008 20008 0 0.00 0 6639 13 0 0
x_lock_depdocs_on_canc_invoice 10532 10532 0 0.00 0 13520 130 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 8003 344 0 0
x_make_qstorno_1000_1200 5407 5407 0 0.00 3 71250 1213 0 0
x_make_qstorno_1200_2000 5335 5335 0 0.00 6 77826 3172 0 0
x_make_qstorno_2100_3300 445157 445157 0 0.00 0 35731 217 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-01-2022 04:38:25 to 08-01-2022 04:38:30.

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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 25263 8192000000 9739241760 10038640640 815841888 9.057 100 88 176 0 844677552 8054832 20780832
02:09:43 17608 8192000000 9776181488 10084651008 847632688 9.376 100 86 172 0 880622896 12722672 20267536
02:11:06 19197 8192000000 9803631888 10117128192 869003856 9.590 100 88 176 0 908584016 19286272 20293888
02:12:29 12956 8192000000 9856292272 10185433088 911343152 10.011 100 78 156 0 962859088 35160608 16355328
02:13:48 6670 8192000000 9905483488 10246123520 952524912 10.416 100 85 168 0 1027738144 57062960 18150272
02:15:00 2872 8192000000 9974588432 10322231296 984994128 10.733 100 89 176 2 1086744256 83691360 18058768
02:16:04 1337 8192000000 9985540896 10341748736 1016856720 11.042 100 94 185 0 1096615936 61159984 18599232
02:17:07 1055 8192000000 10036795360 10404179968 1044391024 11.307 100 99 197 0 1158762592 94554736 19816832
02:18:09 941 8192000000 10051813648 10425024512 1062084688 11.476 100 93 180 4 1169632096 88081600 19465808
02:19:11 1224 8192000000 10051936192 10433290240 1070862784 11.560 100 97 190 0 1175962656 85640480 19459392
02:20:14 1179 8192000000 10066483424 10451726336 1083609840 11.682 100 86 170 1 1186623136 85848784 17164512
02:21:16 984 8192000000 10054861104 10437406720 1086479744 11.709 100 96 185 1 1176898224 71022128 19396352
02:22:17 1325 8192000000 10044522176 10439286784 1093174880 11.773 100 80 149 2 1176472912 68306656 14991376
02:23:20 950 8192000000 10059704560 10445344768 1096643936 11.806 100 90 171 0 1179443680 66109216 16690528
02:24:21 2944 8192000000 10064351792 10466725888 1097138832 11.810 100 65 126 0 1194724208 84416320 13169056
02:25:27 2386 8192000000 10044741488 10441539584 1096446896 11.804 100 78 145 1 1166932080 55642384 14842800
02:26:30 1039 8192000000 10067775904 10457595904 1106026960 11.895 100 92 181 1 1190946624 65549296 19370368
02:27:32 881 8192000000 10041392688 10434420736 1098923616 11.827 100 87 170 0 1163015216 46643936 17447664
02:28:35 1066 8192000000 10028040448 10423521280 1100889728 11.846 100 70 117 1 1150786800 38741376 11155696
02:29:37 1894 8192000000 10040433360 10429865984 1090645984 11.749 100 69 136 0 1158107888 54112928 13348976
02:30:39 1156 8192000000 10056069728 10437824512 1083216560 11.678 100 93 183 2 1175264112 73766064 18281488
02:31:41 1207 8192000000 10009353568 10380582912 1052961568 11.389 100 93 179 1 1131669296 59985536 18722192
02:32:43 1205 8192000000 10018570816 10387484672 1053078240 11.390 100 95 183 0 1139581088 68203472 18299376
02:33:46 5527 8192000000 9998217056 10375004160 1039146480 11.256 99 71 138 2 1113591120 60279120 14165520
02:34:54 13240 8192000000 10014054224 10408927232 1045776080 11.320 100 58 114 0 1107384592 49888160 11720352
02:36:12 3054 8192000000 9979151936 10357915648 1041854256 11.282 100 63 117 1 1098308896 44389904 12064736
02:37:17 14925 8192000000 9968996096 10344566784 1048686272 11.348 100 60 111 2 1089868320 28950384 12231664
02:38:44 6879 8192000000 10025869376 10401669120 1083800368 11.684 100 83 157 0 1148389392 48264576 16324448
02:39:53 1656 8192000000 10067657504 10450595840 1095949120 11.799 100 88 164 0 1193094208 78758960 18386128
02:41:00 22614 8192000000 10085776416 10490552320 1103042016 11.866 100 69 132 1 1207750528 90807072 13901440
02:42:28 12854 8192000000 10121111376 10516602880 1120003760 12.027 100 76 145 1 1237190736 100002208 17184768
02:43:44 3410 8192000000 10157062336 10552823808 1135345984 12.172 100 77 149 0 1272541344 120607152 16588208
02:44:54 2713 8192000000 10205084768 10602135552 1145714752 12.269 100 73 142 0 1318641712 157226576 15700384
02:46:06 1816 8192000000 10219359296 10630393856 1153115616 12.339 100 92 176 0 1332131744 159244064 19772064
02:47:10 1089 8192000000 10269769584 10670587904 1164756688 12.448 100 90 172 1 1382160160 197930000 19473472
02:48:14 3589 8192000000 10256775072 10658557952 1162150704 12.423 100 78 151 1 1374978640 196551808 16276128
02:49:22 10184 8192000000 10271260480 10684743680 1166091280 12.460 100 66 125 0 1382522400 202241488 14189632
02:50:37 1503 8192000000 10345368272 10755215360 1176893840 12.561 100 96 190 1 1455440544 257548944 20997760
02:51:42 964 8192000000 10353232112 10787237888 1185000896 12.637 100 97 190 0 1461875584 254736336 22138352
02:52:46 1185 8192000000 10349607600 10757582848 1185992880 12.646 100 99 198 0 1456890928 248353184 22544864
02:53:50 1672 8192000000 10366675792 10783715328 1176727568 12.560 99 91 174 0 1473730112 276562208 20440336
02:54:54 1215 8192000000 10392802912 10804367360 1200113680 12.777 100 98 190 1 1502857184 280697600 22045904
02:55:57 1995 8192000000 10430952848 10837413888 1192726336 12.709 100 86 169 0 1543059008 330583712 19748960
02:57:02 1478 8192000000 10483992128 10895544320 1195976960 12.739 100 92 178 1 1586929920 370114240 20838720
02:58:06 889 8192000000 10509519440 10924134400 1197687904 12.755 100 99 192 0 1606823344 385448080 23687360
02:59:10 862 8192000000 10530078224 10933272576 1176556080 12.558 100 96 190 1 1634817856 435142224 23119552
03:00:14 1980 8192000000 10550262032 10997956608 1193245872 12.714 100 84 168 1 1651418000 437307312 20864816
03:01:19 1187 8192000000 10550946112 11014483968 1186190272 12.648 100 82 157 0 1652855024 447354240 19310512
03:02:21 4911 8192000000 10578406528 11027718144 1193093232 12.712 100 60 120 0 1687100752 477972320 16035200
03:03:37 4228 8192000000 10518637376 11000426496 1177720384 12.569 100 61 119 2 1636885680 444276672 14888624
03:04:48 9986 8192000000 10493952464 10981666816 1169864624 12.496 100 45 90 3 1596602256 414523664 12213968
03:06:12 19259 8192000000 10381725024 10877227008 1165456416 12.454 100 33 63 0 1503539664 329942128 8141120
03:07:46 42487 8192000000 10265440576 10741796864 1141256672 12.227 99 18 36 1 1374908720 229442224 4209824
03:09:30 37924 8192000000 10101125584 10541629440 1138412224 12.201 99 25 51 0 1203853088 59544592 5896272
03:11:08 29508 8192000000 10095435280 10556297216 1138671232 12.203 99 47 92 2 1202988544 53690528 10626784
03:12:40 77026 8192000000 10129558912 10598174720 1128359392 12.106 99 13 26 1 1208377296 77357872 2660032
03:14:58 56366 8192000000 10093282400 10559168512 1124767264 12.072 98 32 62 1 1180825040 48998864 7058912
03:16:59 89787 8192000000 10109799152 10572136448 1131231872 12.133 99 22 44 1 1210130576 73887648 5011056
03:19:30 148644 8192000000 10086672368 10561687552 1092243344 11.764 95 14 28 1 1150598000 55185472 3169184
03:22:59 48535 8192000000 10065797856 10504880128 1120465904 12.031 98 46 88 3 1138624896 8851648 9307344
03:24:51 64067 8192000000 10064813616 10518700032 1119986816 12.027 98 22 44 0 1155322864 30990464 4345584
03:26:56 11602 8192000000 10104206880 10545496064 1125583072 12.080 99 45 84 3 1198261936 62667408 10011456
03:28:09 52512 8192000000 10093339632 10547847168 1139031200 12.206 100 33 66 1 1219271888 73255728 6984960
03:30:03 57934 8192000000 10132433152 10580254720 1108831808 11.921 97 24 48 1 1185567920 71972064 4764048
03:32:01 87308 8192000000 10085614272 10556108800 1132045936 12.141 99 7 14 1 1192245568 58226624 1973008
03:34:38 78512 8192000000 10086104832 10582253568 1132153360 12.142 99 16 32 1 1197225632 61646448 3425824
03:36:57 68571 8192000000 10085963680 10541498368 1129013376 12.112 99 22 44 2 1168585552 35381120 4191056
03:39:08 53866 8192000000 10067186496 10513764352 1133384976 12.153 99 16 32 0 1162626656 25719248 3522432
03:41:03 29669 8192000000 10096236016 10544418816 1112666464 11.958 98 37 73 4 1170594720 51039344 6888912
03:42:44 31987 8192000000 10104680608 10547318784 1144586224 12.259 100 20 40 1 1186573088 38092112 3894752
03:44:17 74681 8192000000 10137522352 10627604480 1142450000 12.239 99 24 48 0 1231099120 83500144 5148976
03:46:36 125549 8192000000 10088458528 10544082944 1082064784 11.667 95 10 18 1 1171813648 87850336 1898528
03:49:43 47036 8192000000 10081294432 10521300992 1112122272 11.953 98 35 67 0 1156479824 37476768 6880784
03:51:33 36853 8192000000 10118734336 10545688576 1116824064 11.997 99 29 56 0 1186524192 64107056 5593072
03:53:10 102456 8192000000 10064456000 10513981440 1101831040 11.855 98 36 70 1 1155583952 46519360 7233552
03:55:59 37185 8192000000 10080239040 10531835904 1125504592 12.079 100 20 40 1 1180130608 50499072 4126944
03:57:39 53609 8192000000 10088950032 10579070976 1135809200 12.176 100 17 34 0 1203239600 63940288 3490112
03:59:33 57149 8192000000 10139115328 10586357760 1135298128 12.171 100 16 32 0 1218203024 79230096 3674800
04:01:41 98206 8192000000 10217168768 10680717312 1151552848 12.324 100 28 56 1 1314125648 155972688 6600112
04:04:24 76141 8192000000 10094322192 10611818496 1096537472 11.805 97 14 28 1 1232594176 132557376 3499328
04:06:41 67428 8192000000 10060970800 10492805120 1070641024 11.558 95 23 46 1 1124802480 49559616 4601840
04:08:52 33649 8192000000 10072652976 10519883776 1123413728 12.059 100 48 97 0 1161670656 28110144 10146784
04:10:26 9635 8192000000 10089228688 10527092736 1133826688 12.157 100 40 80 0 1184249584 42069856 8353040
04:11:47 56893 8192000000 10059204800 10496372736 1050668320 11.367 93 40 75 1 1102147312 43200112 8278880
04:13:50 47743 8192000000 10123931968 10596679680 1103073856 11.867 97 36 72 1 1229820576 119352672 7394048
04:15:39 40398 8192000000 10111726336 10587373568 1125553168 12.079 100 31 62 1 1182852752 51503520 5796064
04:17:22 84959 8192000000 10152706032 10617974784 1121988496 12.046 99 32 62 1 1242155472 113516528 6650448
04:20:38 71200 8192000000 10042122352 10485829632 1059275088 11.450 94 16 32 2 1095291968 32865440 3151440
04:22:50 72097 8192000000 10081488400 10551328768 1120127296 12.028 100 13 26 0 1163607872 40580672 2899904
04:25:03 61262 8192000000 10078797056 10551123968 1098749232 11.826 99 12 25 0 1168629152 67194720 2685200
04:27:43 59727 8192000000 10085542208 10532376576 1104455344 11.880 98 17 34 1 1189039872 80636464 3948064
04:29:44 47127 8192000000 10069897888 10515116032 1117477472 12.003 99 19 37 1 1198933856 77536032 3920352
04:31:40 50001 8192000000 10110187040 10531282944 1114387024 11.974 98 32 64 0 1176967232 55412304 7167904
04:33:33 61659 8192000000 10154900688 10629660672 1115368576 11.983 98 26 52 1 1249798304 128647808 5781920
04:35:55 29539 8192000000 10149102816 10613768192 1135341040 12.172 100 34 68 1 1230252960 88060384 6851536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2022 04:38:31 to 08-01-2022 04:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 167 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

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

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          216519
MON$OLDEST_ACTIVE               220624
MON$OLDEST_SNAPSHOT             220624
MON$NEXT_TRANSACTION            220624
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                       2047136
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:161: 
:162: mscs-test-161.budzdorov.ru    Sat Jan  8 04:37:12 2022
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Sat Jan  8 04:37:26 2022
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
--- end of diff output ---

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

08.01.22 04:38:51. 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-08 04:38:54 No severe PSQL-related problems occured
08.01.22 04:38:55. End of report.