Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 07:51
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 16130384 kB
MemAvailable 31446664 kB
Buffers 0 kB
Cached 15580844 kB
SwapCached 444 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-12-22 07:49:35
DTS_END                         2021-12-22 07:49:35.6950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40828
TRN_ID                          239884
ATT_ID                          668
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: 9488.57 21516 1138628
customer order: creation 177.89 352 21347
customer order: refuse 14.66 343 1759
order to supplier: creation 48.67 1395 5840
order to supplier: removal 3.47 121617 416
invoice (draft): creation 47.49 3704 5699
invoice (draft): removal 52.53 947 6304
invoice accept: apply 43.37 17982 5204
invoice accept: cancel 48.00 965 5760
customer reserve: creation 4061.53 173 487383
customer reserve: removal 4687.50 71 562500
realization accept: apply 84.77 209 10172
realization accept: cancel 87.29 118 10475
payment from customer: creation 61.10 128 7332
payment from customer: removal 5.97 119 716
payment to supplier: creation 50.27 137 6032
payment to supplier: removal 3.35 125 402
service: total inventory turnovers 3.61 4134 433
service: total monetary turnovers 6.18 615 741
service: aggregate perf. data 0.90 19726 108
service: refresh index statistics 0.04 257458 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 07:51:44 to 22-12-2021 07:51:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4015
TEST_TIME 2 2626
TEST_TIME 3 3941
TEST_TIME 4 5131
TEST_TIME 5 5561
TEST_TIME 6 5552
TEST_TIME 7 5461
TEST_TIME 8 5465
TEST_TIME 9 5753
TEST_TIME 10 6000
TEST_TIME 11 6310
TEST_TIME 12 6395
TEST_TIME 13 6477
TEST_TIME 14 6944
TEST_TIME 15 7034
TEST_TIME 16 7756
TEST_TIME 17 7907
TEST_TIME 18 7874
TEST_TIME 19 7778
TEST_TIME 20 8015
TEST_TIME 21 8533
TEST_TIME 22 8620
TEST_TIME 23 8679
TEST_TIME 24 8710
TEST_TIME 25 8530
TEST_TIME 26 8945
TEST_TIME 27 9213
TEST_TIME 28 9269
TEST_TIME 29 9587
TEST_TIME 30 9723
TEST_TIME 31 10048
TEST_TIME 32 10874
TEST_TIME 33 11394
TEST_TIME 34 11465
TEST_TIME 35 11378
TEST_TIME 36 11217
TEST_TIME 37 11170
TEST_TIME 38 11195
TEST_TIME 39 11078
TEST_TIME 40 10989
TEST_TIME 41 10935
TEST_TIME 42 10839
TEST_TIME 43 10930
TEST_TIME 44 10859
TEST_TIME 45 10821
TEST_TIME 46 10603
TEST_TIME 47 10518
TEST_TIME 48 10503
TEST_TIME 49 10365
TEST_TIME 50 10338
TEST_TIME 51 10322
TEST_TIME 52 10291
TEST_TIME 53 10284
TEST_TIME 54 10220
TEST_TIME 55 10158
TEST_TIME 56 10137
TEST_TIME 57 10037
TEST_TIME 58 9988
TEST_TIME 59 10093
TEST_TIME 60 10067
TEST_TIME 61 10095
TEST_TIME 62 10193
TEST_TIME 63 10168
TEST_TIME 64 10179
TEST_TIME 65 10234
TEST_TIME 66 10269
TEST_TIME 67 10223
TEST_TIME 68 10270
TEST_TIME 69 10273
TEST_TIME 70 10275
TEST_TIME 71 10172
TEST_TIME 72 10118
TEST_TIME 73 10084
TEST_TIME 74 10031
TEST_TIME 75 9988
TEST_TIME 76 9943
TEST_TIME 77 9888
TEST_TIME 78 9877
TEST_TIME 79 9853
TEST_TIME 80 9804
TEST_TIME 81 9758
TEST_TIME 82 9770
TEST_TIME 83 9823
TEST_TIME 84 9783
TEST_TIME 85 9835
TEST_TIME 86 9818
TEST_TIME 87 9795
TEST_TIME 88 9818
TEST_TIME 89 9861
TEST_TIME 90 9943
TEST_TIME 91 9975
TEST_TIME 92 9924
TEST_TIME 93 9843
TEST_TIME 94 9847
TEST_TIME 95 9831
TEST_TIME 96 9770
TEST_TIME 97 9725
TEST_TIME 98 9686
TEST_TIME 99 9688
TEST_TIME 100 9686
TEST_TIME 101 9709
TEST_TIME 102 9655
TEST_TIME 103 9703
TEST_TIME 104 9669
TEST_TIME 105 9677
TEST_TIME 106 9672
TEST_TIME 107 9629
TEST_TIME 108 9653
TEST_TIME 109 9769
TEST_TIME 110 9769
TEST_TIME 111 9758
TEST_TIME 112 9708
TEST_TIME 113 9646
TEST_TIME 114 9611
TEST_TIME 115 9607
TEST_TIME 116 9578
TEST_TIME 117 9541
TEST_TIME 118 9506
TEST_TIME 119 9463
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2021 07:51:48 to 22-12-2021 07:51:51.

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 1586843 1586843 0 0.00 0 16873 7 0 0
doc_list_biud 1586843 1586843 0 0.00 0 53643 32 0 0
sp_add_invoice_to_stock 5327 5204 123 2.30 23 218300 17983 0 123
sp_cancel_adding_invoice 5760 5760 0 0.00 5 78509 965 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 11477 344 0 0
sp_cancel_customer_reserve 562500 562500 0 0.00 1 24415 71 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 10278 120 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 7300 126 0 0
sp_cancel_supplier_invoice 6314 6304 10 0.15 2 53661 948 0 10
sp_cancel_supplier_order 416 416 0 0.00 8 1095389 121618 0 0
sp_cancel_write_off 10475 10475 0 0.00 1 24523 118 0 0
sp_client_order 21347 21347 0 0.00 4 10660 353 0 0
sp_customer_reserve 487383 487383 0 0.00 0 39484 173 0 0
sp_fill_shopping_cart 32871 32871 0 0.00 1 13560 47 0 0
sp_fill_shopping_cart_clo_res 487375 487375 0 0.00 0 15868 16 0 0
sp_get_clo_for_invoice 5196 5196 0 0.00 0 37832 366 0 0
sp_kill_cost_storno 17353 17353 0 0.00 0 7213 64 0 0
sp_kill_qstorno_ret_qs2qd 1142195 1142195 0 0.00 0 53640 38 0 0
sp_kill_qty_storno 602582 602582 0 0.00 0 53641 82 0 0
sp_lock_selected_doc 21317 21317 0 0.00 0 10265 7 0 0
sp_make_cost_storno 28732 28732 0 0.00 0 16648 67 0 0
sp_multiply_rows_for_pdistr 28732 28732 0 0.00 0 7136 34 0 0
sp_multiply_rows_for_qdistr 21347 21347 0 0.00 0 10649 190 0 0
sp_pay_from_customer 7332 7332 0 0.00 4 15860 129 0 0
sp_pay_to_supplier 6032 6032 0 0.00 5 14686 137 0 0
sp_payment_common 13364 13364 0 0.00 1 13734 35 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 11468 308 0 0
sp_qd_handle_on_invoice_upd_sts 10956 10956 0 0.00 0 30200 385 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 25853 62 0 0
sp_reserve_write_off 10172 10172 0 0.00 7 25896 209 0 0
sp_supplier_invoice 5699 5699 0 0.00 7 92360 3704 0 0
sp_supplier_order 5840 5840 0 0.00 5 30080 1395 0 0
srv_aggregate_perf_data 108 108 0 0.00 2779 489483 19726 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 948 112726 41120 0 0
srv_find_qd_qs_mism 1087480 1087480 0 0.00 0 8978 6 0 0
srv_make_invnt_saldo 433 433 0 0.00 48 87024 4135 0 0
srv_make_money_saldo 741 741 0 0.00 1 13244 615 0 0
srv_recalc_idx_stat 5 5 0 0.00 97927 451716 257458 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5204 5204 0 0.00 0 15754 173 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 5233 133 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 9497 22 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2861 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 4580 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2731 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 946 21 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 21473 1666 0 0
t$perf-norm:sp_cancel_write_off 1093 1093 0 0.00 0 851 6 0 0
t$perf-norm:sp_client_order 21347 21347 0 0.00 0 28842 18 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 411 3 0 0
t$perf-norm:sp_pay_from_customer 7332 7332 0 0.00 0 11873 14 0 0
t$perf-norm:sp_pay_to_supplier 6032 6032 0 0.00 0 4401 11 0 0
t$perf-norm:sp_reserve_write_off 10172 10172 0 0.00 0 9166 12 0 0
t$perf-norm:sp_supplier_invoice 5699 5699 0 0.00 0 7477 22 0 0
t$perf-norm:sp_supplier_order 5840 5840 0 0.00 0 9634 24 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 146 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1403 32 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 391 8 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 2998 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10636 10390 246 2.31 0 5964 17 0 246
v_all_customers 39328 39328 0 0.00 0 74 1 0 0
v_all_suppliers 31806 31806 0 0.00 0 26 1 0 0
v_all_wares 42692 42692 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2202 9 0 0
v_cancel_client_order 3518 3518 0 0.00 0 2757 4 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3322 9 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1027 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4426 15 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 4139 25 0 20
v_cancel_supplier_order 832 832 0 0.00 0 2187 6 0 0
v_cancel_write_off 2186 2186 0 0.00 0 3094 18 0 0
v_max_id_clo_ord 5839 5839 0 0.00 0 3035 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 4803 17 0 0
v_max_id_ord_sup 5686 5686 0 0.00 0 6056 6 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 2876 4 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 2374 5 0 0
v_min_id_clo_ord 5839 5839 0 0.00 0 2004 5 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 8321 27 0 0
v_min_id_ord_sup 5686 5686 0 0.00 0 7948 12 0 0
v_min_non_paid_invoice 6032 6032 0 0.00 0 7407 24 0 0
v_min_non_paid_realizn 7332 7332 0 0.00 0 13715 27 0 0
v_our_firm 10610 10610 0 0.00 0 17 1 0 0
v_reserve_write_off 20342 20342 0 0.00 0 3761 6 0 0
x_lock_depdocs_on_canc_invoice 11636 11636 0 0.00 0 26807 129 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 3563 306 0 0
x_make_qstorno_1000_1200 5840 5840 0 0.00 2 30068 1305 0 0
x_make_qstorno_1200_2000 5699 5699 0 0.00 3 87759 3524 0 0
x_make_qstorno_2100_3300 487383 487383 0 0.00 0 37083 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 07:51:52 to 22-12-2021 07:51:56.

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
05:19:39 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:40 22519 8192000000 9723434384 10018926592 803482544 8.932 100 89 178 0 833165632 7960720 21722368
05:22:07 23063 8192000000 9772637312 10081935360 843785792 9.338 100 91 182 0 877507856 11934912 21787152
05:23:45 21045 8192000000 9811281504 10132840448 878658528 9.686 100 84 169 0 917692560 19798784 19235248
05:25:10 14296 8192000000 9875638064 10208133120 920884432 10.105 100 85 168 0 982348992 42898448 18566112
05:26:36 8503 8192000000 9938094832 10281529344 957492944 10.464 100 80 159 1 1050416592 76370720 16552928
05:27:46 6476 8192000000 9971187264 10331828224 1003791248 10.915 100 83 164 0 1092934960 71965760 17177952
05:28:54 1830 8192000000 10028907280 10389852160 1028398304 11.153 100 89 177 0 1140045648 93591856 18055488
05:29:59 1983 8192000000 10026642064 10393337856 1053421648 11.393 100 89 178 0 1132381360 59629280 19330432
05:31:02 1267 8192000000 10048006592 10418032640 1065332928 11.507 100 98 198 1 1160728464 74757152 20638384
05:32:03 1316 8192000000 10024907568 10399678464 1071478912 11.566 100 82 162 3 1130656720 40578128 18599680
05:33:05 7959 8192000000 9999433744 10395586560 1077915392 11.628 100 39 78 1 1115754128 29191616 8647120
05:34:17 10173 8192000000 9991929120 10392969216 1086281264 11.707 100 33 67 3 1113858416 20040176 7536976
05:35:29 74266 8192000000 9991016656 10407415808 1091960656 11.761 100 26 51 3 1123878080 26343104 5574320
05:37:44 7495 8192000000 10010182928 10407813120 1094222688 11.783 100 33 66 1 1118692976 16755232 7715056
05:38:51 5437 8192000000 9997449792 10406129664 1082264160 11.669 99 40 79 0 1109084720 18140048 8680512
05:40:00 7510 8192000000 9983585424 10375319552 1069213792 11.545 99 45 89 3 1094406512 15097856 10094864
05:41:07 33737 8192000000 9968021968 10376290304 1014416304 11.018 94 21 42 0 1037834528 18795392 4622832
05:42:42 8553 8192000000 9948318576 10343563264 1002357200 10.901 96 32 64 1 1039244048 29422352 7464496
05:43:53 3941 8192000000 9948666064 10327445504 1028520464 11.154 100 35 69 1 1054199136 16838512 8840160
05:44:59 70566 8192000000 9915542976 10300674048 997327456 10.853 98 30 59 0 1027249456 23258800 6663200
05:47:11 10344 8192000000 9957970224 10358652928 1046394336 11.326 100 36 72 0 1074746816 20528160 7824320
05:48:22 5326 8192000000 9990280352 10380165120 1063077440 11.486 100 54 105 1 1095484368 20577280 11829648
05:49:28 17729 8192000000 10002125920 10402152448 1079359536 11.641 100 31 58 4 1108354944 22208480 6786928
05:51:06 79242 8192000000 10045113072 10448932864 1097638224 11.815 100 44 88 0 1155021296 47459472 9923600
05:53:33 3518 8192000000 10079156208 10478092288 1115873920 11.988 100 62 124 0 1194327440 65984192 12469328
05:54:42 9410 8192000000 10106494544 10512404480 1124995920 12.074 100 47 93 0 1216622064 81687360 9938784
05:55:57 18388 8192000000 10148276240 10556301312 1143438992 12.248 100 55 109 0 1237549248 82392720 11717536
05:57:24 18186 8192000000 10194608640 10607869952 1152852208 12.336 100 40 80 3 1291150224 128979328 9318688
05:58:58 2405 8192000000 10232615424 10644819968 1167205408 12.471 100 71 141 2 1331202032 148924288 15072336
06:00:03 2577 8192000000 10239973792 10639646720 1145095408 12.263 99 78 154 2 1342059312 180350240 16613664
06:01:10 2969 8192000000 10281321824 10684899328 1146985664 12.281 99 86 172 1 1380827424 215067472 18774288
06:02:18 9480 8192000000 10276172240 10680688640 1155772976 12.364 100 62 124 1 1375431936 205908336 13750624
06:03:30 1399 8192000000 10302143888 10721181696 1167872336 12.477 100 80 158 1 1400686512 213942944 18871232
06:04:35 3982 8192000000 10329390048 10723258368 1135364976 12.172 99 86 173 0 1420547472 264666112 20516384
06:05:44 947 8192000000 10315358416 10715279360 1132013792 12.140 97 90 181 1 1398964656 245759616 21191248
06:06:52 3462 8192000000 10323675504 10738712576 1171220656 12.508 100 69 136 3 1423213632 235969664 16023312
06:08:03 3877 8192000000 10404164448 10805854208 1174873472 12.542 99 83 166 2 1491966544 297778448 19314624
06:09:10 3694 8192000000 10445052272 10852892672 1163223392 12.433 100 70 139 0 1546682272 366713104 16745776
06:10:23 7036 8192000000 10396520464 10838044672 1161912352 12.421 100 46 92 0 1527552272 353719472 11920448
06:11:36 7761 8192000000 10397784912 10836467712 1154286784 12.350 99 52 103 0 1490289504 323258576 12744144
06:12:48 3962 8192000000 10422896528 10834022400 1167798160 12.476 100 58 116 0 1518333776 336825520 13710096
06:14:00 10669 8192000000 10496269648 10917556224 1161066608 12.413 100 49 98 0 1570115232 396534128 12514496
06:15:22 3396 8192000000 10527743392 10941022208 1157932944 12.384 100 67 132 1 1617692608 443364176 16395488
06:16:29 18484 8192000000 10507559072 10970914816 1172807376 12.523 100 32 64 1 1593601520 412667344 8126800
06:17:54 21717 8192000000 10530763552 10998349824 1160506640 12.408 99 27 54 0 1636933952 469115568 7311744
06:19:44 24681 8192000000 10479100848 10967564288 1134380080 12.163 99 21 42 0 1572158160 431715008 6063072
06:21:48 12613 8192000000 10268308272 10848190464 1134419024 12.163 100 17 34 0 1383841376 244211504 5210848
06:23:49 61435 8192000000 10080364800 10548166656 1137297168 12.190 100 14 28 0 1179634192 39431904 2905120
06:25:52 34577 8192000000 10095887280 10544594944 1109154208 11.924 97 18 36 1 1153831040 40948160 3728672
06:27:37 86026 8192000000 10059353744 10485477376 1100656160 11.844 97 20 40 0 1141098464 36742752 3699552
06:30:09 57413 8192000000 10060303472 10502959104 1128140384 12.104 100 24 49 0 1155858512 22441008 5277120
06:32:09 80723 8192000000 10097385936 10536546304 1126542432 12.089 99 10 20 0 1176715040 47574720 2597888
06:34:31 87073 8192000000 10073900400 10510303232 1138045056 12.197 100 19 38 0 1173704784 31175184 4484544
06:37:02 78293 8192000000 10077769376 10540638208 1132325152 12.143 99 22 44 2 1176368256 39559216 4483888
06:39:25 63080 8192000000 10077486416 10547441664 1129765760 12.119 99 15 28 3 1187289024 54226912 3296352
06:41:29 53201 8192000000 10127626192 10597064704 1135612016 12.174 99 28 54 1 1209253648 67936880 5704752
06:43:32 56680 8192000000 10130252176 10606964736 1148006096 12.291 100 17 34 0 1225958304 74079968 3872240
06:45:35 31335 8192000000 10132086976 10609414144 1120909824 12.036 98 18 36 0 1218963856 94005456 4048576
06:47:06 47272 8192000000 10143115824 10624892928 1138871168 12.205 100 11 22 0 1273516496 131826864 2818464
06:49:58 65795 8192000000 10099604304 10553794560 1108289968 11.916 97 23 46 0 1191858400 78722960 4845472
06:52:05 53352 8192000000 10110871824 10575278080 1135458976 12.173 99 16 32 1 1200794128 61712064 3623088
06:54:00 71813 8192000000 10072978912 10562818048 1123247376 12.058 98 14 29 1 1205178800 78955728 2975696
06:56:17 62152 8192000000 10063214864 10524631040 1081680384 11.663 95 28 56 0 1137862144 50582848 5598912
06:58:25 51696 8192000000 10074696816 10525122560 1096722992 11.807 97 15 30 0 1133724112 33918400 3082720
07:00:23 81022 8192000000 10083151536 10526969856 1127579648 12.099 100 42 84 1 1191669856 55267632 8822576
07:02:45 56938 8192000000 10054450544 10489958400 1092054560 11.762 97 22 44 0 1131393104 34870064 4468480
07:04:42 67728 8192000000 10083045264 10527772672 1088358880 11.727 97 19 38 0 1125787760 33991312 3437568
07:06:51 112726 8192000000 10045519152 10542030848 1089307184 11.736 97 12 24 0 1166392080 74530144 2554752
07:09:59 39224 8192000000 10075901744 10517991424 1110425632 11.936 99 15 30 0 1161490480 47489264 3575584
07:11:39 36539 8192000000 10080520656 10531713024 1112566032 11.957 99 33 67 0 1185317408 65945552 6805824
07:13:19 32297 8192000000 10094249072 10517200896 1103113824 11.867 99 32 64 0 1155786048 45965936 6706288
07:15:08 30384 8192000000 10058503200 10489761792 1086906272 11.713 97 26 52 0 1139552096 47519920 5125904
07:16:42 55959 8192000000 10082725168 10518900736 1116591680 11.995 100 32 64 1 1205284816 82087328 6605808
07:18:39 75094 8192000000 10069468432 10551857152 1105827296 11.893 98 20 40 0 1147576544 38257664 3491584
07:20:58 58480 8192000000 10092597600 10529718272 1120704608 12.034 99 27 53 1 1173680448 47208336 5767504
07:22:59 104149 8192000000 10076199648 10537807872 1110843504 11.940 97 23 44 2 1139720720 24159648 4717568
07:25:43 36586 8192000000 10070567280 10536370176 1139669776 12.212 100 6 11 2 1169917744 29673008 574960
07:27:20 74502 8192000000 10075733632 10547351552 1136091152 12.179 99 16 32 1 1188438576 49395296 2952128
07:29:36 50081 8192000000 10091553216 10545156096 1137314736 12.190 99 24 49 1 1181638256 38857472 5466048
07:31:29 46708 8192000000 10135377456 10584633344 1149544624 12.305 99 27 54 1 1201596800 46065856 5986320
07:33:22 33331 8192000000 10096773152 10535833600 1136735360 12.185 98 20 39 2 1168745152 27179424 4830368
07:35:00 31990 8192000000 10099870080 10552573952 1126748576 12.091 98 27 54 1 1180768112 48708496 5311040
07:36:36 33463 8192000000 10166537520 10606952448 1153466000 12.342 100 42 84 0 1241861152 79736032 8659120
07:38:12 61008 8192000000 10095555088 10607775744 1119881200 12.026 98 10 20 0 1205792816 83908784 2002832
07:40:57 95611 8192000000 10078834368 10538659840 1139552288 12.211 100 15 30 0 1166901344 24327936 3021120
07:43:55 23198 8192000000 10077720480 10548826112 1143106976 12.245 100 18 36 1 1187300992 40255744 3938272
07:45:19 82613 8192000000 10099921920 10576568320 1137265056 12.190 99 12 24 0 1188922480 49040928 2616496
07:48:13 60693 8192000000 10136547760 10586812416 1108055824 11.914 97 16 32 2 1188295360 76423312 3816224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2021 07:51:58 to 22-12-2021 07:52:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 07:52:14 to 22-12-2021 07:52:16.

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          238555
MON$OLDEST_ACTIVE               240349
MON$OLDEST_SNAPSHOT             240349
MON$NEXT_TRANSACTION            240349
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                       2093376
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2694 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2694 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2694 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2694 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Dec 22 07:49:42 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Dec 22 07:49:49 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Dec 22 07:50:01 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

22.12.21 07:52:17. 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-12-22 07:52:20 No severe PSQL-related problems occured
22.12.21 07:52:21. End of report.