Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 16: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 16411084 kB
MemAvailable 31513904 kB
Buffers 0 kB
Cached 15271972 kB
SwapCached 2520 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.10
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 2022-04-23 16:38:15
DTS_END                         2022-04-23 16:38:15.2790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49082
TRN_ID                          245202
ATT_ID                          714
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: 9718.45 20112 1166214
customer order: creation 176.51 337 21181
customer order: refuse 14.13 353 1696
order to supplier: creation 47.15 1275 5658
order to supplier: removal 3.65 100789 438
invoice (draft): creation 46.67 3246 5600
invoice (draft): removal 55.93 777 6711
invoice accept: apply 43.13 17232 5175
invoice accept: cancel 50.40 780 6048
customer reserve: creation 4014.09 169 481691
customer reserve: removal 4962.77 58 595532
realization accept: apply 85.18 166 10221
realization accept: cancel 89.13 108 10695
payment from customer: creation 60.43 105 7252
payment from customer: removal 5.63 62 676
payment to supplier: creation 48.22 117 5786
payment to supplier: removal 3.50 77 420
service: total inventory turnovers 4.53 1234 544
service: total monetary turnovers 6.48 31 777
service: aggregate perf. data 0.91 17735 109
service: refresh index statistics 0.03 257588 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 16:38:58 to 23-04-2022 16:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2783
TEST_TIME 2 2867
TEST_TIME 3 3911
TEST_TIME 4 4068
TEST_TIME 5 4357
TEST_TIME 6 4335
TEST_TIME 7 4440
TEST_TIME 8 4577
TEST_TIME 9 4913
TEST_TIME 10 5018
TEST_TIME 11 5049
TEST_TIME 12 5236
TEST_TIME 13 5635
TEST_TIME 14 5822
TEST_TIME 15 6931
TEST_TIME 16 7112
TEST_TIME 17 7508
TEST_TIME 18 7846
TEST_TIME 19 8030
TEST_TIME 20 8193
TEST_TIME 21 8317
TEST_TIME 22 8432
TEST_TIME 23 8736
TEST_TIME 24 8699
TEST_TIME 25 8650
TEST_TIME 26 9363
TEST_TIME 27 9936
TEST_TIME 28 10548
TEST_TIME 29 10520
TEST_TIME 30 10927
TEST_TIME 31 10769
TEST_TIME 32 10567
TEST_TIME 33 10730
TEST_TIME 34 10745
TEST_TIME 35 10734
TEST_TIME 36 10827
TEST_TIME 37 10638
TEST_TIME 38 10654
TEST_TIME 39 10630
TEST_TIME 40 10483
TEST_TIME 41 10451
TEST_TIME 42 10727
TEST_TIME 43 10523
TEST_TIME 44 10338
TEST_TIME 45 10199
TEST_TIME 46 10268
TEST_TIME 47 10133
TEST_TIME 48 10109
TEST_TIME 49 10056
TEST_TIME 50 9949
TEST_TIME 51 9978
TEST_TIME 52 10040
TEST_TIME 53 10021
TEST_TIME 54 10055
TEST_TIME 55 9982
TEST_TIME 56 9939
TEST_TIME 57 9885
TEST_TIME 58 9776
TEST_TIME 59 9893
TEST_TIME 60 9794
TEST_TIME 61 9708
TEST_TIME 62 9616
TEST_TIME 63 9535
TEST_TIME 64 9534
TEST_TIME 65 9705
TEST_TIME 66 9762
TEST_TIME 67 9675
TEST_TIME 68 9681
TEST_TIME 69 9864
TEST_TIME 70 9845
TEST_TIME 71 9895
TEST_TIME 72 9871
TEST_TIME 73 9899
TEST_TIME 74 9891
TEST_TIME 75 9882
TEST_TIME 76 9911
TEST_TIME 77 9893
TEST_TIME 78 9833
TEST_TIME 79 9755
TEST_TIME 80 9747
TEST_TIME 81 9733
TEST_TIME 82 9710
TEST_TIME 83 9662
TEST_TIME 84 9698
TEST_TIME 85 9645
TEST_TIME 86 9593
TEST_TIME 87 9613
TEST_TIME 88 9588
TEST_TIME 89 9645
TEST_TIME 90 9678
TEST_TIME 91 9659
TEST_TIME 92 9681
TEST_TIME 93 9697
TEST_TIME 94 9821
TEST_TIME 95 9813
TEST_TIME 96 9863
TEST_TIME 97 9826
TEST_TIME 98 9869
TEST_TIME 99 9849
TEST_TIME 100 9832
TEST_TIME 101 9766
TEST_TIME 102 9765
TEST_TIME 103 9704
TEST_TIME 104 9704
TEST_TIME 105 9686
TEST_TIME 106 9643
TEST_TIME 107 9611
TEST_TIME 108 9625
TEST_TIME 109 9618
TEST_TIME 110 9657
TEST_TIME 111 9661
TEST_TIME 112 9687
TEST_TIME 113 9649
TEST_TIME 114 9678
TEST_TIME 115 9637
TEST_TIME 116 9720
TEST_TIME 117 9729
TEST_TIME 118 9790
TEST_TIME 119 9770
TEST_TIME 120 9721

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 16:39:02 to 23-04-2022 16:39:05.

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 1611132 1611132 0 0.00 0 9385 5 0 0
doc_list_biud 1611132 1611132 0 0.00 0 23474 27 0 0
sp_add_invoice_to_stock 5249 5175 74 1.40 34 169745 17233 0 74
sp_cancel_adding_invoice 6048 6048 0 0.00 5 51160 781 0 0
sp_cancel_client_order 1696 1696 0 0.00 4 11166 353 0 0
sp_cancel_customer_reserve 595532 595532 0 0.00 1 23476 59 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 3876 63 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4308 77 0 0
sp_cancel_supplier_invoice 6716 6711 5 0.07 2 21346 777 0 5
sp_cancel_supplier_order 438 438 0 0.00 7 1042371 100790 0 0
sp_cancel_write_off 10695 10695 0 0.00 1 15758 108 0 0
sp_client_order 21181 21181 0 0.00 3 12176 337 0 0
sp_customer_reserve 481691 481691 0 0.00 0 48930 169 0 0
sp_fill_shopping_cart 32435 32435 0 0.00 1 20469 39 0 0
sp_fill_shopping_cart_clo_res 481688 481688 0 0.00 0 19353 12 0 0
sp_get_clo_for_invoice 5171 5171 0 0.00 0 24170 307 0 0
sp_kill_cost_storno 17839 17839 0 0.00 0 8253 58 0 0
sp_kill_qstorno_ret_qs2qd 1208908 1208908 0 0.00 0 23469 32 0 0
sp_kill_qty_storno 636512 636512 0 0.00 0 23473 69 0 0
sp_lock_selected_doc 21198 21198 0 0.00 0 5859 4 0 0
sp_make_cost_storno 28430 28430 0 0.00 0 8858 53 0 0
sp_multiply_rows_for_pdistr 28430 28430 0 0.00 0 5815 26 0 0
sp_multiply_rows_for_qdistr 21181 21181 0 0.00 0 12163 152 0 0
sp_pay_from_customer 7252 7252 0 0.00 4 8409 105 0 0
sp_pay_to_supplier 5786 5786 0 0.00 5 13598 118 0 0
sp_payment_common 13038 13038 0 0.00 1 8388 24 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 11144 319 0 0
sp_qd_handle_on_invoice_upd_sts 11219 11219 0 0.00 0 17402 334 0 0
sp_qd_handle_on_reserve_upd_sts 10221 10221 0 0.00 0 21628 59 0 0
sp_reserve_write_off 10221 10221 0 0.00 6 21713 167 0 0
sp_supplier_invoice 5600 5600 0 0.00 5 95524 3246 0 0
sp_supplier_order 5658 5658 0 0.00 5 36652 1276 0 0
srv_aggregate_perf_data 109 109 0 0.00 2707 124241 17735 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2143 155222 45220 0 0
srv_find_qd_qs_mism 1116757 1116757 0 0.00 0 9384 4 0 0
srv_make_invnt_saldo 544 544 0 0.00 43 110612 1234 0 0
srv_make_money_saldo 777 777 0 0.00 0 4820 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 145644 376407 257588 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5175 5175 0 0.00 0 11560 166 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 10019 120 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 5299 14 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1648 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3001 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2939 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 6270 23 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23236 1568 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2534 10 0 0
t$perf-norm:sp_client_order 21181 21181 0 0.00 0 24751 19 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 1747 7 0 0
t$perf-norm:sp_pay_from_customer 7252 7252 0 0.00 0 6871 13 0 0
t$perf-norm:sp_pay_to_supplier 5786 5786 0 0.00 0 3924 10 0 0
t$perf-norm:sp_reserve_write_off 10221 10221 0 0.00 0 4049 9 0 0
t$perf-norm:sp_supplier_invoice 5600 5600 0 0.00 0 8857 21 0 0
t$perf-norm:sp_supplier_order 5658 5658 0 0.00 0 2287 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 415 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1260 20 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 3213 24 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 6023 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 2 0 0
v_add_invoice_to_stock 10490 10342 148 1.41 0 5296 17 0 148
v_all_customers 38418 38418 0 0.00 0 100 1 0 0
v_all_suppliers 30868 30868 0 0.00 0 38 1 0 0
v_all_wares 42362 42362 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1570 9 0 0
v_cancel_client_order 3392 3392 0 0.00 0 4327 7 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3802 8 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1815 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 190 3 0 0
v_cancel_supplier_invoice 834 824 10 1.19 0 908 11 0 10
v_cancel_supplier_order 876 876 0 0.00 0 3940 9 0 0
v_cancel_write_off 2060 2060 0 0.00 0 4679 16 0 0
v_max_id_clo_ord 5655 5655 0 0.00 0 562 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 15463 35 0 0
v_max_id_ord_sup 5599 5599 0 0.00 0 5075 5 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 209 2 0 0
v_max_non_paid_realizn 3833 3833 0 0.00 0 8383 10 0 0
v_min_id_clo_ord 5655 5655 0 0.00 0 2477 5 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 5866 16 0 0
v_min_id_ord_sup 5599 5599 0 0.00 0 11989 15 0 0
v_min_non_paid_invoice 5786 5786 0 0.00 0 8013 15 0 0
v_min_non_paid_realizn 7252 7252 0 0.00 0 4591 17 0 0
v_our_firm 10782 10782 0 0.00 0 73 1 0 0
v_reserve_write_off 20442 20442 0 0.00 0 4090 7 0 0
x_lock_depdocs_on_canc_invoice 12347 12347 0 0.00 0 10189 112 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 4975 325 0 0
x_make_qstorno_1000_1200 5658 5658 0 0.00 1 36644 1202 0 0
x_make_qstorno_1200_2000 5600 5600 0 0.00 3 86901 3102 0 0
x_make_qstorno_2100_3300 481691 481691 0 0.00 0 48928 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 16:39:06 to 23-04-2022 16:39:10.

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
14:08:19 3 8192000000 8801480208 8813707264 6901088 0.084 2 1 2 0 7041328 65696 74544
14:09:20 30681 8192000000 9908530880 10222198784 986031520 10.743 100 90 180 0 1015768272 6311808 23424944
14:10:54 27938 8192000000 9958032288 10282070016 1037875744 11.244 100 92 183 0 1070454704 9396544 23182416
14:12:30 10378 8192000000 9985879536 10321604608 1072202992 11.573 100 79 158 0 1101796560 10266736 19326832
14:13:47 11712 8192000000 10040986640 10389630976 1128030704 12.103 100 86 172 0 1160683984 11928832 20724448
14:15:04 7649 8192000000 10090008704 10449260544 1180967104 12.599 100 80 158 2 1211882320 11951104 18964112
14:16:15 4851 8192000000 10136906464 10507632640 1226876704 13.025 100 86 171 0 1259362272 13428896 19056672
14:17:22 3189 8192000000 10167465808 10542215168 1252437136 13.261 100 89 178 0 1285852336 13710784 19704416
14:18:26 1278 8192000000 10195185152 10576850944 1287267760 13.579 100 93 187 0 1320716464 12338400 21110304
14:19:28 39383 8192000000 10212364096 10602663936 1303238416 13.725 100 58 117 2 1322063616 5330576 13494624
14:21:08 8948 8192000000 10199853280 10609524736 1309495120 13.781 100 19 39 1 1319763552 6280672 3987760
14:22:19 10296 8192000000 10198225712 10618609664 1318816576 13.866 100 24 48 0 1330630592 6251792 5562224
14:23:33 5383 8192000000 10206729984 10625241088 1324566160 13.918 100 21 42 0 1335243504 5613664 5063680
14:24:38 56527 8192000000 10248213728 10645377024 1328433248 13.953 100 71 144 0 1352460640 6262864 17764528
14:26:36 4454 8192000000 10229128768 10648625152 1335953024 14.021 100 34 68 0 1349567968 6272944 7342000
14:27:40 5964 8192000000 10234262080 10651680768 1329167008 13.960 100 31 63 1 1342231792 6213888 6850896
14:28:50 56333 8192000000 10177395824 10580721664 1258814688 13.319 98 42 84 4 1274981520 5763024 10403808
14:30:49 3516 8192000000 10149317296 10544472064 1230963392 13.063 98 37 74 1 1246870432 7504608 8402432
14:31:56 6239 8192000000 10161572256 10560757760 1245852912 13.200 98 38 76 0 1260901696 5796208 9252576
14:33:03 32742 8192000000 10174248576 10586951680 1285852208 13.566 100 31 61 2 1299207584 5018688 8336688
14:34:57 6978 8192000000 10181207632 10589806592 1278546848 13.500 99 32 64 0 1292451936 6032272 7872816
14:36:04 6549 8192000000 10194057568 10608304128 1304793008 13.739 100 27 53 2 1315926976 5453232 5680736
14:37:13 6297 8192000000 10205733264 10621874176 1315356320 13.835 100 23 46 2 1326817616 5984432 5476864
14:38:21 114435 8192000000 10243084848 10660302848 1336485424 14.026 100 28 54 1 1348033840 5124512 6423904
14:41:21 26668 8192000000 10273257904 10693197824 1363965168 14.273 100 42 85 2 1383427248 9257040 10205040
14:42:50 5348 8192000000 10298371152 10712150016 1383063856 14.444 100 62 125 2 1408944416 11275328 14605232
14:44:00 4313 8192000000 10299400736 10723315712 1391791904 14.522 100 58 116 2 1417197504 11224800 14180800
14:45:17 23353 8192000000 10321676496 10747613184 1403411088 14.625 100 46 92 0 1429760048 15266464 11082496
14:46:53 8408 8192000000 10325787456 10750300160 1405975968 14.648 100 50 100 2 1439768368 21569792 12222608
14:48:09 6233 8192000000 10344418368 10764861440 1417543184 14.751 100 67 132 1 1456863424 22925504 16394736
14:49:25 8064 8192000000 10363620368 10782384128 1433122144 14.889 100 77 154 1 1478929312 27201456 18605712
14:50:37 7209 8192000000 10357546736 10783989760 1421115456 14.783 100 47 94 0 1467733088 34258032 12359600
14:51:58 5876 8192000000 10369290016 10776260608 1407826160 14.665 100 78 155 0 1468712272 40955552 19930560
14:53:09 6397 8192000000 10363939664 10775343104 1406303408 14.651 100 79 156 2 1468641328 42747520 19590400
14:54:21 2143 8192000000 10382503584 10789257216 1413227328 14.713 100 91 180 1 1480381040 44915344 22238368
14:55:28 5119 8192000000 10334070864 10753073152 1385978464 14.470 100 54 109 2 1440203408 40522496 13702448
14:56:42 11891 8192000000 10320261280 10747944960 1370363392 14.330 99 36 72 1 1420107488 39547536 10196560
14:57:58 6677 8192000000 10333682336 10764230656 1380680592 14.423 99 50 98 4 1435685872 41226560 13778720
14:59:12 13541 8192000000 10332384768 10759708672 1374112656 14.364 99 43 86 0 1426833504 41296640 11424208
15:00:52 4131 8192000000 10358513968 10785763328 1389369216 14.500 99 61 122 0 1458812256 53474784 15968256
15:01:59 15175 8192000000 10347004288 10776424448 1389342288 14.500 99 31 62 1 1439303088 41408448 8552352
15:03:17 18566 8192000000 10348460528 10785394688 1391869696 14.523 100 33 67 1 1452930704 51382288 9678720
15:04:37 16239 8192000000 10326873328 10768707584 1370835568 14.335 99 23 46 0 1433320272 55090784 7393920
15:05:54 12634 8192000000 10321141520 10762637312 1376171168 14.382 100 20 40 1 1424254816 42610336 5473312
15:08:23 48360 8192000000 10265302944 10721488896 1322918560 13.903 97 4 8 0 1340094448 16552112 623776
15:10:12 42700 8192000000 10296393424 10717163520 1349864816 14.146 98 54 106 2 1372902800 10496480 12541504
15:12:03 61789 8192000000 10299267728 10742325248 1386286400 14.473 100 8 17 0 1399962896 12210032 1466464
15:14:05 82013 8192000000 10315982432 10751234048 1382262032 14.437 99 14 30 1 1399083712 13477696 3343984
15:16:31 155221 8192000000 10325005984 10767380480 1387778752 14.486 99 14 28 0 1400994464 10160256 3055456
15:20:07 75074 8192000000 10323560064 10798972928 1396883296 14.567 99 8 16 0 1418019344 19266800 1869248
15:22:30 54059 8192000000 10321487760 10774765568 1413072480 14.711 100 42 82 4 1428121584 5486208 9562896
15:24:25 49910 8192000000 10314092768 10777395200 1396583872 14.565 99 27 54 2 1417286288 14701200 6001216
15:26:16 16685 8192000000 10312464944 10767110144 1398677248 14.583 100 47 93 1 1423884016 14777760 10429008
15:27:38 54196 8192000000 10339338496 10786004992 1410812976 14.691 100 23 46 0 1431519376 15193904 5512496
15:29:52 65598 8192000000 10306586688 10779062272 1371556736 14.341 98 15 29 0 1395891168 19938448 4395984
15:31:58 52897 8192000000 10309447728 10776612864 1404720368 14.637 100 11 22 0 1418176384 11049568 2406448
15:33:58 99681 8192000000 10301582336 10766594048 1362885008 14.263 97 6 12 0 1373064368 8761280 1418080
15:36:50 94659 8192000000 10302096576 10778390528 1402233184 14.615 100 13 26 0 1419123696 14396688 2493824
15:39:25 64438 8192000000 10296856512 10762113024 1374885088 14.371 98 14 28 1 1387882944 10013776 2984080
15:41:30 56752 8192000000 10296345856 10770399232 1381498256 14.430 98 15 30 1 1404657504 19663376 3495872
15:43:36 30347 8192000000 10290005184 10750435328 1378881216 14.407 99 16 32 0 1388964592 6486928 3596448
15:45:14 36896 8192000000 10321310160 10776711168 1400119952 14.596 100 28 56 0 1421358640 15160912 6077776
15:46:52 49620 8192000000 10289166128 10742898688 1368910192 14.317 99 18 36 0 1389836288 16663840 4262256
15:48:42 77922 8192000000 10274261632 10720137216 1290450144 13.608 94 20 41 1 1305639136 10569280 4619712
15:51:08 35674 8192000000 10284289408 10729631744 1352642048 14.171 99 34 67 0 1373085536 13231440 7212048
15:52:45 33477 8192000000 10253984160 10701860864 1307560848 13.764 97 23 46 1 1324656864 11941984 5154032
15:54:31 31702 8192000000 10269936288 10720264192 1353603312 14.180 100 26 52 0 1376489888 17123600 5762976
15:57:14 62989 8192000000 10249182864 10689122304 1326312816 13.934 97 37 72 1 1343658128 10062016 7283296
15:59:18 62501 8192000000 10254010688 10715389952 1357892000 14.218 99 10 20 0 1369270336 9044576 2333760
16:01:42 45849 8192000000 10258204336 10704887808 1327151728 13.941 97 35 69 1 1344438704 9133952 8153024
16:04:04 70493 8192000000 10255827024 10713128960 1330674848 13.973 98 16 32 0 1345239376 10794976 3769552
16:06:15 42765 8192000000 10283956672 10737664000 1366350368 14.294 99 23 46 0 1383702640 11985216 5367056
16:07:59 69333 8192000000 10268117456 10715299840 1317069328 13.850 96 10 20 0 1329145216 9962800 2113088
16:10:09 28418 8192000000 10302036048 10750676992 1387202544 14.481 100 29 58 1 1406404208 12893696 6307968
16:11:41 78558 8192000000 10303039872 10776219648 1379575360 14.413 99 6 12 0 1396745408 15829872 1340176
16:14:04 82293 8192000000 10308050896 10763034624 1390486096 14.510 99 14 28 0 1403344016 9773040 3084880
16:16:31 54203 8192000000 10308613760 10764881920 1385336672 14.464 99 22 44 1 1402186400 12198528 4651200
16:18:26 89660 8192000000 10291634496 10731507712 1335921440 14.021 96 42 84 0 1355459328 10451056 9086832
16:21:04 53830 8192000000 10297298896 10740625408 1360623632 14.243 98 33 66 0 1377694368 9128240 7942496
16:23:47 52561 8192000000 10269222752 10729897984 1367689568 14.306 100 14 28 0 1378985424 8117040 3178816
16:25:40 81649 8192000000 10270063536 10735263744 1341322544 14.069 97 16 32 1 1353452448 8416544 3713360
16:28:08 59506 8192000000 10251007600 10710052864 1299988048 13.695 95 15 30 0 1317052624 13475808 3588768
16:30:10 53698 8192000000 10265210320 10725998592 1343743616 14.091 98 15 30 1 1356110000 9128720 3237664
16:32:08 51718 8192000000 10275080112 10732605440 1337890976 14.038 98 31 62 0 1356372112 11750240 6730896
16:34:01 42662 8192000000 10268855664 10740285440 1347722768 14.127 99 9 18 1 1371740176 21857328 2160080
16:36:11 74055 8192000000 10255193616 10704556032 1307221632 13.761 97 9 19 0 1322828576 13271312 2335632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-04-2022 16:39:12 to 23-04-2022 16:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 16:39:28 to 23-04-2022 16:39:30.

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          243801
MON$OLDEST_ACTIVE               245350
MON$OLDEST_SNAPSHOT             245350
MON$NEXT_TRANSACTION            245350
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                       2056160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1496: 
:1497: mscs-test-161.budzdorov.ru    Sat Apr 23 16:38:18 2022
:1498:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1499: 
:1500: 
:1501: mscs-test-161.budzdorov.ru    Sat Apr 23 16:38:23 2022
:1502:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1503: 
:1504: 
:1505: mscs-test-161.budzdorov.ru    Sat Apr 23 16:38:23 2022
:1506:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1507: 
:1508: 
:1509: mscs-test-161.budzdorov.ru    Sat Apr 23 16:38:23 2022
:1510:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1511: 
:1512: 
:1513: mscs-test-161.budzdorov.ru    Sat Apr 23 16:38:24 2022
:1514:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1515: 
--- end of diff output ---

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

23.04.22 16:39:31. 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
2022-04-23 16:39:33 No severe PSQL-related problems occured
23.04.22 16:39:35. End of report.