Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.06.2022 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16405296 kB
MemAvailable 31327328 kB
Buffers 0 kB
Cached 15292664 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-03 01:38:14
DTS_END                         2022-06-03 01:38:14.2650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42576
TRN_ID                          233544
ATT_ID                          617
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: 8944.66 21586 1073359
customer order: creation 169.03 357 20283
customer order: refuse 14.05 409 1686
order to supplier: creation 44.61 1368 5353
order to supplier: removal 3.28 110580 393
invoice (draft): creation 45.00 3634 5400
invoice (draft): removal 50.09 887 6011
invoice accept: apply 41.33 18710 4959
invoice accept: cancel 45.36 888 5443
customer reserve: creation 3783.11 187 453973
customer reserve: removal 4462.17 64 535460
realization accept: apply 80.93 203 9711
realization accept: cancel 76.62 105 9194
payment from customer: creation 58.83 159 7060
payment from customer: removal 6.16 127 739
payment to supplier: creation 48.19 192 5783
payment to supplier: removal 3.54 80 425
service: total inventory turnovers 4.78 1421 574
service: total monetary turnovers 6.73 47 807
service: aggregate perf. data 0.84 19012 101
service: refresh index statistics 0.03 273285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-06-2022 01:39:10 to 03-06-2022 01:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2986
TEST_TIME 2 2637
TEST_TIME 3 2877
TEST_TIME 4 5052
TEST_TIME 5 5625
TEST_TIME 6 5608
TEST_TIME 7 6029
TEST_TIME 8 5867
TEST_TIME 9 6030
TEST_TIME 10 6345
TEST_TIME 11 6507
TEST_TIME 12 6456
TEST_TIME 13 6535
TEST_TIME 14 6814
TEST_TIME 15 6968
TEST_TIME 16 7958
TEST_TIME 17 8430
TEST_TIME 18 8748
TEST_TIME 19 8754
TEST_TIME 20 8688
TEST_TIME 21 8725
TEST_TIME 22 8639
TEST_TIME 23 8864
TEST_TIME 24 9009
TEST_TIME 25 9069
TEST_TIME 26 9293
TEST_TIME 27 9501
TEST_TIME 28 10290
TEST_TIME 29 10563
TEST_TIME 30 10500
TEST_TIME 31 10748
TEST_TIME 32 10505
TEST_TIME 33 10677
TEST_TIME 34 10470
TEST_TIME 35 10472
TEST_TIME 36 10275
TEST_TIME 37 10324
TEST_TIME 38 10244
TEST_TIME 39 10074
TEST_TIME 40 9902
TEST_TIME 41 9729
TEST_TIME 42 9691
TEST_TIME 43 9649
TEST_TIME 44 9761
TEST_TIME 45 9706
TEST_TIME 46 9634
TEST_TIME 47 9610
TEST_TIME 48 9503
TEST_TIME 49 9490
TEST_TIME 50 9351
TEST_TIME 51 9273
TEST_TIME 52 9256
TEST_TIME 53 9209
TEST_TIME 54 9196
TEST_TIME 55 9143
TEST_TIME 56 9064
TEST_TIME 57 9005
TEST_TIME 58 9009
TEST_TIME 59 9075
TEST_TIME 60 8961
TEST_TIME 61 9127
TEST_TIME 62 9058
TEST_TIME 63 9090
TEST_TIME 64 9099
TEST_TIME 65 9152
TEST_TIME 66 9199
TEST_TIME 67 9214
TEST_TIME 68 9241
TEST_TIME 69 9273
TEST_TIME 70 9260
TEST_TIME 71 9306
TEST_TIME 72 9325
TEST_TIME 73 9290
TEST_TIME 74 9336
TEST_TIME 75 9412
TEST_TIME 76 9379
TEST_TIME 77 9264
TEST_TIME 78 9276
TEST_TIME 79 9190
TEST_TIME 80 9165
TEST_TIME 81 9154
TEST_TIME 82 9138
TEST_TIME 83 9089
TEST_TIME 84 9095
TEST_TIME 85 9050
TEST_TIME 86 8970
TEST_TIME 87 8966
TEST_TIME 88 9010
TEST_TIME 89 9048
TEST_TIME 90 9015
TEST_TIME 91 8990
TEST_TIME 92 8952
TEST_TIME 93 9006
TEST_TIME 94 9000
TEST_TIME 95 9062
TEST_TIME 96 9118
TEST_TIME 97 9100
TEST_TIME 98 9089
TEST_TIME 99 9067
TEST_TIME 100 9167
TEST_TIME 101 9167
TEST_TIME 102 9192
TEST_TIME 103 9174
TEST_TIME 104 9221
TEST_TIME 105 9217
TEST_TIME 106 9164
TEST_TIME 107 9110
TEST_TIME 108 9054
TEST_TIME 109 9051
TEST_TIME 110 9031
TEST_TIME 111 9006
TEST_TIME 112 8966
TEST_TIME 113 8955
TEST_TIME 114 8967
TEST_TIME 115 8979
TEST_TIME 116 8968
TEST_TIME 117 8988
TEST_TIME 118 8989
TEST_TIME 119 8972
TEST_TIME 120 8950

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-06-2022 01:39:14 to 03-06-2022 01:39:17.

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 1493259 1493259 0 0.00 0 80332 7 0 0
doc_list_biud 1493258 1493258 0 0.00 0 47507 29 0 0
sp_add_invoice_to_stock 5074 4959 115 2.26 45 200526 18710 0 115
sp_cancel_adding_invoice 5443 5443 0 0.00 4 64634 889 0 0
sp_cancel_client_order 1686 1686 0 0.00 4 12305 410 0 0
sp_cancel_customer_reserve 535460 535460 0 0.00 1 19202 64 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 42022 127 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 3013 80 0 0
sp_cancel_supplier_invoice 6022 6011 11 0.18 2 21877 888 0 11
sp_cancel_supplier_order 393 393 0 0.00 7 944700 110580 0 0
sp_cancel_write_off 9194 9194 0 0.00 1 15020 106 0 0
sp_client_order 20283 20283 0 0.00 3 24898 358 0 0
sp_customer_reserve 453973 453973 0 0.00 0 28060 187 0 0
sp_fill_shopping_cart 31034 31034 0 0.00 1 22147 47 0 0
sp_fill_shopping_cart_clo_res 453967 453967 0 0.00 0 24197 14 0 0
sp_get_clo_for_invoice 4953 4953 0 0.00 0 22369 290 0 0
sp_kill_cost_storno 15801 15801 0 0.00 0 42014 62 0 0
sp_kill_qstorno_ret_qs2qd 1086518 1086518 0 0.00 0 21860 35 0 0
sp_kill_qty_storno 572851 572851 0 0.00 0 47505 76 0 0
sp_lock_selected_doc 20309 20309 0 0.00 0 5162 4 0 0
sp_make_cost_storno 27507 27507 0 0.00 0 80326 63 0 0
sp_multiply_rows_for_pdistr 27507 27507 0 0.00 0 42038 32 0 0
sp_multiply_rows_for_qdistr 20283 20283 0 0.00 0 22885 139 0 0
sp_pay_from_customer 7060 7060 0 0.00 4 47467 160 0 0
sp_pay_to_supplier 5783 5783 0 0.00 5 50789 192 0 0
sp_payment_common 12843 12843 0 0.00 1 49204 74 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 12297 371 0 0
sp_qd_handle_on_invoice_upd_sts 10396 10396 0 0.00 0 47502 364 0 0
sp_qd_handle_on_reserve_upd_sts 9711 9711 0 0.00 0 14507 69 0 0
sp_reserve_write_off 9711 9711 0 0.00 6 52452 203 0 0
sp_supplier_invoice 5400 5400 0 0.00 6 97818 3634 0 0
sp_supplier_order 5353 5353 0 0.00 5 89535 1369 0 0
srv_aggregate_perf_data 101 101 0 0.00 2450 304516 19013 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2607 133978 47954 0 0
srv_find_qd_qs_mism 1026054 1026054 0 0.00 0 11126 6 0 0
srv_make_invnt_saldo 574 574 0 0.00 30 135903 1421 0 0
srv_make_money_saldo 807 807 0 0.00 1 11938 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 163151 384562 273285 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 4959 4959 0 0.00 0 13115 177 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 4008 113 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 7229 21 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 2742 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 772 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 2753 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 642 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 23927 1539 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 3390 14 0 0
t$perf-norm:sp_client_order 20283 20283 0 0.00 0 10190 19 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 6326 15 0 0
t$perf-norm:sp_pay_from_customer 7060 7060 0 0.00 0 11435 13 0 0
t$perf-norm:sp_pay_to_supplier 5783 5783 0 0.00 0 4083 10 0 0
t$perf-norm:sp_reserve_write_off 9711 9711 0 0.00 0 9273 13 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 4274 16 0 0
t$perf-norm:sp_supplier_order 5353 5353 0 0.00 0 5759 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 637 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 179 5 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 3930 34 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 4189 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10136 9906 230 2.26 0 4831 19 0 230
v_all_customers 37120 37120 0 0.00 0 22 1 0 0
v_all_suppliers 29952 29952 0 0.00 0 103 1 0 0
v_all_wares 40566 40566 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 4496 15 0 0
v_cancel_client_order 3372 3372 0 0.00 0 5459 12 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 2158 8 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 0 1345 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3003 9 0 0
v_cancel_supplier_invoice 796 774 22 2.76 0 2823 19 0 22
v_cancel_supplier_order 786 786 0 0.00 0 770 5 0 0
v_cancel_write_off 1964 1964 0 0.00 0 2886 12 0 0
v_max_id_clo_ord 5352 5352 0 0.00 0 596 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 1003 7 0 0
v_max_id_ord_sup 5399 5399 0 0.00 0 6920 5 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 945 5 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 13333 9 0 0
v_min_id_clo_ord 5352 5352 0 0.00 0 13489 19 0 0
v_min_id_clo_res 985 985 0 0.00 0 2471 9 0 0
v_min_id_ord_sup 5399 5399 0 0.00 0 4503 8 0 0
v_min_non_paid_invoice 5783 5783 0 0.00 0 49177 69 0 0
v_min_non_paid_realizn 7060 7060 0 0.00 0 45007 62 0 0
v_our_firm 10010 10010 0 0.00 0 86 1 0 0
v_reserve_write_off 19422 19422 0 0.00 0 5459 7 0 0
x_lock_depdocs_on_canc_invoice 11067 11067 0 0.00 0 12809 127 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 9512 393 0 0
x_make_qstorno_1000_1200 5353 5353 0 0.00 1 89514 1270 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 2 91505 3470 0 0
x_make_qstorno_2100_3300 453972 453972 0 0.00 0 26188 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-06-2022 01:39:18 to 03-06-2022 01:39:22.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:17 5 8192000000 8806218720 8819937280 11560272 0.140 3 3 4 0 11744192 76800 107120
23:09:18 32815 8192000000 9910791088 10223984640 992524448 10.806 100 90 180 0 1023330496 7049840 23756208
23:10:57 31367 8192000000 9956990976 10281574400 1039822528 11.263 100 94 188 1 1072988208 9197680 23968000
23:12:32 13261 8192000000 9987603312 10321104896 1067188864 11.525 100 81 162 0 1097272416 10017504 20066048
23:13:55 8004 8192000000 10046122480 10397286400 1128306416 12.105 100 79 157 0 1157867264 11046512 18514336
23:15:05 7455 8192000000 10090910112 10454593536 1179346528 12.584 100 75 149 0 1208903024 12542512 17013984
23:16:15 5503 8192000000 10132661760 10500177920 1214892544 12.914 100 82 164 1 1246901136 13661952 18346640
23:17:21 5878 8192000000 10156487984 10536415232 1245442256 13.196 100 67 135 2 1272439504 12524032 14473216
23:18:30 3210 8192000000 10197525744 10576973824 1280778624 13.520 100 93 186 1 1315052112 13823920 20449568
23:19:36 2481 8192000000 10211109904 10597568512 1297117008 13.669 100 87 174 1 1330964432 13740016 20107408
23:20:42 2081 8192000000 10227555536 10614063104 1307554352 13.764 100 95 190 0 1344027904 14798688 21674864
23:21:48 4212 8192000000 10205827840 10617311232 1316528864 13.845 100 45 90 1 1336697760 10518656 9650240
23:22:55 6222 8192000000 10208453568 10622836736 1323157792 13.905 100 45 90 0 1341089904 7359664 10572448
23:24:03 9172 8192000000 10228053600 10634440704 1331145712 13.978 100 36 73 1 1350104144 9803632 9154800
23:25:14 49448 8192000000 10244280480 10649862144 1335402752 14.016 100 69 137 2 1358197200 5776048 17018400
23:27:05 10321 8192000000 10237877568 10649866240 1337721136 14.037 100 49 103 3 1355053824 5244864 12087824
23:28:19 6338 8192000000 10226899744 10633445376 1318503584 13.863 99 36 72 3 1333236768 5617376 9115808
23:29:27 9383 8192000000 10192353888 10606116864 1291963792 13.622 99 25 50 0 1304579600 6777824 5837984
23:30:39 24498 8192000000 10167924256 10580365312 1202333968 12.798 92 27 52 1 1214501888 4769840 7398080
23:32:05 4212 8192000000 10174646512 10577932288 1277936176 13.494 100 25 50 1 1290041536 6749680 5355680
23:33:11 5564 8192000000 10126892320 10517024768 1195502928 12.735 95 23 45 1 1206055808 5483872 5069008
23:34:20 47254 8192000000 10157933952 10561986560 1243992704 13.183 98 35 70 1 1258208912 4781104 9435104
23:36:08 10089 8192000000 10178664512 10589802496 1279500912 13.508 99 33 64 1 1292837488 5610048 7726528
23:37:22 9375 8192000000 10193425056 10609885184 1303456880 13.727 100 22 44 0 1314723600 6303056 4963664
23:38:33 133978 8192000000 10239930096 10655752192 1319621024 13.873 99 49 98 1 1339070704 7636512 11813168
23:42:04 30942 8192000000 10268663824 10688741376 1357678480 14.217 100 46 91 2 1387275808 18757424 10839904
23:43:39 12327 8192000000 10286801104 10705055744 1372157280 14.346 100 53 106 0 1399639184 15534432 11947472
23:44:55 20567 8192000000 10309513552 10730217472 1387698048 14.485 100 63 125 1 1421434032 19027408 14708576
23:46:21 11639 8192000000 10322248448 10744111104 1399761424 14.593 100 59 118 0 1431389680 17779280 13848976
23:47:38 6184 8192000000 10324409584 10739335168 1393581904 14.538 100 67 135 0 1432454736 23170720 15702112
23:48:48 2913 8192000000 10343823072 10758901760 1406858784 14.656 100 79 158 0 1454076144 27401776 19815584
23:49:55 5249 8192000000 10335489632 10753732608 1398441200 14.581 100 65 129 1 1442661024 27667216 16552608
23:51:05 5887 8192000000 10330545776 10754359296 1396789040 14.566 100 56 110 0 1441350832 30692768 13869024
23:52:17 5418 8192000000 10349641104 10770378752 1403295440 14.624 100 70 141 2 1456892240 35553344 18043456
23:53:37 3104 8192000000 10352691264 10765594624 1389800544 14.504 99 82 162 0 1453230080 42699056 20730480
23:54:46 4481 8192000000 10359091872 10780700672 1399446864 14.590 100 67 134 1 1465668016 48968032 17253120
23:55:56 9343 8192000000 10337403552 10776055808 1405475456 14.644 100 53 106 0 1456577280 37394224 13707600
23:57:11 4676 8192000000 10365348064 10796281856 1420164768 14.774 100 61 118 0 1467917440 33407408 14345264
23:58:24 2606 8192000000 10341710896 10748456960 1383800960 14.451 98 89 176 2 1441768800 35947328 22020512
23:59:33 14622 8192000000 10351385056 10780221440 1407420992 14.661 100 38 76 0 1456126720 38538480 10167248
00:00:57 18994 8192000000 10348950480 10778066944 1400355648 14.598 100 41 82 1 1455881264 44407104 11118512
00:02:22 14160 8192000000 10339728032 10777124864 1386291920 14.473 99 35 70 3 1433572496 37957120 9323456
00:03:45 12286 8192000000 10329086976 10758393856 1381084032 14.426 100 36 71 1 1437301248 46294656 9922560
00:05:02 9267 8192000000 10333338224 10766471168 1377894816 14.398 100 43 83 0 1439102368 49390512 11817040
00:06:13 13815 8192000000 10335829152 10778447872 1381666288 14.431 100 29 58 3 1440579328 50514112 8398928
00:07:29 32055 8192000000 10310587616 10766094336 1375543248 14.377 100 11 22 1 1416366560 37619088 3204224
00:09:21 31615 8192000000 10289854816 10722750464 1322238608 13.897 96 26 51 1 1354697056 25722640 6735808
00:11:14 32830 8192000000 10286785040 10742517760 1381793744 14.433 100 11 22 0 1400592272 16355952 2442576
00:12:48 55421 8192000000 10283027408 10740563968 1369601280 14.323 100 15 31 0 1395706592 22435936 3669376
00:15:08 90326 8192000000 10269750720 10715099136 1327098992 13.941 96 40 81 1 1349527712 12058992 10369728
00:17:39 76909 8192000000 10299479136 10738069504 1368447232 14.313 99 19 38 0 1382957008 10267024 4242752
00:20:14 21939 8192000000 10293457632 10747277312 1376882656 14.389 99 20 41 2 1396851680 15141648 4827376
00:21:36 52786 8192000000 10285999104 10758864896 1380775712 14.423 100 19 36 0 1399722416 14528400 4418304
00:23:35 34504 8192000000 10278607264 10732544000 1381199024 14.427 100 35 69 0 1403359328 13421952 8738352
00:25:21 29679 8192000000 10267106048 10722123776 1360988144 14.246 98 6 12 2 1374603984 12182944 1432896
00:26:51 59978 8192000000 10279217120 10743255040 1382420448 14.438 100 13 26 1 1395974864 10770352 2784064
00:29:08 122009 8192000000 10281096016 10748174336 1385441584 14.465 100 34 66 2 1401540400 7688160 8410656
00:32:10 49006 8192000000 10282739360 10740555776 1373270576 14.356 99 41 82 4 1392187440 9089952 9826912
00:33:59 39501 8192000000 10298489840 10756587520 1400484720 14.599 100 19 38 0 1417061536 12017856 4558960
00:35:39 84451 8192000000 10278623456 10741010432 1313771696 13.820 94 17 34 0 1337648688 19683648 4193344
00:38:03 77798 8192000000 10285088816 10747092992 1362584240 14.261 99 11 22 1 1384598480 19398432 2615808
00:40:21 63974 8192000000 10278723312 10741620736 1367450720 14.304 99 17 33 0 1379173344 7900896 3821728
00:42:29 78165 8192000000 10303886928 10777133056 1386875040 14.478 99 24 48 1 1408752688 16159248 5718400
00:44:47 59951 8192000000 10315339344 10778021888 1390466176 14.510 99 14 28 0 1406799072 13311952 3020944
00:46:50 92589 8192000000 10291369920 10743832576 1329076016 13.959 95 17 34 0 1347839728 15364720 3398992
00:49:26 33214 8192000000 10270120912 10718441472 1331310928 13.979 97 27 52 0 1349742864 12512320 5919616
00:51:04 34811 8192000000 10286485440 10722725888 1338183792 14.041 98 31 62 0 1355121104 10245120 6692192
00:52:42 78928 8192000000 10267283088 10726178816 1345127440 14.104 99 11 22 1 1365331312 17561248 2642624
00:55:37 97686 8192000000 10266896880 10722279424 1318236416 13.861 96 32 64 1 1334808224 9573328 6998480
00:58:16 36642 8192000000 10270199872 10725081088 1356687536 14.208 99 34 68 1 1377049520 12706592 7655392
00:59:57 57284 8192000000 10262631264 10725453824 1368002784 14.309 100 12 24 0 1385311808 14743504 2565520
01:01:55 66689 8192000000 10237999360 10690015232 1321208688 13.888 97 11 22 0 1336654720 12616000 2830032
01:04:47 98635 8192000000 10268795280 10732441600 1353440688 14.178 99 35 69 2 1381527216 19562752 8523776
01:08:14 107591 8192000000 10271085520 10733301760 1354604688 14.189 99 5 10 0 1363050448 7753456 692304
01:11:02 14111 8192000000 10278753328 10721521664 1366620832 14.297 100 38 76 0 1390867408 15409744 8836832
01:12:16 56235 8192000000 10284845568 10731421696 1341613968 14.072 97 17 34 0 1356585648 11188928 3782752
01:14:13 76783 8192000000 10273557360 10731638784 1336623792 14.027 97 12 23 1 1356673760 17666528 2383440
01:16:30 16609 8192000000 10307231088 10751983616 1392518496 14.528 100 40 80 0 1410839616 10007328 8313792
01:17:50 78963 8192000000 10301155744 10769424384 1368680704 14.315 98 35 70 1 1400252752 23846544 7725504
01:20:13 107970 8192000000 10306994704 10758983680 1356612960 14.207 97 16 32 0 1372702240 12666272 3423008
01:23:02 130083 8192000000 10293005744 10753101824 1334494368 14.008 96 17 33 1 1357662768 18976208 4192192
01:26:35 51504 8192000000 10271156656 10733088768 1372899344 14.353 99 19 38 1 1387916080 10205168 4811568
01:28:27 46506 8192000000 10281319008 10750623744 1380982432 14.425 100 5 10 0 1393992096 11976224 1033440
01:30:13 71688 8192000000 10284234512 10752520192 1378029024 14.399 99 16 31 0 1392581216 11036448 3515744
01:33:46 77714 8192000000 10285556256 10725371904 1312366800 13.808 95 14 28 0 1323103984 7646304 3090880
01:36:13 105480 8192000000 10241605632 10683215872 1224073760 12.999 90 22 43 0 1243394720 14452464 4868496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-06-2022 01:39:24 to 03-06-2022 01:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 03-06-2022 01:39:40 to 03-06-2022 01:39:42.

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          231686
MON$OLDEST_ACTIVE               233993
MON$OLDEST_SNAPSHOT             233993
MON$NEXT_TRANSACTION            233993
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                       2030240
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33601 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33601 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33601 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Fri Jun  3 01:38:14 2022
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Fri Jun  3 01:38:16 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Jun  3 01:38:17 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Jun  3 01:38:19 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Jun  3 01:38:20 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

03.06.22 01:39:43. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-03 01:39:45 No severe PSQL-related problems occured
03.06.22 01:39:47. End of report.