Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 22: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 15121840 kB
MemAvailable 30501488 kB
Buffers 0 kB
Cached 16612360 kB
SwapCached 1960 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.11
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-10-21 22:38:34
DTS_END                         2022-10-21 22:38:34.0750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(76:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39588
TRN_ID                          248667
ATT_ID                          733
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: 9490.52 24869 1138862
customer order: creation 175.98 339 21118
customer order: refuse 15.83 321 1899
order to supplier: creation 47.27 1225 5672
order to supplier: removal 3.27 100123 392
invoice (draft): creation 47.53 3495 5703
invoice (draft): removal 53.56 754 6427
invoice accept: apply 43.63 18108 5235
invoice accept: cancel 48.78 760 5853
customer reserve: creation 4028.66 180 483439
customer reserve: removal 4713.82 54 565658
realization accept: apply 87.12 165 10454
realization accept: cancel 88.27 106 10592
payment from customer: creation 62.73 89 7527
payment from customer: removal 5.83 56 700
payment to supplier: creation 52.58 130 6310
payment to supplier: removal 3.51 75 421
service: total inventory turnovers 4.70 1384 564
service: total monetary turnovers 6.58 38 789
service: aggregate perf. data 0.88 19305 105
service: refresh index statistics 0.03 350667 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-10-2022 22:41:07 to 21-10-2022 22:41:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4245
TEST_TIME 2 4350
TEST_TIME 3 4844
TEST_TIME 4 4260
TEST_TIME 5 4315
TEST_TIME 6 4662
TEST_TIME 7 5582
TEST_TIME 8 5965
TEST_TIME 9 6521
TEST_TIME 10 6449
TEST_TIME 11 6696
TEST_TIME 12 6689
TEST_TIME 13 6668
TEST_TIME 14 6671
TEST_TIME 15 6771
TEST_TIME 16 7145
TEST_TIME 17 7254
TEST_TIME 18 7900
TEST_TIME 19 8023
TEST_TIME 20 8034
TEST_TIME 21 8220
TEST_TIME 22 8598
TEST_TIME 23 8812
TEST_TIME 24 9092
TEST_TIME 25 9199
TEST_TIME 26 9346
TEST_TIME 27 9900
TEST_TIME 28 10308
TEST_TIME 29 10379
TEST_TIME 30 10865
TEST_TIME 31 10973
TEST_TIME 32 11136
TEST_TIME 33 11190
TEST_TIME 34 10997
TEST_TIME 35 10929
TEST_TIME 36 10922
TEST_TIME 37 11042
TEST_TIME 38 10897
TEST_TIME 39 10798
TEST_TIME 40 10852
TEST_TIME 41 10725
TEST_TIME 42 10487
TEST_TIME 43 10595
TEST_TIME 44 10422
TEST_TIME 45 10325
TEST_TIME 46 10285
TEST_TIME 47 10199
TEST_TIME 48 10160
TEST_TIME 49 10203
TEST_TIME 50 10326
TEST_TIME 51 10224
TEST_TIME 52 10139
TEST_TIME 53 10007
TEST_TIME 54 10088
TEST_TIME 55 10156
TEST_TIME 56 10129
TEST_TIME 57 10061
TEST_TIME 58 10016
TEST_TIME 59 10268
TEST_TIME 60 10288
TEST_TIME 61 10242
TEST_TIME 62 10298
TEST_TIME 63 10334
TEST_TIME 64 10345
TEST_TIME 65 10315
TEST_TIME 66 10239
TEST_TIME 67 10379
TEST_TIME 68 10258
TEST_TIME 69 10235
TEST_TIME 70 10153
TEST_TIME 71 10074
TEST_TIME 72 10125
TEST_TIME 73 10030
TEST_TIME 74 10057
TEST_TIME 75 10070
TEST_TIME 76 10028
TEST_TIME 77 9918
TEST_TIME 78 9965
TEST_TIME 79 9889
TEST_TIME 80 9989
TEST_TIME 81 9999
TEST_TIME 82 9985
TEST_TIME 83 9915
TEST_TIME 84 9950
TEST_TIME 85 9943
TEST_TIME 86 9935
TEST_TIME 87 9982
TEST_TIME 88 9939
TEST_TIME 89 9968
TEST_TIME 90 10054
TEST_TIME 91 10057
TEST_TIME 92 9986
TEST_TIME 93 9917
TEST_TIME 94 9900
TEST_TIME 95 9841
TEST_TIME 96 9805
TEST_TIME 97 9790
TEST_TIME 98 9770
TEST_TIME 99 9755
TEST_TIME 100 9740
TEST_TIME 101 9691
TEST_TIME 102 9702
TEST_TIME 103 9765
TEST_TIME 104 9758
TEST_TIME 105 9795
TEST_TIME 106 9749
TEST_TIME 107 9746
TEST_TIME 108 9809
TEST_TIME 109 9784
TEST_TIME 110 9752
TEST_TIME 111 9807
TEST_TIME 112 9807
TEST_TIME 113 9735
TEST_TIME 114 9701
TEST_TIME 115 9646
TEST_TIME 116 9629
TEST_TIME 117 9631
TEST_TIME 118 9588
TEST_TIME 119 9550
TEST_TIME 120 9497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2022 22:41:11 to 21-10-2022 22:41:14.

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 1585101 1585101 0 0.00 0 16577 6 0 0
doc_list_biud 1585101 1585101 0 0.00 0 38692 25 0 0
sp_add_invoice_to_stock 5358 5235 123 2.29 17 229843 18109 0 123
sp_cancel_adding_invoice 5853 5853 0 0.00 4 64061 760 0 0
sp_cancel_client_order 1899 1899 0 0.00 4 11461 321 0 0
sp_cancel_customer_reserve 565658 565658 0 0.00 0 22452 55 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 1367 56 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 4940 75 0 0
sp_cancel_supplier_invoice 6437 6427 10 0.15 2 38703 755 0 10
sp_cancel_supplier_order 392 392 0 0.00 6 1412155 100123 0 0
sp_cancel_write_off 10592 10592 0 0.00 1 20322 107 0 0
sp_client_order 21118 21118 0 0.00 3 15374 339 0 0
sp_customer_reserve 483439 483439 0 0.00 0 111262 181 0 0
sp_fill_shopping_cart 32486 32486 0 0.00 1 17664 42 0 0
sp_fill_shopping_cart_clo_res 483432 483432 0 0.00 0 18093 13 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 26152 303 0 0
sp_kill_cost_storno 17566 17566 0 0.00 0 9285 49 0 0
sp_kill_qstorno_ret_qs2qd 1148727 1148727 0 0.00 0 38690 30 0 0
sp_kill_qty_storno 606502 606502 0 0.00 0 38691 65 0 0
sp_lock_selected_doc 21715 21715 0 0.00 0 8248 3 0 0
sp_make_cost_storno 29518 29518 0 0.00 0 16572 46 0 0
sp_multiply_rows_for_pdistr 29518 29518 0 0.00 0 14652 26 0 0
sp_multiply_rows_for_qdistr 21118 21118 0 0.00 0 11782 146 0 0
sp_pay_from_customer 7527 7527 0 0.00 4 18217 89 0 0
sp_pay_to_supplier 6310 6310 0 0.00 4 16598 130 0 0
sp_payment_common 13837 13837 0 0.00 2 18192 25 0 0
sp_qd_handle_on_cancel_clo 1899 1899 0 0.00 0 11437 301 0 0
sp_qd_handle_on_invoice_upd_sts 11080 11080 0 0.00 0 33316 313 0 0
sp_qd_handle_on_reserve_upd_sts 10454 10454 0 0.00 0 16353 62 0 0
sp_reserve_write_off 10454 10454 0 0.00 6 16371 165 0 0
sp_supplier_invoice 5703 5703 0 0.00 5 103029 3495 0 0
sp_supplier_order 5672 5672 0 0.00 5 29589 1226 0 0
srv_aggregate_perf_data 105 105 0 0.00 3066 128753 19306 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1837 131913 45170 0 0
srv_find_qd_qs_mism 1088329 1088329 0 0.00 0 10269 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 45 86657 1384 0 0
srv_make_money_saldo 789 789 0 0.00 1 4785 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 141704 470632 350668 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5236 5236 0 0.00 0 9674 180 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 3002 116 0 0
t$perf-norm:sp_cancel_client_order 1899 1899 0 0.00 0 3243 13 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 517 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 4922 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 857 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 3626 15 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 15801 1385 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 646 5 0 0
t$perf-norm:sp_client_order 21118 21118 0 0.00 0 9302 15 0 0
t$perf-norm:sp_customer_reserve 1106 1106 0 0.00 0 1142 5 0 0
t$perf-norm:sp_pay_from_customer 7527 7527 0 0.00 0 4386 10 0 0
t$perf-norm:sp_pay_to_supplier 6310 6310 0 0.00 0 5288 10 0 0
t$perf-norm:sp_reserve_write_off 10454 10454 0 0.00 0 3666 9 0 0
t$perf-norm:sp_supplier_invoice 5703 5703 0 0.00 0 5596 18 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 4223 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 223 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 391 9 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1347 28 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 3249 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10696 10450 246 2.29 0 4113 17 0 246
v_all_customers 38694 38694 0 0.00 0 22 1 0 0
v_all_suppliers 31846 31846 0 0.00 0 138 1 0 0
v_all_wares 42234 42234 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 715 8 0 0
v_cancel_client_order 3798 3798 0 0.00 0 1967 3 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 741 3 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 141 3 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 4932 10 0 0
v_cancel_supplier_invoice 908 888 20 2.20 0 5581 22 0 20
v_cancel_supplier_order 784 784 0 0.00 0 525 4 0 0
v_cancel_write_off 2082 2082 0 0.00 0 5658 19 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 2549 2 0 0
v_max_id_clo_res 1106 1106 0 0.00 0 18894 43 0 0
v_max_id_ord_sup 5698 5698 0 0.00 0 4955 7 0 0
v_max_non_paid_invoice 1756 1756 0 0.00 0 1780 4 0 0
v_max_non_paid_realizn 4006 4006 0 0.00 0 1781 4 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 5846 9 0 0
v_min_id_clo_res 1106 1106 0 0.00 0 6387 13 0 0
v_min_id_ord_sup 5698 5698 0 0.00 0 6572 10 0 0
v_min_non_paid_invoice 6310 6310 0 0.00 0 10132 19 0 0
v_min_non_paid_realizn 7526 7526 0 0.00 0 4770 15 0 0
v_our_firm 10582 10582 0 0.00 0 17 1 0 0
v_reserve_write_off 20908 20908 0 0.00 0 5773 6 0 0
x_lock_depdocs_on_canc_invoice 11836 11836 0 0.00 0 28874 110 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 7456 407 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 1 29555 1144 0 0
x_make_qstorno_1200_2000 5703 5703 0 0.00 2 103013 3339 0 0
x_make_qstorno_2100_3300 483439 483439 0 0.00 0 111258 165 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-10-2022 22:41:15 to 21-10-2022 22:41:20.

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
20:08:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:37 18083 8192000000 9907569600 10217181184 996220400 10.842 100 89 178 0 1024919088 5884288 22814400
20:11:01 32968 8192000000 9956761376 10281074688 1039023472 11.255 100 90 180 0 1069966320 8207520 22735328
20:12:46 10909 8192000000 9983121376 10315919360 1069909648 11.551 100 84 166 1 1100074720 9492304 20672768
20:14:08 6692 8192000000 10033380048 10381537280 1124506928 12.070 100 75 150 1 1152430416 11052304 16871184
20:15:17 6511 8192000000 10082064224 10441785344 1172432144 12.520 100 79 158 0 1202472816 12470880 17569792
20:16:28 4087 8192000000 10125590880 10491244544 1210222064 12.871 100 81 161 0 1241968800 13671408 18075328
20:17:34 1874 8192000000 10173629888 10547245056 1252008208 13.257 100 81 160 0 1285633216 16300016 17324992
20:18:37 1858 8192000000 10202269200 10586046464 1287149632 13.578 100 85 167 0 1318294992 12977296 18168064
20:19:41 1520 8192000000 10217676784 10604531712 1302347936 13.717 100 86 169 2 1334122192 13289408 18484848
20:20:45 6588 8192000000 10226160256 10614484992 1311545040 13.800 100 85 170 2 1341665216 11510512 18609664
20:21:52 8614 8192000000 10203112464 10613661696 1314906880 13.831 100 24 48 0 1326414960 6187280 5320800
20:23:02 7338 8192000000 10211093648 10623782912 1322689168 13.901 100 32 65 0 1336666352 6919904 7057280
20:24:12 14704 8192000000 10214982592 10631004160 1328497600 13.954 100 14 28 0 1337126192 5763568 2865024
20:25:28 29759 8192000000 10228608336 10647142400 1333807632 14.002 100 41 81 0 1349698512 6274048 9616832
20:26:58 4686 8192000000 10230704736 10650263552 1337374144 14.034 100 32 62 1 1351048384 6661472 7012768
20:28:06 6858 8192000000 10229279520 10648358912 1323888576 13.912 99 13 26 0 1332003392 5240960 2873856
20:29:14 16132 8192000000 10189691392 10600833024 1246765952 13.208 95 29 59 0 1259779168 6098592 6914624
20:30:34 30398 8192000000 10148408112 10547539968 1193765904 12.718 94 35 70 0 1207132800 5043472 8323424
20:32:04 8437 8192000000 10118628000 10515705856 1207728912 12.848 97 26 52 1 1218914160 5190512 5994736
20:33:17 8113 8192000000 10155278560 10558758912 1260049120 13.330 100 20 38 0 1269885936 5106176 4730640
20:34:28 5859 8192000000 10163914912 10575323136 1276169040 13.478 100 14 29 1 1285304704 5498720 3636944
20:35:36 28407 8192000000 10171098656 10590027776 1288334368 13.589 100 24 48 0 1298885856 5132848 5418640
20:37:05 9677 8192000000 10198544544 10609082368 1303096976 13.723 100 22 45 2 1312925632 4829248 4999408
20:38:15 12285 8192000000 10214723360 10634297344 1324412064 13.917 100 21 43 0 1334291360 4856464 5022832
20:39:33 15519 8192000000 10251710880 10665799680 1346028128 14.112 100 40 80 1 1367084816 11543232 9513456
20:40:49 39096 8192000000 10270350224 10691203072 1359593872 14.234 100 45 91 0 1382841904 12299664 10948368
20:42:36 63217 8192000000 10276558032 10705408000 1369121616 14.319 100 39 76 0 1394440848 16476272 8842960
20:44:43 14657 8192000000 10303217920 10723577856 1381367776 14.429 100 49 98 0 1412951648 19711984 11871888
20:46:00 3661 8192000000 10318023664 10737913856 1390225600 14.508 100 63 126 0 1426851168 21701840 14923728
20:47:07 9242 8192000000 10316328416 10744168448 1399025920 14.586 100 52 104 1 1430477984 19037744 12414320
20:48:21 7011 8192000000 10320029248 10744045568 1394981568 14.550 100 66 130 3 1432893824 22225872 15686384
20:49:32 7171 8192000000 10339144016 10770104320 1411921472 14.701 100 58 114 1 1450090768 24477536 13691760
20:50:43 7258 8192000000 10334281008 10765881344 1400319504 14.598 99 50 100 1 1435562816 23018624 12224688
20:51:58 6472 8192000000 10366572432 10784075776 1422730208 14.797 100 79 153 2 1468237152 28048192 17458752
20:53:14 1835 8192000000 10350892832 10761031680 1400249776 14.597 100 83 161 0 1450843344 30674272 19919296
20:54:21 3352 8192000000 10338544048 10752139264 1384571008 14.457 99 78 158 0 1441604848 37328880 19704960
20:55:33 2575 8192000000 10322346240 10727206912 1365934416 14.291 100 85 166 2 1426171184 39002960 21233808
20:56:38 3377 8192000000 10325896176 10733248512 1374457664 14.367 100 71 139 2 1427232896 35577264 17197968
20:57:43 4494 8192000000 10293077120 10708439040 1344075296 14.094 99 47 94 1 1391656176 35391840 12189040
20:58:51 4234 8192000000 10308944656 10712473600 1349910096 14.147 99 69 135 1 1408767504 41903664 16953744
20:59:59 6468 8192000000 10334039280 10756341760 1384742736 14.459 100 43 86 1 1442956208 47191904 11021568
21:01:09 15967 8192000000 10332822496 10770956288 1393198720 14.534 100 41 82 0 1448137440 44097056 10841664
21:02:30 21024 8192000000 10320090000 10755477504 1373930288 14.362 100 31 62 0 1431416112 48440416 9045408
21:04:12 2632 8192000000 10329794128 10739712000 1353209712 14.176 99 68 137 5 1419058256 48780160 17068384
21:05:17 21764 8192000000 10300601760 10747142144 1358205408 14.221 99 29 58 0 1415677488 48934640 8537440
21:06:41 35656 8192000000 10308729456 10746839040 1371690336 14.342 100 28 54 0 1405834624 27228528 6915760
21:08:18 46992 8192000000 10274416000 10732089344 1348845728 14.137 99 13 24 1 1387476608 35482976 3147904
21:10:28 99156 8192000000 10281051936 10735644672 1362948144 14.264 100 9 18 0 1381510528 16833360 1729024
21:13:34 29386 8192000000 10290192960 10732351488 1363094080 14.265 99 11 22 0 1374231392 8642640 2494672
21:15:04 131913 8192000000 10303547248 10748641280 1375205344 14.374 99 21 42 0 1397509728 17108224 5196160
21:18:24 78144 8192000000 10299766912 10750025728 1378926688 14.407 99 31 61 0 1396835232 11486160 6422384
21:20:49 100791 8192000000 10312500688 10762432512 1394371008 14.545 100 33 66 1 1416157184 14611552 7174624
21:23:33 86142 8192000000 10302420416 10764791808 1378474224 14.403 98 17 34 0 1394694320 12444864 3775232
21:26:08 47245 8192000000 10326037680 10781130752 1403034480 14.622 100 27 50 0 1429090320 19216896 6838944
21:28:02 51710 8192000000 10302967584 10780168192 1389869840 14.505 99 12 24 0 1410133952 17225024 3039088
21:30:02 46999 8192000000 10308343968 10769408000 1400936624 14.603 100 17 34 1 1414487232 9636704 3913904
21:32:27 54687 8192000000 10305453248 10764374016 1362686592 14.261 97 9 18 0 1375798176 11275152 1836432
21:34:25 28312 8192000000 10285628208 10743517184 1377510784 14.394 99 32 62 0 1397977968 13519088 6948096
21:35:54 41793 8192000000 10292842928 10751549440 1358701936 14.226 98 16 32 0 1379789136 17346896 3740304
21:37:37 89118 8192000000 10265780816 10711867392 1275017920 13.467 93 7 14 0 1286371600 10001184 1352496
21:40:21 67627 8192000000 10258891296 10700083200 1294661648 13.647 95 16 30 0 1312078336 13936640 3480048
21:42:38 67648 8192000000 10283560016 10727325696 1319200496 13.869 96 18 34 0 1337872928 14515472 4156960
21:44:46 69489 8192000000 10268822144 10734936064 1353110832 14.175 99 21 42 0 1377490976 18902432 5477712
21:47:01 28797 8192000000 10282989744 10728689664 1353575968 14.180 99 42 84 2 1372390512 9554624 9259920
21:48:36 26407 8192000000 10289526672 10721357824 1358151600 14.221 100 58 114 1 1383825264 12838368 12835296
21:50:10 57143 8192000000 10269338256 10731548672 1365334224 14.285 100 32 64 0 1387991856 15356880 7300752
21:52:11 55128 8192000000 10289298192 10748633088 1369932304 14.326 99 16 32 0 1390390336 16528160 3929872
21:54:10 60216 8192000000 10268439136 10734489600 1345220336 14.104 98 3 6 0 1361670464 15817536 632592
21:56:11 48130 8192000000 10270490256 10729443328 1338459328 14.044 98 27 53 0 1362433536 17355440 6618768
21:57:59 72026 8192000000 10271911520 10735104000 1340568448 14.063 98 12 24 0 1357596784 14727888 2300448
22:00:14 50043 8192000000 10284828656 10729156608 1358724144 14.226 99 28 53 2 1372999408 8947776 5327488
22:02:05 67446 8192000000 10285447504 10739314688 1353325696 14.177 98 26 51 0 1367935488 9171136 5438656
22:04:16 62747 8192000000 10288353696 10745446400 1353914576 14.183 98 20 40 0 1371575408 13401056 4259776
22:06:19 27549 8192000000 10300537120 10744975360 1369972192 14.327 99 23 46 0 1385611584 10719856 4919536
22:07:50 81514 8192000000 10296370448 10772217856 1370068912 14.328 99 9 18 0 1392482736 20840752 1573072
22:10:12 124874 8192000000 10308602320 10763915264 1377531648 14.394 99 24 48 0 1397008272 13993296 5483328
22:13:21 40626 8192000000 10289981696 10749194240 1365671856 14.288 98 29 58 1 1384916272 12798384 6446032
22:15:18 68064 8192000000 10280711712 10748821504 1360943408 14.246 98 11 21 0 1370420208 7179712 2297088
22:17:30 41398 8192000000 10288194816 10755571712 1386283728 14.473 100 13 26 2 1398728576 9321872 3122976
22:19:29 39653 8192000000 10299363184 10752876544 1355276608 14.195 97 34 66 0 1376676592 12988208 8411776
22:21:09 59973 8192000000 10285387968 10746273792 1335924176 14.021 96 16 32 2 1356471696 16429536 4117984
22:23:11 66317 8192000000 10285621712 10753994752 1371426288 14.340 99 19 38 0 1386188048 10679984 4081776
22:25:18 123592 8192000000 10286980560 10748878848 1340058848 14.058 97 19 38 0 1358053968 14052336 3942784
22:28:22 19353 8192000000 10286096544 10723532800 1350685008 14.154 99 39 78 0 1371763264 12412736 8665520
22:29:48 89418 8192000000 10260768656 10721345536 1306236384 13.752 96 15 30 0 1326869168 17872768 2760016
22:32:18 70287 8192000000 10249000096 10696216576 1312936576 13.813 97 19 38 0 1325541728 8347696 4257456
22:34:29 38645 8192000000 10248721232 10705190912 1339337280 14.051 99 17 32 0 1350115392 7129136 3648976
22:36:15 38450 8192000000 10255330752 10717097984 1357115088 14.211 100 8 14 0 1370112128 11626416 1370624
22:38:02 44385 8192000000 9813821360 10203758592 945942544 10.351 69 11 22 0 959474272 11322832 2208896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2022 22:41:22 to 21-10-2022 22:41:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 22:41:38 to 21-10-2022 22:41: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          248152
MON$OLDEST_ACTIVE               248856
MON$OLDEST_SNAPSHOT             248856
MON$NEXT_TRANSACTION            248856
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                       2067488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Oct 21 22:38:54 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Oct 21 22:39:03 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Fri Oct 21 22:39:18 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
--- end of diff output ---

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

21.10.22 22:41:41. 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-10-21 22:41:44 No severe PSQL-related problems occured
21.10.22 22:41:45. End of report.