Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 10:37
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 16513784 kB
MemAvailable 31548972 kB
Buffers 0 kB
Cached 15135184 kB
SwapCached 0 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-23 10:37:07
DTS_END                         2021-11-23 10:37:07.7160
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(68:17)
IP                              ::1/41304
TRN_ID                          234438
ATT_ID                          621
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: 9553.13 20405 1146376
customer order: creation 168.26 419 20191
customer order: refuse 14.54 300 1745
order to supplier: creation 43.84 1352 5261
order to supplier: removal 3.64 99820 437
invoice (draft): creation 45.08 3270 5410
invoice (draft): removal 56.82 782 6818
invoice accept: apply 40.74 17623 4889
invoice accept: cancel 50.81 752 6097
customer reserve: creation 3797.13 173 455656
customer reserve: removal 5038.73 57 604648
realization accept: apply 82.16 178 9859
realization accept: cancel 83.71 108 10045
payment from customer: creation 59.62 108 7154
payment from customer: removal 5.71 72 685
payment to supplier: creation 48.36 108 5803
payment to supplier: removal 3.58 51 429
service: total inventory turnovers 3.81 3367 457
service: total monetary turnovers 5.71 483 685
service: aggregate perf. data 0.86 20374 103
service: refresh index statistics 0.03 258712 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 10:38:08 to 23-11-2021 10:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5120
TEST_TIME 2 5655
TEST_TIME 3 5725
TEST_TIME 4 5362
TEST_TIME 5 4986
TEST_TIME 6 4862
TEST_TIME 7 5116
TEST_TIME 8 5016
TEST_TIME 9 5103
TEST_TIME 10 5374
TEST_TIME 11 5581
TEST_TIME 12 5965
TEST_TIME 13 6565
TEST_TIME 14 6836
TEST_TIME 15 7505
TEST_TIME 16 7838
TEST_TIME 17 8524
TEST_TIME 18 8480
TEST_TIME 19 9218
TEST_TIME 20 9207
TEST_TIME 21 9372
TEST_TIME 22 9946
TEST_TIME 23 10250
TEST_TIME 24 10736
TEST_TIME 25 11269
TEST_TIME 26 11581
TEST_TIME 27 11648
TEST_TIME 28 11530
TEST_TIME 29 11277
TEST_TIME 30 11227
TEST_TIME 31 11580
TEST_TIME 32 11360
TEST_TIME 33 11324
TEST_TIME 34 11566
TEST_TIME 35 11286
TEST_TIME 36 11016
TEST_TIME 37 10947
TEST_TIME 38 10787
TEST_TIME 39 10639
TEST_TIME 40 10540
TEST_TIME 41 10414
TEST_TIME 42 10743
TEST_TIME 43 10678
TEST_TIME 44 10581
TEST_TIME 45 10488
TEST_TIME 46 10390
TEST_TIME 47 10384
TEST_TIME 48 10324
TEST_TIME 49 10255
TEST_TIME 50 10393
TEST_TIME 51 10363
TEST_TIME 52 10384
TEST_TIME 53 10424
TEST_TIME 54 10368
TEST_TIME 55 10314
TEST_TIME 56 10349
TEST_TIME 57 10454
TEST_TIME 58 10499
TEST_TIME 59 10466
TEST_TIME 60 10501
TEST_TIME 61 10357
TEST_TIME 62 10252
TEST_TIME 63 10146
TEST_TIME 64 10101
TEST_TIME 65 10128
TEST_TIME 66 10063
TEST_TIME 67 9957
TEST_TIME 68 9945
TEST_TIME 69 9966
TEST_TIME 70 9878
TEST_TIME 71 9921
TEST_TIME 72 9951
TEST_TIME 73 9892
TEST_TIME 74 9949
TEST_TIME 75 9985
TEST_TIME 76 9953
TEST_TIME 77 10046
TEST_TIME 78 10057
TEST_TIME 79 10040
TEST_TIME 80 10135
TEST_TIME 81 10044
TEST_TIME 82 9974
TEST_TIME 83 9969
TEST_TIME 84 9930
TEST_TIME 85 9897
TEST_TIME 86 9836
TEST_TIME 87 9785
TEST_TIME 88 9750
TEST_TIME 89 9743
TEST_TIME 90 9711
TEST_TIME 91 9710
TEST_TIME 92 9700
TEST_TIME 93 9677
TEST_TIME 94 9719
TEST_TIME 95 9753
TEST_TIME 96 9691
TEST_TIME 97 9774
TEST_TIME 98 9757
TEST_TIME 99 9763
TEST_TIME 100 9808
TEST_TIME 101 9880
TEST_TIME 102 9870
TEST_TIME 103 9807
TEST_TIME 104 9762
TEST_TIME 105 9714
TEST_TIME 106 9680
TEST_TIME 107 9683
TEST_TIME 108 9648
TEST_TIME 109 9610
TEST_TIME 110 9603
TEST_TIME 111 9594
TEST_TIME 112 9570
TEST_TIME 113 9574
TEST_TIME 114 9531
TEST_TIME 115 9531
TEST_TIME 116 9624
TEST_TIME 117 9622
TEST_TIME 118 9582
TEST_TIME 119 9592
TEST_TIME 120 9558

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-11-2021 10:38:12 to 23-11-2021 10:38:15.

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 1569115 1569115 0 0.00 0 17233 6 0 0
doc_list_biud 1569113 1569113 0 0.00 0 22822 28 0 0
sp_add_invoice_to_stock 5068 4889 179 3.53 39 166751 17624 0 179
sp_cancel_adding_invoice 6097 6097 0 0.00 5 69124 753 0 0
sp_cancel_client_order 1745 1745 0 0.00 5 9958 301 0 0
sp_cancel_customer_reserve 604648 604648 0 0.00 1 22832 57 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 2703 73 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 3177 52 0 0
sp_cancel_supplier_invoice 6826 6818 8 0.11 2 15159 783 0 8
sp_cancel_supplier_order 437 437 0 0.00 7 1016563 99821 0 0
sp_cancel_write_off 10045 10045 0 0.00 1 12336 108 0 0
sp_client_order 20191 20191 0 0.00 4 26361 420 0 0
sp_customer_reserve 455656 455656 0 0.00 0 32981 174 0 0
sp_fill_shopping_cart 30857 30857 0 0.00 1 16835 36 0 0
sp_fill_shopping_cart_clo_res 455647 455647 0 0.00 0 12110 16 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 24854 329 0 0
sp_kill_cost_storno 17256 17256 0 0.00 0 8576 51 0 0
sp_kill_qstorno_ret_qs2qd 1226596 1226596 0 0.00 0 22627 31 0 0
sp_kill_qty_storno 644526 644526 0 0.00 0 22822 67 0 0
sp_lock_selected_doc 20520 20520 0 0.00 0 8055 5 0 0
sp_make_cost_storno 27694 27694 0 0.00 0 17227 52 0 0
sp_multiply_rows_for_pdistr 27694 27694 0 0.00 0 9699 30 0 0
sp_multiply_rows_for_qdistr 20191 20191 0 0.00 0 12997 222 0 0
sp_pay_from_customer 7154 7154 0 0.00 4 19043 109 0 0
sp_pay_to_supplier 5803 5803 0 0.00 5 9682 109 0 0
sp_payment_common 12957 12957 0 0.00 1 9307 22 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 9935 277 0 0
sp_qd_handle_on_invoice_upd_sts 10974 10974 0 0.00 0 21452 326 0 0
sp_qd_handle_on_reserve_upd_sts 9859 9859 0 0.00 0 15205 57 0 0
sp_reserve_write_off 9859 9859 0 0.00 6 15515 179 0 0
sp_supplier_invoice 5410 5410 0 0.00 5 84837 3271 0 0
sp_supplier_order 5261 5261 0 0.00 6 47580 1353 0 0
srv_aggregate_perf_data 103 103 0 0.00 2969 293079 20375 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2012 126184 44850 0 0
srv_find_qd_qs_mism 1099791 1099791 0 0.00 0 14581 5 0 0
srv_make_invnt_saldo 457 457 0 0.00 50 79554 3368 0 0
srv_make_money_saldo 685 685 0 0.00 1 18271 483 0 0
srv_recalc_idx_stat 4 4 0 0.00 134344 462786 258713 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4889 4889 0 0.00 0 10337 150 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 2840 105 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 3375 15 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 302 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 8970 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 429 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2795 19 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 22202 1484 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2845 9 0 0
t$perf-norm:sp_client_order 20191 20191 0 0.00 0 17017 20 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 465 2 0 0
t$perf-norm:sp_pay_from_customer 7154 7154 0 0.00 0 10115 15 0 0
t$perf-norm:sp_pay_to_supplier 5803 5803 0 0.00 0 14931 14 0 0
t$perf-norm:sp_reserve_write_off 9859 9859 0 0.00 0 21777 14 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 7402 14 0 0
t$perf-norm:sp_supplier_order 5261 5261 0 0.00 0 7949 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 43 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 119 3 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 2468 19 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 3776 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 157 42 0 0
v_add_invoice_to_stock 10112 9754 358 3.54 0 5970 16 0 358
v_all_customers 37464 37464 0 0.00 0 17 1 0 0
v_all_suppliers 30016 30016 0 0.00 0 98 1 0 0
v_all_wares 40376 40376 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 184 5 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3672 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2216 8 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 3225 6 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 809 5 0 0
v_cancel_supplier_invoice 900 884 16 1.77 0 1557 13 0 16
v_cancel_supplier_order 874 874 0 0.00 0 2739 8 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1834 12 0 0
v_max_id_clo_ord 5261 5261 0 0.00 0 14665 4 0 0
v_max_id_clo_res 958 958 0 0.00 0 1469 13 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 5306 5 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 986 3 0 0
v_max_non_paid_realizn 3558 3558 0 0.00 0 593 2 0 0
v_min_id_clo_ord 5261 5261 0 0.00 0 3902 6 0 0
v_min_id_clo_res 958 958 0 0.00 0 1816 8 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 5006 10 0 0
v_min_non_paid_invoice 5803 5803 0 0.00 0 8636 15 0 0
v_min_non_paid_realizn 7154 7154 0 0.00 0 6918 19 0 0
v_our_firm 10104 10104 0 0.00 0 51 1 0 0
v_reserve_write_off 19718 19718 0 0.00 0 2504 4 0 0
x_lock_depdocs_on_canc_invoice 12473 12473 0 0.00 0 16420 100 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 8329 318 0 0
x_make_qstorno_1000_1200 5261 5261 0 0.00 2 47569 1283 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 2 84826 3140 0 0
x_make_qstorno_2100_3300 455655 455655 0 0.00 0 32979 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 10:38:16 to 23-11-2021 10:38:20.

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
08:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 16286 8192000000 9704428560 9999286272 781255872 8.706 100 85 170 0 811222560 8298080 21668608
08:09:33 21260 8192000000 9760380448 10067316736 833452768 9.234 100 86 172 0 865776720 11988256 20335696
08:11:02 20834 8192000000 9808535344 10126958592 861752240 9.518 100 82 164 0 903776256 23109296 18914720
08:12:25 11524 8192000000 9865728080 10199830528 912952496 10.026 100 74 148 1 975862272 46703472 16206304
08:13:39 10249 8192000000 9921030912 10267140096 962387584 10.512 100 78 156 1 1037551984 58105728 17058672
08:14:52 5747 8192000000 9964060208 10320707584 999384160 10.873 100 84 168 2 1076710240 60339104 16986976
08:16:01 7561 8192000000 9988674496 10357653504 1027717184 11.146 100 77 154 1 1105396608 62119168 15560256
08:17:12 1611 8192000000 10019358992 10386014208 1053130032 11.391 100 88 177 0 1134764384 63993936 17640416
08:18:15 5464 8192000000 10018582272 10406359040 1061885904 11.475 100 47 94 1 1139929600 68577056 9466640
08:19:22 8292 8192000000 9996058992 10387509248 1071750736 11.569 100 34 67 2 1109477328 31273440 6453152
08:20:31 8429 8192000000 9996547568 10410999808 1080934032 11.656 100 26 52 0 1133659216 47567184 5158000
08:21:40 42185 8192000000 9995314672 10390294528 1084422496 11.690 100 42 83 1 1104396848 10868656 9105696
08:23:29 5745 8192000000 10002205344 10407092224 1088866496 11.732 100 43 88 2 1127607344 28607200 10133648
08:24:36 7482 8192000000 10026006064 10424311808 1093034640 11.772 100 39 79 1 1129209952 28228512 7946800
08:25:44 4865 8192000000 10015758112 10416640000 1091999072 11.762 100 41 82 1 1131987920 29665872 10322976
08:26:53 29120 8192000000 10026519520 10413621248 1095640352 11.796 100 73 147 1 1125657072 13850032 16166688
08:28:22 6880 8192000000 9973232016 10368573440 1042473600 11.288 98 26 52 0 1072580048 24845024 5261424
08:29:29 3282 8192000000 9955658256 10326933504 1007931360 10.955 98 51 99 2 1051874048 33409856 10532832
08:30:33 8446 8192000000 9949814448 10343415808 1015077824 11.024 99 49 96 1 1057726992 31705472 10943696
08:31:42 17169 8192000000 9934753184 10329849856 992250464 10.803 97 33 66 2 1020457824 21235952 6971408
08:32:59 6952 8192000000 9959181696 10341154816 1025553440 11.126 98 38 76 0 1058310960 24554128 8203392
08:34:11 10588 8192000000 9960015376 10362441728 1050457728 11.365 100 52 104 4 1081039808 19210656 11371424
08:35:24 69345 8192000000 9974270416 10376658944 1063695232 11.492 100 35 71 1 1091219424 19515328 8008864
08:37:37 22045 8192000000 10020952224 10409115648 1086164592 11.706 100 65 129 1 1126683536 27173632 13345312
08:39:03 44298 8192000000 10085224768 10494992384 1109445664 11.927 100 51 100 1 1203434432 83825888 10162880
08:40:56 4635 8192000000 10107935952 10506596352 1124636944 12.071 100 57 111 2 1215979904 79172976 12169984
08:42:08 47971 8192000000 10150293168 10571599872 1138539440 12.202 100 31 62 0 1249282656 103930432 6812784
08:44:20 14285 8192000000 10203914912 10612047872 1153607328 12.343 100 58 115 1 1309545856 143105968 12832560
08:45:41 7466 8192000000 10261905728 10670649344 1168380288 12.482 100 67 133 1 1366400656 183251600 14768768
08:47:06 7707 8192000000 10303533184 10710347776 1167266304 12.471 100 70 139 0 1409081216 225907440 15907472
08:48:17 3899 8192000000 10359643888 10770345984 1169426320 12.491 99 69 137 0 1451759856 265952976 16380560
08:49:24 8138 8192000000 10374980384 10785923072 1168340192 12.481 99 71 142 0 1465169968 280264208 16565568
08:50:47 2009 8192000000 10379265936 10783383552 1171058576 12.507 99 92 184 2 1465615216 274194560 20362080
08:51:53 2338 8192000000 10398606704 10800762880 1170563344 12.502 99 81 162 0 1487408112 298180688 18664080
08:52:57 2630 8192000000 10354181424 10795200512 1178019504 12.572 100 66 128 0 1454127664 261512336 14595824
08:54:11 9542 8192000000 10375458864 10820186112 1185276880 12.639 100 50 100 2 1490458928 292778944 12403104
08:55:23 10032 8192000000 10339181888 10767876096 1148444720 12.295 98 39 78 0 1418154560 259687904 10021936
08:56:35 7219 8192000000 10289863232 10725019648 1157901008 12.384 100 35 70 1 1395442080 229630400 7910672
08:57:46 15851 8192000000 10309471568 10742878208 1126774160 12.091 98 35 69 0 1403643472 268674720 8194592
08:59:05 19744 8192000000 10283625792 10724225024 1061580736 11.472 95 35 69 2 1380283248 309524256 9178256
09:00:28 30835 8192000000 10239230576 10666946560 1084335456 11.689 97 16 32 1 1310008320 221526400 4146464
09:01:59 38634 8192000000 10164958400 10607902720 1075498576 11.605 97 14 28 0 1234115424 155144896 3471952
09:03:41 82892 8192000000 10062993440 10577424384 1099818768 11.836 99 12 24 1 1163324560 60927760 2578032
09:06:05 64159 8192000000 10093111216 10529697792 1085931696 11.704 98 46 89 3 1158956096 62081920 10942480
09:08:19 126184 8192000000 10110716256 10543747072 1049332256 11.354 95 18 36 0 1078924480 26774976 2817248
09:11:34 81635 8192000000 10052231312 10469748736 1114209408 11.972 100 17 34 0 1163448288 45613280 3625600
09:14:35 26852 8192000000 10102207072 10522685440 1119864464 12.026 99 59 115 1 1187517920 56416048 11237408
09:16:03 52803 8192000000 10123256560 10553233408 1138824064 12.204 100 21 42 0 1214193728 70810976 4558688
09:17:57 52601 8192000000 10181603472 10612035584 1135332784 12.172 100 36 72 0 1267390720 122925232 9132704
09:19:52 30961 8192000000 10087502592 10575364096 1118830624 12.016 99 35 70 0 1177074032 51159104 7084304
09:21:25 20315 8192000000 10064533280 10504671232 1123432960 12.059 99 16 32 0 1163798912 37153584 3212368
09:22:48 35791 8192000000 10100452384 10574073856 1142755936 12.241 100 9 18 0 1220424096 75538896 2129264
09:24:24 48750 8192000000 10097135552 10585223168 1142572944 12.240 100 10 20 1 1212410368 67689360 2148064
09:26:13 85831 8192000000 10132015552 10649333760 1126380016 12.087 98 12 24 0 1252699024 123534864 2784144
09:28:43 52731 8192000000 10123964256 10590715904 1141934176 12.234 100 15 30 1 1202004256 56877776 3192304
09:30:37 28492 8192000000 10070381568 10500894720 1119384464 12.021 98 24 48 0 1150275936 25789760 5101712
09:32:20 98640 8192000000 10116934128 10583531520 1150651696 12.316 100 20 40 0 1227921712 73172800 4097216
09:34:59 75537 8192000000 10090038816 10561302528 1151987376 12.328 100 8 16 0 1191812960 38294624 1530960
09:37:15 111702 8192000000 10057429600 10498838528 1096260320 11.802 95 16 32 0 1130400224 30145744 3994160
09:40:14 62751 8192000000 10082982720 10533470208 1130516752 12.126 99 19 38 0 1174771712 40630944 3624016
09:42:20 64236 8192000000 10055210464 10517331968 1129390256 12.116 99 15 30 0 1160890400 28246544 3253600
09:44:27 63043 8192000000 10099492656 10556014592 1113360368 11.964 98 9 18 1 1181007360 65605424 2041568
09:46:47 37078 8192000000 10071704464 10532659200 1108778816 11.921 98 12 24 0 1159646064 48808144 2059104
09:48:24 76599 8192000000 10083550992 10546180096 1079508672 11.643 96 15 30 0 1171674272 88809168 3356432
09:51:00 39765 8192000000 10052285872 10491908096 1075711744 11.607 97 23 46 0 1119548016 39186848 4649424
09:52:48 33952 8192000000 10066071040 10495717376 1091785040 11.760 99 23 46 1 1157981600 61720240 4476320
09:54:22 54121 8192000000 10058944208 10514022400 1098653776 11.825 99 25 50 2 1155876768 52189072 5033920
09:56:19 47029 8192000000 10128471952 10587930624 1110242672 11.935 99 30 58 2 1189020464 73066576 5711216
09:58:07 77037 8192000000 10050306768 10484326400 1096671712 11.806 98 16 32 1 1161846976 61507040 3668224
10:00:49 23340 8192000000 10060128480 10550317056 1116005552 11.989 100 24 48 0 1164643616 43806704 4831360
10:02:18 57091 8192000000 10046029760 10500775936 1096540352 11.805 98 14 28 0 1137947584 38470784 2936448
10:04:40 77280 8192000000 10071012288 10522075136 1096021408 11.800 98 18 36 0 1168077824 67889200 4167216
10:07:18 61042 8192000000 10068375344 10513874944 1108538592 11.919 98 20 40 1 1149599696 37034256 4026848
10:09:29 34875 8192000000 10069020576 10504306688 1095489536 11.795 97 26 52 0 1144829808 44140672 5199600
10:11:06 55448 8192000000 10083274672 10542505984 1113720304 11.968 98 27 54 2 1204136272 84675488 5740480
10:13:03 20534 8192000000 10095279264 10522349568 1125448704 12.078 99 36 69 1 1171200992 38180448 7571840
10:14:27 46917 8192000000 10078872592 10507866112 1111604704 11.948 98 19 38 0 1150112384 34836032 3671648
10:16:15 29555 8192000000 10157404864 10598027264 1145634640 12.269 100 40 80 0 1219642592 65871008 8136944
10:17:49 90076 8192000000 10075539424 10533249024 1147444912 12.286 100 14 28 0 1188384224 38039696 2899616
10:20:19 98330 8192000000 10098385952 10566651904 1156352224 12.369 100 28 56 0 1197105008 34035952 6716832
10:22:58 14854 8192000000 10115237456 10564259840 1152400512 12.332 99 28 57 0 1210282144 52325040 5556592
10:24:13 51852 8192000000 10089458560 10570207232 1143233360 12.246 99 13 27 1 1191855200 45780112 2841728
10:26:05 51968 8192000000 10092383824 10551308288 1136558864 12.183 99 16 32 0 1198959056 58521648 3878544
10:28:33 53216 8192000000 10086185504 10525028352 1098325072 11.822 96 20 40 0 1141242080 38641216 4275792
10:30:29 42219 8192000000 10098464944 10535600128 1110705376 11.939 97 32 65 0 1157830080 40643760 6480944
10:32:15 74351 8192000000 10080594096 10584489984 1101694144 11.854 97 17 34 1 1203189792 97530448 3965200
10:34:36 70025 8192000000 10120143392 10581061632 1110415376 11.936 97 16 31 1 1163325184 49469104 3440704
10:36:47 31465 8192000000 9111765344 9404026880 235953056 2.799 21 7 12 1 287700048 50374704 1372288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2021 10:38:22 to 23-11-2021 10:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 10:38:38 to 23-11-2021 10:38:40.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233985
MON$OLDEST_ACTIVE               234995
MON$OLDEST_SNAPSHOT             234995
MON$NEXT_TRANSACTION            234995
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-30 07:30:08.6030 GMT
MON$PAGES                       2058784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:247: 
:248: mscs-test-161.budzdorov.ru    Tue Nov 23 10:37:34 2021
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:250: 
:251: 
:252: mscs-test-161.budzdorov.ru    Tue Nov 23 10:37:37 2021
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:254: 
--- end of diff output ---

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

23.11.21 10:38:41. There are 3 logs in /var/tmp/logs-oltp40 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-23 10:38:43 No severe PSQL-related problems occured
23.11.21 10:38:44. End of report.