Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.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 15812608 kB
MemAvailable 30915200 kB
Buffers 0 kB
Cached 15821112 kB
SwapCached 1868 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-08-23 16:37:50
DTS_END                         2021-08-23 16:37:50.0440
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/44588
TRN_ID                          250728
ATT_ID                          731
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: 9815.83 20990 1177900
customer order: creation 183.04 368 21965
customer order: refuse 15.88 353 1906
order to supplier: creation 48.70 1365 5844
order to supplier: removal 3.72 99174 446
invoice (draft): creation 49.00 3354 5880
invoice (draft): removal 55.40 830 6648
invoice accept: apply 45.12 17304 5414
invoice accept: cancel 50.12 823 6014
customer reserve: creation 4155.81 171 498697
customer reserve: removal 4896.86 59 587623
realization accept: apply 88.10 200 10572
realization accept: cancel 86.17 121 10340
payment from customer: creation 63.78 134 7653
payment from customer: removal 6.01 80 721
payment to supplier: creation 51.42 148 6170
payment to supplier: removal 3.63 88 435
service: total inventory turnovers 4.96 1231 595
service: total monetary turnovers 7.32 25 878
service: aggregate perf. data 0.79 21367 95
service: refresh index statistics 0.03 272599 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-08-2021 16:38:44 to 23-08-2021 16:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2632
TEST_TIME 2 2634
TEST_TIME 3 3486
TEST_TIME 4 2831
TEST_TIME 5 3851
TEST_TIME 6 3613
TEST_TIME 7 3895
TEST_TIME 8 4054
TEST_TIME 9 4606
TEST_TIME 10 5241
TEST_TIME 11 5826
TEST_TIME 12 6333
TEST_TIME 13 6268
TEST_TIME 14 6557
TEST_TIME 15 6708
TEST_TIME 16 6953
TEST_TIME 17 7145
TEST_TIME 18 7474
TEST_TIME 19 7521
TEST_TIME 20 7725
TEST_TIME 21 8335
TEST_TIME 22 8493
TEST_TIME 23 9019
TEST_TIME 24 9250
TEST_TIME 25 9235
TEST_TIME 26 9480
TEST_TIME 27 9840
TEST_TIME 28 9968
TEST_TIME 29 9876
TEST_TIME 30 9876
TEST_TIME 31 10245
TEST_TIME 32 11192
TEST_TIME 33 11025
TEST_TIME 34 10832
TEST_TIME 35 10858
TEST_TIME 36 10872
TEST_TIME 37 10752
TEST_TIME 38 10788
TEST_TIME 39 10828
TEST_TIME 40 10782
TEST_TIME 41 10588
TEST_TIME 42 10765
TEST_TIME 43 10591
TEST_TIME 44 10385
TEST_TIME 45 10376
TEST_TIME 46 10300
TEST_TIME 47 10338
TEST_TIME 48 10247
TEST_TIME 49 10217
TEST_TIME 50 10223
TEST_TIME 51 10143
TEST_TIME 52 10151
TEST_TIME 53 10201
TEST_TIME 54 10168
TEST_TIME 55 10097
TEST_TIME 56 10053
TEST_TIME 57 9965
TEST_TIME 58 9838
TEST_TIME 59 9853
TEST_TIME 60 9816
TEST_TIME 61 9758
TEST_TIME 62 9810
TEST_TIME 63 9803
TEST_TIME 64 9897
TEST_TIME 65 9896
TEST_TIME 66 9960
TEST_TIME 67 9904
TEST_TIME 68 9970
TEST_TIME 69 9930
TEST_TIME 70 10079
TEST_TIME 71 10088
TEST_TIME 72 10127
TEST_TIME 73 10155
TEST_TIME 74 10258
TEST_TIME 75 10272
TEST_TIME 76 10263
TEST_TIME 77 10318
TEST_TIME 78 10198
TEST_TIME 79 10147
TEST_TIME 80 10173
TEST_TIME 81 10110
TEST_TIME 82 10087
TEST_TIME 83 10066
TEST_TIME 84 10054
TEST_TIME 85 10029
TEST_TIME 86 9984
TEST_TIME 87 9942
TEST_TIME 88 9917
TEST_TIME 89 9895
TEST_TIME 90 9878
TEST_TIME 91 9914
TEST_TIME 92 9894
TEST_TIME 93 9986
TEST_TIME 94 9950
TEST_TIME 95 9993
TEST_TIME 96 9957
TEST_TIME 97 9982
TEST_TIME 98 9981
TEST_TIME 99 9952
TEST_TIME 100 10011
TEST_TIME 101 10014
TEST_TIME 102 10019
TEST_TIME 103 10089
TEST_TIME 104 10033
TEST_TIME 105 9957
TEST_TIME 106 9942
TEST_TIME 107 9902
TEST_TIME 108 9868
TEST_TIME 109 9842
TEST_TIME 110 9830
TEST_TIME 111 9819
TEST_TIME 112 9826
TEST_TIME 113 9782
TEST_TIME 114 9820
TEST_TIME 115 9837
TEST_TIME 116 9856
TEST_TIME 117 9837
TEST_TIME 118 9834
TEST_TIME 119 9841
TEST_TIME 120 9819

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-08-2021 16:38:48 to 23-08-2021 16:38:51.

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 1639446 1639446 0 0.00 0 24966 6 0 0
doc_list_biud 1639446 1639446 0 0.00 0 82612 27 0 0
sp_add_invoice_to_stock 5544 5414 130 2.34 38 189041 17305 0 130
sp_cancel_adding_invoice 6014 6014 0 0.00 3 75864 823 0 0
sp_cancel_client_order 1906 1906 0 0.00 4 10563 354 0 0
sp_cancel_customer_reserve 587623 587623 0 0.00 1 46358 59 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 5262 81 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 3426 88 0 0
sp_cancel_supplier_invoice 6657 6648 9 0.13 2 82625 830 0 9
sp_cancel_supplier_order 446 446 0 0.00 5 1006012 99175 0 0
sp_cancel_write_off 10340 10340 0 0.00 1 13789 122 0 0
sp_client_order 21965 21965 0 0.00 3 24814 368 0 0
sp_customer_reserve 498697 498697 0 0.00 0 49921 172 0 0
sp_fill_shopping_cart 33683 33683 0 0.00 1 23056 39 0 0
sp_fill_shopping_cart_clo_res 498694 498694 0 0.00 0 32613 13 0 0
sp_get_clo_for_invoice 5411 5411 0 0.00 0 35027 284 0 0
sp_kill_cost_storno 17510 17510 0 0.00 0 13638 62 0 0
sp_kill_qstorno_ret_qs2qd 1192680 1192680 0 0.00 0 82605 32 0 0
sp_kill_qty_storno 628960 628960 0 0.00 0 82610 70 0 0
sp_lock_selected_doc 22154 22154 0 0.00 0 9914 6 0 0
sp_make_cost_storno 29806 29806 0 0.00 0 14396 58 0 0
sp_multiply_rows_for_pdistr 29806 29806 0 0.00 0 14528 33 0 0
sp_multiply_rows_for_qdistr 21965 21965 0 0.00 0 24808 141 0 0
sp_pay_from_customer 7653 7653 0 0.00 4 14539 134 0 0
sp_pay_to_supplier 6170 6170 0 0.00 5 11487 149 0 0
sp_payment_common 13823 13823 0 0.00 1 9534 32 0 0
sp_qd_handle_on_cancel_clo 1906 1906 0 0.00 0 10555 324 0 0
sp_qd_handle_on_invoice_upd_sts 11425 11425 0 0.00 0 31264 342 0 0
sp_qd_handle_on_reserve_upd_sts 10572 10572 0 0.00 0 13537 63 0 0
sp_reserve_write_off 10572 10572 0 0.00 7 15002 201 0 0
sp_supplier_invoice 5880 5880 0 0.00 6 95281 3354 0 0
sp_supplier_order 5844 5844 0 0.00 4 42645 1365 0 0
srv_aggregate_perf_data 95 95 0 0.00 2554 195589 21367 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1727 133504 42764 0 0
srv_find_qd_qs_mism 1126718 1126718 0 0.00 0 24965 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 26 61591 1232 0 0
srv_make_money_saldo 878 878 0 0.00 0 1085 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 55249 554445 272600 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5416 5416 0 0.00 0 8667 154 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 4359 113 0 0
t$perf-norm:sp_cancel_client_order 1906 1906 0 0.00 0 3135 14 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 5851 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 1942 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 3652 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1032 21 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 18709 1588 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 2509 7 0 0
t$perf-norm:sp_client_order 21965 21965 0 0.00 0 7733 19 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 725 4 0 0
t$perf-norm:sp_pay_from_customer 7653 7653 0 0.00 0 4946 13 0 0
t$perf-norm:sp_pay_to_supplier 6170 6170 0 0.00 0 4299 10 0 0
t$perf-norm:sp_reserve_write_off 10572 10572 0 0.00 0 5172 12 0 0
t$perf-norm:sp_supplier_invoice 5880 5880 0 0.00 0 8467 17 0 0
t$perf-norm:sp_supplier_order 5844 5844 0 0.00 0 4796 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 200 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 89 3 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 735 17 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 4094 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 11082 10822 260 2.34 0 12018 21 0 260
v_all_customers 40862 40862 0 0.00 0 17 1 0 0
v_all_suppliers 32202 32202 0 0.00 0 66 1 0 0
v_all_wares 43930 43930 0 0.00 0 98 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 8840 19 0 0
v_cancel_client_order 3812 3812 0 0.00 0 3187 6 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 3186 8 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 2965 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2464 8 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 2728 16 0 18
v_cancel_supplier_order 892 892 0 0.00 0 401 4 0 0
v_cancel_write_off 2122 2122 0 0.00 0 8641 23 0 0
v_max_id_clo_ord 5844 5844 0 0.00 0 3550 2 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 2052 15 0 0
v_max_id_ord_sup 5874 5874 0 0.00 0 4248 4 0 0
v_max_non_paid_invoice 1787 1787 0 0.00 0 3872 7 0 0
v_max_non_paid_realizn 3781 3781 0 0.00 0 5262 6 0 0
v_min_id_clo_ord 5844 5844 0 0.00 0 17000 14 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 2606 8 0 0
v_min_id_ord_sup 5874 5874 0 0.00 0 3808 6 0 0
v_min_non_paid_invoice 6170 6170 0 0.00 0 9524 19 0 0
v_min_non_paid_realizn 7653 7653 0 0.00 0 9472 22 0 0
v_our_firm 10812 10812 0 0.00 0 113 1 0 0
v_reserve_write_off 21144 21144 0 0.00 0 8345 8 0 0
x_lock_depdocs_on_canc_invoice 12228 12228 0 0.00 0 19338 108 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 10662 342 0 0
x_make_qstorno_1000_1200 5844 5844 0 0.00 1 42565 1277 0 0
x_make_qstorno_1200_2000 5880 5880 0 0.00 2 95227 3221 0 0
x_make_qstorno_2100_3300 498697 498697 0 0.00 0 49920 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 16:38:52 to 23-08-2021 16:38:56.

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:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:55 23770 8192000000 10373564640 10688925696 1425046304 14.817 100 92 184 1 1460781952 5681280 30054368
14:10:24 10018 8192000000 10414407920 10739187712 1470189664 15.215 100 94 188 0 1513111968 12575344 30346960
14:11:46 10574 8192000000 10462154400 10799206400 1511262736 15.574 100 82 164 0 1546742512 9304304 26175472
14:13:08 10719 8192000000 10547250368 10903506944 1603471536 16.369 100 73 145 3 1635707008 9955584 22279888
14:14:22 7178 8192000000 10641173808 11014025216 1703666336 17.216 100 77 154 2 1738471264 12095136 22709792
14:15:34 7714 8192000000 10701324272 11087089664 1771302816 17.778 100 79 159 1 1806456640 12665024 22488800
14:16:43 4829 8192000000 10770690032 11151110144 1824009760 18.210 100 93 186 1 1864737616 14709936 26017920
14:17:50 1544 8192000000 10803823616 11189030912 1859143104 18.496 100 89 178 1 1897452736 13247648 25061984
14:18:53 19362 8192000000 10812342912 11208826880 1877148144 18.642 100 72 144 0 1910353328 12980496 20224688
14:20:13 4674 8192000000 10789301760 11210686464 1877956304 18.649 100 44 89 0 1899202960 8876272 12370384
14:21:21 5628 8192000000 10798890432 11217051648 1882975552 18.689 100 30 59 1 1899425568 7441616 9008400
14:22:33 5894 8192000000 10795125936 11227455488 1891480784 18.758 100 29 56 2 1906134000 6415408 8237808
14:23:41 59720 8192000000 10846219600 11255136256 1908350432 18.893 100 55 109 4 1929658512 5214384 16093696
14:25:41 9848 8192000000 10821747264 11252928512 1903091168 18.851 100 31 62 0 1918475952 6527232 8857552
14:26:51 10476 8192000000 10810896000 11249455104 1903106368 18.851 100 21 42 0 1915280640 5499056 6675216
14:28:04 9199 8192000000 10728502128 11163189248 1790747488 17.938 97 17 34 0 1802007632 6039472 5220672
14:29:16 30460 8192000000 10713590864 11141218304 1737799088 17.500 94 24 49 2 1750625232 5840640 6985504
14:30:52 7742 8192000000 10707280032 11127111680 1784779120 17.889 99 24 48 0 1798431280 6098304 7553856
14:32:00 8216 8192000000 10694002752 11110502400 1757079232 17.660 97 30 60 1 1771651248 5792112 8779904
14:33:13 6464 8192000000 10727020240 11157852160 1808315600 18.082 99 20 39 1 1823827824 10333824 5178400
14:34:21 21024 8192000000 10795324720 11186499584 1844717504 18.379 100 60 122 1 1868437568 5580992 18139072
14:35:42 6363 8192000000 10770104848 11198464000 1856472544 18.475 100 24 49 3 1868760240 5655344 6632352
14:36:51 7592 8192000000 10784854480 11215659008 1873612384 18.613 100 24 48 0 1890265136 9638048 7014704
14:38:01 39905 8192000000 10835858528 11262070784 1908620512 18.896 100 28 55 0 1922785904 5914880 8250512
14:39:48 31172 8192000000 10865774528 11293745152 1938757728 19.137 100 41 80 1 1962542320 11844656 11939936
14:41:22 90110 8192000000 10875086768 11319877632 1952235936 19.244 100 45 90 2 1979950656 13668288 14046432
14:44:32 16908 8192000000 10908254176 11336224768 1964882640 19.345 100 36 72 1 1992718480 16121840 11714000
14:45:59 2524 8192000000 10950776448 11364524032 1989095488 19.537 100 86 172 5 2037107248 21619504 26392256
14:47:04 5244 8192000000 10947561216 11378954240 1993851136 19.574 100 59 116 2 2036456976 25521344 17084496
14:48:15 5179 8192000000 10952038304 11371339776 1976151968 19.434 99 83 165 1 2028229616 28010912 24066736
14:49:26 9995 8192000000 10938572496 11376615424 1990389296 19.547 100 52 103 0 2030050432 23242320 16418816
14:50:40 5472 8192000000 10956092320 11378638848 1973625696 19.414 99 72 144 2 2026038736 29943776 22469264
14:51:50 3125 8192000000 10979390864 11402784768 2008486416 19.690 100 78 156 1 2062724736 30269648 23968672
14:53:01 10030 8192000000 10969693456 11403137024 1994220336 19.577 99 66 129 2 2046286624 31578944 20487344
14:54:13 2684 8192000000 10949281328 11367649280 1975870544 19.432 100 79 156 2 2034480976 33106256 25504176
14:55:21 3616 8192000000 10932681552 11346731008 1954942080 19.266 100 66 129 0 2008176752 34045600 19189072
14:56:29 3997 8192000000 10943372976 11372544000 1973563264 19.414 100 59 118 3 2032210240 38759456 19887520
14:57:42 1726 8192000000 10972068768 11385499648 1988268192 19.530 100 92 183 3 2060044816 43516144 28260480
14:58:49 10057 8192000000 10954886864 11393490944 1971043376 19.394 99 43 86 0 2026286336 40268912 14974048
15:00:04 13698 8192000000 10925009488 11366842368 1957879168 19.289 100 43 87 1 2014428896 41555920 14993808
15:01:23 11368 8192000000 10915803648 11353559040 1949626480 19.224 100 34 68 1 1995050992 34291584 11132928
15:02:38 9554 8192000000 10932953520 11374477312 1943246960 19.173 99 34 68 1 1997658256 43197568 11213728
15:03:53 29154 8192000000 10941958768 11386515456 1935321872 19.109 98 33 66 1 1997365584 50949584 11094128
15:05:54 48568 8192000000 10950316976 11393466368 1966385136 19.357 100 40 80 1 2028434256 49165152 12883968
15:07:53 28130 8192000000 10923266224 11393523712 1953252784 19.252 100 20 40 2 2013238464 53065888 6919792
15:09:22 89732 8192000000 10890026720 11349647360 1914899072 18.946 98 11 22 0 1952657696 34377392 3381232
15:11:53 86616 8192000000 10897648480 11369791488 1955258432 19.268 99 20 40 1 1975464976 15018704 5187840
15:14:26 133504 8192000000 10898531904 11368591360 1920008080 18.987 97 13 26 0 1941540512 17942320 3590112
15:17:46 76831 8192000000 10922589840 11372535808 1950151616 19.228 99 25 52 0 1972766000 15755280 6859104
15:20:10 36898 8192000000 10901472608 11348008960 1933521664 19.095 98 43 87 1 1958141968 12522832 12097472
15:21:53 93013 8192000000 10933369600 11386044416 1969450528 19.381 100 26 52 2 1988386368 12112256 6823584
15:24:28 25885 8192000000 10946866144 11390914560 1975638240 19.430 99 41 82 1 1996477824 9744240 11095344
15:26:04 20091 8192000000 10911008480 11382214656 1970915408 19.393 99 18 36 1 1985588384 9730592 4942384
15:27:31 17727 8192000000 10920793936 11383263232 1982054128 19.481 100 18 36 0 1997849728 10162512 5633088
15:28:50 25424 8192000000 10939937488 11376832512 1961169584 19.315 99 35 69 2 1982698736 11124464 10404688
15:30:18 78123 8192000000 10859904336 11331731456 1905183168 18.868 96 18 36 0 1922865968 12682912 4999888
15:32:37 31688 8192000000 10880012352 11358601216 1932884784 19.090 98 6 11 1 1940533056 6328160 1320112
15:34:16 54317 8192000000 10862490832 11342053376 1919049968 18.979 98 14 28 0 1931103296 8239952 3813376
15:36:11 73585 8192000000 10873949024 11349995520 1933332272 19.094 99 24 49 0 1950742800 10165504 7245024
15:38:48 52684 8192000000 10861977936 11355226112 1935048224 19.107 99 6 12 0 1945854304 9415360 1390720
15:40:42 59305 8192000000 10865863600 11337572352 1915854384 18.954 98 18 36 0 1932775680 12142240 4779056
15:43:04 39326 8192000000 10874724400 11326980096 1871846656 18.599 96 37 73 1 1892358272 10146816 10364800
15:44:44 20831 8192000000 10894068720 11349602304 1944838032 19.185 100 51 101 2 1971071600 12213696 14019872
15:46:08 49878 8192000000 10891255664 11353235456 1898093984 18.811 97 18 36 1 1919566176 16526544 4945648
15:48:01 53621 8192000000 10879213120 11345735680 1895050320 18.786 97 20 40 1 1914758528 13951536 5756672
15:49:58 31601 8192000000 10871603072 11342802944 1873609600 18.613 96 24 48 2 1895127968 14137536 7380832
15:51:32 72842 8192000000 10883693392 11351912448 1906475248 18.878 98 13 26 0 1923493648 13351840 3666560
15:53:46 63245 8192000000 10896636256 11362844672 1941489376 19.159 99 16 32 1 1959294384 12919920 4885088
15:56:34 39759 8192000000 10885310992 11349393408 1930042864 19.067 98 34 67 3 1947949728 8598416 9308448
15:58:20 32145 8192000000 10900198176 11373142016 1963622080 19.335 100 18 36 1 1979095024 10613504 4859440
15:59:55 23060 8192000000 10887464672 11364864000 1962964192 19.330 100 38 74 3 1983593536 10053504 10575840
16:02:34 58494 8192000000 10884889376 11363008512 1934979120 19.107 99 33 66 0 1955023072 10126464 9917488
16:04:37 46176 8192000000 10837383424 11326922752 1908597952 18.895 99 8 16 1 1924225584 13377376 2250256
16:06:33 93409 8192000000 10911582304 11383672832 1961082096 19.315 100 9 17 0 1981771072 18571600 2117376
16:09:07 35347 8192000000 10887297840 11361935360 1942133904 19.164 99 22 44 3 1958693248 10472480 6086864
16:10:42 37389 8192000000 10911440272 11385356288 1969526592 19.382 100 20 40 0 1987230128 12443744 5259792
16:12:20 80058 8192000000 10915738800 11399139328 1971962064 19.401 99 23 46 0 1995673008 17247312 6463632
16:14:45 33698 8192000000 10932265456 11381473280 1938513296 19.135 97 48 94 0 1959727408 7772048 13442064
16:16:23 32966 8192000000 10924243088 11384430592 1962293040 19.324 99 39 76 1 1983278080 11185664 9799376
16:18:03 115621 8192000000 10896165200 11357630464 1815514736 18.141 92 22 44 1 1831081152 9677424 5888992
16:20:58 48487 8192000000 10900732928 11347542016 1882673712 18.687 96 29 58 0 1902727600 12220016 7833872
16:22:50 82048 8192000000 10875727232 11345989632 1894735456 18.784 97 24 48 0 1913364256 11863888 6764912
16:25:13 42446 8192000000 10873247600 11335512064 1912053456 18.923 98 27 54 2 1930896224 10530320 8312448
16:27:02 71609 8192000000 10858914128 11334471680 1883679392 18.695 97 20 40 1 1902956848 13128416 6149040
16:29:35 66106 8192000000 10862159648 11336364032 1925870032 19.034 100 16 32 1 1947161312 16726464 4564816
16:32:07 85888 8192000000 10860705552 11345424384 1882675408 18.687 97 15 30 0 1900789680 14324880 3789392
16:34:33 38283 8192000000 10882802416 11349659648 1942044608 19.163 100 35 69 1 1960646992 8267344 10335040
16:36:12 47598 8192000000 10871484720 11340091392 1914731456 18.945 99 22 44 0 1930388816 9585312 6072048

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-08-2021 16:38:58 to 23-08-2021 16:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 23-08-2021 16:39:14 to 23-08-2021 16:39:16.

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          248248
MON$OLDEST_ACTIVE               251147
MON$OLDEST_SNAPSHOT             251147
MON$NEXT_TRANSACTION            251147
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                       2069920
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:330: 
:331: mscs-test-161.budzdorov.ru    Mon Aug 23 16:37:57 2021
:332:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:333: 
:334: 
:335: mscs-test-161.budzdorov.ru    Mon Aug 23 16:37:57 2021
:336:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:337: 
:338: 
:339: mscs-test-161.budzdorov.ru    Mon Aug 23 16:37:57 2021
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
--- end of diff output ---

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

23.08.21 16:39:17. 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-08-23 16:39:19 No severe PSQL-related problems occured
23.08.21 16:39:21. End of report.