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

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-02 16:37:53
DTS_END                         2021-10-02 16:37:53.3050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50378
TRN_ID                          243171
ATT_ID                          720
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: 8934.43 25822 1072132
customer order: creation 174.03 343 20884
customer order: refuse 14.65 342 1758
order to supplier: creation 45.31 1274 5437
order to supplier: removal 3.37 99061 404
invoice (draft): creation 46.21 3487 5545
invoice (draft): removal 49.83 842 5980
invoice accept: apply 41.93 17253 5032
invoice accept: cancel 44.88 838 5385
customer reserve: creation 3848.83 172 461859
customer reserve: removal 4369.89 59 524387
realization accept: apply 83.14 174 9977
realization accept: cancel 80.43 106 9652
payment from customer: creation 60.08 116 7210
payment from customer: removal 5.67 93 680
payment to supplier: creation 50.04 128 6005
payment to supplier: removal 3.50 76 420
service: total inventory turnovers 4.97 965 596
service: total monetary turnovers 6.89 23 827
service: aggregate perf. data 0.76 22056 91
service: refresh index statistics 0.03 369029 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2021 16:39:18 to 02-10-2021 16:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1721
TEST_TIME 2 2704
TEST_TIME 3 3505
TEST_TIME 4 3555
TEST_TIME 5 3732
TEST_TIME 6 3936
TEST_TIME 7 4081
TEST_TIME 8 4378
TEST_TIME 9 4781
TEST_TIME 10 5285
TEST_TIME 11 5512
TEST_TIME 12 5632
TEST_TIME 13 5876
TEST_TIME 14 6307
TEST_TIME 15 6436
TEST_TIME 16 6819
TEST_TIME 17 7219
TEST_TIME 18 7417
TEST_TIME 19 7586
TEST_TIME 20 7576
TEST_TIME 21 7751
TEST_TIME 22 8033
TEST_TIME 23 8505
TEST_TIME 24 8749
TEST_TIME 25 8747
TEST_TIME 26 9129
TEST_TIME 27 9363
TEST_TIME 28 9906
TEST_TIME 29 10191
TEST_TIME 30 10254
TEST_TIME 31 10117
TEST_TIME 32 9985
TEST_TIME 33 9803
TEST_TIME 34 9794
TEST_TIME 35 9910
TEST_TIME 36 9735
TEST_TIME 37 9622
TEST_TIME 38 9572
TEST_TIME 39 9651
TEST_TIME 40 9613
TEST_TIME 41 9536
TEST_TIME 42 9432
TEST_TIME 43 9309
TEST_TIME 44 9253
TEST_TIME 45 9156
TEST_TIME 46 9206
TEST_TIME 47 9273
TEST_TIME 48 9248
TEST_TIME 49 9207
TEST_TIME 50 9165
TEST_TIME 51 9203
TEST_TIME 52 9250
TEST_TIME 53 9189
TEST_TIME 54 9280
TEST_TIME 55 9228
TEST_TIME 56 9272
TEST_TIME 57 9311
TEST_TIME 58 9363
TEST_TIME 59 9338
TEST_TIME 60 9309
TEST_TIME 61 9322
TEST_TIME 62 9352
TEST_TIME 63 9318
TEST_TIME 64 9467
TEST_TIME 65 9530
TEST_TIME 66 9402
TEST_TIME 67 9312
TEST_TIME 68 9241
TEST_TIME 69 9231
TEST_TIME 70 9164
TEST_TIME 71 9141
TEST_TIME 72 9150
TEST_TIME 73 9151
TEST_TIME 74 9165
TEST_TIME 75 9116
TEST_TIME 76 9161
TEST_TIME 77 9181
TEST_TIME 78 9198
TEST_TIME 79 9137
TEST_TIME 80 9212
TEST_TIME 81 9243
TEST_TIME 82 9199
TEST_TIME 83 9143
TEST_TIME 84 9370
TEST_TIME 85 9322
TEST_TIME 86 9229
TEST_TIME 87 9222
TEST_TIME 88 9213
TEST_TIME 89 9170
TEST_TIME 90 9160
TEST_TIME 91 9117
TEST_TIME 92 9087
TEST_TIME 93 9020
TEST_TIME 94 9034
TEST_TIME 95 9017
TEST_TIME 96 9037
TEST_TIME 97 9063
TEST_TIME 98 9098
TEST_TIME 99 9130
TEST_TIME 100 9158
TEST_TIME 101 9146
TEST_TIME 102 9153
TEST_TIME 103 9255
TEST_TIME 104 9252
TEST_TIME 105 9229
TEST_TIME 106 9205
TEST_TIME 107 9222
TEST_TIME 108 9231
TEST_TIME 109 9159
TEST_TIME 110 9161
TEST_TIME 111 9108
TEST_TIME 112 9092
TEST_TIME 113 9056
TEST_TIME 114 9013
TEST_TIME 115 9000
TEST_TIME 116 8959
TEST_TIME 117 8963
TEST_TIME 118 8962
TEST_TIME 119 8939
TEST_TIME 120 8939

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2021 16:39:22 to 02-10-2021 16:39: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 1500867 1500867 0 0.00 0 17003 6 0 0
doc_list_biud 1500865 1500865 0 0.00 0 74112 27 0 0
sp_add_invoice_to_stock 5120 5032 88 1.71 41 249885 17254 0 88
sp_cancel_adding_invoice 5385 5385 0 0.00 5 74482 838 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 12388 343 0 0
sp_cancel_customer_reserve 524387 524387 0 0.00 1 74115 60 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 6157 93 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4997 76 0 0
sp_cancel_supplier_invoice 5990 5980 10 0.16 1 13870 843 0 10
sp_cancel_supplier_order 404 404 0 0.00 6 996352 99062 0 0
sp_cancel_write_off 9652 9652 0 0.00 1 7663 107 0 0
sp_client_order 20884 20884 0 0.00 4 20885 343 0 0
sp_customer_reserve 461859 461859 0 0.00 0 83342 172 0 0
sp_fill_shopping_cart 31864 31864 0 0.00 1 20317 45 0 0
sp_fill_shopping_cart_clo_res 461848 461848 0 0.00 0 16184 13 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 46105 336 0 0
sp_kill_cost_storno 16137 16137 0 0.00 0 7660 56 0 0
sp_kill_qstorno_ret_qs2qd 1064810 1064810 0 0.00 0 74106 33 0 0
sp_kill_qty_storno 562563 562563 0 0.00 0 74111 71 0 0
sp_lock_selected_doc 20825 20825 0 0.00 0 5262 4 0 0
sp_make_cost_storno 28212 28212 0 0.00 0 16998 55 0 0
sp_multiply_rows_for_pdistr 28212 28212 0 0.00 0 9264 28 0 0
sp_multiply_rows_for_qdistr 20884 20884 0 0.00 0 11242 162 0 0
sp_pay_from_customer 7210 7210 0 0.00 4 13509 116 0 0
sp_pay_to_supplier 6005 6005 0 0.00 5 17052 129 0 0
sp_payment_common 13215 13215 0 0.00 1 11329 29 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 12382 309 0 0
sp_qd_handle_on_invoice_upd_sts 10405 10405 0 0.00 0 23329 342 0 0
sp_qd_handle_on_reserve_upd_sts 9977 9977 0 0.00 0 11728 56 0 0
sp_reserve_write_off 9977 9977 0 0.00 6 13070 175 0 0
sp_supplier_invoice 5545 5545 0 0.00 5 84065 3488 0 0
sp_supplier_order 5437 5437 0 0.00 5 43310 1275 0 0
srv_aggregate_perf_data 91 91 0 0.00 3015 146081 22056 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1323 202826 52137 0 0
srv_find_qd_qs_mism 1024562 1024562 0 0.00 0 9265 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 34 30818 965 0 0
srv_make_money_saldo 827 827 0 0.00 1 732 23 0 0
srv_recalc_idx_stat 3 3 0 0.00 316673 454294 369030 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5032 5032 0 0.00 0 22097 210 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 2858 107 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 7498 17 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1119 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 4534 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2448 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1014 9 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 24691 1682 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 19577 40 0 0
t$perf-norm:sp_client_order 20884 20884 0 0.00 0 15557 20 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 2147 5 0 0
t$perf-norm:sp_pay_from_customer 7210 7210 0 0.00 0 15788 13 0 0
t$perf-norm:sp_pay_to_supplier 6005 6005 0 0.00 0 8256 15 0 0
t$perf-norm:sp_reserve_write_off 9977 9977 0 0.00 0 9649 14 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 6912 19 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 8043 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 641 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 88 2 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2645 27 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 727 12 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10216 10040 176 1.72 0 8473 21 0 176
v_all_customers 37806 37806 0 0.00 0 61 1 0 0
v_all_suppliers 30784 30784 0 0.00 0 16 1 0 0
v_all_wares 41768 41768 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 3601 12 0 0
v_cancel_client_order 3516 3516 0 0.00 0 3965 11 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 4453 7 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 293 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4934 18 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 1331 16 0 20
v_cancel_supplier_order 808 808 0 0.00 0 373 4 0 0
v_cancel_write_off 1990 1990 0 0.00 0 3208 15 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 897 1 0 0
v_max_id_clo_res 973 973 0 0.00 0 8198 28 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 5096 5 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 315 2 0 0
v_max_non_paid_realizn 3905 3905 0 0.00 0 3672 4 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 5802 8 0 0
v_min_id_clo_res 973 973 0 0.00 0 2860 11 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 3976 3 0 0
v_min_non_paid_invoice 6005 6005 0 0.00 0 7439 15 0 0
v_min_non_paid_realizn 7210 7210 0 0.00 0 8453 19 0 0
v_our_firm 10572 10572 0 0.00 0 152 1 0 0
v_reserve_write_off 19952 19952 0 0.00 0 5133 6 0 0
x_lock_depdocs_on_canc_invoice 10962 10962 0 0.00 0 9919 118 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 12806 326 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 1 43300 1180 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 2 83986 3327 0 0
x_make_qstorno_2100_3300 461858 461858 0 0.00 0 83322 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2021 16:39:26 to 02-10-2021 16:39: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:07:57 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:58 22024 8192000000 10371155680 10690822144 1410364880 14.687 100 83 166 0 1443045040 5586752 27093408
14:10:21 4829 8192000000 10427040080 10756366336 1486039248 15.354 100 90 180 0 1522663712 7492880 29131584
14:11:29 23303 8192000000 10471394096 10808164352 1528037040 15.720 100 89 178 1 1565369456 9030640 28301776
14:12:53 14590 8192000000 10552763856 10905358336 1596861552 16.313 100 74 147 2 1628940672 9392784 22686336
14:14:13 9209 8192000000 10616821152 10982932480 1678673536 17.006 100 73 146 0 1711535584 11569392 21292656
14:15:26 7013 8192000000 10691474304 11065733120 1742867616 17.542 100 80 160 2 1780067888 14641568 22558704
14:16:37 4805 8192000000 10746750496 11130437632 1801143632 18.023 100 77 154 1 1835765680 12277376 22344672
14:17:44 1864 8192000000 10796926800 11182850048 1861445024 18.515 100 83 167 1 1896878128 12357040 23076064
14:18:47 1840 8192000000 10832862880 11222126592 1885814720 18.712 100 93 185 2 1925351456 13756160 25780576
14:20:01 4911 8192000000 10807028336 11223355392 1891690656 18.759 100 44 87 1 1911917888 8178192 12049040
14:21:12 5891 8192000000 10804187600 11233013760 1897595296 18.807 100 29 58 0 1912892928 7250208 8047424
14:22:19 10691 8192000000 10815890256 11246026752 1904623968 18.863 100 25 50 1 1922680320 10978752 7077600
14:23:34 14881 8192000000 10831487984 11259576320 1906576336 18.879 100 27 53 2 1925323776 11638064 7109376
14:24:50 4336 8192000000 10841802928 11275456512 1919188288 18.980 100 43 86 3 1943236672 11676960 12371424
14:25:55 12659 8192000000 10807281392 11242397696 1874993152 18.625 98 20 39 1 1886789872 5838976 5957744
14:27:08 63091 8192000000 10762569408 11183230976 1791320048 17.943 94 28 56 5 1803297216 5096896 6880272
14:29:16 7490 8192000000 10764609328 11174072320 1809162576 18.089 98 45 89 1 1829570640 8016704 12391360
14:30:24 6425 8192000000 10723699248 11145912320 1788212960 17.917 98 24 48 0 1801170496 6038576 6918960
14:31:30 63989 8192000000 10752108512 11161853952 1817092640 18.154 100 37 74 2 1832999408 5467216 10439552
14:33:37 5754 8192000000 10751733296 11170066432 1835572080 18.305 100 34 68 0 1851894800 6715248 9607472
14:34:45 14223 8192000000 10771195872 11195781120 1851360320 18.433 100 17 35 1 1861261088 4761776 5138992
14:36:01 39654 8192000000 10776758096 11216871424 1876448992 18.636 100 26 52 1 1891489296 5812416 9227888
14:37:43 5424 8192000000 10823618720 11250696192 1909603440 18.903 100 32 64 3 1924807136 6047664 9156032
14:38:57 32724 8192000000 10863725376 11284877312 1927462272 19.047 100 41 82 0 1947206800 8454768 11289760
14:40:33 23000 8192000000 10874857248 11314475008 1947891552 19.210 100 32 64 1 1972781200 15551600 9338048
14:42:00 20520 8192000000 10891509072 11324416000 1957141680 19.283 100 45 90 1 1988346336 18296944 12907712
14:43:27 33465 8192000000 10893826640 11332825088 1961511936 19.318 100 27 53 3 1987284608 17858608 7914064
14:45:12 4753 8192000000 10923870016 11358212096 1981213616 19.474 100 49 98 0 2015808896 18798240 15797040
14:46:20 9271 8192000000 10905286400 11334115328 1950322864 19.229 99 61 120 1 1993212000 25149280 17739856
14:47:46 10084 8192000000 10950814576 11384451072 1998675712 19.612 100 58 116 1 2041844896 25276176 17893008
14:48:59 3073 8192000000 10942108464 11379576832 1987607696 19.525 100 55 111 1 2032141184 27446976 17086512
14:50:06 4848 8192000000 10970052976 11398447104 2004238144 19.656 100 66 132 0 2057992624 33452480 20302000
14:51:16 2825 8192000000 10955627440 11373887488 1976628112 19.438 100 74 147 0 2036946608 37861312 22457184
14:52:23 1968 8192000000 10940489760 11359064064 1968032064 19.370 100 75 150 0 2026956208 35087936 23836208
14:53:31 13585 8192000000 10954560960 11382652928 1978773440 19.455 100 62 124 2 2039444896 40684256 19987200
14:54:48 2714 8192000000 10921263008 11337228288 1925661696 19.032 98 83 164 2 1985308912 34405552 25241664
14:55:57 1321 8192000000 10965112512 11376594944 1978542240 19.453 100 82 161 0 2039131184 36007696 24581248
14:57:01 5498 8192000000 10960332528 11390119936 1987203520 19.522 100 60 119 1 2042648544 36929280 18515744
14:58:18 14715 8192000000 10940768960 11383435264 1955839120 19.273 99 43 86 1 2014050272 43301888 14909264
14:59:42 4058 8192000000 10943333552 11373240320 1962874384 19.329 100 54 106 5 2023900688 42380688 18645616
15:00:48 12098 8192000000 10930656768 11372544000 1961738688 19.320 100 40 80 2 2013918160 39840592 12338880
15:02:08 11852 8192000000 10893763520 11329064960 1904472416 18.862 98 33 66 0 1951275328 36134448 10668464
15:03:28 23205 8192000000 10911704208 11358896128 1949563216 19.223 100 25 50 1 1997592576 39483280 8546080
15:04:52 67656 8192000000 10888975040 11353141248 1894451744 18.782 97 14 28 0 1946317088 46525824 5339520
15:07:00 4343 8192000000 10897714704 11323064320 1942133520 19.164 100 57 114 0 1970976576 12558544 16284512
15:08:06 202826 8192000000 10909753632 11364528128 1931808192 19.081 98 15 30 0 1956174432 19975856 4390384
15:12:29 55056 8192000000 10886898320 11346108416 1929223360 19.061 98 11 23 0 1941219408 9201024 2795024
15:14:39 130048 8192000000 10926086240 11370242048 1971295488 19.396 99 38 73 2 1996939312 14127904 11515920
15:17:56 43164 8192000000 10899831984 11358846976 1960726192 19.312 99 20 40 2 1972947936 6575200 5646544
15:20:15 29120 8192000000 10919484288 11380727808 1992324576 19.562 100 30 60 0 2011845152 11279616 8240960
15:21:44 32391 8192000000 10897983392 11364802560 1964354032 19.341 99 22 43 1 1984802592 14027184 6421376
15:23:20 74197 8192000000 10906511568 11388444672 1959258864 19.300 98 21 42 0 1987293520 21320672 6713984
15:25:34 71506 8192000000 10880474560 11350462464 1958177504 19.292 99 17 35 1 1976254048 12768160 5308384
15:28:43 74578 8192000000 10910081216 11388829696 1976185472 19.434 99 13 25 1 1991619072 11773824 3659776
15:30:57 64007 8192000000 10905197504 11379355648 1973680192 19.415 99 32 64 0 1994343984 11358256 9305536
15:33:02 25423 8192000000 10901125008 11367886848 1961687344 19.319 99 31 62 0 1980985216 10846128 8451744
15:34:28 80753 8192000000 10887059664 11356737536 1843378496 18.368 93 22 44 1 1865712800 16122960 6211344
15:36:55 53682 8192000000 10910235696 11361480704 1917589472 18.968 97 33 66 1 1936736832 10399936 8747424
15:38:49 90112 8192000000 10897657040 11353509888 1839963488 18.341 93 15 28 0 1851693488 8174288 3555712
15:41:20 64452 8192000000 10874802624 11341156352 1843990720 18.373 94 13 26 1 1863813456 16402368 3420368
15:43:29 80071 8192000000 10855854992 11314876416 1877257200 18.643 96 23 46 1 1889859936 5161248 7441488
15:45:57 48597 8192000000 10853338064 11328458752 1934730448 19.105 100 6 12 0 1946610912 10016992 1863472
15:48:40 40052 8192000000 10872707216 11327844352 1893881232 18.777 97 28 54 2 1911128752 8600416 8647104
15:50:26 56701 8192000000 10851222528 11313774592 1880376112 18.668 97 15 30 2 1892208864 8104544 3728208
15:52:24 38698 8192000000 10878420160 11328548864 1923504976 19.015 99 39 78 0 1944404464 9793600 11105888
15:54:11 118598 8192000000 10888681360 11354423296 1929347984 19.062 99 21 42 1 1944027152 9419760 5259408
15:57:18 85890 8192000000 10874556896 11342385152 1895197936 18.788 97 21 42 0 1916047248 15172080 5677232
15:59:44 65361 8192000000 10918319328 11380297728 1972626880 19.406 100 11 22 1 1996346496 20422064 3297552
16:01:57 153063 8192000000 10904539440 11368755200 1951028448 19.235 99 14 28 0 1967798336 12986496 3783392
16:05:42 39387 8192000000 10903389248 11373682688 1953901008 19.258 99 29 58 0 1975666624 13888288 7877328
16:07:28 80710 8192000000 10898390208 11369463808 1959646816 19.303 99 11 22 0 1972078240 9578128 2853296
16:09:49 71431 8192000000 10866680064 11338039296 1900185696 18.828 96 16 32 1 1917436272 12347008 4903568
16:12:04 63671 8192000000 10887298320 11363155968 1946388288 19.198 99 12 24 1 1961848048 12608992 2850768
16:14:18 61631 8192000000 10910195648 11381800960 1979515920 19.461 100 14 28 1 1997964928 14790640 3658368
16:16:20 88969 8192000000 10910679536 11375304704 1873511824 18.613 94 16 32 0 1888043312 9823360 4708128
16:19:18 163533 8192000000 10915796320 11412017152 1927096128 19.044 97 13 26 1 1955200736 24495504 3609104
16:23:01 58151 8192000000 10901808176 11351625728 1870410784 18.588 94 37 74 0 1893531216 12782960 10337472
16:25:02 123607 8192000000 10873913776 11337674752 1912699600 18.928 98 23 46 0 1934385632 15239872 6446160
16:28:17 33319 8192000000 10877568688 11336871936 1944660848 19.184 100 25 48 1 1959398336 8038368 6699120
16:29:51 77067 8192000000 10866132256 11343396864 1950093120 19.227 100 15 30 0 1961801600 7551168 4157312
16:32:59 86600 8192000000 10875137456 11352940544 1940924240 19.154 99 16 32 1 1958306752 12512944 4869568
16:35:26 71678 8192000000 10864192864 11327827968 1850173152 18.424 95 10 20 0 1864962288 12378880 2410256
16:37:38 22466 8192000000 9037013008 9253941248 208151568 2.477 11 6 10 1 214197872 4541632 1504672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-10-2021 16:39:32 to 02-10-2021 16:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2021 16:39:47 to 02-10-2021 16:39: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          241683
MON$OLDEST_ACTIVE               243513
MON$OLDEST_SNAPSHOT             243513
MON$NEXT_TRANSACTION            243513
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                       2019520
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:74: 
:75: mscs-test-161.budzdorov.ru    Sat Oct  2 16:38:09 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Sat Oct  2 16:38:10 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
--- end of diff output ---

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

02.10.21 16:39: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-10-02 16:39:53 No severe PSQL-related problems occured
02.10.21 16:39:54. End of report.