Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.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 16235008 kB
MemAvailable 31449716 kB
Buffers 20 kB
Cached 15440108 kB
SwapCached 2644 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-14 04:37:17
DTS_END                         2022-05-14 04:37:17.7760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35610
TRN_ID                          213788
ATT_ID                          643
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: 8824.58 30197 1058949
customer order: creation 157.53 482 18903
customer order: refuse 13.69 376 1643
order to supplier: creation 42.06 1383 5047
order to supplier: removal 3.40 157166 408
invoice (draft): creation 42.55 3336 5106
invoice (draft): removal 50.54 910 6065
invoice accept: apply 39.39 26767 4727
invoice accept: cancel 45.92 1181 5510
customer reserve: creation 3649.24 255 437909
customer reserve: removal 4501.47 93 540176
realization accept: apply 77.11 279 9253
realization accept: cancel 80.42 167 9650
payment from customer: creation 56.24 210 6749
payment from customer: removal 5.16 149 619
payment to supplier: creation 44.98 217 5397
payment to supplier: removal 3.25 170 390
service: total inventory turnovers 3.75 4220 450
service: total monetary turnovers 6.93 340 832
service: aggregate perf. data 0.93 15471 111
service: refresh index statistics 0.03 390763 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 04:39:01 to 14-05-2022 04:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7444
TEST_TIME 2 6028
TEST_TIME 3 5804
TEST_TIME 4 6363
TEST_TIME 5 6183
TEST_TIME 6 6111
TEST_TIME 7 6423
TEST_TIME 8 6575
TEST_TIME 9 6554
TEST_TIME 10 6772
TEST_TIME 11 6712
TEST_TIME 12 6848
TEST_TIME 13 6919
TEST_TIME 14 6912
TEST_TIME 15 7205
TEST_TIME 16 7219
TEST_TIME 17 7457
TEST_TIME 18 7596
TEST_TIME 19 7720
TEST_TIME 20 7648
TEST_TIME 21 7872
TEST_TIME 22 8316
TEST_TIME 23 8514
TEST_TIME 24 8640
TEST_TIME 25 8786
TEST_TIME 26 9210
TEST_TIME 27 9309
TEST_TIME 28 9681
TEST_TIME 29 10025
TEST_TIME 30 10437
TEST_TIME 31 10857
TEST_TIME 32 11099
TEST_TIME 33 11238
TEST_TIME 34 11253
TEST_TIME 35 11001
TEST_TIME 36 11128
TEST_TIME 37 10990
TEST_TIME 38 10766
TEST_TIME 39 10582
TEST_TIME 40 10465
TEST_TIME 41 10467
TEST_TIME 42 10491
TEST_TIME 43 10394
TEST_TIME 44 10247
TEST_TIME 45 10194
TEST_TIME 46 10052
TEST_TIME 47 9972
TEST_TIME 48 9904
TEST_TIME 49 9921
TEST_TIME 50 9785
TEST_TIME 51 9663
TEST_TIME 52 9566
TEST_TIME 53 9481
TEST_TIME 54 9442
TEST_TIME 55 9582
TEST_TIME 56 9547
TEST_TIME 57 9440
TEST_TIME 58 9302
TEST_TIME 59 9285
TEST_TIME 60 9374
TEST_TIME 61 9381
TEST_TIME 62 9351
TEST_TIME 63 9278
TEST_TIME 64 9227
TEST_TIME 65 9213
TEST_TIME 66 9262
TEST_TIME 67 9220
TEST_TIME 68 9180
TEST_TIME 69 9161
TEST_TIME 70 9174
TEST_TIME 71 9255
TEST_TIME 72 9228
TEST_TIME 73 9286
TEST_TIME 74 9250
TEST_TIME 75 9159
TEST_TIME 76 9080
TEST_TIME 77 9035
TEST_TIME 78 9015
TEST_TIME 79 8955
TEST_TIME 80 8930
TEST_TIME 81 8884
TEST_TIME 82 8853
TEST_TIME 83 8851
TEST_TIME 84 8846
TEST_TIME 85 8849
TEST_TIME 86 8820
TEST_TIME 87 8802
TEST_TIME 88 8837
TEST_TIME 89 8837
TEST_TIME 90 8872
TEST_TIME 91 8861
TEST_TIME 92 8894
TEST_TIME 93 8917
TEST_TIME 94 8932
TEST_TIME 95 8886
TEST_TIME 96 8929
TEST_TIME 97 8967
TEST_TIME 98 8893
TEST_TIME 99 8838
TEST_TIME 100 8869
TEST_TIME 101 8830
TEST_TIME 102 8840
TEST_TIME 103 8805
TEST_TIME 104 8784
TEST_TIME 105 8761
TEST_TIME 106 8742
TEST_TIME 107 8780
TEST_TIME 108 8768
TEST_TIME 109 8836
TEST_TIME 110 8819
TEST_TIME 111 8819
TEST_TIME 112 8883
TEST_TIME 113 8872
TEST_TIME 114 8949
TEST_TIME 115 8913
TEST_TIME 116 8898
TEST_TIME 117 8977
TEST_TIME 118 8969
TEST_TIME 119 8919
TEST_TIME 120 8845

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 04:39:05 to 14-05-2022 04:39:08.

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 1462096 1462096 0 0.00 0 25892 14 0 0
doc_list_biud 1462091 1462091 0 0.00 0 32352 40 0 0
sp_add_invoice_to_stock 4894 4727 167 3.41 19 203977 26767 0 167
sp_cancel_adding_invoice 5510 5510 0 0.00 6 85440 1181 0 0
sp_cancel_client_order 1643 1643 0 0.00 5 8851 377 0 0
sp_cancel_customer_reserve 540176 540176 0 0.00 1 23956 94 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 4 10838 149 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 14293 170 0 0
sp_cancel_supplier_invoice 6082 6065 17 0.27 2 12300 910 0 17
sp_cancel_supplier_order 408 408 0 0.00 7 1476472 157166 0 0
sp_cancel_write_off 9650 9650 0 0.00 2 15812 167 0 0
sp_client_order 18903 18903 0 0.00 6 20750 482 0 0
sp_customer_reserve 437909 437909 0 0.00 1 68178 256 0 0
sp_fill_shopping_cart 29034 29034 0 0.00 1 36335 88 0 0
sp_fill_shopping_cart_clo_res 437889 437889 0 0.00 0 13811 19 0 0
sp_get_clo_for_invoice 4686 4686 0 0.00 0 55408 363 0 0
sp_kill_cost_storno 16169 16169 0 0.00 0 8261 72 0 0
sp_kill_qstorno_ret_qs2qd 1096475 1096475 0 0.00 0 23931 40 0 0
sp_kill_qty_storno 577391 577391 0 0.00 0 32350 92 0 0
sp_lock_selected_doc 19333 19333 0 0.00 0 8967 8 0 0
sp_make_cost_storno 26085 26085 0 0.00 0 23113 95 0 0
sp_multiply_rows_for_pdistr 26085 26085 0 0.00 0 9594 39 0 0
sp_multiply_rows_for_qdistr 18902 18902 0 0.00 0 20735 174 0 0
sp_pay_from_customer 6749 6749 0 0.00 5 19138 211 0 0
sp_pay_to_supplier 5397 5397 0 0.00 6 13392 218 0 0
sp_payment_common 12146 12146 0 0.00 2 9965 63 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 8840 310 0 0
sp_qd_handle_on_invoice_upd_sts 10196 10196 0 0.00 0 32344 502 0 0
sp_qd_handle_on_reserve_upd_sts 9253 9253 0 0.00 0 19872 59 0 0
sp_reserve_write_off 9253 9253 0 0.00 9 19928 279 0 0
sp_supplier_invoice 5106 5106 0 0.00 9 79512 3336 0 0
sp_supplier_order 5047 5047 0 0.00 8 26251 1384 0 0
srv_aggregate_perf_data 111 111 0 0.00 2390 218796 15472 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 809 178489 42011 0 0
srv_find_qd_qs_mism 1013180 1013180 0 0.00 0 25891 11 0 0
srv_make_invnt_saldo 450 450 0 0.00 76 104878 4220 0 0
srv_make_money_saldo 832 832 0 0.00 1 13441 340 0 0
srv_recalc_idx_stat 4 4 0 0.00 266967 493995 390763 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 4729 4729 0 0.00 1 26166 906 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 5953 509 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 2703 30 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 903 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 1916 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 1162 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 803 31 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 95227 6480 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 4638 20 0 0
t$perf-norm:sp_client_order 18905 18905 0 0.00 0 4587 34 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 4006 20 0 0
t$perf-norm:sp_pay_from_customer 6749 6749 0 0.00 0 3584 17 0 0
t$perf-norm:sp_pay_to_supplier 5397 5397 0 0.00 0 3802 18 0 0
t$perf-norm:sp_reserve_write_off 9253 9253 0 0.00 0 5278 22 0 0
t$perf-norm:sp_supplier_invoice 5106 5106 0 0.00 0 4611 29 0 0
t$perf-norm:sp_supplier_order 5048 5048 0 0.00 0 4337 34 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 582 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 134 7 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 1334 13 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 339 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 199 53 0 0
v_add_invoice_to_stock 9706 9372 334 3.44 0 5413 25 0 334
v_all_customers 35096 35096 0 0.00 0 91 3 0 0
v_all_suppliers 28238 28238 0 0.00 0 70 4 0 0
v_all_wares 37794 37794 0 0.00 0 86 4 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 335 12 0 0
v_cancel_client_order 3286 3286 0 0.00 0 2172 9 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 5775 21 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 2335 11 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 845 10 0 0
v_cancel_supplier_invoice 830 796 34 4.09 0 1002 23 0 34
v_cancel_supplier_order 816 816 0 0.00 0 3278 18 0 0
v_cancel_write_off 1860 1860 0 0.00 0 2121 23 0 0
v_max_id_clo_ord 5042 5042 0 0.00 0 1558 5 0 0
v_max_id_clo_res 948 948 0 0.00 0 5269 18 0 0
v_max_id_ord_sup 5095 5095 0 0.00 0 6210 9 0 0
v_max_non_paid_invoice 1415 1415 0 0.00 0 355 7 0 0
v_max_non_paid_realizn 3287 3287 0 0.00 0 8758 15 0 0
v_min_id_clo_ord 5042 5042 0 0.00 0 1285 7 0 0
v_min_id_clo_res 948 948 0 0.00 0 1297 9 0 0
v_min_id_ord_sup 5095 5095 0 0.00 0 6156 10 0 0
v_min_non_paid_invoice 5397 5397 0 0.00 0 8491 22 0 0
v_min_non_paid_realizn 6749 6749 0 0.00 0 9741 30 0 0
v_our_firm 9622 9622 0 0.00 0 70 4 0 0
v_reserve_write_off 18506 18506 0 0.00 0 3710 11 0 0
x_lock_depdocs_on_canc_invoice 11177 11177 0 0.00 0 15321 120 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 3193 335 0 0
x_make_qstorno_1000_1200 5047 5047 0 0.00 3 26191 1217 0 0
x_make_qstorno_1200_2000 5106 5106 0 0.00 4 75910 3084 0 0
x_make_qstorno_2100_3300 437908 437908 0 0.00 0 68177 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 04:39:09 to 14-05-2022 04:39:13.

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:21 2 8192000000 8798941984 8810389504 4724576 0.057 3 1 2 0 4825744 65392 35776
02:08:22 25524 8192000000 9750963072 10054643712 819415440 9.093 100 87 174 0 847677408 7339632 20922336
02:09:50 21491 8192000000 9782263088 10093342720 854404880 9.444 100 93 186 0 889357968 13554080 21399008
02:11:15 24003 8192000000 9817147760 10140422144 880240112 9.702 100 88 174 1 923075872 22826848 20008912
02:12:44 11365 8192000000 9875449840 10209460224 917965120 10.076 100 80 160 0 985777616 50163168 17649328
02:13:59 5073 8192000000 9917420784 10267394048 957524144 10.465 100 70 136 0 1030472544 58408336 14540064
02:15:10 2398 8192000000 9960974832 10312867840 1000984656 10.888 100 90 179 0 1081835088 62116480 18733952
02:16:14 1865 8192000000 10003888400 10368737280 1024153424 11.112 100 92 177 0 1119000704 78004640 16842640
02:17:18 1636 8192000000 10011081984 10380898304 1041527040 11.279 100 92 178 1 1132351872 72682528 18142304
02:18:22 1128 8192000000 10040197936 10421030912 1060269056 11.459 100 92 184 2 1162090816 82526256 19295504
02:19:27 1153 8192000000 10039788992 10417852416 1070055312 11.553 100 92 164 1 1155249760 69222688 15971760
02:20:29 10068 8192000000 10043773760 10430435328 1075307824 11.603 100 64 128 1 1164141104 76697440 12135840
02:21:40 1069 8192000000 10047412224 10432368640 1084488624 11.690 100 93 181 1 1165800368 63432976 17878768
02:22:43 1214 8192000000 10062357408 10448183296 1090399616 11.746 100 97 181 2 1180965008 70737616 19827776
02:23:45 1168 8192000000 10067861232 10453831680 1097382608 11.813 100 98 186 4 1186933328 71060880 18489840
02:24:47 1307 8192000000 10067315744 10453098496 1097475392 11.814 100 94 186 1 1188409184 71674352 19259440
02:25:49 1259 8192000000 10088896544 10479210496 1100712400 11.844 100 93 174 0 1210895760 91774608 18408752
02:26:51 1020 8192000000 10058948512 10444402688 1096040336 11.800 100 87 165 0 1180511952 67954640 16516976
02:27:52 1085 8192000000 10079540032 10472071168 1098589776 11.824 100 94 185 1 1200761456 82160704 20010976
02:28:54 1136 8192000000 10053949872 10439725056 1095076336 11.791 100 97 192 0 1176121472 63041312 18003824
02:29:56 1170 8192000000 10047279424 10428145664 1063680464 11.492 99 90 175 0 1166683024 83923056 19079504
02:30:58 1171 8192000000 10036016752 10417532928 1074877760 11.599 100 92 175 0 1160661888 67129968 18654160
02:32:00 1337 8192000000 10047779040 10428170240 1070500784 11.557 100 95 184 1 1171185824 81290256 19394784
02:33:02 1271 8192000000 9991836048 10364780544 1046991904 11.332 99 92 180 1 1111737664 47810560 16935200
02:34:04 1096 8192000000 9993340752 10370502656 1048489472 11.346 99 96 185 1 1119198512 51355728 19353312
02:35:06 2280 8192000000 9971589504 10339438592 1022141728 11.093 100 87 167 1 1102822128 62729328 17951072
02:36:09 1775 8192000000 9997507872 10376028160 1034750928 11.214 100 76 148 0 1111995680 61593952 15650800
02:37:12 3827 8192000000 9985077024 10362945536 1051957584 11.379 100 71 132 3 1108344864 43760096 12627184
02:38:21 1088 8192000000 10003898544 10381570048 1059915296 11.456 100 83 158 2 1123625920 47702848 16007776
02:39:27 1861 8192000000 10014736768 10395537408 1054113664 11.400 99 68 128 3 1141465008 74709632 12641712
02:40:32 845 8192000000 10072128768 10447958016 1076411872 11.613 100 94 183 0 1190356352 96212848 17731632
02:41:37 1981 8192000000 10078979776 10463227904 1089483712 11.738 100 86 161 1 1197388064 91329568 16574784
02:42:43 895 8192000000 10116190272 10502930432 1106984752 11.904 100 92 176 3 1237148880 109908704 20255424
02:43:48 992 8192000000 10154836880 10542432256 1118004448 12.008 100 94 179 0 1266756784 131296352 17455984
02:44:53 6398 8192000000 10184985776 10579333120 1127537280 12.098 100 87 159 0 1303198224 159506144 16154800
02:46:03 6028 8192000000 10222303968 10622894080 1138745504 12.204 100 78 150 2 1339324000 184361520 16216976
02:47:16 1806 8192000000 10267288768 10674745344 1162437984 12.426 100 78 153 1 1382774832 203279248 17057600
02:48:27 1158 8192000000 10308856160 10703273984 1172515376 12.520 100 91 173 1 1418118064 226416480 19186208
02:49:30 3242 8192000000 10340497456 10745135104 1182799424 12.616 100 81 158 1 1445790784 245104448 17886912
02:50:37 1020 8192000000 10368969600 10770522112 1187213888 12.657 100 93 180 1 1482098768 274310896 20573984
02:51:44 1103 8192000000 10429804304 10831708160 1192033104 12.702 100 97 192 0 1539163920 325739312 21391504
02:52:47 1148 8192000000 10442995152 10859368448 1186144352 12.647 100 88 168 1 1547882192 342314848 19422992
02:53:52 797 8192000000 10481502256 10907660288 1204914704 12.822 100 90 178 0 1591491216 364791936 21784576
02:54:56 2834 8192000000 10507899712 10931146752 1207374096 12.845 100 84 168 0 1618993280 391966912 19652272
02:56:01 1374 8192000000 10526432144 10938736640 1188884192 12.673 100 95 188 1 1634247200 423404912 21958096
02:57:06 1154 8192000000 10584246688 10995343360 1205859312 12.831 100 96 189 0 1689393248 459892656 23641280
02:58:10 1160 8192000000 10590647664 11030970368 1196662496 12.745 100 98 193 1 1697424848 475738592 25023760
02:59:12 2995 8192000000 10616495264 11082477568 1200349888 12.780 100 85 164 1 1720341584 499865312 20126384
03:00:19 4532 8192000000 10580854496 11053191168 1189521296 12.679 100 56 112 0 1700315488 496211808 14582384
03:01:27 6148 8192000000 10578148960 11012853760 1187966608 12.664 100 57 112 1 1673543200 471084400 14492192
03:02:35 11568 8192000000 10554263536 11051339776 1191295616 12.695 100 41 82 2 1652850960 450442944 11112400
03:03:58 9385 8192000000 10503121456 10981527552 1176054208 12.553 100 42 81 0 1620755312 434319888 10381216
03:05:46 28778 8192000000 10413825440 10961649664 1151892816 12.327 99 31 62 1 1558492048 398060800 8538432
03:07:53 52795 8192000000 10218235232 10759393280 1114899536 11.979 97 21 41 1 1387651440 266812976 5938928
03:09:46 63430 8192000000 10159274496 10613649408 1136258144 12.180 99 27 54 0 1259201472 116327376 6615952
03:12:23 11105 8192000000 10114386032 10539614208 1125718784 12.081 99 64 117 3 1197475584 60038560 11718240
03:13:38 59643 8192000000 10074228784 10570039296 1133683632 12.156 100 15 30 0 1194126416 57272864 3169920
03:15:38 104957 8192000000 10117375856 10571083776 1091520848 11.757 95 19 38 1 1180843952 85625760 3697344
03:18:24 82177 8192000000 10086474880 10560655360 1101629952 11.853 97 32 64 0 1170800976 63117584 6053440
03:20:47 141184 8192000000 10055881456 10522836992 1105955744 11.894 97 15 30 0 1136881504 27778256 3147504
03:24:50 94201 8192000000 10074952992 10546622464 1092335824 11.765 95 25 50 1 1140821616 42458688 6027104
03:27:34 39728 8192000000 10076663424 10515787776 1133357440 12.153 99 34 67 1 1161902032 21467408 7077184
03:29:49 13564 8192000000 10151472752 10588135424 1149596400 12.306 100 47 93 0 1246948640 86972176 10380064
03:31:40 40670 8192000000 10102201904 10593665024 1120662688 12.033 98 29 58 1 1237264416 110029424 6572304
03:33:24 74648 8192000000 10101838224 10582495232 1127088160 12.094 99 17 34 0 1175212752 44555056 3569536
03:35:59 76487 8192000000 10115215680 10602995712 1115733776 11.987 98 15 30 1 1205497904 86325536 3438592
03:38:16 138867 8192000000 10048487168 10486857728 1067677856 11.530 94 28 56 0 1098642624 24903040 6061728
03:41:36 84868 8192000000 10107425088 10582884352 1116594304 11.995 98 19 38 0 1207281872 86783888 3903680
03:44:01 48155 8192000000 10059469712 10483625984 1085063936 11.696 96 19 37 1 1108385520 19784176 3537408
03:45:50 15739 8192000000 10096225152 10525130752 1130062688 12.122 100 47 94 1 1193524400 53769040 9692672
03:47:06 47320 8192000000 10121701616 10559655936 1128052720 12.103 99 35 70 3 1207366736 72393872 6920144
03:48:53 45345 8192000000 10106895216 10539139072 1119248144 12.020 98 32 64 0 1170571360 45412800 5910416
03:50:45 30339 8192000000 10096099392 10544250880 1125492112 12.079 99 39 72 3 1179934608 47026528 7415968
03:52:16 131945 8192000000 10079149392 10519674880 1128200736 12.104 99 23 46 3 1192230656 58796944 5232976
03:55:29 50580 8192000000 10072464240 10536300544 1130759760 12.129 99 9 19 0 1176673200 44152144 1761296
03:57:20 41946 8192000000 10085698112 10555187200 1113958960 11.970 98 17 34 2 1169417232 51517072 3941200
03:59:10 87306 8192000000 10079623456 10587795456 1114700832 11.977 98 16 32 0 1188183200 69878160 3604208
04:01:39 59761 8192000000 10051213248 10525679616 1112805184 11.959 98 11 22 0 1164496112 49260512 2430416
04:03:44 55899 8192000000 10104625888 10546511872 1125385760 12.078 99 32 64 1 1191206720 58793520 7027440
04:05:40 45907 8192000000 10060796912 10494799872 1085585424 11.701 96 23 46 0 1132111584 42029856 4496304
04:07:26 16687 8192000000 10040631824 10465370112 1074162304 11.592 95 42 83 1 1109566592 27260416 8143872
04:08:49 51566 8192000000 10089026096 10554544128 1105825184 11.893 98 33 66 4 1186349376 73775328 6748864
04:10:47 63861 8192000000 10109806736 10575654912 1120457568 12.031 99 29 58 2 1198270800 71708528 6104704
04:13:03 24365 8192000000 10195295440 10632536064 1145271264 12.265 100 46 92 1 1281465440 126985264 9208912
04:14:28 102570 8192000000 10119444096 10580377600 1127482752 12.098 99 21 40 1 1205227312 73607808 4136752
04:17:13 178489 8192000000 10079271440 10643824640 1113097344 11.962 98 5 10 0 1175227776 61331392 799040
04:21:12 54249 8192000000 10083734592 10532483072 1108738352 11.920 98 23 46 0 1164602512 51276032 4588128
04:23:18 26832 8192000000 10141085024 10575806464 1112731328 11.958 98 30 61 0 1205799040 86684160 6383552
04:24:53 80411 8192000000 10098289440 10568384512 1083528768 11.681 95 26 52 1 1170650240 81541056 5580416
04:27:14 82289 8192000000 10158587600 10593218560 1129385760 12.116 99 30 60 1 1234595808 99222464 5987584
04:29:48 45679 8192000000 10108905568 10549506048 1094515424 11.786 96 34 68 0 1157691264 56184688 6991152
04:31:34 71597 8192000000 10066280000 10492432384 1091525728 11.757 97 20 38 1 1148842784 53021120 4295936
04:34:15 80169 8192000000 10068089184 10561634304 1111614960 11.948 99 5 10 0 1166929920 54491616 823344
04:36:37 54860 8192000000 9399074640 9742200832 526290016 6.036 48 6 10 1 548299824 20815856 1193952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-05-2022 04:39:15 to 14-05-2022 04:39:31.

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 14-05-2022 04:39:32 to 14-05-2022 04:39:34.

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          214169
MON$OLDEST_ACTIVE               214170
MON$OLDEST_SNAPSHOT             214170
MON$NEXT_TRANSACTION            214170
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                       2065440
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Sat May 14 04:37:33 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sat May 14 04:37:40 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

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