Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2021 16: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 15876576 kB
MemAvailable 30612812 kB
Buffers 0 kB
Cached 15822404 kB
SwapCached 3704 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-23 16:37:56
DTS_END                         2021-10-23 16:37:56.3840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39660
TRN_ID                          252326
ATT_ID                          746
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: 8973.43 18139 1076812
customer order: creation 181.77 350 21812
customer order: refuse 15.70 346 1884
order to supplier: creation 48.36 1292 5803
order to supplier: removal 3.29 110916 395
invoice (draft): creation 47.36 3318 5683
invoice (draft): removal 50.31 877 6037
invoice accept: apply 41.98 18997 5038
invoice accept: cancel 45.10 924 5412
customer reserve: creation 3847.87 191 461744
customer reserve: removal 4379.55 66 525546
realization accept: apply 87.83 194 10540
realization accept: cancel 89.27 117 10712
payment from customer: creation 62.26 113 7471
payment from customer: removal 6.13 75 736
payment to supplier: creation 51.24 127 6149
payment to supplier: removal 3.60 69 432
service: total inventory turnovers 4.43 1104 531
service: total monetary turnovers 6.53 31 784
service: aggregate perf. data 0.83 25188 99
service: refresh index statistics 0.03 198490 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2021 16:39:29 to 23-10-2021 16:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2549
TEST_TIME 2 2320
TEST_TIME 3 3065
TEST_TIME 4 3696
TEST_TIME 5 4206
TEST_TIME 6 4269
TEST_TIME 7 4467
TEST_TIME 8 4786
TEST_TIME 9 4940
TEST_TIME 10 5079
TEST_TIME 11 5390
TEST_TIME 12 5466
TEST_TIME 13 5889
TEST_TIME 14 6237
TEST_TIME 15 6286
TEST_TIME 16 6284
TEST_TIME 17 6457
TEST_TIME 18 7033
TEST_TIME 19 7138
TEST_TIME 20 7077
TEST_TIME 21 7761
TEST_TIME 22 8277
TEST_TIME 23 8090
TEST_TIME 24 8326
TEST_TIME 25 8628
TEST_TIME 26 9043
TEST_TIME 27 9369
TEST_TIME 28 9595
TEST_TIME 29 10790
TEST_TIME 30 10698
TEST_TIME 31 10553
TEST_TIME 32 10250
TEST_TIME 33 10249
TEST_TIME 34 10128
TEST_TIME 35 10089
TEST_TIME 36 9963
TEST_TIME 37 9878
TEST_TIME 38 9915
TEST_TIME 39 9825
TEST_TIME 40 9687
TEST_TIME 41 9621
TEST_TIME 42 9694
TEST_TIME 43 9652
TEST_TIME 44 9530
TEST_TIME 45 9342
TEST_TIME 46 9336
TEST_TIME 47 9247
TEST_TIME 48 9250
TEST_TIME 49 9171
TEST_TIME 50 9137
TEST_TIME 51 9075
TEST_TIME 52 9045
TEST_TIME 53 9074
TEST_TIME 54 8975
TEST_TIME 55 8954
TEST_TIME 56 8944
TEST_TIME 57 8934
TEST_TIME 58 8966
TEST_TIME 59 8915
TEST_TIME 60 8935
TEST_TIME 61 8990
TEST_TIME 62 9037
TEST_TIME 63 9012
TEST_TIME 64 9020
TEST_TIME 65 9075
TEST_TIME 66 9109
TEST_TIME 67 9162
TEST_TIME 68 9130
TEST_TIME 69 9194
TEST_TIME 70 9190
TEST_TIME 71 9156
TEST_TIME 72 9227
TEST_TIME 73 9108
TEST_TIME 74 9117
TEST_TIME 75 9087
TEST_TIME 76 9028
TEST_TIME 77 9035
TEST_TIME 78 8989
TEST_TIME 79 8977
TEST_TIME 80 8929
TEST_TIME 81 8870
TEST_TIME 82 8861
TEST_TIME 83 8901
TEST_TIME 84 9012
TEST_TIME 85 9059
TEST_TIME 86 9022
TEST_TIME 87 9024
TEST_TIME 88 9042
TEST_TIME 89 9071
TEST_TIME 90 9061
TEST_TIME 91 9113
TEST_TIME 92 9106
TEST_TIME 93 9112
TEST_TIME 94 9183
TEST_TIME 95 9218
TEST_TIME 96 9169
TEST_TIME 97 9220
TEST_TIME 98 9129
TEST_TIME 99 9087
TEST_TIME 100 9134
TEST_TIME 101 9082
TEST_TIME 102 9055
TEST_TIME 103 9018
TEST_TIME 104 8999
TEST_TIME 105 8961
TEST_TIME 106 8969
TEST_TIME 107 8994
TEST_TIME 108 9032
TEST_TIME 109 9005
TEST_TIME 110 9011
TEST_TIME 111 9020
TEST_TIME 112 9052
TEST_TIME 113 9044
TEST_TIME 114 9031
TEST_TIME 115 9086
TEST_TIME 116 9067
TEST_TIME 117 9040
TEST_TIME 118 9034
TEST_TIME 119 9029
TEST_TIME 120 8975
TEST_TIME 121 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2021 16:39:33 to 23-10-2021 16:39:36.

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 1506388 1506388 0 0.00 0 23597 7 0 0
doc_list_biud 1506388 1506388 0 0.00 0 44166 29 0 0
sp_add_invoice_to_stock 5149 5038 111 2.15 35 207012 18997 0 111
sp_cancel_adding_invoice 5412 5412 0 0.00 5 80524 924 0 0
sp_cancel_client_order 1884 1884 0 0.00 4 12343 346 0 0
sp_cancel_customer_reserve 525546 525546 0 0.00 1 44167 66 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 9347 75 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 5567 69 0 0
sp_cancel_supplier_invoice 6048 6037 11 0.18 2 19136 878 0 11
sp_cancel_supplier_order 395 395 0 0.00 10 1178470 110916 0 0
sp_cancel_write_off 10712 10712 0 0.00 1 12476 117 0 0
sp_client_order 21812 21812 0 0.00 3 43974 351 0 0
sp_customer_reserve 461744 461744 0 0.00 0 54170 192 0 0
sp_fill_shopping_cart 33284 33284 0 0.00 1 12595 36 0 0
sp_fill_shopping_cart_clo_res 461743 461743 0 0.00 0 12317 13 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 34633 285 0 0
sp_kill_cost_storno 17292 17292 0 0.00 0 12506 59 0 0
sp_kill_qstorno_ret_qs2qd 1068236 1068236 0 0.00 0 43951 36 0 0
sp_kill_qty_storno 565560 565560 0 0.00 0 44166 77 0 0
sp_lock_selected_doc 21667 21667 0 0.00 0 7728 5 0 0
sp_make_cost_storno 29194 29194 0 0.00 0 18886 54 0 0
sp_multiply_rows_for_pdistr 29194 29194 0 0.00 0 13398 27 0 0
sp_multiply_rows_for_qdistr 21812 21812 0 0.00 0 43961 153 0 0
sp_pay_from_customer 7471 7471 0 0.00 4 13946 114 0 0
sp_pay_to_supplier 6149 6149 0 0.00 4 13413 127 0 0
sp_payment_common 13620 13620 0 0.00 1 13918 33 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 12330 321 0 0
sp_qd_handle_on_invoice_upd_sts 10446 10446 0 0.00 0 21239 320 0 0
sp_qd_handle_on_reserve_upd_sts 10540 10540 0 0.00 0 21456 72 0 0
sp_reserve_write_off 10540 10540 0 0.00 7 21529 194 0 0
sp_supplier_invoice 5683 5683 0 0.00 7 73068 3319 0 0
sp_supplier_order 5803 5803 0 0.00 5 46161 1292 0 0
srv_aggregate_perf_data 99 99 0 0.00 2495 478313 25188 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2206 141099 46773 0 0
srv_find_qd_qs_mism 1028585 1028585 0 0.00 0 23330 7 0 0
srv_make_invnt_saldo 531 531 0 0.00 45 60943 1105 0 0
srv_make_money_saldo 784 784 0 0.00 1 8244 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 87466 405281 198490 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5038 5038 0 0.00 0 6626 143 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 16085 175 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 5921 16 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1748 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 554 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 178 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 1109 17 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 19047 1713 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 11403 21 0 0
t$perf-norm:sp_client_order 21812 21812 0 0.00 0 16694 18 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 7491 20 0 0
t$perf-norm:sp_pay_from_customer 7471 7471 0 0.00 0 16907 17 0 0
t$perf-norm:sp_pay_to_supplier 6149 6149 0 0.00 0 7489 10 0 0
t$perf-norm:sp_reserve_write_off 10540 10540 0 0.00 0 15450 13 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 3476 15 0 0
t$perf-norm:sp_supplier_order 5803 5803 0 0.00 0 16500 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 68 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 213 6 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 6022 31 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 11431 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10290 10068 222 2.15 0 13066 18 0 222
v_all_customers 40074 40074 0 0.00 0 63 0 0 0
v_all_suppliers 31932 31932 0 0.00 0 14 1 0 0
v_all_wares 43624 43624 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1884 11 0 0
v_cancel_client_order 3768 3768 0 0.00 0 5308 5 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 9341 11 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1656 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 428 3 0 0
v_cancel_supplier_invoice 880 858 22 2.50 0 4687 25 0 22
v_cancel_supplier_order 790 790 0 0.00 0 2957 9 0 0
v_cancel_write_off 2128 2128 0 0.00 0 2397 15 0 0
v_max_id_clo_ord 5803 5803 0 0.00 0 317 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 1338 8 0 0
v_max_id_ord_sup 5668 5668 0 0.00 0 3985 3 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 473 2 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 4674 6 0 0
v_min_id_clo_ord 5803 5803 0 0.00 0 3393 7 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 7095 17 0 0
v_min_id_ord_sup 5668 5668 0 0.00 0 5186 9 0 0
v_min_non_paid_invoice 6149 6149 0 0.00 0 13091 22 0 0
v_min_non_paid_realizn 7471 7471 0 0.00 0 13913 29 0 0
v_our_firm 10850 10850 0 0.00 0 11 1 0 0
v_reserve_write_off 21080 21080 0 0.00 0 3562 7 0 0
x_lock_depdocs_on_canc_invoice 11020 11020 0 0.00 0 21042 136 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 15876 390 0 0
x_make_qstorno_1000_1200 5803 5803 0 0.00 1 42766 1220 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 3 73063 3177 0 0
x_make_qstorno_2100_3300 461744 461744 0 0.00 0 54167 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2021 16:39:37 to 23-10-2021 16:39:41.

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
14:07:59 3 8192000000 8806216864 8819806208 11560656 0.140 3 3 4 0 11744576 76800 107120
14:09:00 22127 8192000000 9897380176 10204672000 976204384 10.647 100 88 176 0 1005650240 5812736 23633120
14:10:29 19978 8192000000 9930886816 10247770112 1008091968 10.957 100 84 168 0 1038068672 7847280 22129424
14:12:11 12167 8192000000 9973083888 10306015232 1055239712 11.411 100 74 148 0 1083116656 9688112 18188832
14:13:34 9211 8192000000 10056488128 10406981632 1140422672 12.220 100 82 164 2 1171314080 11382960 19508448
14:14:52 6567 8192000000 10105173008 10471014400 1193761568 12.718 100 77 155 1 1224392704 13049392 17581744
14:16:01 3655 8192000000 10153371584 10525011968 1237856384 13.126 100 85 169 0 1270107696 13679888 18571424
14:17:05 3707 8192000000 10167768912 10548072448 1255260592 13.287 100 86 173 1 1288210416 14497200 18452624
14:18:14 1506 8192000000 10208314640 10591924224 1289984256 13.604 100 93 186 1 1324987616 14517680 20485680
14:19:16 29450 8192000000 10202867472 10602967040 1301585232 13.710 100 45 88 2 1318221056 6354656 10281168
14:20:47 6509 8192000000 10201357424 10609680384 1310628800 13.792 100 32 64 0 1324790416 7049280 7112336
14:21:55 7458 8192000000 10201712320 10621272064 1319674112 13.874 100 17 34 1 1329403584 5597312 4132160
14:23:05 8192 8192000000 10217236672 10634813440 1327886768 13.948 100 22 44 0 1339332512 6576432 4869312
14:24:17 56556 8192000000 10220077696 10646433792 1334483200 14.008 100 19 37 0 1348811840 9642320 4686320
14:26:14 9771 8192000000 10222095696 10641526784 1331080160 13.977 100 18 34 0 1340249888 5215504 3954224
14:27:24 12910 8192000000 10209106432 10632183808 1312881616 13.812 99 9 18 1 1320371024 5290368 2199040
14:28:37 37305 8192000000 10161029584 10569392128 1251748432 13.254 97 26 52 1 1264017648 6489216 5780000
14:30:26 6655 8192000000 10150568912 10539933696 1227446944 13.030 97 33 66 1 1245760864 9872416 8441504
14:31:35 5244 8192000000 10159280992 10560282624 1263998096 13.367 100 30 61 0 1276070736 4947504 7125136
14:32:42 13416 8192000000 10158001760 10565869568 1259382736 13.324 99 18 36 2 1269248208 5382112 4483360
14:33:56 25570 8192000000 10174114752 10590711808 1289266048 13.598 100 37 75 1 1308100288 9160016 9674224
14:35:26 2889 8192000000 10203725392 10609557504 1302323936 13.716 100 39 78 4 1317463568 6886720 8252912
14:36:30 8475 8192000000 10205607168 10620071936 1307988240 13.768 100 40 81 0 1328139840 9658368 10493232
14:37:39 47843 8192000000 10227647456 10643111936 1331417856 13.980 100 27 54 0 1343377360 4946176 7013328
14:39:32 16815 8192000000 10244236480 10656247808 1333618192 14.000 99 43 86 1 1351555184 8487776 9449216
14:40:54 9552 8192000000 10250646768 10662047744 1328780048 13.956 98 47 94 1 1354533136 14595696 11157392
14:42:07 37356 8192000000 10266739200 10690940928 1350366992 14.151 99 38 76 1 1375115408 16395600 8352816
14:43:51 18151 8192000000 10293847776 10711654400 1374322144 14.366 100 43 84 1 1401330192 16902768 10105280
14:45:14 19415 8192000000 10326902032 10743476224 1393555408 14.538 100 56 112 1 1433797936 26405776 13836752
14:46:40 8641 8192000000 10319996464 10745753600 1397951664 14.577 100 44 88 4 1434879264 26289264 10638336
14:47:53 4643 8192000000 10320701040 10732662784 1384166720 14.454 100 69 137 1 1430904496 30509648 16228128
14:49:01 10388 8192000000 10329679776 10748502016 1382899072 14.442 100 50 98 1 1434088096 38209136 12979888
14:50:16 5865 8192000000 10319144144 10734473216 1367755440 14.307 99 57 112 0 1420585200 38538080 14291680
14:51:25 11198 8192000000 10320996848 10739060736 1380705872 14.423 100 50 99 4 1429663168 36653056 12304240
14:52:44 6038 8192000000 10326668976 10733780992 1361933856 14.255 99 73 146 2 1425538256 45437760 18166640
14:53:57 2526 8192000000 10336414784 10735247360 1367826768 14.308 100 89 178 2 1439289248 48999968 22462512
14:55:05 2205 8192000000 10353892784 10758987776 1376581104 14.386 100 95 187 0 1455977424 56083664 23312656
14:56:12 19729 8192000000 10335638112 10763341824 1378216832 14.401 100 34 68 0 1441603232 53488800 9897600
14:57:35 5351 8192000000 10369039728 10783670272 1385987536 14.470 100 63 123 2 1465070208 63216064 15866608
14:58:44 13256 8192000000 10345470208 10775965696 1386367280 14.473 100 37 72 3 1451157696 53400224 11390192
15:00:12 13891 8192000000 10355145392 10783571968 1397118640 14.569 100 34 67 2 1454635408 48544672 8972096
15:01:31 11936 8192000000 10349998368 10779164672 1389045248 14.497 100 43 86 2 1455357648 54407104 11905296
15:02:49 20944 8192000000 10347501920 10781454336 1378040704 14.399 99 34 68 0 1447634848 60158144 9436000
15:04:31 30050 8192000000 10315298608 10755911680 1376550400 14.386 100 22 44 2 1427893376 44776784 6566192
15:06:40 46231 8192000000 10278511440 10715951104 1339322560 14.051 98 16 32 1 1364000480 20968208 3709712
15:08:27 113991 8192000000 10264342256 10703400960 1305295872 13.743 95 30 60 0 1319460096 7416256 6747968
15:11:54 23161 8192000000 10282157040 10730639360 1373991632 14.363 100 36 70 0 1393557696 10734128 8831936
15:13:18 11850 8192000000 10283996640 10723217408 1366343456 14.294 99 30 60 1 1381832320 8645200 6843664
15:14:31 94820 8192000000 10279062000 10733776896 1376424928 14.385 99 30 60 0 1396025424 12214064 7386432
15:17:25 32938 8192000000 10278820976 10727534592 1354467456 14.188 98 20 40 0 1370224928 11314864 4442608
15:19:02 43802 8192000000 10284786128 10740023296 1382343104 14.437 100 23 46 0 1400473680 12855904 5274672
15:21:10 94084 8192000000 10290818608 10742411264 1377824880 14.397 99 20 40 0 1390335136 7594032 4916224
15:24:27 79840 8192000000 10284828880 10751451136 1356143600 14.203 97 17 34 0 1372344976 12449360 3752016
15:26:50 12080 8192000000 10306163552 10748772352 1392367856 14.527 100 50 99 0 1413464960 9778192 11318912
15:28:08 50644 8192000000 10295443600 10754945024 1384593728 14.458 99 23 47 0 1403530400 13235776 5700896
15:29:59 38581 8192000000 10279265536 10739847168 1360836720 14.245 98 15 30 0 1378479120 14081472 3560928
15:31:38 91560 8192000000 10265029536 10737676288 1361096768 14.247 99 11 22 1 1381123184 15852528 4173888
15:34:10 44584 8192000000 10285823504 10744606720 1374137184 14.364 99 17 34 1 1387795440 8863936 4794320
15:35:58 20839 8192000000 10274105696 10716520448 1331620464 13.982 97 40 80 0 1348969088 8193344 9155280
15:37:23 86046 8192000000 10246873568 10691874816 1246977472 13.210 91 12 24 2 1256373152 6987952 2407728
15:39:49 50428 8192000000 10260275792 10702577664 1331881088 13.984 98 21 41 1 1345477248 8867184 4728976
15:41:46 74408 8192000000 10271751280 10724200448 1349194464 14.140 99 30 60 0 1369675504 14218000 6263040
15:44:01 108266 8192000000 10265956112 10702471168 1272246016 13.442 93 12 24 0 1286378688 11306816 2825856
15:47:27 100516 8192000000 10286325872 10730180608 1332760848 13.992 97 21 42 2 1351060336 13413088 4886400
15:50:09 94488 8192000000 10268998272 10721988608 1357149552 14.212 99 15 30 1 1370945856 10562352 3233952
15:52:51 46887 8192000000 10281721584 10726735872 1371977696 14.345 100 32 64 0 1388084224 8115088 7991440
15:54:52 68408 8192000000 10275992016 10727399424 1349228432 14.141 98 22 44 4 1363664912 9291392 5145088
15:57:06 92396 8192000000 10284206960 10739138560 1337150496 14.032 96 19 38 1 1356501312 14326432 5024384
15:59:41 80868 8192000000 10296507456 10757181440 1365981824 14.291 99 12 24 0 1392733120 23471824 3279472
16:02:02 60752 8192000000 10280561888 10730287104 1363288080 14.267 99 22 44 0 1377522176 8889904 5344192
16:04:04 46891 8192000000 10288775792 10739597312 1366618304 14.297 99 28 56 0 1385053712 12576848 5858560
16:05:53 73760 8192000000 10303759664 10758324224 1384335728 14.455 100 29 58 1 1406139984 15746128 6058128
16:08:26 29725 8192000000 10316188144 10766934016 1398594016 14.582 100 30 60 2 1419067888 14587696 5886176
16:09:56 60257 8192000000 10304240976 10749239296 1340766048 14.064 96 27 54 0 1357719088 11381600 5571440
16:11:56 75133 8192000000 10306229008 10770522112 1368751728 14.316 98 9 18 2 1389046720 17957856 2337136
16:14:33 141099 8192000000 10274022176 10723090432 1294490224 13.645 93 13 26 0 1309050800 10982080 3578496
16:18:10 43256 8192000000 10259585936 10709467136 1312764576 13.811 95 18 36 0 1329258416 12689728 3804112
16:20:04 41563 8192000000 10271391344 10729938944 1369519936 14.323 100 8 16 0 1387681408 16152976 2008496
16:21:46 88741 8192000000 10294736976 10748919808 1378343392 14.402 100 21 42 1 1401710912 17604896 5762624
16:24:26 36493 8192000000 10294290240 10749861888 1384911488 14.460 100 19 35 2 1400375664 10643872 4820304
16:26:27 39184 8192000000 10286454256 10725511168 1335635264 14.018 97 39 78 0 1351888528 7561680 8691584
16:28:12 91266 8192000000 10261739600 10721120256 1300450896 13.699 95 18 32 1 1321554432 17449584 3653952
16:30:43 67437 8192000000 10272124256 10720247808 1319920480 13.876 97 25 50 0 1337482336 12063968 5497888
16:33:45 36050 8192000000 10283222176 10727305216 1368962112 14.318 100 31 61 0 1382114912 6591824 6560976
16:35:22 19568 8192000000 10278578672 10715656192 1356195296 14.203 100 35 70 1 1373865728 10059360 7611072
16:36:44 77929 8192000000 9682949440 10034995200 810736064 9.005 60 28 54 2 823513312 6966560 5810688

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-10-2021 16:39:43 to 23-10-2021 16:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2021 16:39:59 to 23-10-2021 16:40:01.

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          248076
MON$OLDEST_ACTIVE               252942
MON$OLDEST_SNAPSHOT             252942
MON$NEXT_TRANSACTION            252942
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                       2030752
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Sat Oct 23 16:38:12 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Sat Oct 23 16:38:17 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Sat Oct 23 16:38:18 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
--- end of diff output ---

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

23.10.21 16:40:02. 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-23 16:40:04 No severe PSQL-related problems occured
23.10.21 16:40:05. End of report.