Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15778580 kB
MemAvailable 30867872 kB
Buffers 0 kB
Cached 15894348 kB
SwapCached 4744 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2021-10-25 01:37:56
DTS_END                         2021-10-25 01:37:56.0360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(570:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33204
TRN_ID                          242016
ATT_ID                          708
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: 9861.12 22037 1183334
customer order: creation 174.97 327 20996
customer order: refuse 15.88 361 1906
order to supplier: creation 48.45 1296 5814
order to supplier: removal 3.54 119419 425
invoice (draft): creation 46.70 3449 5604
invoice (draft): removal 56.58 975 6790
invoice accept: apply 43.54 19602 5225
invoice accept: cancel 51.58 836 6190
customer reserve: creation 4071.03 192 488524
customer reserve: removal 5038.34 64 604601
realization accept: apply 84.90 182 10188
realization accept: cancel 92.63 114 11116
payment from customer: creation 60.82 112 7298
payment from customer: removal 6.13 65 735
payment to supplier: creation 50.00 125 6000
payment to supplier: removal 3.14 89 377
service: total inventory turnovers 4.93 1039 592
service: total monetary turnovers 7.00 26 840
service: aggregate perf. data 0.91 21063 109
service: refresh index statistics 0.03 271402 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2021 01:38:41 to 25-10-2021 01:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2079
TEST_TIME 2 3333
TEST_TIME 3 3759
TEST_TIME 4 4068
TEST_TIME 5 4247
TEST_TIME 6 4615
TEST_TIME 7 5038
TEST_TIME 8 5283
TEST_TIME 9 5437
TEST_TIME 10 5706
TEST_TIME 11 5913
TEST_TIME 12 5917
TEST_TIME 13 6100
TEST_TIME 14 6214
TEST_TIME 15 6509
TEST_TIME 16 6722
TEST_TIME 17 7012
TEST_TIME 18 7365
TEST_TIME 19 7700
TEST_TIME 20 8162
TEST_TIME 21 8186
TEST_TIME 22 8319
TEST_TIME 23 8277
TEST_TIME 24 8227
TEST_TIME 25 8911
TEST_TIME 26 9422
TEST_TIME 27 9657
TEST_TIME 28 9943
TEST_TIME 29 10007
TEST_TIME 30 11213
TEST_TIME 31 11676
TEST_TIME 32 11454
TEST_TIME 33 11534
TEST_TIME 34 11490
TEST_TIME 35 11325
TEST_TIME 36 11143
TEST_TIME 37 11141
TEST_TIME 38 11284
TEST_TIME 39 11009
TEST_TIME 40 10834
TEST_TIME 41 11035
TEST_TIME 42 10860
TEST_TIME 43 10932
TEST_TIME 44 10723
TEST_TIME 45 10654
TEST_TIME 46 10561
TEST_TIME 47 10460
TEST_TIME 48 10592
TEST_TIME 49 10668
TEST_TIME 50 10586
TEST_TIME 51 10571
TEST_TIME 52 10473
TEST_TIME 53 10307
TEST_TIME 54 10159
TEST_TIME 55 10306
TEST_TIME 56 10417
TEST_TIME 57 10467
TEST_TIME 58 10456
TEST_TIME 59 10346
TEST_TIME 60 10343
TEST_TIME 61 10284
TEST_TIME 62 10299
TEST_TIME 63 10270
TEST_TIME 64 10300
TEST_TIME 65 10364
TEST_TIME 66 10328
TEST_TIME 67 10456
TEST_TIME 68 10442
TEST_TIME 69 10424
TEST_TIME 70 10477
TEST_TIME 71 10451
TEST_TIME 72 10366
TEST_TIME 73 10347
TEST_TIME 74 10253
TEST_TIME 75 10237
TEST_TIME 76 10245
TEST_TIME 77 10165
TEST_TIME 78 10171
TEST_TIME 79 10143
TEST_TIME 80 10079
TEST_TIME 81 10043
TEST_TIME 82 10029
TEST_TIME 83 9961
TEST_TIME 84 9943
TEST_TIME 85 9881
TEST_TIME 86 9899
TEST_TIME 87 9939
TEST_TIME 88 9932
TEST_TIME 89 9935
TEST_TIME 90 9913
TEST_TIME 91 9908
TEST_TIME 92 10021
TEST_TIME 93 9983
TEST_TIME 94 9986
TEST_TIME 95 10081
TEST_TIME 96 10103
TEST_TIME 97 10118
TEST_TIME 98 10106
TEST_TIME 99 10120
TEST_TIME 100 10059
TEST_TIME 101 10020
TEST_TIME 102 10064
TEST_TIME 103 10007
TEST_TIME 104 9992
TEST_TIME 105 9957
TEST_TIME 106 9919
TEST_TIME 107 9883
TEST_TIME 108 9857
TEST_TIME 109 9822
TEST_TIME 110 9828
TEST_TIME 111 9798
TEST_TIME 112 9814
TEST_TIME 113 9790
TEST_TIME 114 9823
TEST_TIME 115 9799
TEST_TIME 116 9834
TEST_TIME 117 9828
TEST_TIME 118 9814
TEST_TIME 119 9828
TEST_TIME 120 9865

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-10-2021 01:38:45 to 25-10-2021 01:38:48.

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 1634635 1634635 0 0.00 0 21417 7 0 0
doc_list_biud 1634632 1634632 0 0.00 0 81975 30 0 0
sp_add_invoice_to_stock 5352 5225 127 2.37 26 179739 19603 0 127
sp_cancel_adding_invoice 6190 6190 0 0.00 4 60661 837 0 0
sp_cancel_client_order 1906 1906 0 0.00 4 9732 362 0 0
sp_cancel_customer_reserve 604601 604601 0 0.00 1 21335 64 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 2527 66 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 2 2856 89 0 0
sp_cancel_supplier_invoice 6802 6790 12 0.17 2 81979 976 0 12
sp_cancel_supplier_order 425 425 0 0.00 6 1246365 119419 0 0
sp_cancel_write_off 11116 11116 0 0.00 1 9770 114 0 0
sp_client_order 20996 20996 0 0.00 3 21343 328 0 0
sp_customer_reserve 488524 488524 0 0.00 0 33876 192 0 0
sp_fill_shopping_cart 32401 32401 0 0.00 1 12155 39 0 0
sp_fill_shopping_cart_clo_res 488509 488509 0 0.00 0 16893 13 0 0
sp_get_clo_for_invoice 5210 5210 0 0.00 0 23565 260 0 0
sp_kill_cost_storno 18418 18418 0 0.00 0 9767 63 0 0
sp_kill_qstorno_ret_qs2qd 1227533 1227533 0 0.00 0 81972 35 0 0
sp_kill_qty_storno 646426 646426 0 0.00 0 81974 75 0 0
sp_lock_selected_doc 21412 21412 0 0.00 0 9958 6 0 0
sp_make_cost_storno 28696 28696 0 0.00 0 21053 57 0 0
sp_multiply_rows_for_pdistr 28696 28696 0 0.00 0 8980 28 0 0
sp_multiply_rows_for_qdistr 20996 20996 0 0.00 0 19340 145 0 0
sp_pay_from_customer 7298 7298 0 0.00 4 16877 112 0 0
sp_pay_to_supplier 6000 6000 0 0.00 5 23486 126 0 0
sp_payment_common 13298 13298 0 0.00 2 23439 30 0 0
sp_qd_handle_on_cancel_clo 1906 1906 0 0.00 0 8800 328 0 0
sp_qd_handle_on_invoice_upd_sts 11400 11400 0 0.00 0 19541 349 0 0
sp_qd_handle_on_reserve_upd_sts 10188 10188 0 0.00 0 14310 54 0 0
sp_reserve_write_off 10188 10188 0 0.00 6 17566 183 0 0
sp_supplier_invoice 5604 5604 0 0.00 6 100322 3449 0 0
sp_supplier_order 5814 5814 0 0.00 5 47381 1296 0 0
srv_aggregate_perf_data 110 109 1 0.90 2873 168912 21064 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2069 124419 44490 0 0
srv_find_qd_qs_mism 1133334 1133334 0 0.00 0 21416 6 0 0
srv_make_invnt_saldo 592 592 0 0.00 56 18118 1039 0 0
srv_make_money_saldo 840 840 0 0.00 1 738 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 159116 482722 271403 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5225 5225 0 0.00 0 43216 186 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 6789 118 0 0
t$perf-norm:sp_cancel_client_order 1906 1906 0 0.00 0 5467 14 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 5680 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2444 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 732 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1098 19 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 49189 1872 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 5962 14 0 0
t$perf-norm:sp_client_order 20996 20996 0 0.00 0 11469 20 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 4532 15 0 0
t$perf-norm:sp_pay_from_customer 7298 7298 0 0.00 0 10872 13 0 0
t$perf-norm:sp_pay_to_supplier 6000 6000 0 0.00 0 3004 8 0 0
t$perf-norm:sp_reserve_write_off 10188 10188 0 0.00 0 42672 19 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 14573 21 0 0
t$perf-norm:sp_supplier_order 5814 5814 0 0.00 0 16044 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1729 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 768 18 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 9845 43 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 2531 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10674 10420 254 2.37 0 13946 24 0 254
v_all_customers 38486 38486 0 0.00 0 82 1 0 0
v_all_suppliers 31876 31876 0 0.00 0 83 1 0 0
v_all_wares 41992 41992 0 0.00 0 118 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1642 8 0 0
v_cancel_client_order 3812 3812 0 0.00 0 4868 5 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 2324 9 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1343 6 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 2666 11 0 0
v_cancel_supplier_invoice 870 846 24 2.75 0 2198 18 0 24
v_cancel_supplier_order 850 850 0 0.00 0 4564 15 0 0
v_cancel_write_off 2050 2050 0 0.00 0 3563 18 0 0
v_max_id_clo_ord 5813 5813 0 0.00 0 1790 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 4017 22 0 0
v_max_id_ord_sup 5592 5592 0 0.00 0 4748 3 0 0
v_max_non_paid_invoice 1467 1467 0 0.00 0 1654 4 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 11231 10 0 0
v_min_id_clo_ord 5813 5813 0 0.00 0 3649 7 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 1026 3 0 0
v_min_id_ord_sup 5592 5592 0 0.00 0 5831 8 0 0
v_min_non_paid_invoice 6000 6000 0 0.00 0 23430 22 0 0
v_min_non_paid_realizn 7298 7298 0 0.00 0 16864 20 0 0
v_our_firm 10640 10640 0 0.00 0 14 1 0 0
v_reserve_write_off 20376 20376 0 0.00 0 6442 8 0 0
x_lock_depdocs_on_canc_invoice 12557 12557 0 0.00 0 18143 133 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 13525 437 0 0
x_make_qstorno_1000_1200 5814 5814 0 0.00 2 47374 1211 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 3 96768 3314 0 0
x_make_qstorno_2100_3300 488524 488524 0 0.00 0 31976 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2021 01:38:49 to 25-10-2021 01:38:53.

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
23:07:59 10 8192000000 8801721248 8813907968 6309952 0.076 2 1 2 0 6409584 56848 42784
23:09:00 31682 8192000000 9897883088 10209558528 979970048 10.684 100 93 186 0 1014437872 10258160 24209664
23:10:41 12990 8192000000 9940871376 10261155840 1022610368 11.097 100 87 174 1 1052798624 7430448 22757808
23:12:01 22132 8192000000 9978692688 10309849088 1058268944 11.440 100 85 170 1 1089139936 9114064 21756928
23:13:26 9381 8192000000 10024835872 10373054464 1121176928 12.038 100 77 154 0 1148988240 9440368 18370944
23:14:39 10732 8192000000 10085140880 10443972608 1173233440 12.527 100 82 164 0 1204251712 12089472 18928800
23:15:54 6769 8192000000 10128962112 10497040384 1212902272 12.896 100 86 171 1 1246280336 14583280 18794784
23:17:03 2253 8192000000 10156572480 10529624064 1244580288 13.188 100 82 161 1 1275941216 13699360 17661568
23:18:07 1394 8192000000 10187462896 10564726784 1274873840 13.466 100 86 173 0 1306873536 13063120 18936576
23:19:12 1526 8192000000 10210875408 10593501184 1294994080 13.650 100 87 174 0 1326658096 11702592 19961424
23:20:13 4956 8192000000 10181539344 10588344320 1295807136 13.657 100 43 87 3 1311804752 6669568 9328048
23:21:24 2687 8192000000 10202906720 10608963584 1309009488 13.777 100 34 68 3 1323935408 6847440 8078480
23:22:27 8661 8192000000 10207757760 10620157952 1321280112 13.888 100 23 47 0 1332312224 5651488 5380624
23:23:40 52371 8192000000 10223968304 10633273344 1327491168 13.944 100 42 85 3 1342815728 5470208 9854352
23:25:33 9621 8192000000 10221255344 10642894848 1333483472 13.999 100 19 38 1 1344201952 5684672 5033808
23:26:43 7232 8192000000 10228551760 10649018368 1340090464 14.058 100 23 47 1 1351590784 6577888 4922432
23:27:52 10624 8192000000 10215590032 10631667712 1315870224 13.839 99 20 40 1 1325828912 5541440 4417248
23:29:06 18720 8192000000 10185946384 10608603136 1256849744 13.301 95 12 24 0 1264584256 4629344 3105168
23:30:25 4967 8192000000 10172949152 10573639680 1262206144 13.350 98 35 65 4 1281225152 11541168 7477840
23:31:33 7012 8192000000 10130017136 10528931840 1219957216 12.961 98 21 42 2 1230740464 5977360 4805888
23:32:41 65659 8192000000 10140227520 10538287104 1229923424 13.053 98 36 69 0 1243296960 5023200 8350336
23:34:47 6364 8192000000 10170435072 10575478784 1275217536 13.469 100 38 77 0 1291468448 6946336 9304576
23:35:54 14236 8192000000 10172122224 10584485888 1282487984 13.536 100 20 39 1 1293164672 5702400 4974288
23:37:09 8911 8192000000 10184417024 10599796736 1295741056 13.657 100 18 34 1 1305063664 5461104 3861504
23:38:21 28352 8192000000 10219159216 10624761856 1312254544 13.807 100 52 104 0 1330767232 6589216 11923472
23:39:55 26706 8192000000 10255658992 10666356736 1340261056 14.060 100 55 110 1 1362333264 10213072 11859136
23:41:29 10361 8192000000 10276845392 10688954368 1358567888 14.224 100 67 133 1 1390755280 16725792 15461600
23:42:47 9849 8192000000 10283512976 10702274560 1368776432 14.316 100 53 106 1 1397925344 17058400 12090512
23:44:03 9506 8192000000 10290508560 10717908992 1378504368 14.403 100 43 86 0 1407322928 18322720 10495840
23:45:17 13527 8192000000 10314866496 10735640576 1390191248 14.508 100 43 85 2 1422095280 21277344 10626688
23:46:39 5108 8192000000 10318715792 10740609024 1387527472 14.484 100 61 122 1 1426033056 23792080 14713504
23:47:46 4607 8192000000 10322570576 10741788672 1384235904 14.454 99 68 135 0 1427627536 26869824 16521808
23:48:56 5602 8192000000 10332454432 10754371584 1401601120 14.609 100 60 120 1 1445439248 28603040 15235088
23:50:06 2629 8192000000 10341940208 10753540096 1392161216 14.525 99 80 159 0 1445245120 33649984 19433920
23:51:16 2068 8192000000 10342070320 10751234048 1394651248 14.547 99 85 171 0 1452598896 36542048 21405600
23:52:24 2324 8192000000 10358764400 10764599296 1397007696 14.568 99 74 146 2 1459276976 44648416 17620864
23:53:32 3983 8192000000 10384471104 10796191744 1420093680 14.774 100 82 163 2 1490072272 49522800 20455792
23:54:42 5027 8192000000 10376739904 10789531648 1415093728 14.729 100 78 156 2 1482019744 47803120 19122896
23:55:50 4921 8192000000 10341991904 10750353408 1357855344 14.218 97 69 135 2 1424322832 48413872 18053616
23:57:12 3007 8192000000 10355952240 10771316736 1394923456 14.550 100 70 135 3 1460633472 48112272 17597744
23:58:22 8714 8192000000 10372436048 10780733440 1399479648 14.590 100 78 156 2 1471341456 51963456 19898352
23:59:36 3506 8192000000 10383316608 10795069440 1398468096 14.581 99 79 156 0 1477704192 58718656 20517440
00:00:42 3535 8192000000 10392137680 10806767616 1412484048 14.706 100 70 140 0 1497413040 66387984 18541008
00:02:00 13848 8192000000 10379259808 10803752960 1394088752 14.542 100 51 102 1 1487603952 77892848 15622352
00:03:19 14523 8192000000 10361310336 10785402880 1387331264 14.482 100 44 86 3 1465183136 64624400 13227472
00:04:40 24174 8192000000 10333002048 10773549056 1359753088 14.235 98 26 52 1 1431000384 63803808 7443488
00:06:05 13887 8192000000 10338741072 10773852160 1368961744 14.318 100 26 52 1 1440517872 63686992 7869136
00:07:22 36912 8192000000 10283402304 10760294400 1329947088 13.967 98 16 32 1 1392730256 57553792 5229376
00:09:07 66709 8192000000 10281318048 10711228416 1330668640 13.973 97 24 49 2 1344288720 8637696 4982384
00:11:18 81790 8192000000 10295310944 10745917440 1362346448 14.258 99 4 8 0 1373168960 10374752 447760
00:13:55 63511 8192000000 10303043728 10744750080 1360825728 14.245 98 22 44 0 1382435424 16470768 5138928
00:16:06 77135 8192000000 10295871808 10722983936 1333574064 13.999 96 33 64 2 1353696288 12210992 7911232
00:18:28 66255 8192000000 10293622176 10731302912 1359494800 14.233 99 41 78 3 1377394816 8978784 8921232
00:20:37 41228 8192000000 10296509504 10747752448 1370954320 14.336 99 21 42 0 1386190432 10471120 4764992
00:23:03 37064 8192000000 10271700944 10721849344 1350101168 14.148 98 19 37 0 1364857200 10572896 4183136
00:25:37 57777 8192000000 10303135360 10774011904 1380487520 14.421 99 20 41 2 1406608288 21255328 4865440
00:27:39 82886 8192000000 10297731008 10764259328 1391236352 14.517 100 15 30 0 1410842592 15846816 3759424
00:30:02 51762 8192000000 10335419488 10791436288 1414778896 14.726 100 25 50 0 1445715136 24281296 6654944
00:31:55 116798 8192000000 10308430224 10786119680 1386438160 14.474 99 14 28 0 1416301760 26154272 3709328
00:35:02 31352 8192000000 10298640928 10773168128 1386666048 14.476 99 15 30 0 1404733168 14807584 3259536
00:36:45 79063 8192000000 10311989184 10787475456 1394125024 14.543 99 8 15 0 1411384784 15321168 1938592
00:39:04 68447 8192000000 10309367136 10765127680 1379768048 14.414 98 13 26 0 1391232624 8957136 2507440
00:41:14 66349 8192000000 10275972096 10743435264 1307353584 13.762 94 8 16 0 1323411248 13784048 2273616
00:43:40 40796 8192000000 10292231376 10746834944 1359385696 14.232 98 12 24 0 1372789360 10741856 2661808
00:45:21 75872 8192000000 10279976464 10726535168 1277487664 13.490 92 11 22 0 1288410784 8661776 2261344
00:47:43 89189 8192000000 10283030784 10721816576 1314015936 13.822 96 20 41 0 1334365968 15756368 4593664
00:50:16 64266 8192000000 10296708432 10744524800 1368807024 14.316 99 37 74 0 1389053024 11939696 8306304
00:52:38 78440 8192000000 10279104160 10739777536 1326641808 13.937 96 14 28 0 1346036256 16442096 2952352
00:54:58 35203 8192000000 10252930016 10718638080 1359993248 14.237 100 44 88 1 1379976800 9922656 10060896
00:56:45 67805 8192000000 10254551200 10708877312 1314605568 13.828 96 15 30 0 1328677776 10844096 3228112
00:58:56 58308 8192000000 10273391120 10726277120 1341003264 14.066 98 28 56 0 1361829184 14391168 6434752
01:00:54 47152 8192000000 10255757952 10707263488 1332650304 13.991 98 30 58 1 1349374912 10013648 6710960
01:02:54 59949 8192000000 10254956576 10702225408 1334102352 14.004 98 9 18 0 1351906112 15698256 2105504
01:05:00 57213 8192000000 10271908752 10719195136 1337257024 14.033 98 19 38 0 1354285616 12968176 4060416
01:07:04 27476 8192000000 10256898432 10693607424 1313021152 13.813 96 26 49 0 1327707440 8720208 5966080
01:08:33 124419 8192000000 10281530832 10728292352 1324299552 13.916 96 13 26 0 1339584464 12359920 2924992
01:11:38 15789 8192000000 10305478800 10745475072 1372880352 14.353 99 46 92 1 1395308960 12779856 9648752
01:12:54 118273 8192000000 10299133280 10743877632 1346828672 14.119 97 12 22 1 1367693664 17922016 2942976
01:15:53 39787 8192000000 10323100304 10765979648 1396685248 14.565 100 39 78 2 1421501104 15932848 8883008
01:17:35 82764 8192000000 10292061888 10749353984 1359488416 14.233 97 25 50 2 1376015504 10448752 6078336
01:20:04 41450 8192000000 10302080864 10749886464 1365932912 14.291 98 20 40 1 1380725936 10586480 4206544
01:21:47 64027 8192000000 10287041168 10755186688 1374315472 14.366 99 11 22 1 1393256448 16848784 2092192
01:24:14 102447 8192000000 10264614816 10729861120 1314918384 13.831 95 15 30 1 1328410560 10038848 3453328
01:27:01 68944 8192000000 10273009536 10733727744 1373423680 14.358 100 16 31 0 1389418464 12313920 3680864
01:29:13 65942 8192000000 10272934656 10725244928 1334521008 14.008 97 18 37 1 1347942256 9512128 3909120
01:31:20 59551 8192000000 10277053488 10731864064 1360027120 14.238 99 16 32 0 1374501840 10819376 3655344
01:33:20 65116 8192000000 10269790496 10719698944 1324185712 13.915 97 22 44 2 1345182416 16307584 4689120
01:35:36 17918 8192000000 10274767536 10713198592 1329944944 13.967 98 41 82 1 1350504784 11716096 8843744
01:36:54 78854 8192000000 9442973792 9762033664 572633328 6.533 42 9 18 0 584214256 9625424 1955504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2021 01:38:55 to 25-10-2021 01:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 127 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-10-2021 01:39:11 to 25-10-2021 01:39:13.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242299
MON$OLDEST_ACTIVE               242300
MON$OLDEST_SNAPSHOT             242300
MON$NEXT_TRANSACTION            242300
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-03 16:41:55.3470
MON$PAGES                       2067808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:192: 
:193: 
:194: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:196: 
:197: 
:198: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
:225: 
:226: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
:229: 
:230: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:232: 
:233: 
:234: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:236: 
:237: 
:238: mscs-test-161.budzdorov.ru    Mon Oct 25 01:38:15 2021
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:240: 
--- end of diff output ---

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

25.10.21 01:39:14. There are 3 logs in /var/tmp/logs-oltp30 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
2021-10-25 01:39:16 No severe PSQL-related problems occured
25.10.21 01:39:17. End of report.