Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.11.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16713128 kB
MemAvailable 31548044 kB
Buffers 0 kB
Cached 14933828 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-21 16:37:53
DTS_END                         2021-11-21 16:37:53.2120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55708
TRN_ID                          242567
ATT_ID                          677
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: 9270.92 20177 1112510
customer order: creation 173.77 382 20852
customer order: refuse 14.30 334 1716
order to supplier: creation 46.36 1370 5563
order to supplier: removal 3.71 93080 445
invoice (draft): creation 45.29 3350 5435
invoice (draft): removal 52.58 764 6309
invoice accept: apply 42.28 18545 5073
invoice accept: cancel 48.01 779 5761
customer reserve: creation 3813.99 187 457679
customer reserve: removal 4734.93 57 568191
realization accept: apply 83.26 185 9991
realization accept: cancel 83.40 111 10008
payment from customer: creation 58.31 100 6997
payment from customer: removal 5.87 103 704
payment to supplier: creation 49.18 121 5901
payment to supplier: removal 3.20 73 384
service: total inventory turnovers 5.02 1068 602
service: total monetary turnovers 6.57 45 788
service: aggregate perf. data 0.89 17432 107
service: refresh index statistics 0.03 265444 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-11-2021 16:39:08 to 21-11-2021 16:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8001
TEST_TIME 2 5139
TEST_TIME 3 6688
TEST_TIME 4 5938
TEST_TIME 5 5694
TEST_TIME 6 5321
TEST_TIME 7 5467
TEST_TIME 8 5484
TEST_TIME 9 5130
TEST_TIME 10 5285
TEST_TIME 11 5318
TEST_TIME 12 5545
TEST_TIME 13 5696
TEST_TIME 14 5871
TEST_TIME 15 6107
TEST_TIME 16 6313
TEST_TIME 17 6496
TEST_TIME 18 6896
TEST_TIME 19 6921
TEST_TIME 20 7398
TEST_TIME 21 7464
TEST_TIME 22 7681
TEST_TIME 23 8026
TEST_TIME 24 8906
TEST_TIME 25 8790
TEST_TIME 26 9126
TEST_TIME 27 9234
TEST_TIME 28 9560
TEST_TIME 29 10278
TEST_TIME 30 10550
TEST_TIME 31 10598
TEST_TIME 32 10486
TEST_TIME 33 10331
TEST_TIME 34 10229
TEST_TIME 35 10003
TEST_TIME 36 9800
TEST_TIME 37 9885
TEST_TIME 38 9755
TEST_TIME 39 9722
TEST_TIME 40 9628
TEST_TIME 41 9690
TEST_TIME 42 9628
TEST_TIME 43 9516
TEST_TIME 44 9576
TEST_TIME 45 9664
TEST_TIME 46 9508
TEST_TIME 47 9568
TEST_TIME 48 9494
TEST_TIME 49 9477
TEST_TIME 50 9471
TEST_TIME 51 9549
TEST_TIME 52 9589
TEST_TIME 53 9677
TEST_TIME 54 9749
TEST_TIME 55 9742
TEST_TIME 56 9690
TEST_TIME 57 9713
TEST_TIME 58 9791
TEST_TIME 59 9899
TEST_TIME 60 9786
TEST_TIME 61 9633
TEST_TIME 62 9632
TEST_TIME 63 9570
TEST_TIME 64 9558
TEST_TIME 65 9513
TEST_TIME 66 9527
TEST_TIME 67 9449
TEST_TIME 68 9389
TEST_TIME 69 9363
TEST_TIME 70 9286
TEST_TIME 71 9321
TEST_TIME 72 9306
TEST_TIME 73 9251
TEST_TIME 74 9270
TEST_TIME 75 9241
TEST_TIME 76 9281
TEST_TIME 77 9310
TEST_TIME 78 9364
TEST_TIME 79 9379
TEST_TIME 80 9379
TEST_TIME 81 9357
TEST_TIME 82 9467
TEST_TIME 83 9467
TEST_TIME 84 9495
TEST_TIME 85 9557
TEST_TIME 86 9462
TEST_TIME 87 9462
TEST_TIME 88 9407
TEST_TIME 89 9405
TEST_TIME 90 9347
TEST_TIME 91 9298
TEST_TIME 92 9236
TEST_TIME 93 9262
TEST_TIME 94 9228
TEST_TIME 95 9228
TEST_TIME 96 9256
TEST_TIME 97 9270
TEST_TIME 98 9371
TEST_TIME 99 9417
TEST_TIME 100 9401
TEST_TIME 101 9386
TEST_TIME 102 9405
TEST_TIME 103 9413
TEST_TIME 104 9371
TEST_TIME 105 9462
TEST_TIME 106 9546
TEST_TIME 107 9523
TEST_TIME 108 9519
TEST_TIME 109 9451
TEST_TIME 110 9428
TEST_TIME 111 9381
TEST_TIME 112 9366
TEST_TIME 113 9330
TEST_TIME 114 9332
TEST_TIME 115 9302
TEST_TIME 116 9313
TEST_TIME 117 9300
TEST_TIME 118 9303
TEST_TIME 119 9259
TEST_TIME 120 9272

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-11-2021 16:39:11 to 21-11-2021 16: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 1538481 1538481 0 0.00 0 17882 6 0 0
doc_list_biud 1538481 1538481 0 0.00 0 45754 27 0 0
sp_add_invoice_to_stock 5211 5073 138 2.64 39 215819 18546 0 138
sp_cancel_adding_invoice 5761 5761 0 0.00 5 60508 780 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 19171 334 0 0
sp_cancel_customer_reserve 568191 568191 0 0.00 1 45755 58 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 4171 104 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 5124 73 0 0
sp_cancel_supplier_invoice 6323 6309 14 0.22 1 23410 765 0 14
sp_cancel_supplier_order 445 445 0 0.00 7 1124076 93081 0 0
sp_cancel_write_off 10008 10008 0 0.00 1 17082 112 0 0
sp_client_order 20852 20852 0 0.00 3 33974 383 0 0
sp_customer_reserve 457679 457679 0 0.00 0 46103 188 0 0
sp_fill_shopping_cart 31850 31850 0 0.00 1 9353 37 0 0
sp_fill_shopping_cart_clo_res 457678 457678 0 0.00 0 16380 15 0 0
sp_get_clo_for_invoice 5072 5072 0 0.00 0 22495 319 0 0
sp_kill_cost_storno 16857 16857 0 0.00 0 8378 53 0 0
sp_kill_qstorno_ret_qs2qd 1153144 1153144 0 0.00 0 45752 31 0 0
sp_kill_qty_storno 607493 607493 0 0.00 0 45754 68 0 0
sp_lock_selected_doc 20841 20841 0 0.00 0 5974 3 0 0
sp_make_cost_storno 27961 27961 0 0.00 0 17656 67 0 0
sp_multiply_rows_for_pdistr 27961 27961 0 0.00 0 9314 30 0 0
sp_multiply_rows_for_qdistr 20852 20852 0 0.00 0 33967 185 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 10385 101 0 0
sp_pay_to_supplier 5901 5901 0 0.00 4 13584 122 0 0
sp_payment_common 12898 12898 0 0.00 2 10161 25 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 19163 311 0 0
sp_qd_handle_on_invoice_upd_sts 10833 10833 0 0.00 0 23488 348 0 0
sp_qd_handle_on_reserve_upd_sts 9991 9991 0 0.00 0 20219 59 0 0
sp_reserve_write_off 9991 9991 0 0.00 6 20259 185 0 0
sp_supplier_invoice 5435 5435 0 0.00 6 97893 3351 0 0
sp_supplier_order 5563 5563 0 0.00 6 31034 1371 0 0
srv_aggregate_perf_data 107 107 0 0.00 2850 138390 17433 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2361 170583 47374 0 0
srv_find_qd_qs_mism 1065994 1065994 0 0.00 0 15784 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 44 50002 1068 0 0
srv_make_money_saldo 788 788 0 0.00 1 10352 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 64291 437074 265444 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5073 5073 0 0.00 0 11131 183 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 7265 154 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 2801 8 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1106 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 4352 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 750 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 547 16 0 0
t$perf-norm:sp_cancel_supplier_order 445 445 0 0.00 0 20974 1513 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 2205 11 0 0
t$perf-norm:sp_client_order 20852 20852 0 0.00 0 4353 17 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 1022 9 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 12071 10 0 0
t$perf-norm:sp_pay_to_supplier 5901 5901 0 0.00 0 3559 8 0 0
t$perf-norm:sp_reserve_write_off 9991 9991 0 0.00 0 5911 12 0 0
t$perf-norm:sp_supplier_invoice 5435 5435 0 0.00 0 12292 21 0 0
t$perf-norm:sp_supplier_order 5563 5563 0 0.00 0 4238 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 356 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1793 37 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 6376 39 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 3651 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 30 9 0 0
v_add_invoice_to_stock 10420 10144 276 2.64 0 3418 19 0 276
v_all_customers 37668 37668 0 0.00 0 88 1 0 0
v_all_suppliers 30152 30152 0 0.00 0 13 1 0 0
v_all_wares 41704 41704 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2061 9 0 0
v_cancel_client_order 3432 3432 0 0.00 0 3209 6 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3146 10 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1895 6 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 5111 10 0 0
v_cancel_supplier_invoice 872 844 28 3.21 0 555 15 0 28
v_cancel_supplier_order 890 890 0 0.00 0 688 4 0 0
v_cancel_write_off 1924 1924 0 0.00 0 1976 16 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 364 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 6295 25 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 1485 2 0 0
v_max_non_paid_invoice 1823 1823 0 0.00 0 2875 6 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 7131 8 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 9160 8 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 3440 12 0 0
v_min_id_ord_sup 5435 5435 0 0.00 0 8793 9 0 0
v_min_non_paid_invoice 5901 5901 0 0.00 0 8187 15 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 6051 20 0 0
v_our_firm 10594 10594 0 0.00 0 15 1 0 0
v_reserve_write_off 19982 19982 0 0.00 0 5143 7 0 0
x_lock_depdocs_on_canc_invoice 11648 11648 0 0.00 0 15617 122 0 0
x_lock_depdocs_on_canc_sup_ord 445 445 0 0.00 0 8228 398 0 0
x_make_qstorno_1000_1200 5563 5563 0 0.00 2 30987 1285 0 0
x_make_qstorno_1200_2000 5435 5435 0 0.00 3 97846 3223 0 0
x_make_qstorno_2100_3300 457679 457679 0 0.00 0 41110 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-11-2021 16:39:16 to 21-11-2021 16: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
14:07:56 3 8192000000 8801710704 8813907968 7736576 0.094 2 2 3 0 7877664 66144 74944
14:08:57 18059 8192000000 9891132848 10202492928 979368480 10.678 100 89 177 0 1008103600 5866048 22869072
14:10:27 22845 8192000000 9944596864 10268626944 1032161856 11.189 100 85 170 1 1062159984 8202496 21795632
14:11:51 20583 8192000000 10002381472 10339389440 1079229856 11.640 100 84 168 0 1109749264 10048704 20470704
14:13:18 15879 8192000000 10048109776 10399531008 1131549248 12.136 100 82 164 0 1163441264 12307776 19584240
14:14:42 9265 8192000000 10096084784 10458845184 1183006832 12.618 100 74 148 1 1212557952 12311952 17239168
14:15:53 3308 8192000000 10138724896 10509086720 1226886176 13.025 100 77 154 0 1255478928 10791632 17801120
14:16:58 2662 8192000000 10178630032 10554011648 1265642400 13.382 100 82 165 0 1297008832 12744800 18621632
14:18:03 2006 8192000000 10207921840 10589753344 1293015952 13.632 100 86 172 0 1326007984 13648224 19343808
14:19:05 22870 8192000000 10209517872 10600124416 1300993712 13.704 100 59 116 0 1322375824 8479552 12902560
14:20:29 6035 8192000000 10196859024 10613633024 1312769696 13.811 100 27 54 1 1326473584 7700384 6003504
14:21:35 11133 8192000000 10205590944 10624163840 1321794816 13.893 100 14 28 1 1330659424 5764976 3099632
14:22:48 9431 8192000000 10213584288 10635227136 1330086720 13.968 100 17 34 1 1340052032 6126080 3839232
14:23:58 38075 8192000000 10254277280 10653188096 1331301392 13.979 100 75 150 4 1354321872 4320592 18699888
14:25:36 32176 8192000000 10227136768 10657017856 1342292928 14.078 100 28 54 1 1353454912 5060416 6101568
14:27:11 3544 8192000000 10224077472 10643558400 1324182192 13.915 99 26 52 0 1336106656 5695360 6229104
14:28:15 5909 8192000000 10196677584 10621997056 1308124016 13.769 99 18 36 0 1317407952 5148480 4135456
14:29:21 38903 8192000000 10153057504 10556776448 1128381168 12.106 86 27 53 2 1139824704 5377888 6065648
14:31:03 9233 8192000000 10146448784 10548011008 1215740192 12.922 96 22 44 1 1225890512 4921824 5228496
14:32:17 8107 8192000000 10134451392 10529439744 1221815456 12.978 98 19 38 2 1231717552 5223744 4678352
14:33:25 10742 8192000000 10165000672 10577068032 1267882896 13.402 99 46 91 5 1284450320 5380032 11187392
14:35:07 8530 8192000000 10189300048 10598903808 1290877296 13.612 100 22 44 1 1301416096 5573504 4965296
14:36:16 12307 8192000000 10203866208 10616709120 1303011760 13.723 100 19 38 0 1316767552 9650528 4105264
14:37:29 11706 8192000000 10207498480 10624475136 1307943664 13.767 99 11 22 2 1314300672 4576640 1780368
14:38:45 72077 8192000000 10249025072 10665021440 1341774976 14.073 100 51 102 0 1362613840 8971328 11867536
14:41:01 8279 8192000000 10251950048 10668576768 1326776752 13.938 98 53 104 5 1347182272 7254704 13150816
14:42:15 6709 8192000000 10275019856 10689806336 1360163808 14.239 100 54 108 1 1382488048 9512480 12811760
14:43:27 18681 8192000000 10289350512 10709819392 1372528416 14.350 100 54 106 1 1398280336 13499936 12251984
14:44:56 13120 8192000000 10299499264 10725023744 1383591248 14.449 100 40 79 1 1408825392 15966672 9267472
14:46:17 56468 8192000000 10307489280 10728689664 1381110464 14.426 99 54 108 1 1413820912 19939152 12771296
14:48:22 13886 8192000000 10311717680 10738429952 1385429984 14.465 99 55 110 0 1419304448 20785968 13088496
14:49:38 3104 8192000000 10328338256 10749132800 1400875664 14.603 100 77 152 1 1442601824 22934384 18791776
14:50:44 2359 8192000000 10331626928 10744999936 1381752112 14.432 99 79 157 1 1425955760 25729392 18474256
14:51:54 3126 8192000000 10341844752 10761830400 1403430016 14.626 100 56 110 3 1447641456 30611680 13599760
14:53:03 9157 8192000000 10344480144 10753396736 1384849696 14.460 99 65 130 1 1434929760 34308448 15771616
14:54:17 3161 8192000000 10345623072 10748964864 1386437504 14.474 99 84 165 1 1446754544 40229856 20087184
14:55:25 3025 8192000000 10361645056 10772230144 1402189984 14.614 100 76 150 0 1462551504 41828288 18533232
14:56:36 2490 8192000000 10353858016 10752851968 1380989776 14.425 100 84 164 1 1449586752 47961632 20635344
14:57:43 9185 8192000000 10340307744 10754772992 1385042688 14.462 100 60 119 2 1447261584 46811840 15407056
14:58:58 5894 8192000000 10348147456 10761572352 1380289424 14.419 100 59 118 0 1451017648 55071856 15656368
15:00:11 20704 8192000000 10340263040 10773155840 1374965296 14.372 99 42 84 1 1451281328 63375616 12940416
15:01:37 17238 8192000000 10344760608 10775924736 1389685792 14.503 100 40 80 0 1452176304 51608400 10882112
15:03:05 25125 8192000000 10325308320 10761912320 1374685344 14.369 99 25 50 1 1425098832 43291136 7122352
15:04:43 32947 8192000000 10309111792 10745065472 1354662016 14.189 99 19 36 0 1400956064 41176208 5117840
15:06:23 40683 8192000000 10288706064 10736185344 1358853136 14.227 99 19 38 0 1399868208 36065088 4949984
15:08:07 67932 8192000000 10289003040 10744754176 1368339616 14.312 99 15 30 1 1382841504 11315792 3186096
15:10:20 5646 8192000000 10309635296 10742407168 1388735184 14.495 100 48 99 2 1408080432 8113072 11232176
15:11:26 165654 8192000000 10303015824 10748923904 1382175536 14.436 99 31 62 0 1398744160 8266160 8302464
15:15:13 24608 8192000000 10317956512 10757394432 1398374624 14.581 100 41 82 0 1415624080 6969280 10280176
15:16:42 24416 8192000000 10313838304 10763182080 1405637376 14.645 100 26 49 1 1423384624 11776608 5970640
15:18:14 74764 8192000000 10304996608 10770436096 1342412944 14.079 95 18 36 0 1370042080 23533216 4095920
15:20:58 51404 8192000000 10308184336 10765639680 1394466432 14.546 100 16 33 0 1416501760 17817760 4217568
15:22:50 21705 8192000000 10300879024 10771050496 1400990848 14.604 100 6 12 1 1421789872 19818688 980336
15:24:13 75200 8192000000 10281180640 10745319424 1340690448 14.064 96 7 14 0 1353318368 10921136 1706784
15:26:29 62373 8192000000 10291653184 10746195968 1337196800 14.032 96 31 62 1 1362427040 18233968 6996272
15:28:36 77363 8192000000 10297895904 10759684096 1373688752 14.360 99 16 32 3 1400561136 22459568 4412816
15:30:54 42547 8192000000 10288158048 10741133312 1367436464 14.304 99 33 66 0 1392150480 17136160 7577856
15:32:42 68379 8192000000 10286843248 10733498368 1340890000 14.065 97 10 20 1 1354374496 10543552 2940944
15:34:51 45096 8192000000 10284131680 10737762304 1359486080 14.233 99 14 28 1 1379694096 16977584 3230432
15:36:36 72175 8192000000 10277882080 10739707904 1340961840 14.066 97 11 21 1 1360047168 16337936 2747392
15:38:48 68840 8192000000 10270181792 10713223168 1307414816 13.763 94 29 57 0 1320560336 6564672 6580848
15:40:59 35085 8192000000 10282766096 10733293568 1375772272 14.379 100 31 63 1 1395342992 12434016 7136704
15:42:45 51783 8192000000 10261277552 10738274304 1350647056 14.153 98 6 12 0 1366230688 14152704 1430928
15:44:37 41101 8192000000 10234149472 10692947968 1309511456 13.782 96 5 10 1 1318553712 7930288 1111968
15:46:20 76953 8192000000 10268397968 10713665536 1330550800 13.972 97 15 30 1 1343311872 9620688 3140384
15:48:44 68024 8192000000 10262699024 10728427520 1367948432 14.309 100 9 18 0 1386652624 16477024 2227168
15:51:06 84145 8192000000 10252139696 10709413888 1329949824 13.967 97 18 36 0 1347024496 13003552 4071120
15:53:46 15969 8192000000 10274583488 10716504064 1350724944 14.154 99 32 64 0 1368180144 9542544 7912656
15:55:02 119076 8192000000 10251722976 10713231360 1302573648 13.719 95 14 28 0 1320124144 14506256 3044240
15:58:02 27100 8192000000 10280303136 10717085696 1344100400 14.094 98 45 89 1 1365231776 10992368 10139008
15:59:30 89337 8192000000 10287916496 10741760000 1335572528 14.017 97 13 25 0 1349825792 11557888 2695376
16:02:00 86021 8192000000 10291441312 10750894080 1376377936 14.384 100 15 31 0 1402554880 22961248 3215696
16:04:59 62510 8192000000 10282808544 10744221696 1370381056 14.330 99 21 41 0 1384923952 9970736 4572160
16:07:04 49651 8192000000 10271981968 10732609536 1380464160 14.421 100 7 14 0 1390754496 9145280 1145056
16:08:53 41027 8192000000 10284285680 10737168384 1376612032 14.386 99 7 14 0 1389426880 10404048 2410800
16:10:35 47096 8192000000 10268455056 10729086976 1377422400 14.393 100 6 12 0 1390642352 12183296 1036656
16:12:23 35304 8192000000 10309235504 10765295616 1400239088 14.597 100 21 42 2 1417265808 11830272 5196448
16:14:05 73201 8192000000 10310355536 10773295104 1355048240 14.193 96 20 40 0 1380297024 20150128 5098656
16:16:18 103374 8192000000 10308858384 10763005952 1344486624 14.098 96 17 34 0 1366746656 18749776 3510256
16:19:14 32316 8192000000 10320377552 10769252352 1396497888 14.564 100 33 66 0 1418780336 14809424 7473024
16:20:52 42076 8192000000 10293578864 10735476736 1340867008 14.065 96 35 71 3 1359403840 10247616 8289216
16:22:40 66838 8192000000 10290998384 10760429568 1359756032 14.235 98 15 30 0 1379766464 16639904 3370528
16:24:49 170582 8192000000 10268630384 10725707776 1322148752 13.896 96 25 50 0 1342503648 14741184 5613712
16:30:45 72930 8192000000 10224697440 10680926208 1294922800 13.649 95 8 16 1 1309230288 12533984 1773504
16:33:05 98072 8192000000 10253335136 10713255936 1311329904 13.798 96 8 16 0 1322418496 9271968 1816624
16:35:43 66910 8192000000 10263736368 10707185664 1314590608 13.828 96 20 40 0 1328050560 8960512 4499440
16:37:52 12471 8192000000 9079655072 9311035392 249250176 2.952 18 2 4 0 252439472 2848928 340368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-11-2021 16:39:22 to 21-11-2021 16:39:36.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-11-2021 16:39:37 to 21-11-2021 16: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          242846
MON$OLDEST_ACTIVE               242847
MON$OLDEST_SNAPSHOT             242847
MON$NEXT_TRANSACTION            242847
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                       2038880
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:126: 
:127: mscs-test-161.budzdorov.ru    Sun Nov 21 16:38:21 2021
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:129: 
--- end of diff output ---

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

21.11.21 16: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-11-21 16:39:43 No severe PSQL-related problems occured
21.11.21 16:39:44. End of report.