Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.2022 04:38
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 15944256 kB
MemAvailable 31307796 kB
Buffers 20 kB
Cached 15731544 kB
SwapCached 2436 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-06-09 04:37:16
DTS_END                         2022-06-09 04:37:16.8460
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/38960
TRN_ID                          224980
ATT_ID                          610
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: 8886.46 20310 1066375
customer order: creation 174.58 457 20950
customer order: refuse 14.82 394 1778
order to supplier: creation 46.27 1373 5552
order to supplier: removal 3.36 147703 403
invoice (draft): creation 46.37 3218 5564
invoice (draft): removal 49.13 1013 5895
invoice accept: apply 41.58 23593 4989
invoice accept: cancel 44.48 1205 5338
customer reserve: creation 3814.24 226 457709
customer reserve: removal 4354.43 89 522532
realization accept: apply 85.43 272 10252
realization accept: cancel 82.28 149 9874
payment from customer: creation 59.48 175 7138
payment from customer: removal 5.79 117 695
payment to supplier: creation 48.48 192 5818
payment to supplier: removal 3.24 100 389
service: total inventory turnovers 3.97 3958 476
service: total monetary turnovers 7.60 253 912
service: aggregate perf. data 0.88 17507 106
service: refresh index statistics 0.04 204199 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2022 04:38:51 to 09-06-2022 04:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8772
TEST_TIME 2 7301
TEST_TIME 3 5971
TEST_TIME 4 5989
TEST_TIME 5 6333
TEST_TIME 6 5975
TEST_TIME 7 5877
TEST_TIME 8 6026
TEST_TIME 9 6154
TEST_TIME 10 6070
TEST_TIME 11 6294
TEST_TIME 12 6448
TEST_TIME 13 6478
TEST_TIME 14 6578
TEST_TIME 15 6919
TEST_TIME 16 7107
TEST_TIME 17 7629
TEST_TIME 18 7946
TEST_TIME 19 8212
TEST_TIME 20 8135
TEST_TIME 21 8741
TEST_TIME 22 8888
TEST_TIME 23 8778
TEST_TIME 24 8744
TEST_TIME 25 8584
TEST_TIME 26 8647
TEST_TIME 27 9435
TEST_TIME 28 9730
TEST_TIME 29 10080
TEST_TIME 30 10295
TEST_TIME 31 10405
TEST_TIME 32 10594
TEST_TIME 33 10503
TEST_TIME 34 10505
TEST_TIME 35 10414
TEST_TIME 36 10916
TEST_TIME 37 10968
TEST_TIME 38 10758
TEST_TIME 39 10669
TEST_TIME 40 10564
TEST_TIME 41 10601
TEST_TIME 42 10536
TEST_TIME 43 10432
TEST_TIME 44 10441
TEST_TIME 45 10370
TEST_TIME 46 10290
TEST_TIME 47 10195
TEST_TIME 48 10115
TEST_TIME 49 9987
TEST_TIME 50 9905
TEST_TIME 51 9844
TEST_TIME 52 9790
TEST_TIME 53 9764
TEST_TIME 54 9719
TEST_TIME 55 9697
TEST_TIME 56 9684
TEST_TIME 57 9632
TEST_TIME 58 9559
TEST_TIME 59 9516
TEST_TIME 60 9408
TEST_TIME 61 9445
TEST_TIME 62 9554
TEST_TIME 63 9603
TEST_TIME 64 9602
TEST_TIME 65 9673
TEST_TIME 66 9689
TEST_TIME 67 9634
TEST_TIME 68 9591
TEST_TIME 69 9524
TEST_TIME 70 9634
TEST_TIME 71 9601
TEST_TIME 72 9577
TEST_TIME 73 9515
TEST_TIME 74 9626
TEST_TIME 75 9620
TEST_TIME 76 9683
TEST_TIME 77 9630
TEST_TIME 78 9521
TEST_TIME 79 9493
TEST_TIME 80 9475
TEST_TIME 81 9453
TEST_TIME 82 9409
TEST_TIME 83 9384
TEST_TIME 84 9363
TEST_TIME 85 9315
TEST_TIME 86 9271
TEST_TIME 87 9263
TEST_TIME 88 9245
TEST_TIME 89 9239
TEST_TIME 90 9266
TEST_TIME 91 9211
TEST_TIME 92 9178
TEST_TIME 93 9187
TEST_TIME 94 9182
TEST_TIME 95 9156
TEST_TIME 96 9125
TEST_TIME 97 9192
TEST_TIME 98 9232
TEST_TIME 99 9199
TEST_TIME 100 9249
TEST_TIME 101 9207
TEST_TIME 102 9345
TEST_TIME 103 9322
TEST_TIME 104 9286
TEST_TIME 105 9287
TEST_TIME 106 9289
TEST_TIME 107 9259
TEST_TIME 108 9250
TEST_TIME 109 9214
TEST_TIME 110 9162
TEST_TIME 111 9131
TEST_TIME 112 9091
TEST_TIME 113 9059
TEST_TIME 114 9042
TEST_TIME 115 9023
TEST_TIME 116 9018
TEST_TIME 117 9042
TEST_TIME 118 9006
TEST_TIME 119 8983
TEST_TIME 120 8919

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-06-2022 04:38:55 to 09-06-2022 04:38:58.

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 1488066 1488066 0 0.00 0 23835 13 0 0
doc_list_biud 1488058 1488058 0 0.00 0 44073 37 0 0
sp_add_invoice_to_stock 5152 4989 163 3.16 60 172795 23593 0 163
sp_cancel_adding_invoice 5338 5338 0 0.00 6 83488 1206 0 0
sp_cancel_client_order 1778 1778 0 0.00 6 9975 394 0 0
sp_cancel_customer_reserve 522532 522532 0 0.00 1 27513 89 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 3645 117 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 1942 101 0 0
sp_cancel_supplier_invoice 5906 5895 11 0.18 2 44084 1013 0 11
sp_cancel_supplier_order 403 403 0 0.00 9 1272384 147703 0 0
sp_cancel_write_off 9874 9874 0 0.00 2 9815 150 0 0
sp_client_order 20950 20950 0 0.00 6 41577 457 0 0
sp_customer_reserve 457709 457709 0 0.00 0 83593 227 0 0
sp_fill_shopping_cart 32061 32061 0 0.00 1 23563 81 0 0
sp_fill_shopping_cart_clo_res 457675 457675 0 0.00 0 23501 17 0 0
sp_get_clo_for_invoice 4938 4938 0 0.00 0 11376 257 0 0
sp_kill_cost_storno 16296 16296 0 0.00 0 7466 70 0 0
sp_kill_qstorno_ret_qs2qd 1061236 1061236 0 0.00 0 44072 40 0 0
sp_kill_qty_storno 561008 561008 0 0.00 0 44073 91 0 0
sp_lock_selected_doc 21016 21016 0 0.00 0 10509 10 0 0
sp_make_cost_storno 28144 28144 0 0.00 0 15196 81 0 0
sp_multiply_rows_for_pdistr 28144 28144 0 0.00 0 10093 41 0 0
sp_multiply_rows_for_qdistr 20950 20950 0 0.00 0 11987 166 0 0
sp_pay_from_customer 7138 7138 0 0.00 6 15051 176 0 0
sp_pay_to_supplier 5818 5818 0 0.00 7 14358 193 0 0
sp_payment_common 12956 12956 0 0.00 2 15038 42 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 9586 318 0 0
sp_qd_handle_on_invoice_upd_sts 10275 10275 0 0.00 0 10083 487 0 0
sp_qd_handle_on_reserve_upd_sts 10251 10251 0 0.00 0 16684 57 0 0
sp_reserve_write_off 10252 10252 0 0.00 9 20311 272 0 0
sp_supplier_invoice 5564 5564 0 0.00 10 100537 3219 0 0
sp_supplier_order 5552 5552 0 0.00 7 85106 1374 0 0
srv_aggregate_perf_data 106 106 0 0.00 2497 150571 17508 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 674 152021 39213 0 0
srv_find_qd_qs_mism 1017529 1017529 0 0.00 0 23833 10 0 0
srv_make_invnt_saldo 476 476 0 0.00 67 59085 3959 0 0
srv_make_money_saldo 912 912 0 0.00 1 8925 254 0 0
srv_recalc_idx_stat 5 5 0 0.00 147066 296792 204199 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 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 4995 4995 0 0.00 1 24358 863 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 2 7541 433 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 1169 24 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 1764 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 458 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 2744 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 4710 44 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 116032 7686 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 1723 16 0 0
t$perf-norm:sp_client_order 20950 20950 0 0.00 0 10429 36 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1298 14 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 5764 22 0 0
t$perf-norm:sp_pay_to_supplier 5818 5818 0 0.00 0 10958 20 0 0
t$perf-norm:sp_reserve_write_off 10252 10252 0 0.00 0 10790 28 0 0
t$perf-norm:sp_supplier_invoice 5564 5564 0 0.00 0 9305 34 0 0
t$perf-norm:sp_supplier_order 5552 5552 0 0.00 0 7049 31 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1001 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 300 11 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1039 16 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 3689 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 231 86 0 0
v_add_invoice_to_stock 10200 9874 326 3.19 0 6412 28 0 326
v_all_customers 38374 38374 0 0.00 0 194 3 0 0
v_all_suppliers 30780 30780 0 0.00 0 192 4 0 0
v_all_wares 41900 41900 0 0.00 0 197 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1700 15 0 0
v_cancel_client_order 3556 3556 0 0.00 0 2566 12 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2593 15 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 2641 13 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1211 13 0 0
v_cancel_supplier_invoice 910 888 22 2.41 0 2774 32 0 22
v_cancel_supplier_order 806 806 0 0.00 0 3874 21 0 0
v_cancel_write_off 1948 1948 0 0.00 0 3408 28 0 0
v_max_id_clo_ord 5551 5551 0 0.00 0 3683 6 0 0
v_max_id_clo_res 990 990 0 0.00 0 1442 15 0 0
v_max_id_ord_sup 5559 5559 0 0.00 0 5260 8 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 239 6 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 1877 7 0 0
v_min_id_clo_ord 5551 5551 0 0.00 0 2768 8 0 0
v_min_id_clo_res 990 990 0 0.00 0 526 8 0 0
v_min_id_ord_sup 5559 5559 0 0.00 0 4772 8 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 6931 15 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 15027 17 0 0
v_our_firm 10438 10438 0 0.00 0 67 4 0 0
v_reserve_write_off 20502 20502 0 0.00 0 6226 12 0 0
x_lock_depdocs_on_canc_invoice 10789 10789 0 0.00 0 13982 124 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 6106 307 0 0
x_make_qstorno_1000_1200 5552 5552 0 0.00 2 85093 1211 0 0
x_make_qstorno_1200_2000 5564 5564 0 0.00 5 89761 2994 0 0
x_make_qstorno_2100_3300 457708 457708 0 0.00 0 75866 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-06-2022 04:38:59 to 09-06-2022 04:39:03.

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:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 27344 8192000000 9716155744 10014461952 789842400 8.793 100 90 180 1 820699920 8336112 22521408
02:09:50 18382 8192000000 9763804912 10071961600 839913904 9.299 100 93 186 0 874622976 12987920 21721152
02:11:20 15202 8192000000 9803214784 10121027584 865161808 9.552 100 88 175 1 907445712 21992448 20291456
02:12:41 15123 8192000000 9869377136 10204467200 920024304 10.096 100 75 150 2 975860736 39384672 16451760
02:14:01 6814 8192000000 9912719664 10257539072 960447504 10.493 100 83 155 0 1031592048 54144784 16999760
02:15:10 3062 8192000000 9958291600 10310500352 993496640 10.815 100 87 165 3 1074130608 63607728 17026240
02:16:16 1285 8192000000 10012576160 10374250496 1020907392 11.081 100 92 181 1 1127815712 88496064 18412256
02:17:20 939 8192000000 10014263440 10385600512 1045917184 11.322 100 98 190 2 1135800784 70936208 18947392
02:18:22 1221 8192000000 10045275104 10425032704 1060927200 11.465 100 97 189 2 1160991808 80153104 19911504
02:19:24 1150 8192000000 10018560000 10396610560 1066900400 11.522 100 98 185 0 1135701504 50707760 18093344
02:20:26 1255 8192000000 10050020080 10433269760 1078363120 11.632 100 92 180 0 1173331136 76401216 18566800
02:21:29 1148 8192000000 10051369856 10437849088 1089670160 11.740 100 97 184 1 1169959696 62285472 18004064
02:22:30 1179 8192000000 10053782512 10438938624 1091918544 11.761 100 96 191 1 1176599264 64376704 20304016
02:23:32 1173 8192000000 10079598672 10474385408 1101082880 11.848 100 97 188 0 1204883968 84977248 18823840
02:24:35 1202 8192000000 10083875584 10476371968 1105181520 11.887 100 94 178 0 1208832752 84837376 18813856
02:25:37 727 8192000000 10085066560 10474479616 1104350928 11.879 100 97 190 0 1203879712 80480256 19048528
02:26:40 1312 8192000000 10088459712 10479857664 1110381904 11.936 100 95 186 1 1213374816 84175408 18817504
02:27:42 1286 8192000000 10066886320 10456055808 1094538080 11.786 99 91 176 1 1188444096 75778208 18127808
02:28:44 1105 8192000000 10073690144 10464276480 1105084080 11.886 100 92 176 2 1195313904 72351200 17878624
02:29:46 1434 8192000000 10049657184 10432204800 1075818592 11.608 100 97 185 2 1169901408 75436064 18646752
02:30:48 1297 8192000000 10034500288 10419679232 1076871952 11.618 100 95 184 0 1154966688 59246784 18847952
02:31:50 1210 8192000000 10018568448 10390106112 1043309104 11.296 100 98 190 0 1139010592 77438608 18262880
02:32:52 1281 8192000000 10009837344 10393505792 1049812864 11.359 100 89 169 0 1131824160 65154192 16857104
02:33:54 1094 8192000000 9993074992 10366910464 1036886000 11.235 100 88 173 1 1113171952 58381232 17904720
02:34:56 1416 8192000000 9989797584 10372833280 1044522784 11.308 100 74 146 0 1109886496 50069712 15294000
02:35:59 5020 8192000000 9971042496 10351837184 1036288992 11.229 99 59 110 3 1095974768 48927776 10758000
02:37:05 1284 8192000000 10000762000 10381389824 1061081200 11.467 100 79 157 1 1119230880 42899808 15249872
02:38:10 19524 8192000000 10014081296 10408034304 1069615680 11.548 99 54 107 1 1136366624 55796960 10953984
02:39:35 1935 8192000000 10041117456 10432327680 1087268080 11.717 100 86 161 2 1161424160 57882944 16273136
02:40:42 11621 8192000000 10087831440 10482757632 1108740608 11.920 100 72 140 1 1200589248 76804768 15043872
02:42:02 27715 8192000000 10156896960 10566111232 1126003584 12.084 100 72 137 0 1279924624 139678016 14243024
02:43:41 4695 8192000000 10159056912 10560512000 1138534144 12.202 100 70 133 1 1281763008 128607888 14620976
02:44:49 1846 8192000000 10218075136 10617200640 1149444080 12.304 100 86 163 1 1333027648 165529312 18054256
02:46:00 1987 8192000000 10257021776 10657558528 1152784016 12.336 100 90 169 2 1367325872 196245200 18296656
02:47:12 1572 8192000000 10252546752 10657075200 1160864864 12.411 100 68 131 1 1372603648 197257232 14481552
02:48:19 975 8192000000 10288292912 10697560064 1164773472 12.448 100 93 181 1 1401875472 217162240 19939760
02:49:24 1009 8192000000 10325165296 10734514176 1182680912 12.615 100 92 177 1 1441386944 236651040 22054992
02:50:27 1446 8192000000 10391285264 10791383040 1186438112 12.650 100 97 180 2 1497659488 290339616 20881760
02:51:33 2602 8192000000 10397201232 10830475264 1192266400 12.704 100 92 179 1 1502908656 289419312 21222944
02:52:38 1615 8192000000 10422726976 10845519872 1187369952 12.659 100 88 169 0 1529675408 321895344 20410112
02:53:43 1475 8192000000 10429211104 10872815616 1191240896 12.695 100 96 185 0 1534903408 322372800 21289712
02:54:46 1229 8192000000 10387312688 10850025472 1168282416 12.481 100 96 185 1 1498473168 307696640 22494112
02:55:52 657 8192000000 10391834336 10822905856 1178145136 12.573 100 92 175 2 1503688784 304747024 20796624
02:56:56 1816 8192000000 10373046720 10799050752 1173257904 12.527 100 65 126 0 1477008032 288902256 14847872
02:58:05 2891 8192000000 10396422704 10865967104 1187972400 12.664 100 85 170 0 1503192448 295267024 19953024
02:59:10 20763 8192000000 10365289264 10810695680 1170186768 12.499 99 39 73 4 1469668448 290340640 9141040
03:00:37 7351 8192000000 10374847344 10806943744 1173420128 12.529 100 49 96 1 1472728384 287826640 11481616
03:01:49 6616 8192000000 10383481584 10820472832 1139257040 12.209 100 37 72 0 1490594272 341696304 9640928
03:03:02 1248 8192000000 10425454048 10865205248 1151352768 12.322 100 71 135 1 1529221056 362365424 15502864
03:04:04 8115 8192000000 10334799616 10912878592 1146045424 12.272 99 63 125 0 1462702208 302071616 14585168
03:05:13 13092 8192000000 10276708864 10730459136 1137111408 12.188 99 34 66 0 1396177184 249907584 9158192
03:06:36 43205 8192000000 10205554784 10691166208 1102675872 11.863 96 32 62 1 1290800672 180723056 7401744
03:08:21 11902 8192000000 10172496768 10627260416 1124290064 12.068 99 30 60 0 1275515040 143855024 7369952
03:09:38 10227 8192000000 10186217200 10634506240 1156428784 12.370 100 61 113 1 1290004304 121280240 12295280
03:10:50 12318 8192000000 10230328736 10663395328 1157635536 12.381 100 35 70 0 1338546368 172294016 8616816
03:12:28 28561 8192000000 10256916368 10761895936 1144994288 12.262 100 12 24 1 1353799840 205553104 3252448
03:14:02 70503 8192000000 10070745936 10537033728 1119244608 12.020 98 16 32 0 1193355312 70978928 3131776
03:16:22 57893 8192000000 10097379408 10546094080 1134404112 12.163 99 23 47 2 1184043904 44538000 5101792
03:18:23 66760 8192000000 10089007728 10525843456 1104370528 11.879 96 36 68 2 1166329216 54050320 7908368
03:20:31 32382 8192000000 10087386208 10520866816 1133911680 12.158 99 26 50 1 1179886672 41520352 4454640
03:22:07 49668 8192000000 10069315696 10513039360 1110826896 11.940 97 22 44 1 1137055984 21425856 4803232
03:24:00 134143 8192000000 10088817440 10558894080 1136127760 12.179 99 37 68 3 1195269616 51383360 7758496
03:27:18 78356 8192000000 10128048080 10588758016 1143290544 12.246 100 30 58 1 1223605888 74102512 6212832
03:29:46 11985 8192000000 10080505456 10544156672 1129113104 12.113 99 16 32 1 1175283584 42863312 3307168
03:31:01 25994 8192000000 10111500480 10559983616 1130621216 12.127 99 29 58 0 1194873200 57821056 6430928
03:32:44 36239 8192000000 10138505136 10593206272 1142491408 12.239 99 38 73 0 1239409264 88413952 8503904
03:34:21 73762 8192000000 10162577568 10619088896 1109979184 11.932 96 16 32 0 1219790640 105918160 3893296
03:36:35 39957 8192000000 10219434416 10686148608 1150906112 12.318 100 12 24 0 1306881664 153495504 2480048
03:38:16 41779 8192000000 10192954816 10678509568 1123888784 12.064 98 18 36 0 1277115056 149051696 4174576
03:40:00 42734 8192000000 10081391424 10530066432 1101473168 11.852 98 21 42 0 1169884432 63411072 5000192
03:41:43 19151 8192000000 10109296752 10531991552 1135470496 12.173 100 54 102 1 1199894448 54183408 10240544
03:43:03 78847 8192000000 10033036496 10504486912 1048955136 11.351 93 18 36 0 1117551840 65053568 3543136
03:45:25 50500 8192000000 10109269264 10557173760 1130581744 12.127 100 30 60 1 1194901632 58345456 5974432
03:47:20 124938 8192000000 10120917904 10596564992 1103640272 11.872 97 13 26 2 1165959552 59364176 2955104
03:50:28 57119 8192000000 10090281408 10542878720 1103066160 11.867 97 37 74 1 1209359504 97876976 8416368
03:52:31 45459 8192000000 10096735168 10573135872 1132337376 12.143 100 23 46 0 1194029776 57074864 4617536
03:54:23 116358 8192000000 10137734592 10581491712 1137132224 12.189 99 41 82 1 1189375648 43624720 8618704
03:57:27 45756 8192000000 10085919360 10588872704 1129752320 12.119 100 10 20 0 1194498704 62859440 1886944
03:59:30 59320 8192000000 10087742848 10544439296 1117632864 12.005 99 24 46 1 1196704624 73882784 5188976
04:01:30 72557 8192000000 10112731888 10562109440 1118896928 12.017 98 20 38 1 1201065936 78285392 3883616
04:03:42 60349 8192000000 10120106384 10570297344 1099582256 11.834 97 18 36 0 1217216320 113574432 4059632
04:05:48 76339 8192000000 10070205952 10554839040 1119483920 12.022 99 16 32 1 1165493728 42525776 3484032
04:08:05 62929 8192000000 10063273760 10517413888 1119109056 12.019 98 10 20 0 1151443216 30500832 1833328
04:10:11 42674 8192000000 10056227952 10508406784 1101719744 11.854 97 19 36 0 1138328064 32972576 3635744
04:11:54 45572 8192000000 10108476144 10545623040 1121868560 12.045 98 25 48 1 1172567712 46194400 4504752
04:13:41 44135 8192000000 10098744208 10586431488 1144999184 12.263 100 28 56 1 1236378608 85478688 5900736
04:15:26 65412 8192000000 10116002656 10552819712 1140080800 12.216 99 27 54 2 1199108944 53721184 5306960
04:17:31 55380 8192000000 10117539520 10561122304 1127415184 12.097 98 37 73 1 1223769456 88552592 7801680
04:19:27 139087 8192000000 10115769856 10555015168 1095419616 11.794 95 26 50 3 1134567008 33820704 5326688
04:22:52 152021 8192000000 10086103680 10539716608 1134932288 12.168 99 29 58 0 1197373648 55916800 6524560
04:26:28 67627 8192000000 10050831008 10510913536 1093491296 11.776 96 7 14 0 1129414368 34623136 1299936
04:28:36 79322 8192000000 10090115232 10566041600 1116033840 11.990 98 11 22 0 1164942880 46630416 2278624
04:31:04 35560 8192000000 10092401904 10539876352 1117220912 12.001 98 12 24 2 1189653568 69203344 3229312
04:32:46 30832 8192000000 10073616496 10520125440 1086348912 11.708 96 12 24 1 1150380736 61550992 2480832
04:34:47 30596 8192000000 10090183264 10525384704 1120544752 12.032 99 33 66 0 1167565376 40383456 6637168
04:36:21 35479 8192000000 10162577952 10598793216 1110535392 11.937 97 37 74 0 1236947008 118089504 8322112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-06-2022 04:39:05 to 09-06-2022 04:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 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 09-06-2022 04:39:22 to 09-06-2022 04:39:24.

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          221532
MON$OLDEST_ACTIVE               225300
MON$OLDEST_SNAPSHOT             225300
MON$NEXT_TRANSACTION            225300
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                       2075680
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2774 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2774 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2774 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2774 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Jun  9 04:37:17 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Jun  9 04:37:19 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Jun  9 04:37:24 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Jun  9 04:37:27 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Jun  9 04:37:28 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
--- end of diff output ---

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

09.06.22 04:39:25. 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-06-09 04:39:27 No severe PSQL-related problems occured
09.06.22 04:39:29. End of report.