Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.09.2021 22:38
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 15811700 kB
MemAvailable 30804492 kB
Buffers 0 kB
Cached 15852208 kB
SwapCached 416 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-09-16 22:37:50
DTS_END                         2021-09-16 22:37:50.1580
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/58418
TRN_ID                          246264
ATT_ID                          624
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: 9784.94 18400 1174193
customer order: creation 180.62 327 21674
customer order: refuse 14.84 389 1781
order to supplier: creation 48.49 1231 5819
order to supplier: removal 3.45 92095 414
invoice (draft): creation 48.06 3261 5767
invoice (draft): removal 55.73 704 6687
invoice accept: apply 44.42 15777 5330
invoice accept: cancel 50.76 716 6091
customer reserve: creation 4077.63 156 489315
customer reserve: removal 4945.33 50 593439
realization accept: apply 87.76 172 10531
realization accept: cancel 91.68 100 11002
payment from customer: creation 61.98 106 7437
payment from customer: removal 5.83 73 700
payment to supplier: creation 51.95 108 6234
payment to supplier: removal 3.90 62 468
service: total inventory turnovers 4.99 1076 599
service: total monetary turnovers 6.60 55 792
service: aggregate perf. data 0.91 17546 109
service: refresh index statistics 0.03 233986 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-09-2021 22:39:05 to 16-09-2021 22:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5493
TEST_TIME 2 4138
TEST_TIME 3 4810
TEST_TIME 4 5633
TEST_TIME 5 6156
TEST_TIME 6 5856
TEST_TIME 7 5713
TEST_TIME 8 5542
TEST_TIME 9 5924
TEST_TIME 10 6214
TEST_TIME 11 6402
TEST_TIME 12 6921
TEST_TIME 13 7472
TEST_TIME 14 7686
TEST_TIME 15 7873
TEST_TIME 16 8089
TEST_TIME 17 8062
TEST_TIME 18 8168
TEST_TIME 19 8096
TEST_TIME 20 8657
TEST_TIME 21 9178
TEST_TIME 22 9420
TEST_TIME 23 9324
TEST_TIME 24 9571
TEST_TIME 25 9693
TEST_TIME 26 10248
TEST_TIME 27 10106
TEST_TIME 28 10442
TEST_TIME 29 10821
TEST_TIME 30 10993
TEST_TIME 31 10782
TEST_TIME 32 10650
TEST_TIME 33 10938
TEST_TIME 34 10893
TEST_TIME 35 10889
TEST_TIME 36 10808
TEST_TIME 37 10708
TEST_TIME 38 10710
TEST_TIME 39 10705
TEST_TIME 40 10728
TEST_TIME 41 10669
TEST_TIME 42 10564
TEST_TIME 43 10342
TEST_TIME 44 10413
TEST_TIME 45 10339
TEST_TIME 46 10340
TEST_TIME 47 10251
TEST_TIME 48 10242
TEST_TIME 49 10161
TEST_TIME 50 10075
TEST_TIME 51 10226
TEST_TIME 52 10219
TEST_TIME 53 10147
TEST_TIME 54 10086
TEST_TIME 55 10016
TEST_TIME 56 9884
TEST_TIME 57 9845
TEST_TIME 58 9893
TEST_TIME 59 9930
TEST_TIME 60 9863
TEST_TIME 61 9835
TEST_TIME 62 9755
TEST_TIME 63 9727
TEST_TIME 64 9800
TEST_TIME 65 9830
TEST_TIME 66 9905
TEST_TIME 67 9873
TEST_TIME 68 9893
TEST_TIME 69 9969
TEST_TIME 70 9950
TEST_TIME 71 9997
TEST_TIME 72 10085
TEST_TIME 73 10106
TEST_TIME 74 10133
TEST_TIME 75 10238
TEST_TIME 76 10207
TEST_TIME 77 10177
TEST_TIME 78 10080
TEST_TIME 79 10053
TEST_TIME 80 9990
TEST_TIME 81 9995
TEST_TIME 82 10047
TEST_TIME 83 10010
TEST_TIME 84 9977
TEST_TIME 85 9913
TEST_TIME 86 9883
TEST_TIME 87 9893
TEST_TIME 88 9876
TEST_TIME 89 9890
TEST_TIME 90 9903
TEST_TIME 91 9895
TEST_TIME 92 9890
TEST_TIME 93 9880
TEST_TIME 94 9945
TEST_TIME 95 9912
TEST_TIME 96 9935
TEST_TIME 97 9973
TEST_TIME 98 10004
TEST_TIME 99 9994
TEST_TIME 100 9999
TEST_TIME 101 9977
TEST_TIME 102 10051
TEST_TIME 103 10100
TEST_TIME 104 10024
TEST_TIME 105 9967
TEST_TIME 106 9903
TEST_TIME 107 9910
TEST_TIME 108 9919
TEST_TIME 109 9833
TEST_TIME 110 9850
TEST_TIME 111 9813
TEST_TIME 112 9787
TEST_TIME 113 9746
TEST_TIME 114 9697
TEST_TIME 115 9712
TEST_TIME 116 9699
TEST_TIME 117 9699
TEST_TIME 118 9795
TEST_TIME 119 9808
TEST_TIME 120 9790

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-09-2021 22:39:09 to 16-09-2021 22:39:12.

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 1626089 1626089 0 0.00 0 22000 5 0 0
doc_list_biud 1626089 1626089 0 0.00 0 19954 24 0 0
sp_add_invoice_to_stock 5424 5330 94 1.73 32 156055 15777 0 94
sp_cancel_adding_invoice 6091 6091 0 0.00 3 54862 716 0 0
sp_cancel_client_order 1781 1781 0 0.00 3 9088 390 0 0
sp_cancel_customer_reserve 593439 593439 0 0.00 1 19955 50 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 4291 74 0 0
sp_cancel_pay_to_supplier 468 468 0 0.00 3 2755 63 0 0
sp_cancel_supplier_invoice 6695 6687 8 0.11 1 17016 705 0 8
sp_cancel_supplier_order 414 414 0 0.00 8 1168535 92095 0 0
sp_cancel_write_off 11002 11002 0 0.00 1 16669 101 0 0
sp_client_order 21674 21674 0 0.00 3 20542 327 0 0
sp_customer_reserve 489315 489315 0 0.00 0 54466 157 0 0
sp_fill_shopping_cart 33252 33252 0 0.00 1 12309 36 0 0
sp_fill_shopping_cart_clo_res 489303 489303 0 0.00 0 11336 12 0 0
sp_get_clo_for_invoice 5318 5318 0 0.00 0 21605 244 0 0
sp_kill_cost_storno 18261 18261 0 0.00 0 21969 48 0 0
sp_kill_qstorno_ret_qs2qd 1204981 1204981 0 0.00 0 19951 27 0 0
sp_kill_qty_storno 635263 635263 0 0.00 0 19954 60 0 0
sp_lock_selected_doc 21877 21877 0 0.00 0 7572 6 0 0
sp_make_cost_storno 29520 29520 0 0.00 0 9786 44 0 0
sp_multiply_rows_for_pdistr 29520 29520 0 0.00 0 6505 28 0 0
sp_multiply_rows_for_qdistr 21674 21674 0 0.00 0 12805 154 0 0
sp_pay_from_customer 7437 7437 0 0.00 4 11286 107 0 0
sp_pay_to_supplier 6234 6234 0 0.00 5 12053 109 0 0
sp_payment_common 13671 13671 0 0.00 1 12038 23 0 0
sp_qd_handle_on_cancel_clo 1781 1781 0 0.00 0 8987 347 0 0
sp_qd_handle_on_invoice_upd_sts 11409 11409 0 0.00 0 17334 304 0 0
sp_qd_handle_on_reserve_upd_sts 10531 10531 0 0.00 0 9391 62 0 0
sp_reserve_write_off 10531 10531 0 0.00 6 9824 172 0 0
sp_supplier_invoice 5767 5767 0 0.00 7 104506 3262 0 0
sp_supplier_order 5819 5819 0 0.00 5 22611 1232 0 0
srv_aggregate_perf_data 109 109 0 0.00 2508 103161 17547 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2226 93291 43485 0 0
srv_find_qd_qs_mism 1123261 1123261 0 0.00 0 16086 4 0 0
srv_make_invnt_saldo 599 599 0 0.00 28 47584 1076 0 0
srv_make_money_saldo 792 792 0 0.00 0 12532 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 104289 389438 233986 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5330 5330 0 0.00 0 9230 149 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 6056 98 0 0
t$perf-norm:sp_cancel_client_order 1781 1781 0 0.00 0 2379 16 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 640 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 444 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 468 468 0 0.00 0 669 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 3390 23 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 33077 1518 0 0
t$perf-norm:sp_cancel_write_off 1085 1085 0 0.00 0 2689 11 0 0
t$perf-norm:sp_client_order 21674 21674 0 0.00 0 9298 17 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 632 4 0 0
t$perf-norm:sp_pay_from_customer 7437 7437 0 0.00 0 23507 16 0 0
t$perf-norm:sp_pay_to_supplier 6234 6234 0 0.00 0 3372 7 0 0
t$perf-norm:sp_reserve_write_off 10531 10531 0 0.00 0 4530 11 0 0
t$perf-norm:sp_supplier_invoice 5767 5767 0 0.00 0 3951 14 0 0
t$perf-norm:sp_supplier_order 5819 5819 0 0.00 0 22943 21 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 128 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 117 4 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 2404 19 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 750 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10824 10636 188 1.73 0 6182 20 0 188
v_all_customers 39732 39732 0 0.00 0 104 1 0 0
v_all_suppliers 31802 31802 0 0.00 0 36 1 0 0
v_all_wares 43348 43348 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 3569 13 0 0
v_cancel_client_order 3562 3562 0 0.00 0 6756 14 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 997 3 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 3315 5 0 0
v_cancel_payment_to_supplier 936 936 0 0.00 0 2397 9 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 1945 18 0 16
v_cancel_supplier_order 828 828 0 0.00 0 1935 8 0 0
v_cancel_write_off 2170 2170 0 0.00 0 3020 17 0 0
v_max_id_clo_ord 5819 5819 0 0.00 0 1666 2 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 3396 17 0 0
v_max_id_ord_sup 5759 5759 0 0.00 0 4147 3 0 0
v_max_non_paid_invoice 1911 1911 0 0.00 0 331 3 0 0
v_max_non_paid_realizn 3915 3915 0 0.00 0 610 3 0 0
v_min_id_clo_ord 5819 5819 0 0.00 0 2319 4 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 5376 11 0 0
v_min_id_ord_sup 5759 5759 0 0.00 0 9071 17 0 0
v_min_non_paid_invoice 6234 6234 0 0.00 0 12034 15 0 0
v_min_non_paid_realizn 7437 7437 0 0.00 0 7477 14 0 0
v_our_firm 10660 10660 0 0.00 0 21 1 0 0
v_reserve_write_off 21062 21062 0 0.00 0 4278 7 0 0
x_lock_depdocs_on_canc_invoice 12368 12368 0 0.00 0 16342 102 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 6974 342 0 0
x_make_qstorno_1000_1200 5819 5819 0 0.00 2 22606 1154 0 0
x_make_qstorno_1200_2000 5767 5767 0 0.00 4 104398 3130 0 0
x_make_qstorno_2100_3300 489315 489315 0 0.00 0 54464 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-09-2021 22:39:13 to 16-09-2021 22:39:17.

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:54 3 8192000000 8800342288 8811544576 6595984 0.080 2 1 2 0 6715360 56816 62560
20:08:55 34372 8192000000 10386632400 10707427328 1428598656 14.849 100 90 180 0 1464233808 6494784 29140368
20:10:36 16285 8192000000 10439711856 10770833408 1488972640 15.380 100 88 176 0 1526271808 8853776 28445392
20:11:58 26442 8192000000 10497947824 10842841088 1553677856 15.942 100 85 170 1 1590627312 10205712 26743744
20:13:29 17681 8192000000 10563730720 10919489536 1611877248 16.441 100 74 148 1 1645822528 11370576 22574704
20:14:51 5444 8192000000 10635184992 11004280832 1692646944 17.124 100 72 143 0 1724227520 11172896 20407680
20:16:00 4642 8192000000 10704404544 11081453568 1759486752 17.680 100 79 159 1 1795343408 13678864 22177792
20:17:07 2228 8192000000 10760684176 11138535424 1813036512 18.121 100 85 170 0 1850180880 12873824 24270544
20:18:11 1676 8192000000 10800907200 11187179520 1856753648 18.477 100 93 186 2 1896923520 14463312 25706560
20:19:14 12289 8192000000 10778654944 11199631360 1865190320 18.545 100 50 100 1 1889266736 9797344 14279072
20:20:27 4330 8192000000 10819790368 11221614592 1883382512 18.692 100 49 98 0 1908197104 11223280 13591312
20:21:34 9036 8192000000 10794554064 11221098496 1883558752 18.694 100 17 34 0 1893842720 5478304 4805664
20:22:43 62897 8192000000 10794370560 11232595968 1888553456 18.734 100 26 52 0 1901352560 5465456 7333648
20:24:48 10655 8192000000 10804522576 11241418752 1882066816 18.682 99 21 42 0 1893998640 5885552 6046272
20:26:01 5085 8192000000 10821337424 11258019840 1908132624 18.892 100 28 55 4 1921983616 5757168 8093824
20:27:07 10221 8192000000 10806574064 11242188800 1895688480 18.792 100 21 43 1 1908247712 6349680 6209552
20:28:21 25591 8192000000 10781991584 11213328384 1808319840 18.082 96 18 35 1 1818380304 5549792 4510672
20:29:50 7774 8192000000 10715138768 11136348160 1768348304 17.753 97 27 54 0 1781689008 5766976 7573728
20:30:58 9537 8192000000 10670756112 11082944512 1685921184 17.067 94 28 56 0 1699753920 5519056 8313680
20:32:09 10720 8192000000 10701752320 11127169024 1795259728 17.975 100 18 36 0 1806380640 6297760 4823152
20:33:22 17906 8192000000 10740726080 11163979776 1822210848 18.196 100 22 43 2 1837986112 9759952 6015312
20:34:40 3736 8192000000 10774140208 11185524736 1847470528 18.402 100 39 78 0 1869252624 10098672 11683424
20:35:47 10069 8192000000 10785046736 11209658368 1869026416 18.576 100 26 53 0 1881982048 5182496 7773136
20:36:59 13018 8192000000 10800553360 11230842880 1887085392 18.722 100 19 38 2 1897743744 5401280 5257072
20:38:15 25367 8192000000 10844165552 11263049728 1910288816 18.909 100 36 67 1 1925043360 4876544 9878000
20:39:49 11177 8192000000 10875847456 11298902016 1939236736 19.141 100 58 116 1 1968163632 12296448 16630448
20:41:07 20579 8192000000 10891246928 11321081856 1953086192 19.251 100 46 92 0 1981629872 14971728 13571952
20:42:37 21349 8192000000 10896718976 11330793472 1959530912 19.302 100 49 98 0 1988929840 15270448 14128480
20:44:03 17595 8192000000 10916230832 11355549696 1979885840 19.464 100 42 84 2 2011455824 18971520 12598464
20:45:23 15861 8192000000 10939636976 11378999296 1995263408 19.585 100 55 110 1 2036697808 24538464 16895936
20:46:57 5959 8192000000 10963034096 11396272128 1991090144 19.552 99 58 115 1 2035169552 25689920 18389488
20:48:08 2226 8192000000 10986027328 11408109568 2019297152 19.775 100 81 160 1 2071849664 28890496 23662016
20:49:14 2734 8192000000 10971346384 11391287296 1996308048 19.594 100 69 136 3 2051149104 33593264 21247792
20:50:21 9321 8192000000 10968019232 11383369728 1992316656 19.562 100 76 149 0 2045860608 31873760 21670192
20:51:35 4065 8192000000 10952105024 11377397760 1974716144 19.423 99 67 134 2 2028847056 33828496 20302416
20:52:42 8153 8192000000 10912559424 11336024064 1946788816 19.201 100 70 140 2 2002214992 34091952 21334224
20:53:59 24066 8192000000 10902857376 11337936896 1911060720 18.915 98 44 86 1 1955866816 31897648 12908448
20:55:29 16558 8192000000 10903296528 11341549568 1931279008 19.077 100 31 62 3 1982098128 40531056 10288064
20:56:51 25672 8192000000 10887138016 11330052096 1877150192 18.642 97 30 60 1 1938567520 50840416 10576912
20:58:25 16623 8192000000 10882581552 11332317184 1915628000 18.952 99 43 86 1 1976934112 46699376 14606736
20:59:45 36875 8192000000 10898111168 11338412032 1900639808 18.831 98 27 54 1 1947405936 38077328 8688800
21:01:29 21941 8192000000 10923824672 11369086976 1941216704 19.156 99 28 56 1 1993808400 43140064 9451632
21:03:01 8521 8192000000 10951129184 11396046848 1977220624 19.443 100 35 69 0 2029528144 40691184 11616336
21:04:43 27436 8192000000 10921343040 11375505408 1971645024 19.398 100 41 80 0 2015126432 29814768 13666640
21:06:16 36530 8192000000 10885147776 11358871552 1939824112 19.145 99 15 30 0 1970087824 25070064 5193648
21:07:53 60321 8192000000 10904274784 11349094400 1914180224 18.940 97 27 54 3 1934283312 13255168 6847920
21:10:13 89334 8192000000 10923469504 11395653632 1986438448 19.516 100 13 26 0 2011369856 21382784 3548624
21:12:43 89273 8192000000 10905359744 11361030144 1923346080 19.014 97 6 12 0 1933907488 9528480 1032928
21:15:14 70241 8192000000 10908042464 11374346240 1945323760 19.189 98 14 29 0 1960864688 11923152 3617776
21:17:25 86060 8192000000 10904252416 11355713536 1892523344 18.766 96 20 40 0 1911995328 14154208 5317776
21:20:02 93291 8192000000 10907904384 11371757568 1982770352 19.487 100 38 76 3 2000908528 6539264 11598912
21:22:36 11722 8192000000 10943149584 11385614336 1997989504 19.607 100 54 104 1 2021412400 8125296 15297600
21:23:49 19264 8192000000 10925852464 11392925696 1999081200 19.615 100 27 54 0 2015002112 8438480 7482432
21:25:14 20289 8192000000 10936977296 11398201344 2003026992 19.647 99 43 85 0 2026676432 12028352 11621088
21:26:38 31735 8192000000 10952612752 11403538432 1996202864 19.593 99 34 68 1 2019737168 13041136 10493168
21:28:20 29534 8192000000 10897422736 11382140928 1933875952 19.098 97 12 24 2 1954747792 16903648 3968192
21:29:52 24270 8192000000 10902887184 11369316352 1967145296 19.363 99 23 46 1 1979406800 5903392 6358112
21:31:58 36147 8192000000 10927917744 11389423616 1993817360 19.574 100 24 45 1 2010018576 9993472 6207744
21:33:34 74838 8192000000 10905421168 11387523072 1954536112 19.263 98 20 40 0 1979865872 18729184 6600576
21:36:05 19202 8192000000 10892283824 11372748800 1957933696 19.290 99 25 51 1 1981298608 16051504 7313408
21:37:25 74199 8192000000 10859163328 11332526080 1938783312 19.137 99 21 42 0 1955726272 11285616 5657344
21:39:50 51666 8192000000 10858762224 11338711040 1892239440 18.764 97 3 6 0 1908924000 16154128 530432
21:41:42 28698 8192000000 10865807264 11335512064 1887697504 18.727 96 15 30 1 1901148496 8788736 4662256
21:43:13 44246 8192000000 10855281584 11327520768 1896293088 18.796 98 17 34 1 1911857520 10567952 4996480
21:45:02 52334 8192000000 10873984064 11339943936 1905012688 18.867 98 26 52 1 1924849296 12670880 7165728
21:46:57 68359 8192000000 10867382080 11335962624 1864334784 18.538 95 16 32 1 1880545136 11790608 4419744
21:49:08 68142 8192000000 10884887280 11357515776 1927978864 19.051 99 22 44 1 1948522064 14222672 6320528
21:51:19 59425 8192000000 10885957392 11348090880 1914238512 18.941 98 24 47 2 1937925632 16848688 6838432
21:53:46 25327 8192000000 10904041040 11352371200 1935540912 19.111 99 27 54 1 1952703152 9057312 8104928
21:55:17 75636 8192000000 10895069024 11351719936 1922228576 19.005 98 35 70 1 1939649344 7396400 10024368
21:57:34 45880 8192000000 10894754128 11370872832 1951552448 19.239 99 33 66 2 1977329424 16132688 9644288
21:59:49 88140 8192000000 10873294464 11351199744 1937835376 19.129 99 19 38 1 1953437936 9906800 5695760
22:02:18 76623 8192000000 10890788160 11378520064 1971826608 19.400 100 13 26 1 1987258640 11382928 4049104
22:04:35 66373 8192000000 10905709888 11384877056 1983819216 19.495 100 15 30 1 2000232192 12454384 3958592
22:06:42 82367 8192000000 10906509424 11387600896 1935705024 19.112 97 15 29 1 1954569936 14549680 4315232
22:09:10 30365 8192000000 10926627968 11399692288 2004977968 19.662 100 31 62 2 2024462176 10581072 8903136
22:10:41 60377 8192000000 10914150320 11396034560 1956507664 19.278 98 17 34 1 1976397440 14964592 4925184
22:12:48 32730 8192000000 10937746288 11398729728 1986182464 19.514 100 29 58 0 2004351872 9389216 8780192
22:14:33 89196 8192000000 10928549200 11408056320 1974145680 19.418 99 15 30 0 1998708704 20104944 4458080
22:17:02 40534 8192000000 10887576240 11351392256 1903214768 18.852 96 23 47 1 1918189712 8957424 6017520
22:18:52 66408 8192000000 10904919040 11388633088 1941422640 19.158 98 9 18 0 1958550736 14600816 2527280
22:21:19 90968 8192000000 10852835808 11324366848 1900043248 18.827 97 18 36 0 1919151008 13741936 5365824
22:24:14 36921 8192000000 10873024976 11344834560 1940736512 19.153 100 30 60 0 1958869216 9424720 8707984
22:25:53 33268 8192000000 10856854144 11350708224 1932050992 19.083 100 4 8 0 1951374704 18219536 1104176
22:27:27 49890 8192000000 10853573728 11328688128 1918781424 18.977 99 8 16 0 1931876048 11084576 2010048
22:29:21 91869 8192000000 10873298048 11344748544 1941139936 19.156 100 13 25 0 1955987760 11074448 3773376
22:31:55 74290 8192000000 10877164768 11364376576 1911505104 18.919 98 9 18 0 1929699792 15261536 2933152
22:34:10 59106 8192000000 10897739600 11381280768 1966520000 19.358 100 16 32 2 1990522464 19272064 4730400
22:36:15 49044 8192000000 10887694608 11357720576 1928524448 19.055 98 18 36 1 1947674944 13937760 5212736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-09-2021 22:39:19 to 16-09-2021 22:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-09-2021 22:39:35 to 16-09-2021 22:39:37.

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          245301
MON$OLDEST_ACTIVE               246931
MON$OLDEST_SNAPSHOT             246931
MON$NEXT_TRANSACTION            246931
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                       2060768
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.33497 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33497 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33497 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33497 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Sep 16 22:38:00 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Sep 16 22:38:00 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Sep 16 22:38:01 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

16.09.21 22:39:38. 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-09-16 22:39:40 No severe PSQL-related problems occured
16.09.21 22:39:42. End of report.