Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15890552 kB
MemAvailable 30840844 kB
Buffers 0 kB
Cached 15820848 kB
SwapCached 4032 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.8
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 2021-11-03 01:37:51
DTS_END                         2021-11-03 01:37:51.8380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32822
TRN_ID                          244841
ATT_ID                          683
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: 9633.21 20330 1155985
customer order: creation 176.29 333 21155
customer order: refuse 15.38 359 1845
order to supplier: creation 47.81 1257 5737
order to supplier: removal 3.80 96010 456
invoice (draft): creation 46.84 3118 5621
invoice (draft): removal 57.06 717 6847
invoice accept: apply 41.87 17288 5024
invoice accept: cancel 51.16 721 6139
customer reserve: creation 3889.69 170 466763
customer reserve: removal 4986.55 58 598386
realization accept: apply 86.34 198 10361
realization accept: cancel 96.15 105 11538
payment from customer: creation 61.07 127 7328
payment from customer: removal 6.03 115 723
payment to supplier: creation 50.78 137 6094
payment to supplier: removal 3.69 69 443
service: total inventory turnovers 4.68 1176 561
service: total monetary turnovers 7.16 24 859
service: aggregate perf. data 0.84 21359 101
service: refresh index statistics 0.03 263257 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2021 01:39:49 to 03-11-2021 01:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4370
TEST_TIME 2 3160
TEST_TIME 3 3532
TEST_TIME 4 3935
TEST_TIME 5 4047
TEST_TIME 6 4274
TEST_TIME 7 5072
TEST_TIME 8 5011
TEST_TIME 9 5050
TEST_TIME 10 5128
TEST_TIME 11 5200
TEST_TIME 12 5394
TEST_TIME 13 5461
TEST_TIME 14 5932
TEST_TIME 15 6178
TEST_TIME 16 6329
TEST_TIME 17 6744
TEST_TIME 18 7350
TEST_TIME 19 7593
TEST_TIME 20 7724
TEST_TIME 21 7635
TEST_TIME 22 7887
TEST_TIME 23 8294
TEST_TIME 24 8350
TEST_TIME 25 8486
TEST_TIME 26 8426
TEST_TIME 27 8862
TEST_TIME 28 9671
TEST_TIME 29 9977
TEST_TIME 30 10293
TEST_TIME 31 10115
TEST_TIME 32 10220
TEST_TIME 33 10303
TEST_TIME 34 10339
TEST_TIME 35 10242
TEST_TIME 36 10209
TEST_TIME 37 10318
TEST_TIME 38 10126
TEST_TIME 39 10011
TEST_TIME 40 9863
TEST_TIME 41 9964
TEST_TIME 42 9845
TEST_TIME 43 9899
TEST_TIME 44 9854
TEST_TIME 45 9793
TEST_TIME 46 9766
TEST_TIME 47 9748
TEST_TIME 48 9750
TEST_TIME 49 9876
TEST_TIME 50 9875
TEST_TIME 51 9797
TEST_TIME 52 9721
TEST_TIME 53 9688
TEST_TIME 54 9676
TEST_TIME 55 9666
TEST_TIME 56 9699
TEST_TIME 57 9711
TEST_TIME 58 9759
TEST_TIME 59 9765
TEST_TIME 60 9776
TEST_TIME 61 9799
TEST_TIME 62 9759
TEST_TIME 63 9876
TEST_TIME 64 9893
TEST_TIME 65 10104
TEST_TIME 66 10114
TEST_TIME 67 10091
TEST_TIME 68 10025
TEST_TIME 69 9991
TEST_TIME 70 9928
TEST_TIME 71 9921
TEST_TIME 72 9865
TEST_TIME 73 9822
TEST_TIME 74 9826
TEST_TIME 75 9785
TEST_TIME 76 9750
TEST_TIME 77 9719
TEST_TIME 78 9722
TEST_TIME 79 9677
TEST_TIME 80 9695
TEST_TIME 81 9680
TEST_TIME 82 9659
TEST_TIME 83 9710
TEST_TIME 84 9696
TEST_TIME 85 9746
TEST_TIME 86 9761
TEST_TIME 87 9795
TEST_TIME 88 9770
TEST_TIME 89 9824
TEST_TIME 90 9913
TEST_TIME 91 9913
TEST_TIME 92 9914
TEST_TIME 93 9915
TEST_TIME 94 9822
TEST_TIME 95 9778
TEST_TIME 96 9791
TEST_TIME 97 9746
TEST_TIME 98 9708
TEST_TIME 99 9652
TEST_TIME 100 9625
TEST_TIME 101 9593
TEST_TIME 102 9601
TEST_TIME 103 9557
TEST_TIME 104 9523
TEST_TIME 105 9472
TEST_TIME 106 9427
TEST_TIME 107 9445
TEST_TIME 108 9468
TEST_TIME 109 9468
TEST_TIME 110 9453
TEST_TIME 111 9447
TEST_TIME 112 9527
TEST_TIME 113 9491
TEST_TIME 114 9509
TEST_TIME 115 9498
TEST_TIME 116 9565
TEST_TIME 117 9644
TEST_TIME 118 9683
TEST_TIME 119 9674
TEST_TIME 120 9643

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2021 01:39:53 to 03-11-2021 01:39:56.

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 1589511 1589511 0 0.00 0 21605 6 0 0
doc_list_biud 1589511 1589511 0 0.00 0 34368 27 0 0
sp_add_invoice_to_stock 5205 5024 181 3.47 42 196664 17289 0 181
sp_cancel_adding_invoice 6139 6139 0 0.00 5 71615 721 0 0
sp_cancel_client_order 1845 1845 0 0.00 3 13915 360 0 0
sp_cancel_customer_reserve 598386 598386 0 0.00 1 34369 58 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 8713 115 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 2 3863 70 0 0
sp_cancel_supplier_invoice 6862 6847 15 0.21 2 24696 717 0 15
sp_cancel_supplier_order 456 456 0 0.00 7 1122791 96010 0 0
sp_cancel_write_off 11538 11538 0 0.00 1 17501 105 0 0
sp_client_order 21155 21155 0 0.00 3 21855 334 0 0
sp_customer_reserve 466763 466763 0 0.00 0 57414 170 0 0
sp_fill_shopping_cart 32510 32510 0 0.00 1 8749 36 0 0
sp_fill_shopping_cart_clo_res 466751 466751 0 0.00 0 19389 13 0 0
sp_get_clo_for_invoice 5011 5011 0 0.00 0 32875 255 0 0
sp_kill_cost_storno 18843 18843 0 0.00 0 9752 53 0 0
sp_kill_qstorno_ret_qs2qd 1215613 1215613 0 0.00 0 32479 31 0 0
sp_kill_qty_storno 640583 640583 0 0.00 0 34367 67 0 0
sp_lock_selected_doc 21395 21395 0 0.00 0 8603 7 0 0
sp_make_cost_storno 28793 28793 0 0.00 0 20060 60 0 0
sp_multiply_rows_for_pdistr 28793 28793 0 0.00 0 9267 32 0 0
sp_multiply_rows_for_qdistr 21155 21155 0 0.00 0 21848 147 0 0
sp_pay_from_customer 7328 7328 0 0.00 4 14423 128 0 0
sp_pay_to_supplier 6094 6094 0 0.00 5 20078 138 0 0
sp_payment_common 13421 13421 0 0.00 1 10563 39 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 13897 311 0 0
sp_qd_handle_on_invoice_upd_sts 11150 11150 0 0.00 0 15457 299 0 0
sp_qd_handle_on_reserve_upd_sts 10361 10361 0 0.00 0 13216 55 0 0
sp_reserve_write_off 10361 10361 0 0.00 6 15652 199 0 0
sp_supplier_invoice 5621 5621 0 0.00 6 74229 3119 0 0
sp_supplier_order 5737 5737 0 0.00 5 22643 1257 0 0
srv_aggregate_perf_data 101 101 0 0.00 2557 287768 21359 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2581 163335 46376 0 0
srv_find_qd_qs_mism 1107757 1107757 0 0.00 0 21602 5 0 0
srv_make_invnt_saldo 561 561 0 0.00 53 47035 1177 0 0
srv_make_money_saldo 859 859 0 0.00 1 1273 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 54471 348222 263258 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5024 5024 0 0.00 0 18258 193 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 4352 87 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 3939 17 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 9239 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3556 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1426 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 4815 32 0 0
t$perf-norm:sp_cancel_supplier_order 456 456 0 0.00 0 36283 1844 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 1254 7 0 0
t$perf-norm:sp_client_order 21155 21155 0 0.00 0 13605 22 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 2566 13 0 0
t$perf-norm:sp_pay_from_customer 7328 7328 0 0.00 0 11969 16 0 0
t$perf-norm:sp_pay_to_supplier 6094 6094 0 0.00 0 17525 22 0 0
t$perf-norm:sp_reserve_write_off 10361 10361 0 0.00 0 11997 12 0 0
t$perf-norm:sp_supplier_invoice 5621 5621 0 0.00 0 9416 21 0 0
t$perf-norm:sp_supplier_order 5737 5737 0 0.00 0 13123 25 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1023 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 212 5 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 1564 24 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 1471 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10382 10020 362 3.48 0 6180 23 0 362
v_all_customers 38918 38918 0 0.00 0 21 1 0 0
v_all_suppliers 31596 31596 0 0.00 0 21 1 0 0
v_all_wares 42304 42304 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 4605 13 0 0
v_cancel_client_order 3690 3690 0 0.00 0 3419 6 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 3948 8 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 2905 5 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 2986 9 0 0
v_cancel_supplier_invoice 836 806 30 3.58 0 2354 27 0 30
v_cancel_supplier_order 912 912 0 0.00 0 498 4 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1994 14 0 0
v_max_id_clo_ord 5736 5736 0 0.00 0 2050 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 2085 16 0 0
v_max_id_ord_sup 5621 5621 0 0.00 0 4077 4 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 5209 7 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 3615 5 0 0
v_min_id_clo_ord 5736 5736 0 0.00 0 3689 12 0 0
v_min_id_clo_res 995 995 0 0.00 0 4261 16 0 0
v_min_id_ord_sup 5621 5621 0 0.00 0 6958 7 0 0
v_min_non_paid_invoice 6094 6094 0 0.00 0 9386 30 0 0
v_min_non_paid_realizn 7327 7327 0 0.00 0 10559 28 0 0
v_our_firm 10502 10502 0 0.00 0 11 1 0 0
v_reserve_write_off 20720 20720 0 0.00 0 4703 8 0 0
x_lock_depdocs_on_canc_invoice 12583 12583 0 0.00 0 15001 110 0 0
x_lock_depdocs_on_canc_sup_ord 456 456 0 0.00 0 6865 315 0 0
x_make_qstorno_1000_1200 5737 5737 0 0.00 1 22589 1170 0 0
x_make_qstorno_1200_2000 5621 5621 0 0.00 3 74205 3001 0 0
x_make_qstorno_2100_3300 466763 466763 0 0.00 0 57410 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2021 01:39:57 to 03-11-2021 01:40:01.

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:07:55 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:57 36274 8192000000 9909993184 10223968256 990997200 10.791 100 82 164 0 1018364080 5885712 21481168
23:10:37 25712 8192000000 9959059680 10285072384 1041337120 11.278 100 87 174 0 1072395152 8172896 22885136
23:12:09 13108 8192000000 9994483264 10331189248 1079846352 11.646 100 85 170 0 1110460848 9948688 20665808
23:13:43 8949 8192000000 10064496720 10419429376 1149356448 12.303 100 83 166 0 1180263248 11328784 19578016
23:14:59 8818 8192000000 10108379168 10471366656 1191326432 12.696 100 80 160 0 1221630272 12494064 17809776
23:16:09 4391 8192000000 10144629584 10515214336 1226936320 13.026 100 83 166 1 1258992944 13826832 18229792
23:17:15 3588 8192000000 10185735952 10566234112 1263728192 13.364 100 77 155 3 1293729856 13360320 16641344
23:18:22 1910 8192000000 10215801056 10600185856 1294214800 13.643 100 91 182 2 1327927792 13424192 20288800
23:19:26 1964 8192000000 10214538912 10602700800 1301040416 13.705 100 89 175 3 1334275392 12947152 20287824
23:20:28 4351 8192000000 10192264304 10601152512 1308713728 13.774 100 42 85 1 1328221840 9637648 9870464
23:21:36 9983 8192000000 10199105680 10613862400 1317426704 13.853 100 24 47 2 1328602096 6276208 4899184
23:22:47 11200 8192000000 10203976128 10623664128 1322559968 13.900 100 17 34 0 1333281376 6544672 4176736
23:23:59 67067 8192000000 10213797760 10638508032 1330265104 13.970 100 46 91 3 1346758880 5881712 10612064
23:26:10 6462 8192000000 10227094832 10652098560 1333701472 14.001 100 32 64 0 1348571536 7156896 7713168
23:27:18 8621 8192000000 10212341248 10632650752 1314065808 13.823 99 14 26 2 1322706528 5573024 3067696
23:28:28 13350 8192000000 10195799344 10595057664 1257119488 13.304 95 39 79 0 1271151616 4168880 9863248
23:29:42 17339 8192000000 10173027856 10586734592 1217236768 12.936 93 23 46 2 1235540784 12860704 5443312
23:31:00 5334 8192000000 10140839856 10542112768 1247447200 13.215 100 33 66 1 1262603808 6968000 8188608
23:32:09 8090 8192000000 10140219904 10534993920 1200294000 12.779 96 26 52 1 1212024368 5251344 6479024
23:33:18 52590 8192000000 10186224992 10584969216 1275780176 13.474 100 29 58 1 1291433744 8849808 6803760
23:35:12 5718 8192000000 10184618208 10598973440 1293235824 13.634 100 24 48 1 1309728768 10732064 5760880
23:36:18 7044 8192000000 10197762800 10608709632 1304876720 13.740 100 29 58 1 1316975744 5351792 6747232
23:37:25 7858 8192000000 10210643232 10629951488 1323322272 13.907 100 30 58 1 1335301392 5475152 6503968
23:38:38 21769 8192000000 10248965632 10660376576 1343405232 14.088 100 49 98 0 1362352944 6983616 11964096
23:40:03 46938 8192000000 10260881264 10683559936 1353165200 14.176 100 37 72 3 1375749904 13525072 9059632
23:41:59 13016 8192000000 10284757824 10701893632 1374925456 14.371 100 61 122 1 1399937232 11403264 13608512
23:43:20 22800 8192000000 10301395600 10722131968 1387699056 14.485 100 61 122 1 1415656480 13831056 14126368
23:44:46 10352 8192000000 10296892496 10728644608 1391439136 14.519 100 39 76 2 1411453728 10929168 9085424
23:46:01 11944 8192000000 10317691472 10747482112 1406546832 14.653 100 36 72 0 1431315472 16000992 8767648
23:47:19 3501 8192000000 10321629696 10744696832 1382686224 14.441 98 62 122 1 1415831872 18620816 14524832
23:48:28 5894 8192000000 10337773280 10758238208 1406345248 14.651 100 66 132 1 1445860272 23270704 16244320
23:49:38 4463 8192000000 10357264832 10777542656 1425226256 14.819 100 73 147 2 1468022016 24272672 18523088
23:50:48 8716 8192000000 10355689584 10771329024 1412893520 14.710 100 74 148 1 1459124576 27952432 18278624
23:52:01 3565 8192000000 10357025328 10768961536 1410928960 14.692 100 83 164 0 1462233584 30412416 20892208
23:53:07 6593 8192000000 10320978144 10726334464 1360728608 14.244 99 79 157 1 1417129856 36477616 19923632
23:54:21 2710 8192000000 10317339504 10722074624 1366060112 14.292 99 84 169 5 1425300944 37382256 21858576
23:55:31 2581 8192000000 10324944304 10727661568 1365925936 14.291 100 85 166 1 1427178016 40136464 21115616
23:56:37 10256 8192000000 10328136624 10751336448 1391767184 14.522 100 51 101 2 1433018032 28403200 12847648
23:57:50 4575 8192000000 10325209728 10749440000 1385542544 14.466 100 48 96 0 1428718240 31018528 12157168
23:59:01 4989 8192000000 10319752048 10734055424 1369651632 14.324 100 57 114 2 1421663760 37116800 14895328
00:00:10 11264 8192000000 10316842192 10749423616 1376433936 14.385 100 40 80 1 1427237536 39462144 11341456
00:01:41 10846 8192000000 10328299328 10758344704 1389838688 14.504 100 29 58 2 1430811552 32900864 8072000
00:02:55 17123 8192000000 10324228256 10753130496 1379857920 14.415 100 27 54 2 1424231264 36635248 7738096
00:04:15 15692 8192000000 10333098128 10774556672 1386562928 14.475 100 34 69 0 1443058976 45658928 10837120
00:05:51 20802 8192000000 10293023008 10760896512 1369056336 14.319 100 16 32 0 1407713248 33631904 5025008
00:07:49 33746 8192000000 10293728640 10726940672 1364172800 14.275 99 28 59 2 1379379136 8441184 6765152
00:09:26 33321 8192000000 10274333904 10726019072 1350831504 14.155 98 8 16 0 1369099216 16500384 1767328
00:11:04 75582 8192000000 10298975920 10753486848 1357680512 14.217 98 8 17 1 1371996960 13069600 1246848
00:13:22 86562 8192000000 10316656320 10772307968 1396764656 14.566 100 16 32 0 1421816304 21465216 3586432
00:15:49 98120 8192000000 10309259952 10751393792 1364547680 14.278 98 31 60 1 1382178640 9651312 7979648
00:18:37 100729 8192000000 10313659424 10764595200 1381500128 14.430 99 15 30 0 1401698560 17034928 3163504
00:21:19 9333 8192000000 10315786544 10762743808 1399790192 14.593 100 31 63 0 1418558400 11666960 7101248
00:22:36 38293 8192000000 10325238112 10773458944 1401846272 14.611 100 26 53 0 1425075152 16631264 6597616
00:24:27 64595 8192000000 10314604000 10777038848 1404459008 14.635 100 24 48 0 1430929024 21031152 5438864
00:26:34 49314 8192000000 10307902624 10775015424 1393359440 14.536 99 11 22 0 1407916208 12274464 2282304
00:28:24 30058 8192000000 10296127824 10753658880 1386247360 14.472 99 13 26 2 1404745856 15563312 2935184
00:29:55 45495 8192000000 10285931840 10759778304 1373695616 14.360 98 12 24 0 1386362736 10113072 2554048
00:31:41 72318 8192000000 10292135072 10756980736 1327040240 13.940 95 27 54 0 1353476768 20819280 5617248
00:34:03 41400 8192000000 10291507984 10738929664 1345377408 14.106 97 16 31 0 1360831024 11947888 3505728
00:35:54 31120 8192000000 10292864432 10740662272 1377868464 14.397 99 23 46 0 1392467632 8862432 5736736
00:37:29 63622 8192000000 10296180976 10754150400 1381320416 14.428 99 24 48 0 1397542736 11013520 5208800
00:39:33 86870 8192000000 10260229744 10715652096 1256701584 13.300 91 12 24 2 1273071376 13654704 2715088
00:42:00 132275 8192000000 10279712080 10750177280 1307484816 13.763 98 12 24 1 1336711616 26902064 2324736
00:45:20 50315 8192000000 10267806144 10700701696 1315586112 13.837 97 43 86 2 1336885392 11702016 9597264
00:47:21 119974 8192000000 10264354896 10716913664 1300191440 13.697 95 16 32 0 1313198960 9900096 3107424
00:50:26 19935 8192000000 10285536944 10712555520 1334451776 14.007 98 55 110 1 1358646368 12072352 12122240
00:51:57 76474 8192000000 10264441984 10719191040 1333064400 13.995 97 14 28 0 1348153376 11990640 3098336
00:54:28 50631 8192000000 10266249632 10723540992 1366527392 14.296 100 27 54 2 1385029168 11674208 6827568
00:56:27 60431 8192000000 10262684656 10731732992 1355081200 14.193 99 14 28 1 1369128752 10452480 3595072
00:58:30 51055 8192000000 10264342112 10719612928 1350523264 14.152 98 11 22 0 1362048240 9143328 2381648
01:00:38 56617 8192000000 10273090976 10723090432 1354150768 14.185 99 27 54 2 1373125360 12403664 6570928
01:02:37 38235 8192000000 10282829184 10749460480 1379414992 14.411 100 14 29 0 1394636160 12009952 3211216
01:04:40 47855 8192000000 10308374416 10757541888 1371870176 14.344 99 27 54 0 1388161664 10658928 5632560
01:06:29 55554 8192000000 10309985600 10786275328 1392053712 14.524 100 22 44 0 1416622112 19768032 4800368
01:08:25 163335 8192000000 10302826976 10767282176 1337224016 14.032 95 8 16 0 1350048096 11331136 1492944
01:13:32 80678 8192000000 10278350752 10748137472 1371660848 14.342 98 3 6 0 1383936656 11666208 609600
01:15:53 81693 8192000000 10301042560 10762092544 1383484384 14.448 99 15 30 0 1396756752 9902480 3369888
01:18:21 70619 8192000000 10299626208 10762178560 1370289472 14.330 98 18 35 2 1392947616 19227168 3430976
01:20:34 82178 8192000000 10280690608 10746486784 1355382544 14.196 97 14 28 0 1371159312 12887440 2889328
01:22:56 63589 8192000000 10282454432 10747314176 1346886304 14.119 97 13 26 0 1361302544 11743696 2672544
01:25:00 78179 8192000000 10272428880 10733219840 1323285664 13.906 95 8 16 0 1338766064 13359808 2120592
01:27:19 28929 8192000000 10287158128 10737414144 1362358736 14.259 99 37 74 1 1382643600 11970624 8314240
01:28:55 83578 8192000000 10253913008 10715590656 1280487472 13.517 93 15 30 0 1300524160 16433232 3603456
01:31:23 65372 8192000000 10274973872 10723475456 1353139504 14.176 99 26 52 2 1371735600 12513248 6082848
01:33:46 32829 8192000000 10279168144 10725457920 1323386912 13.907 97 30 60 0 1349958272 19960976 6610384
01:35:20 110422 8192000000 10277012688 10730668032 1306113440 13.751 96 13 26 0 1326379600 17830560 2435600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-11-2021 01:40:03 to 03-11-2021 01:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2021 01:40:19 to 03-11-2021 01:40:21.

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          244628
MON$OLDEST_ACTIVE               245693
MON$OLDEST_SNAPSHOT             245693
MON$NEXT_TRANSACTION            245693
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                       2048928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33526 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33526 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33526 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33526 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Nov  3 01:37:56 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Nov  3 01:38:13 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Nov  3 01:38:19 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

03.11.21 01:40:22. 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
2021-11-03 01:40:24 No severe PSQL-related problems occured
03.11.21 01:40:25. End of report.