Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.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 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 16605624 kB
MemAvailable 31436488 kB
Buffers 0 kB
Cached 15102320 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 3.0.9
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-12-20 16:37:58
DTS_END                         2021-12-20 16:37:58.1980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56586
TRN_ID                          239956
ATT_ID                          642
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: 9019.83 20932 1082380
customer order: creation 170.83 379 20500
customer order: refuse 15.04 330 1805
order to supplier: creation 45.72 1390 5486
order to supplier: removal 3.33 104421 400
invoice (draft): creation 46.27 3678 5552
invoice (draft): removal 50.85 769 6102
invoice accept: apply 40.99 17978 4919
invoice accept: cancel 46.39 849 5567
customer reserve: creation 3762.77 178 451532
customer reserve: removal 4546.94 61 545633
realization accept: apply 83.13 198 9975
realization accept: cancel 79.57 119 9548
payment from customer: creation 59.02 118 7082
payment from customer: removal 5.41 75 649
payment to supplier: creation 48.45 142 5814
payment to supplier: removal 3.29 55 395
service: total inventory turnovers 4.53 1400 544
service: total monetary turnovers 6.46 37 775
service: aggregate perf. data 0.82 21655 98
service: refresh index statistics 0.03 264809 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2021 16:40:17 to 20-12-2021 16:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3130
TEST_TIME 2 3543
TEST_TIME 3 3182
TEST_TIME 4 3428
TEST_TIME 5 4383
TEST_TIME 6 5562
TEST_TIME 7 5340
TEST_TIME 8 5286
TEST_TIME 9 5564
TEST_TIME 10 5698
TEST_TIME 11 5686
TEST_TIME 12 5795
TEST_TIME 13 5785
TEST_TIME 14 5949
TEST_TIME 15 6371
TEST_TIME 16 6428
TEST_TIME 17 6488
TEST_TIME 18 6873
TEST_TIME 19 6957
TEST_TIME 20 7139
TEST_TIME 21 7356
TEST_TIME 22 7447
TEST_TIME 23 7574
TEST_TIME 24 7784
TEST_TIME 25 8256
TEST_TIME 26 8616
TEST_TIME 27 8640
TEST_TIME 28 8601
TEST_TIME 29 9163
TEST_TIME 30 9725
TEST_TIME 31 10113
TEST_TIME 32 10163
TEST_TIME 33 10078
TEST_TIME 34 9910
TEST_TIME 35 9761
TEST_TIME 36 9841
TEST_TIME 37 9793
TEST_TIME 38 9740
TEST_TIME 39 9594
TEST_TIME 40 9504
TEST_TIME 41 9467
TEST_TIME 42 9482
TEST_TIME 43 9563
TEST_TIME 44 9624
TEST_TIME 45 9507
TEST_TIME 46 9483
TEST_TIME 47 9347
TEST_TIME 48 9188
TEST_TIME 49 9116
TEST_TIME 50 9155
TEST_TIME 51 9137
TEST_TIME 52 9078
TEST_TIME 53 9031
TEST_TIME 54 8929
TEST_TIME 55 8999
TEST_TIME 56 8965
TEST_TIME 57 8927
TEST_TIME 58 8969
TEST_TIME 59 9057
TEST_TIME 60 9031
TEST_TIME 61 9081
TEST_TIME 62 9160
TEST_TIME 63 9191
TEST_TIME 64 9137
TEST_TIME 65 9163
TEST_TIME 66 9277
TEST_TIME 67 9268
TEST_TIME 68 9353
TEST_TIME 69 9359
TEST_TIME 70 9401
TEST_TIME 71 9291
TEST_TIME 72 9176
TEST_TIME 73 9224
TEST_TIME 74 9188
TEST_TIME 75 9110
TEST_TIME 76 9120
TEST_TIME 77 9068
TEST_TIME 78 9011
TEST_TIME 79 8990
TEST_TIME 80 9054
TEST_TIME 81 9083
TEST_TIME 82 9060
TEST_TIME 83 9040
TEST_TIME 84 9003
TEST_TIME 85 9092
TEST_TIME 86 9239
TEST_TIME 87 9224
TEST_TIME 88 9222
TEST_TIME 89 9287
TEST_TIME 90 9309
TEST_TIME 91 9324
TEST_TIME 92 9346
TEST_TIME 93 9257
TEST_TIME 94 9186
TEST_TIME 95 9170
TEST_TIME 96 9120
TEST_TIME 97 9073
TEST_TIME 98 9109
TEST_TIME 99 9077
TEST_TIME 100 9062
TEST_TIME 101 9037
TEST_TIME 102 9094
TEST_TIME 103 9118
TEST_TIME 104 9134
TEST_TIME 105 9122
TEST_TIME 106 9150
TEST_TIME 107 9161
TEST_TIME 108 9218
TEST_TIME 109 9200
TEST_TIME 110 9223
TEST_TIME 111 9236
TEST_TIME 112 9227
TEST_TIME 113 9191
TEST_TIME 114 9141
TEST_TIME 115 9095
TEST_TIME 116 9100
TEST_TIME 117 9073
TEST_TIME 118 9029
TEST_TIME 119 9045
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2021 16:40:21 to 20-12-2021 16:40:25.

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 1501013 1501013 0 0.00 0 24998 6 0 0
doc_list_biud 1501013 1501013 0 0.00 0 39962 28 0 0
sp_add_invoice_to_stock 5014 4919 95 1.89 58 192975 17979 0 95
sp_cancel_adding_invoice 5567 5567 0 0.00 5 97514 849 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 26252 330 0 0
sp_cancel_customer_reserve 545633 545633 0 0.00 1 39963 62 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 2 5500 76 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 1979 56 0 0
sp_cancel_supplier_invoice 6108 6102 6 0.09 2 37488 769 0 6
sp_cancel_supplier_order 400 400 0 0.00 6 947939 104422 0 0
sp_cancel_write_off 9548 9548 0 0.00 1 23948 120 0 0
sp_client_order 20500 20500 0 0.00 4 48460 380 0 0
sp_customer_reserve 451532 451532 0 0.00 0 105947 178 0 0
sp_fill_shopping_cart 31513 31513 0 0.00 1 27188 47 0 0
sp_fill_shopping_cart_clo_res 451531 451531 0 0.00 0 15369 14 0 0
sp_get_clo_for_invoice 4918 4918 0 0.00 0 17211 291 0 0
sp_kill_cost_storno 16159 16159 0 0.00 0 23910 59 0 0
sp_kill_qstorno_ret_qs2qd 1107316 1107316 0 0.00 0 39959 33 0 0
sp_kill_qty_storno 583948 583948 0 0.00 0 39962 72 0 0
sp_lock_selected_doc 20692 20692 0 0.00 0 6573 4 0 0
sp_make_cost_storno 27789 27789 0 0.00 0 24988 61 0 0
sp_multiply_rows_for_pdistr 27789 27789 0 0.00 0 23913 32 0 0
sp_multiply_rows_for_qdistr 20500 20500 0 0.00 0 39606 169 0 0
sp_pay_from_customer 7082 7082 0 0.00 4 21340 119 0 0
sp_pay_to_supplier 5814 5814 0 0.00 5 23947 143 0 0
sp_payment_common 12896 12896 0 0.00 1 13656 29 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 20922 303 0 0
sp_qd_handle_on_invoice_upd_sts 10485 10485 0 0.00 0 27548 343 0 0
sp_qd_handle_on_reserve_upd_sts 9975 9975 0 0.00 0 27274 76 0 0
sp_reserve_write_off 9975 9975 0 0.00 6 27399 198 0 0
sp_supplier_invoice 5552 5552 0 0.00 7 90577 3678 0 0
sp_supplier_order 5486 5486 0 0.00 5 37125 1391 0 0
srv_aggregate_perf_data 98 98 0 0.00 2621 325663 21656 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1325 156476 47723 0 0
srv_find_qd_qs_mism 1035523 1035523 0 0.00 0 17083 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 26 165094 1401 0 0
srv_make_money_saldo 775 775 0 0.00 1 6585 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 221306 296178 264810 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 13244 177 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 10796 154 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 6782 16 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 2983 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 9155 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 294 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 10082 52 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 22533 1715 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2955 11 0 0
t$perf-norm:sp_client_order 20500 20500 0 0.00 0 7051 17 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 7680 20 0 0
t$perf-norm:sp_pay_from_customer 7082 7082 0 0.00 0 12441 16 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 9012 17 0 0
t$perf-norm:sp_reserve_write_off 9975 9975 0 0.00 0 10183 13 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 4971 22 0 0
t$perf-norm:sp_supplier_order 5486 5486 0 0.00 0 5262 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 471 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 751 13 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 591 17 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 4898 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10026 9836 190 1.89 0 7846 19 0 190
v_all_customers 37384 37384 0 0.00 0 300 1 0 0
v_all_suppliers 30446 30446 0 0.00 0 301 1 0 0
v_all_wares 41000 41000 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 2530 14 0 0
v_cancel_client_order 3610 3610 0 0.00 0 3314 6 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2516 6 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 2482 8 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 647 4 0 0
v_cancel_supplier_invoice 806 794 12 1.48 0 6450 23 0 12
v_cancel_supplier_order 800 800 0 0.00 0 765 5 0 0
v_cancel_write_off 1972 1972 0 0.00 0 6030 17 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 3527 2 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 2074 14 0 0
v_max_id_ord_sup 5527 5527 0 0.00 0 4190 4 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 831 3 0 0
v_max_non_paid_realizn 3734 3734 0 0.00 0 5115 6 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 3495 7 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 5519 12 0 0
v_min_id_ord_sup 5527 5527 0 0.00 0 7275 14 0 0
v_min_non_paid_invoice 5814 5814 0 0.00 0 13648 23 0 0
v_min_non_paid_realizn 7082 7082 0 0.00 0 8895 21 0 0
v_our_firm 10312 10312 0 0.00 0 88 1 0 0
v_reserve_write_off 19950 19950 0 0.00 0 7292 6 0 0
x_lock_depdocs_on_canc_invoice 11272 11272 0 0.00 0 13009 119 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 8718 365 0 0
x_make_qstorno_1000_1200 5486 5486 0 0.00 2 37118 1311 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 90564 3493 0 0
x_make_qstorno_2100_3300 451532 451532 0 0.00 0 105944 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2021 16:40:26 to 20-12-2021 16:40:30.

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:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:04 17337 8192000000 9884020864 10190614528 965075952 10.539 100 87 174 0 994181648 5452928 23652768
14:10:28 25499 8192000000 9933602512 10251694080 1012593536 11.000 100 91 182 0 1044009536 7481856 23934144
14:12:03 21181 8192000000 9982992384 10317348864 1068802624 11.541 100 84 168 2 1099015312 8301168 21911520
14:13:30 10444 8192000000 10052205488 10404032512 1133890400 12.158 100 74 149 0 1162008496 10228208 17889888
14:14:53 7365 8192000000 10109880432 10474811392 1195119504 12.731 100 80 161 3 1226221040 13144416 17957120
14:16:04 4512 8192000000 10140990816 10519785472 1233018480 13.082 100 71 142 1 1260993680 11914496 16060704
14:17:11 2983 8192000000 10168146944 10553147392 1261139424 13.340 100 78 157 0 1291789536 13260512 17389600
14:18:16 1890 8192000000 10204187280 10586464256 1282134480 13.532 100 93 188 0 1318443888 15127120 21182288
14:19:21 3725 8192000000 10220549776 10605993984 1299532752 13.691 100 91 180 0 1334230128 14352160 20345216
14:20:26 2446 8192000000 10220438992 10613784576 1304327344 13.735 100 73 145 1 1331921552 11371760 16222448
14:21:32 2981 8192000000 10205853856 10614054912 1315868976 13.839 100 49 97 2 1335044208 8195968 10979264
14:22:35 16697 8192000000 10207266544 10627637248 1321748256 13.893 100 44 87 0 1337847696 5807792 10291648
14:23:55 7205 8192000000 10224322352 10643505152 1334095648 14.004 100 45 91 1 1353151008 8940944 10114416
14:25:06 8374 8192000000 10224434256 10647265280 1338841120 14.047 100 27 54 0 1350734800 6065232 5828448
14:26:20 53126 8192000000 10253136592 10650046464 1331225744 13.978 99 64 130 2 1351910848 5850000 14835104
14:28:14 6826 8192000000 10204106192 10619559936 1312218272 13.806 99 33 67 1 1330781968 11088000 7475696
14:29:21 8945 8192000000 10190582144 10600689664 1288723712 13.593 99 21 42 0 1298864080 5244064 4896304
14:30:30 18812 8192000000 10158814800 10569232384 1238304656 13.131 96 26 52 1 1250252384 6288528 5659200
14:31:50 5052 8192000000 10119055936 10506846208 1193893600 12.720 96 34 68 1 1207954272 5601136 8459536
14:32:57 7267 8192000000 10163691120 10560544768 1265500064 13.380 100 24 49 0 1276609680 5073808 6035808
14:34:05 62411 8192000000 10189436704 10599948288 1291025152 13.614 100 22 45 0 1301580208 5568000 4987056
14:36:11 6779 8192000000 10201132976 10613809152 1303675888 13.729 100 22 44 2 1315714352 6256480 5781984
14:37:24 9334 8192000000 10210953488 10622287872 1315234416 13.834 100 24 48 1 1326314256 5653584 5426256
14:38:37 10539 8192000000 10247571632 10656116736 1341382096 14.070 100 52 104 0 1362203552 9548560 11272896
14:39:55 105738 8192000000 10261282112 10683342848 1359236768 14.231 100 36 72 1 1378078464 10241200 8600496
14:42:53 17243 8192000000 10289258128 10708037632 1375821056 14.379 100 53 107 0 1400244256 12427344 11995856
14:44:14 9904 8192000000 10302598768 10725838848 1387451760 14.483 100 48 95 1 1410296064 11489696 11354608
14:45:36 5655 8192000000 10310126112 10738655232 1399952384 14.595 100 51 102 2 1428129296 14964672 13212240
14:46:45 8351 8192000000 10329978064 10752200704 1406464912 14.653 100 69 137 1 1441941440 19929312 15547216
14:48:02 9112 8192000000 10332860928 10757136384 1403690896 14.628 100 59 117 2 1439246208 22198384 13356928
14:49:22 4205 8192000000 10347062976 10769149952 1415995264 14.737 100 60 120 0 1455920784 25422896 14502624
14:50:31 11036 8192000000 10363000688 10787971072 1424659536 14.814 100 68 135 0 1475302256 34195904 16446816
14:51:48 13922 8192000000 10348516736 10777333760 1396938336 14.568 98 49 97 1 1446515520 37024032 12553152
14:53:05 1791 8192000000 10354318880 10767507456 1395639488 14.556 99 75 149 2 1452854896 37661008 19554400
14:54:16 11693 8192000000 10344632928 10766929920 1396426496 14.563 100 52 105 3 1457000304 46565360 14008448
14:55:37 4593 8192000000 10344324048 10765324288 1380955120 14.425 99 59 118 1 1442978240 46967712 15055408
14:56:57 1322 8192000000 10361235232 10766487552 1389944640 14.505 100 79 158 2 1463075264 53083520 20047104
14:58:05 10802 8192000000 10335433312 10756829184 1390144864 14.507 100 44 88 2 1454700064 52796736 11758464
14:59:21 7105 8192000000 10340933968 10755821568 1381617936 14.431 100 55 110 0 1448281296 52185056 14478304
15:00:32 3850 8192000000 10329814560 10748088320 1366893264 14.299 98 51 100 0 1431653536 51559824 13200448
15:01:41 10143 8192000000 10361501008 10786635776 1399311600 14.589 100 39 78 1 1467453520 56958944 11182976
15:02:55 17891 8192000000 10329322000 10762571776 1373185088 14.356 99 28 56 1 1427073776 46266816 7621872
15:04:18 21103 8192000000 10310782496 10746286080 1365458336 14.286 99 22 44 1 1408922576 37526624 5937616
15:06:07 22569 8192000000 10282717472 10713849856 1309838640 13.785 97 20 40 0 1365502304 49620064 6043600
15:08:23 70428 8192000000 10263354032 10713063424 1315235952 13.834 97 6 12 0 1336539872 20318832 985088
15:10:34 31188 8192000000 10255493200 10679820288 1308959040 13.777 97 42 77 3 1326211216 8072096 9180080
15:12:12 52816 8192000000 10265864416 10692673536 1325909216 13.930 98 48 98 1 1344882032 7931376 11041440
15:14:08 62020 8192000000 10263770432 10703142912 1345299200 14.105 98 21 40 0 1357395888 7599376 4497312
15:16:13 103136 8192000000 10295935616 10738044928 1386754256 14.477 100 27 54 1 1404668352 11296912 6617184
15:19:02 43412 8192000000 10290186320 10732761088 1380813456 14.424 100 18 36 1 1403249632 18148320 4287856
15:20:49 16398 8192000000 10303950080 10759979008 1387195584 14.481 100 39 79 0 1419316464 22796848 9324032
15:22:10 23431 8192000000 10290806960 10767020032 1384298960 14.455 100 3 6 1 1402420208 17650800 470448
15:23:33 30396 8192000000 10285246048 10743545856 1387938016 14.487 100 10 19 2 1399462816 9189968 2334832
15:25:04 89208 8192000000 10285385024 10746458112 1395264640 14.553 100 32 63 0 1407781552 5385520 7131392
15:27:38 68482 8192000000 10286535664 10758856704 1398369088 14.580 100 18 36 2 1412172928 9690096 4113744
15:29:48 42581 8192000000 10287225232 10745561088 1385318080 14.464 99 15 30 0 1398474960 9868432 3288448
15:31:33 85787 8192000000 10284995424 10763087872 1382170720 14.436 99 13 26 0 1398239632 13020208 3048704
15:34:11 45004 8192000000 10294079968 10735403008 1359515824 14.233 97 24 48 0 1373312064 8621744 5174496
15:35:59 66261 8192000000 10290841504 10729930752 1347479872 14.125 97 29 59 0 1372273712 17395760 7398080
15:38:12 72547 8192000000 10269658960 10721533952 1300031904 13.696 94 23 47 1 1321510352 15991920 5486528
15:40:27 60809 8192000000 10289026640 10736050176 1329828016 13.966 96 20 40 0 1342150832 8216944 4105872
15:42:28 64104 8192000000 10262906848 10716753920 1263165088 13.359 92 9 18 1 1283517600 17930320 2422192
15:44:34 16770 8192000000 10283972272 10720575488 1351464176 14.161 99 51 101 2 1373453264 10747600 11241488
15:45:51 51858 8192000000 10263062512 10710118400 1287221712 13.579 94 25 50 0 1307840864 14636432 5982720
15:47:43 128874 8192000000 10259888208 10705244160 1315485808 13.836 97 14 28 0 1333264368 14656400 3122160
15:51:05 42720 8192000000 10258147776 10724425728 1359941760 14.237 100 7 14 1 1379449968 18058880 1449328
15:52:48 64167 8192000000 10247211488 10704273408 1328933008 13.958 97 9 18 0 1342821696 11082752 2805936
15:54:53 85595 8192000000 10258577168 10711773184 1342516768 14.080 99 20 40 1 1358727056 11422544 4787744
15:57:26 47862 8192000000 10265480480 10716942336 1355387536 14.196 100 10 20 1 1371846528 14325232 2133760
15:59:17 35285 8192000000 10287210256 10735063040 1366843152 14.299 100 40 79 1 1387165088 11191440 9130496
16:01:10 126997 8192000000 10278673056 10746359808 1321506384 13.890 96 15 30 2 1349576944 24700992 3369568
16:04:25 26811 8192000000 10294232304 10736242688 1372997312 14.354 100 38 76 0 1391836400 10226128 8612960
16:05:58 66227 8192000000 10294961456 10749734912 1366787728 14.298 99 13 26 0 1387657328 17993968 2875632
16:08:05 48970 8192000000 10315618944 10756022272 1359253376 14.231 98 30 60 1 1375384800 10017920 6113504
16:10:02 91285 8192000000 10314768512 10762219520 1400210272 14.597 100 32 64 1 1417279936 9882320 7187344
16:12:35 81923 8192000000 10300646800 10765946880 1390677136 14.512 99 10 20 2 1405000144 11544096 2778912
16:14:57 73043 8192000000 10309533200 10772668416 1390809824 14.513 99 18 36 1 1406369872 11087536 4472512
16:17:18 91083 8192000000 10324578160 10780377088 1387342272 14.482 98 16 32 0 1403807504 12765408 3699824
16:20:00 45035 8192000000 10308749504 10765606912 1379645904 14.413 99 12 24 0 1395339632 12200144 3493584
16:21:46 23135 8192000000 10305985376 10759303168 1389501216 14.501 99 35 71 2 1405770192 8221008 8047968
16:23:12 63403 8192000000 10305172048 10748813312 1363148176 14.266 98 21 42 1 1379083184 11335296 4599712
16:25:24 93083 8192000000 10274480240 10722676736 1284317040 13.552 92 17 34 0 1299239136 10857600 4064496
16:27:58 93584 8192000000 10276084352 10719928320 1301686144 13.711 94 13 27 1 1315871680 11369072 2816464
16:30:31 156476 8192000000 10273261712 10722131968 1326405616 13.935 97 31 61 2 1342090512 8830960 6853936
16:34:42 67972 8192000000 10251188784 10704465920 1312267120 13.807 95 8 17 0 1323588144 9520880 1800144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2021 16:40:31 to 20-12-2021 16:40:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2021 16:40:47 to 20-12-2021 16:40:49.

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          238630
MON$OLDEST_ACTIVE               240138
MON$OLDEST_SNAPSHOT             240138
MON$NEXT_TRANSACTION            240138
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                       2033952
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:158: 
:159: mscs-test-161.budzdorov.ru    Mon Dec 20 16:38:02 2021
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Mon Dec 20 16:38:34 2021
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Mon Dec 20 16:38:34 2021
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:169: 
--- end of diff output ---

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

20.12.21 16:40:50. 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-12-20 16:40:52 No severe PSQL-related problems occured
20.12.21 16:40:54. End of report.