Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.11.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15881948 kB
MemAvailable 30855252 kB
Buffers 0 kB
Cached 15837040 kB
SwapCached 4112 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-01 19:37:54
DTS_END                         2021-11-01 19:37:54.2040
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/41158
TRN_ID                          232701
ATT_ID                          634
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: 9325.35 21877 1119042
customer order: creation 158.39 369 19007
customer order: refuse 13.79 352 1655
order to supplier: creation 42.43 1302 5092
order to supplier: removal 3.38 121088 405
invoice (draft): creation 43.14 3530 5177
invoice (draft): removal 56.81 817 6817
invoice accept: apply 39.16 18442 4699
invoice accept: cancel 51.33 743 6159
customer reserve: creation 3586.67 184 430400
customer reserve: removal 5039.31 63 604717
realization accept: apply 78.29 176 9395
realization accept: cancel 92.07 118 11048
payment from customer: creation 55.45 118 6654
payment from customer: removal 5.08 68 609
payment to supplier: creation 45.89 123 5507
payment to supplier: removal 3.04 57 365
service: total inventory turnovers 4.34 1283 521
service: total monetary turnovers 6.00 34 720
service: aggregate perf. data 0.76 19799 91
service: refresh index statistics 0.03 268879 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2021 19:39:57 to 01-11-2021 19:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 724
TEST_TIME 2 1334
TEST_TIME 3 2274
TEST_TIME 4 3078
TEST_TIME 5 3525
TEST_TIME 6 3692
TEST_TIME 7 3858
TEST_TIME 8 4196
TEST_TIME 9 4606
TEST_TIME 10 4749
TEST_TIME 11 5162
TEST_TIME 12 5358
TEST_TIME 13 5681
TEST_TIME 14 5857
TEST_TIME 15 5947
TEST_TIME 16 5988
TEST_TIME 17 6069
TEST_TIME 18 6359
TEST_TIME 19 6697
TEST_TIME 20 6954
TEST_TIME 21 7163
TEST_TIME 22 7486
TEST_TIME 23 7422
TEST_TIME 24 8221
TEST_TIME 25 8441
TEST_TIME 26 8964
TEST_TIME 27 9524
TEST_TIME 28 10534
TEST_TIME 29 10436
TEST_TIME 30 10353
TEST_TIME 31 10368
TEST_TIME 32 10266
TEST_TIME 33 10438
TEST_TIME 34 10388
TEST_TIME 35 10230
TEST_TIME 36 10078
TEST_TIME 37 9987
TEST_TIME 38 9986
TEST_TIME 39 9835
TEST_TIME 40 9745
TEST_TIME 41 9895
TEST_TIME 42 9821
TEST_TIME 43 9723
TEST_TIME 44 9803
TEST_TIME 45 9719
TEST_TIME 46 9667
TEST_TIME 47 9518
TEST_TIME 48 9400
TEST_TIME 49 9443
TEST_TIME 50 9427
TEST_TIME 51 9534
TEST_TIME 52 9794
TEST_TIME 53 9716
TEST_TIME 54 9834
TEST_TIME 55 9766
TEST_TIME 56 9797
TEST_TIME 57 9829
TEST_TIME 58 9838
TEST_TIME 59 9802
TEST_TIME 60 9828
TEST_TIME 61 9952
TEST_TIME 62 9906
TEST_TIME 63 9926
TEST_TIME 64 10035
TEST_TIME 65 9942
TEST_TIME 66 9861
TEST_TIME 67 9770
TEST_TIME 68 9799
TEST_TIME 69 9721
TEST_TIME 70 9676
TEST_TIME 71 9626
TEST_TIME 72 9598
TEST_TIME 73 9565
TEST_TIME 74 9496
TEST_TIME 75 9475
TEST_TIME 76 9455
TEST_TIME 77 9415
TEST_TIME 78 9386
TEST_TIME 79 9323
TEST_TIME 80 9334
TEST_TIME 81 9357
TEST_TIME 82 9300
TEST_TIME 83 9269
TEST_TIME 84 9297
TEST_TIME 85 9417
TEST_TIME 86 9411
TEST_TIME 87 9398
TEST_TIME 88 9397
TEST_TIME 89 9475
TEST_TIME 90 9516
TEST_TIME 91 9543
TEST_TIME 92 9584
TEST_TIME 93 9572
TEST_TIME 94 9484
TEST_TIME 95 9489
TEST_TIME 96 9442
TEST_TIME 97 9376
TEST_TIME 98 9321
TEST_TIME 99 9270
TEST_TIME 100 9334
TEST_TIME 101 9395
TEST_TIME 102 9420
TEST_TIME 103 9418
TEST_TIME 104 9447
TEST_TIME 105 9426
TEST_TIME 106 9447
TEST_TIME 107 9435
TEST_TIME 108 9436
TEST_TIME 109 9455
TEST_TIME 110 9498
TEST_TIME 111 9523
TEST_TIME 112 9568
TEST_TIME 113 9490
TEST_TIME 114 9437
TEST_TIME 115 9400
TEST_TIME 116 9382
TEST_TIME 117 9377
TEST_TIME 118 9331
TEST_TIME 119 9323
TEST_TIME 120 9329

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-11-2021 19:40:01 to 01-11-2021 19:40:04.

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 1519906 1519906 0 0.00 0 30361 6 0 0
doc_list_biud 1519906 1519906 0 0.00 0 40571 32 0 0
sp_add_invoice_to_stock 4857 4699 158 3.25 23 232021 18443 0 158
sp_cancel_adding_invoice 6159 6159 0 0.00 4 93307 743 0 0
sp_cancel_client_order 1655 1655 0 0.00 4 10103 353 0 0
sp_cancel_customer_reserve 604717 604717 0 0.00 1 31803 64 0 0
sp_cancel_pay_from_customer 609 609 0 0.00 3 3397 69 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 3219 57 0 0
sp_cancel_supplier_invoice 6830 6817 13 0.19 1 32004 817 0 13
sp_cancel_supplier_order 405 405 0 0.00 10 936002 121088 0 0
sp_cancel_write_off 11048 11048 0 0.00 1 40655 118 0 0
sp_client_order 19007 19007 0 0.00 3 30983 369 0 0
sp_customer_reserve 430400 430400 0 0.00 0 55865 185 0 0
sp_fill_shopping_cart 29271 29271 0 0.00 1 14446 39 0 0
sp_fill_shopping_cart_clo_res 430399 430399 0 0.00 0 10264 14 0 0
sp_get_clo_for_invoice 4696 4696 0 0.00 0 18541 323 0 0
sp_kill_cost_storno 18181 18181 0 0.00 0 16686 58 0 0
sp_kill_qstorno_ret_qs2qd 1227704 1227704 0 0.00 0 40569 34 0 0
sp_kill_qty_storno 644892 644892 0 0.00 0 40569 74 0 0
sp_lock_selected_doc 19442 19442 0 0.00 0 10569 5 0 0
sp_make_cost_storno 26252 26252 0 0.00 0 11737 47 0 0
sp_multiply_rows_for_pdistr 26252 26252 0 0.00 0 9120 27 0 0
sp_multiply_rows_for_qdistr 19007 19007 0 0.00 0 21102 147 0 0
sp_pay_from_customer 6654 6654 0 0.00 4 22217 118 0 0
sp_pay_to_supplier 5507 5507 0 0.00 5 26092 123 0 0
sp_payment_common 12161 12161 0 0.00 1 7153 20 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 10097 330 0 0
sp_qd_handle_on_invoice_upd_sts 10855 10855 0 0.00 0 28028 339 0 0
sp_qd_handle_on_reserve_upd_sts 9395 9395 0 0.00 0 10081 57 0 0
sp_reserve_write_off 9395 9395 0 0.00 6 19313 176 0 0
sp_supplier_invoice 5177 5177 0 0.00 7 84806 3531 0 0
sp_supplier_order 5092 5092 0 0.00 4 66433 1302 0 0
srv_aggregate_perf_data 91 91 0 0.00 3038 154233 19799 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1663 199216 50717 0 0
srv_find_qd_qs_mism 1075834 1075834 0 0.00 0 30361 5 0 0
srv_make_invnt_saldo 521 521 0 0.00 40 60750 1283 0 0
srv_make_money_saldo 720 720 0 0.00 1 6595 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 189804 378550 268880 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4699 4699 0 0.00 0 10854 175 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 4987 104 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 11614 19 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 381 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 609 609 0 0.00 0 2932 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 4575 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 533 10 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 64891 1829 0 0
t$perf-norm:sp_cancel_write_off 921 921 0 0.00 0 2658 9 0 0
t$perf-norm:sp_client_order 19007 19007 0 0.00 0 18815 22 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 2811 9 0 0
t$perf-norm:sp_pay_from_customer 6654 6654 0 0.00 0 17441 17 0 0
t$perf-norm:sp_pay_to_supplier 5507 5507 0 0.00 0 8787 14 0 0
t$perf-norm:sp_reserve_write_off 9395 9395 0 0.00 0 14883 15 0 0
t$perf-norm:sp_supplier_invoice 5177 5177 0 0.00 0 3626 17 0 0
t$perf-norm:sp_supplier_order 5092 5092 0 0.00 0 11037 24 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1581 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 963 18 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 723 19 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 714 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9708 9392 316 3.25 0 18838 22 0 316
v_all_customers 35228 35228 0 0.00 0 111 1 0 0
v_all_suppliers 28696 28696 0 0.00 0 19 1 0 0
v_all_wares 38014 38014 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 3767 10 0 0
v_cancel_client_order 3310 3310 0 0.00 0 2300 6 0 0
v_cancel_customer_prepayment 1218 1218 0 0.00 0 2236 6 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1963 5 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 142 2 0 0
v_cancel_supplier_invoice 760 734 26 3.42 0 1276 14 0 26
v_cancel_supplier_order 810 810 0 0.00 0 1848 7 0 0
v_cancel_write_off 1842 1842 0 0.00 0 1565 14 0 0
v_max_id_clo_ord 5091 5091 0 0.00 0 320 1 0 0
v_max_id_clo_res 968 968 0 0.00 0 1751 7 0 0
v_max_id_ord_sup 5171 5171 0 0.00 0 4875 4 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 267 2 0 0
v_max_non_paid_realizn 3368 3368 0 0.00 0 1065 3 0 0
v_min_id_clo_ord 5091 5091 0 0.00 0 1502 6 0 0
v_min_id_clo_res 968 968 0 0.00 0 17390 22 0 0
v_min_id_ord_sup 5171 5171 0 0.00 0 7080 6 0 0
v_min_non_paid_invoice 5507 5507 0 0.00 0 4314 13 0 0
v_min_non_paid_realizn 6654 6654 0 0.00 0 6692 17 0 0
v_our_firm 9358 9358 0 0.00 0 23 1 0 0
v_reserve_write_off 18790 18790 0 0.00 0 19190 8 0 0
x_lock_depdocs_on_canc_invoice 12609 12609 0 0.00 0 16916 120 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 5523 365 0 0
x_make_qstorno_1000_1200 5092 5092 0 0.00 1 66423 1225 0 0
x_make_qstorno_1200_2000 5177 5177 0 0.00 3 84103 3394 0 0
x_make_qstorno_2100_3300 430400 430400 0 0.00 0 55864 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2021 19:40:05 to 01-11-2021 19:40:09.

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
17:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:58 36455 8192000000 9912507376 10227924992 989818112 10.780 100 86 172 0 1018747872 6248016 22681744
17:10:38 17781 8192000000 9960460048 10286096384 1043013040 11.294 100 90 180 0 1075027152 8978640 23035472
17:12:00 12378 8192000000 9979276336 10314379264 1063819840 11.493 100 81 162 0 1093792800 10096496 19876464
17:13:23 8800 8192000000 10042139904 10392641536 1126449808 12.088 100 83 166 1 1156870832 11018000 19403024
17:14:34 10451 8192000000 10091724496 10455252992 1178683392 12.578 100 71 141 1 1206422880 11534176 16205312
17:15:50 4962 8192000000 10139125136 10509496320 1228899008 13.044 100 85 168 2 1260978224 13432816 18646400
17:16:58 6063 8192000000 10168270528 10545336320 1247052704 13.211 100 81 160 0 1277921440 13395472 17473264
17:18:06 1691 8192000000 10200759168 10583953408 1281182416 13.524 100 88 176 1 1313409072 12371408 19855248
17:19:09 1986 8192000000 10219570816 10603433984 1297486192 13.672 100 95 191 3 1332653888 12421472 22746224
17:20:11 4710 8192000000 10205554080 10614861824 1313351808 13.816 100 51 101 1 1334658784 9712688 11594288
17:21:18 4742 8192000000 10222366192 10627162112 1315884256 13.839 100 29 57 1 1328772976 6839872 6048848
17:22:24 5828 8192000000 10217063136 10632089600 1326064656 13.932 100 29 58 2 1340012128 7746896 6200576
17:23:32 70583 8192000000 10233391744 10648551424 1334982784 14.012 100 32 64 1 1348121104 5492448 7645872
17:25:46 5116 8192000000 10243849488 10651500544 1325435840 13.926 99 42 83 3 1342650016 7652736 9561440
17:26:52 6758 8192000000 10220897728 10648154112 1335370304 14.016 100 28 56 0 1347985424 6264608 6350512
17:28:03 55622 8192000000 10193625376 10615406592 1296840960 13.667 99 36 72 1 1311641568 6201264 8599344
17:30:00 6535 8192000000 10146943520 10543116288 1230145856 13.055 98 34 66 4 1243643584 6601952 6895776
17:31:07 6701 8192000000 10148085520 10551951360 1255657440 13.290 100 22 44 0 1266265840 5477328 5131072
17:32:16 8402 8192000000 10150089200 10551259136 1245716944 13.199 99 32 65 0 1258698880 5080576 7901360
17:33:25 32415 8192000000 10164418560 10570686464 1238364368 13.131 97 16 32 0 1247613520 5857296 3391856
17:35:03 6095 8192000000 10189827488 10602868736 1295197024 13.652 100 29 58 0 1312026672 9847200 6982448
17:36:10 9081 8192000000 10199181984 10613850112 1307594528 13.764 100 23 46 0 1318769056 5810256 5364272
17:37:20 16201 8192000000 10226234784 10639216640 1325400464 13.926 100 21 41 1 1334571792 4634368 4536960
17:38:40 62385 8192000000 10254615040 10666594304 1343959264 14.093 100 52 102 0 1362214304 6312704 11942336
17:40:45 20653 8192000000 10266895664 10689187840 1363707280 14.271 100 48 97 1 1388207968 13349824 11150864
17:42:08 32775 8192000000 10284263264 10708733952 1374330368 14.366 100 29 58 0 1394798240 13694352 6773520
17:43:44 10112 8192000000 10300809504 10717921280 1377657392 14.396 99 58 116 1 1405194048 14496912 13039744
17:45:00 19449 8192000000 10318652576 10743787520 1400470784 14.599 100 52 102 0 1429636288 16621120 12544384
17:46:24 10679 8192000000 10326119344 10753761280 1398221840 14.579 99 52 102 1 1433897008 23136560 12538608
17:47:39 6847 8192000000 10348017040 10773778432 1418343296 14.758 100 50 98 1 1458465184 28154064 11967824
17:48:51 1663 8192000000 10359437552 10774491136 1414803296 14.727 100 82 160 0 1464804544 31190480 18810768
17:49:56 4891 8192000000 10360734000 10780684288 1417463776 14.750 100 70 136 2 1467413872 33644768 16305328
17:51:05 7051 8192000000 10357233360 10778697728 1407409584 14.661 100 61 122 1 1462763776 40317936 15036256
17:52:18 4857 8192000000 10373582848 10791710720 1415410384 14.732 100 69 138 0 1476696896 44129840 17156672
17:53:28 1677 8192000000 10373211280 10784419840 1399741424 14.593 99 80 158 0 1468255136 48965712 19548000
17:54:33 5497 8192000000 10378930768 10787852288 1400366368 14.598 100 75 150 0 1480599264 60824368 19408528
17:55:47 4483 8192000000 10385288256 10799558656 1395745872 14.557 99 72 144 4 1482385248 67123648 19515728
17:56:59 4648 8192000000 10393333504 10809946112 1421119392 14.783 100 70 138 1 1503492624 64010224 18363008
17:58:16 11068 8192000000 10389434912 10814914560 1413930448 14.719 99 49 97 2 1491806640 64256720 13619472
17:59:39 25128 8192000000 10384505808 10816868352 1408056912 14.667 99 47 92 1 1490193600 68687200 13449488
18:01:08 14842 8192000000 10381079216 10817556480 1403552096 14.627 100 36 72 1 1486647664 71996272 11099296
18:03:10 26612 8192000000 10339177456 10791112704 1377297744 14.392 99 27 54 0 1441362864 56159104 7906016
18:04:37 25856 8192000000 10323974528 10790748160 1372654016 14.351 100 25 49 1 1433690432 53745232 7291184
18:06:06 82444 8192000000 10256900912 10691080192 1265454160 13.380 93 13 25 0 1286588448 18870752 2263536
18:08:35 48163 8192000000 10277578576 10708058112 1346834928 14.119 99 12 24 1 1358639040 9799376 2004736
18:10:27 64485 8192000000 10280626208 10734899200 1341473936 14.071 98 14 28 0 1355861248 11683648 2703664
18:12:32 103225 8192000000 10268568400 10705362944 1325570288 13.927 97 35 70 3 1340121936 5960368 8591280
18:15:22 44537 8192000000 10291241296 10725588992 1354468720 14.188 99 43 86 0 1372049920 7183680 10397520
18:17:09 46059 8192000000 10283651104 10728509440 1368935808 14.318 100 32 64 0 1391019552 14239136 7844608
18:18:57 37425 8192000000 10275951808 10717544448 1367230720 14.302 100 22 44 1 1377988720 5860448 4897552
18:20:37 36674 8192000000 10297037072 10746417152 1372704560 14.351 99 37 74 0 1397013056 15944544 8363952
18:22:16 40056 8192000000 10291282448 10752733184 1387769504 14.486 100 9 18 0 1409281232 19227024 2284704
18:24:02 62556 8192000000 10307661872 10757238784 1386789968 14.477 100 10 20 2 1409799856 20622240 2387648
18:26:20 104896 8192000000 10309600784 10762280960 1402303568 14.616 100 22 44 0 1434064976 25663088 6098320
18:29:48 46313 8192000000 10303784752 10757210112 1383320064 14.446 99 9 18 1 1397704880 12368080 2016736
18:31:39 106402 8192000000 10315489520 10761289728 1361909952 14.255 97 48 96 1 1388961888 16415568 10636368
18:34:26 37116 8192000000 10292037152 10745171968 1360992736 14.246 98 24 47 0 1375180272 9288560 4898976
18:36:07 73274 8192000000 10290368800 10749984768 1365857360 14.290 98 29 59 1 1385513824 12859152 6797312
18:38:21 76153 8192000000 10278883424 10722643968 1319533696 13.872 95 9 18 0 1331488640 9688944 2266000
18:40:37 54200 8192000000 10297987296 10759278592 1381137648 14.427 100 22 44 1 1402487296 16936832 4412816
18:42:32 99906 8192000000 10312061936 10764681216 1393681472 14.539 100 28 56 0 1415279104 14477872 7119760
18:45:13 62372 8192000000 10280669808 10749075456 1353094880 14.175 97 17 32 0 1369465376 12462096 3908400
18:47:16 64979 8192000000 10274084912 10736246784 1371667392 14.342 99 12 24 0 1382537376 8010544 2859440
18:49:54 88041 8192000000 10280088464 10732548096 1346369824 14.115 97 21 41 0 1364106864 13172768 4564272
18:52:28 47395 8192000000 10267985440 10722004992 1334691904 14.010 97 17 34 2 1346453152 7232576 4528672
18:54:16 48814 8192000000 10273440608 10727890944 1363436224 14.268 99 29 58 0 1379395600 9580368 6379008
18:56:18 93883 8192000000 10251603344 10715865088 1342980048 14.084 98 13 26 0 1355844096 9898496 2965552
18:58:53 52346 8192000000 10254798544 10710822912 1331854016 13.984 98 15 30 0 1343862336 8426496 3581824
19:00:56 102162 8192000000 10261006000 10717409280 1299463376 13.690 95 15 30 0 1320898432 17677920 3757136
19:03:45 32115 8192000000 10282884272 10724712448 1357113696 14.211 99 42 84 1 1376954000 11047712 8792592
19:05:18 40694 8192000000 10283871456 10723405824 1348045840 14.130 99 24 48 1 1367752816 14266480 5440496
19:07:08 199215 8192000000 10290684720 10740326400 1279105728 13.505 93 10 20 0 1294005232 13123600 1775904
19:11:28 80152 8192000000 10283343168 10734747648 1371952880 14.345 100 30 60 0 1392350064 13073888 7323296
19:14:11 98784 8192000000 10287553104 10745225216 1367762400 14.307 99 11 22 0 1386581824 16087776 2731648
19:16:50 79676 8192000000 10302091312 10775810048 1377179488 14.391 99 23 44 4 1410908320 27883824 5845008
19:19:22 87586 8192000000 10286853568 10735067136 1346053824 14.112 97 25 50 0 1373289360 21103840 6131696
19:21:51 24641 8192000000 10286486064 10731814912 1378989392 14.408 100 41 82 1 1396122912 8288128 8845392
19:23:21 76990 8192000000 10298847040 10749898752 1358686112 14.226 98 23 46 0 1375918128 12045872 5186144
19:25:38 52113 8192000000 10313118800 10771193856 1392124288 14.525 100 26 52 2 1411022672 13094640 5803744
19:28:45 77565 8192000000 10293665584 10770030592 1366869472 14.299 98 7 14 0 1384324704 15694688 1760544
19:31:06 99237 8192000000 10287252208 10745298944 1349113552 14.140 97 24 48 0 1362608720 8150976 5344192
19:33:59 28504 8192000000 10293764016 10747932672 1373644848 14.360 99 27 52 1 1390720688 11409648 5666192
19:35:28 61273 8192000000 10293387552 10755727360 1392808624 14.531 100 10 20 1 1407562352 12410016 2343712
19:37:33 30051 8192000000 9204680656 9583403008 327568880 3.844 24 2 4 0 336194240 8294720 330640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-11-2021 19:40:11 to 01-11-2021 19:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-11-2021 19:40:26 to 01-11-2021 19:40:28.

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          232988
MON$OLDEST_ACTIVE               232989
MON$OLDEST_SNAPSHOT             232989
MON$NEXT_TRANSACTION            232989
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                       2053088
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.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:179: 
:180: mscs-test-161.budzdorov.ru    Mon Nov  1 19:38:15 2021
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
--- end of diff output ---

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

01.11.21 19:40:29. 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-01 19:40:32 No severe PSQL-related problems occured
01.11.21 19:40:33. End of report.