Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.07.2022 19: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 15826300 kB
MemAvailable 31057840 kB
Buffers 0 kB
Cached 15913428 kB
SwapCached 2552 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-07-26 19:38:24
DTS_END                         2022-07-26 19:38:24.8320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45330
TRN_ID                          245377
ATT_ID                          663
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: 9627.35 16793 1155282
customer order: creation 181.90 345 21828
customer order: refuse 16.00 347 1920
order to supplier: creation 47.68 1287 5722
order to supplier: removal 3.47 107209 416
invoice (draft): creation 47.45 3540 5694
invoice (draft): removal 54.91 816 6589
invoice accept: apply 42.80 18716 5136
invoice accept: cancel 49.38 797 5925
customer reserve: creation 4006.45 184 480774
customer reserve: removal 4871.20 60 584544
realization accept: apply 87.34 189 10481
realization accept: cancel 85.25 115 10230
payment from customer: creation 60.72 99 7286
payment from customer: removal 5.55 87 666
payment to supplier: creation 51.21 114 6145
payment to supplier: removal 3.43 134 411
service: total inventory turnovers 4.49 1046 539
service: total monetary turnovers 7.16 30 859
service: aggregate perf. data 0.94 15756 113
service: refresh index statistics 0.03 184990 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-07-2022 19:40:34 to 26-07-2022 19:40:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6539
TEST_TIME 2 5394
TEST_TIME 3 5612
TEST_TIME 4 4917
TEST_TIME 5 5172
TEST_TIME 6 5446
TEST_TIME 7 5515
TEST_TIME 8 5423
TEST_TIME 9 5712
TEST_TIME 10 6318
TEST_TIME 11 6244
TEST_TIME 12 6251
TEST_TIME 13 6306
TEST_TIME 14 6860
TEST_TIME 15 6816
TEST_TIME 16 6913
TEST_TIME 17 6990
TEST_TIME 18 7039
TEST_TIME 19 7056
TEST_TIME 20 7320
TEST_TIME 21 7766
TEST_TIME 22 7787
TEST_TIME 23 8158
TEST_TIME 24 8415
TEST_TIME 25 8490
TEST_TIME 26 8887
TEST_TIME 27 9000
TEST_TIME 28 9109
TEST_TIME 29 9707
TEST_TIME 30 10839
TEST_TIME 31 10775
TEST_TIME 32 10593
TEST_TIME 33 10701
TEST_TIME 34 10665
TEST_TIME 35 10463
TEST_TIME 36 10406
TEST_TIME 37 10219
TEST_TIME 38 10060
TEST_TIME 39 10341
TEST_TIME 40 10267
TEST_TIME 41 10247
TEST_TIME 42 10110
TEST_TIME 43 10080
TEST_TIME 44 10014
TEST_TIME 45 9987
TEST_TIME 46 10115
TEST_TIME 47 10110
TEST_TIME 48 10080
TEST_TIME 49 9990
TEST_TIME 50 9954
TEST_TIME 51 9776
TEST_TIME 52 9929
TEST_TIME 53 9844
TEST_TIME 54 9822
TEST_TIME 55 9784
TEST_TIME 56 9824
TEST_TIME 57 9778
TEST_TIME 58 9731
TEST_TIME 59 9701
TEST_TIME 60 9877
TEST_TIME 61 9847
TEST_TIME 62 9939
TEST_TIME 63 9954
TEST_TIME 64 9989
TEST_TIME 65 9994
TEST_TIME 66 9960
TEST_TIME 67 10064
TEST_TIME 68 10006
TEST_TIME 69 10045
TEST_TIME 70 10000
TEST_TIME 71 9950
TEST_TIME 72 9911
TEST_TIME 73 9932
TEST_TIME 74 9877
TEST_TIME 75 9898
TEST_TIME 76 9870
TEST_TIME 77 9800
TEST_TIME 78 9785
TEST_TIME 79 9738
TEST_TIME 80 9630
TEST_TIME 81 9716
TEST_TIME 82 9668
TEST_TIME 83 9668
TEST_TIME 84 9692
TEST_TIME 85 9664
TEST_TIME 86 9663
TEST_TIME 87 9739
TEST_TIME 88 9720
TEST_TIME 89 9703
TEST_TIME 90 9720
TEST_TIME 91 9731
TEST_TIME 92 9791
TEST_TIME 93 9809
TEST_TIME 94 9766
TEST_TIME 95 9821
TEST_TIME 96 9826
TEST_TIME 97 9764
TEST_TIME 98 9716
TEST_TIME 99 9672
TEST_TIME 100 9710
TEST_TIME 101 9700
TEST_TIME 102 9641
TEST_TIME 103 9620
TEST_TIME 104 9657
TEST_TIME 105 9618
TEST_TIME 106 9616
TEST_TIME 107 9645
TEST_TIME 108 9647
TEST_TIME 109 9614
TEST_TIME 110 9620
TEST_TIME 111 9697
TEST_TIME 112 9681
TEST_TIME 113 9674
TEST_TIME 114 9690
TEST_TIME 115 9725
TEST_TIME 116 9791
TEST_TIME 117 9810
TEST_TIME 118 9734
TEST_TIME 119 9668
TEST_TIME 120 9629

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-07-2022 19:40:37 to 26-07-2022 19:40:41.

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 1600758 1600758 0 0.00 0 15697 6 0 0
doc_list_biud 1600758 1600758 0 0.00 0 27897 28 0 0
sp_add_invoice_to_stock 5209 5136 73 1.40 43 212144 18717 0 73
sp_cancel_adding_invoice 5925 5925 0 0.00 4 60233 798 0 0
sp_cancel_client_order 1920 1920 0 0.00 4 11666 348 0 0
sp_cancel_customer_reserve 584544 584544 0 0.00 0 24187 60 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 6221 88 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 6787 134 0 0
sp_cancel_supplier_invoice 6596 6589 7 0.10 1 14358 817 0 7
sp_cancel_supplier_order 416 416 0 0.00 5 1176440 107209 0 0
sp_cancel_write_off 10230 10230 0 0.00 1 14178 115 0 0
sp_client_order 21828 21828 0 0.00 3 48628 345 0 0
sp_customer_reserve 480774 480774 0 0.00 0 109571 185 0 0
sp_fill_shopping_cart 33236 33236 0 0.00 1 13296 40 0 0
sp_fill_shopping_cart_clo_res 480771 480771 0 0.00 0 14646 12 0 0
sp_get_clo_for_invoice 5133 5133 0 0.00 0 24241 281 0 0
sp_kill_cost_storno 17232 17232 0 0.00 0 15687 56 0 0
sp_kill_qstorno_ret_qs2qd 1186323 1186323 0 0.00 0 27896 33 0 0
sp_kill_qty_storno 625238 625238 0 0.00 0 27897 71 0 0
sp_lock_selected_doc 21698 21698 0 0.00 0 7683 7 0 0
sp_make_cost_storno 29045 29045 0 0.00 0 10955 50 0 0
sp_multiply_rows_for_pdistr 29045 29045 0 0.00 0 6882 27 0 0
sp_multiply_rows_for_qdistr 21828 21828 0 0.00 0 44001 146 0 0
sp_pay_from_customer 7286 7286 0 0.00 4 8492 99 0 0
sp_pay_to_supplier 6145 6145 0 0.00 4 12046 115 0 0
sp_payment_common 13431 13431 0 0.00 1 7888 26 0 0
sp_qd_handle_on_cancel_clo 1920 1920 0 0.00 0 11660 322 0 0
sp_qd_handle_on_invoice_upd_sts 11058 11058 0 0.00 0 21360 328 0 0
sp_qd_handle_on_reserve_upd_sts 10481 10481 0 0.00 0 18297 64 0 0
sp_reserve_write_off 10481 10481 0 0.00 6 18310 189 0 0
sp_supplier_invoice 5694 5694 0 0.00 6 101786 3540 0 0
sp_supplier_order 5722 5722 0 0.00 4 20807 1288 0 0
srv_aggregate_perf_data 113 113 0 0.00 2757 144304 15756 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 843 115626 41442 0 0
srv_find_qd_qs_mism 1105749 1105749 0 0.00 0 13271 6 0 0
srv_make_invnt_saldo 539 539 0 0.00 27 59911 1046 0 0
srv_make_money_saldo 859 859 0 0.00 0 1931 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 120651 273028 184991 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5136 5136 0 0.00 0 16845 186 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 10627 129 0 0
t$perf-norm:sp_cancel_client_order 1920 1920 0 0.00 0 4978 16 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 10162 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 2630 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 615 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 11118 51 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 24057 1705 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 7644 23 0 0
t$perf-norm:sp_client_order 21828 21828 0 0.00 0 12422 18 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 3661 10 0 0
t$perf-norm:sp_pay_from_customer 7286 7286 0 0.00 0 13717 12 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 14807 13 0 0
t$perf-norm:sp_reserve_write_off 10481 10481 0 0.00 0 8840 14 0 0
t$perf-norm:sp_supplier_invoice 5694 5694 0 0.00 0 10837 18 0 0
t$perf-norm:sp_supplier_order 5722 5722 0 0.00 0 6968 18 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 479 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 229 10 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 3127 26 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 4379 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 44 14 0 0
v_add_invoice_to_stock 10404 10258 146 1.40 0 7133 15 0 146
v_all_customers 39876 39876 0 0.00 0 26 1 0 0
v_all_suppliers 31970 31970 0 0.00 0 35 1 0 0
v_all_wares 43656 43656 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 1700 11 0 0
v_cancel_client_order 3840 3840 0 0.00 0 2476 4 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 6200 15 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 4147 12 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 6762 23 0 0
v_cancel_supplier_invoice 886 872 14 1.58 0 3863 21 0 14
v_cancel_supplier_order 832 832 0 0.00 0 266 3 0 0
v_cancel_write_off 2138 2138 0 0.00 0 4330 15 0 0
v_max_id_clo_ord 5722 5722 0 0.00 0 372 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1591 13 0 0
v_max_id_ord_sup 5686 5686 0 0.00 0 2752 4 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 2122 4 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 4082 6 0 0
v_min_id_clo_ord 5722 5722 0 0.00 0 13057 13 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 1148 4 0 0
v_min_id_ord_sup 5686 5686 0 0.00 0 6803 16 0 0
v_min_non_paid_invoice 6145 6145 0 0.00 0 7884 17 0 0
v_min_non_paid_realizn 7286 7286 0 0.00 0 6884 19 0 0
v_our_firm 10828 10828 0 0.00 0 18 1 0 0
v_reserve_write_off 20962 20962 0 0.00 0 6202 7 0 0
x_lock_depdocs_on_canc_invoice 12078 12078 0 0.00 0 13785 111 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 17677 383 0 0
x_make_qstorno_1000_1200 5722 5722 0 0.00 1 20798 1206 0 0
x_make_qstorno_1200_2000 5694 5694 0 0.00 3 101747 3387 0 0
x_make_qstorno_2100_3300 480774 480774 0 0.00 0 109569 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-07-2022 19:40:42 to 26-07-2022 19:40:46.

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:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:29 17924 8192000000 9886746048 10191433728 968292256 10.570 100 92 185 0 998357680 5544816 24520608
17:10:52 28308 8192000000 9942721024 10262679552 1024945840 11.120 100 94 188 0 1056963552 7399376 24618336
17:12:22 14557 8192000000 9956704976 10287226880 1044678480 11.310 100 82 164 0 1074921072 8912480 21330112
17:13:47 9030 8192000000 10017379776 10360422400 1103173584 11.868 100 83 164 0 1132598608 9866960 19558064
17:15:03 5626 8192000000 10069316576 10426011648 1157637072 12.381 100 77 150 0 1186134960 11243088 17254800
17:16:11 3088 8192000000 10117881696 10482978816 1205770336 12.830 100 83 167 2 1238095104 13948416 18376352
17:17:17 2740 8192000000 10143655216 10518200320 1239040672 13.137 100 82 161 0 1269437312 13016352 17380288
17:18:21 1303 8192000000 10190399648 10569228288 1273973248 13.458 100 92 182 0 1307759824 12861200 20925376
17:19:24 1202 8192000000 10201279712 10587299840 1286856752 13.576 100 87 170 4 1318721968 12837072 19028144
17:20:26 5839 8192000000 10189263056 10594738176 1301118528 13.705 100 38 76 0 1317295024 7847648 8328848
17:21:33 62787 8192000000 10197212176 10613231616 1303447952 13.727 100 35 70 0 1321848816 10243200 8157664
17:23:36 6091 8192000000 10199627568 10616999936 1310100896 13.787 100 19 39 1 1320345728 5964816 4280016
17:24:46 6358 8192000000 10211076960 10630508544 1322854736 13.903 100 22 44 1 1333945408 5921600 5169072
17:25:54 75861 8192000000 10214689200 10638589952 1327883488 13.948 100 19 38 0 1337226864 4867232 4476144
17:28:11 4863 8192000000 10216023456 10630873088 1320162240 13.878 100 30 58 0 1333528720 7138432 6228048
17:29:16 7100 8192000000 10183370496 10592387072 1251878736 13.255 96 23 48 1 1262402128 4714288 5809104
17:30:25 75397 8192000000 10119435696 10517463040 1077334192 11.622 84 23 47 0 1087226672 4629456 5263024
17:32:44 8014 8192000000 10139606112 10540408832 1230057008 13.055 97 13 26 0 1238119152 5130608 2931536
17:33:56 5268 8192000000 10169094064 10564866048 1266309808 13.388 99 34 68 2 1279991536 6051680 7630048
17:35:03 13032 8192000000 10182465008 10597888000 1298939440 13.686 100 19 38 0 1309596592 5787648 4869504
17:36:48 6637 8192000000 10194599664 10606845952 1301455472 13.708 100 31 62 0 1315254256 6917488 6881296
17:37:55 6222 8192000000 10209365488 10624262144 1314431200 13.826 100 23 46 0 1325945760 6568784 4945776
17:39:09 31688 8192000000 10246704928 10659622912 1329609728 13.964 99 51 100 0 1349592832 8569136 11413968
17:40:49 23112 8192000000 10264038976 10686087168 1357343088 14.213 100 49 97 1 1379156800 11109200 10704512
17:42:20 9804 8192000000 10282731872 10704633856 1373522736 14.359 100 31 62 0 1392276624 11845776 6908112
17:43:35 6649 8192000000 10291749408 10713939968 1381858752 14.433 100 50 100 0 1408484912 15039168 11586992
17:44:47 10334 8192000000 10309664848 10733420544 1390546400 14.511 100 58 108 1 1418190144 15762560 11881184
17:46:05 22083 8192000000 10318198752 10742173696 1401085024 14.605 100 50 96 1 1431788096 19451696 11251376
17:47:32 8820 8192000000 10317774768 10741309440 1382451328 14.438 98 52 104 1 1420642416 25254784 12936304
17:48:45 12021 8192000000 10318679264 10748231680 1387952832 14.488 99 37 73 1 1418997056 22054192 8990032
17:50:01 4305 8192000000 10332221072 10755301376 1399179600 14.588 99 61 123 0 1442002224 27211296 15611328
17:51:09 19779 8192000000 10358347872 10786521088 1421302576 14.784 100 55 110 0 1467047792 32088256 13656960
17:52:32 3582 8192000000 10361547760 10781794304 1415980992 14.737 100 76 148 2 1469628432 35369552 18277888
17:53:39 6378 8192000000 10346518064 10767687680 1406247408 14.651 100 65 130 0 1455408032 33378192 15782432
17:54:52 838 8192000000 10363444080 10775851008 1411275904 14.695 100 85 168 0 1465581424 34270800 20034720
17:56:04 3675 8192000000 10360985984 10783686656 1398701840 14.583 98 64 125 0 1455459552 40677648 16080064
17:57:13 6793 8192000000 10365640336 10785390592 1411549200 14.698 100 64 121 0 1472376240 45055920 15771120
17:58:26 4109 8192000000 10384929104 10804424704 1417342336 14.749 100 74 142 0 1488999088 53468400 18188352
17:59:34 17244 8192000000 10311919632 10740973568 1345121248 14.104 97 37 74 0 1401825648 46396064 10308336
18:00:57 2492 8192000000 10376710960 10789625856 1403034240 14.622 100 76 152 0 1479890528 56891728 19964560
18:02:02 10029 8192000000 10351077488 10778935296 1397472800 14.572 100 34 68 1 1459490064 52466720 9550544
18:03:19 9509 8192000000 10362202864 10790838272 1389785840 14.504 100 45 88 0 1463864496 62410352 11668304
18:04:50 16945 8192000000 10317009504 10749624320 1367128416 14.301 100 37 73 0 1431649632 53964144 10557072
18:06:24 14041 8192000000 10310958304 10750611456 1347532464 14.125 99 33 67 0 1413603504 56263040 9808000
18:07:42 36001 8192000000 10282257120 10732769280 1321788064 13.893 98 12 24 0 1368734112 43071360 3874688
18:09:33 47100 8192000000 10254956000 10687344640 1318313648 13.861 97 33 64 1 1332610928 6503280 7794000
18:11:21 60307 8192000000 10270090240 10713202688 1333931264 14.003 98 8 16 0 1343621088 8407856 1281968
18:14:07 18296 8192000000 10294938336 10732675072 1379478288 14.412 100 40 79 1 1398968832 10630224 8860320
18:15:27 89854 8192000000 10308059952 10749435904 1382668928 14.440 100 34 68 0 1406015616 15188608 8158080
18:17:59 61230 8192000000 10302525360 10755719168 1380790800 14.424 99 24 48 0 1396962240 10604896 5566544
18:20:04 57986 8192000000 10315982256 10765754368 1405633472 14.645 100 35 70 1 1426864208 12970928 8259808
18:22:04 24000 8192000000 10315309680 10757931008 1392611136 14.529 100 23 46 0 1409280560 11300768 5368656
18:23:30 25866 8192000000 10327598224 10773655552 1398734752 14.584 100 54 108 0 1429373360 18348656 12289952
18:25:02 93132 8192000000 10322468096 10797404160 1410289760 14.687 100 15 30 0 1432372896 18187504 3895632
18:27:36 20424 8192000000 10330724912 10789466112 1415564496 14.733 100 30 61 1 1437450384 15421200 6464688
18:29:25 96986 8192000000 10314851552 10797158400 1411684416 14.699 100 19 38 0 1433063632 17184992 4194224
18:32:05 58105 8192000000 10298969904 10773549056 1404495312 14.635 100 15 30 0 1418500400 10592448 3412640
18:34:10 75133 8192000000 10298581408 10779439104 1390587504 14.511 99 3 4 0 1403300208 12623632 89072
18:36:25 42725 8192000000 10315391824 10778583040 1400024368 14.595 99 20 39 0 1417408288 12694272 4689648
18:38:34 32547 8192000000 10297480944 10757967872 1399039008 14.586 100 19 38 1 1412629504 9516128 4074368
18:40:07 63803 8192000000 10281377696 10729996288 1309315696 13.780 94 23 46 0 1325289856 11001504 4972656
18:42:16 67913 8192000000 10280164592 10734366720 1310946848 13.795 95 20 38 0 1327044432 12268864 3828720
18:44:27 26705 8192000000 10253431968 10683781120 1309093184 13.778 97 35 70 0 1329532640 12645248 7794208
18:45:54 110369 8192000000 10267630944 10715967488 1260050448 13.330 92 9 19 0 1275527088 13112112 2364528
18:48:49 115626 8192000000 10269442032 10724605952 1342914544 14.084 99 23 46 1 1369723296 21001584 5807168
18:51:49 53842 8192000000 10257832224 10703708160 1328166928 13.951 98 29 56 1 1348709968 14116256 6426784
18:53:44 64187 8192000000 10235695344 10689691648 1316644592 13.846 97 11 20 1 1331604928 12318560 2641776
18:55:48 41260 8192000000 10246105952 10688364544 1329841504 13.966 99 32 64 0 1346071440 8485904 7744032
18:57:33 44347 8192000000 10254167264 10712100864 1358309216 14.222 100 16 32 0 1375471616 13635296 3527104
18:59:39 41920 8192000000 10254257888 10711556096 1331991088 13.985 98 16 30 0 1345819968 10499056 3329824
19:01:23 59291 8192000000 10262558944 10722357248 1325865856 13.930 97 9 18 0 1339419728 11560608 1993264
19:03:23 27501 8192000000 10278443696 10726547456 1363159584 14.266 99 38 71 0 1383799408 12210480 8429344
19:04:55 40226 8192000000 10289488592 10748456960 1375402672 14.375 100 24 48 0 1392658064 11454256 5801136
19:06:56 16969 8192000000 10285517984 10729172992 1350983504 14.156 98 37 73 0 1368326032 9313072 8029456
19:08:21 17842 8192000000 10285206496 10726490112 1370645120 14.333 99 37 73 0 1389585888 11331728 7609040
19:09:43 78798 8192000000 10306930544 10768056320 1399098016 14.587 100 12 24 0 1413439088 11572144 2768928
19:12:03 44410 8192000000 10324727504 10770685952 1382677408 14.440 98 25 49 2 1400256704 12086640 5492656
19:13:51 77379 8192000000 10314744752 10771390464 1394545104 14.546 99 16 30 0 1412330672 13561840 4223728
19:16:11 96577 8192000000 10314106288 10773581824 1392302448 14.526 98 14 28 0 1406400464 10600784 3497232
19:18:48 86654 8192000000 10313322048 10795769856 1402967616 14.621 99 9 18 0 1421939952 16703952 2268384
19:21:14 58229 8192000000 10315146672 10789601280 1421840416 14.789 100 16 31 0 1437588768 12057552 3690800
19:23:14 37074 8192000000 10291894528 10746662912 1362372496 14.259 96 14 28 0 1371386048 6182016 2831536
19:24:58 60020 8192000000 10277001024 10730950656 1341092016 14.067 96 10 20 0 1351998064 8789408 2116640
19:26:59 33621 8192000000 10309378304 10762022912 1382805168 14.442 99 19 38 1 1395642080 8840368 3996544
19:28:37 37374 8192000000 10298996432 10759053312 1384702608 14.459 99 29 58 1 1405272432 13305568 7264256
19:30:25 33759 8192000000 10254865008 10691682304 1276651920 13.482 94 21 42 0 1290217792 9197488 4368384
19:31:59 61566 8192000000 10280252656 10722381824 1341326432 14.069 99 41 80 0 1366943136 16835312 8781392
19:34:01 83881 8192000000 10261156464 10707415040 1269517664 13.417 94 9 18 0 1290227392 19175872 1533856
19:36:29 111470 8192000000 10240678624 10689445888 1304435584 13.736 96 24 48 0 1317770128 8191504 5143040

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-07-2022 19:40:47 to 26-07-2022 19:41:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-07-2022 19:41:03 to 26-07-2022 19:41:05.

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          244948
MON$OLDEST_ACTIVE               245592
MON$OLDEST_SNAPSHOT             245592
MON$NEXT_TRANSACTION            245592
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                       2050464
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:227: 
:228: mscs-test-161.budzdorov.ru    Tue Jul 26 19:38:28 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:230: 
--- end of diff output ---

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

26.07.22 19:41:06. 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-07-26 19:41:09 No severe PSQL-related problems occured
26.07.22 19:41:10. End of report.