Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.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 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 15580896 kB
MemAvailable 30651516 kB
Buffers 0 kB
Cached 16079728 kB
SwapCached 3800 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-13 16:37:58
DTS_END                         2021-10-13 16:37:58.0870
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/52368
TRN_ID                          243507
ATT_ID                          671
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: 9777.18 21820 1173262
customer order: creation 180.35 363 21642
customer order: refuse 15.55 355 1866
order to supplier: creation 47.72 1288 5726
order to supplier: removal 3.59 104456 431
invoice (draft): creation 47.95 3284 5754
invoice (draft): removal 57.14 823 6857
invoice accept: apply 44.04 17077 5285
invoice accept: cancel 51.46 832 6175
customer reserve: creation 4005.58 171 480669
customer reserve: removal 5013.49 59 601619
realization accept: apply 87.81 188 10537
realization accept: cancel 85.28 111 10233
payment from customer: creation 62.38 109 7486
payment from customer: removal 6.25 87 750
payment to supplier: creation 52.40 133 6288
payment to supplier: removal 3.58 98 430
service: total inventory turnovers 5.11 1202 613
service: total monetary turnovers 6.64 26 797
service: aggregate perf. data 0.83 23354 100
service: refresh index statistics 0.03 282375 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2021 16:38:40 to 13-10-2021 16:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4710
TEST_TIME 2 4263
TEST_TIME 3 5151
TEST_TIME 4 4978
TEST_TIME 5 5173
TEST_TIME 6 5191
TEST_TIME 7 4981
TEST_TIME 8 5265
TEST_TIME 9 5486
TEST_TIME 10 5892
TEST_TIME 11 5823
TEST_TIME 12 6182
TEST_TIME 13 6688
TEST_TIME 14 6765
TEST_TIME 15 6677
TEST_TIME 16 7338
TEST_TIME 17 7404
TEST_TIME 18 7517
TEST_TIME 19 7589
TEST_TIME 20 7721
TEST_TIME 21 7981
TEST_TIME 22 8283
TEST_TIME 23 8567
TEST_TIME 24 8530
TEST_TIME 25 8925
TEST_TIME 26 9301
TEST_TIME 27 9108
TEST_TIME 28 9711
TEST_TIME 29 10078
TEST_TIME 30 10325
TEST_TIME 31 10503
TEST_TIME 32 11275
TEST_TIME 33 11346
TEST_TIME 34 11268
TEST_TIME 35 11228
TEST_TIME 36 11163
TEST_TIME 37 11148
TEST_TIME 38 10989
TEST_TIME 39 10854
TEST_TIME 40 10685
TEST_TIME 41 10687
TEST_TIME 42 10537
TEST_TIME 43 10524
TEST_TIME 44 10605
TEST_TIME 45 10509
TEST_TIME 46 10341
TEST_TIME 47 10262
TEST_TIME 48 10239
TEST_TIME 49 10316
TEST_TIME 50 10239
TEST_TIME 51 10430
TEST_TIME 52 10322
TEST_TIME 53 10214
TEST_TIME 54 10203
TEST_TIME 55 10084
TEST_TIME 56 10058
TEST_TIME 57 10032
TEST_TIME 58 9914
TEST_TIME 59 9870
TEST_TIME 60 9820
TEST_TIME 61 9735
TEST_TIME 62 9754
TEST_TIME 63 9722
TEST_TIME 64 9765
TEST_TIME 65 9804
TEST_TIME 66 9801
TEST_TIME 67 9919
TEST_TIME 68 9835
TEST_TIME 69 9842
TEST_TIME 70 10022
TEST_TIME 71 10094
TEST_TIME 72 10083
TEST_TIME 73 10109
TEST_TIME 74 10102
TEST_TIME 75 10108
TEST_TIME 76 10079
TEST_TIME 77 9985
TEST_TIME 78 9936
TEST_TIME 79 9899
TEST_TIME 80 9858
TEST_TIME 81 9817
TEST_TIME 82 9851
TEST_TIME 83 9785
TEST_TIME 84 9742
TEST_TIME 85 9746
TEST_TIME 86 9731
TEST_TIME 87 9731
TEST_TIME 88 9697
TEST_TIME 89 9700
TEST_TIME 90 9782
TEST_TIME 91 9835
TEST_TIME 92 9793
TEST_TIME 93 9854
TEST_TIME 94 9913
TEST_TIME 95 9945
TEST_TIME 96 9971
TEST_TIME 97 9938
TEST_TIME 98 9924
TEST_TIME 99 9897
TEST_TIME 100 9853
TEST_TIME 101 9879
TEST_TIME 102 9859
TEST_TIME 103 9822
TEST_TIME 104 9778
TEST_TIME 105 9765
TEST_TIME 106 9699
TEST_TIME 107 9687
TEST_TIME 108 9656
TEST_TIME 109 9662
TEST_TIME 110 9702
TEST_TIME 111 9669
TEST_TIME 112 9638
TEST_TIME 113 9669
TEST_TIME 114 9674
TEST_TIME 115 9687
TEST_TIME 116 9706
TEST_TIME 117 9778
TEST_TIME 118 9736
TEST_TIME 119 9777
TEST_TIME 120 9780

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 16:38:43 to 13-10-2021 16:38:46.

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 1618953 1618953 0 0.00 0 18826 6 0 0
doc_list_biud 1618952 1618952 0 0.00 0 38851 28 0 0
sp_add_invoice_to_stock 5381 5285 96 1.78 38 190668 17078 0 96
sp_cancel_adding_invoice 6175 6175 0 0.00 4 57896 833 0 0
sp_cancel_client_order 1866 1866 0 0.00 4 12466 355 0 0
sp_cancel_customer_reserve 601619 601619 0 0.00 1 23241 59 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 3 6373 88 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5533 99 0 0
sp_cancel_supplier_invoice 6868 6857 11 0.16 1 15529 823 0 11
sp_cancel_supplier_order 431 431 0 0.00 7 1021569 104456 0 0
sp_cancel_write_off 10233 10233 0 0.00 1 14661 112 0 0
sp_client_order 21642 21642 0 0.00 3 15963 363 0 0
sp_customer_reserve 480669 480669 0 0.00 0 157735 171 0 0
sp_fill_shopping_cart 33117 33117 0 0.00 1 13093 38 0 0
sp_fill_shopping_cart_clo_res 480666 480666 0 0.00 0 19201 14 0 0
sp_get_clo_for_invoice 5280 5280 0 0.00 0 28690 274 0 0
sp_kill_cost_storno 17588 17588 0 0.00 0 10340 55 0 0
sp_kill_qstorno_ret_qs2qd 1220759 1220759 0 0.00 0 23235 32 0 0
sp_kill_qty_storno 642997 642997 0 0.00 0 38846 69 0 0
sp_lock_selected_doc 21927 21927 0 0.00 0 6972 4 0 0
sp_make_cost_storno 29591 29591 0 0.00 0 18817 56 0 0
sp_multiply_rows_for_pdistr 29590 29590 0 0.00 0 10057 30 0 0
sp_multiply_rows_for_qdistr 21642 21642 0 0.00 0 10924 167 0 0
sp_pay_from_customer 7486 7486 0 0.00 4 11317 109 0 0
sp_pay_to_supplier 6288 6288 0 0.00 5 14031 134 0 0
sp_payment_common 13774 13774 0 0.00 1 13898 28 0 0
sp_qd_handle_on_cancel_clo 1866 1866 0 0.00 0 12448 332 0 0
sp_qd_handle_on_invoice_upd_sts 11454 11454 0 0.00 0 38837 336 0 0
sp_qd_handle_on_reserve_upd_sts 10537 10537 0 0.00 0 23701 54 0 0
sp_reserve_write_off 10537 10537 0 0.00 6 23719 188 0 0
sp_supplier_invoice 5754 5754 0 0.00 7 82979 3285 0 0
sp_supplier_order 5726 5726 0 0.00 5 36508 1288 0 0
srv_aggregate_perf_data 100 100 0 0.00 2595 399745 23355 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1584 170720 45955 0 0
srv_find_qd_qs_mism 1123501 1123501 0 0.00 0 18329 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 21 80232 1202 0 0
srv_make_money_saldo 797 797 0 0.00 1 580 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 113137 417864 282375 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5285 5285 0 0.00 0 14874 194 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 3429 109 0 0
t$perf-norm:sp_cancel_client_order 1866 1866 0 0.00 0 3515 18 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 1029 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 1020 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 2166 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1130 22 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 21789 1613 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 9058 32 0 0
t$perf-norm:sp_client_order 21642 21642 0 0.00 0 8065 19 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 2579 6 0 0
t$perf-norm:sp_pay_from_customer 7486 7486 0 0.00 0 10019 12 0 0
t$perf-norm:sp_pay_to_supplier 6288 6288 0 0.00 0 9219 9 0 0
t$perf-norm:sp_reserve_write_off 10537 10537 0 0.00 0 7117 11 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 8240 16 0 0
t$perf-norm:sp_supplier_order 5726 5726 0 0.00 0 8393 19 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 481 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1166 22 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 1187 19 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1056 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10750 10558 192 1.78 0 4421 19 0 192
v_all_customers 39320 39320 0 0.00 0 278 1 0 0
v_all_suppliers 31744 31744 0 0.00 0 98 1 0 0
v_all_wares 43284 43284 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2788 13 0 0
v_cancel_client_order 3732 3732 0 0.00 0 3622 6 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 2894 11 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 3467 7 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3196 12 0 0
v_cancel_supplier_invoice 874 852 22 2.51 0 2050 23 0 22
v_cancel_supplier_order 862 862 0 0.00 0 3066 14 0 0
v_cancel_write_off 2120 2120 0 0.00 0 3226 14 0 0
v_max_id_clo_ord 5724 5724 0 0.00 0 434 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 4271 22 0 0
v_max_id_ord_sup 5750 5750 0 0.00 0 6723 5 0 0
v_max_non_paid_invoice 1890 1890 0 0.00 0 2311 4 0 0
v_max_non_paid_realizn 4102 4102 0 0.00 0 1644 4 0 0
v_min_id_clo_ord 5724 5724 0 0.00 0 4763 8 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 8655 14 0 0
v_min_id_ord_sup 5750 5750 0 0.00 0 6030 6 0 0
v_min_non_paid_invoice 6288 6288 0 0.00 0 13893 21 0 0
v_min_non_paid_realizn 7486 7486 0 0.00 0 9564 21 0 0
v_our_firm 10732 10732 0 0.00 0 50 1 0 0
v_reserve_write_off 21074 21074 0 0.00 0 6845 9 0 0
x_lock_depdocs_on_canc_invoice 12606 12606 0 0.00 0 16527 104 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 7812 328 0 0
x_make_qstorno_1000_1200 5726 5726 0 0.00 2 36503 1204 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 3 82966 3150 0 0
x_make_qstorno_2100_3300 480669 480669 0 0.00 0 157733 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2021 16:38:48 to 13-10-2021 16:38:52.

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:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:02 35329 8192000000 9897292576 10208743424 970233344 10.589 100 80 160 0 997290720 5935728 21121648
14:10:43 31347 8192000000 9944297808 10264473600 1020606560 11.078 100 92 184 0 1051856400 7670736 23579104
14:12:18 22653 8192000000 9988640608 10325811200 1065757408 11.512 100 78 156 0 1094663776 9211936 19694432
14:13:42 12886 8192000000 10043704832 10398474240 1135825600 12.176 100 71 142 0 1162033072 9980960 16226512
14:14:56 10333 8192000000 10109253664 10475606016 1197639760 12.754 100 81 162 2 1229168064 12966432 18561872
14:16:12 4545 8192000000 10136155664 10514776064 1234615072 13.097 100 75 150 0 1262802096 11363392 16823632
14:17:19 3068 8192000000 10189589856 10566729728 1267676480 13.400 100 81 163 0 1301984624 16420976 17887168
14:18:25 1775 8192000000 10205353088 10586271744 1287885584 13.585 100 97 198 0 1323343232 14242960 21214688
14:19:29 2472 8192000000 10205181808 10600546304 1300718592 13.702 100 72 144 0 1329354016 12413136 16222288
14:20:33 30170 8192000000 10202501328 10605809664 1307528752 13.764 100 46 93 0 1325213600 6910624 10774224
14:22:03 8526 8192000000 10194120544 10611838976 1312538928 13.809 100 41 81 1 1330275968 8514128 9222912
14:23:12 9644 8192000000 10200800016 10618310656 1318597936 13.864 100 27 52 1 1330734416 6553616 5582864
14:24:24 9558 8192000000 10204107504 10624458752 1323048496 13.904 100 15 30 1 1332077968 6087760 2941712
14:25:35 14882 8192000000 10220002688 10633601024 1330919760 13.975 100 16 30 2 1339774304 5754192 3100352
14:26:52 34513 8192000000 10217212224 10642927616 1331459696 13.980 100 22 42 1 1342726352 6639200 4627456
14:28:27 5561 8192000000 10203704384 10619502592 1305380096 13.744 99 39 77 3 1320104576 5379248 9345232
14:29:33 6460 8192000000 10191311728 10601267200 1298672880 13.683 100 22 44 1 1309513360 5767952 5072528
14:30:44 12426 8192000000 10123722960 10517659648 1198270000 12.760 96 21 41 1 1207974592 5218096 4486496
14:31:57 33143 8192000000 10121180624 10513862656 1108909600 11.922 89 23 46 1 1118771600 4920560 4941440
14:33:31 13017 8192000000 10152601200 10557059072 1255239984 13.286 99 27 54 1 1266786352 4763664 6782704
14:34:48 7575 8192000000 10175222880 10584883200 1284781248 13.557 100 27 54 1 1296430384 5409216 6239920
14:35:58 23073 8192000000 10188733744 10607341568 1304671216 13.738 100 28 54 0 1316488080 5436160 6380704
14:37:21 9606 8192000000 10190523200 10609291264 1305700896 13.747 100 18 35 1 1315918416 6425376 3792144
14:38:33 3235 8192000000 10254007200 10654216192 1336009808 14.021 100 73 146 4 1361828864 8855200 16963856
14:39:42 34073 8192000000 10260809600 10674888704 1350584064 14.153 100 48 96 0 1371948160 10395392 10968704
14:41:22 19741 8192000000 10274339072 10692972544 1370221024 14.329 100 37 69 1 1388941648 10324368 8396256
14:42:45 26417 8192000000 10287748176 10715836416 1382292400 14.437 100 35 70 2 1402195296 12086400 7816496
14:44:15 37481 8192000000 10307978240 10734555136 1394234496 14.544 100 29 58 0 1416279168 15113968 6930704
14:45:56 4160 8192000000 10327606800 10744537088 1397121616 14.569 100 57 113 1 1428177632 17299424 13756592
14:47:11 8360 8192000000 10336964768 10765787136 1411678416 14.699 100 51 101 0 1449671936 25250704 12742816
14:48:25 9447 8192000000 10346893456 10770665472 1414793888 14.727 100 47 94 2 1453391520 27460720 11136912
14:49:41 1583 8192000000 10361716512 10781212672 1417153728 14.747 100 73 145 1 1468501840 33955696 17392416
14:50:47 7138 8192000000 10359491920 10779348992 1406179792 14.650 99 61 120 0 1456801408 36373488 14248128
14:52:00 9940 8192000000 10364118400 10787241984 1416920800 14.745 100 59 119 1 1470778992 39584704 14273488
14:53:16 6967 8192000000 10356879312 10777423872 1398724320 14.584 99 70 139 2 1459343120 43533760 17085040
14:54:29 6065 8192000000 10365660960 10784215040 1414146816 14.721 100 66 132 1 1472679488 42535152 15997520
14:55:40 2498 8192000000 10363578656 10780680192 1409190192 14.677 100 54 108 2 1467404096 43918848 14295056
14:56:47 2184 8192000000 10377943856 10787635200 1407258656 14.660 100 89 178 6 1481621680 51805216 22557808
14:57:53 3585 8192000000 10349650288 10757894144 1374356576 14.366 100 84 168 1 1451227520 55336736 21534208
14:59:01 3511 8192000000 10373236368 10788978688 1403431312 14.626 100 73 146 0 1480205216 58008016 18765888
15:00:09 5389 8192000000 10345879296 10771128320 1393200208 14.534 100 39 78 0 1456110176 52538512 10371456
15:01:18 7392 8192000000 10392587296 10809999360 1415124464 14.729 100 67 134 1 1490953584 58488384 17340736
15:02:31 18460 8192000000 10366112240 10790232064 1395511744 14.555 100 43 86 1 1464757696 57077248 12168704
15:03:52 8973 8192000000 10360027936 10785390592 1407137520 14.658 100 39 78 1 1466901440 48891888 10872032
15:05:09 11072 8192000000 10376972688 10808733696 1407332784 14.660 100 51 102 2 1479529280 57302000 14894496
15:06:28 19048 8192000000 10343649712 10785185792 1398997360 14.586 100 24 48 1 1449669632 44155200 6517072
15:08:13 71155 8192000000 10332668640 10788642816 1380146016 14.418 99 12 24 1 1426109472 42966736 2996720
15:10:34 89647 8192000000 10295573760 10748067840 1351815520 14.164 97 8 17 0 1372927488 19378576 1733392
15:13:22 59703 8192000000 10296391808 10741780480 1355065136 14.193 97 5 10 0 1363639088 7351088 1222864
15:15:34 56791 8192000000 10305255168 10747125760 1384064112 14.453 99 33 65 3 1400704384 9595744 7044528
15:17:33 37678 8192000000 10324477168 10760380416 1390326816 14.509 99 44 88 1 1416469584 15851296 10291472
15:19:25 64531 8192000000 10283450608 10729213952 1331505312 13.981 95 29 57 3 1348714672 10545136 6664224
15:21:30 170719 8192000000 10333955920 10769346560 1390205472 14.508 99 55 108 1 1419221824 16868272 12148080
15:25:22 44160 8192000000 10326079648 10772295680 1385083104 14.462 98 26 50 3 1411205936 19850384 6272448
15:27:15 79815 8192000000 10298046736 10769055744 1375386496 14.375 98 12 24 0 1399508160 21048880 3072784
15:29:35 126358 8192000000 10298877120 10771447808 1401681264 14.610 100 11 22 0 1415250128 10950720 2618144
15:32:53 23459 8192000000 10279970640 10732498944 1349842992 14.146 97 17 34 1 1365722816 11835456 4044368
15:34:25 56588 8192000000 10274808704 10735874048 1369716400 14.325 99 9 18 0 1378364256 6881632 1766224
15:36:25 45982 8192000000 10289723568 10753654784 1389913216 14.505 100 20 40 0 1408179280 13610336 4655728
15:38:16 62684 8192000000 10279025088 10750320640 1361792736 14.253 98 8 16 1 1378351136 15105808 1452592
15:40:20 63807 8192000000 10272192736 10727124992 1313970672 13.822 95 15 30 1 1328855296 11752640 3131984
15:42:28 18743 8192000000 10281893952 10729766912 1354668304 14.189 98 31 62 0 1372961200 11446784 6846112
15:43:53 34311 8192000000 10278754944 10738503680 1352647936 14.171 99 24 48 0 1377149296 18977824 5523536
15:45:38 36241 8192000000 10311977456 10756321280 1390162112 14.507 100 43 86 2 1419482240 18295904 11024224
15:47:18 73266 8192000000 10291784912 10755883008 1353671184 14.180 98 14 28 0 1376693600 19988352 3034064
15:49:32 89921 8192000000 10293019216 10742599680 1365430896 14.286 99 20 40 2 1379986336 10258432 4297008
15:52:04 61704 8192000000 10265404832 10713948160 1291257664 13.616 94 29 58 0 1310409232 12663584 6487984
15:54:12 68028 8192000000 10262344880 10716798976 1349223360 14.140 99 28 56 1 1363021008 7446800 6350848
15:56:21 56372 8192000000 10254955888 10693079040 1306972880 13.759 97 45 90 2 1333810320 16719648 10117792
15:58:18 80902 8192000000 10270850944 10729582592 1366324672 14.294 100 17 34 1 1382969344 12673488 3971184
16:00:39 46877 8192000000 10267520416 10727829504 1372620288 14.351 100 16 32 1 1388971296 12224816 4126192
16:02:28 37018 8192000000 10272284000 10724712448 1357353104 14.214 99 9 18 1 1365294576 6327200 1614272
16:04:06 40362 8192000000 10260564320 10717782016 1332262256 13.988 97 26 51 2 1346478784 8073424 6143104
16:05:53 75898 8192000000 10286936832 10750935040 1361980912 14.255 99 15 30 0 1382152096 16748256 3422928
16:08:10 86497 8192000000 10300362480 10736934912 1343963776 14.093 97 18 36 0 1357078544 9210992 3903776
16:10:37 89185 8192000000 10306055008 10770837504 1375044800 14.372 99 7 14 0 1394731296 18287648 1398848
16:13:08 96004 8192000000 10307618672 10761388032 1366798896 14.298 98 21 42 1 1385232048 13688192 4744960
16:15:44 29498 8192000000 10323803872 10764873728 1376940288 14.389 98 54 108 1 1404065152 14787520 12337344
16:17:19 99294 8192000000 10292857216 10763694080 1381883744 14.433 99 20 40 0 1402385552 15478000 5023808
16:20:10 67809 8192000000 10263075872 10720047104 1355725744 14.199 97 11 22 0 1365738672 7249088 2763840
16:22:53 79241 8192000000 10293425376 10750431232 1384754432 14.459 99 20 40 1 1397167232 7901456 4511344
16:25:16 82900 8192000000 10270638032 10733309952 1319007712 13.868 95 6 12 1 1332939008 12473136 1458160
16:27:39 39558 8192000000 10263898896 10719342592 1331745152 13.983 97 13 26 0 1343502672 9018560 2738960
16:29:19 29170 8192000000 10262766768 10706386944 1344676576 14.100 99 28 55 2 1361113280 10313088 6123616
16:30:48 77773 8192000000 10269082000 10731216896 1361847072 14.254 100 21 42 0 1377938768 11254784 4836912
16:33:13 58498 8192000000 10293245616 10746187776 1358958032 14.228 99 15 30 0 1377220752 14869632 3393088
16:35:36 76198 8192000000 10266210928 10714738688 1325430992 13.926 97 18 36 1 1344244080 14663824 4149264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2021 16:38:53 to 13-10-2021 16:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          241963
MON$OLDEST_ACTIVE               243859
MON$OLDEST_SNAPSHOT             243859
MON$NEXT_TRANSACTION            243859
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                       2065632
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:258: 
:259: mscs-test-161.budzdorov.ru    Wed Oct 13 16:38:09 2021
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:261: 
:262: 
:263: mscs-test-161.budzdorov.ru    Wed Oct 13 16:38:09 2021
:264:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:265: 
--- end of diff output ---

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

13.10.21 16:39:12. 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-13 16:39:15 No severe PSQL-related problems occured
13.10.21 16:39:16. End of report.