Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2022 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 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 16257768 kB
MemAvailable 31239180 kB
Buffers 0 kB
Cached 15380956 kB
SwapCached 2300 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.9
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-01-13 19:38:04
DTS_END                         2022-01-13 19:38:04.3410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36578
TRN_ID                          244855
ATT_ID                          660
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: 9451.14 17718 1134137
customer order: creation 181.48 353 21778
customer order: refuse 15.22 345 1826
order to supplier: creation 47.30 1333 5676
order to supplier: removal 3.46 104167 415
invoice (draft): creation 48.33 3675 5800
invoice (draft): removal 52.05 851 6246
invoice accept: apply 44.15 18030 5298
invoice accept: cancel 46.54 782 5585
customer reserve: creation 4088.08 177 490570
customer reserve: removal 4622.33 61 554680
realization accept: apply 87.05 174 10446
realization accept: cancel 78.12 115 9374
payment from customer: creation 63.59 128 7631
payment from customer: removal 5.99 66 719
payment to supplier: creation 50.59 133 6071
payment to supplier: removal 3.67 88 440
service: total inventory turnovers 5.16 919 619
service: total monetary turnovers 7.23 34 868
service: aggregate perf. data 0.76 19464 91
service: refresh index statistics 0.03 203469 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2022 19:39:09 to 13-01-2022 19:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4517
TEST_TIME 2 5918
TEST_TIME 3 6034
TEST_TIME 4 6132
TEST_TIME 5 5940
TEST_TIME 6 6548
TEST_TIME 7 6375
TEST_TIME 8 6539
TEST_TIME 9 6480
TEST_TIME 10 6482
TEST_TIME 11 6770
TEST_TIME 12 6756
TEST_TIME 13 7006
TEST_TIME 14 7551
TEST_TIME 15 7941
TEST_TIME 16 8032
TEST_TIME 17 8110
TEST_TIME 18 8025
TEST_TIME 19 8263
TEST_TIME 20 8487
TEST_TIME 21 8909
TEST_TIME 22 9011
TEST_TIME 23 8937
TEST_TIME 24 9174
TEST_TIME 25 9300
TEST_TIME 26 9775
TEST_TIME 27 10184
TEST_TIME 28 11467
TEST_TIME 29 11614
TEST_TIME 30 11358
TEST_TIME 31 11154
TEST_TIME 32 11021
TEST_TIME 33 10833
TEST_TIME 34 10736
TEST_TIME 35 10642
TEST_TIME 36 10641
TEST_TIME 37 10793
TEST_TIME 38 10656
TEST_TIME 39 10593
TEST_TIME 40 10514
TEST_TIME 41 10385
TEST_TIME 42 10258
TEST_TIME 43 10174
TEST_TIME 44 10212
TEST_TIME 45 10107
TEST_TIME 46 10036
TEST_TIME 47 10017
TEST_TIME 48 9924
TEST_TIME 49 9877
TEST_TIME 50 9808
TEST_TIME 51 9762
TEST_TIME 52 9775
TEST_TIME 53 9652
TEST_TIME 54 9542
TEST_TIME 55 9557
TEST_TIME 56 9617
TEST_TIME 57 9461
TEST_TIME 58 9526
TEST_TIME 59 9522
TEST_TIME 60 9477
TEST_TIME 61 9503
TEST_TIME 62 9586
TEST_TIME 63 9564
TEST_TIME 64 9596
TEST_TIME 65 9706
TEST_TIME 66 9652
TEST_TIME 67 9762
TEST_TIME 68 9708
TEST_TIME 69 9696
TEST_TIME 70 9698
TEST_TIME 71 9762
TEST_TIME 72 9727
TEST_TIME 73 9837
TEST_TIME 74 9865
TEST_TIME 75 9764
TEST_TIME 76 9690
TEST_TIME 77 9674
TEST_TIME 78 9604
TEST_TIME 79 9636
TEST_TIME 80 9585
TEST_TIME 81 9531
TEST_TIME 82 9530
TEST_TIME 83 9439
TEST_TIME 84 9484
TEST_TIME 85 9483
TEST_TIME 86 9513
TEST_TIME 87 9491
TEST_TIME 88 9463
TEST_TIME 89 9572
TEST_TIME 90 9579
TEST_TIME 91 9603
TEST_TIME 92 9564
TEST_TIME 93 9589
TEST_TIME 94 9611
TEST_TIME 95 9650
TEST_TIME 96 9707
TEST_TIME 97 9676
TEST_TIME 98 9690
TEST_TIME 99 9788
TEST_TIME 100 9694
TEST_TIME 101 9639
TEST_TIME 102 9594
TEST_TIME 103 9538
TEST_TIME 104 9531
TEST_TIME 105 9491
TEST_TIME 106 9455
TEST_TIME 107 9472
TEST_TIME 108 9479
TEST_TIME 109 9460
TEST_TIME 110 9471
TEST_TIME 111 9476
TEST_TIME 112 9467
TEST_TIME 113 9441
TEST_TIME 114 9478
TEST_TIME 115 9466
TEST_TIME 116 9479
TEST_TIME 117 9453
TEST_TIME 118 9434
TEST_TIME 119 9537
TEST_TIME 120 9459

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2022 19:39:13 to 13-01-2022 19:39:16.

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 1586814 1586814 0 0.00 0 20993 7 0 0
doc_list_biud 1586813 1586813 0 0.00 0 68814 27 0 0
sp_add_invoice_to_stock 5395 5298 97 1.79 29 186064 18031 0 97
sp_cancel_adding_invoice 5585 5585 0 0.00 4 54090 782 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 10666 345 0 0
sp_cancel_customer_reserve 554680 554680 0 0.00 1 68815 61 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 1978 67 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 4285 88 0 0
sp_cancel_supplier_invoice 6252 6246 6 0.09 2 16128 851 0 6
sp_cancel_supplier_order 415 415 0 0.00 6 1206437 104168 0 0
sp_cancel_write_off 9374 9374 0 0.00 1 12031 116 0 0
sp_client_order 21778 21778 0 0.00 4 45797 353 0 0
sp_customer_reserve 490570 490570 0 0.00 0 37569 178 0 0
sp_fill_shopping_cart 33250 33250 0 0.00 1 18092 41 0 0
sp_fill_shopping_cart_clo_res 490561 490561 0 0.00 0 11367 13 0 0
sp_get_clo_for_invoice 5284 5284 0 0.00 0 31752 307 0 0
sp_kill_cost_storno 16118 16118 0 0.00 0 5710 57 0 0
sp_kill_qstorno_ret_qs2qd 1125395 1125395 0 0.00 0 68808 33 0 0
sp_kill_qty_storno 593856 593856 0 0.00 0 68813 72 0 0
sp_lock_selected_doc 21721 21721 0 0.00 0 8190 4 0 0
sp_make_cost_storno 29432 29432 0 0.00 0 20979 53 0 0
sp_multiply_rows_for_pdistr 29432 29432 0 0.00 0 6831 32 0 0
sp_multiply_rows_for_qdistr 21778 21778 0 0.00 0 36662 152 0 0
sp_pay_from_customer 7631 7631 0 0.00 4 21066 128 0 0
sp_pay_to_supplier 6071 6071 0 0.00 4 14951 134 0 0
sp_payment_common 13702 13702 0 0.00 1 19095 31 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 10660 322 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 16026 339 0 0
sp_qd_handle_on_reserve_upd_sts 10446 10446 0 0.00 0 15107 62 0 0
sp_reserve_write_off 10446 10446 0 0.00 6 15231 175 0 0
sp_supplier_invoice 5800 5800 0 0.00 7 91845 3675 0 0
sp_supplier_order 5676 5676 0 0.00 5 82562 1334 0 0
srv_aggregate_perf_data 91 91 0 0.00 2923 169714 19465 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3202 171842 44478 0 0
srv_find_qd_qs_mism 1082909 1082909 0 0.00 0 17337 6 0 0
srv_make_invnt_saldo 619 619 0 0.00 40 32682 919 0 0
srv_make_money_saldo 868 868 0 0.00 0 2521 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 120164 327216 203469 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 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5299 5299 0 0.00 0 10266 176 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 6697 134 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 5493 13 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1240 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 4859 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1377 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 1415 15 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 16470 1610 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 4385 16 0 0
t$perf-norm:sp_client_order 21778 21778 0 0.00 0 12370 22 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1954 7 0 0
t$perf-norm:sp_pay_from_customer 7631 7631 0 0.00 0 7625 9 0 0
t$perf-norm:sp_pay_to_supplier 6071 6071 0 0.00 0 7626 12 0 0
t$perf-norm:sp_reserve_write_off 10446 10446 0 0.00 0 9572 14 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 7461 20 0 0
t$perf-norm:sp_supplier_order 5676 5676 0 0.00 0 10558 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 593 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 619 619 0 0.00 0 1815 22 0 0
t$perf-norm:srv_make_money_saldo 868 868 0 0.00 0 2279 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10762 10568 194 1.80 0 5433 20 0 194
v_all_customers 40252 40252 0 0.00 0 52 1 0 0
v_all_suppliers 31722 31722 0 0.00 0 19 1 0 0
v_all_wares 43556 43556 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 407 7 0 0
v_cancel_client_order 3652 3652 0 0.00 0 1543 5 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 1968 6 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2012 6 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 1778 8 0 0
v_cancel_supplier_invoice 882 870 12 1.36 0 4637 21 0 12
v_cancel_supplier_order 830 830 0 0.00 0 865 4 0 0
v_cancel_write_off 2060 2060 0 0.00 0 5413 16 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 957 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3714 18 0 0
v_max_id_ord_sup 5799 5799 0 0.00 0 6655 5 0 0
v_max_non_paid_invoice 1682 1682 0 0.00 0 6199 9 0 0
v_max_non_paid_realizn 3952 3952 0 0.00 0 2175 5 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 1996 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 6680 10 0 0
v_min_id_ord_sup 5799 5799 0 0.00 0 2516 4 0 0
v_min_non_paid_invoice 6071 6071 0 0.00 0 5234 15 0 0
v_min_non_paid_realizn 7631 7631 0 0.00 0 19090 24 0 0
v_our_firm 10662 10662 0 0.00 0 53 1 0 0
v_reserve_write_off 20890 20890 0 0.00 0 7913 7 0 0
x_lock_depdocs_on_canc_invoice 11396 11396 0 0.00 0 21143 121 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 5263 345 0 0
x_make_qstorno_1000_1200 5676 5676 0 0.00 1 82546 1251 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 2 78111 3526 0 0
x_make_qstorno_2100_3300 490570 490570 0 0.00 0 37568 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 19:39:17 to 13-01-2022 19:39:21.

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:08:08 15 8192000000 8806700352 8820727808 11716496 0.142 3 3 4 0 11901856 78240 107120
17:09:08 13810 8192000000 9895282992 10204983296 976944928 10.654 100 90 180 0 1006085216 5453232 23687056
17:10:27 21642 8192000000 9938148800 10256150528 1014248928 11.016 100 91 182 0 1045343424 7573824 23520672
17:11:55 15844 8192000000 9967310896 10297090048 1047857168 11.340 100 89 178 0 1079389776 9300672 22231936
17:13:17 14927 8192000000 10025766112 10379935744 1118228320 12.010 100 73 145 0 1145138432 9010432 17899680
17:14:35 12785 8192000000 10096961488 10461597696 1185531248 12.642 100 77 153 0 1214307440 11450784 17325408
17:15:55 5464 8192000000 10147431824 10519027712 1231422752 13.067 100 83 166 2 1263658800 13898128 18337920
17:17:02 3711 8192000000 10165796000 10545643520 1253846464 13.274 100 78 155 0 1285761104 15093984 16820656
17:18:08 1685 8192000000 10204083920 10583859200 1285180464 13.560 100 93 187 0 1319924928 14165056 20579408
17:19:10 15833 8192000000 10204436112 10596450304 1294496368 13.645 100 69 138 0 1320631744 10317984 15817392
17:20:28 5116 8192000000 10196033968 10608050176 1307104656 13.760 100 37 75 1 1322802224 7492096 8205472
17:21:37 7336 8192000000 10203778256 10614013952 1312757408 13.811 100 29 58 1 1325863168 6269184 6836576
17:22:46 54053 8192000000 10236863200 10638848000 1322827488 13.902 100 47 94 0 1339249600 4924560 11497552
17:24:40 6096 8192000000 10223185168 10642137088 1333288992 13.997 100 33 65 0 1346976784 6535344 7152448
17:25:47 10941 8192000000 10219790720 10646491136 1338583440 14.045 100 20 39 2 1348065680 5515104 3967136
17:26:58 54510 8192000000 10224283040 10648399872 1335900448 14.020 100 47 95 0 1353870688 6402432 11567808
17:28:56 5215 8192000000 10175519056 10584694784 1256588288 13.299 97 22 44 0 1267712128 5988960 5134880
17:30:02 6882 8192000000 10128169344 10523856896 1181125200 12.601 94 21 42 4 1191879680 5100144 5654336
17:31:09 10912 8192000000 10138918912 10538303488 1216524576 12.930 97 33 66 1 1229394640 5183888 7686176
17:32:24 21764 8192000000 10157923440 10563952640 1244746624 13.190 98 21 42 1 1255092848 5332352 5013872
17:33:47 7019 8192000000 10180445824 10582278144 1283030592 13.541 100 24 49 1 1294799264 5963440 5805232
17:34:56 9720 8192000000 10187357568 10601037824 1296395760 13.662 100 27 54 0 1308534272 5668032 6470480
17:36:08 43014 8192000000 10188644896 10607550464 1303192272 13.724 100 27 54 1 1316374400 5649792 7532336
17:37:59 13432 8192000000 10213134512 10620157952 1312834848 13.812 100 22 42 2 1323033984 6021680 4177456
17:39:34 30890 8192000000 10246453712 10662363136 1342788144 14.083 100 42 84 2 1360343024 8429408 9125472
17:41:46 12250 8192000000 10278750672 10699800576 1370356032 14.330 100 47 94 1 1394640032 11836064 12447936
17:43:01 7819 8192000000 10293232000 10715095040 1382229600 14.436 100 41 80 2 1405359824 12982880 10147344
17:44:13 7529 8192000000 10302858880 10727772160 1392778640 14.531 100 61 121 2 1420514144 13643984 14091520
17:45:27 10530 8192000000 10317438480 10745729024 1397912624 14.576 100 41 82 2 1426495952 18837840 9745488
17:46:43 10054 8192000000 10326212832 10753830912 1405554512 14.644 100 49 96 4 1435403984 17932112 11917360
17:47:58 6673 8192000000 10328122192 10745659392 1397601904 14.574 99 55 107 2 1431194880 21169024 12423952
17:49:10 4944 8192000000 10332304000 10750259200 1395812928 14.558 100 72 144 1 1441974528 28495840 17665760
17:50:21 4021 8192000000 10325202304 10743042048 1388205008 14.490 100 70 139 0 1435619936 30081920 17333008
17:51:30 4823 8192000000 10339705296 10765459456 1399222272 14.588 100 50 100 1 1448561984 36273200 13066512
17:52:38 6112 8192000000 10337475648 10757705728 1382487696 14.439 99 59 118 0 1436069312 39028464 14553152
17:53:49 5805 8192000000 10322898960 10735816704 1359362064 14.232 99 70 140 0 1416873328 39673824 17837440
17:54:59 5576 8192000000 10324847248 10728939520 1366442352 14.295 100 84 168 1 1427232048 39989936 20799760
17:56:13 3201 8192000000 10335602192 10734682112 1369399792 14.322 100 86 173 1 1434744336 43991792 21352752
17:57:22 3341 8192000000 10342631616 10744356864 1366623792 14.297 99 86 171 1 1437967920 49915184 21428944
17:58:32 9845 8192000000 10351615552 10768908288 1386372192 14.473 100 55 108 1 1456956144 55895216 14688736
17:59:55 10422 8192000000 10339020896 10758041600 1375156752 14.373 100 38 76 0 1436900432 51019568 10724112
18:01:09 20834 8192000000 10320431856 10746376192 1361311520 14.249 99 33 66 1 1422410496 51632800 9466176
18:02:36 12388 8192000000 10325445712 10754519040 1357704688 14.217 99 35 70 0 1421192272 53186912 10300672
18:04:00 34240 8192000000 10292494512 10734886912 1321238128 13.888 98 22 44 0 1387793248 59677424 6877696
18:05:41 21984 8192000000 10297373520 10730328064 1308316688 13.771 97 22 44 1 1371060880 55952640 6791552
18:07:22 62210 8192000000 10285456512 10704293888 1338732128 14.046 99 39 79 5 1356823280 8835488 9255664
18:09:34 53245 8192000000 10284637872 10721710080 1364465936 14.277 100 43 86 2 1387181024 12315984 10399104
18:11:28 33286 8192000000 10289899584 10738319360 1372777456 14.352 99 22 44 1 1396484704 18447456 5259792
18:13:22 54342 8192000000 10303994688 10764144640 1387512112 14.484 100 11 22 0 1417951184 27342016 3097056
18:15:40 130171 8192000000 10267704464 10721075200 1312140768 13.805 95 31 58 2 1326616496 8414864 6060864
18:18:50 29681 8192000000 10282372512 10725675008 1373537840 14.359 100 24 49 1 1386103488 6911360 5654288
18:20:21 46606 8192000000 10289439392 10736779264 1358664976 14.225 98 14 28 0 1373994032 11896880 3432176
18:22:14 171840 8192000000 10293647408 10743218176 1357605456 14.216 98 27 54 1 1375445552 11062800 6777296
18:26:09 68013 8192000000 10287550464 10745004032 1389103984 14.498 100 15 30 1 1398933312 6304272 3525056
18:28:25 25517 8192000000 10294515504 10736648192 1360984080 14.246 98 39 77 1 1376416288 5978320 9453888
18:29:52 37966 8192000000 10268938000 10723524608 1357056032 14.211 99 16 32 1 1369132800 8431888 3644880
18:31:34 35365 8192000000 10285231712 10740637696 1372734304 14.352 100 7 14 0 1391742960 17065248 1943408
18:34:32 105739 8192000000 10281759024 10733494272 1367351968 14.303 99 25 48 1 1389076016 16236352 5487696
18:37:18 64247 8192000000 10292344064 10748657664 1384690592 14.458 100 10 20 0 1396532640 9642800 2199248
18:39:30 27933 8192000000 10270072960 10723835904 1329346768 13.961 96 23 45 1 1345255104 10626912 5281424
18:41:09 26071 8192000000 10276021312 10727448576 1356654208 14.207 99 11 22 1 1366254176 7291776 2308192
18:42:36 49092 8192000000 10264675840 10720239616 1326595744 13.936 97 26 52 0 1350284320 17931472 5757104
18:45:04 61578 8192000000 10270911456 10726305792 1355239808 14.195 99 17 34 0 1374135696 15181456 3714432
18:47:06 47778 8192000000 10246703648 10692464640 1308410864 13.772 96 19 38 1 1321169648 8439040 4319744
18:48:56 54845 8192000000 10232188592 10679750656 1267390192 13.398 94 14 28 0 1281977680 11634384 2953104
18:50:59 29318 8192000000 10237360768 10671927296 1312483328 13.809 98 43 86 1 1336065824 14548352 9034144
18:53:12 122906 8192000000 10233175216 10670809088 1293677872 13.638 97 27 54 0 1307129808 7669408 5782528
18:56:15 24484 8192000000 10260194016 10687361024 1342135584 14.077 100 55 111 2 1364822480 9596336 13090560
18:57:40 66913 8192000000 10245789152 10693156864 1325296464 13.925 98 13 25 3 1338037184 9484224 3256496
18:59:47 63605 8192000000 10250944944 10706444288 1333873632 14.002 98 15 30 0 1351887328 14421120 3592576
19:01:51 70840 8192000000 10272625664 10735325184 1350230272 14.150 99 10 21 0 1369428560 16844064 2354224
19:04:59 24643 8192000000 10274496112 10718396416 1351484256 14.161 99 28 56 0 1370972816 12800512 6688048
19:06:26 70452 8192000000 10281021520 10729787392 1362896880 14.263 99 15 30 0 1381576672 14817680 3862112
19:08:40 78719 8192000000 10294678320 10751307776 1368197248 14.311 98 16 32 0 1381017184 9813248 3006688
19:11:00 55270 8192000000 10290029280 10737385472 1355103376 14.193 98 14 28 0 1369744384 11671360 2969648
19:13:04 55481 8192000000 10304344736 10751983616 1372860144 14.353 99 25 50 0 1396521040 18310208 5350688
19:15:06 96282 8192000000 10302234496 10750394368 1327055616 13.941 96 11 22 1 1338753600 9716640 1981344
19:17:42 167398 8192000000 10265051392 10716209152 1326679600 13.937 96 14 28 0 1340267936 10449504 3138832
19:21:30 82976 8192000000 10270992752 10727788544 1342377376 14.079 97 13 26 1 1364215712 18663808 3174528
19:24:00 68952 8192000000 10269430256 10716921856 1335533552 14.017 98 14 29 0 1357760544 19202688 3024304
19:26:10 67683 8192000000 10264589312 10711859200 1356298080 14.204 99 15 30 0 1371313472 11441728 3573664
19:28:21 56943 8192000000 10257412848 10713915392 1329961920 13.967 97 10 20 0 1343055408 10995552 2097936
19:30:23 14445 8192000000 10289585344 10733969408 1378553056 14.404 100 49 98 2 1399207872 9823808 10831008
19:31:42 42438 8192000000 10263953184 10710564864 1344117648 14.095 99 37 74 1 1365432928 12599392 8715888
19:33:45 41213 8192000000 10258583760 10703011840 1290573936 13.609 95 28 56 1 1306053648 9511520 5968192
19:35:27 26244 8192000000 10253097328 10685177856 1296246960 13.661 97 33 66 0 1315903808 12560880 7095968
19:36:59 87051 8192000000 9455438416 9776885760 577239200 6.582 43 12 24 0 595052464 14782864 3030400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-01-2022 19:39:23 to 13-01-2022 19:39:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 13-01-2022 19:39:39 to 13-01-2022 19:39:40.

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          244854
MON$OLDEST_ACTIVE               245563
MON$OLDEST_SNAPSHOT             245563
MON$NEXT_TRANSACTION            245563
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                       2050208
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:887: 
:888: mscs-test-161.budzdorov.ru    Thu Jan 13 19:38:35 2022
:889:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:890: 
--- end of diff output ---

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

13.01.22 19:39:42. 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-01-13 19:39:44 No severe PSQL-related problems occured
13.01.22 19:39:45. End of report.