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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15996416 kB
MemAvailable 31218720 kB
Buffers 0 kB
Cached 15684368 kB
SwapCached 2468 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-20 16:38:28
DTS_END                         2022-06-20 16:38:28.5570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59670
TRN_ID                          251912
ATT_ID                          699
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: 9648.46 17700 1157815
customer order: creation 190.64 330 22877
customer order: refuse 16.20 359 1944
order to supplier: creation 49.64 1292 5957
order to supplier: removal 3.60 104510 432
invoice (draft): creation 50.23 3153 6027
invoice (draft): removal 54.23 759 6507
invoice accept: apply 45.07 19559 5408
invoice accept: cancel 48.63 914 5836
customer reserve: creation 4109.07 196 493088
customer reserve: removal 4762.15 63 571458
realization accept: apply 91.67 178 11000
realization accept: cancel 85.95 106 10314
payment from customer: creation 64.78 109 7774
payment from customer: removal 6.14 74 737
payment to supplier: creation 53.11 134 6373
payment to supplier: removal 3.73 105 447
service: total inventory turnovers 5.51 1132 661
service: total monetary turnovers 7.26 38 871
service: aggregate perf. data 0.83 20806 100
service: refresh index statistics 0.03 200177 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-06-2022 16:39:03 to 20-06-2022 16:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11213
TEST_TIME 2 7678
TEST_TIME 3 8004
TEST_TIME 4 6917
TEST_TIME 5 6503
TEST_TIME 6 6644
TEST_TIME 7 7086
TEST_TIME 8 7091
TEST_TIME 9 7355
TEST_TIME 10 7433
TEST_TIME 11 7569
TEST_TIME 12 7539
TEST_TIME 13 7560
TEST_TIME 14 7525
TEST_TIME 15 7619
TEST_TIME 16 7626
TEST_TIME 17 7820
TEST_TIME 18 8274
TEST_TIME 19 8388
TEST_TIME 20 8723
TEST_TIME 21 8748
TEST_TIME 22 9125
TEST_TIME 23 9069
TEST_TIME 24 9160
TEST_TIME 25 9090
TEST_TIME 26 9658
TEST_TIME 27 9666
TEST_TIME 28 9654
TEST_TIME 29 10174
TEST_TIME 30 10703
TEST_TIME 31 11272
TEST_TIME 32 11164
TEST_TIME 33 10998
TEST_TIME 34 10815
TEST_TIME 35 10788
TEST_TIME 36 10940
TEST_TIME 37 10872
TEST_TIME 38 10802
TEST_TIME 39 10708
TEST_TIME 40 10524
TEST_TIME 41 10343
TEST_TIME 42 10371
TEST_TIME 43 10326
TEST_TIME 44 10305
TEST_TIME 45 10200
TEST_TIME 46 10143
TEST_TIME 47 10062
TEST_TIME 48 10108
TEST_TIME 49 10195
TEST_TIME 50 10205
TEST_TIME 51 10098
TEST_TIME 52 10081
TEST_TIME 53 10042
TEST_TIME 54 9927
TEST_TIME 55 9793
TEST_TIME 56 9957
TEST_TIME 57 9855
TEST_TIME 58 9866
TEST_TIME 59 9785
TEST_TIME 60 9751
TEST_TIME 61 9667
TEST_TIME 62 9678
TEST_TIME 63 9674
TEST_TIME 64 9633
TEST_TIME 65 9784
TEST_TIME 66 9837
TEST_TIME 67 9805
TEST_TIME 68 9802
TEST_TIME 69 9781
TEST_TIME 70 9888
TEST_TIME 71 9893
TEST_TIME 72 9895
TEST_TIME 73 9886
TEST_TIME 74 9967
TEST_TIME 75 9950
TEST_TIME 76 9836
TEST_TIME 77 9825
TEST_TIME 78 9751
TEST_TIME 79 9834
TEST_TIME 80 9782
TEST_TIME 81 9706
TEST_TIME 82 9631
TEST_TIME 83 9589
TEST_TIME 84 9550
TEST_TIME 85 9651
TEST_TIME 86 9706
TEST_TIME 87 9671
TEST_TIME 88 9726
TEST_TIME 89 9695
TEST_TIME 90 9700
TEST_TIME 91 9713
TEST_TIME 92 9714
TEST_TIME 93 9769
TEST_TIME 94 9790
TEST_TIME 95 9773
TEST_TIME 96 9838
TEST_TIME 97 9800
TEST_TIME 98 9723
TEST_TIME 99 9700
TEST_TIME 100 9702
TEST_TIME 101 9660
TEST_TIME 102 9651
TEST_TIME 103 9634
TEST_TIME 104 9597
TEST_TIME 105 9551
TEST_TIME 106 9522
TEST_TIME 107 9547
TEST_TIME 108 9571
TEST_TIME 109 9556
TEST_TIME 110 9583
TEST_TIME 111 9573
TEST_TIME 112 9552
TEST_TIME 113 9565
TEST_TIME 114 9649
TEST_TIME 115 9626
TEST_TIME 116 9648
TEST_TIME 117 9651
TEST_TIME 118 9656
TEST_TIME 119 9708
TEST_TIME 120 9652

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-06-2022 16:39:07 to 20-06-2022 16:39:10.

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 1615373 1615373 0 0.00 0 22731 6 0 0
doc_list_biud 1615373 1615373 0 0.00 0 73620 28 0 0
sp_add_invoice_to_stock 5505 5408 97 1.76 26 188048 19560 0 97
sp_cancel_adding_invoice 5836 5836 0 0.00 5 81082 914 0 0
sp_cancel_client_order 1944 1944 0 0.00 3 9396 360 0 0
sp_cancel_customer_reserve 571458 571458 0 0.00 1 25255 63 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 2 3590 75 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 5048 105 0 0
sp_cancel_supplier_invoice 6510 6507 3 0.04 2 73628 760 0 3
sp_cancel_supplier_order 432 432 0 0.00 9 1106851 104511 0 0
sp_cancel_write_off 10314 10314 0 0.00 1 11984 106 0 0
sp_client_order 22877 22877 0 0.00 3 11849 331 0 0
sp_customer_reserve 493088 493088 0 0.00 0 127343 196 0 0
sp_fill_shopping_cart 34847 34847 0 0.00 1 10857 34 0 0
sp_fill_shopping_cart_clo_res 493080 493080 0 0.00 0 17051 12 0 0
sp_get_clo_for_invoice 5391 5391 0 0.00 0 19136 285 0 0
sp_kill_cost_storno 17334 17334 0 0.00 0 22723 60 0 0
sp_kill_qstorno_ret_qs2qd 1160169 1160169 0 0.00 0 73616 34 0 0
sp_kill_qty_storno 612877 612877 0 0.00 0 73619 73 0 0
sp_lock_selected_doc 22716 22716 0 0.00 0 8091 5 0 0
sp_make_cost_storno 30533 30533 0 0.00 0 12608 51 0 0
sp_multiply_rows_for_pdistr 30533 30533 0 0.00 0 5903 27 0 0
sp_multiply_rows_for_qdistr 22877 22877 0 0.00 0 11736 148 0 0
sp_pay_from_customer 7774 7774 0 0.00 4 9895 110 0 0
sp_pay_to_supplier 6373 6373 0 0.00 5 21272 134 0 0
sp_payment_common 14147 14147 0 0.00 1 21259 21 0 0
sp_qd_handle_on_cancel_clo 1944 1944 0 0.00 0 8021 316 0 0
sp_qd_handle_on_invoice_upd_sts 11222 11222 0 0.00 0 17655 370 0 0
sp_qd_handle_on_reserve_upd_sts 11000 11000 0 0.00 0 24279 66 0 0
sp_reserve_write_off 11000 11000 0 0.00 6 24616 178 0 0
sp_supplier_invoice 6027 6027 0 0.00 5 78487 3153 0 0
sp_supplier_order 5957 5957 0 0.00 5 24021 1292 0 0
srv_aggregate_perf_data 100 100 0 0.00 3306 341409 20806 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1473 147634 45784 0 0
srv_find_qd_qs_mism 1106470 1106470 0 0.00 0 16792 5 0 0
srv_make_invnt_saldo 661 661 0 0.00 36 96187 1132 0 0
srv_make_money_saldo 871 871 0 0.00 1 7588 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 129376 240139 200178 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5408 5408 0 0.00 0 14729 181 0 0
t$perf-norm:sp_cancel_adding_invoice 476 476 0 0.00 0 4121 88 0 0
t$perf-norm:sp_cancel_client_order 1944 1944 0 0.00 0 2754 9 0 0
t$perf-norm:sp_cancel_customer_reserve 768 768 0 0.00 0 1347 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 2647 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 3414 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 452 452 0 0.00 0 1139 16 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 29693 1625 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 581 5 0 0
t$perf-norm:sp_client_order 22877 22877 0 0.00 0 8313 17 0 0
t$perf-norm:sp_customer_reserve 1113 1113 0 0.00 0 560 5 0 0
t$perf-norm:sp_pay_from_customer 7774 7774 0 0.00 0 5696 10 0 0
t$perf-norm:sp_pay_to_supplier 6373 6373 0 0.00 0 5864 13 0 0
t$perf-norm:sp_reserve_write_off 11000 11000 0 0.00 0 4325 10 0 0
t$perf-norm:sp_supplier_invoice 6027 6027 0 0.00 0 5721 18 0 0
t$perf-norm:sp_supplier_order 5957 5957 0 0.00 0 6520 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 344 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 661 661 0 0.00 0 1499 25 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 1364 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10960 10766 194 1.77 0 4282 20 0 194
v_all_customers 41626 41626 0 0.00 0 17 1 0 0
v_all_suppliers 33344 33344 0 0.00 0 19 1 0 0
v_all_wares 45754 45754 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 952 952 0 0.00 0 5518 20 0 0
v_cancel_client_order 3888 3888 0 0.00 0 8120 11 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 2850 8 0 0
v_cancel_customer_reserve 1536 1536 0 0.00 0 3224 7 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 5025 20 0 0
v_cancel_supplier_invoice 910 904 6 0.65 0 1547 16 0 6
v_cancel_supplier_order 864 864 0 0.00 0 2463 15 0 0
v_cancel_write_off 2154 2154 0 0.00 0 5970 17 0 0
v_max_id_clo_ord 5957 5957 0 0.00 0 965 1 0 0
v_max_id_clo_res 1113 1113 0 0.00 0 1594 9 0 0
v_max_id_ord_sup 6013 6013 0 0.00 0 3170 2 0 0
v_max_non_paid_invoice 1671 1671 0 0.00 0 507 3 0 0
v_max_non_paid_realizn 4142 4142 0 0.00 0 2313 6 0 0
v_min_id_clo_ord 5957 5957 0 0.00 0 6967 6 0 0
v_min_id_clo_res 1113 1113 0 0.00 0 914 3 0 0
v_min_id_ord_sup 6013 6013 0 0.00 0 4334 4 0 0
v_min_non_paid_invoice 6373 6373 0 0.00 0 21255 14 0 0
v_min_non_paid_realizn 7774 7774 0 0.00 0 5867 14 0 0
v_our_firm 11392 11392 0 0.00 0 21 1 0 0
v_reserve_write_off 22000 22000 0 0.00 0 7828 8 0 0
x_lock_depdocs_on_canc_invoice 11891 11891 0 0.00 0 22961 119 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 19432 391 0 0
x_make_qstorno_1000_1200 5957 5957 0 0.00 1 24005 1206 0 0
x_make_qstorno_1200_2000 6027 6027 0 0.00 2 78472 3040 0 0
x_make_qstorno_2100_3300 493088 493088 0 0.00 0 127341 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2022 16:39:11 to 20-06-2022 16:39:15.

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:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:32 22979 8192000000 9887000736 10191433728 965487488 10.543 100 87 174 0 994342704 5547920 23307296
14:11:04 16989 8192000000 9928766320 10245185536 1012404096 10.999 100 85 170 0 1042888864 7465328 23019440
14:12:31 19105 8192000000 9967439744 10296500224 1053553264 11.395 100 86 172 0 1083624800 8503264 21568272
14:13:56 12303 8192000000 10014914640 10355015680 1103147616 11.867 100 81 162 1 1132663872 10255920 19260336
14:15:11 9841 8192000000 10079181872 10435194880 1167631216 12.475 100 75 150 0 1196502640 11921104 16950320
14:16:30 5293 8192000000 10134020016 10500571136 1216094496 12.926 100 84 168 0 1249200224 14706048 18399680
14:17:37 2764 8192000000 10159659712 10533408768 1247635024 13.216 100 91 179 2 1281642576 14107696 19899856
14:18:42 2332 8192000000 10190790656 10569711616 1277308400 13.488 100 78 157 1 1306502720 11735264 17459056
14:19:47 7395 8192000000 10210181648 10592768000 1292107808 13.623 100 92 185 2 1326929584 14501376 20320400
14:20:55 5477 8192000000 10196537840 10592280576 1296858928 13.667 100 38 76 1 1314040960 8200880 8981152
14:22:01 12480 8192000000 10185932224 10603343872 1307840112 13.766 100 18 36 2 1317647712 5803728 4003872
14:23:14 48262 8192000000 10205560448 10620391424 1315135888 13.833 100 32 64 0 1328055904 5998608 6921408
14:25:05 6518 8192000000 10210196272 10630524928 1327069440 13.941 100 30 59 1 1340038112 6447168 6521504
14:26:13 11701 8192000000 10211407552 10632200192 1317445696 13.854 99 12 24 0 1325680720 5569344 2665680
14:27:27 23068 8192000000 10228453488 10639618048 1320191392 13.878 99 29 57 2 1332280432 5820688 6268352
14:28:54 20629 8192000000 10196521440 10613362688 1282452960 13.535 97 20 40 0 1292646496 6001616 4191920
14:30:16 3998 8192000000 10187634640 10597117952 1295784976 13.657 100 34 68 0 1310819152 6742704 8291472
14:31:22 7129 8192000000 10132002112 10528063488 1209250832 12.862 95 28 56 2 1221339888 5740176 6348880
14:32:32 13876 8192000000 10151912016 10555486208 1220032096 12.962 96 27 53 1 1231114576 4995680 6086800
14:33:46 34313 8192000000 10159446208 10570534912 1270132160 13.423 100 26 52 0 1286524144 11086016 5305968
14:35:25 7128 8192000000 10183954768 10595717120 1293900832 13.640 100 28 56 1 1306840416 6099952 6839632
14:36:32 9289 8192000000 10203314128 10612801536 1304876304 13.740 100 19 38 1 1314612192 5020912 4714976
14:37:43 61752 8192000000 10219177712 10630287360 1315474336 13.836 100 38 76 3 1328844336 5526352 7843648
14:39:52 3565 8192000000 10264658336 10672926720 1352448672 14.170 100 63 127 2 1375643088 8898848 14295568
14:40:59 3382 8192000000 10286273712 10695692288 1369135824 14.319 100 77 152 3 1398944752 11995296 17813632
14:42:07 18424 8192000000 10284703552 10701545472 1373327808 14.357 100 57 114 3 1397505824 11042304 13135712
14:43:46 6029 8192000000 10304807552 10723622912 1387697008 14.485 100 61 120 2 1421199216 20139136 13363072
14:44:55 10657 8192000000 10314907296 10743095296 1396101856 14.560 100 45 90 2 1429216032 22509744 10604432
14:46:11 6446 8192000000 10312100432 10730110976 1381952368 14.434 99 51 100 1 1412176224 18430832 11793024
14:47:20 36208 8192000000 10318309632 10754572288 1392679520 14.530 99 40 78 1 1423167856 20624704 9863632
14:49:02 3313 8192000000 10309190128 10732257280 1387063584 14.480 100 67 131 1 1423673216 21282800 15326832
14:50:10 13488 8192000000 10311267280 10736979968 1385381792 14.465 99 48 93 1 1421015632 24088592 11545248
14:51:27 3824 8192000000 10311715584 10721759232 1347137168 14.122 96 81 164 3 1392709552 25840800 19731584
14:52:35 3361 8192000000 10336403968 10738601984 1386508288 14.475 100 90 178 5 1437044544 29363712 21172544
14:53:47 7896 8192000000 10324122336 10727424000 1365071792 14.283 99 79 157 0 1416616704 31353552 20191360
14:55:01 1472 8192000000 10316328432 10724413440 1371309376 14.339 100 77 152 0 1426015744 35439872 19266496
14:56:06 3355 8192000000 10303339920 10703720448 1350947232 14.156 100 80 156 0 1409785552 39395440 19442880
14:57:14 2654 8192000000 10308170928 10713165824 1346598096 14.117 99 65 130 0 1398520528 35708624 16213808
14:58:23 7155 8192000000 10315310304 10722082816 1365052992 14.283 100 61 121 2 1416812752 36588752 15171008
14:59:35 4488 8192000000 10333091776 10751524864 1387764080 14.486 100 56 112 2 1440964640 38230800 14969760
15:00:48 10037 8192000000 10334880688 10761195520 1392807808 14.531 100 49 98 1 1446065200 39681760 13575632
15:02:00 2436 8192000000 10326834320 10740736000 1378468192 14.403 98 58 117 0 1433844240 39171968 16204080
15:03:12 6891 8192000000 10350932272 10770923520 1381226560 14.428 99 58 116 2 1443859520 46240816 16392144
15:04:23 15988 8192000000 10349423440 10780217344 1394079056 14.542 100 40 80 1 1460787040 55021712 11686272
15:05:45 14226 8192000000 10340459360 10773671936 1396376048 14.563 100 44 88 3 1445730672 37468432 11886192
15:07:09 23886 8192000000 10342027968 10788139008 1384632528 14.458 100 26 52 0 1447777968 55175248 7970192
15:08:36 31481 8192000000 10300475264 10777407488 1352820960 14.173 99 13 26 1 1401967504 45398144 3748400
15:10:12 66523 8192000000 10290680544 10710429696 1341760064 14.073 98 17 33 3 1355112256 9374400 3977792
15:12:20 29495 8192000000 10305384528 10742292480 1379072096 14.408 100 34 68 1 1398658960 11260336 8326528
15:13:56 64201 8192000000 10299475968 10762883072 1358330944 14.222 98 19 37 1 1383949216 21464928 4153344
15:16:23 100854 8192000000 10291100672 10740060160 1369927952 14.326 99 16 33 0 1387582336 14371984 3282400
15:19:04 99688 8192000000 10293740992 10738229248 1374423728 14.367 99 40 81 0 1394765584 11175808 9166048
15:21:47 147633 8192000000 10307460496 10758074368 1347913952 14.129 97 30 58 2 1369630368 14961328 6755088
15:25:18 49178 8192000000 10307487568 10751569920 1359812512 14.236 97 26 52 0 1377348272 11410784 6124976
15:27:10 49863 8192000000 10310551168 10768244736 1391404640 14.518 100 31 62 2 1415267536 16665968 7196928
15:29:18 88489 8192000000 10264880576 10717622272 1309392512 13.781 94 10 20 0 1322516736 10944352 2179872
15:31:51 66610 8192000000 10279314352 10741587968 1368537424 14.314 99 13 27 0 1379237296 7760352 2939520
15:34:05 86211 8192000000 10248901520 10729123840 1306249136 13.752 95 18 36 0 1330392160 19979456 4163568
15:36:31 50437 8192000000 10253306448 10709123072 1336494896 14.026 98 12 24 0 1353040704 13738144 2807664
15:38:46 51361 8192000000 10256974288 10723205120 1354808224 14.191 99 3 6 0 1365675584 10663232 204128
15:40:39 44970 8192000000 10267002032 10722832384 1340429760 14.061 98 10 20 1 1351040848 7872336 2738752
15:42:30 64608 8192000000 10271431776 10732273664 1346533664 14.116 98 21 42 0 1368498880 16898384 5066832
15:44:35 27178 8192000000 10268099920 10727034880 1360499936 14.242 100 25 51 1 1378771808 12556048 5715824
15:46:03 61820 8192000000 10286434592 10727522304 1325106176 13.923 96 28 56 0 1345023776 13978304 5939296
15:48:46 81118 8192000000 10256523472 10710155264 1276827664 13.484 93 20 40 0 1290547744 9276912 4443168
15:51:07 42924 8192000000 10270497552 10709311488 1285395280 13.562 94 27 54 0 1302065008 10711632 5958096
15:52:51 44427 8192000000 10294738016 10747183104 1362279424 14.258 99 18 36 0 1377395328 11159872 3956032
15:54:36 125298 8192000000 10289143872 10738171904 1339405520 14.052 97 32 63 1 1357815248 11660896 6748832
15:57:48 36743 8192000000 10275178128 10736459776 1360792464 14.244 99 14 27 0 1374960784 11029312 3139008
15:59:25 73559 8192000000 10294898048 10752155648 1386133712 14.471 100 14 28 0 1403011920 13319024 3559184
16:01:39 81534 8192000000 10297405488 10759262208 1384034896 14.453 99 21 42 2 1405062544 15702512 5325136
16:04:01 109354 8192000000 10306502416 10772967424 1399465824 14.590 100 13 26 0 1416466848 14202576 2798448
16:06:50 58349 8192000000 10301441744 10756030464 1369815776 14.325 98 17 34 0 1383030000 9460128 3754096
16:08:51 64239 8192000000 10312014080 10765312000 1400360592 14.598 100 11 22 0 1410165184 7907936 1896656
16:10:58 37465 8192000000 10317627712 10772217856 1394046784 14.542 99 27 54 0 1418887408 18997632 5842992
16:12:37 71216 8192000000 10318561824 10764943360 1368138160 14.310 97 21 40 1 1381197456 9198736 3860560
16:14:48 65012 8192000000 10288150704 10732818432 1310835392 13.794 94 12 24 1 1321802320 8337328 2629600
16:16:54 79568 8192000000 10290123728 10735575040 1364718864 14.280 99 42 84 1 1388342768 14469120 9154784
16:19:15 28891 8192000000 10280625584 10741596160 1384449136 14.456 100 16 32 0 1398180416 10459488 3271792
16:20:46 67576 8192000000 10274198768 10737111040 1359389024 14.232 99 10 20 1 1375101808 13086928 2625856
16:22:54 44251 8192000000 10264139984 10719600640 1328612384 13.955 97 17 35 1 1349240640 16723200 3905056
16:24:40 95507 8192000000 10248221280 10708606976 1280049120 13.513 94 17 34 0 1304518704 19732640 4736944
16:27:23 41596 8192000000 10250071168 10694942720 1320644608 13.883 97 21 42 0 1333528400 8288992 4594800
16:29:05 31126 8192000000 10229290944 10672660480 1282188960 13.533 95 11 22 0 1294074256 9376752 2508544
16:30:37 52288 8192000000 10259527984 10709241856 1347825168 14.128 100 27 54 1 1369567280 14959904 6782208
16:32:30 93700 8192000000 10267899040 10714001408 1307765264 13.766 96 13 26 1 1326732080 16089152 2877664
16:35:03 43714 8192000000 10264090240 10713935872 1348396528 14.133 99 22 45 1 1361909104 9018384 4494192
16:36:48 100633 8192000000 10110543968 10535759872 1170131632 12.498 86 11 22 1 1185167680 12832560 2203488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-06-2022 16:39:17 to 20-06-2022 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-06-2022 16:39:33 to 20-06-2022 16:39:35.

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          251304
MON$OLDEST_ACTIVE               252113
MON$OLDEST_SNAPSHOT             252113
MON$NEXT_TRANSACTION            252113
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                       2056864
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Mon Jun 20 16:38:31 2022
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Mon Jun 20 16:38:38 2022
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:177: 
--- end of diff output ---

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

20.06.22 16:39:36. 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-20 16:39:39 No severe PSQL-related problems occured
20.06.22 16:39:40. End of report.