Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.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 16402380 kB
MemAvailable 31018640 kB
Buffers 0 kB
Cached 15250512 kB
SwapCached 2580 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-08-05 22:37:41
DTS_END                         2021-08-05 22:37:41.8830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47014
TRN_ID                          249011
ATT_ID                          720
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9057.53 20594 1086904
customer order: creation 180.42 381 21650
customer order: refuse 14.64 317 1757
order to supplier: creation 47.18 1279 5662
order to supplier: removal 3.43 102800 411
invoice (draft): creation 48.17 3354 5780
invoice (draft): removal 50.24 870 6029
invoice accept: apply 43.74 19553 5249
invoice accept: cancel 44.79 804 5375
customer reserve: creation 3977.72 198 477326
customer reserve: removal 4341.32 64 520958
realization accept: apply 86.68 178 10401
realization accept: cancel 81.58 121 9790
payment from customer: creation 62.55 112 7506
payment from customer: removal 6.21 90 745
payment to supplier: creation 52.01 139 6241
payment to supplier: removal 3.88 127 466
service: total inventory turnovers 4.83 1197 580
service: total monetary turnovers 7.24 26 869
service: aggregate perf. data 0.88 19604 105
service: refresh index statistics 0.03 260665 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 22:38:45 to 05-08-2021 22:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1589
TEST_TIME 2 3229
TEST_TIME 3 4349
TEST_TIME 4 4573
TEST_TIME 5 4594
TEST_TIME 6 4684
TEST_TIME 7 4975
TEST_TIME 8 4952
TEST_TIME 9 5030
TEST_TIME 10 5146
TEST_TIME 11 5390
TEST_TIME 12 5435
TEST_TIME 13 5762
TEST_TIME 14 6067
TEST_TIME 15 6042
TEST_TIME 16 6109
TEST_TIME 17 6196
TEST_TIME 18 6294
TEST_TIME 19 6488
TEST_TIME 20 6749
TEST_TIME 21 7314
TEST_TIME 22 7628
TEST_TIME 23 7626
TEST_TIME 24 8064
TEST_TIME 25 8810
TEST_TIME 26 8927
TEST_TIME 27 9996
TEST_TIME 28 10187
TEST_TIME 29 10353
TEST_TIME 30 10171
TEST_TIME 31 10105
TEST_TIME 32 9833
TEST_TIME 33 10064
TEST_TIME 34 10131
TEST_TIME 35 10094
TEST_TIME 36 10000
TEST_TIME 37 9948
TEST_TIME 38 9901
TEST_TIME 39 9828
TEST_TIME 40 9791
TEST_TIME 41 9773
TEST_TIME 42 9687
TEST_TIME 43 9599
TEST_TIME 44 9571
TEST_TIME 45 9434
TEST_TIME 46 9417
TEST_TIME 47 9449
TEST_TIME 48 9425
TEST_TIME 49 9465
TEST_TIME 50 9444
TEST_TIME 51 9432
TEST_TIME 52 9324
TEST_TIME 53 9438
TEST_TIME 54 9405
TEST_TIME 55 9445
TEST_TIME 56 9554
TEST_TIME 57 9532
TEST_TIME 58 9439
TEST_TIME 59 9554
TEST_TIME 60 9492
TEST_TIME 61 9709
TEST_TIME 62 9659
TEST_TIME 63 9630
TEST_TIME 64 9578
TEST_TIME 65 9536
TEST_TIME 66 9510
TEST_TIME 67 9458
TEST_TIME 68 9443
TEST_TIME 69 9446
TEST_TIME 70 9381
TEST_TIME 71 9379
TEST_TIME 72 9285
TEST_TIME 73 9223
TEST_TIME 74 9151
TEST_TIME 75 9194
TEST_TIME 76 9144
TEST_TIME 77 9109
TEST_TIME 78 9186
TEST_TIME 79 9138
TEST_TIME 80 9144
TEST_TIME 81 9189
TEST_TIME 82 9177
TEST_TIME 83 9289
TEST_TIME 84 9384
TEST_TIME 85 9349
TEST_TIME 86 9319
TEST_TIME 87 9394
TEST_TIME 88 9379
TEST_TIME 89 9332
TEST_TIME 90 9267
TEST_TIME 91 9235
TEST_TIME 92 9232
TEST_TIME 93 9197
TEST_TIME 94 9162
TEST_TIME 95 9129
TEST_TIME 96 9091
TEST_TIME 97 9185
TEST_TIME 98 9192
TEST_TIME 99 9183
TEST_TIME 100 9218
TEST_TIME 101 9215
TEST_TIME 102 9213
TEST_TIME 103 9224
TEST_TIME 104 9224
TEST_TIME 105 9214
TEST_TIME 106 9250
TEST_TIME 107 9242
TEST_TIME 108 9175
TEST_TIME 109 9155
TEST_TIME 110 9149
TEST_TIME 111 9096
TEST_TIME 112 9116
TEST_TIME 113 9077
TEST_TIME 114 9088
TEST_TIME 115 9055
TEST_TIME 116 9038
TEST_TIME 117 9074
TEST_TIME 118 9103
TEST_TIME 119 9075
TEST_TIME 120 9058

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-08-2021 22:38:49 to 05-08-2021 22:38:52.

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 1528525 1528525 0 0.00 0 17253 7 0 0
doc_list_biud 1528525 1528525 0 0.00 0 24384 28 0 0
sp_add_invoice_to_stock 5315 5249 66 1.24 20 239668 19553 0 66
sp_cancel_adding_invoice 5375 5375 0 0.00 4 78329 804 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 7226 317 0 0
sp_cancel_customer_reserve 520958 520958 0 0.00 1 20926 64 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 6752 91 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 2 9393 128 0 0
sp_cancel_supplier_invoice 6036 6029 7 0.11 1 24388 871 0 7
sp_cancel_supplier_order 411 411 0 0.00 6 1301726 102800 0 0
sp_cancel_write_off 9790 9790 0 0.00 1 11044 121 0 0
sp_client_order 21650 21650 0 0.00 3 20029 381 0 0
sp_customer_reserve 477326 477326 0 0.00 0 47741 199 0 0
sp_fill_shopping_cart 33076 33076 0 0.00 1 14343 37 0 0
sp_fill_shopping_cart_clo_res 477325 477325 0 0.00 0 16934 15 0 0
sp_get_clo_for_invoice 5246 5246 0 0.00 0 29613 328 0 0
sp_kill_cost_storno 16376 16376 0 0.00 0 10236 58 0 0
sp_kill_qstorno_ret_qs2qd 1058146 1058146 0 0.00 0 24382 35 0 0
sp_kill_qty_storno 559967 559967 0 0.00 0 24383 74 0 0
sp_lock_selected_doc 21622 21622 0 0.00 0 7136 5 0 0
sp_make_cost_storno 29394 29394 0 0.00 0 17244 48 0 0
sp_multiply_rows_for_pdistr 29394 29394 0 0.00 0 8536 31 0 0
sp_multiply_rows_for_qdistr 21650 21650 0 0.00 0 18734 172 0 0
sp_pay_from_customer 7506 7506 0 0.00 4 11214 113 0 0
sp_pay_to_supplier 6241 6241 0 0.00 5 13869 140 0 0
sp_payment_common 13747 13747 0 0.00 1 11208 29 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 6105 286 0 0
sp_qd_handle_on_invoice_upd_sts 10621 10621 0 0.00 0 21678 309 0 0
sp_qd_handle_on_reserve_upd_sts 10401 10401 0 0.00 0 13119 59 0 0
sp_reserve_write_off 10401 10401 0 0.00 6 15944 179 0 0
sp_supplier_invoice 5780 5780 0 0.00 5 84595 3355 0 0
sp_supplier_order 5662 5662 0 0.00 5 51347 1279 0 0
srv_aggregate_perf_data 105 105 0 0.00 3034 180423 19605 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1457 113642 38543 0 0
srv_find_qd_qs_mism 1036775 1036775 0 0.00 0 8503 7 0 0
srv_make_invnt_saldo 580 580 0 0.00 29 51500 1198 0 0
srv_make_money_saldo 869 869 0 0.00 1 4101 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 137058 516436 260666 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 7942 153 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 0 3547 78 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 7552 18 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 1814 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 3015 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 5460 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 5266 30 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 21319 1397 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 937 4 0 0
t$perf-norm:sp_client_order 21650 21650 0 0.00 0 10784 17 0 0
t$perf-norm:sp_customer_reserve 1072 1072 0 0.00 0 4872 15 0 0
t$perf-norm:sp_pay_from_customer 7506 7506 0 0.00 0 5065 9 0 0
t$perf-norm:sp_pay_to_supplier 6241 6241 0 0.00 0 6712 10 0 0
t$perf-norm:sp_reserve_write_off 10401 10401 0 0.00 0 6667 12 0 0
t$perf-norm:sp_supplier_invoice 5780 5780 0 0.00 0 9891 18 0 0
t$perf-norm:sp_supplier_order 5662 5662 0 0.00 0 3693 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 654 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 654 17 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 1157 22 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 2334 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 9 5 0 0
v_add_invoice_to_stock 10624 10492 132 1.24 0 7517 17 0 132
v_all_customers 39468 39468 0 0.00 0 46 1 0 0
v_all_suppliers 31746 31746 0 0.00 0 15 1 0 0
v_all_wares 43298 43298 0 0.00 0 175 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 4613 17 0 0
v_cancel_client_order 3514 3514 0 0.00 0 2467 4 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 987 5 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 1572 5 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 9383 15 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 2092 14 0 14
v_cancel_supplier_order 822 822 0 0.00 0 2680 12 0 0
v_cancel_write_off 1978 1978 0 0.00 0 9505 21 0 0
v_max_id_clo_ord 5662 5662 0 0.00 0 14312 4 0 0
v_max_id_clo_res 1072 1072 0 0.00 0 2472 15 0 0
v_max_id_ord_sup 5765 5765 0 0.00 0 2827 3 0 0
v_max_non_paid_invoice 1795 1795 0 0.00 0 3022 5 0 0
v_max_non_paid_realizn 4035 4035 0 0.00 0 3259 5 0 0
v_min_id_clo_ord 5662 5662 0 0.00 0 2406 5 0 0
v_min_id_clo_res 1072 1072 0 0.00 0 5326 13 0 0
v_min_id_ord_sup 5765 5765 0 0.00 0 4890 6 0 0
v_min_non_paid_invoice 6241 6241 0 0.00 0 8405 19 0 0
v_min_non_paid_realizn 7506 7506 0 0.00 0 11203 19 0 0
v_our_firm 10772 10772 0 0.00 0 14 1 0 0
v_reserve_write_off 20802 20802 0 0.00 0 5994 8 0 0
x_lock_depdocs_on_canc_invoice 11005 11005 0 0.00 0 17307 123 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 23793 427 0 0
x_make_qstorno_1000_1200 5662 5662 0 0.00 1 51338 1202 0 0
x_make_qstorno_1200_2000 5780 5780 0 0.00 2 83918 3214 0 0
x_make_qstorno_2100_3300 477326 477326 0 0.00 0 47724 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 22:38:53 to 05-08-2021 22:38:57.

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:45 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:47 32370 8192000000 10370287360 10687537152 1413796272 14.718 100 88 176 1 1448122096 5866048 28459776
20:10:38 18588 8192000000 10446160064 10776039424 1500659200 15.482 100 90 180 1 1537964576 8997872 28307504
20:12:03 10217 8192000000 10502016784 10851315712 1562495968 16.018 100 66 132 0 1592213760 9795072 19922720
20:13:19 9751 8192000000 10610555888 10972545024 1666632096 16.905 100 81 162 0 1702584736 11548320 24404320
20:14:40 8341 8192000000 10679001920 11052589056 1741336352 17.530 100 81 160 2 1777913232 13042640 23534240
20:15:50 4135 8192000000 10727365264 11105058816 1787475584 17.911 100 82 164 1 1824731008 13791824 23463600
20:16:56 3828 8192000000 10772351008 11156492288 1818350800 18.164 100 80 160 1 1853820896 12943472 22526624
20:18:01 1830 8192000000 10803630560 11186171904 1857453312 18.483 100 92 184 2 1896673648 13607776 25612560
20:19:04 30629 8192000000 10790447408 11201036288 1870216832 18.586 100 48 97 0 1891821008 7139216 14464960
20:20:35 11438 8192000000 10781183040 11205730304 1869715072 18.582 100 16 32 0 1880042384 5891072 4436240
20:21:48 5698 8192000000 10787339920 11217399808 1880381056 18.668 100 21 40 2 1893340640 6681632 6277952
20:22:54 7158 8192000000 10803455568 11233468416 1895637984 18.791 100 22 44 2 1909110320 6169440 7302896
20:24:03 56336 8192000000 10858907296 11266441216 1913233072 18.933 100 53 106 2 1934683920 6093088 15357760
20:25:59 4741 8192000000 10830724256 11259179008 1907707632 18.888 100 27 55 0 1920655008 5607600 7339776
20:27:07 8795 8192000000 10770281664 11200081920 1817067872 18.154 96 19 36 2 1827870112 5150560 5651680
20:28:17 53885 8192000000 10758995632 11187658752 1766809248 17.741 94 33 64 2 1781625392 5390032 9426112
20:30:17 7292 8192000000 10683839808 11088527360 1721513456 17.365 95 18 36 1 1732136032 5494240 5128336
20:31:25 5795 8192000000 10723454992 11141263360 1805073408 18.056 100 23 46 0 1817126672 5450256 6603008
20:32:33 59806 8192000000 10741942272 11178266624 1838504224 18.329 100 31 62 3 1854133264 5833696 9795344
20:34:34 7310 8192000000 10768861424 11199930368 1859812688 18.502 100 28 57 1 1873919744 5705296 8401760
20:35:42 12705 8192000000 10777465056 11214368768 1869541232 18.581 100 11 20 1 1877469968 5329840 2598896
20:36:56 13917 8192000000 10790531376 11226857472 1878149520 18.650 100 9 18 2 1885602832 5224192 2229120
20:38:12 42572 8192000000 10842206160 11261333504 1916462352 18.958 100 42 84 1 1934198320 5855024 11880944
20:40:04 14170 8192000000 10866687440 11289985024 1933664800 19.096 100 58 116 2 1961510160 11747264 16098096
20:41:29 8523 8192000000 10872478912 11304984576 1940012464 19.147 100 56 109 1 1970122448 14116992 15992992
20:42:42 10599 8192000000 10889057792 11320311808 1954804848 19.265 100 44 89 2 1980358736 12862448 12691440
20:43:59 8674 8192000000 10905990400 11335684096 1966791088 19.360 100 57 114 2 2001133552 17484192 16858272
20:45:15 47684 8192000000 10915968720 11368873984 1988510112 19.532 100 37 74 1 2019527280 19585104 11432064
20:47:23 6594 8192000000 10929551312 11378581504 1981004688 19.473 99 28 55 1 2012826976 23051968 8770320
20:48:35 1793 8192000000 10967845488 11396730880 1992213184 19.561 99 71 140 0 2040821936 26936000 21672752
20:49:43 6250 8192000000 10961462192 11392745472 1996599536 19.596 100 54 108 0 2043857872 30667856 16590480
20:50:51 1823 8192000000 10962387904 11383840768 1980934800 19.472 99 73 146 2 2036216832 33284208 21997824
20:51:58 1931 8192000000 10947010448 11369857024 1977448944 19.444 99 74 148 3 2033753680 33307232 22997504
20:53:03 7574 8192000000 10965077152 11392958464 1996619904 19.596 100 63 127 2 2055861936 40211648 19030384
20:54:17 3643 8192000000 10964417344 11387977728 1976004048 19.433 99 71 136 1 2038959152 41647088 21308016
20:55:28 5059 8192000000 10960720320 11392724992 1969277280 19.380 99 61 122 1 2035063296 46391440 19394576
20:56:40 1456 8192000000 10995915200 11399819264 1989328864 19.538 100 94 188 0 2072062080 53194224 29538992
20:57:48 5095 8192000000 10976896048 11389259776 1970112576 19.386 99 65 130 0 2044939920 53992816 20834528
20:58:57 12855 8192000000 10936232576 11368013824 1921422160 18.998 98 48 96 1 1992241680 55043232 15776288
21:00:21 4824 8192000000 10942556752 11366871040 1942536784 19.167 100 64 128 3 2025293504 62439344 20317376
21:01:37 14083 8192000000 10927290592 11365433344 1924030288 19.019 99 34 67 1 1999239680 63492288 11717104
21:03:04 20509 8192000000 10894201840 11345653760 1925130560 19.028 100 20 39 0 1978961136 46942160 6888416
21:04:27 41319 8192000000 10877350752 11349983232 1932541616 19.087 100 12 24 0 1975438208 38620016 4276576
21:06:31 31605 8192000000 10881335232 11333910528 1907472384 18.886 98 12 24 3 1924712896 14030848 3209664
21:08:39 90190 8192000000 10891017104 11358810112 1964779360 19.344 100 35 70 2 1988299232 12931856 10588016
21:11:19 94258 8192000000 10885125984 11345653760 1883103216 18.690 95 17 34 0 1899548368 11822192 4622960
21:13:58 6883 8192000000 10874814048 11339513856 1953949088 19.258 100 10 20 1 1966252672 9508912 2794672
21:15:05 23454 8192000000 10909087968 11372195840 1984467040 19.500 100 13 26 2 1994819104 6787408 3564656
21:16:35 14771 8192000000 10938046528 11386122240 2004887840 19.661 100 55 111 1 2029851680 9205984 15757856
21:17:57 41102 8192000000 10915051840 11387047936 1966826672 19.360 98 16 32 0 1980765232 9735680 4202880
21:19:38 19524 8192000000 10920190944 11393314816 1986342400 19.515 99 11 22 2 2000657920 10681984 3633536
21:20:59 21166 8192000000 10931159088 11390640128 1994856720 19.582 100 34 67 0 2017860256 13505904 9497632
21:22:24 59627 8192000000 10937061168 11403272192 2003574816 19.651 100 16 32 0 2023599328 14977632 5046880
21:24:44 49054 8192000000 10946695744 11411767296 1993609744 19.572 99 28 55 1 2013829616 12306080 7913792
21:26:36 68777 8192000000 10893339392 11385884672 1958190672 19.292 99 9 18 0 1977042768 16519568 2332528
21:28:46 59526 8192000000 10896486832 11365883904 1931672784 19.080 97 19 36 1 1946135024 9577952 4884288
21:30:47 27429 8192000000 10908407808 11371655168 1954165808 19.260 98 25 50 3 1972091792 10682176 7243808
21:32:18 51236 8192000000 10897420736 11372539904 1912102624 18.924 96 30 60 1 1938835360 17374720 9358016
21:34:09 45816 8192000000 10885713712 11342430208 1875794784 18.631 95 24 48 0 1891899904 9739632 6365488
21:36:01 41487 8192000000 10881817504 11351281664 1869670368 18.582 95 35 70 2 1899970384 20287680 10012336
21:37:45 78107 8192000000 10897329808 11371835392 1931516720 19.079 98 17 34 0 1955939824 19671552 4751552
21:40:23 13816 8192000000 10903954944 11352944640 1936230240 19.117 99 54 108 1 1964255568 12712144 15313184
21:41:39 84746 8192000000 10884664384 11347275776 1922084512 19.004 98 30 57 2 1944636432 14149600 8402320
21:44:05 10853 8192000000 10858909984 11314135040 1910435104 18.910 98 39 76 0 1929775168 8833744 10506320
21:45:16 79668 8192000000 10875420832 11363409920 1951880928 19.241 100 8 16 0 1966849120 12777392 2190800
21:47:56 60423 8192000000 10858521360 11344117760 1919686112 18.984 98 9 18 0 1929925808 7890368 2349328
21:50:05 68582 8192000000 10874668128 11345707008 1917422448 18.966 98 24 48 1 1932880240 8249568 7208224
21:52:14 44782 8192000000 10855000288 11344261120 1918498464 18.975 98 15 26 3 1934917200 11760912 4657824
21:54:43 36942 8192000000 10880306336 11356160000 1954929328 19.266 100 29 58 0 1975314144 11717280 8667536
21:56:29 20411 8192000000 10889012816 11338457088 1927490912 19.047 99 38 76 0 1949019200 10608160 10920128
21:57:56 55558 8192000000 10909045904 11365302272 1954538768 19.263 100 18 36 1 1976873008 17159584 5174656
21:59:52 78294 8192000000 10896058960 11395833856 1953840784 19.257 99 17 34 1 1979576608 20646432 5089392
22:02:12 89145 8192000000 10913139888 11375816704 1934247104 19.101 98 22 44 1 1953729888 12916400 6566384
22:06:05 22914 8192000000 10938770224 11382378496 1970134288 19.387 99 40 79 0 1988635360 7889184 10611888
22:07:30 77393 8192000000 10905828656 11380035584 1967112352 19.363 99 31 62 3 1983049264 7549424 8387488
22:11:34 72355 8192000000 10898471168 11387138048 1981914032 19.480 100 9 18 1 1992051312 7601200 2536080
22:14:02 50565 8192000000 10891711136 11375546368 1935006096 19.107 97 21 43 0 1950703408 9624720 6072592
22:16:03 24381 8192000000 10893805680 11355234304 1948614928 19.215 99 36 72 3 1970180544 11812128 9753488
22:17:29 45523 8192000000 10890937648 11359887360 1918867968 18.978 97 22 44 1 1936607536 11903408 5836160
22:19:57 35645 8192000000 10917072160 11365842944 1967569424 19.366 100 36 71 2 1985541424 8056384 9915616
22:21:34 21141 8192000000 10885360144 11341615104 1918992368 18.979 98 28 55 1 1935337072 8627248 7717456
22:23:01 83202 8192000000 10874845648 11334721536 1800860000 18.021 92 30 60 0 1822706000 13597584 8248416
22:26:48 46487 8192000000 10865120048 11339481088 1920631232 18.992 99 20 39 1 1941435184 14986512 5817440
22:28:38 72186 8192000000 10855125280 11341750272 1911831680 18.921 98 17 34 2 1931838224 15101728 4904816
22:31:21 113642 8192000000 10848801376 11322634240 1858199248 18.489 96 17 33 1 1874505936 11255232 5051456
22:34:20 80230 8192000000 10828521376 11305152512 1786006672 17.899 93 13 26 0 1804704224 14883504 3814048
22:36:45 25328 8192000000 10868748192 11324227584 1927997552 19.051 100 32 64 1 1944672112 7742128 8932432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 22:38:59 to 05-08-2021 22:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-08-2021 22:39:15 to 05-08-2021 22:39:17.

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          246477
MON$OLDEST_ACTIVE               249136
MON$OLDEST_SNAPSHOT             249136
MON$NEXT_TRANSACTION            249136
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                       2021888
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:216: 
:217: mscs-test-161.budzdorov.ru    Thu Aug  5 22:37:46 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Thu Aug  5 22:37:49 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
:224: 
:225: mscs-test-161.budzdorov.ru    Thu Aug  5 22:37:52 2021
:226:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:227: 
--- end of diff output ---

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

05.08.21 22:39:18. 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-08-05 22:39:21 No severe PSQL-related problems occured
05.08.21 22:39:22. End of report.