Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.05.2022 01:39
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 16588208 kB
MemAvailable 31488260 kB
Buffers 0 kB
Cached 15114192 kB
SwapCached 2560 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-07 01:38:12
DTS_END                         2022-05-07 01:38:12.7990
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/53200
TRN_ID                          236044
ATT_ID                          691
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: 9422.14 23626 1130657
customer order: creation 167.02 383 20042
customer order: refuse 14.33 364 1719
order to supplier: creation 44.14 1256 5297
order to supplier: removal 3.61 100793 433
invoice (draft): creation 45.04 3238 5405
invoice (draft): removal 56.99 714 6839
invoice accept: apply 40.39 18266 4847
invoice accept: cancel 50.83 738 6099
customer reserve: creation 3779.05 179 453486
customer reserve: removal 4923.92 59 590870
realization accept: apply 80.80 180 9696
realization accept: cancel 88.27 113 10592
payment from customer: creation 58.43 118 7012
payment from customer: removal 5.63 102 675
payment to supplier: creation 48.05 143 5766
payment to supplier: removal 3.38 105 405
service: total inventory turnovers 4.92 1008 590
service: total monetary turnovers 6.43 35 772
service: aggregate perf. data 0.90 16600 108
service: refresh index statistics 0.03 328118 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-05-2022 01:39:41 to 07-05-2022 01:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5987
TEST_TIME 2 5436
TEST_TIME 3 5190
TEST_TIME 4 4739
TEST_TIME 5 4901
TEST_TIME 6 4702
TEST_TIME 7 4841
TEST_TIME 8 4683
TEST_TIME 9 4994
TEST_TIME 10 5587
TEST_TIME 11 5854
TEST_TIME 12 6066
TEST_TIME 13 6471
TEST_TIME 14 6782
TEST_TIME 15 7072
TEST_TIME 16 8390
TEST_TIME 17 8574
TEST_TIME 18 8912
TEST_TIME 19 9105
TEST_TIME 20 9554
TEST_TIME 21 9995
TEST_TIME 22 9728
TEST_TIME 23 10231
TEST_TIME 24 10617
TEST_TIME 25 10975
TEST_TIME 26 11514
TEST_TIME 27 11545
TEST_TIME 28 11526
TEST_TIME 29 11238
TEST_TIME 30 10929
TEST_TIME 31 10897
TEST_TIME 32 10967
TEST_TIME 33 10756
TEST_TIME 34 10747
TEST_TIME 35 10567
TEST_TIME 36 10430
TEST_TIME 37 10500
TEST_TIME 38 10398
TEST_TIME 39 10415
TEST_TIME 40 10352
TEST_TIME 41 10332
TEST_TIME 42 10178
TEST_TIME 43 10055
TEST_TIME 44 9939
TEST_TIME 45 9891
TEST_TIME 46 9773
TEST_TIME 47 9929
TEST_TIME 48 9758
TEST_TIME 49 9787
TEST_TIME 50 9692
TEST_TIME 51 9666
TEST_TIME 52 9700
TEST_TIME 53 9873
TEST_TIME 54 9887
TEST_TIME 55 9825
TEST_TIME 56 10124
TEST_TIME 57 10067
TEST_TIME 58 10047
TEST_TIME 59 10122
TEST_TIME 60 10108
TEST_TIME 61 10135
TEST_TIME 62 10037
TEST_TIME 63 9931
TEST_TIME 64 9894
TEST_TIME 65 9877
TEST_TIME 66 9842
TEST_TIME 67 9797
TEST_TIME 68 9770
TEST_TIME 69 9791
TEST_TIME 70 9762
TEST_TIME 71 9699
TEST_TIME 72 9743
TEST_TIME 73 9675
TEST_TIME 74 9727
TEST_TIME 75 9768
TEST_TIME 76 9780
TEST_TIME 77 9847
TEST_TIME 78 9855
TEST_TIME 79 9877
TEST_TIME 80 9868
TEST_TIME 81 9867
TEST_TIME 82 9856
TEST_TIME 83 9870
TEST_TIME 84 9855
TEST_TIME 85 9757
TEST_TIME 86 9774
TEST_TIME 87 9720
TEST_TIME 88 9692
TEST_TIME 89 9672
TEST_TIME 90 9638
TEST_TIME 91 9585
TEST_TIME 92 9541
TEST_TIME 93 9517
TEST_TIME 94 9539
TEST_TIME 95 9541
TEST_TIME 96 9546
TEST_TIME 97 9578
TEST_TIME 98 9577
TEST_TIME 99 9608
TEST_TIME 100 9604
TEST_TIME 101 9593
TEST_TIME 102 9680
TEST_TIME 103 9660
TEST_TIME 104 9647
TEST_TIME 105 9702
TEST_TIME 106 9688
TEST_TIME 107 9677
TEST_TIME 108 9691
TEST_TIME 109 9620
TEST_TIME 110 9582
TEST_TIME 111 9505
TEST_TIME 112 9563
TEST_TIME 113 9588
TEST_TIME 114 9542
TEST_TIME 115 9524
TEST_TIME 116 9483
TEST_TIME 117 9487
TEST_TIME 118 9477
TEST_TIME 119 9490
TEST_TIME 120 9425

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-05-2022 01:39:45 to 07-05-2022 01:39:48.

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 1550372 1550372 0 0.00 0 13714 6 0 0
doc_list_biud 1550372 1550372 0 0.00 0 56479 28 0 0
sp_add_invoice_to_stock 4973 4847 126 2.53 64 162033 18267 0 126
sp_cancel_adding_invoice 6099 6099 0 0.00 4 69383 739 0 0
sp_cancel_client_order 1719 1719 0 0.00 4 11246 365 0 0
sp_cancel_customer_reserve 590870 590870 0 0.00 1 28679 59 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 9012 103 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 13742 105 0 0
sp_cancel_supplier_invoice 6853 6839 14 0.20 2 56510 715 0 14
sp_cancel_supplier_order 433 433 0 0.00 4 1023201 100794 0 0
sp_cancel_write_off 10592 10592 0 0.00 1 12972 113 0 0
sp_client_order 20042 20042 0 0.00 3 27262 383 0 0
sp_customer_reserve 453486 453486 0 0.00 0 64247 180 0 0
sp_fill_shopping_cart 30742 30742 0 0.00 1 8995 35 0 0
sp_fill_shopping_cart_clo_res 453468 453468 0 0.00 0 13872 14 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 24371 334 0 0
sp_kill_cost_storno 17771 17771 0 0.00 0 6492 56 0 0
sp_kill_qstorno_ret_qs2qd 1199604 1199604 0 0.00 0 56471 32 0 0
sp_kill_qty_storno 631077 631077 0 0.00 0 56476 68 0 0
sp_lock_selected_doc 20153 20153 0 0.00 0 8998 7 0 0
sp_make_cost_storno 27303 27303 0 0.00 0 10852 52 0 0
sp_multiply_rows_for_pdistr 27303 27303 0 0.00 0 8724 31 0 0
sp_multiply_rows_for_qdistr 20042 20042 0 0.00 0 11977 170 0 0
sp_pay_from_customer 7012 7012 0 0.00 4 13884 119 0 0
sp_pay_to_supplier 5766 5766 0 0.00 5 13719 143 0 0
sp_payment_common 12778 12778 0 0.00 1 8148 27 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 11239 330 0 0
sp_qd_handle_on_invoice_upd_sts 10928 10928 0 0.00 0 23755 314 0 0
sp_qd_handle_on_reserve_upd_sts 9696 9696 0 0.00 0 15809 60 0 0
sp_reserve_write_off 9696 9696 0 0.00 6 15846 180 0 0
sp_supplier_invoice 5405 5405 0 0.00 6 72814 3239 0 0
sp_supplier_order 5297 5297 0 0.00 5 28059 1257 0 0
srv_aggregate_perf_data 108 108 0 0.00 2487 114782 16600 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2120 141579 51967 0 0
srv_find_qd_qs_mism 1083800 1083800 0 0.00 0 10503 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 38 81194 1008 0 0
srv_make_money_saldo 772 772 0 0.00 1 8752 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 181295 513800 328119 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4847 4847 0 0.00 0 15068 180 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 3048 78 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 5602 14 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 2361 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1407 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 703 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 717 11 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 23258 1457 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1640 6 0 0
t$perf-norm:sp_client_order 20042 20042 0 0.00 0 18754 20 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 5168 13 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 5627 14 0 0
t$perf-norm:sp_pay_to_supplier 5766 5766 0 0.00 0 4541 13 0 0
t$perf-norm:sp_reserve_write_off 9696 9696 0 0.00 0 7129 10 0 0
t$perf-norm:sp_supplier_invoice 5405 5405 0 0.00 0 6602 16 0 0
t$perf-norm:sp_supplier_order 5297 5297 0 0.00 0 8456 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 880 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 633 14 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1031 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9902 9650 252 2.54 0 14596 24 0 252
v_all_customers 37032 37032 0 0.00 0 23 0 0 0
v_all_suppliers 29926 29926 0 0.00 0 17 1 0 0
v_all_wares 40084 40084 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 478 6 0 0
v_cancel_client_order 3438 3438 0 0.00 0 2766 5 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4021 14 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 983 4 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 13715 27 0 0
v_cancel_supplier_invoice 814 786 28 3.43 0 5336 21 0 28
v_cancel_supplier_order 866 866 0 0.00 0 2634 11 0 0
v_cancel_write_off 1918 1918 0 0.00 0 2703 15 0 0
v_max_id_clo_ord 5297 5297 0 0.00 0 1400 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 2817 15 0 0
v_max_id_ord_sup 5403 5403 0 0.00 0 3797 3 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 237 2 0 0
v_max_non_paid_realizn 3588 3588 0 0.00 0 903 3 0 0
v_min_id_clo_ord 5297 5297 0 0.00 0 3274 7 0 0
v_min_id_clo_res 974 974 0 0.00 0 6769 25 0 0
v_min_id_ord_sup 5403 5403 0 0.00 0 3237 4 0 0
v_min_non_paid_invoice 5766 5766 0 0.00 0 8143 17 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 7906 24 0 0
v_our_firm 9898 9898 0 0.00 0 33 1 0 0
v_reserve_write_off 19392 19392 0 0.00 0 14526 9 0 0
x_lock_depdocs_on_canc_invoice 12545 12545 0 0.00 0 18576 99 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 10300 291 0 0
x_make_qstorno_1000_1200 5297 5297 0 0.00 1 28047 1183 0 0
x_make_qstorno_1200_2000 5405 5405 0 0.00 3 72796 3119 0 0
x_make_qstorno_2100_3300 453486 453486 0 0.00 0 64235 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-05-2022 01:39:49 to 07-05-2022 01:39:53.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:17 39735 8192000000 9923064688 10234626048 997941824 10.859 100 86 172 0 1026280880 6322768 22016288
23:11:03 15573 8192000000 9959603584 10285428736 1045817024 11.321 100 91 182 0 1077839632 8751152 23271456
23:12:31 20416 8192000000 9994028784 10330497024 1077560880 11.624 100 85 170 2 1109418544 10603776 21253888
23:14:01 11671 8192000000 10048828128 10399707136 1136531072 12.183 100 84 168 1 1168184480 11260192 20393216
23:15:15 7373 8192000000 10095753072 10458664960 1187548192 12.661 100 77 154 2 1216798848 11778384 17472272
23:16:25 4462 8192000000 10147186240 10517889024 1230471376 13.058 100 83 164 1 1262581872 13923904 18186592
23:17:31 3205 8192000000 10189977264 10566324224 1269122592 13.414 100 91 182 1 1304359312 14780016 20456704
23:18:36 2425 8192000000 10213116576 10597670912 1293650096 13.637 100 85 170 1 1326035296 13286848 19098352
23:19:42 24550 8192000000 10214231568 10609979392 1306940704 13.758 100 63 123 0 1330854112 9798496 14114912
23:21:08 7183 8192000000 10208318544 10617049088 1317778048 13.857 100 37 75 0 1334435504 8570448 8087008
23:22:17 14414 8192000000 10215579328 10632245248 1328843488 13.957 100 31 62 1 1342993648 5961488 8188672
23:23:33 16993 8192000000 10224378576 10646470656 1332792304 13.992 100 16 32 3 1346393952 10073488 3528160
23:24:54 11007 8192000000 10245459904 10662395904 1344382928 14.097 100 27 55 1 1357552800 5997024 7172848
23:26:05 8351 8192000000 10235699008 10659622912 1347218432 14.122 100 14 27 1 1356416816 5837984 3360400
23:27:18 56931 8192000000 10214893600 10640084992 1302272208 13.716 97 27 54 0 1314574000 5776864 6524928
23:29:15 6196 8192000000 10192021920 10600108032 1277326096 13.489 98 36 71 0 1296343200 10748448 8268656
23:30:22 9614 8192000000 10160440032 10563547136 1228715840 13.042 96 17 34 0 1237942320 5626304 3600176
23:31:32 6419 8192000000 10139966112 10537668608 1245786480 13.199 100 31 61 2 1258505696 5788720 6930496
23:32:38 34488 8192000000 10145302848 10547970048 1216943392 12.933 96 39 78 0 1232105824 5534496 9627936
23:34:14 5941 8192000000 10181158400 10585464832 1282300976 13.534 100 30 60 1 1295501728 6080864 7119888
23:35:20 13428 8192000000 10188730496 10599538688 1290479984 13.609 100 27 53 2 1306359824 9525472 6354368
23:36:34 41771 8192000000 10200930032 10608123904 1305272144 13.743 100 23 47 2 1316290736 5389952 5628640
23:38:26 23244 8192000000 10241485280 10649174016 1332409600 13.989 100 57 112 0 1353401040 8603600 12387840
23:39:55 30856 8192000000 10267446768 10679078912 1351670480 14.163 100 49 98 0 1376858944 14366000 10822464
23:41:29 58448 8192000000 10294458368 10719117312 1380152704 14.418 100 37 74 1 1409114080 19998592 8962784
23:43:30 10777 8192000000 10320771664 10740629504 1398501104 14.582 100 49 98 2 1433909552 22996784 12411664
23:44:45 34168 8192000000 10334870720 10762588160 1409745744 14.682 100 45 90 1 1448624672 27300768 11578160
23:46:29 7242 8192000000 10360765008 10781298688 1426056032 14.826 100 59 116 0 1471637136 31289776 14291328
23:47:41 6743 8192000000 10369103456 10786631680 1424893584 14.816 100 71 141 1 1479392912 36518304 17981024
23:48:53 3682 8192000000 10377847056 10796326912 1429511392 14.857 100 68 135 0 1486069520 39310176 17247952
23:50:00 6195 8192000000 10382420944 10799239168 1428172144 14.845 100 66 132 2 1487414752 43345120 15897488
23:51:12 2739 8192000000 10381749328 10797490176 1423841104 14.807 100 71 140 0 1487786496 45976400 17968992
23:52:21 2120 8192000000 10386008560 10798936064 1419352288 14.767 100 91 180 0 1490805264 48217792 23235184
23:53:30 2580 8192000000 10371831904 10791927808 1405413520 14.643 100 66 132 3 1477052208 53898224 17740464
23:54:36 11605 8192000000 10329923072 10759237632 1371571008 14.341 98 51 102 1 1436353200 51374960 13407232
23:56:37 5850 8192000000 10375583584 10801999872 1413201456 14.712 100 59 119 0 1478708160 49622528 15884176
23:57:45 15543 8192000000 10347870176 10783846400 1383049136 14.444 99 39 77 0 1445565712 51666736 10849840
23:59:21 19706 8192000000 10280518304 10727534592 1322452160 13.899 97 21 42 0 1374646992 45949920 6244912
00:01:35 13890 8192000000 10305196176 10742247424 1343278720 14.087 98 31 62 0 1395397456 43882512 8236224
00:03:07 24325 8192000000 10296690384 10737672192 1365432576 14.286 100 18 36 0 1404899392 34480976 4985840
00:05:23 20399 8192000000 10259812672 10687242240 1313787088 13.820 97 44 83 2 1329981584 6269648 9924848
00:06:51 95947 8192000000 10260349552 10700222464 1311485984 13.800 97 14 28 1 1323453728 9034576 2933168
00:09:35 95485 8192000000 10265397328 10712035328 1306743552 13.757 95 16 32 0 1323172992 13251648 3177792
00:12:12 141579 8192000000 10303317184 10744582144 1358345536 14.222 98 26 50 0 1376006128 12307424 5353168
00:15:35 36092 8192000000 10286709488 10744868864 1379524288 14.412 100 32 64 0 1400352464 13160272 7667904
00:17:11 39077 8192000000 10283341680 10746998784 1378873296 14.406 100 10 20 0 1396082496 14982464 2226736
00:18:56 45871 8192000000 10275922192 10738831360 1369037232 14.318 99 7 13 0 1379502000 9269600 1195168
00:20:45 96738 8192000000 10280428720 10740211712 1370074736 14.328 99 15 30 1 1382824960 8893920 3856304
00:23:56 8876 8192000000 10296907648 10742390784 1385404096 14.465 100 60 116 1 1406141600 6693488 14044016
00:25:06 88235 8192000000 10281786976 10759692288 1384722112 14.459 100 15 30 1 1402568528 14258192 3588224
00:27:34 49313 8192000000 10281777168 10735894528 1348523920 14.134 97 11 21 1 1362993856 12219344 2250592
00:29:24 48136 8192000000 10302650256 10758430720 1387645712 14.485 99 18 36 1 1408438624 16740864 4052048
00:31:14 57507 8192000000 10323478432 10780831744 1390750272 14.513 99 16 32 0 1416742416 21846768 4145376
00:33:18 117037 8192000000 10292467072 10763640832 1351046944 14.157 97 10 20 0 1378396640 25249808 2099888
00:36:16 40302 8192000000 10300201856 10743418880 1365133120 14.283 99 32 64 3 1382946368 10687024 7126224
00:37:57 61166 8192000000 10290191536 10729893888 1320168032 13.878 96 38 76 1 1338103552 9082704 8852816
00:40:02 96263 8192000000 10272990128 10723377152 1347222240 14.122 98 21 42 0 1362442560 10771216 4449104
00:42:42 72418 8192000000 10273016176 10735120384 1365681712 14.288 99 23 46 1 1383846624 12744320 5420592
00:44:55 73619 8192000000 10266094880 10721226752 1351272112 14.159 98 14 28 2 1368804656 14040016 3492528
00:47:13 93384 8192000000 10262778752 10717237248 1334923040 14.012 97 18 34 0 1350736064 10887616 4925408
00:50:00 85409 8192000000 10284320160 10755923968 1373765424 14.361 100 9 18 0 1399539456 23700832 2073200
00:52:26 44427 8192000000 10255312208 10699636736 1334145520 14.005 97 30 60 1 1354657936 13429840 7082576
00:54:19 44305 8192000000 10267446928 10708271104 1297579392 13.673 95 26 51 1 1315727360 12405760 5742208
00:56:09 71202 8192000000 10251617424 10692837376 1298786032 13.684 96 30 60 1 1318310336 13138144 6386160
00:58:20 29145 8192000000 10265555456 10710454272 1354765248 14.190 100 32 64 0 1375386240 12908656 7712336
00:59:50 44882 8192000000 10278741840 10719580160 1359590112 14.234 100 19 38 0 1372837296 9418752 3828432
01:01:35 29395 8192000000 10294801392 10724618240 1357435584 14.214 100 38 76 0 1379190192 12690128 9064480
01:03:05 103073 8192000000 10253425312 10701443072 1309554080 13.782 96 22 44 0 1327941936 13230992 5156864
01:05:57 74862 8192000000 10260689520 10720587776 1354282272 14.186 99 30 60 0 1369760256 8239152 7238832
01:08:15 70358 8192000000 10270465168 10732126208 1351431824 14.160 98 6 12 0 1360519616 7917984 1169808
01:10:30 100248 8192000000 10285654352 10745495552 1373203280 14.356 99 17 34 0 1384203152 7174656 3825216
01:13:10 86953 8192000000 10285938064 10743590912 1336438608 14.025 96 20 40 1 1350561200 9184224 4938368
01:15:45 58987 8192000000 10310505200 10760126464 1383323088 14.446 99 21 40 1 1401262336 13380112 4559136
01:17:44 38190 8192000000 10306991440 10748698624 1371674816 14.342 98 37 74 0 1390478096 10411792 8391488
01:19:28 77368 8192000000 10305363792 10780844032 1389136352 14.498 99 22 44 0 1408824128 14760256 4927520
01:21:52 37234 8192000000 10302676592 10743332864 1339118240 14.049 96 35 70 0 1356544448 10030816 7395392
01:23:32 78223 8192000000 10287666912 10739290112 1341718976 14.073 97 14 28 0 1358012192 13342384 2950832
01:25:51 113566 8192000000 10273196112 10725621760 1342340048 14.079 97 22 44 0 1359056336 11830912 4885376
01:28:58 66053 8192000000 10286749920 10732179456 1353685696 14.181 98 32 64 0 1374672048 13405280 7581072
01:31:04 57040 8192000000 10280843456 10747473920 1367099952 14.301 99 25 49 2 1386232752 12829712 6303088
01:33:02 47349 8192000000 10297506912 10765672448 1390594224 14.511 100 10 20 0 1409279616 16331264 2354128
01:34:49 71573 8192000000 10275929392 10736701440 1359519008 14.233 98 16 31 1 1376179168 13084576 3575584
01:37:01 82918 8192000000 9622967200 9981878272 741820352 8.303 54 7 14 0 751835616 8357472 1657792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-05-2022 01:39:55 to 07-05-2022 01:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-05-2022 01:40:10 to 07-05-2022 01:40:12.

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          236423
MON$OLDEST_ACTIVE               236424
MON$OLDEST_SNAPSHOT             236424
MON$NEXT_TRANSACTION            236424
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                       2024448
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.33586 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33586 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33586 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33586 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 ---
:109: 
:110: mscs-test-161.budzdorov.ru    Sat May  7 01:38:30 2022
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Sat May  7 01:38:34 2022
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
--- end of diff output ---

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

07.05.22 01:40:13. 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-07 01:40:16 No severe PSQL-related problems occured
07.05.22 01:40:17. End of report.