Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 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 16756456 kB
MemAvailable 31351748 kB
Buffers 0 kB
Cached 14897896 kB
SwapCached 440 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.9
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-12-25 16:38:02
DTS_END                         2021-12-25 16:38:02.0170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50776
TRN_ID                          245543
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: 9270.88 19390 1112505
customer order: creation 181.49 417 21779
customer order: refuse 15.06 368 1807
order to supplier: creation 48.60 1243 5832
order to supplier: removal 3.80 86071 456
invoice (draft): creation 48.31 3317 5797
invoice (draft): removal 52.02 737 6242
invoice accept: apply 43.19 17694 5183
invoice accept: cancel 46.33 782 5560
customer reserve: creation 3946.71 178 473605
customer reserve: removal 4575.87 56 549104
realization accept: apply 88.70 185 10644
realization accept: cancel 83.83 108 10060
payment from customer: creation 63.34 113 7601
payment from customer: removal 5.94 103 713
payment to supplier: creation 50.85 124 6102
payment to supplier: removal 3.88 54 466
service: total inventory turnovers 5.03 1018 603
service: total monetary turnovers 6.98 23 838
service: aggregate perf. data 0.91 18445 109
service: refresh index statistics 0.03 256762 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2021 16:38:53 to 25-12-2021 16:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4910
TEST_TIME 2 6201
TEST_TIME 3 5951
TEST_TIME 4 5969
TEST_TIME 5 5583
TEST_TIME 6 6195
TEST_TIME 7 5836
TEST_TIME 8 5935
TEST_TIME 9 6166
TEST_TIME 10 6520
TEST_TIME 11 6543
TEST_TIME 12 6546
TEST_TIME 13 7592
TEST_TIME 14 7738
TEST_TIME 15 8008
TEST_TIME 16 7997
TEST_TIME 17 7962
TEST_TIME 18 8862
TEST_TIME 19 8912
TEST_TIME 20 9025
TEST_TIME 21 9164
TEST_TIME 22 9641
TEST_TIME 23 10101
TEST_TIME 24 10675
TEST_TIME 25 10452
TEST_TIME 26 10582
TEST_TIME 27 10653
TEST_TIME 28 10502
TEST_TIME 29 10292
TEST_TIME 30 10236
TEST_TIME 31 10064
TEST_TIME 32 10080
TEST_TIME 33 9882
TEST_TIME 34 9748
TEST_TIME 35 9629
TEST_TIME 36 9513
TEST_TIME 37 9439
TEST_TIME 38 9510
TEST_TIME 39 9745
TEST_TIME 40 9774
TEST_TIME 41 9673
TEST_TIME 42 9916
TEST_TIME 43 9930
TEST_TIME 44 9923
TEST_TIME 45 9964
TEST_TIME 46 9995
TEST_TIME 47 10072
TEST_TIME 48 10032
TEST_TIME 49 10025
TEST_TIME 50 9984
TEST_TIME 51 10033
TEST_TIME 52 10039
TEST_TIME 53 10005
TEST_TIME 54 9996
TEST_TIME 55 9939
TEST_TIME 56 9776
TEST_TIME 57 9816
TEST_TIME 58 9769
TEST_TIME 59 9748
TEST_TIME 60 9749
TEST_TIME 61 9696
TEST_TIME 62 9688
TEST_TIME 63 9571
TEST_TIME 64 9529
TEST_TIME 65 9474
TEST_TIME 66 9466
TEST_TIME 67 9431
TEST_TIME 68 9488
TEST_TIME 69 9466
TEST_TIME 70 9527
TEST_TIME 71 9538
TEST_TIME 72 9538
TEST_TIME 73 9606
TEST_TIME 74 9597
TEST_TIME 75 9611
TEST_TIME 76 9610
TEST_TIME 77 9679
TEST_TIME 78 9680
TEST_TIME 79 9648
TEST_TIME 80 9557
TEST_TIME 81 9517
TEST_TIME 82 9464
TEST_TIME 83 9421
TEST_TIME 84 9355
TEST_TIME 85 9329
TEST_TIME 86 9319
TEST_TIME 87 9309
TEST_TIME 88 9315
TEST_TIME 89 9290
TEST_TIME 90 9330
TEST_TIME 91 9295
TEST_TIME 92 9366
TEST_TIME 93 9345
TEST_TIME 94 9349
TEST_TIME 95 9390
TEST_TIME 96 9384
TEST_TIME 97 9433
TEST_TIME 98 9447
TEST_TIME 99 9444
TEST_TIME 100 9467
TEST_TIME 101 9512
TEST_TIME 102 9466
TEST_TIME 103 9405
TEST_TIME 104 9425
TEST_TIME 105 9381
TEST_TIME 106 9380
TEST_TIME 107 9365
TEST_TIME 108 9349
TEST_TIME 109 9344
TEST_TIME 110 9343
TEST_TIME 111 9330
TEST_TIME 112 9297
TEST_TIME 113 9302
TEST_TIME 114 9284
TEST_TIME 115 9286
TEST_TIME 116 9332
TEST_TIME 117 9323
TEST_TIME 118 9322
TEST_TIME 119 9347
TEST_TIME 120 9273

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 16:38:57 to 25-12-2021 16:39:00.

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 1552558 1552558 0 0.00 0 12177 7 0 0
doc_list_biud 1552558 1552558 0 0.00 0 61067 25 0 0
sp_add_invoice_to_stock 5287 5183 104 1.96 38 199983 17695 0 104
sp_cancel_adding_invoice 5560 5560 0 0.00 4 48011 782 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 13381 369 0 0
sp_cancel_customer_reserve 549104 549104 0 0.00 1 61068 57 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 5647 103 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 976 55 0 0
sp_cancel_supplier_invoice 6251 6242 9 0.14 1 13448 737 0 9
sp_cancel_supplier_order 456 456 0 0.00 7 860996 86072 0 0
sp_cancel_write_off 10060 10060 0 0.00 1 8034 109 0 0
sp_client_order 21779 21779 0 0.00 3 19812 418 0 0
sp_customer_reserve 473605 473605 0 0.00 0 108415 179 0 0
sp_fill_shopping_cart 33397 33397 0 0.00 1 15461 37 0 0
sp_fill_shopping_cart_clo_res 473601 473601 0 0.00 0 19359 13 0 0
sp_get_clo_for_invoice 5179 5179 0 0.00 0 30942 251 0 0
sp_kill_cost_storno 16799 16799 0 0.00 0 5641 56 0 0
sp_kill_qstorno_ret_qs2qd 1114966 1114966 0 0.00 0 61064 31 0 0
sp_kill_qty_storno 589052 589052 0 0.00 0 61065 66 0 0
sp_lock_selected_doc 21883 21883 0 0.00 0 9919 7 0 0
sp_make_cost_storno 29526 29526 0 0.00 0 10751 52 0 0
sp_multiply_rows_for_pdistr 29526 29526 0 0.00 0 10597 32 0 0
sp_multiply_rows_for_qdistr 21779 21779 0 0.00 0 19172 194 0 0
sp_pay_from_customer 7601 7601 0 0.00 4 11370 113 0 0
sp_pay_to_supplier 6102 6102 0 0.00 4 9109 125 0 0
sp_payment_common 13703 13703 0 0.00 1 9004 31 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 13373 314 0 0
sp_qd_handle_on_invoice_upd_sts 10739 10739 0 0.00 0 12241 296 0 0
sp_qd_handle_on_reserve_upd_sts 10644 10644 0 0.00 0 22656 60 0 0
sp_reserve_write_off 10644 10644 0 0.00 6 23193 186 0 0
sp_supplier_invoice 5797 5797 0 0.00 7 74996 3318 0 0
sp_supplier_order 5832 5832 0 0.00 5 23466 1244 0 0
srv_aggregate_perf_data 109 109 0 0.00 2205 218471 18445 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2156 144165 47482 0 0
srv_find_qd_qs_mism 1063370 1063370 0 0.00 0 12177 6 0 0
srv_make_invnt_saldo 603 603 0 0.00 27 28486 1019 0 0
srv_make_money_saldo 838 838 0 0.00 0 2831 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 193296 423226 256763 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5184 5184 0 0.00 0 29716 181 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 5791 97 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 2748 15 0 0
t$perf-norm:sp_cancel_customer_reserve 753 753 0 0.00 0 5733 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 6520 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 6473 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 543 8 0 0
t$perf-norm:sp_cancel_supplier_order 456 456 0 0.00 0 27038 1519 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 1149 8 0 0
t$perf-norm:sp_client_order 21779 21779 0 0.00 0 8570 18 0 0
t$perf-norm:sp_customer_reserve 1085 1085 0 0.00 0 2053 6 0 0
t$perf-norm:sp_pay_from_customer 7601 7601 0 0.00 0 8709 15 0 0
t$perf-norm:sp_pay_to_supplier 6102 6102 0 0.00 0 7786 12 0 0
t$perf-norm:sp_reserve_write_off 10644 10644 0 0.00 0 17740 15 0 0
t$perf-norm:sp_supplier_invoice 5797 5797 0 0.00 0 8274 22 0 0
t$perf-norm:sp_supplier_order 5832 5832 0 0.00 0 7069 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 6707 65 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 228 4 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 741 13 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 4490 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10566 10358 208 1.96 0 5610 21 0 208
v_all_customers 39780 39780 0 0.00 0 15 0 0 0
v_all_suppliers 32054 32054 0 0.00 0 52 1 0 0
v_all_wares 43558 43558 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 3393 17 0 0
v_cancel_client_order 3614 3614 0 0.00 0 5834 13 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 3455 10 0 0
v_cancel_customer_reserve 1506 1506 0 0.00 0 3449 8 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 655 5 0 0
v_cancel_supplier_invoice 808 790 18 2.22 0 1444 17 0 18
v_cancel_supplier_order 912 912 0 0.00 0 3378 9 0 0
v_cancel_write_off 2122 2122 0 0.00 0 3499 12 0 0
v_max_id_clo_ord 5832 5832 0 0.00 0 730 1 0 0
v_max_id_clo_res 1085 1085 0 0.00 0 5576 20 0 0
v_max_id_ord_sup 5786 5786 0 0.00 0 5629 3 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 1790 4 0 0
v_max_non_paid_realizn 4105 4105 0 0.00 0 2161 5 0 0
v_min_id_clo_ord 5832 5832 0 0.00 0 10702 10 0 0
v_min_id_clo_res 1085 1085 0 0.00 0 1217 3 0 0
v_min_id_ord_sup 5786 5786 0 0.00 0 5785 8 0 0
v_min_non_paid_invoice 6102 6102 0 0.00 0 5738 20 0 0
v_min_non_paid_realizn 7601 7601 0 0.00 0 8799 22 0 0
v_our_firm 10770 10770 0 0.00 0 13 1 0 0
v_reserve_write_off 21288 21288 0 0.00 0 6776 7 0 0
x_lock_depdocs_on_canc_invoice 11407 11407 0 0.00 0 18232 101 0 0
x_lock_depdocs_on_canc_sup_ord 456 456 0 0.00 0 18868 319 0 0
x_make_qstorno_1000_1200 5832 5832 0 0.00 1 23456 1167 0 0
x_make_qstorno_1200_2000 5797 5797 0 0.00 3 74878 3181 0 0
x_make_qstorno_2100_3300 473605 473605 0 0.00 0 108414 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 16:39:01 to 25-12-2021 16:39:05.

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:05 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:07 21989 8192000000 9886868896 10195611648 972658656 10.613 100 87 174 0 1001993216 5893456 23441104
14:10:34 22099 8192000000 9942027648 10262986752 1021025888 11.082 100 90 179 1 1052467568 8137472 23304208
14:12:01 17892 8192000000 9975595024 10308866048 1054004464 11.399 100 81 163 0 1083638832 9186896 20447472
14:13:22 7509 8192000000 10025614032 10372448256 1108223184 11.916 100 81 161 0 1138430096 10542608 19664304
14:14:32 11739 8192000000 10080773456 10435911680 1156783760 12.373 100 80 160 0 1187459248 12309648 18365840
14:15:49 4692 8192000000 10120542800 10491285504 1210868256 12.877 100 80 156 1 1241012624 12610528 17533840
14:16:56 2992 8192000000 10161945728 10540023808 1247637872 13.217 100 87 175 3 1279938272 13048288 19252112
14:18:01 2565 8192000000 10200078096 10580402176 1279944352 13.513 100 88 174 3 1314740848 15057744 19738752
14:19:05 2230 8192000000 10213563952 10598461440 1298857968 13.685 100 88 176 0 1330858816 13074064 18926784
14:20:08 5977 8192000000 10214950000 10610601984 1307500384 13.763 100 56 112 0 1328693120 7969392 13223344
14:21:16 5824 8192000000 10198430352 10616602624 1315225872 13.833 100 41 81 1 1331057392 6227184 9604336
14:22:22 8413 8192000000 10212233568 10627772416 1325589984 13.927 100 22 44 2 1337152512 6473920 5088608
14:23:33 6096 8192000000 10216365872 10636177408 1330685600 13.973 100 28 57 0 1343930000 7081360 6163040
14:24:40 53646 8192000000 10245806592 10649391104 1330821824 13.975 100 64 126 4 1351531968 5116512 15593632
14:26:35 5499 8192000000 10226554768 10645098496 1321524080 13.891 99 31 62 0 1335514160 7201808 6788272
14:27:43 21010 8192000000 10223304464 10646994944 1312396848 13.808 98 31 62 2 1325675024 5716032 7562144
14:29:08 6339 8192000000 10185394624 10591375360 1276460256 13.481 98 36 72 2 1290752192 5727520 8564416
14:30:17 7841 8192000000 10138829936 10540150784 1204125472 12.815 95 12 24 1 1211951184 5110672 2715040
14:31:26 53049 8192000000 10136450352 10544275456 1188804464 12.672 94 21 42 0 1203056256 9288592 4963200
14:33:20 4242 8192000000 10149048272 10548625408 1254210896 13.277 100 22 43 1 1265862704 6240848 5410960
14:34:26 8214 8192000000 10178768240 10583744512 1283113520 13.541 100 27 54 4 1295002480 5406640 6482320
14:35:35 13452 8192000000 10179988144 10594258944 1279943088 13.512 99 29 58 1 1293354848 5460944 7950816
14:36:49 11085 8192000000 10180553568 10600538112 1297960432 13.677 100 34 68 1 1311609712 5048000 8601280
14:38:05 18270 8192000000 10210298928 10622324736 1316318224 13.843 100 36 72 2 1330235984 5229632 8688128
14:39:28 33361 8192000000 10249371536 10662596608 1347495776 14.125 100 32 62 1 1362322096 8229040 6597280
14:41:28 17204 8192000000 10278348880 10696568832 1367541216 14.305 100 47 94 2 1391239872 12950720 10747936
14:42:48 13587 8192000000 10289195968 10716524544 1379909792 14.416 100 30 59 1 1403704000 16789856 7004352
14:44:13 15262 8192000000 10302416448 10730647552 1389283488 14.499 100 48 96 3 1420918352 20492592 11142272
14:45:35 5757 8192000000 10323395200 10748035072 1402333632 14.616 100 42 84 0 1435723664 23122256 10267776
14:46:52 7651 8192000000 10346039184 10762588160 1412488112 14.706 100 67 133 0 1454556944 26456448 15612384
14:48:05 2198 8192000000 10343031760 10762674176 1409143696 14.676 100 77 153 2 1452133008 24126416 18862896
14:49:12 5031 8192000000 10361115392 10780782592 1423011280 14.799 100 70 138 1 1465976272 26185504 16779488
14:50:23 9934 8192000000 10358979792 10778157056 1417715264 14.752 100 63 123 3 1469900736 36722976 15462496
14:51:37 2737 8192000000 10365121088 10789322752 1431307296 14.873 100 75 151 1 1474610304 25183616 18119392
14:52:45 6464 8192000000 10360279600 10781609984 1418989024 14.764 100 73 147 1 1467511520 29830912 18691584
14:54:00 2155 8192000000 10361775824 10782928896 1418829536 14.762 100 77 154 0 1464910464 27498704 18582224
14:55:10 2397 8192000000 10351356672 10766528512 1403586064 14.627 100 87 174 2 1457735408 32580512 21568832
14:56:16 4475 8192000000 10345397840 10768568320 1386949152 14.479 99 64 129 2 1447270128 43136096 17184880
14:57:26 11093 8192000000 10299139488 10726907904 1367039376 14.301 99 36 72 4 1399611856 23181024 9391456
14:58:41 18064 8192000000 10308448608 10744864768 1385607024 14.467 100 36 72 0 1422607856 26878032 10122800
15:00:11 17733 8192000000 10296920672 10739736576 1375454016 14.376 100 21 42 2 1404769456 23829152 5486288
15:02:18 39417 8192000000 10255516480 10691985408 1318204512 13.860 97 9 18 0 1339824288 19241568 2378208
15:03:59 43345 8192000000 10262231536 10697478144 1320266256 13.879 98 30 60 1 1342316640 14958128 7092256
15:05:43 84335 8192000000 10254226688 10696097792 1310673264 13.792 97 8 16 1 1322665344 10453616 1538464
15:08:13 80416 8192000000 10243073936 10670186496 1290442464 13.608 96 29 56 3 1302821168 6192832 6185872
15:10:39 117789 8192000000 10302985584 10719682560 1329330368 13.961 98 52 101 1 1349701872 8839744 11531760
15:13:38 42535 8192000000 10319377456 10750529536 1389741376 14.504 100 56 112 0 1420983072 15706208 15535488
15:15:25 47529 8192000000 10309663968 10756538368 1393625760 14.538 100 26 52 2 1423336688 22787584 6923344
15:17:32 63166 8192000000 10305394704 10762862592 1393009616 14.533 100 13 26 0 1418089072 21688928 3390528
15:19:39 80983 8192000000 10292056736 10748665856 1374137408 14.364 99 8 16 1 1394233264 18254960 1840896
15:22:03 38215 8192000000 10305851584 10763366400 1397891488 14.576 100 27 53 0 1423295008 18865456 6538064
15:23:41 92421 8192000000 10316090160 10777137152 1398152384 14.579 100 21 42 1 1414479920 11514416 4813120
15:26:14 53660 8192000000 10319900240 10770096128 1403482416 14.626 99 39 77 2 1421534720 9103024 8949280
15:28:12 33888 8192000000 10325515040 10770882560 1396609248 14.565 99 31 62 0 1414621904 11447456 6565200
15:29:47 78715 8192000000 10321419264 10778112000 1385361168 14.464 98 21 42 2 1403321200 13254496 4705536
15:32:14 73303 8192000000 10322720304 10771333120 1394758816 14.548 99 30 59 0 1411386864 9864752 6763296
15:34:28 50704 8192000000 10306444736 10762047488 1366129632 14.292 97 27 55 2 1382246240 10102288 6014320
15:36:25 46686 8192000000 10299679248 10771505152 1392170656 14.525 100 7 14 2 1412642128 18776576 1694896
15:38:12 52719 8192000000 10298160640 10753552384 1350342816 14.151 96 22 44 0 1365908864 10909776 4656272
15:40:05 43479 8192000000 10275456048 10732326912 1337651104 14.036 96 9 18 0 1352064192 12420656 1992432
15:41:49 31386 8192000000 10272116912 10728620032 1360368496 14.241 99 17 35 0 1372732144 8339632 4024016
15:43:27 43179 8192000000 10264928912 10718728192 1303288816 13.725 95 8 16 1 1315136240 10130976 1716448
15:45:26 42443 8192000000 10269771008 10719682560 1355626368 14.198 99 37 74 0 1376368976 10896848 9845760
15:47:10 65422 8192000000 10271568720 10710405120 1321372688 13.889 97 22 44 2 1338037936 12161456 4503792
15:49:19 75113 8192000000 10276091088 10714607616 1302819840 13.721 95 18 36 0 1319072176 11994480 4257856
15:51:38 131513 8192000000 10269911600 10715111424 1324366976 13.916 96 14 28 1 1341407952 14101776 2939200
15:54:53 67520 8192000000 10275767392 10735140864 1339533152 14.053 98 13 26 0 1356220288 14033200 2653936
15:57:01 141499 8192000000 10261422752 10697302016 1256666800 13.299 91 32 64 0 1272712496 8671296 7374400
16:00:23 27105 8192000000 10263755936 10713481216 1349019504 14.139 99 8 16 0 1359889712 9313424 1556784
16:01:50 73445 8192000000 10276992896 10730135552 1376496160 14.385 100 16 33 1 1390370848 10142272 3732416
16:04:08 40621 8192000000 10270921664 10720727040 1339000304 14.048 97 21 42 0 1352228384 8303216 4924864
16:05:50 53566 8192000000 10255941840 10715181056 1329731472 13.965 97 7 14 0 1339689808 8300752 1657584
16:07:47 31638 8192000000 10281195184 10735353856 1369774352 14.325 99 18 36 0 1383176128 9289888 4111888
16:09:19 35052 8192000000 10291746192 10739802112 1392072688 14.524 100 37 74 1 1408125824 7820256 8232880
16:10:55 64522 8192000000 10285373904 10735624192 1365567776 14.287 98 19 38 0 1380399440 10567728 4263936
16:13:05 79909 8192000000 10315199424 10762539008 1396914928 14.568 100 13 24 1 1413847824 14335872 2597024
16:15:26 63300 8192000000 10325963472 10759569408 1385869104 14.469 99 21 42 0 1400842656 10673008 4300544
16:17:34 30958 8192000000 10312679440 10759782400 1368656800 14.315 97 30 60 0 1385963792 10546752 6760240
16:19:05 82577 8192000000 10312025088 10769006592 1359185472 14.230 97 11 22 0 1379374640 17862464 2326704
16:21:28 144165 8192000000 10288245888 10733613056 1357143216 14.212 98 28 56 0 1381515616 17413424 6958976
16:26:08 34599 8192000000 10282141424 10752700416 1363485344 14.269 99 8 16 1 1388525600 22904720 2135536
16:27:46 76838 8192000000 10272318032 10741399552 1329702896 13.964 97 11 22 0 1356528832 24222048 2603888
16:30:32 30007 8192000000 10272102272 10724671488 1349971744 14.147 98 33 65 1 1365300320 7506848 7821728
16:32:09 81576 8192000000 10272396512 10730381312 1355883744 14.200 99 27 55 1 1372646016 10687104 6075168
16:34:32 49163 8192000000 10286035920 10730758144 1333105280 13.995 97 31 62 0 1350841552 10412000 7324272
16:36:24 37499 8192000000 10288082672 10732077056 1357060704 14.211 99 26 52 3 1374140768 11327328 5752736

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-12-2021 16:39:07 to 25-12-2021 16:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-12-2021 16:39:23 to 25-12-2021 16:39:25.

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          243802
MON$OLDEST_ACTIVE               245725
MON$OLDEST_SNAPSHOT             245725
MON$NEXT_TRANSACTION            245725
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                       2006080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Sat Dec 25 16:38:07 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Sat Dec 25 16:38:10 2021
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Sat Dec 25 16:38:11 2021
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:125: 
--- end of diff output ---

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

25.12.21 16:39:26. 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-12-25 16:39:28 No severe PSQL-related problems occured
25.12.21 16:39:29. End of report.