Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2021 19: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 16141460 kB
MemAvailable 30752132 kB
Buffers 0 kB
Cached 15538088 kB
SwapCached 3172 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-09-26 19:37:50
DTS_END                         2021-09-26 19:37:50.0660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43790
TRN_ID                          246610
ATT_ID                          689
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: 8948.63 18758 1073836
customer order: creation 174.98 342 20998
customer order: refuse 15.14 349 1817
order to supplier: creation 47.05 1210 5646
order to supplier: removal 3.27 96752 392
invoice (draft): creation 45.95 3268 5514
invoice (draft): removal 49.51 756 5941
invoice accept: apply 41.85 18512 5022
invoice accept: cancel 45.38 841 5445
customer reserve: creation 3785.75 186 454290
customer reserve: removal 4443.46 56 533215
realization accept: apply 84.21 177 10105
realization accept: cancel 80.28 116 9633
payment from customer: creation 61.29 129 7355
payment from customer: removal 5.58 79 670
payment to supplier: creation 48.63 159 5835
payment to supplier: removal 3.54 80 425
service: total inventory turnovers 4.99 1331 599
service: total monetary turnovers 6.93 26 831
service: aggregate perf. data 0.83 22601 99
service: refresh index statistics 0.03 228193 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2021 19:38:30 to 26-09-2021 19:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2264
TEST_TIME 2 2981
TEST_TIME 3 2496
TEST_TIME 4 4663
TEST_TIME 5 4545
TEST_TIME 6 4477
TEST_TIME 7 4483
TEST_TIME 8 4835
TEST_TIME 9 4808
TEST_TIME 10 5190
TEST_TIME 11 5759
TEST_TIME 12 5880
TEST_TIME 13 6214
TEST_TIME 14 6439
TEST_TIME 15 6604
TEST_TIME 16 6833
TEST_TIME 17 7017
TEST_TIME 18 7505
TEST_TIME 19 8304
TEST_TIME 20 8468
TEST_TIME 21 8550
TEST_TIME 22 8878
TEST_TIME 23 9177
TEST_TIME 24 9580
TEST_TIME 25 9445
TEST_TIME 26 9511
TEST_TIME 27 9571
TEST_TIME 28 9707
TEST_TIME 29 9731
TEST_TIME 30 10049
TEST_TIME 31 10396
TEST_TIME 32 10217
TEST_TIME 33 10040
TEST_TIME 34 9903
TEST_TIME 35 9744
TEST_TIME 36 9609
TEST_TIME 37 9406
TEST_TIME 38 9458
TEST_TIME 39 9391
TEST_TIME 40 9398
TEST_TIME 41 9276
TEST_TIME 42 9190
TEST_TIME 43 9274
TEST_TIME 44 9154
TEST_TIME 45 9093
TEST_TIME 46 8930
TEST_TIME 47 9131
TEST_TIME 48 9027
TEST_TIME 49 9059
TEST_TIME 50 8996
TEST_TIME 51 9017
TEST_TIME 52 8953
TEST_TIME 53 8904
TEST_TIME 54 8897
TEST_TIME 55 8797
TEST_TIME 56 8899
TEST_TIME 57 8834
TEST_TIME 58 8833
TEST_TIME 59 8864
TEST_TIME 60 8895
TEST_TIME 61 8931
TEST_TIME 62 9080
TEST_TIME 63 9012
TEST_TIME 64 9092
TEST_TIME 65 9044
TEST_TIME 66 9074
TEST_TIME 67 9106
TEST_TIME 68 9052
TEST_TIME 69 9060
TEST_TIME 70 9071
TEST_TIME 71 9167
TEST_TIME 72 9099
TEST_TIME 73 8982
TEST_TIME 74 9058
TEST_TIME 75 9050
TEST_TIME 76 8989
TEST_TIME 77 8946
TEST_TIME 78 8978
TEST_TIME 79 8928
TEST_TIME 80 8877
TEST_TIME 81 8790
TEST_TIME 82 8781
TEST_TIME 83 8772
TEST_TIME 84 8787
TEST_TIME 85 8840
TEST_TIME 86 8838
TEST_TIME 87 8808
TEST_TIME 88 8888
TEST_TIME 89 8846
TEST_TIME 90 8900
TEST_TIME 91 8903
TEST_TIME 92 8984
TEST_TIME 93 8963
TEST_TIME 94 9006
TEST_TIME 95 9081
TEST_TIME 96 9064
TEST_TIME 97 9136
TEST_TIME 98 9107
TEST_TIME 99 9070
TEST_TIME 100 9008
TEST_TIME 101 9012
TEST_TIME 102 8961
TEST_TIME 103 8984
TEST_TIME 104 8960
TEST_TIME 105 8928
TEST_TIME 106 8936
TEST_TIME 107 8908
TEST_TIME 108 8858
TEST_TIME 109 8818
TEST_TIME 110 8836
TEST_TIME 111 8849
TEST_TIME 112 8804
TEST_TIME 113 8823
TEST_TIME 114 8898
TEST_TIME 115 8886
TEST_TIME 116 8921
TEST_TIME 117 8887
TEST_TIME 118 8938
TEST_TIME 119 8948
TEST_TIME 120 8952

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2021 19:38:34 to 26-09-2021 19:38:37.

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 1494700 1494700 0 0.00 0 20029 6 0 0
doc_list_biud 1494700 1494700 0 0.00 0 23915 26 0 0
sp_add_invoice_to_stock 5246 5022 224 4.26 38 211969 18513 0 224
sp_cancel_adding_invoice 5445 5445 0 0.00 4 67635 842 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 12477 350 0 0
sp_cancel_customer_reserve 533215 533215 0 0.00 1 23044 56 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 8209 79 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 4903 81 0 0
sp_cancel_supplier_invoice 5955 5941 14 0.23 2 17727 756 0 14
sp_cancel_supplier_order 392 392 0 0.00 6 1084053 96753 0 0
sp_cancel_write_off 9633 9633 0 0.00 1 15460 116 0 0
sp_client_order 20998 20998 0 0.00 3 17210 342 0 0
sp_customer_reserve 454290 454290 0 0.00 0 52125 187 0 0
sp_fill_shopping_cart 32145 32145 0 0.00 1 11545 40 0 0
sp_fill_shopping_cart_clo_res 454280 454280 0 0.00 0 23328 16 0 0
sp_get_clo_for_invoice 5012 5012 0 0.00 0 48495 321 0 0
sp_kill_cost_storno 16173 16173 0 0.00 0 8202 59 0 0
sp_kill_qstorno_ret_qs2qd 1082396 1082396 0 0.00 0 22260 30 0 0
sp_kill_qty_storno 571560 571560 0 0.00 0 23913 67 0 0
sp_lock_selected_doc 20915 20915 0 0.00 0 6871 5 0 0
sp_make_cost_storno 28307 28307 0 0.00 0 20013 52 0 0
sp_multiply_rows_for_pdistr 28307 28307 0 0.00 0 19697 32 0 0
sp_multiply_rows_for_qdistr 20998 20998 0 0.00 0 16817 155 0 0
sp_pay_from_customer 7355 7355 0 0.00 4 20310 129 0 0
sp_pay_to_supplier 5835 5835 0 0.00 4 20048 160 0 0
sp_payment_common 13190 13190 0 0.00 2 16654 42 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 12471 320 0 0
sp_qd_handle_on_invoice_upd_sts 10457 10457 0 0.00 0 23913 356 0 0
sp_qd_handle_on_reserve_upd_sts 10105 10105 0 0.00 0 17522 54 0 0
sp_reserve_write_off 10105 10105 0 0.00 6 17934 178 0 0
sp_supplier_invoice 5514 5514 0 0.00 6 88493 3269 0 0
sp_supplier_order 5646 5646 0 0.00 5 26863 1210 0 0
srv_aggregate_perf_data 99 99 0 0.00 3338 346107 22602 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2528 113017 45507 0 0
srv_find_qd_qs_mism 1025995 1025995 0 0.00 0 15684 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 30 183099 1332 0 0
srv_make_money_saldo 831 831 0 0.00 1 1748 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 72716 305622 228193 0 0
t$perf-abend:sp_add_invoice_to_stock 224 224 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5023 5023 0 0.00 0 17379 174 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 5000 103 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 2113 12 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 3528 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 505 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 4959 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 691 13 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 21913 1728 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 4446 14 0 0
t$perf-norm:sp_client_order 20998 20998 0 0.00 0 12461 19 0 0
t$perf-norm:sp_customer_reserve 1082 1082 0 0.00 0 10768 17 0 0
t$perf-norm:sp_pay_from_customer 7355 7355 0 0.00 0 19901 17 0 0
t$perf-norm:sp_pay_to_supplier 5835 5835 0 0.00 0 5779 11 0 0
t$perf-norm:sp_reserve_write_off 10105 10105 0 0.00 0 14678 16 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 10498 16 0 0
t$perf-norm:sp_supplier_order 5646 5646 0 0.00 0 5087 14 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 714 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 323 6 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 1275 20 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 908 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10472 10024 448 4.27 0 16597 22 0 448
v_all_customers 38400 38400 0 0.00 0 21 1 0 0
v_all_suppliers 30084 30084 0 0.00 0 19 1 0 0
v_all_wares 41996 41996 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2236 15 0 0
v_cancel_client_order 3634 3634 0 0.00 0 5037 6 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1939 10 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2253 6 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2254 11 0 0
v_cancel_supplier_invoice 854 826 28 3.27 0 2261 18 0 28
v_cancel_supplier_order 784 784 0 0.00 0 5037 11 0 0
v_cancel_write_off 2034 2034 0 0.00 0 5893 21 0 0
v_max_id_clo_ord 5646 5646 0 0.00 0 425 1 0 0
v_max_id_clo_res 1082 1082 0 0.00 0 2493 11 0 0
v_max_id_ord_sup 5501 5501 0 0.00 0 3715 3 0 0
v_max_non_paid_invoice 1940 1940 0 0.00 0 1751 4 0 0
v_max_non_paid_realizn 3891 3891 0 0.00 0 1284 4 0 0
v_min_id_clo_ord 5646 5646 0 0.00 0 9308 9 0 0
v_min_id_clo_res 1082 1082 0 0.00 0 8803 29 0 0
v_min_id_ord_sup 5501 5501 0 0.00 0 4513 6 0 0
v_min_non_paid_invoice 5835 5835 0 0.00 0 16644 32 0 0
v_min_non_paid_realizn 7355 7355 0 0.00 0 16493 28 0 0
v_our_firm 10524 10524 0 0.00 0 95 1 0 0
v_reserve_write_off 20210 20210 0 0.00 0 12472 10 0 0
x_lock_depdocs_on_canc_invoice 10973 10973 0 0.00 0 19352 122 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 12384 361 0 0
x_make_qstorno_1000_1200 5646 5646 0 0.00 2 26830 1123 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 2 88420 3132 0 0
x_make_qstorno_2100_3300 454290 454290 0 0.00 0 52099 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2021 19:38:38 to 26-09-2021 19:38:42.

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
17:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:54 48324 8192000000 10383632672 10703724544 1422733024 14.797 100 86 172 1 1456699808 6074528 27892256
17:10:55 5215 8192000000 10449591904 10779471872 1504817920 15.518 100 90 180 1 1541581824 8554896 28209008
17:12:03 25291 8192000000 10511202336 10854924288 1561433232 16.009 100 87 173 1 1598102160 9957840 26711088
17:13:39 8304 8192000000 10600657104 10963378176 1658346256 16.835 100 84 168 0 1695413664 12064048 25003360
17:14:51 6148 8192000000 10663873152 11034910720 1722493456 17.373 100 77 154 1 1757502192 13035712 21973024
17:16:00 4135 8192000000 10727383936 11101757440 1782331360 17.869 100 88 177 0 1822222112 14961472 24929280
17:17:06 4006 8192000000 10756822240 11143327744 1817364352 18.156 100 76 151 0 1851452864 12948880 21139632
17:18:11 1662 8192000000 10802251440 11187232768 1862483232 18.523 100 91 182 1 1902865904 15868720 24513952
17:19:14 3107 8192000000 10816008336 11214303232 1880014672 18.665 100 75 150 0 1913797792 12689168 21093952
17:20:18 5380 8192000000 10800177392 11218071552 1884845952 18.704 100 27 54 1 1897995280 5868448 7280880
17:21:25 40137 8192000000 10802597184 11234103296 1889343120 18.740 100 32 64 1 1904243104 5872752 9027232
17:23:10 5372 8192000000 10814827520 11246137344 1899377936 18.821 100 23 44 2 1911639632 5272496 6989200
17:24:16 7495 8192000000 10820890656 11257016320 1908224912 18.892 100 25 50 0 1921990832 7166960 6598960
17:25:26 50531 8192000000 10833015360 11239305216 1886141120 18.715 98 45 90 0 1904521184 5054256 13325808
17:27:18 11046 8192000000 10781100544 11209793536 1813017648 18.121 96 20 40 2 1824796336 5345696 6432992
17:28:29 12359 8192000000 10753864384 11173539840 1767001904 17.742 95 25 50 1 1780121696 5884512 7235280
17:29:44 41872 8192000000 10696548848 11123023872 1650294384 16.767 90 22 44 1 1661163760 4764000 6105376
17:31:32 6144 8192000000 10724337536 11145568256 1812273008 18.114 100 27 54 1 1827323856 7194112 7856736
17:32:39 9831 8192000000 10745328192 11172024320 1822891056 18.201 99 39 76 2 1840242432 6087584 11263792
17:33:52 56094 8192000000 10801277584 11201323008 1861069536 18.512 100 54 107 3 1882875024 5396976 16408512
17:35:48 8142 8192000000 10786842832 11212222464 1870135408 18.585 100 34 69 2 1885633904 5797344 9701152
17:36:57 11462 8192000000 10810501328 11233927168 1884493536 18.701 100 15 30 1 1893886768 5019776 4373456
17:38:11 6174 8192000000 10861012640 11273945088 1924661360 19.024 100 52 104 1 1947065200 6964560 15439280
17:39:21 10469 8192000000 10876098144 11293741056 1938327216 19.133 100 49 98 0 1964431856 11631792 14472848
17:40:34 76580 8192000000 10875752464 11316871168 1942318544 19.165 100 31 62 2 1968943936 16872224 9753168
17:42:56 37787 8192000000 10896148928 11339784192 1965520432 19.350 100 37 74 0 1990994736 14982544 10491760
17:44:35 20071 8192000000 10930516752 11360768000 1969608432 19.382 99 57 114 0 2007527120 21337968 16580720
17:46:00 10380 8192000000 10927433152 11356880896 1971988784 19.401 99 59 117 3 2015589600 26166064 17434752
17:47:11 10671 8192000000 10943062864 11381219328 1992780496 19.566 100 45 88 1 2032235008 26117648 13336864
17:48:27 2528 8192000000 10964315312 11387944960 1997409616 19.602 100 60 120 0 2046045440 30596224 18039600
17:49:35 5697 8192000000 10946224512 11367006208 1978451696 19.452 100 81 162 3 2032265728 30819312 22994720
17:50:47 4109 8192000000 10936919296 11353923584 1938320944 19.133 98 78 155 0 1995650976 34011168 23318864
17:51:56 3537 8192000000 10919591728 11340288000 1941862928 19.162 99 67 135 0 1998108560 34705040 21540592
17:53:06 3593 8192000000 10918645136 11337953280 1949370480 19.221 100 74 148 0 2010281696 38397104 22514112
17:54:14 4035 8192000000 10941810896 11352625152 1956171712 19.276 100 75 148 1 2020183776 41304848 22707216
17:55:24 3349 8192000000 10965166016 11380760576 1976705360 19.439 100 81 159 3 2050946064 50062064 24178640
17:56:33 3130 8192000000 10912230240 11331813376 1897736752 18.808 97 57 111 1 1965325056 49528800 18059504
17:57:40 9107 8192000000 10906203552 11339214848 1943871504 19.178 100 43 84 1 1990638672 33752688 13014480
17:58:54 9468 8192000000 10899791760 11331960832 1916185152 18.956 98 51 102 2 1970379024 37972896 16220976
18:00:17 12304 8192000000 10906092064 11346747392 1944617040 19.184 100 36 72 1 1994829824 38666000 11546784
18:01:32 24197 8192000000 10929811008 11378151424 1965089568 19.346 100 27 54 2 2012146000 38759536 8296896
18:03:02 27091 8192000000 10942308976 11402211328 1984968512 19.504 100 23 46 1 2033206048 40951680 7285856
18:04:47 37157 8192000000 10926187104 11377319936 1934959040 19.107 98 26 52 1 1988330944 44536272 8835632
18:06:45 32468 8192000000 10910276432 11376578560 1963747920 19.336 100 24 48 0 2006452176 35008224 7696032
18:09:11 47258 8192000000 10882885136 11329871872 1911666992 18.920 97 36 72 1 1928646752 6561008 10418752
18:11:04 64783 8192000000 10876384960 11339468800 1942791152 19.169 99 18 36 0 1956889712 8701216 5397344
18:13:19 99887 8192000000 10938678960 11377127424 1986838400 19.519 100 22 41 1 2007351536 15169776 5343360
18:15:59 59654 8192000000 10932535200 11382140928 1951695536 19.240 98 22 43 0 1975810704 18293360 5821808
18:18:01 59084 8192000000 10917018592 11371917312 1961375360 19.317 99 19 38 1 1982153408 15136064 5641984
18:20:15 38917 8192000000 10899317536 11379916800 1976961344 19.441 100 11 22 0 2002443248 21816912 3664992
18:22:07 45928 8192000000 10909795008 11385446400 1987284240 19.522 100 16 32 1 2009967376 17493040 5190096
18:25:27 83246 8192000000 10911575616 11380375552 1987271792 19.522 100 29 58 0 2004871888 9186256 8413840
18:28:08 49650 8192000000 10899491136 11376021504 1976319568 19.436 99 13 26 0 1991211600 10971680 3920352
18:30:01 82906 8192000000 10892236832 11371270144 1914725824 18.945 96 14 27 0 1934242336 15370320 4146192
18:32:27 82936 8192000000 10876826960 11367538688 1945012272 19.187 99 15 30 0 1964496352 15095456 4388624
18:35:08 36471 8192000000 10901496208 11377139712 1982995424 19.488 100 29 58 1 2003871104 12926784 7948896
18:36:50 103005 8192000000 10861123040 11325505536 1816725424 18.151 92 15 30 0 1828729632 8533664 3470544
18:39:34 99742 8192000000 10856212144 11321212928 1839018368 18.333 94 7 14 0 1850846096 9982768 1844960
18:43:18 31177 8192000000 10877374624 11333226496 1929452048 19.062 99 41 82 2 1954166912 13377728 11337136
18:44:50 28017 8192000000 10892710000 11345879040 1956889280 19.281 100 53 105 0 1981952144 10526672 14536192
18:46:20 37289 8192000000 10901014816 11348291584 1912016064 18.923 97 46 92 0 1937690768 13185680 12489024
18:48:25 99850 8192000000 10877142016 11345887232 1906605008 18.879 98 6 12 0 1922481504 14542864 1333632
18:52:02 90725 8192000000 10884130592 11358953472 1954913456 19.266 100 20 39 0 1974726544 14061328 5751760
18:54:39 39294 8192000000 10862649376 11337957376 1918345664 18.974 98 17 34 0 1939576912 16789168 4442080
18:56:19 57758 8192000000 10863166544 11343572992 1917718144 18.969 98 10 20 1 1931609024 11094704 2796176
18:58:17 52830 8192000000 10867576672 11337568256 1938693456 19.136 100 13 26 0 1958704768 15884368 4126944
19:01:10 113017 8192000000 10876970064 11338080256 1884296368 18.700 96 14 28 1 1899798848 11931600 3570880
19:04:03 48810 8192000000 10885609968 11358306304 1956452624 19.278 100 29 56 0 1976825808 12832352 7540832
19:05:53 44900 8192000000 10874534656 11344109568 1900970352 18.834 97 13 26 0 1917113680 12527984 3615344
19:07:49 97046 8192000000 10894062768 11372154880 1942327824 19.165 99 12 24 0 1963572528 18233440 3011264
19:10:26 49375 8192000000 10909564096 11375325184 1943602432 19.175 98 22 44 0 1959475824 9501552 6371840
19:12:16 89425 8192000000 10926677456 11393605632 1941320512 19.157 98 14 28 0 1969590656 24494336 3775808
19:15:09 39715 8192000000 10901832304 11361599488 1944298784 19.181 98 18 35 2 1961086224 12185424 4602016
19:16:48 87052 8192000000 10898364192 11354808320 1940200768 19.148 99 34 68 0 1963514944 13693504 9620672
19:19:28 52800 8192000000 10910394384 11373191168 1978068608 19.449 100 32 63 2 1999740272 13210560 8461104
19:21:47 34022 8192000000 10862082832 11337850880 1917130192 18.964 98 6 12 1 1928778768 10195072 1453504
19:23:22 33876 8192000000 10847563776 11323019264 1919223376 18.981 99 10 18 1 1928569888 6681808 2664704
19:24:56 65192 8192000000 10856560720 11334037504 1922982384 19.011 99 9 18 1 1935807024 10431888 2392752
19:27:03 51088 8192000000 10866487760 11351425024 1913245168 18.933 98 15 30 2 1936615392 18680592 4689632
19:29:02 87275 8192000000 10863178848 11341594624 1885684784 18.711 97 14 28 2 1912905008 23115120 4105104
19:31:30 60874 8192000000 10853534368 11327574016 1856271360 18.473 96 15 30 0 1874417520 13752704 4393456
19:33:45 41875 8192000000 10850521840 11306856448 1897991568 18.810 99 27 54 0 1919113328 13547152 7574608
19:35:27 42344 8192000000 10861200416 11325435904 1918330720 18.973 99 24 48 0 1935287968 10204224 6753024
19:37:11 38345 8192000000 10874823408 11326074880 1899966256 18.826 98 29 58 1 1917438624 8960640 8511728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 26-09-2021 19:38:44 to 26-09-2021 19:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 224 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2021 19:38:59 to 26-09-2021 19:39:01.

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          246928
MON$OLDEST_ACTIVE               246929
MON$OLDEST_SNAPSHOT             246929
MON$NEXT_TRANSACTION            246929
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                       2021120
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
--- end of diff output ---

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

26.09.21 19:39:02. 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-09-26 19:39:05 No severe PSQL-related problems occured
26.09.21 19:39:06. End of report.