Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.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 15857060 kB
MemAvailable 30723344 kB
Buffers 0 kB
Cached 15859116 kB
SwapCached 3804 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-10-08 01:38:02
DTS_END                         2021-10-08 01:38:02.0940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(20:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49042
TRN_ID                          243597
ATT_ID                          733
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: 9503.95 15688 1140474
customer order: creation 181.69 331 21803
customer order: refuse 15.07 352 1808
order to supplier: creation 47.90 1276 5748
order to supplier: removal 3.72 96338 446
invoice (draft): creation 48.53 3441 5823
invoice (draft): removal 53.16 867 6379
invoice accept: apply 43.41 18029 5209
invoice accept: cancel 47.79 853 5735
customer reserve: creation 4034.41 179 484129
customer reserve: removal 4721.08 59 566529
realization accept: apply 86.70 174 10404
realization accept: cancel 84.78 102 10173
payment from customer: creation 61.35 96 7362
payment from customer: removal 6.06 79 727
payment to supplier: creation 51.90 126 6228
payment to supplier: removal 3.57 63 428
service: total inventory turnovers 4.78 1285 573
service: total monetary turnovers 7.07 35 848
service: aggregate perf. data 0.98 16888 118
service: refresh index statistics 0.03 173183 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2021 01:39:48 to 08-10-2021 01:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9573
TEST_TIME 2 7192
TEST_TIME 3 6155
TEST_TIME 4 6097
TEST_TIME 5 5841
TEST_TIME 6 6634
TEST_TIME 7 6516
TEST_TIME 8 6810
TEST_TIME 9 6789
TEST_TIME 10 7356
TEST_TIME 11 7875
TEST_TIME 12 8173
TEST_TIME 13 8239
TEST_TIME 14 8281
TEST_TIME 15 8169
TEST_TIME 16 8114
TEST_TIME 17 8383
TEST_TIME 18 8517
TEST_TIME 19 8713
TEST_TIME 20 8721
TEST_TIME 21 9691
TEST_TIME 22 9464
TEST_TIME 23 9817
TEST_TIME 24 10242
TEST_TIME 25 10694
TEST_TIME 26 11477
TEST_TIME 27 11421
TEST_TIME 28 11307
TEST_TIME 29 11469
TEST_TIME 30 11199
TEST_TIME 31 11231
TEST_TIME 32 11217
TEST_TIME 33 11089
TEST_TIME 34 11033
TEST_TIME 35 11026
TEST_TIME 36 10858
TEST_TIME 37 10615
TEST_TIME 38 10601
TEST_TIME 39 10628
TEST_TIME 40 10456
TEST_TIME 41 10380
TEST_TIME 42 10362
TEST_TIME 43 10293
TEST_TIME 44 10323
TEST_TIME 45 10502
TEST_TIME 46 10417
TEST_TIME 47 10342
TEST_TIME 48 10254
TEST_TIME 49 10177
TEST_TIME 50 10068
TEST_TIME 51 10035
TEST_TIME 52 9929
TEST_TIME 53 9950
TEST_TIME 54 9921
TEST_TIME 55 9920
TEST_TIME 56 9927
TEST_TIME 57 9916
TEST_TIME 58 9897
TEST_TIME 59 9933
TEST_TIME 60 9983
TEST_TIME 61 9980
TEST_TIME 62 10024
TEST_TIME 63 10042
TEST_TIME 64 10115
TEST_TIME 65 10117
TEST_TIME 66 10172
TEST_TIME 67 10129
TEST_TIME 68 10147
TEST_TIME 69 10196
TEST_TIME 70 10083
TEST_TIME 71 10085
TEST_TIME 72 9988
TEST_TIME 73 9962
TEST_TIME 74 9892
TEST_TIME 75 9929
TEST_TIME 76 9918
TEST_TIME 77 9853
TEST_TIME 78 9789
TEST_TIME 79 9746
TEST_TIME 80 9725
TEST_TIME 81 9670
TEST_TIME 82 9685
TEST_TIME 83 9682
TEST_TIME 84 9669
TEST_TIME 85 9735
TEST_TIME 86 9703
TEST_TIME 87 9707
TEST_TIME 88 9719
TEST_TIME 89 9732
TEST_TIME 90 9788
TEST_TIME 91 9824
TEST_TIME 92 9785
TEST_TIME 93 9936
TEST_TIME 94 9874
TEST_TIME 95 9818
TEST_TIME 96 9735
TEST_TIME 97 9712
TEST_TIME 98 9703
TEST_TIME 99 9662
TEST_TIME 100 9624
TEST_TIME 101 9624
TEST_TIME 102 9572
TEST_TIME 103 9518
TEST_TIME 104 9628
TEST_TIME 105 9610
TEST_TIME 106 9563
TEST_TIME 107 9568
TEST_TIME 108 9623
TEST_TIME 109 9639
TEST_TIME 110 9617
TEST_TIME 111 9629
TEST_TIME 112 9625
TEST_TIME 113 9634
TEST_TIME 114 9672
TEST_TIME 115 9678
TEST_TIME 116 9757
TEST_TIME 117 9698
TEST_TIME 118 9630
TEST_TIME 119 9586
TEST_TIME 120 9509

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-10-2021 01:39:51 to 08-10-2021 01:39:55.

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 1588361 1588361 0 0.00 0 24775 7 0 0
doc_list_biud 1588360 1588360 0 0.00 0 62745 27 0 0
sp_add_invoice_to_stock 5294 5209 85 1.60 42 201983 18029 0 85
sp_cancel_adding_invoice 5735 5735 0 0.00 5 75229 853 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 14772 353 0 0
sp_cancel_customer_reserve 566529 566529 0 0.00 1 62747 59 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 8962 80 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 2533 64 0 0
sp_cancel_supplier_invoice 6389 6379 10 0.15 2 23955 867 0 10
sp_cancel_supplier_order 446 446 0 0.00 5 1008855 96339 0 0
sp_cancel_write_off 10173 10173 0 0.00 1 13158 103 0 0
sp_client_order 21803 21803 0 0.00 3 24910 332 0 0
sp_customer_reserve 484129 484129 0 0.00 0 44769 179 0 0
sp_fill_shopping_cart 33370 33370 0 0.00 1 15224 35 0 0
sp_fill_shopping_cart_clo_res 484126 484126 0 0.00 0 14479 12 0 0
sp_get_clo_for_invoice 5202 5202 0 0.00 0 32711 270 0 0
sp_kill_cost_storno 17063 17063 0 0.00 0 10933 53 0 0
sp_kill_qstorno_ret_qs2qd 1150056 1150056 0 0.00 0 60154 32 0 0
sp_kill_qty_storno 606675 606675 0 0.00 0 62745 70 0 0
sp_lock_selected_doc 21642 21642 0 0.00 0 8956 6 0 0
sp_make_cost_storno 29195 29195 0 0.00 0 12915 50 0 0
sp_multiply_rows_for_pdistr 29195 29195 0 0.00 0 12955 32 0 0
sp_multiply_rows_for_qdistr 21803 21803 0 0.00 0 14047 138 0 0
sp_pay_from_customer 7362 7362 0 0.00 4 11257 97 0 0
sp_pay_to_supplier 6228 6228 0 0.00 5 24781 127 0 0
sp_payment_common 13589 13589 0 0.00 1 8082 24 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 14763 326 0 0
sp_qd_handle_on_invoice_upd_sts 10936 10936 0 0.00 0 24004 341 0 0
sp_qd_handle_on_reserve_upd_sts 10404 10404 0 0.00 0 13027 57 0 0
sp_reserve_write_off 10404 10404 0 0.00 6 15369 175 0 0
sp_supplier_invoice 5823 5823 0 0.00 7 99729 3441 0 0
sp_supplier_order 5748 5748 0 0.00 5 31954 1276 0 0
srv_aggregate_perf_data 119 118 1 0.84 2787 162549 16888 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 1887 146004 40760 0 0
srv_find_qd_qs_mism 1090149 1090149 0 0.00 0 8217 6 0 0
srv_make_invnt_saldo 573 573 0 0.00 47 58154 1286 0 0
srv_make_money_saldo 848 848 0 0.00 1 5982 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 83397 310758 173183 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5209 5209 0 0.00 0 6905 145 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 3973 86 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 6392 17 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 975 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 3177 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 540 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 2159 15 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 30589 1505 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 6826 18 0 0
t$perf-norm:sp_client_order 21803 21803 0 0.00 0 8022 15 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 849 3 0 0
t$perf-norm:sp_pay_from_customer 7362 7362 0 0.00 0 6065 8 0 0
t$perf-norm:sp_pay_to_supplier 6228 6228 0 0.00 0 12666 12 0 0
t$perf-norm:sp_reserve_write_off 10404 10404 0 0.00 0 4627 10 0 0
t$perf-norm:sp_supplier_invoice 5823 5823 0 0.00 0 8490 21 0 0
t$perf-norm:sp_supplier_order 5748 5748 0 0.00 0 4294 15 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 948 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 143 4 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 7614 32 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 3894 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10572 10402 170 1.60 0 6078 18 0 170
v_all_customers 39112 39112 0 0.00 0 22 1 0 0
v_all_suppliers 32178 32178 0 0.00 0 18 1 0 0
v_all_wares 43606 43606 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2738 10 0 0
v_cancel_client_order 3616 3616 0 0.00 0 2663 5 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 831 4 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 1623 6 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2278 7 0 0
v_cancel_supplier_invoice 810 790 20 2.46 0 5985 24 0 20
v_cancel_supplier_order 892 892 0 0.00 0 2686 7 0 0
v_cancel_write_off 2176 2176 0 0.00 0 2738 17 0 0
v_max_id_clo_ord 5748 5748 0 0.00 0 1173 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2705 13 0 0
v_max_id_ord_sup 5818 5818 0 0.00 0 4153 5 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 1498 4 0 0
v_max_non_paid_realizn 4120 4120 0 0.00 0 2100 4 0 0
v_min_id_clo_ord 5748 5748 0 0.00 0 1938 6 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2103 6 0 0
v_min_id_ord_sup 5818 5818 0 0.00 0 8114 7 0 0
v_min_non_paid_invoice 6228 6228 0 0.00 0 7213 20 0 0
v_min_non_paid_realizn 7361 7361 0 0.00 0 6213 15 0 0
v_our_firm 10976 10976 0 0.00 0 118 1 0 0
v_reserve_write_off 20808 20808 0 0.00 0 6446 6 0 0
x_lock_depdocs_on_canc_invoice 11719 11719 0 0.00 0 17881 111 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 7751 289 0 0
x_make_qstorno_1000_1200 5748 5748 0 0.00 2 31923 1194 0 0
x_make_qstorno_1200_2000 5823 5823 0 0.00 4 99711 3322 0 0
x_make_qstorno_2100_3300 484129 484129 0 0.00 0 44767 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2021 01:39:56 to 08-10-2021 01:40:00.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:05 24 8192000000 8811795056 8827080704 15882768 0.193 4 4 6 0 16121504 89456 149280
23:09:08 30632 8192000000 9897350960 10214445056 982317440 10.707 100 86 172 0 1011109968 5955136 22837392
23:10:47 18493 8192000000 9948142000 10270126080 1031023296 11.178 100 92 184 0 1063155600 8506048 23626256
23:12:23 11510 8192000000 9982267232 10318888960 1068518320 11.538 100 84 168 1 1099668736 10034416 21116000
23:13:42 15764 8192000000 10042250464 10393149440 1126410128 12.088 100 82 162 1 1157136784 11243552 19483104
23:14:59 6915 8192000000 10092212720 10459582464 1185571920 12.642 100 73 146 1 1213734384 11445200 16717264
23:16:09 4866 8192000000 10143702960 10514104320 1228293568 13.038 100 79 159 2 1259624272 14126032 17204672
23:17:15 8714 8192000000 10158272656 10537119744 1248319952 13.223 100 82 164 0 1280698608 14720480 17658176
23:18:26 2016 8192000000 10203199744 10584252416 1288234736 13.588 100 93 186 1 1322757120 13381648 21140736
23:19:30 23415 8192000000 10196119456 10592243712 1296953376 13.668 100 58 116 0 1319137856 9537728 12646752
23:20:55 3625 8192000000 10200738704 10608447488 1308110112 13.769 100 26 51 0 1320987888 7211184 5666592
23:22:04 6398 8192000000 10209705872 10626637824 1320223280 13.879 100 22 44 0 1335524736 10724960 4576496
23:23:12 12394 8192000000 10211437584 10633596928 1328220896 13.951 100 40 80 3 1343950720 6768368 8961456
23:24:29 10193 8192000000 10246324480 10648883200 1330118224 13.968 100 70 141 0 1352841104 5243872 17479008
23:25:40 4874 8192000000 10230372128 10650828800 1339611760 14.054 100 48 95 0 1356702928 6243200 10847968
23:26:45 9967 8192000000 10215620912 10634104832 1294581776 13.646 97 23 46 1 1305373712 5582848 5209088
23:27:57 20930 8192000000 10215552080 10638225408 1330751776 13.974 100 30 60 0 1342777568 4775072 7250720
23:29:20 2823 8192000000 10198172416 10609045504 1290918736 13.613 99 31 62 0 1308851056 11246256 6686064
23:30:23 5527 8192000000 10170907808 10574548992 1256185728 13.295 98 24 48 2 1272335504 10139104 6010672
23:31:30 5318 8192000000 10142062352 10541203456 1226797328 13.024 98 33 67 0 1244762368 10024272 7940768
23:32:38 25618 8192000000 10153772992 10560122880 1244113600 13.184 98 18 36 0 1253699408 5075632 4510176
23:34:05 7288 8192000000 10171503200 10573045760 1272670128 13.446 100 23 46 0 1283387232 4748800 5968304
23:35:16 10989 8192000000 10184437024 10595225600 1289139088 13.596 100 19 38 1 1299366960 5802624 4425248
23:36:28 52752 8192000000 10203181312 10611802112 1303370416 13.726 100 43 84 0 1318288496 5085776 9832304
23:38:29 24656 8192000000 10238808912 10640568320 1325840928 13.930 100 49 97 1 1343934976 7335248 10758800
23:39:56 17860 8192000000 10262716448 10675703808 1358163456 14.221 100 58 114 1 1381879472 10490272 13225744
23:41:28 10904 8192000000 10289667392 10705027072 1374096896 14.364 100 56 112 0 1402704656 15016144 13591616
23:43:06 15755 8192000000 10295259840 10717257728 1381276624 14.428 100 57 111 1 1408393264 14203760 12912880
23:44:27 12367 8192000000 10302663808 10723897344 1382359920 14.438 100 57 111 1 1413479504 17501744 13617840
23:45:45 8479 8192000000 10311186768 10732224512 1392251168 14.526 100 47 94 1 1419418000 16203200 10963632
23:46:56 3059 8192000000 10329775648 10752032768 1403177056 14.623 100 60 120 0 1441826960 23963568 14686336
23:48:05 11101 8192000000 10314854704 10731872256 1358729376 14.226 98 45 90 1 1399346432 28457936 12159120
23:49:20 14633 8192000000 10341106080 10765103104 1393695328 14.539 99 54 108 0 1437108528 30414848 12998352
23:50:39 2486 8192000000 10362464032 10774757376 1409969056 14.684 100 82 163 3 1460132592 29701392 20462144
23:51:45 4348 8192000000 10351093920 10769371136 1402057488 14.613 99 82 165 1 1456769920 32823664 21888768
23:52:53 3167 8192000000 10358922560 10778931200 1413471632 14.715 100 69 138 0 1465020816 34168608 17380576
23:54:05 1886 8192000000 10382905728 10796736512 1425853728 14.825 100 82 162 2 1486043680 39422416 20767536
23:55:10 1983 8192000000 10372972016 10779443200 1402630960 14.618 100 92 184 0 1472372016 45447824 24293232
23:56:19 4693 8192000000 10382077008 10789126144 1416762560 14.744 100 92 183 2 1481583248 41652304 23168384
23:57:30 12400 8192000000 10357095584 10784747520 1409243232 14.677 100 46 91 0 1464601536 42753456 12604848
23:58:47 13520 8192000000 10344359440 10773168128 1387627344 14.485 99 43 86 2 1449863904 49823456 12413104
00:00:13 11680 8192000000 10312983008 10742808576 1356958832 14.210 99 31 62 0 1403881840 38412832 8510176
00:01:27 9586 8192000000 10304282768 10744131584 1353377888 14.178 99 30 60 1 1407861744 45706144 8777712
00:02:38 12114 8192000000 10298644032 10728607744 1325130544 13.923 97 29 58 0 1372129808 38744832 8254432
00:03:53 12196 8192000000 10286095600 10718203904 1338165760 14.041 99 27 53 1 1371299440 26221856 6911824
00:05:07 44406 8192000000 10267166352 10707943424 1330893712 13.975 98 8 16 0 1353604416 20793008 1917696
00:06:52 70228 8192000000 10261644624 10703233024 1332158064 13.987 98 5 10 0 1348546880 15310960 1077856
00:09:04 58255 8192000000 10281960272 10720522240 1336036512 14.022 98 10 20 1 1352981264 14329840 2614912
00:11:04 76705 8192000000 10289466272 10735202304 1354340336 14.187 99 8 16 0 1370095232 13946880 1808016
00:13:21 67281 8192000000 10300202608 10744401920 1383622064 14.449 100 20 40 0 1397808032 10067072 4118896
00:15:34 71562 8192000000 10321785776 10765463552 1398834528 14.585 100 54 105 1 1421674848 9507376 13332944
00:18:00 71109 8192000000 10314785632 10768470016 1402494128 14.617 100 34 68 1 1421372960 10853744 8025088
00:20:14 52215 8192000000 10305466992 10765271040 1378347472 14.402 98 16 32 3 1395837344 13531088 3958784
00:22:10 59594 8192000000 10304915504 10764394496 1389775536 14.504 100 20 40 1 1410689472 16191152 4722784
00:24:19 25666 8192000000 10306317872 10766508032 1392220064 14.526 99 24 49 0 1406981232 8516928 6244240
00:25:48 52759 8192000000 10302733952 10763603968 1400075392 14.596 100 18 36 2 1410712224 6046928 4589904
00:28:21 41934 8192000000 10299353712 10756902912 1377014832 14.390 98 16 31 0 1387474112 7084560 3374720
00:30:05 38188 8192000000 10315525584 10765148160 1388768688 14.495 99 22 44 2 1407253776 13677488 4807600
00:31:44 30735 8192000000 10286801776 10750656512 1365317872 14.285 98 16 32 0 1383504864 14569088 3617904
00:33:16 43756 8192000000 10291339472 10755903488 1382777216 14.441 99 4 8 0 1392143200 8246208 1119776
00:35:03 64738 8192000000 10307995584 10772348928 1402576128 14.618 100 13 26 2 1415877824 10547360 2754336
00:37:08 31523 8192000000 10294707216 10756042752 1361900096 14.254 97 19 38 0 1375541024 9139424 4501504
00:38:41 35496 8192000000 10287034192 10718294016 1317191936 13.851 95 30 60 0 1332195920 8994640 6009344
00:40:17 24300 8192000000 10293775088 10736328704 1362342848 14.258 99 40 78 1 1381680544 10838160 8499536
00:41:42 85478 8192000000 10278509008 10735120384 1331188912 13.978 96 23 46 1 1349765616 13307296 5269408
00:44:12 36830 8192000000 10269193200 10723110912 1332950192 13.994 97 21 42 0 1348253184 10251328 5051664
00:45:49 40927 8192000000 10278702304 10728120320 1337204080 14.032 98 39 76 1 1357410288 11369072 8837136
00:47:30 104920 8192000000 10247599856 10691112960 1277038416 13.486 94 24 48 1 1291478224 8623760 5816048
00:50:20 34090 8192000000 10227744064 10672873472 1323777360 13.911 99 14 28 1 1338945504 11646640 3521504
00:51:55 42994 8192000000 10243160432 10693926912 1330709392 13.974 99 19 38 0 1343963824 8877104 4377328
00:53:47 66774 8192000000 10255943184 10715529216 1340800688 14.065 99 15 29 0 1356198544 12106016 3291840
00:55:54 70959 8192000000 10251247088 10706042880 1331719776 13.983 98 12 24 1 1344477120 10426912 2330432
00:58:09 56338 8192000000 10245637520 10709053440 1340152624 14.059 99 8 16 1 1351069120 9500384 1416112
01:00:06 48169 8192000000 10280844800 10736455680 1361664048 14.252 99 21 42 2 1376069680 9275040 5130592
01:01:56 55810 8192000000 10279907136 10733977600 1372152448 14.346 100 20 40 1 1388371632 11767232 4451952
01:04:05 32301 8192000000 10291172368 10733641728 1348103936 14.130 98 26 52 0 1361908816 8448400 5356480
01:05:42 54031 8192000000 10290474736 10734907392 1363883040 14.272 99 43 85 3 1385522176 11878688 9760448
01:07:38 93482 8192000000 10286892512 10755678208 1339651520 14.054 97 17 34 0 1354946432 11941328 3353584
01:10:12 56492 8192000000 10324735184 10773868544 1399977008 14.595 100 22 44 0 1418453872 13761744 4715120
01:12:10 146003 8192000000 10298035200 10756993024 1385656768 14.467 99 13 26 0 1402052048 12888400 3506880
01:16:16 41823 8192000000 10280386800 10751635456 1375961168 14.380 98 10 19 1 1387724992 9462064 2301760
01:18:06 39812 8192000000 10270220176 10733817856 1356928800 14.210 98 7 14 0 1366971280 8502608 1539872
01:19:46 70932 8192000000 10288130448 10751979520 1367683440 14.306 98 12 24 0 1379750672 8859472 3207760
01:22:05 70370 8192000000 10280155232 10745446400 1354605568 14.189 97 9 18 0 1367441184 10165920 2669696
01:24:16 15150 8192000000 10292191424 10749763584 1389744032 14.504 100 22 44 1 1402855648 7921360 5190256
01:25:41 53566 8192000000 10309701920 10761973760 1380692384 14.423 99 22 45 0 1400399584 14702112 5005088
01:27:35 57368 8192000000 10301738080 10753826816 1366737136 14.298 98 16 32 1 1384657632 13981904 3938592
01:29:37 36252 8192000000 10299861696 10746601472 1349597760 14.144 97 38 76 0 1369565584 11512240 8455584
01:31:16 24368 8192000000 10285111408 10726965248 1343231088 14.087 98 24 48 0 1357356816 8853088 5272640
01:32:42 34197 8192000000 10281101104 10727112704 1329402784 13.962 97 34 68 0 1352654768 16084192 7167792
01:34:24 94895 8192000000 10258942784 10727518208 1297768000 13.675 94 7 14 0 1320975216 21598704 1608512
01:37:32 37939 8192000000 9243456368 9518845952 406455440 4.727 30 10 20 0 411229760 2524096 2250224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-10-2021 01:40:02 to 08-10-2021 01:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 85 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 08-10-2021 01:40:18 to 08-10-2021 01:40:20.

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          243902
MON$OLDEST_ACTIVE               243903
MON$OLDEST_SNAPSHOT             243903
MON$NEXT_TRANSACTION            243903
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                       2044192
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.33519 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33519 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33519 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33519 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Oct  8 01:38:12 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

08.10.21 01:40:21. 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-10-08 01:40:23 No severe PSQL-related problems occured
08.10.21 01:40:25. End of report.