Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.11.2021 22: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 16852920 kB
MemAvailable 31579824 kB
Buffers 0 kB
Cached 14815928 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-11-18 22:37:54
DTS_END                         2021-11-18 22:37:54.9750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40622
TRN_ID                          238138
ATT_ID                          646
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: 9213.42 23007 1105610
customer order: creation 166.65 335 19998
customer order: refuse 13.85 306 1662
order to supplier: creation 45.00 1134 5400
order to supplier: removal 3.25 89188 390
invoice (draft): creation 44.70 3336 5364
invoice (draft): removal 53.83 709 6460
invoice accept: apply 41.12 16405 4934
invoice accept: cancel 48.89 677 5867
customer reserve: creation 3751.80 165 450216
customer reserve: removal 4749.52 47 569943
realization accept: apply 79.79 163 9575
realization accept: cancel 88.72 88 10646
payment from customer: creation 57.04 92 6845
payment from customer: removal 5.73 64 688
payment to supplier: creation 48.31 113 5797
payment to supplier: removal 3.23 51 387
service: total inventory turnovers 4.78 948 573
service: total monetary turnovers 6.38 29 765
service: aggregate perf. data 0.80 21621 96
service: refresh index statistics 0.03 324663 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-11-2021 22:39:21 to 18-11-2021 22:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1576
TEST_TIME 2 2426
TEST_TIME 3 2807
TEST_TIME 4 3324
TEST_TIME 5 3755
TEST_TIME 6 4430
TEST_TIME 7 4796
TEST_TIME 8 5113
TEST_TIME 9 5465
TEST_TIME 10 5521
TEST_TIME 11 5501
TEST_TIME 12 5541
TEST_TIME 13 5730
TEST_TIME 14 5943
TEST_TIME 15 6278
TEST_TIME 16 7303
TEST_TIME 17 7481
TEST_TIME 18 7757
TEST_TIME 19 8644
TEST_TIME 20 9696
TEST_TIME 21 9971
TEST_TIME 22 10041
TEST_TIME 23 10239
TEST_TIME 24 10138
TEST_TIME 25 10519
TEST_TIME 26 10362
TEST_TIME 27 10397
TEST_TIME 28 10450
TEST_TIME 29 10431
TEST_TIME 30 10223
TEST_TIME 31 10090
TEST_TIME 32 9925
TEST_TIME 33 9777
TEST_TIME 34 9537
TEST_TIME 35 9465
TEST_TIME 36 9420
TEST_TIME 37 9278
TEST_TIME 38 9363
TEST_TIME 39 9290
TEST_TIME 40 9183
TEST_TIME 41 9052
TEST_TIME 42 8923
TEST_TIME 43 8895
TEST_TIME 44 8989
TEST_TIME 45 8989
TEST_TIME 46 8994
TEST_TIME 47 9156
TEST_TIME 48 9116
TEST_TIME 49 9087
TEST_TIME 50 9165
TEST_TIME 51 9250
TEST_TIME 52 9202
TEST_TIME 53 9161
TEST_TIME 54 9277
TEST_TIME 55 9340
TEST_TIME 56 9287
TEST_TIME 57 9135
TEST_TIME 58 9103
TEST_TIME 59 9049
TEST_TIME 60 8928
TEST_TIME 61 8892
TEST_TIME 62 8821
TEST_TIME 63 8887
TEST_TIME 64 8929
TEST_TIME 65 8910
TEST_TIME 66 9076
TEST_TIME 67 9070
TEST_TIME 68 9017
TEST_TIME 69 9084
TEST_TIME 70 9084
TEST_TIME 71 9126
TEST_TIME 72 9106
TEST_TIME 73 9122
TEST_TIME 74 9171
TEST_TIME 75 9194
TEST_TIME 76 9219
TEST_TIME 77 9218
TEST_TIME 78 9179
TEST_TIME 79 9071
TEST_TIME 80 9114
TEST_TIME 81 9086
TEST_TIME 82 9013
TEST_TIME 83 8943
TEST_TIME 84 8910
TEST_TIME 85 9006
TEST_TIME 86 9007
TEST_TIME 87 9024
TEST_TIME 88 8987
TEST_TIME 89 8968
TEST_TIME 90 8954
TEST_TIME 91 9001
TEST_TIME 92 9090
TEST_TIME 93 9067
TEST_TIME 94 9110
TEST_TIME 95 9213
TEST_TIME 96 9194
TEST_TIME 97 9188
TEST_TIME 98 9286
TEST_TIME 99 9277
TEST_TIME 100 9216
TEST_TIME 101 9184
TEST_TIME 102 9193
TEST_TIME 103 9162
TEST_TIME 104 9191
TEST_TIME 105 9219
TEST_TIME 106 9206
TEST_TIME 107 9157
TEST_TIME 108 9170
TEST_TIME 109 9197
TEST_TIME 110 9192
TEST_TIME 111 9185
TEST_TIME 112 9309
TEST_TIME 113 9290
TEST_TIME 114 9283
TEST_TIME 115 9297
TEST_TIME 116 9256
TEST_TIME 117 9181
TEST_TIME 118 9221
TEST_TIME 119 9223
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-11-2021 22:39:25 to 18-11-2021 22:39:28.

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 1522267 1522267 0 0.00 0 18766 5 0 0
doc_list_biud 1522266 1522266 0 0.00 0 58412 23 0 0
sp_add_invoice_to_stock 5072 4934 138 2.72 44 209355 16406 0 138
sp_cancel_adding_invoice 5867 5867 0 0.00 5 70795 677 0 0
sp_cancel_client_order 1662 1662 0 0.00 4 8729 306 0 0
sp_cancel_customer_reserve 569943 569943 0 0.00 1 58414 47 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 5798 65 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 1438 52 0 0
sp_cancel_supplier_invoice 6467 6460 7 0.10 1 14735 710 0 7
sp_cancel_supplier_order 390 390 0 0.00 7 1082069 89189 0 0
sp_cancel_write_off 10646 10646 0 0.00 1 11714 88 0 0
sp_client_order 19998 19998 0 0.00 4 23880 335 0 0
sp_customer_reserve 450216 450216 0 0.00 0 78823 166 0 0
sp_fill_shopping_cart 30756 30756 0 0.00 1 19453 39 0 0
sp_fill_shopping_cart_clo_res 450211 450211 0 0.00 0 23896 12 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 28887 324 0 0
sp_kill_cost_storno 17588 17588 0 0.00 0 11081 43 0 0
sp_kill_qstorno_ret_qs2qd 1157382 1157382 0 0.00 0 58405 26 0 0
sp_kill_qty_storno 609471 609471 0 0.00 0 58412 57 0 0
sp_lock_selected_doc 20061 20061 0 0.00 0 7345 6 0 0
sp_make_cost_storno 27145 27145 0 0.00 0 10220 45 0 0
sp_multiply_rows_for_pdistr 27145 27145 0 0.00 0 7125 24 0 0
sp_multiply_rows_for_qdistr 19998 19998 0 0.00 0 23871 140 0 0
sp_pay_from_customer 6845 6845 0 0.00 4 12671 93 0 0
sp_pay_to_supplier 5797 5797 0 0.00 5 13749 113 0 0
sp_payment_common 12642 12642 0 0.00 1 12642 24 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 8659 277 0 0
sp_qd_handle_on_invoice_upd_sts 10795 10795 0 0.00 0 18770 287 0 0
sp_qd_handle_on_reserve_upd_sts 9575 9575 0 0.00 0 16223 59 0 0
sp_reserve_write_off 9575 9575 0 0.00 6 16408 164 0 0
sp_supplier_invoice 5364 5364 0 0.00 6 87259 3337 0 0
sp_supplier_order 5400 5400 0 0.00 5 34171 1135 0 0
srv_aggregate_perf_data 96 96 0 0.00 2635 314127 21622 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 2097 132952 58501 0 0
srv_find_qd_qs_mism 1059013 1059013 0 0.00 0 18764 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 44 26064 948 0 0
srv_make_money_saldo 765 765 0 0.00 0 6184 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 135258 483245 324664 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4936 4936 0 0.00 0 22587 138 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5094 109 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 3306 12 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 3304 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 2137 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 3394 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 6956 34 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 27729 1423 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 2451 8 0 0
t$perf-norm:sp_client_order 20002 20002 0 0.00 0 13766 14 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 589 3 0 0
t$perf-norm:sp_pay_from_customer 6845 6845 0 0.00 0 13718 10 0 0
t$perf-norm:sp_pay_to_supplier 5797 5797 0 0.00 0 4839 9 0 0
t$perf-norm:sp_reserve_write_off 9575 9575 0 0.00 0 10361 12 0 0
t$perf-norm:sp_supplier_invoice 5364 5364 0 0.00 0 6700 15 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 14860 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 259 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 182 4 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 1010 15 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 925 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 49 14 0 0
v_add_invoice_to_stock 10130 9854 276 2.72 0 9590 18 0 276
v_all_customers 36602 36602 0 0.00 0 14 0 0 0
v_all_suppliers 29978 29978 0 0.00 0 17 0 0 0
v_all_wares 39996 39996 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 232 5 0 0
v_cancel_client_order 3324 3324 0 0.00 0 2643 5 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 1027 4 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1373 4 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 1391 6 0 0
v_cancel_supplier_invoice 774 760 14 1.80 0 2222 18 0 14
v_cancel_supplier_order 780 780 0 0.00 0 4763 13 0 0
v_cancel_write_off 1946 1946 0 0.00 0 5839 15 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 299 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 2125 6 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 5825 6 0 0
v_max_non_paid_invoice 1565 1565 0 0.00 0 7701 8 0 0
v_max_non_paid_realizn 3623 3623 0 0.00 0 5509 6 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 3136 5 0 0
v_min_id_clo_res 934 934 0 0.00 0 3822 12 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 15822 14 0 0
v_min_non_paid_invoice 5797 5797 0 0.00 0 12639 17 0 0
v_min_non_paid_realizn 6845 6845 0 0.00 0 8971 15 0 0
v_our_firm 9838 9838 0 0.00 0 18 1 0 0
v_reserve_write_off 19150 19150 0 0.00 0 3006 5 0 0
x_lock_depdocs_on_canc_invoice 11947 11947 0 0.00 0 20763 100 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 11994 327 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 1 34161 1068 0 0
x_make_qstorno_1200_2000 5364 5364 0 0.00 2 87232 3188 0 0
x_make_qstorno_2100_3300 450216 450216 0 0.00 0 78800 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-11-2021 22:39:29 to 18-11-2021 22:39:34.

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
20:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:59 15313 8192000000 9883869808 10191220736 964510496 10.533 100 85 172 0 993410416 5700384 23199536
20:10:31 21254 8192000000 9944879920 10264768512 1021966688 11.091 100 86 171 1 1051848320 7702752 22178880
20:11:57 9742 8192000000 9960864256 10289901568 1047557184 11.337 100 85 170 0 1078586112 9663632 21365296
20:13:16 10817 8192000000 10036193664 10384052224 1123519360 12.060 100 80 160 0 1153240208 10850208 18870640
20:14:29 8186 8192000000 10091812096 10454056960 1177586128 12.568 100 81 162 0 1208226176 11739808 18900240
20:15:40 6070 8192000000 10135459744 10502553600 1220317184 12.965 100 89 178 1 1253906848 13670448 19919216
20:16:49 3039 8192000000 10162013984 10535710720 1247487328 13.215 100 91 182 3 1282115072 14760080 19867664
20:17:54 2340 8192000000 10194977536 10572607488 1277973824 13.495 100 92 184 2 1312263760 13540752 20749184
20:18:58 2497 8192000000 10212946928 10597486592 1298914016 13.685 100 92 182 1 1333130640 12806448 21410176
20:20:02 5973 8192000000 10212931616 10607931392 1307388784 13.762 100 55 111 1 1327370960 7268464 12713712
20:21:10 8803 8192000000 10208026496 10615341056 1313628704 13.819 100 40 80 1 1332298448 9844352 8825392
20:22:19 8159 8192000000 10199775408 10620424192 1320863712 13.885 100 22 44 1 1331452960 5514416 5074832
20:23:30 12564 8192000000 10219704672 10640261120 1330852544 13.975 100 10 20 0 1338184544 5167344 2164656
20:24:43 17449 8192000000 10222232144 10644131840 1336305248 14.024 100 16 32 0 1345423408 5185376 3932784
20:26:04 14609 8192000000 10256541424 10666164224 1343456672 14.089 100 59 118 1 1363788848 5835280 14496896
20:27:19 5681 8192000000 10223453456 10640584704 1319870448 13.876 99 33 66 1 1334845088 7557136 7417504
20:28:28 7967 8192000000 10184851360 10599452672 1279844832 13.512 97 13 26 0 1287704928 4749504 3110592
20:29:36 52283 8192000000 10168503088 10566508544 1210223440 12.871 94 23 46 1 1221323488 5822432 5277616
20:31:29 7431 8192000000 10135312480 10534449152 1166826416 12.467 92 22 44 1 1176393120 4869264 4697440
20:32:37 4202 8192000000 10160675632 10557169664 1245788144 13.200 99 47 93 0 1266890256 10315952 10786160
20:33:43 8589 8192000000 10159353200 10566004736 1261048160 13.340 99 18 36 0 1270768368 5385568 4334640
20:34:55 33169 8192000000 10167464960 10578403328 1269725552 13.419 99 20 40 0 1279513536 5328416 4459568
20:36:28 5275 8192000000 10185307104 10599690240 1294783024 13.648 100 33 67 3 1309239264 7067888 7388352
20:37:36 12158 8192000000 10203681600 10627739648 1319113392 13.869 100 12 24 1 1326296912 4779440 2404080
20:38:58 15914 8192000000 10244603296 10656407552 1336090480 14.022 100 41 79 2 1353444336 8260096 9093760
20:40:23 102676 8192000000 10262934144 10687832064 1357912240 14.219 100 42 82 1 1383323344 15024800 10386304
20:43:11 26015 8192000000 10283355328 10706665472 1368266240 14.312 100 32 64 1 1390702560 15129008 7307312
20:44:42 9250 8192000000 10305123104 10724241408 1383218448 14.445 100 60 119 2 1419341056 22459776 13662832
20:45:57 10492 8192000000 10317165696 10741522432 1383009024 14.443 99 47 94 1 1416757536 22643328 11105184
20:47:11 12724 8192000000 10345819856 10768203776 1410413488 14.688 100 57 111 0 1452634128 28993008 13227632
20:48:28 6496 8192000000 10359725152 10779779072 1419529248 14.769 100 60 120 0 1466165312 32013808 14622256
20:49:42 6548 8192000000 10373200416 10787868672 1422187760 14.792 100 76 152 2 1479999616 39170304 18641552
20:50:52 2096 8192000000 10380967696 10792132608 1419572368 14.769 100 89 178 2 1481607760 39524944 22510448
20:52:00 2366 8192000000 10373380496 10784796672 1413250224 14.713 100 85 170 1 1477585088 42629408 21705456
20:53:04 4162 8192000000 10354252400 10765111296 1380950160 14.425 99 72 143 1 1449265072 50226832 18088080
20:54:13 13766 8192000000 10322888176 10746114048 1369583920 14.323 100 42 84 1 1426172688 44464832 12123936
20:55:43 2767 8192000000 10332493200 10739912704 1352833312 14.173 98 78 157 3 1424292192 51057120 20401760
20:56:47 26684 8192000000 10279699088 10726801408 1335761184 14.019 99 22 45 0 1389044800 46850976 6432640
20:58:16 14010 8192000000 10272850800 10702364672 1324473584 13.917 99 30 60 0 1361402288 29212688 7716016
20:59:31 27533 8192000000 10270454480 10705321984 1340009056 14.057 99 17 34 0 1365991280 21453792 4528432
21:01:12 57651 8192000000 10286068896 10727321600 1350944832 14.156 99 15 30 0 1383800464 28924032 3931600
21:03:11 22827 8192000000 10275574160 10708959232 1340684784 14.064 98 36 73 0 1364466624 14892544 8889296
21:04:47 78908 8192000000 10266236688 10711158784 1345127552 14.104 99 21 42 1 1363431440 13470608 4833280
21:07:10 129845 8192000000 10263614256 10708729856 1322642400 13.901 97 10 20 0 1333568384 9254832 1671152
21:10:20 113347 8192000000 10286347712 10727002112 1367663184 14.306 99 26 52 1 1380914752 7452368 5799200
21:13:22 75411 8192000000 10280111120 10728660992 1368250496 14.311 99 12 24 0 1380699248 9438624 3010128
21:15:50 56567 8192000000 10264113456 10721386496 1365218800 14.284 99 15 31 0 1379175536 10581216 3375520
21:17:47 59172 8192000000 10284504032 10737717248 1388087856 14.489 100 14 26 3 1399450192 8040016 3322320
21:19:53 73348 8192000000 10287032080 10757046272 1380140368 14.418 99 10 20 1 1395234976 12713200 2381408
21:22:07 74605 8192000000 10303767568 10758467584 1392312416 14.526 99 22 44 0 1407453424 9739376 5401632
21:24:24 92120 8192000000 10313848656 10762936320 1383623536 14.449 99 26 52 1 1402954400 13557136 5773728
21:27:01 110273 8192000000 10303398160 10762678272 1382231792 14.436 98 13 25 1 1398690240 13510896 2947552
21:29:59 20344 8192000000 10323906784 10762203136 1396746160 14.566 100 47 94 1 1417502896 10304128 10452608
21:31:24 101597 8192000000 10306240304 10760302592 1366615840 14.297 98 10 20 0 1384717296 16182448 1919008
21:34:07 57401 8192000000 10295080608 10744942592 1378192720 14.400 99 38 76 0 1394549792 7517792 8839280
21:36:09 47077 8192000000 10279862304 10738405376 1375363136 14.375 99 14 28 0 1388816416 10317872 3135408
21:37:58 97243 8192000000 10301763808 10764857344 1362334128 14.258 98 11 22 0 1375231120 10365872 2531120
21:40:36 94977 8192000000 10291846336 10755616768 1333752976 14.001 95 18 36 0 1355622912 17896832 3973104
21:43:16 112631 8192000000 10278026192 10729119744 1256534144 13.298 91 12 24 1 1277035616 17716960 2784512
21:46:11 38066 8192000000 10289903744 10736365568 1362181808 14.257 99 30 60 1 1382633216 13558768 6892640
21:47:54 95387 8192000000 10259856352 10700238848 1291182240 13.615 95 34 67 1 1311076704 12562544 7331920
21:50:38 66980 8192000000 10253779024 10701058048 1288858336 13.594 95 14 28 0 1304738544 12728656 3151552
21:52:45 50398 8192000000 10257362016 10699358208 1317564928 13.855 97 10 20 0 1331957904 12338256 2054720
21:54:38 132952 8192000000 10252049568 10691809280 1311991168 13.804 97 26 52 2 1341133200 22861008 6281024
21:58:11 46325 8192000000 10235417456 10690174976 1320202896 13.879 98 16 32 1 1332825680 9222976 3399808
22:00:01 100103 8192000000 10276317040 10721312768 1361430208 14.250 100 12 22 1 1381695472 17288896 2976368
22:03:42 115892 8192000000 10272527392 10724421632 1294818608 13.648 95 14 28 0 1306777328 9262592 2696128
22:06:38 37500 8192000000 10281208608 10727264256 1353559360 14.179 99 29 58 1 1377306336 17494704 6252272
22:08:26 53446 8192000000 10305490736 10751766528 1385977552 14.470 100 16 32 0 1406463568 16863936 3622080
22:10:23 30358 8192000000 10304885888 10746105856 1378250896 14.401 99 45 91 1 1395085680 6766320 10068464
22:11:54 64153 8192000000 10308629360 10778992640 1391555216 14.520 100 21 41 0 1416132800 20051632 4525952
22:14:09 55307 8192000000 10327456880 10775973888 1396993296 14.568 100 20 40 0 1413580368 12201856 4385216
22:16:05 99563 8192000000 10322534048 10804088832 1404366160 14.634 100 3 6 0 1429528064 24657136 504768
22:18:50 100845 8192000000 10314359536 10775445504 1375995328 14.381 98 9 18 0 1387099392 9249312 1854752
22:21:37 22009 8192000000 10298307360 10752565248 1390060640 14.506 99 30 60 1 1410358768 13361088 6937040
22:22:59 111197 8192000000 10320676880 10761117696 1362047440 14.256 97 21 41 0 1377861312 11408928 4404944
22:25:53 61584 8192000000 10292864624 10754183168 1358168736 14.221 97 28 56 1 1384320496 20080048 6071712
22:27:55 58050 8192000000 10291306224 10743078912 1348700512 14.136 97 15 30 3 1379416304 27031296 3684496
22:29:55 47752 8192000000 10274518240 10721538048 1308007168 13.768 95 21 42 0 1328211968 15936304 4268496
22:31:47 25528 8192000000 10296380224 10739240960 1367035424 14.300 99 33 66 0 1384731872 10905920 6790528
22:33:15 120515 8192000000 10251338336 10694336512 1289625792 13.601 94 21 41 1 1311342512 17084304 4632416
22:36:22 100163 8192000000 9785498528 10161262592 872420384 9.624 64 11 21 0 888316496 13633504 2262608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-11-2021 22:39:35 to 18-11-2021 22:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2021 22:39:51 to 18-11-2021 22:39:53.

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          237038
MON$OLDEST_ACTIVE               238333
MON$OLDEST_SNAPSHOT             238333
MON$NEXT_TRANSACTION            238333
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                       2028192
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.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Thu Nov 18 22:38:04 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
--- end of diff output ---

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

18.11.21 22:39:54. 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-11-18 22:39:56 No severe PSQL-related problems occured
18.11.21 22:39:58. End of report.