Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.06.2022 16:40
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 16236376 kB
MemAvailable 31234620 kB
Buffers 0 kB
Cached 15504724 kB
SwapCached 2508 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-26 16:38:20
DTS_END                         2022-06-26 16:38:20.7590
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/38430
TRN_ID                          236250
ATT_ID                          613
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: 9391.95 21957 1127034
customer order: creation 164.70 374 19764
customer order: refuse 13.75 374 1650
order to supplier: creation 43.89 1325 5267
order to supplier: removal 3.47 116139 416
invoice (draft): creation 44.55 3712 5346
invoice (draft): removal 56.12 855 6734
invoice accept: apply 39.79 18149 4775
invoice accept: cancel 50.19 776 6023
customer reserve: creation 3749.83 176 449980
customer reserve: removal 4934.97 64 592196
realization accept: apply 80.92 190 9710
realization accept: cancel 84.36 119 10123
payment from customer: creation 56.99 115 6839
payment from customer: removal 5.81 62 697
payment to supplier: creation 46.98 132 5637
payment to supplier: removal 3.31 100 397
service: total inventory turnovers 4.93 1105 591
service: total monetary turnovers 6.59 30 791
service: aggregate perf. data 0.78 21552 94
service: refresh index statistics 0.03 273797 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-06-2022 16:40:22 to 26-06-2022 16:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6482
TEST_TIME 2 4941
TEST_TIME 3 4708
TEST_TIME 4 4254
TEST_TIME 5 4386
TEST_TIME 6 4452
TEST_TIME 7 5447
TEST_TIME 8 5485
TEST_TIME 9 5787
TEST_TIME 10 5980
TEST_TIME 11 6493
TEST_TIME 12 6479
TEST_TIME 13 6474
TEST_TIME 14 6629
TEST_TIME 15 6916
TEST_TIME 16 7014
TEST_TIME 17 7414
TEST_TIME 18 7207
TEST_TIME 19 7148
TEST_TIME 20 7851
TEST_TIME 21 8594
TEST_TIME 22 8618
TEST_TIME 23 8551
TEST_TIME 24 9009
TEST_TIME 25 9126
TEST_TIME 26 9040
TEST_TIME 27 9023
TEST_TIME 28 9799
TEST_TIME 29 11146
TEST_TIME 30 11345
TEST_TIME 31 11129
TEST_TIME 32 10933
TEST_TIME 33 10846
TEST_TIME 34 10733
TEST_TIME 35 10640
TEST_TIME 36 10535
TEST_TIME 37 10425
TEST_TIME 38 10352
TEST_TIME 39 10219
TEST_TIME 40 10107
TEST_TIME 41 9933
TEST_TIME 42 9880
TEST_TIME 43 10065
TEST_TIME 44 9978
TEST_TIME 45 9897
TEST_TIME 46 9829
TEST_TIME 47 9766
TEST_TIME 48 9732
TEST_TIME 49 9700
TEST_TIME 50 9721
TEST_TIME 51 9620
TEST_TIME 52 9470
TEST_TIME 53 9344
TEST_TIME 54 9451
TEST_TIME 55 9364
TEST_TIME 56 9387
TEST_TIME 57 9398
TEST_TIME 58 9362
TEST_TIME 59 9439
TEST_TIME 60 9459
TEST_TIME 61 9539
TEST_TIME 62 9611
TEST_TIME 63 9596
TEST_TIME 64 9677
TEST_TIME 65 9719
TEST_TIME 66 9768
TEST_TIME 67 9810
TEST_TIME 68 9786
TEST_TIME 69 9902
TEST_TIME 70 9849
TEST_TIME 71 9891
TEST_TIME 72 9824
TEST_TIME 73 9739
TEST_TIME 74 9700
TEST_TIME 75 9674
TEST_TIME 76 9633
TEST_TIME 77 9657
TEST_TIME 78 9599
TEST_TIME 79 9576
TEST_TIME 80 9494
TEST_TIME 81 9475
TEST_TIME 82 9401
TEST_TIME 83 9603
TEST_TIME 84 9558
TEST_TIME 85 9544
TEST_TIME 86 9598
TEST_TIME 87 9597
TEST_TIME 88 9567
TEST_TIME 89 9616
TEST_TIME 90 9599
TEST_TIME 91 9646
TEST_TIME 92 9638
TEST_TIME 93 9673
TEST_TIME 94 9659
TEST_TIME 95 9653
TEST_TIME 96 9632
TEST_TIME 97 9563
TEST_TIME 98 9480
TEST_TIME 99 9474
TEST_TIME 100 9447
TEST_TIME 101 9424
TEST_TIME 102 9395
TEST_TIME 103 9345
TEST_TIME 104 9424
TEST_TIME 105 9499
TEST_TIME 106 9460
TEST_TIME 107 9485
TEST_TIME 108 9476
TEST_TIME 109 9511
TEST_TIME 110 9606
TEST_TIME 111 9603
TEST_TIME 112 9623
TEST_TIME 113 9658
TEST_TIME 114 9625
TEST_TIME 115 9571
TEST_TIME 116 9557
TEST_TIME 117 9522
TEST_TIME 118 9497
TEST_TIME 119 9463
TEST_TIME 120 9401

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-06-2022 16:40:26 to 26-06-2022 16:40:29.

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 1544950 1544950 0 0.00 0 19000 6 0 0
doc_list_biud 1544950 1544950 0 0.00 0 59089 31 0 0
sp_add_invoice_to_stock 4890 4775 115 2.35 30 209423 18150 0 115
sp_cancel_adding_invoice 6023 6023 0 0.00 3 57965 776 0 0
sp_cancel_client_order 1650 1650 0 0.00 4 12157 374 0 0
sp_cancel_customer_reserve 592196 592196 0 0.00 1 29072 64 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 3861 63 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 13151 100 0 0
sp_cancel_supplier_invoice 6750 6734 16 0.23 1 59093 856 0 16
sp_cancel_supplier_order 416 416 0 0.00 9 1003272 116140 0 0
sp_cancel_write_off 10123 10123 0 0.00 1 9546 119 0 0
sp_client_order 19764 19764 0 0.00 3 15185 374 0 0
sp_customer_reserve 449980 449980 0 0.00 0 46893 177 0 0
sp_fill_shopping_cart 30368 30368 0 0.00 1 39431 44 0 0
sp_fill_shopping_cart_clo_res 449965 449965 0 0.00 0 23745 15 0 0
sp_get_clo_for_invoice 4759 4759 0 0.00 0 20639 315 0 0
sp_kill_cost_storno 17240 17240 0 0.00 0 13140 59 0 0
sp_kill_qstorno_ret_qs2qd 1201665 1201665 0 0.00 0 59087 35 0 0
sp_kill_qty_storno 631611 631611 0 0.00 0 59089 75 0 0
sp_lock_selected_doc 20062 20062 0 0.00 0 7922 5 0 0
sp_make_cost_storno 26945 26945 0 0.00 0 18914 58 0 0
sp_multiply_rows_for_pdistr 26945 26945 0 0.00 0 11500 31 0 0
sp_multiply_rows_for_qdistr 19764 19764 0 0.00 0 12431 168 0 0
sp_pay_from_customer 6839 6839 0 0.00 4 14216 115 0 0
sp_pay_to_supplier 5637 5637 0 0.00 5 17994 132 0 0
sp_payment_common 12476 12476 0 0.00 2 11309 30 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 12151 345 0 0
sp_qd_handle_on_invoice_upd_sts 10782 10782 0 0.00 0 16250 343 0 0
sp_qd_handle_on_reserve_upd_sts 9710 9710 0 0.00 0 11805 71 0 0
sp_reserve_write_off 9710 9710 0 0.00 6 14913 190 0 0
sp_supplier_invoice 5346 5346 0 0.00 7 94925 3712 0 0
sp_supplier_order 5267 5267 0 0.00 5 36283 1326 0 0
srv_aggregate_perf_data 94 94 0 0.00 3003 257182 21552 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1632 192714 49005 0 0
srv_find_qd_qs_mism 1081501 1081501 0 0.00 0 9464 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 26 82600 1106 0 0
srv_make_money_saldo 791 791 0 0.00 0 3075 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 154372 401067 273797 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4775 4775 0 0.00 0 10441 170 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 4330 110 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 2446 13 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 2049 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2679 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 6902 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2384 27 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 18227 1786 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 8892 18 0 0
t$perf-norm:sp_client_order 19764 19764 0 0.00 0 9244 16 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2577 7 0 0
t$perf-norm:sp_pay_from_customer 6839 6839 0 0.00 0 4601 11 0 0
t$perf-norm:sp_pay_to_supplier 5637 5637 0 0.00 0 5450 8 0 0
t$perf-norm:sp_reserve_write_off 9710 9710 0 0.00 0 4683 14 0 0
t$perf-norm:sp_supplier_invoice 5346 5346 0 0.00 0 6522 20 0 0
t$perf-norm:sp_supplier_order 5267 5267 0 0.00 0 3715 15 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 534 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 63 1 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 2841 25 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 2408 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 9748 9518 230 2.35 0 6118 19 0 230
v_all_customers 36116 36116 0 0.00 0 88 1 0 0
v_all_suppliers 29690 29690 0 0.00 0 16 1 0 0
v_all_wares 39528 39528 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 390 5 0 0
v_cancel_client_order 3300 3300 0 0.00 0 2393 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3856 8 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 890 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1158 5 0 0
v_cancel_supplier_invoice 834 802 32 3.83 0 784 13 0 32
v_cancel_supplier_order 832 832 0 0.00 0 2391 9 0 0
v_cancel_write_off 1918 1918 0 0.00 0 1941 14 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 3647 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 3325 17 0 0
v_max_id_ord_sup 5339 5339 0 0.00 0 4721 3 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 1850 4 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 478 3 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 6606 7 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 1114 6 0 0
v_min_id_ord_sup 5339 5339 0 0.00 0 5679 15 0 0
v_min_non_paid_invoice 5637 5637 0 0.00 0 11306 22 0 0
v_min_non_paid_realizn 6839 6839 0 0.00 0 9414 26 0 0
v_our_firm 9970 9970 0 0.00 0 15 1 0 0
v_reserve_write_off 19420 19420 0 0.00 0 3504 6 0 0
x_lock_depdocs_on_canc_invoice 12356 12356 0 0.00 0 11150 109 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 13018 384 0 0
x_make_qstorno_1000_1200 5267 5267 0 0.00 1 36212 1243 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 3 90140 3549 0 0
x_make_qstorno_2100_3300 449980 449980 0 0.00 0 46316 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-06-2022 16:40:30 to 26-06-2022 16:40:34.

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:24 7 8192000000 8801917024 8814170112 7670864 0.093 3 1 2 0 7771824 58176 42784
14:09:25 1724 8192000000 9894745664 10196717568 982176704 10.705 100 91 182 0 1011796960 5305808 24314448
14:10:28 18903 8192000000 9944906448 10262335488 1025841744 11.128 100 90 180 0 1056427168 7806784 22778640
14:11:55 16032 8192000000 9972547152 10301071360 1056599776 11.424 100 88 175 0 1088006144 9430368 21976000
14:13:26 14565 8192000000 10023368624 10365194240 1098936352 11.828 100 76 152 0 1127827536 10619616 18271568
14:14:45 8457 8192000000 10068716048 10426826752 1157560000 12.380 100 73 146 1 1185622016 11182224 16879792
14:15:57 9333 8192000000 10127156032 10493472768 1205230544 12.825 100 77 155 1 1234833744 11972464 17630736
14:17:11 2451 8192000000 10169944912 10545467392 1255846112 13.292 100 85 170 3 1288372176 13355776 19170288
14:18:15 2186 8192000000 10204865792 10586251264 1288427712 13.590 100 82 164 0 1319920816 12413984 19079120
14:19:19 2920 8192000000 10225522192 10609946624 1307518128 13.764 100 86 171 1 1339768576 12680544 19569904
14:20:22 3886 8192000000 10207104368 10617872384 1316465840 13.845 100 34 68 2 1333076448 9087856 7522752
14:21:27 58731 8192000000 10229007696 10636972032 1326894832 13.939 100 53 105 0 1343622800 4567424 12160544
14:23:26 4617 8192000000 10222506144 10642436096 1331919472 13.984 100 25 48 1 1343912960 6276512 5716976
14:24:33 8730 8192000000 10231075600 10652516352 1339531152 14.053 100 16 31 0 1348233936 5078192 3624592
14:25:46 12754 8192000000 10227088160 10655006720 1346599712 14.117 100 23 46 0 1357672336 5945024 5127600
14:27:06 20340 8192000000 10223359520 10648723456 1327678128 13.946 99 36 70 1 1342154080 6954944 7521008
14:28:29 6185 8192000000 10208675536 10627477504 1302528192 13.718 98 23 46 1 1312873872 5603440 4742240
14:29:37 10946 8192000000 10151129296 10560778240 1208549680 12.856 93 18 34 1 1218463920 5698096 4216144
14:30:49 10114 8192000000 10120998704 10520502272 1181228400 12.602 95 21 42 1 1196201168 9823344 5149424
14:31:59 14800 8192000000 10104746800 10500730880 1172622480 12.521 94 25 48 2 1183101840 4760592 5718768
14:33:15 8294 8192000000 10161827104 10560061440 1254450832 13.279 99 14 28 0 1262744112 4970992 3322288
14:34:25 10879 8192000000 10175179904 10590023680 1289609232 13.601 100 27 54 1 1301987248 5938304 6439712
14:35:36 20165 8192000000 10187709264 10602663936 1300042656 13.696 100 24 47 2 1315831200 10131136 5657408
14:36:57 15598 8192000000 10196131600 10609274880 1305748624 13.747 100 24 47 0 1316284480 5331744 5204112
14:38:13 9574 8192000000 10214562384 10632896512 1323149264 13.905 100 23 44 2 1333305920 5930176 4226480
14:39:31 17557 8192000000 10243547584 10659794944 1342815248 14.083 100 41 82 0 1359597968 7815280 8967440
14:40:52 34216 8192000000 10267236592 10686705664 1361846768 14.254 100 53 106 0 1385836800 10964128 13025904
14:42:34 22075 8192000000 10287109056 10705756160 1378125024 14.400 100 43 86 3 1400680592 12025248 10530320
14:44:44 16046 8192000000 10293250640 10724397056 1382117936 14.435 100 34 68 1 1408804816 18048000 8638880
14:46:10 17096 8192000000 10310854992 10738085888 1397214112 14.570 100 46 92 1 1427698960 19653136 10831712
14:47:32 3559 8192000000 10333999280 10752581632 1408168272 14.668 100 58 114 0 1443333888 21566608 13599008
14:48:40 2689 8192000000 10351994960 10772537344 1421443952 14.786 100 71 141 1 1464688032 25348416 17895664
14:49:45 9672 8192000000 10342985808 10769371136 1416802432 14.744 100 61 122 1 1457932080 26297472 14832176
14:51:03 4581 8192000000 10354192032 10771668992 1413163792 14.712 100 71 143 2 1458138848 27431712 17543344
14:52:12 3154 8192000000 10345561040 10764214272 1407284880 14.660 100 64 129 1 1454625744 31464432 15876432
14:53:20 3766 8192000000 10360575680 10774700032 1410762672 14.691 100 76 152 2 1466453968 36450960 19240336
14:54:32 1631 8192000000 10379109008 10785406976 1413651024 14.716 100 85 170 3 1474461328 40208752 20601552
14:55:40 7466 8192000000 10339695728 10765262848 1391016672 14.515 99 55 110 1 1451144672 45870256 14257744
14:56:56 4076 8192000000 10338817920 10742378496 1359565488 14.233 99 80 160 1 1438705120 57811936 21327696
14:58:05 6396 8192000000 10364457520 10775003136 1377608016 14.395 100 76 152 1 1470798016 72389824 20800176
14:59:17 7696 8192000000 10356487888 10786279424 1386585344 14.475 100 50 99 4 1471801632 71193376 14022912
15:00:30 4609 8192000000 10371184384 10785722368 1397602016 14.574 100 61 122 0 1472434576 58924192 15908368
15:01:42 7535 8192000000 10344251424 10767368192 1374390224 14.366 100 38 76 0 1448467360 62906352 11170784
15:03:01 13964 8192000000 10347092032 10776207360 1380715728 14.423 100 35 70 1 1450839328 59909152 10214448
15:04:27 44443 8192000000 10343065968 10776809472 1364525424 14.278 100 31 62 3 1443264192 69545808 9192960
15:06:23 44343 8192000000 10332042512 10779906048 1342574288 14.081 99 23 44 0 1441605760 91872576 7158896
15:08:11 55398 8192000000 10292507328 10724818944 1344807664 14.101 98 33 64 2 1366793008 15079856 6905488
15:10:07 50066 8192000000 10288206544 10720645120 1359029664 14.229 99 34 68 1 1378338224 11898224 7410336
15:12:05 67687 8192000000 10289180096 10744029184 1376869472 14.389 100 11 22 0 1395546096 16438512 2238112
15:14:19 47645 8192000000 10279283488 10732687360 1374597040 14.368 100 17 32 1 1386081584 7414144 4070400
15:16:07 63652 8192000000 10294471920 10735906816 1357440032 14.214 98 13 26 0 1368050032 7787424 2822576
15:18:20 56879 8192000000 10306707344 10751430656 1386278000 14.473 100 48 95 2 1403707088 6651664 10777424
15:20:22 48497 8192000000 10312566240 10764947456 1400242160 14.597 100 24 48 0 1422627296 16397216 5987920
15:22:25 123524 8192000000 10296020560 10756792320 1375270336 14.374 98 16 32 0 1389116672 10513920 3332416
15:25:34 19331 8192000000 10320645136 10766249984 1403557280 14.627 100 36 70 2 1422104272 10990176 7556816
15:26:55 41751 8192000000 10306573040 10762502144 1405421968 14.643 100 27 50 0 1418915120 7354848 6138304
15:28:39 47135 8192000000 10307262672 10774376448 1395638784 14.556 99 17 35 1 1407664368 8777520 3248064
15:30:27 93279 8192000000 10309649728 10773528576 1398662528 14.583 99 13 26 0 1413322144 11900112 2759504
15:33:50 61724 8192000000 10290263264 10755756032 1373999856 14.363 98 12 24 0 1384734048 7900384 2833808
15:35:53 49628 8192000000 10310439456 10774265856 1383749472 14.450 98 14 28 1 1400963408 13913152 3300784
15:37:44 75366 8192000000 10337597120 10799693824 1398691744 14.583 99 18 36 1 1423538016 20676736 4169536
15:40:00 60605 8192000000 10291186624 10747289600 1339208992 14.050 95 15 30 0 1356914128 14350512 3354624
15:42:01 125089 8192000000 10280542832 10736476160 1277795408 13.493 91 9 18 1 1290079728 10474880 1809440
15:45:34 51910 8192000000 10296667312 10738307072 1345551584 14.107 97 25 50 0 1364448384 13538480 5358320
15:47:26 83971 8192000000 10287480064 10736132096 1330873712 13.975 96 11 22 1 1343699248 10274768 2550768
15:49:50 112663 8192000000 10246251520 10683813888 1264588256 13.372 92 24 49 0 1279524736 9198384 5738096
15:52:50 39409 8192000000 10259044928 10701791232 1334815184 14.011 98 25 51 1 1353795504 13025984 5954336
15:54:29 34264 8192000000 10235853568 10680713216 1294121552 13.642 96 19 38 0 1312534560 13604048 4808960
15:56:34 59760 8192000000 10260880096 10701246464 1326461648 13.935 98 20 41 3 1346795888 15132304 5201936
15:58:55 43078 8192000000 10260295120 10710523904 1344837664 14.101 99 22 44 0 1367436160 17002224 5596272
16:00:45 95283 8192000000 10243050400 10687848448 1293048992 13.632 96 14 28 0 1314571424 18268624 3253808
16:03:41 37661 8192000000 10258295952 10701639680 1330306144 13.970 98 25 50 0 1347263136 11616704 5340288
16:05:19 66857 8192000000 10276389696 10713296896 1334211744 14.005 98 19 38 0 1347999008 9837328 3949936
16:07:28 115769 8192000000 10280010096 10736283648 1316624160 13.846 96 14 28 0 1337970416 18331504 3014752
16:10:27 58369 8192000000 10300263728 10742124544 1358295488 14.222 98 38 75 1 1379127120 12585904 8245728
16:12:37 40222 8192000000 10297115248 10739576832 1369074880 14.319 99 27 55 1 1383695776 8732096 5888800
16:14:19 38040 8192000000 10293972528 10736738304 1364172016 14.275 98 33 66 1 1379741664 8848912 6720736
16:15:59 78241 8192000000 10286457888 10744647680 1381821904 14.433 100 34 68 0 1402978144 13590944 7565296
16:18:25 46974 8192000000 10284764288 10750132224 1388717824 14.494 100 15 31 1 1404539712 12034512 3787376
16:20:13 72283 8192000000 10308327856 10768441344 1388517888 14.493 99 17 34 0 1407605632 14958336 4129408
16:22:25 50676 8192000000 10302925168 10772967424 1372564352 14.350 98 19 38 0 1396151136 19062304 4524480
16:24:16 74994 8192000000 10293420160 10749284352 1349474720 14.143 96 22 44 1 1371136240 16652128 5009392
16:26:50 55772 8192000000 10291296112 10753744896 1361231008 14.248 98 20 40 0 1381999232 16234560 4533664
16:28:52 192714 8192000000 10299412112 10756067328 1305890912 13.749 94 12 24 1 1326031632 17882560 2258160
16:33:07 124339 8192000000 10296858208 10750984192 1362169648 14.257 98 32 64 1 1382009888 12888016 6952224
16:36:12 91546 8192000000 10261395200 10725703680 1323997856 13.913 96 7 14 1 1333943984 8499840 1446288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-06-2022 16:40:36 to 26-06-2022 16:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-06-2022 16:40:52 to 26-06-2022 16:40:54.

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          234893
MON$OLDEST_ACTIVE               236359
MON$OLDEST_SNAPSHOT             236359
MON$NEXT_TRANSACTION            236359
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                       2031840
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 ---
:450: 
:451: mscs-test-161.budzdorov.ru    Sun Jun 26 16:38:35 2022
:452:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:453: 
:454: 
:455: mscs-test-161.budzdorov.ru    Sun Jun 26 16:38:36 2022
:456:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:457: 
:458: 
:459: mscs-test-161.budzdorov.ru    Sun Jun 26 16:38:38 2022
:460:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:461: 
--- end of diff output ---

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

26.06.22 16:40:55. 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-26 16:40:57 No severe PSQL-related problems occured
26.06.22 16:40:58. End of report.