Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.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 15828064 kB
MemAvailable 30914028 kB
Buffers 0 kB
Cached 15854872 kB
SwapCached 336 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-01 22:37:44
DTS_END                         2021-09-01 22:37:44.6200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49590
TRN_ID                          251441
ATT_ID                          672
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: 9271.38 22318 1112566
customer order: creation 180.18 323 21622
customer order: refuse 15.33 380 1840
order to supplier: creation 46.79 1317 5615
order to supplier: removal 3.47 109725 416
invoice (draft): creation 47.98 3599 5758
invoice (draft): removal 51.08 793 6129
invoice accept: apply 42.91 19493 5149
invoice accept: cancel 46.21 890 5545
customer reserve: creation 3925.92 195 471110
customer reserve: removal 4600.26 65 552031
realization accept: apply 87.60 200 10512
realization accept: cancel 87.49 127 10499
payment from customer: creation 61.77 126 7412
payment from customer: removal 6.12 89 734
payment to supplier: creation 51.53 131 6184
payment to supplier: removal 3.84 73 461
service: total inventory turnovers 5.23 1212 627
service: total monetary turnovers 6.81 29 817
service: aggregate perf. data 0.84 24183 101
service: refresh index statistics 0.03 283417 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-09-2021 22:39:08 to 01-09-2021 22:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1553
TEST_TIME 2 2714
TEST_TIME 3 3264
TEST_TIME 4 3887
TEST_TIME 5 4626
TEST_TIME 6 4605
TEST_TIME 7 4925
TEST_TIME 8 4986
TEST_TIME 9 5559
TEST_TIME 10 5889
TEST_TIME 11 5863
TEST_TIME 12 6155
TEST_TIME 13 6008
TEST_TIME 14 6028
TEST_TIME 15 6317
TEST_TIME 16 6611
TEST_TIME 17 6699
TEST_TIME 18 6767
TEST_TIME 19 6955
TEST_TIME 20 7539
TEST_TIME 21 7755
TEST_TIME 22 7651
TEST_TIME 23 7621
TEST_TIME 24 7890
TEST_TIME 25 8001
TEST_TIME 26 8366
TEST_TIME 27 8306
TEST_TIME 28 8473
TEST_TIME 29 8847
TEST_TIME 30 8899
TEST_TIME 31 9399
TEST_TIME 32 9692
TEST_TIME 33 9878
TEST_TIME 34 10339
TEST_TIME 35 10391
TEST_TIME 36 10328
TEST_TIME 37 10197
TEST_TIME 38 10179
TEST_TIME 39 10051
TEST_TIME 40 10048
TEST_TIME 41 9996
TEST_TIME 42 9931
TEST_TIME 43 9872
TEST_TIME 44 9977
TEST_TIME 45 9940
TEST_TIME 46 9992
TEST_TIME 47 9939
TEST_TIME 48 9807
TEST_TIME 49 9683
TEST_TIME 50 9628
TEST_TIME 51 9664
TEST_TIME 52 9527
TEST_TIME 53 9611
TEST_TIME 54 9612
TEST_TIME 55 9607
TEST_TIME 56 9638
TEST_TIME 57 9565
TEST_TIME 58 9490
TEST_TIME 59 9413
TEST_TIME 60 9363
TEST_TIME 61 9359
TEST_TIME 62 9215
TEST_TIME 63 9259
TEST_TIME 64 9384
TEST_TIME 65 9461
TEST_TIME 66 9471
TEST_TIME 67 9551
TEST_TIME 68 9499
TEST_TIME 69 9467
TEST_TIME 70 9557
TEST_TIME 71 9533
TEST_TIME 72 9566
TEST_TIME 73 9538
TEST_TIME 74 9526
TEST_TIME 75 9579
TEST_TIME 76 9591
TEST_TIME 77 9614
TEST_TIME 78 9677
TEST_TIME 79 9564
TEST_TIME 80 9481
TEST_TIME 81 9422
TEST_TIME 82 9370
TEST_TIME 83 9394
TEST_TIME 84 9452
TEST_TIME 85 9426
TEST_TIME 86 9447
TEST_TIME 87 9401
TEST_TIME 88 9399
TEST_TIME 89 9389
TEST_TIME 90 9366
TEST_TIME 91 9372
TEST_TIME 92 9363
TEST_TIME 93 9324
TEST_TIME 94 9296
TEST_TIME 95 9430
TEST_TIME 96 9525
TEST_TIME 97 9509
TEST_TIME 98 9475
TEST_TIME 99 9481
TEST_TIME 100 9539
TEST_TIME 101 9506
TEST_TIME 102 9531
TEST_TIME 103 9536
TEST_TIME 104 9540
TEST_TIME 105 9471
TEST_TIME 106 9447
TEST_TIME 107 9490
TEST_TIME 108 9447
TEST_TIME 109 9439
TEST_TIME 110 9402
TEST_TIME 111 9370
TEST_TIME 112 9312
TEST_TIME 113 9304
TEST_TIME 114 9293
TEST_TIME 115 9335
TEST_TIME 116 9291
TEST_TIME 117 9276
TEST_TIME 118 9313
TEST_TIME 119 9296
TEST_TIME 120 9271

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-09-2021 22:39:11 to 01-09-2021 22:39:15.

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 1550719 1550719 0 0.00 0 18091 7 0 0
doc_list_biud 1550698 1550698 0 0.00 0 69960 29 0 0
sp_add_invoice_to_stock 5330 5149 181 3.39 60 180534 19494 0 181
sp_cancel_adding_invoice 5545 5545 0 0.00 5 69146 890 0 0
sp_cancel_client_order 1840 1840 0 0.00 4 14680 380 0 0
sp_cancel_customer_reserve 552031 552031 0 0.00 1 69963 65 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 2 5294 89 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 2329 73 0 0
sp_cancel_supplier_invoice 6138 6129 9 0.14 2 18466 794 0 9
sp_cancel_supplier_order 416 416 0 0.00 6 1105675 109726 0 0
sp_cancel_write_off 10499 10499 0 0.00 1 39122 127 0 0
sp_client_order 21622 21622 0 0.00 4 15161 324 0 0
sp_customer_reserve 471110 471110 0 0.00 0 43044 196 0 0
sp_fill_shopping_cart 32985 32985 0 0.00 1 13279 40 0 0
sp_fill_shopping_cart_clo_res 471110 471110 0 0.00 0 22184 13 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 28606 268 0 0
sp_kill_cost_storno 17239 17239 0 0.00 0 13183 63 0 0
sp_kill_qstorno_ret_qs2qd 1121106 1121106 0 0.00 0 69955 35 0 0
sp_kill_qty_storno 592110 592110 0 0.00 0 69960 76 0 0
sp_lock_selected_doc 21607 21607 0 0.00 0 7593 6 0 0
sp_make_cost_storno 29257 29257 0 0.00 0 18083 59 0 0
sp_multiply_rows_for_pdistr 29236 29236 0 0.00 0 8904 31 0 0
sp_multiply_rows_for_qdistr 21622 21622 0 0.00 0 10058 126 0 0
sp_pay_from_customer 7412 7412 0 0.00 4 20852 126 0 0
sp_pay_to_supplier 6184 6184 0 0.00 5 14745 132 0 0
sp_payment_common 13586 13586 0 0.00 1 20843 34 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 14676 348 0 0
sp_qd_handle_on_invoice_upd_sts 10689 10689 0 0.00 0 28306 363 0 0
sp_qd_handle_on_reserve_upd_sts 10506 10506 0 0.00 0 15023 74 0 0
sp_reserve_write_off 10512 10512 0 0.00 6 15053 201 0 0
sp_supplier_invoice 5758 5758 0 0.00 7 105902 3599 0 0
sp_supplier_order 5615 5615 0 0.00 5 40697 1318 0 0
srv_aggregate_perf_data 101 101 0 0.00 3178 474958 24183 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1428 144110 41627 0 0
srv_find_qd_qs_mism 1064015 1064015 0 0.00 0 11357 6 0 0
srv_make_invnt_saldo 627 627 0 0.00 42 116721 1213 0 0
srv_make_money_saldo 817 817 0 0.00 0 1602 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 146033 389705 283417 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5149 5149 0 0.00 0 8153 164 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 5162 122 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 6870 16 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 6282 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 1636 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 1910 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1241 16 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 32088 1842 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 3324 8 0 0
t$perf-norm:sp_client_order 21622 21622 0 0.00 0 21430 19 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 7889 21 0 0
t$perf-norm:sp_pay_from_customer 7412 7412 0 0.00 0 5317 11 0 0
t$perf-norm:sp_pay_to_supplier 6184 6184 0 0.00 0 7885 14 0 0
t$perf-norm:sp_reserve_write_off 10512 10512 0 0.00 0 6580 10 0 0
t$perf-norm:sp_supplier_invoice 5758 5758 0 0.00 0 16296 21 0 0
t$perf-norm:sp_supplier_order 5615 5615 0 0.00 0 4591 13 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 383 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 311 9 0 0
t$perf-norm:srv_make_invnt_saldo 627 627 0 0.00 0 4075 30 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 1719 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10650 10288 362 3.39 0 3068 18 0 362
v_all_customers 39320 39320 0 0.00 0 107 1 0 0
v_all_suppliers 31344 31344 0 0.00 0 137 1 0 0
v_all_wares 43244 43244 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1902 9 0 0
v_cancel_client_order 3680 3680 0 0.00 0 5151 8 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 1199 5 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 743 4 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 2320 6 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 3943 27 0 18
v_cancel_supplier_order 832 832 0 0.00 0 5383 11 0 0
v_cancel_write_off 2062 2062 0 0.00 0 5980 17 0 0
v_max_id_clo_ord 5609 5609 0 0.00 0 4083 2 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 5712 20 0 0
v_max_id_ord_sup 5752 5752 0 0.00 0 8294 6 0 0
v_max_non_paid_invoice 1863 1863 0 0.00 0 4304 7 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 6234 10 0 0
v_min_id_clo_ord 5609 5609 0 0.00 0 3985 7 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 438 2 0 0
v_min_id_ord_sup 5752 5752 0 0.00 0 7351 12 0 0
v_min_non_paid_invoice 6176 6176 0 0.00 0 6235 16 0 0
v_min_non_paid_realizn 7410 7410 0 0.00 0 14156 23 0 0
v_our_firm 10724 10724 0 0.00 0 213 1 0 0
v_reserve_write_off 21012 21012 0 0.00 0 5149 5 0 0
x_lock_depdocs_on_canc_invoice 11280 11280 0 0.00 0 26875 153 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 11958 422 0 0
x_make_qstorno_1000_1200 5615 5615 0 0.00 2 40685 1235 0 0
x_make_qstorno_1200_2000 5758 5758 0 0.00 3 105875 3458 0 0
x_make_qstorno_2100_3300 471110 471110 0 0.00 0 36269 180 0 0

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

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:48 7 8192000000 8802501536 8814034944 8572016 0.104 2 1 2 0 8691424 56848 62560
20:08:49 20408 8192000000 10355231312 10670059520 1410006624 14.684 100 90 180 1 1445491680 5877024 29608032
20:10:19 26134 8192000000 10428501840 10756640768 1479173456 15.294 100 94 187 0 1517784544 8318560 30292528
20:11:49 18602 8192000000 10484878656 10828423168 1526236464 15.704 100 81 162 0 1561432480 10032688 25163328
20:13:23 10388 8192000000 10582943664 10940010496 1641705968 16.694 100 84 168 1 1679426544 12038704 25681872
20:14:36 8376 8192000000 10646091056 11015716864 1700858208 17.192 100 77 153 3 1734526080 11601792 22066080
20:15:46 4591 8192000000 10727481024 11104735232 1788138672 17.916 100 86 168 2 1822653712 11054816 23460224
20:16:53 2411 8192000000 10782895232 11165634560 1840793376 18.347 100 93 181 1 1880124720 13417824 25913520
20:17:57 1354 8192000000 10821180304 11206688768 1874628720 18.622 100 90 184 0 1911816640 12168560 25019360
20:18:59 4496 8192000000 10814586192 11222818816 1883184608 18.691 100 58 116 1 1908418784 9101056 16133120
20:20:04 13873 8192000000 10790516672 11225620480 1889053888 18.738 100 14 28 1 1899121056 6323136 3744032
20:21:18 42897 8192000000 10823084464 11252293632 1903579792 18.855 100 23 47 0 1915820032 5655744 6584496
20:23:06 4280 8192000000 10835432560 11258851328 1907196816 18.884 100 38 77 0 1926134928 8733952 10204160
20:24:10 9047 8192000000 10831797600 11260518400 1907953824 18.890 100 19 38 2 1919611696 5116912 6540960
20:25:22 16243 8192000000 10823745008 11261190144 1912590784 18.927 100 36 72 3 1929842896 6629232 10622880
20:26:43 5276 8192000000 10828241952 11261120512 1909327616 18.901 100 26 52 1 1923303712 6042624 7933472
20:27:51 8867 8192000000 10741809280 11172823040 1779627136 17.846 96 27 54 0 1793738496 6287376 7823984
20:29:01 35394 8192000000 10707264928 11123589120 1716687568 17.325 94 31 61 3 1730881040 5317744 8875728
20:30:37 3599 8192000000 10689109744 11102449664 1762293488 17.703 98 30 59 1 1777458624 6268112 8897024
20:31:41 8386 8192000000 10720822992 11129782272 1784464656 17.886 99 24 49 1 1796973504 5557008 6951840
20:32:50 6453 8192000000 10754761472 11185905664 1846864512 18.397 100 27 54 2 1861669344 5879024 8925808
20:33:57 28253 8192000000 10764934304 11197218816 1859119328 18.496 100 36 71 3 1875712416 5609072 10984016
20:35:29 6050 8192000000 10801199376 11222884352 1874869040 18.624 100 38 75 2 1892815520 6468864 11477616
20:36:38 9166 8192000000 10814969200 11236921344 1887519392 18.726 100 43 86 1 1906020128 5419088 13081648
20:37:52 68308 8192000000 10844399536 11270115328 1898967520 18.818 99 36 68 1 1914671440 5344336 10359584
20:40:04 44408 8192000000 10878455008 11311263744 1944176832 19.180 100 59 116 1 1971297488 10435552 16685104
20:41:52 10677 8192000000 10890628992 11323011072 1958123696 19.291 100 46 92 2 1984879904 13894976 12861232
20:43:08 11568 8192000000 10925625616 11351339008 1979675856 19.462 100 64 128 1 2013332672 15040624 18616192
20:44:46 6310 8192000000 10925278272 11367108608 1988272368 19.530 100 49 98 2 2025418608 22589696 14556544
20:46:05 6799 8192000000 10907453312 11349504000 1965492576 19.350 99 37 74 0 1993367456 16474432 11400448
20:47:17 1552 8192000000 10949079952 11370807296 1988077104 19.529 100 78 156 4 2030966144 19738592 23150448
20:48:31 4511 8192000000 10927644432 11347222528 1966715344 19.359 99 78 157 2 2012464640 23275440 22473856
20:49:39 1427 8192000000 10946460992 11356606464 1976099120 19.434 99 84 166 1 2028751552 28927920 23724512
20:50:43 12669 8192000000 10886374304 11304554496 1908703264 18.896 97 68 133 0 1962581424 33492624 20385536
20:52:18 4903 8192000000 10945277088 11362066432 1973449968 19.413 100 67 132 0 2028271584 33686528 21135088
20:53:27 5230 8192000000 10946236816 11373088768 1977071072 19.442 100 69 138 1 2035833408 38306144 20456192
20:54:34 2764 8192000000 10931849456 11353288704 1953625440 19.255 100 71 141 0 2016646000 41105088 21915472
20:55:41 4862 8192000000 10916750352 11331166208 1930856752 19.074 100 65 121 3 1987511216 37534976 19119488
20:56:54 4596 8192000000 10924972064 11337953280 1934286832 19.101 100 76 152 1 2005845952 46944944 24614176
20:58:04 2575 8192000000 10911066816 11318194176 1906249312 18.877 99 87 168 1 1978288400 45185840 26853248
20:59:08 6321 8192000000 10905958192 11325132800 1919896496 18.986 99 44 87 1 1978635392 45787600 12951296
21:00:28 3624 8192000000 10965116720 11373424640 1962021968 19.322 100 81 162 1 2043153360 56237232 24894160
21:01:35 15723 8192000000 10935840400 11370942464 1954686688 19.264 100 35 70 0 2019696960 53159696 11850576
21:03:03 20155 8192000000 10939140976 11385069568 1960566784 19.311 100 42 85 0 2033836288 59196240 14073264
21:04:30 12665 8192000000 10966861088 11407659008 1985068784 19.505 100 37 74 2 2051597216 53859264 12669168
21:05:53 12575 8192000000 10972922912 11421454336 1986870928 19.519 100 31 62 2 2056140368 58447280 10822160
21:07:09 18512 8192000000 10961754032 11415814144 1975402192 19.428 99 25 48 0 2034575376 51271008 7902176
21:08:55 16800 8192000000 10951096784 11405946880 1983312240 19.491 100 19 38 3 2038433632 48076864 7044528
21:10:12 12532 8192000000 10929177296 11369197568 1957257488 19.284 100 45 89 0 2007420064 36400128 13762448
21:11:26 12770 8192000000 10920065632 11390689280 1977043264 19.441 100 26 52 1 2010763328 25778672 7941392
21:12:46 96535 8192000000 10928166256 11368964096 1941735440 19.161 98 7 14 0 1959887616 16241376 1910800
21:15:26 71023 8192000000 10910578928 11369603072 1949337728 19.221 98 9 18 0 1960397488 8795440 2264320
21:17:38 74805 8192000000 10924934912 11390455808 1973323584 19.412 99 22 44 1 1993555328 13638016 6593728
21:20:08 76179 8192000000 10935345824 11409956864 2001599680 19.635 100 29 58 0 2021244976 11291856 8353440
21:22:37 78139 8192000000 10938408032 11419951104 1987931712 19.527 99 9 17 1 2007039984 16194160 2914112
21:25:08 98797 8192000000 10936008576 11392765952 1964649392 19.343 98 31 61 1 1982698000 9306768 8741840
21:27:59 25829 8192000000 10902921680 11358932992 1943593248 19.175 98 57 113 2 1970655840 11492400 15570192
21:29:33 16821 8192000000 10898844336 11352788992 1941538896 19.159 99 31 62 1 1960641616 10443808 8658912
21:30:54 40209 8192000000 10839386576 11305328640 1855759920 18.469 94 23 46 3 1872032720 8970608 7302192
21:32:35 28231 8192000000 10907779104 11352416256 1937836464 19.129 98 56 110 3 1963377536 10299056 15242016
21:34:25 30386 8192000000 10875944464 11335008256 1935474192 19.111 99 26 53 1 1950173520 7691200 7008128
21:36:47 60138 8192000000 10896364656 11372728320 1932188688 19.084 98 12 24 0 1950813872 15012768 3612416
21:38:47 100796 8192000000 10908399056 11379187712 1947339280 19.205 98 16 32 1 1972675568 20031408 5304880
21:41:38 48594 8192000000 10877798192 11354787840 1921124912 18.996 98 16 32 1 1945484272 19690720 4668640
21:43:26 43645 8192000000 10887509744 11366641664 1951538192 19.239 99 19 38 1 1966750464 9581024 5631248
21:45:16 31923 8192000000 10905067456 11357003776 1937018112 19.123 98 48 95 1 1959317552 9257184 13042256
21:46:56 33949 8192000000 10896212048 11354890240 1952894128 19.250 100 35 70 0 1978595520 16304176 9397216
21:48:33 13499 8192000000 10878651248 11332141056 1911972768 18.922 98 49 97 1 1934937024 8899104 14065152
21:49:55 43315 8192000000 10884354816 11320922112 1883986512 18.697 97 32 64 1 1902158480 8386128 9785840
21:51:38 78930 8192000000 10864830080 11320725504 1869994464 18.584 97 26 53 1 1896056112 18703840 7357808
21:54:01 55980 8192000000 10889816384 11350163456 1921224128 18.997 99 29 58 2 1949834432 20912944 7697360
21:56:03 144109 8192000000 10866551376 11335954432 1887715840 18.727 97 17 34 0 1904186832 11246736 5224256
21:59:28 47896 8192000000 10873800816 11344834560 1935977120 19.115 99 25 50 1 1965087776 21924256 7186400
22:01:24 36756 8192000000 10870980272 11358507008 1939658112 19.144 100 4 8 0 1959483456 18653056 1172288
22:03:16 77003 8192000000 10882720208 11364982784 1956871808 19.281 100 14 28 2 1978985360 18174752 3938800
22:05:37 54080 8192000000 10875306336 11366932480 1937922256 19.130 99 15 30 0 1954879104 12412720 4544128
22:07:32 58088 8192000000 10890597968 11369328640 1955312832 19.269 99 15 30 1 1967127536 8304672 3510032
22:09:30 54137 8192000000 10900914080 11382378496 1942958272 19.170 98 16 32 1 1957277648 10222832 4096544
22:11:26 52150 8192000000 10930463680 11416444928 1991266816 19.554 100 13 26 0 2016412624 20927216 4218592
22:13:30 72451 8192000000 10898867856 11389145088 1874842240 18.623 95 3 6 0 1898478048 23099888 535920
22:15:53 25860 8192000000 10940811040 11403833344 1991728240 19.557 100 33 66 0 2013783568 12510592 9544736
22:17:21 71915 8192000000 10907258624 11377651712 1922637184 19.008 97 17 34 1 1940570400 13113680 4819536
22:19:34 48967 8192000000 10904049424 11377405952 1930501104 19.071 98 13 26 3 1942383488 8574736 3307648
22:21:29 143648 8192000000 10922998640 11394490368 1969017888 19.378 99 34 68 0 1992402288 14040960 9343440
22:25:01 48982 8192000000 10903708160 11374948352 1944100432 19.179 98 14 27 1 1959807024 11744640 3961952
22:26:53 64125 8192000000 10881466448 11364192256 1914088240 18.939 97 15 30 1 1925587840 7141648 4357952
22:28:57 73605 8192000000 10907410960 11388411904 1982565760 19.485 100 16 32 0 1998531600 10816336 5149504
22:31:25 75666 8192000000 10841543648 11338555392 1828610048 18.248 93 16 33 2 1846309872 12973824 4726000
22:33:45 75986 8192000000 10879901344 11359035392 1915146320 18.948 98 12 24 2 1927739408 9333552 3259536
22:36:07 49339 8192000000 10904695280 11389571072 1968730896 19.375 100 13 26 0 1982817120 10377440 3708784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-09-2021 22:39:21 to 01-09-2021 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          248390
MON$OLDEST_ACTIVE               251550
MON$OLDEST_SNAPSHOT             251550
MON$NEXT_TRANSACTION            251550
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                       2072800
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.33490 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33490 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33490 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33490 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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Wed Sep  1 22:37:54 2021
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Wed Sep  1 22:37:55 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
--- end of diff output ---

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

01.09.21 22:39:40. 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-01 22:39:43 No severe PSQL-related problems occured
01.09.21 22:39:44. End of report.