Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 01: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 16410452 kB
MemAvailable 31450020 kB
Buffers 0 kB
Cached 15333984 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-21 01:38:15
DTS_END                         2022-05-21 01:38:15.7470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41040
TRN_ID                          241881
ATT_ID                          623
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: 9547.07 23892 1145648
customer order: creation 173.92 363 20870
customer order: refuse 14.71 336 1765
order to supplier: creation 46.71 1207 5605
order to supplier: removal 3.63 103157 435
invoice (draft): creation 46.74 3204 5609
invoice (draft): removal 53.17 809 6380
invoice accept: apply 43.74 18654 5249
invoice accept: cancel 48.06 668 5767
customer reserve: creation 4059.89 182 487187
customer reserve: removal 4755.93 60 570712
realization accept: apply 84.19 193 10103
realization accept: cancel 84.14 112 10097
payment from customer: creation 60.18 113 7221
payment from customer: removal 6.34 102 761
payment to supplier: creation 49.15 143 5898
payment to supplier: removal 3.96 98 475
service: total inventory turnovers 5.00 1201 600
service: total monetary turnovers 6.93 52 831
service: aggregate perf. data 0.66 26512 79
service: refresh index statistics 0.03 320670 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-05-2022 01:40:54 to 21-05-2022 01:40:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9567
TEST_TIME 2 7706
TEST_TIME 3 6422
TEST_TIME 4 6556
TEST_TIME 5 7057
TEST_TIME 6 6800
TEST_TIME 7 7519
TEST_TIME 8 7217
TEST_TIME 9 7175
TEST_TIME 10 7151
TEST_TIME 11 7085
TEST_TIME 12 7420
TEST_TIME 13 7711
TEST_TIME 14 7978
TEST_TIME 15 7967
TEST_TIME 16 8258
TEST_TIME 17 8383
TEST_TIME 18 8345
TEST_TIME 19 8217
TEST_TIME 20 8569
TEST_TIME 21 9071
TEST_TIME 22 9198
TEST_TIME 23 9301
TEST_TIME 24 9553
TEST_TIME 25 9414
TEST_TIME 26 9557
TEST_TIME 27 9734
TEST_TIME 28 9903
TEST_TIME 29 10193
TEST_TIME 30 10201
TEST_TIME 31 11579
TEST_TIME 32 11432
TEST_TIME 33 11491
TEST_TIME 34 11376
TEST_TIME 35 11236
TEST_TIME 36 11114
TEST_TIME 37 10927
TEST_TIME 38 10679
TEST_TIME 39 10574
TEST_TIME 40 10636
TEST_TIME 41 10480
TEST_TIME 42 10470
TEST_TIME 43 10554
TEST_TIME 44 10426
TEST_TIME 45 10320
TEST_TIME 46 10195
TEST_TIME 47 10076
TEST_TIME 48 9927
TEST_TIME 49 9975
TEST_TIME 50 9926
TEST_TIME 51 9902
TEST_TIME 52 9829
TEST_TIME 53 9727
TEST_TIME 54 9673
TEST_TIME 55 9696
TEST_TIME 56 9653
TEST_TIME 57 9650
TEST_TIME 58 9679
TEST_TIME 59 9727
TEST_TIME 60 9713
TEST_TIME 61 9706
TEST_TIME 62 9849
TEST_TIME 63 9891
TEST_TIME 64 9977
TEST_TIME 65 10054
TEST_TIME 66 10087
TEST_TIME 67 10022
TEST_TIME 68 10163
TEST_TIME 69 10076
TEST_TIME 70 9967
TEST_TIME 71 9939
TEST_TIME 72 9884
TEST_TIME 73 9886
TEST_TIME 74 9857
TEST_TIME 75 9844
TEST_TIME 76 9806
TEST_TIME 77 9736
TEST_TIME 78 9731
TEST_TIME 79 9721
TEST_TIME 80 9781
TEST_TIME 81 9758
TEST_TIME 82 9767
TEST_TIME 83 9813
TEST_TIME 84 9771
TEST_TIME 85 9736
TEST_TIME 86 9826
TEST_TIME 87 9792
TEST_TIME 88 9797
TEST_TIME 89 9831
TEST_TIME 90 9905
TEST_TIME 91 9870
TEST_TIME 92 9809
TEST_TIME 93 9727
TEST_TIME 94 9746
TEST_TIME 95 9720
TEST_TIME 96 9708
TEST_TIME 97 9676
TEST_TIME 98 9668
TEST_TIME 99 9711
TEST_TIME 100 9726
TEST_TIME 101 9685
TEST_TIME 102 9701
TEST_TIME 103 9706
TEST_TIME 104 9709
TEST_TIME 105 9714
TEST_TIME 106 9736
TEST_TIME 107 9704
TEST_TIME 108 9772
TEST_TIME 109 9738
TEST_TIME 110 9767
TEST_TIME 111 9812
TEST_TIME 112 9748
TEST_TIME 113 9698
TEST_TIME 114 9701
TEST_TIME 115 9671
TEST_TIME 116 9646
TEST_TIME 117 9629
TEST_TIME 118 9620
TEST_TIME 119 9610
TEST_TIME 120 9548

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-05-2022 01:40:58 to 21-05-2022 01:41:01.

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 1593673 1593673 0 0.00 0 19009 6 0 0
doc_list_biud 1593672 1593672 0 0.00 0 49437 27 0 0
sp_add_invoice_to_stock 5414 5249 165 3.04 44 174996 18654 0 165
sp_cancel_adding_invoice 5767 5767 0 0.00 4 42298 668 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 13400 336 0 0
sp_cancel_customer_reserve 570712 570712 0 0.00 1 49439 61 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 2 9463 102 0 0
sp_cancel_pay_to_supplier 475 475 0 0.00 2 12190 98 0 0
sp_cancel_supplier_invoice 6396 6380 16 0.25 1 14971 809 0 16
sp_cancel_supplier_order 435 435 0 0.00 6 1011033 103158 0 0
sp_cancel_write_off 10097 10097 0 0.00 1 7768 112 0 0
sp_client_order 20870 20870 0 0.00 3 29213 363 0 0
sp_customer_reserve 487187 487187 0 0.00 0 87535 183 0 0
sp_fill_shopping_cart 32076 32076 0 0.00 1 12378 38 0 0
sp_fill_shopping_cart_clo_res 487184 487184 0 0.00 0 43219 14 0 0
sp_get_clo_for_invoice 5246 5246 0 0.00 0 42094 297 0 0
sp_kill_cost_storno 17100 17100 0 0.00 0 10818 58 0 0
sp_kill_qstorno_ret_qs2qd 1158336 1158336 0 0.00 0 49433 33 0 0
sp_kill_qty_storno 610505 610505 0 0.00 0 49437 71 0 0
sp_lock_selected_doc 21322 21322 0 0.00 0 8335 6 0 0
sp_make_cost_storno 28468 28468 0 0.00 0 19005 59 0 0
sp_multiply_rows_for_pdistr 28468 28468 0 0.00 0 8916 32 0 0
sp_multiply_rows_for_qdistr 20870 20870 0 0.00 0 12619 143 0 0
sp_pay_from_customer 7221 7221 0 0.00 4 18883 113 0 0
sp_pay_to_supplier 5898 5898 0 0.00 5 40254 144 0 0
sp_payment_common 13119 13119 0 0.00 1 23551 31 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 13384 314 0 0
sp_qd_handle_on_invoice_upd_sts 11013 11013 0 0.00 0 20727 335 0 0
sp_qd_handle_on_reserve_upd_sts 10103 10103 0 0.00 0 14869 63 0 0
sp_reserve_write_off 10103 10103 0 0.00 6 19016 193 0 0
sp_supplier_invoice 5609 5609 0 0.00 7 79406 3205 0 0
sp_supplier_order 5605 5605 0 0.00 5 35970 1208 0 0
srv_aggregate_perf_data 80 79 1 1.25 2244 550236 26512 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1738 141808 44907 0 0
srv_find_qd_qs_mism 1094917 1094917 0 0.00 0 9500 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 30 100997 1202 0 0
srv_make_money_saldo 831 831 0 0.00 0 19947 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 80039 520095 320670 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 14470 200 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 2949 83 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 2216 14 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 12779 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 4255 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 475 475 0 0.00 0 1709 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 8057 30 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 21872 1554 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 578 3 0 0
t$perf-norm:sp_client_order 20870 20870 0 0.00 0 15606 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 988 6 0 0
t$perf-norm:sp_pay_from_customer 7221 7221 0 0.00 0 6932 12 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 14272 16 0 0
t$perf-norm:sp_reserve_write_off 10103 10103 0 0.00 0 4583 11 0 0
t$perf-norm:sp_supplier_invoice 5609 5609 0 0.00 0 15146 20 0 0
t$perf-norm:sp_supplier_order 5605 5605 0 0.00 0 6247 20 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 297 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 627 21 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 1050 19 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 2410 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10822 10492 330 3.04 0 5566 20 0 330
v_all_customers 37984 37984 0 0.00 0 17 1 0 0
v_all_suppliers 30736 30736 0 0.00 0 18 1 0 0
v_all_wares 41740 41740 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 438 6 0 0
v_cancel_client_order 3530 3530 0 0.00 0 2577 5 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 3314 11 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 3572 7 0 0
v_cancel_payment_to_supplier 950 950 0 0.00 0 2479 7 0 0
v_cancel_supplier_invoice 882 850 32 3.62 0 878 13 0 32
v_cancel_supplier_order 870 870 0 0.00 0 5029 12 0 0
v_cancel_write_off 1988 1988 0 0.00 0 4921 20 0 0
v_max_id_clo_ord 5601 5601 0 0.00 0 316 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 1790 8 0 0
v_max_id_ord_sup 5605 5605 0 0.00 0 3733 4 0 0
v_max_non_paid_invoice 1736 1736 0 0.00 0 179 2 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 989 3 0 0
v_min_id_clo_ord 5601 5601 0 0.00 0 3977 6 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 124 2 0 0
v_min_id_ord_sup 5605 5605 0 0.00 0 5517 6 0 0
v_min_non_paid_invoice 5898 5898 0 0.00 0 23532 24 0 0
v_min_non_paid_realizn 7221 7221 0 0.00 0 15435 23 0 0
v_our_firm 10396 10396 0 0.00 0 12 1 0 0
v_reserve_write_off 20206 20206 0 0.00 0 5678 7 0 0
x_lock_depdocs_on_canc_invoice 11722 11722 0 0.00 0 20550 116 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 10249 372 0 0
x_make_qstorno_1000_1200 5605 5605 0 0.00 2 35926 1122 0 0
x_make_qstorno_1200_2000 5609 5609 0 0.00 4 79369 3073 0 0
x_make_qstorno_2100_3300 487187 487187 0 0.00 0 87534 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-05-2022 01:41:02 to 21-05-2022 01:41:06.

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:19 16 8192000000 8805626624 8819281920 9310704 0.113 3 2 4 0 9463696 68032 84960
23:09:20 23054 8192000000 9923043936 10236932096 1003315968 10.911 100 92 184 2 1033788576 6005216 24467392
23:10:51 32338 8192000000 9978211696 10304626688 1057301728 11.431 100 93 185 1 1089365328 8482400 23581200
23:12:27 18435 8192000000 10016408992 10359013376 1100137824 11.839 100 82 164 0 1129906208 9852480 19915904
23:13:57 10746 8192000000 10066942704 10424655872 1152281312 12.331 100 81 162 1 1183103920 11240992 19581616
23:15:11 10031 8192000000 10100142432 10465284096 1192258240 12.704 100 73 147 3 1221804384 12737456 16808688
23:16:23 4105 8192000000 10137208384 10518372352 1234069648 13.092 100 59 117 3 1257344976 10737392 12537936
23:17:32 2495 8192000000 10182304320 10563592192 1269001888 13.412 100 86 170 1 1301479232 14232272 18245072
23:18:37 1999 8192000000 10210448320 10592083968 1291170256 13.615 100 90 181 1 1325989632 14060512 20758864
23:19:42 1565 8192000000 10221268720 10611359744 1307570048 13.764 100 79 158 1 1338783808 12742016 18471744
23:20:46 8935 8192000000 10228913536 10621038592 1314429904 13.826 100 79 159 1 1343627856 11636656 17561296
23:21:56 6272 8192000000 10208571504 10619445248 1317105008 13.850 100 32 64 0 1331265200 6564880 7595312
23:23:04 12912 8192000000 10198914288 10620448768 1320097264 13.878 100 20 40 1 1330904352 6092864 4714224
23:24:17 10243 8192000000 10212330192 10633781248 1329206784 13.960 100 26 52 2 1341860176 6157728 6495664
23:25:30 73372 8192000000 10230683776 10656612352 1341120656 14.068 100 31 60 1 1353938048 5990256 6827136
23:27:44 7661 8192000000 10233933648 10655330304 1341487296 14.071 100 33 66 1 1355392496 5946880 7958320
23:28:54 11204 8192000000 10197627296 10609610752 1278893024 13.503 97 18 36 0 1288876944 5655216 4328704
23:30:07 10516 8192000000 10144315632 10548973568 1225002816 13.008 96 13 26 0 1237108672 9363440 2742416
23:31:21 25190 8192000000 10133870544 10537246720 1159855552 12.402 92 12 24 0 1166912880 4483296 2574032
23:32:46 2832 8192000000 10153835936 10551402496 1250839552 13.246 99 28 55 0 1264091776 7051696 6200528
23:33:50 5221 8192000000 10173824912 10574585856 1275579680 13.473 100 45 91 2 1292738656 5495760 11663216
23:34:56 8688 8192000000 10176097136 10586890240 1287894944 13.585 100 18 35 3 1297582432 5189680 4497808
23:36:06 19703 8192000000 10175512192 10590613504 1292962544 13.631 100 20 40 1 1302845920 5177312 4706064
23:37:26 5396 8192000000 10189140240 10600325120 1296932256 13.667 100 32 64 0 1309852464 5310032 7610176
23:38:34 3482 8192000000 10241465040 10644819968 1331264816 13.979 100 62 124 0 1354457440 8413952 14778672
23:39:43 3552 8192000000 10260357984 10670911488 1347261456 14.123 100 67 133 0 1372516976 10349232 14906288
23:40:50 20159 8192000000 10272612016 10687365120 1356941088 14.210 100 46 92 2 1376374704 8800512 10633104
23:42:14 2748 8192000000 10292991312 10698682368 1371279440 14.339 100 73 146 2 1402075024 14972608 15822976
23:43:26 5664 8192000000 10285503840 10703220736 1372543712 14.350 100 54 104 1 1400889008 15962032 12383264
23:44:35 11028 8192000000 10305454784 10719391744 1382112800 14.435 100 62 124 0 1413686336 16937024 14636512
23:45:58 14264 8192000000 10308583152 10737143808 1396255728 14.562 100 45 88 1 1421175312 14963728 9955856
23:47:20 6791 8192000000 10329541040 10750689280 1406586384 14.654 100 60 120 0 1439702368 19125072 13990912
23:48:32 7964 8192000000 10343042048 10766688256 1419585008 14.769 100 55 105 1 1453345680 21567632 12193040
23:49:47 13982 8192000000 10339870400 10767343616 1414971232 14.728 100 58 115 1 1457002992 28184816 13846944
23:51:11 2299 8192000000 10343142368 10753961984 1401699184 14.610 100 89 178 1 1448607056 25645504 21262368
23:52:17 1738 8192000000 10348923520 10759610368 1399519760 14.591 100 83 167 0 1449853040 29089888 21243392
23:53:22 3418 8192000000 10329024704 10744553472 1386775248 14.477 100 67 133 1 1433289392 30130624 16383520
23:54:31 3844 8192000000 10323879488 10735136768 1379667472 14.414 100 63 124 1 1431083408 35627760 15788176
23:55:47 5490 8192000000 10340583776 10750971904 1387702288 14.485 100 66 132 0 1443356592 38706016 16948288
23:56:58 3702 8192000000 10346059104 10750296064 1374604720 14.368 99 81 162 1 1436472464 41601648 20266096
23:58:10 2061 8192000000 10372001872 10771001344 1392586576 14.529 100 86 171 1 1471304352 55618720 23099056
23:59:18 1974 8192000000 10366993648 10768896000 1386284624 14.473 100 87 171 4 1464693568 56257424 22151520
00:00:26 6450 8192000000 10353407648 10773901312 1397686704 14.574 100 58 115 1 1460911312 47548048 15676560
00:01:40 4057 8192000000 10330702736 10747830272 1368509632 14.314 100 56 111 2 1429289632 45941600 14838400
00:02:52 9804 8192000000 10345534048 10775711744 1394838480 14.549 100 40 79 1 1454543776 48431408 11273888
00:04:04 11060 8192000000 10371883040 10796544000 1401766816 14.611 100 45 90 0 1473309168 58969328 12573024
00:05:18 17503 8192000000 10353194928 10787041280 1392900064 14.532 100 30 60 0 1454810464 53122880 8787520
00:06:53 33098 8192000000 10322313424 10778079232 1380366176 14.420 100 20 40 0 1443731968 57551824 5813968
00:08:27 29830 8192000000 10329672080 10774474752 1367566864 14.305 100 19 38 0 1431028672 57392176 6069632
00:09:59 53645 8192000000 10283386624 10731548672 1356541024 14.206 98 11 22 0 1370709792 12052096 2116672
00:11:55 67226 8192000000 10287213456 10731008000 1350491760 14.152 98 6 12 0 1363553872 12007248 1054864
00:14:02 141807 8192000000 10291706400 10740604928 1360312592 14.240 98 27 55 0 1374073488 7965536 5795360
00:17:28 59572 8192000000 10280776160 10727444480 1349438464 14.142 97 33 64 1 1365454944 8329664 7686816
00:19:31 36469 8192000000 10294494480 10745180160 1373195568 14.356 99 35 69 3 1396940768 15898112 7847088
00:21:31 50046 8192000000 10287858688 10756362240 1378638800 14.404 99 6 13 0 1391242928 11170928 1433200
00:23:22 61336 8192000000 10290682208 10750713856 1371600112 14.341 98 9 19 0 1381719408 8126096 1993200
00:25:24 64657 8192000000 10298066464 10763309056 1398799184 14.584 100 13 26 0 1410368144 8789264 2779696
00:28:01 68239 8192000000 10300769776 10763296768 1404193568 14.632 100 24 48 3 1416440448 6437664 5809216
00:30:12 20527 8192000000 10285134496 10756780032 1383329296 14.446 99 15 28 1 1397003520 10591056 3083168
00:31:33 91320 8192000000 10279113616 10750709760 1355148048 14.194 97 10 20 0 1365875280 8308976 2418256
00:34:39 54311 8192000000 10302438704 10755354624 1379861696 14.415 99 31 62 1 1396472032 9798736 6811600
00:36:38 40131 8192000000 10305955168 10753921024 1383531152 14.448 99 21 42 0 1404219040 15395312 5292576
00:38:20 54204 8192000000 10313729264 10774360064 1376741520 14.387 98 18 36 0 1398558000 17492128 4324352
00:40:16 61108 8192000000 10282126112 10739441664 1338146448 14.041 96 14 26 1 1356628368 15233792 3248128
00:42:20 55981 8192000000 10288875248 10743824384 1313688848 13.820 95 13 26 0 1327242272 10969456 2583968
00:44:31 68549 8192000000 10304250784 10742902784 1351598656 14.162 98 28 57 1 1382597088 24673152 6325280
00:46:40 126329 8192000000 10299897360 10739494912 1328470448 13.953 96 31 62 1 1346123856 10586656 7066752
00:50:02 16623 8192000000 10283088912 10738216960 1378033072 14.399 100 22 41 0 1391564512 9039744 4491696
00:51:38 79591 8192000000 10261959568 10727632896 1341380048 14.070 98 8 16 1 1356486832 13302288 1804496
00:54:18 67661 8192000000 10269155616 10726080512 1350115728 14.149 99 14 29 0 1364312320 10949760 3246832
00:56:34 66841 8192000000 10256481008 10718003200 1317146160 13.851 97 11 22 0 1340980512 21138224 2696128
00:58:41 48165 8192000000 10247527168 10681556992 1309761008 13.784 97 20 40 0 1322518576 8161968 4595600
01:00:39 57186 8192000000 10251467680 10697211904 1319325360 13.871 97 22 44 0 1333812832 9749040 4738432
01:02:38 40318 8192000000 10274831712 10717413376 1351930752 14.165 99 26 52 2 1366541472 8653792 5956928
01:04:22 90171 8192000000 10279843712 10728771584 1357787792 14.217 99 19 38 0 1372535168 11020544 3726832
01:06:56 111799 8192000000 10280604256 10739380224 1329102064 13.959 97 9 18 0 1343051872 12353264 1596544
01:10:09 111372 8192000000 10297218512 10746818560 1384521872 14.457 100 32 64 2 1403113376 11409456 7182048
01:13:06 59356 8192000000 10281956592 10756354048 1362304640 14.258 99 4 8 0 1384480144 21676064 499440
01:15:06 46690 8192000000 10290093792 10744098816 1379085264 14.408 100 17 33 0 1391909360 9534944 3289152
01:16:53 77737 8192000000 10295777792 10765770752 1371972032 14.345 98 19 38 0 1391432048 15256032 4203984
01:19:12 61756 8192000000 10308915760 10755686400 1378948912 14.407 99 33 65 1 1394484208 8553472 6981824
01:21:15 42447 8192000000 10292871072 10749149184 1353879888 14.182 97 16 32 2 1369459216 12279936 3299392
01:22:58 108780 8192000000 10281347568 10734796800 1333263360 13.997 96 13 26 0 1346829104 10640960 2924784
01:25:47 77053 8192000000 10284854016 10747949056 1325463888 13.926 95 19 38 0 1350337344 20620784 4252672
01:28:07 52917 8192000000 10291920608 10747179008 1341818336 14.074 97 20 40 0 1355279296 8934272 4526688
01:30:09 139178 8192000000 10280237664 10740432896 1346001216 14.111 97 23 46 2 1361266624 10138304 5127104
01:33:29 14151 8192000000 10284109424 10744381440 1354688624 14.190 98 17 35 0 1375524288 16631104 4204560
01:34:44 70769 8192000000 10258488432 10717855744 1323148640 13.905 96 12 24 3 1339158992 13063120 2947232
01:37:03 76944 8192000000 9345380800 9645719552 493933808 5.686 36 8 16 0 499039568 3895728 1210032

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-05-2022 01:41:08 to 21-05-2022 01:41:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 165 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-05-2022 01:41:24 to 21-05-2022 01:41:26.

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          239544
MON$OLDEST_ACTIVE               242138
MON$OLDEST_SNAPSHOT             242138
MON$NEXT_TRANSACTION            242138
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                       2041440
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Sat May 21 01:38:49 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Sat May 21 01:38:50 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
--- end of diff output ---

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

21.05.22 01:41:27. 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-05-21 01:41:29 No severe PSQL-related problems occured
21.05.22 01:41:31. End of report.