Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.07.2022 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15978420 kB
MemAvailable 31056028 kB
Buffers 0 kB
Cached 15714800 kB
SwapCached 2492 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-19 01:38:24
DTS_END                         2022-07-19 01:38:24.5400
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/39128
TRN_ID                          248657
ATT_ID                          744
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: 9563.38 18654 1147605
customer order: creation 182.99 313 21959
customer order: refuse 15.51 360 1861
order to supplier: creation 49.28 1099 5913
order to supplier: removal 3.68 83922 442
invoice (draft): creation 48.36 3023 5803
invoice (draft): removal 52.96 704 6355
invoice accept: apply 44.57 17235 5348
invoice accept: cancel 47.16 806 5659
customer reserve: creation 4148.21 169 497785
customer reserve: removal 4661.78 51 559414
realization accept: apply 86.84 164 10421
realization accept: cancel 86.60 114 10392
payment from customer: creation 62.89 102 7547
payment from customer: removal 5.79 45 695
payment to supplier: creation 51.48 134 6178
payment to supplier: removal 3.64 106 437
service: total inventory turnovers 4.58 1225 549
service: total monetary turnovers 6.17 32 740
service: aggregate perf. data 0.86 17266 103
service: refresh index statistics 0.03 246214 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2236
TEST_TIME 2 2426
TEST_TIME 3 2239
TEST_TIME 4 2855
TEST_TIME 5 2871
TEST_TIME 6 3546
TEST_TIME 7 4145
TEST_TIME 8 4744
TEST_TIME 9 4698
TEST_TIME 10 5086
TEST_TIME 11 5188
TEST_TIME 12 5867
TEST_TIME 13 6112
TEST_TIME 14 6593
TEST_TIME 15 6893
TEST_TIME 16 7237
TEST_TIME 17 7031
TEST_TIME 18 7271
TEST_TIME 19 7424
TEST_TIME 20 7896
TEST_TIME 21 8468
TEST_TIME 22 8626
TEST_TIME 23 8543
TEST_TIME 24 9576
TEST_TIME 25 9373
TEST_TIME 26 9330
TEST_TIME 27 9834
TEST_TIME 28 10328
TEST_TIME 29 10339
TEST_TIME 30 10420
TEST_TIME 31 10328
TEST_TIME 32 10082
TEST_TIME 33 10016
TEST_TIME 34 10201
TEST_TIME 35 10144
TEST_TIME 36 10031
TEST_TIME 37 10023
TEST_TIME 38 10104
TEST_TIME 39 10110
TEST_TIME 40 10147
TEST_TIME 41 10088
TEST_TIME 42 9961
TEST_TIME 43 9769
TEST_TIME 44 9617
TEST_TIME 45 9917
TEST_TIME 46 9740
TEST_TIME 47 9693
TEST_TIME 48 9670
TEST_TIME 49 9672
TEST_TIME 50 9770
TEST_TIME 51 9728
TEST_TIME 52 9660
TEST_TIME 53 9603
TEST_TIME 54 9500
TEST_TIME 55 9495
TEST_TIME 56 9571
TEST_TIME 57 9500
TEST_TIME 58 9537
TEST_TIME 59 9433
TEST_TIME 60 9366
TEST_TIME 61 9406
TEST_TIME 62 9439
TEST_TIME 63 9478
TEST_TIME 64 9503
TEST_TIME 65 9435
TEST_TIME 66 9587
TEST_TIME 67 9596
TEST_TIME 68 9586
TEST_TIME 69 9623
TEST_TIME 70 9593
TEST_TIME 71 9751
TEST_TIME 72 9784
TEST_TIME 73 9780
TEST_TIME 74 9783
TEST_TIME 75 9705
TEST_TIME 76 9730
TEST_TIME 77 9674
TEST_TIME 78 9656
TEST_TIME 79 9663
TEST_TIME 80 9576
TEST_TIME 81 9559
TEST_TIME 82 9610
TEST_TIME 83 9600
TEST_TIME 84 9568
TEST_TIME 85 9563
TEST_TIME 86 9551
TEST_TIME 87 9596
TEST_TIME 88 9639
TEST_TIME 89 9624
TEST_TIME 90 9637
TEST_TIME 91 9646
TEST_TIME 92 9643
TEST_TIME 93 9670
TEST_TIME 94 9733
TEST_TIME 95 9730
TEST_TIME 96 9779
TEST_TIME 97 9804
TEST_TIME 98 9733
TEST_TIME 99 9697
TEST_TIME 100 9642
TEST_TIME 101 9634
TEST_TIME 102 9631
TEST_TIME 103 9577
TEST_TIME 104 9600
TEST_TIME 105 9569
TEST_TIME 106 9523
TEST_TIME 107 9489
TEST_TIME 108 9483
TEST_TIME 109 9485
TEST_TIME 110 9463
TEST_TIME 111 9470
TEST_TIME 112 9473
TEST_TIME 113 9527
TEST_TIME 114 9516
TEST_TIME 115 9526
TEST_TIME 116 9540
TEST_TIME 117 9567
TEST_TIME 118 9589
TEST_TIME 119 9619
TEST_TIME 120 9564

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-07-2022 01:39:10 to 19-07-2022 01:39: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 1607743 1607743 0 0.00 0 20931 6 0 0
doc_list_biud 1607743 1607743 0 0.00 0 66338 23 0 0
sp_add_invoice_to_stock 5490 5348 142 2.58 26 180838 17236 0 142
sp_cancel_adding_invoice 5659 5659 0 0.00 4 45597 807 0 0
sp_cancel_client_order 1861 1861 0 0.00 4 14770 361 0 0
sp_cancel_customer_reserve 559414 559414 0 0.00 0 66340 51 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 1761 45 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 6889 107 0 0
sp_cancel_supplier_invoice 6360 6355 5 0.07 2 43642 704 0 5
sp_cancel_supplier_order 442 442 0 0.00 6 867388 83923 0 0
sp_cancel_write_off 10392 10392 0 0.00 1 15036 115 0 0
sp_client_order 21959 21959 0 0.00 3 65412 314 0 0
sp_customer_reserve 497785 497785 0 0.00 0 127815 169 0 0
sp_fill_shopping_cart 33670 33670 0 0.00 1 17979 34 0 0
sp_fill_shopping_cart_clo_res 497784 497784 0 0.00 0 20600 13 0 0
sp_get_clo_for_invoice 5347 5347 0 0.00 0 23432 299 0 0
sp_kill_cost_storno 17183 17183 0 0.00 0 13906 55 0 0
sp_kill_qstorno_ret_qs2qd 1136017 1136017 0 0.00 0 66336 28 0 0
sp_kill_qty_storno 599891 599891 0 0.00 0 66338 61 0 0
sp_lock_selected_doc 21805 21805 0 0.00 0 9434 5 0 0
sp_make_cost_storno 29493 29493 0 0.00 0 14568 50 0 0
sp_multiply_rows_for_pdistr 29493 29493 0 0.00 0 10196 26 0 0
sp_multiply_rows_for_qdistr 21959 21959 0 0.00 0 65406 142 0 0
sp_pay_from_customer 7547 7547 0 0.00 4 14914 102 0 0
sp_pay_to_supplier 6178 6178 0 0.00 4 13337 134 0 0
sp_payment_common 13725 13725 0 0.00 1 14207 33 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 14641 337 0 0
sp_qd_handle_on_invoice_upd_sts 11006 11006 0 0.00 0 22613 331 0 0
sp_qd_handle_on_reserve_upd_sts 10421 10421 0 0.00 0 18307 54 0 0
sp_reserve_write_off 10421 10421 0 0.00 6 18336 165 0 0
sp_supplier_invoice 5803 5803 0 0.00 5 80095 3023 0 0
sp_supplier_order 5913 5913 0 0.00 5 23086 1099 0 0
srv_aggregate_perf_data 103 103 0 0.00 2546 206588 17266 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1374 133730 47718 0 0
srv_find_qd_qs_mism 1096391 1096391 0 0.00 0 20912 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 41 44158 1225 0 0
srv_make_money_saldo 740 740 0 0.00 1 2962 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 203010 318003 246215 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5348 5348 0 0.00 0 26495 188 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 12912 136 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 1725 12 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 731 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1125 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2658 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2770 20 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 27269 1704 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 6787 13 0 0
t$perf-norm:sp_client_order 21959 21959 0 0.00 0 12500 17 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 1357 6 0 0
t$perf-norm:sp_pay_from_customer 7547 7547 0 0.00 0 9387 13 0 0
t$perf-norm:sp_pay_to_supplier 6178 6178 0 0.00 0 11804 17 0 0
t$perf-norm:sp_reserve_write_off 10421 10421 0 0.00 0 11210 13 0 0
t$perf-norm:sp_supplier_invoice 5803 5803 0 0.00 0 7875 16 0 0
t$perf-norm:sp_supplier_order 5913 5913 0 0.00 0 23930 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1057 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1803 33 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 2391 32 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 2771 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 190 49 0 0
v_add_invoice_to_stock 10978 10694 284 2.58 0 4745 16 0 284
v_all_customers 40084 40084 0 0.00 0 23 1 0 0
v_all_suppliers 32390 32390 0 0.00 0 22 1 0 0
v_all_wares 43918 43918 0 0.00 0 235 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 345 6 0 0
v_cancel_client_order 3722 3722 0 0.00 0 2924 5 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 295 3 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1798 6 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2113 13 0 0
v_cancel_supplier_invoice 858 848 10 1.16 0 1647 17 0 10
v_cancel_supplier_order 884 884 0 0.00 0 3258 6 0 0
v_cancel_write_off 2090 2090 0 0.00 0 2688 12 0 0
v_max_id_clo_ord 5913 5913 0 0.00 0 380 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1116 10 0 0
v_max_id_ord_sup 5798 5798 0 0.00 0 7985 6 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 666 3 0 0
v_max_non_paid_realizn 3926 3926 0 0.00 0 4663 5 0 0
v_min_id_clo_ord 5913 5913 0 0.00 0 8200 6 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 3095 7 0 0
v_min_id_ord_sup 5798 5798 0 0.00 0 6191 6 0 0
v_min_non_paid_invoice 6178 6178 0 0.00 0 11351 26 0 0
v_min_non_paid_realizn 7547 7547 0 0.00 0 14201 25 0 0
v_our_firm 11076 11076 0 0.00 0 32 1 0 0
v_reserve_write_off 20842 20842 0 0.00 0 4860 5 0 0
x_lock_depdocs_on_canc_invoice 11590 11590 0 0.00 0 30357 123 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 21654 398 0 0
x_make_qstorno_1000_1200 5913 5913 0 0.00 1 23073 1031 0 0
x_make_qstorno_1200_2000 5803 5803 0 0.00 2 80086 2898 0 0
x_make_qstorno_2100_3300 497785 497785 0 0.00 0 127815 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-07-2022 01:39:15 to 19-07-2022 01:39:19.

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
23:08:28 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:29 19069 8192000000 9907843760 10215989248 978204768 10.667 100 90 180 0 1008201712 6067152 23929792
23:10:55 23593 8192000000 9946786800 10269626368 1029879216 11.167 100 87 174 0 1060524992 8568496 22077280
23:12:33 13309 8192000000 9996082688 10333622272 1079610160 11.644 100 85 170 0 1110693136 9674992 21407984
23:13:55 8696 8192000000 10045214608 10395672576 1129297664 12.115 100 79 156 0 1158502032 10662432 18541936
23:15:05 5356 8192000000 10086112928 10446774272 1174623616 12.540 100 79 159 1 1205477680 12440864 18413200
23:16:14 2199 8192000000 10122211072 10488819712 1210825440 12.877 100 81 163 1 1242657104 14215008 17616656
23:17:19 2196 8192000000 10154726448 10538573824 1251452192 13.252 100 74 148 0 1279391744 12113616 15825936
23:18:22 1112 8192000000 10199428704 10579996672 1283788000 13.548 100 88 173 1 1315959712 13285856 18885856
23:19:25 838 8192000000 10217443808 10601484288 1298716288 13.684 100 92 184 0 1331590928 13162624 19712016
23:20:28 8292 8192000000 10211261072 10604179456 1305615488 13.746 100 63 125 1 1327919728 8357312 13946928
23:21:37 1420 8192000000 10207402576 10613420032 1311826560 13.803 100 54 108 0 1332205248 8582992 11795696
23:22:39 10750 8192000000 10212157920 10623840256 1316568896 13.846 100 19 36 1 1326838288 6383952 3885440
23:23:51 8199 8192000000 10199475648 10623430656 1321528336 13.891 100 23 44 1 1333068496 6154752 5385408
23:25:01 44827 8192000000 10211520832 10636177408 1323948336 13.912 100 31 62 0 1336838288 5272480 7617472
23:26:46 5608 8192000000 10230271792 10652241920 1335910960 14.021 100 37 66 3 1349138128 5478832 7748336
23:27:56 9394 8192000000 10223839408 10645852160 1329383520 13.962 100 19 39 0 1339655648 6161968 4110160
23:29:06 12150 8192000000 10191333792 10606374912 1263172688 13.359 96 10 20 0 1270285824 4861600 2251536
23:30:19 15723 8192000000 10167513904 10565267456 1225976192 13.017 95 57 114 0 1245551280 5309664 14265424
23:31:37 8553 8192000000 10150542400 10548707328 1224111328 13.000 97 25 50 0 1236642336 6593136 5937872
23:32:46 7431 8192000000 10156531296 10552213504 1253925296 13.274 100 30 60 0 1266993744 5602704 7465744
23:33:54 13093 8192000000 10166308768 10577375232 1278467872 13.499 100 13 26 2 1286701232 5150640 3082720
23:35:08 16210 8192000000 10162060592 10576834560 1275354016 13.471 100 21 42 0 1286001328 5885152 4762160
23:36:25 7077 8192000000 10185959760 10601086976 1295581968 13.655 100 26 53 0 1307638032 6200048 5856016
23:37:33 9584 8192000000 10204789648 10616016896 1304746640 13.738 100 26 53 0 1316238096 4892336 6599120
23:38:50 41521 8192000000 10231074000 10645463040 1330410896 13.971 100 32 63 1 1349613232 11891872 7310464
23:40:35 93758 8192000000 10262431056 10686439424 1356799328 14.209 100 38 76 0 1379662240 14199328 8663584
23:43:28 48363 8192000000 10280972688 10710765568 1370470784 14.331 100 31 61 1 1395107184 17001440 7634960
23:45:26 12031 8192000000 10300867920 10722242560 1379665152 14.414 100 49 97 0 1409954080 19067792 11221136
23:46:42 10197 8192000000 10321190048 10743566336 1391183200 14.516 100 52 104 0 1430839568 27324208 12332160
23:47:58 6756 8192000000 10315664256 10740097024 1391454016 14.519 100 54 109 0 1427420240 23203744 12762480
23:49:14 4080 8192000000 10339346960 10764582912 1407017104 14.657 100 49 93 1 1445534240 27255664 11261472
23:50:26 3381 8192000000 10343652880 10764468224 1392865104 14.531 99 75 150 1 1448331152 37863088 17602960
23:51:34 4382 8192000000 10315945408 10727997440 1368176240 14.311 99 69 135 1 1417343216 32954256 16212720
23:52:43 1371 8192000000 10341426432 10747695104 1387239632 14.481 100 86 173 0 1443138624 35114768 20784224
23:53:51 2008 8192000000 10320550208 10728493056 1363788256 14.271 99 69 137 1 1412941776 32128800 17024720
23:54:57 3208 8192000000 10324047024 10732167168 1378444880 14.403 99 69 138 1 1426456656 31376560 16635216
23:56:05 2508 8192000000 10342614800 10756120576 1396889424 14.567 100 75 150 1 1450730800 34973424 18867952
23:57:13 2377 8192000000 10356822112 10766110720 1398047712 14.578 100 76 152 0 1460599616 43950624 18601280
23:58:21 2175 8192000000 10339502144 10747011072 1373165712 14.355 99 67 134 0 1440732304 50183536 17383056
23:59:27 7718 8192000000 10324059152 10754662400 1366341456 14.294 98 39 77 0 1415445936 38991040 10113440
00:00:38 2804 8192000000 10327147920 10741501952 1364527248 14.278 99 64 128 1 1423163808 41526784 17109776
00:01:48 11087 8192000000 10330763104 10752749568 1364361040 14.276 99 42 84 0 1426171920 50028560 11782320
00:03:02 17400 8192000000 10296761584 10737598464 1363011776 14.264 99 27 54 0 1401071104 30804512 7254816
00:04:53 13531 8192000000 10311133424 10748755968 1377671456 14.396 100 49 98 1 1425186480 35968464 11546560
00:07:12 100125 8192000000 10279210784 10724016128 1323649408 13.910 96 8 16 0 1346804944 21191120 1964416
00:09:53 76173 8192000000 10291958976 10722582528 1324447472 13.917 96 22 45 0 1349356464 19619520 5289472
00:12:20 110307 8192000000 10282026432 10727567360 1357516624 14.215 99 6 13 0 1368259040 9531472 1210944
00:15:11 57565 8192000000 10309106288 10759544832 1388086496 14.489 100 21 40 0 1411933328 19060496 4786336
00:17:10 76357 8192000000 10303703344 10761609216 1387792368 14.486 100 4 8 0 1399835664 11356672 686624
00:19:38 60464 8192000000 10324093184 10769334272 1387480800 14.483 99 30 61 0 1404320144 10246528 6592816
00:21:38 48245 8192000000 10336435616 10777255936 1403476592 14.626 100 50 99 0 1428325216 12650464 12198160
00:23:36 84344 8192000000 10298450304 10768080896 1372417600 14.349 98 5 10 0 1388790064 15333424 1039040
00:26:04 53916 8192000000 10321148480 10777350144 1392178608 14.525 99 18 36 0 1413354016 16892480 4282928
00:28:11 44302 8192000000 10294772624 10762174464 1394431728 14.545 100 19 35 0 1409206544 10678640 4096176
00:29:56 43160 8192000000 10301180352 10762326016 1378140128 14.400 98 10 19 0 1386485440 6321488 2023824
00:31:40 84535 8192000000 10290947504 10744242176 1356803760 14.209 97 20 40 0 1371911424 9855856 5251808
00:34:37 34215 8192000000 10283616512 10746032128 1379635120 14.413 100 16 33 0 1391641856 8840208 3166528
00:36:14 39429 8192000000 10256019552 10714152960 1334306496 14.006 97 17 34 0 1347230000 8965040 3958464
00:38:50 66874 8192000000 10278744112 10744369152 1353596064 14.180 98 8 16 0 1363576320 8494080 1486176
00:41:39 36507 8192000000 10274433776 10736529408 1353542640 14.179 98 17 34 0 1365382704 8172368 3667696
00:43:23 14613 8192000000 10284253712 10719211520 1336486384 14.026 98 43 84 0 1358661712 12844944 9330384
00:44:39 29138 8192000000 10269911648 10714009600 1323702864 13.910 97 30 59 1 1341987104 11702848 6581392
00:46:37 43323 8192000000 10281510992 10728042496 1343124032 14.086 98 28 54 0 1365855232 17089280 5641920
00:48:21 31423 8192000000 10286705776 10734817280 1364193984 14.275 100 19 39 0 1382399712 13636416 4569312
00:50:01 85252 8192000000 10278966368 10730577920 1292186864 13.624 94 9 18 0 1306925088 12596000 2142224
00:52:43 98198 8192000000 10289394272 10738049024 1372078912 14.346 100 33 63 0 1396089072 16517312 7492848
00:55:46 53107 8192000000 10296708528 10738843648 1374709056 14.369 100 46 90 0 1397627136 13497184 9420896
00:57:42 59377 8192000000 10269235168 10731008000 1360900480 14.245 99 10 20 0 1379651424 16157184 2593760
00:59:43 67724 8192000000 10257833488 10721398784 1340491520 14.062 98 10 20 0 1362327216 19231408 2604288
01:01:55 76383 8192000000 10278629776 10738905088 1344912864 14.102 98 13 21 1 1354655632 8065968 1676800
01:04:12 56503 8192000000 10286092912 10735239168 1343834624 14.092 98 24 48 0 1367065440 17825504 5405312
01:06:21 49469 8192000000 10285990896 10740101120 1377401200 14.393 100 13 26 0 1390918480 10425600 3091680
01:08:28 85005 8192000000 10294505568 10752589824 1365551216 14.287 98 26 52 0 1379947920 9091824 5304880
01:10:53 100118 8192000000 10302522640 10762330112 1340818064 14.065 96 8 16 0 1358447408 16281072 1348272
01:13:38 93264 8192000000 10307368784 10770808832 1374588992 14.368 98 14 28 0 1391189456 13732160 2868304
01:16:12 133730 8192000000 10300148784 10765713408 1382047392 14.435 99 17 33 0 1397007072 11661376 3298304
01:19:26 49078 8192000000 10296208848 10761670656 1388136704 14.489 100 31 61 0 1404101152 8821712 7142736
01:21:15 65743 8192000000 10290789488 10761883648 1367480976 14.304 98 10 20 0 1390468496 20644512 2343008
01:23:21 44545 8192000000 10272813728 10730057728 1347595936 14.126 97 15 29 1 1357840560 6296336 3948288
01:25:06 67632 8192000000 10272111424 10740588544 1367426976 14.304 99 13 26 0 1378401408 7992336 2982096
01:27:18 71713 8192000000 10258811456 10710536192 1307879520 13.767 95 11 22 0 1316326640 6095536 2351584
01:29:39 84498 8192000000 10265478288 10726178816 1323395392 13.907 97 11 22 0 1337001376 11013008 2592976
01:32:05 10590 8192000000 10271633616 10714361856 1346801424 14.119 99 35 66 0 1362104096 7754112 7548560
01:33:19 54418 8192000000 10252193744 10697596928 1296284160 13.661 96 36 72 1 1319184064 15040000 7859904
01:35:15 88208 8192000000 10256202432 10709217280 1254314896 13.278 93 18 35 0 1275777568 17412256 4050416
01:37:43 38202 8192000000 10261053568 10715897856 1303462480 13.727 97 13 25 0 1316460064 10534896 2462688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-07-2022 01:39:21 to 19-07-2022 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-07-2022 01:39:36 to 19-07-2022 01:39:38.

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          247909
MON$OLDEST_ACTIVE               248776
MON$OLDEST_SNAPSHOT             248776
MON$NEXT_TRANSACTION            248776
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                       2040736
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Tue Jul 19 01:38:28 2022
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Tue Jul 19 01:38:33 2022
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Tue Jul 19 01:38:33 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
--- end of diff output ---

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

19.07.22 01:39:39. 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-19 01:39:42 No severe PSQL-related problems occured
19.07.22 01:39:43. End of report.