Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.09.2021 01:40
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 16191816 kB
MemAvailable 30824736 kB
Buffers 0 kB
Cached 15527104 kB
SwapCached 668 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-21 01:37:51
DTS_END                         2021-09-21 01:37:51.5310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39110
TRN_ID                          239739
ATT_ID                          659
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: 9006.20 19147 1080744
customer order: creation 171.04 351 20525
customer order: refuse 14.67 344 1760
order to supplier: creation 45.23 1292 5428
order to supplier: removal 3.19 91230 383
invoice (draft): creation 45.60 3765 5472
invoice (draft): removal 51.88 654 6225
invoice accept: apply 40.75 18226 4890
invoice accept: cancel 47.12 732 5654
customer reserve: creation 3715.58 183 445870
customer reserve: removal 4573.08 50 548769
realization accept: apply 83.05 195 9966
realization accept: cancel 87.85 100 10542
payment from customer: creation 58.20 133 6984
payment from customer: removal 5.76 86 691
payment to supplier: creation 48.65 136 5838
payment to supplier: removal 3.40 87 408
service: total inventory turnovers 4.13 1312 496
service: total monetary turnovers 6.20 50 744
service: aggregate perf. data 0.79 22494 95
service: refresh index statistics 0.03 241529 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-09-2021 01:40:26 to 21-09-2021 01:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3495
TEST_TIME 2 3792
TEST_TIME 3 4697
TEST_TIME 4 4834
TEST_TIME 5 4842
TEST_TIME 6 5356
TEST_TIME 7 5332
TEST_TIME 8 5375
TEST_TIME 9 5372
TEST_TIME 10 6046
TEST_TIME 11 6371
TEST_TIME 12 6374
TEST_TIME 13 6588
TEST_TIME 14 6561
TEST_TIME 15 7315
TEST_TIME 16 7793
TEST_TIME 17 7918
TEST_TIME 18 8100
TEST_TIME 19 8310
TEST_TIME 20 8678
TEST_TIME 21 9273
TEST_TIME 22 9490
TEST_TIME 23 9816
TEST_TIME 24 10109
TEST_TIME 25 10217
TEST_TIME 26 10452
TEST_TIME 27 10188
TEST_TIME 28 10042
TEST_TIME 29 9862
TEST_TIME 30 10183
TEST_TIME 31 10181
TEST_TIME 32 10329
TEST_TIME 33 10211
TEST_TIME 34 10050
TEST_TIME 35 10115
TEST_TIME 36 10027
TEST_TIME 37 9999
TEST_TIME 38 9846
TEST_TIME 39 9808
TEST_TIME 40 9675
TEST_TIME 41 9578
TEST_TIME 42 9512
TEST_TIME 43 9673
TEST_TIME 44 9615
TEST_TIME 45 9532
TEST_TIME 46 9434
TEST_TIME 47 9327
TEST_TIME 48 9152
TEST_TIME 49 9237
TEST_TIME 50 9222
TEST_TIME 51 9159
TEST_TIME 52 9136
TEST_TIME 53 9158
TEST_TIME 54 9018
TEST_TIME 55 8946
TEST_TIME 56 9021
TEST_TIME 57 9146
TEST_TIME 58 9240
TEST_TIME 59 9222
TEST_TIME 60 9242
TEST_TIME 61 9332
TEST_TIME 62 9342
TEST_TIME 63 9323
TEST_TIME 64 9366
TEST_TIME 65 9488
TEST_TIME 66 9447
TEST_TIME 67 9478
TEST_TIME 68 9478
TEST_TIME 69 9453
TEST_TIME 70 9342
TEST_TIME 71 9295
TEST_TIME 72 9252
TEST_TIME 73 9246
TEST_TIME 74 9204
TEST_TIME 75 9187
TEST_TIME 76 9182
TEST_TIME 77 9116
TEST_TIME 78 9117
TEST_TIME 79 9112
TEST_TIME 80 9110
TEST_TIME 81 9131
TEST_TIME 82 9090
TEST_TIME 83 9075
TEST_TIME 84 9215
TEST_TIME 85 9220
TEST_TIME 86 9170
TEST_TIME 87 9189
TEST_TIME 88 9186
TEST_TIME 89 9218
TEST_TIME 90 9278
TEST_TIME 91 9251
TEST_TIME 92 9280
TEST_TIME 93 9199
TEST_TIME 94 9130
TEST_TIME 95 9099
TEST_TIME 96 9046
TEST_TIME 97 9013
TEST_TIME 98 8990
TEST_TIME 99 9022
TEST_TIME 100 9032
TEST_TIME 101 9046
TEST_TIME 102 9038
TEST_TIME 103 9030
TEST_TIME 104 9063
TEST_TIME 105 9055
TEST_TIME 106 9026
TEST_TIME 107 9051
TEST_TIME 108 9118
TEST_TIME 109 9125
TEST_TIME 110 9153
TEST_TIME 111 9168
TEST_TIME 112 9153
TEST_TIME 113 9144
TEST_TIME 114 9070
TEST_TIME 115 9074
TEST_TIME 116 9061
TEST_TIME 117 9086
TEST_TIME 118 9042
TEST_TIME 119 9009
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-09-2021 01:40:29 to 21-09-2021 01:40:33.

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 1495113 1495113 0 0.00 0 19727 6 0 0
doc_list_biud 1495109 1495109 0 0.00 0 51096 24 0 0
sp_add_invoice_to_stock 5015 4890 125 2.49 32 170187 18226 0 125
sp_cancel_adding_invoice 5654 5654 0 0.00 3 67704 733 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 11675 344 0 0
sp_cancel_customer_reserve 548769 548769 0 0.00 1 51098 50 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4762 87 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2551 87 0 0
sp_cancel_supplier_invoice 6237 6225 12 0.19 2 21542 654 0 12
sp_cancel_supplier_order 383 383 0 0.00 6 896477 91230 0 0
sp_cancel_write_off 10542 10542 0 0.00 1 11102 100 0 0
sp_client_order 20525 20525 0 0.00 4 21681 352 0 0
sp_customer_reserve 445870 445870 0 0.00 0 68052 183 0 0
sp_fill_shopping_cart 31394 31394 0 0.00 1 15407 46 0 0
sp_fill_shopping_cart_clo_res 445864 445864 0 0.00 0 20772 14 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 39786 296 0 0
sp_kill_cost_storno 17295 17295 0 0.00 0 8648 52 0 0
sp_kill_qstorno_ret_qs2qd 1114688 1114688 0 0.00 0 51093 27 0 0
sp_kill_qty_storno 588180 588180 0 0.00 0 51096 60 0 0
sp_lock_selected_doc 20539 20539 0 0.00 0 9846 7 0 0
sp_make_cost_storno 27670 27670 0 0.00 0 14256 55 0 0
sp_multiply_rows_for_pdistr 27669 27669 0 0.00 0 19720 33 0 0
sp_multiply_rows_for_qdistr 20525 20525 0 0.00 0 16950 155 0 0
sp_pay_from_customer 6984 6984 0 0.00 4 19736 133 0 0
sp_pay_to_supplier 5838 5838 0 0.00 4 16813 137 0 0
sp_payment_common 12822 12822 0 0.00 1 16900 39 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 11669 311 0 0
sp_qd_handle_on_invoice_upd_sts 10535 10535 0 0.00 0 20617 329 0 0
sp_qd_handle_on_reserve_upd_sts 9966 9966 0 0.00 0 15330 60 0 0
sp_reserve_write_off 9966 9966 0 0.00 6 15715 196 0 0
sp_supplier_invoice 5472 5472 0 0.00 7 81446 3765 0 0
sp_supplier_order 5428 5428 0 0.00 5 37492 1293 0 0
srv_aggregate_perf_data 95 95 0 0.00 2893 606663 22495 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1166 124306 49870 0 0
srv_find_qd_qs_mism 1034702 1034702 0 0.00 0 13896 5 0 0
srv_make_invnt_saldo 496 496 0 0.00 41 71464 1312 0 0
srv_make_money_saldo 744 744 0 0.00 0 14455 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 56674 502565 241529 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 14928 189 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 4374 100 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 9039 21 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 4329 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 735 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 2008 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 2079 22 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 24504 1457 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1116 5 0 0
t$perf-norm:sp_client_order 20525 20525 0 0.00 0 9048 21 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 5678 16 0 0
t$perf-norm:sp_pay_from_customer 6984 6984 0 0.00 0 5770 9 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 6965 12 0 0
t$perf-norm:sp_reserve_write_off 9966 9966 0 0.00 0 7324 12 0 0
t$perf-norm:sp_supplier_invoice 5472 5472 0 0.00 0 5508 19 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 5760 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 80 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 509 10 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 809 22 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 927 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10010 9760 250 2.49 0 14004 22 0 250
v_all_customers 36918 36918 0 0.00 0 23 1 0 0
v_all_suppliers 29918 29918 0 0.00 0 21 1 0 0
v_all_wares 41050 41050 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 421 6 0 0
v_cancel_client_order 3520 3520 0 0.00 0 4257 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4720 15 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 760 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2338 9 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 7843 25 0 24
v_cancel_supplier_order 766 766 0 0.00 0 282 4 0 0
v_cancel_write_off 1964 1964 0 0.00 0 3182 17 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 1274 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 7182 19 0 0
v_max_id_ord_sup 5446 5446 0 0.00 0 5243 6 0 0
v_max_non_paid_invoice 1748 1748 0 0.00 0 1112 3 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 4974 6 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 11040 22 0 0
v_min_id_clo_res 987 987 0 0.00 0 15419 36 0 0
v_min_id_ord_sup 5446 5446 0 0.00 0 8004 8 0 0
v_min_non_paid_invoice 5838 5838 0 0.00 0 16801 25 0 0
v_min_non_paid_realizn 6984 6984 0 0.00 0 12353 26 0 0
v_our_firm 10228 10228 0 0.00 0 22 1 0 0
v_reserve_write_off 19932 19932 0 0.00 0 9081 9 0 0
x_lock_depdocs_on_canc_invoice 11479 11479 0 0.00 0 9495 102 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 4763 329 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 37484 1189 0 0
x_make_qstorno_1200_2000 5472 5472 0 0.00 4 81438 3607 0 0
x_make_qstorno_2100_3300 445870 445870 0 0.00 0 68051 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-09-2021 01:40:34 to 21-09-2021 01:40:38.

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
23:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:56 17928 8192000000 10383122432 10702495744 1434276448 14.899 100 88 177 0 1468627072 6037952 28312672
23:10:22 13633 8192000000 10447751616 10778406912 1499736592 15.474 100 88 176 0 1536777584 8939408 28101584
23:11:40 19470 8192000000 10484510224 10823258112 1536565344 15.794 100 91 182 0 1575408032 10495376 28347312
23:13:10 14649 8192000000 10575822464 10928398336 1619309472 16.504 100 80 159 1 1654411152 11783808 23317872
23:14:30 6015 8192000000 10623194336 10987446272 1681503616 17.030 100 77 154 1 1716334704 12470944 22360144
23:15:40 6640 8192000000 10699614464 11074764800 1762178528 17.702 100 86 172 0 1800240528 13504032 24557968
23:16:49 3695 8192000000 10731060688 11118194688 1792867872 17.955 100 81 160 2 1829035072 13930736 22236464
23:17:56 2032 8192000000 10792388464 11174363136 1851205824 18.432 100 93 187 2 1890506352 13835648 25464880
23:18:59 2102 8192000000 10808654896 11193470976 1869346848 18.579 100 93 184 0 1908870864 12955120 26568896
23:20:04 18524 8192000000 10796025424 11206500352 1873201648 18.610 100 51 102 0 1896950336 9805408 13943280
23:21:24 3675 8192000000 10810312768 11226451968 1891252144 18.756 100 48 96 1 1912708544 8469920 12986480
23:22:30 6278 8192000000 10797335056 11226648576 1886853536 18.720 100 13 26 0 1896278320 5826656 3598128
23:23:37 9756 8192000000 10812107920 11236130816 1895627312 18.791 100 25 50 0 1909071152 6208080 7235760
23:24:52 17917 8192000000 10810004784 11241156608 1896125760 18.795 100 19 36 1 1908023536 6034000 5863776
23:26:12 7937 8192000000 10802131328 11231305728 1880793792 18.672 99 21 42 1 1893009552 6108272 6107488
23:27:22 7569 8192000000 10787858960 11213336576 1830026080 18.260 97 28 56 1 1843983344 5392544 8564720
23:28:37 13832 8192000000 10751283104 11168968704 1739908432 17.518 93 30 60 2 1753629264 4670432 9050400
23:29:51 19014 8192000000 10711438848 11143786496 1728430224 17.422 94 21 40 3 1739429616 5291088 5708304
23:31:13 3948 8192000000 10719304800 11130769408 1783754016 17.880 99 38 76 0 1802097072 6938960 11404096
23:32:18 4686 8192000000 10741880784 11148398592 1800703760 18.020 99 37 74 2 1819304880 6384224 12216896
23:33:23 8644 8192000000 10750940736 11174367232 1834147632 18.293 100 15 30 1 1844646128 5514416 4984080
23:34:32 49151 8192000000 10769324512 11198676992 1857240304 18.481 100 24 48 1 1869226768 5113520 6872944
23:36:21 6328 8192000000 10782236256 11215925248 1870423232 18.588 100 29 57 1 1885097952 5976736 8697984
23:37:28 8408 8192000000 10823560960 11253571584 1903171024 18.852 100 16 32 0 1916744160 9151392 4421744
23:38:42 36380 8192000000 10862690608 11290075136 1929436640 19.062 100 42 83 3 1950883248 8859792 12586816
23:40:23 15101 8192000000 10897648064 11325829120 1941669744 19.160 99 45 90 0 1974693392 20106128 12917520
23:41:56 51445 8192000000 10903773952 11338551296 1964277952 19.340 100 53 106 0 1996928416 16424000 16226464
23:43:59 15442 8192000000 10921118112 11351367680 1963119872 19.331 99 61 120 1 1999872096 20120384 16631840
23:45:32 18521 8192000000 10942502432 11366801408 1982531600 19.485 100 60 120 1 2025675600 26218528 16925472
23:47:00 5660 8192000000 10926714576 11360436224 1964141744 19.339 99 60 118 2 2010243264 28549072 17552448
23:48:13 6247 8192000000 10951798256 11378634752 1992611472 19.564 100 70 140 4 2039916992 26825056 20480464
23:49:37 1321 8192000000 10930848416 11341950976 1956457200 19.278 99 75 150 1 2005880576 26357936 23065440
23:50:42 4065 8192000000 10939948528 11354898432 1966573872 19.358 100 79 157 1 2023380304 32638880 24167552
23:51:52 3625 8192000000 10954194176 11372945408 1971244736 19.395 100 67 129 0 2032365536 41146864 19973936
23:52:59 1162 8192000000 10955611600 11361619968 1972140976 19.402 100 93 183 2 2033991520 34664160 27186384
23:54:03 5602 8192000000 10898394128 11317100544 1925724704 19.033 99 72 146 1 1976937008 28420048 22792256
23:55:13 12804 8192000000 10881783760 11322396672 1905633104 18.872 98 33 65 1 1945454992 28562992 11258896
23:56:34 7163 8192000000 10917757056 11342094336 1945182656 19.188 100 62 124 2 2002644112 36688208 20773248
23:57:45 16583 8192000000 10908353328 11342290944 1928978016 19.059 99 31 62 3 1975030544 35480992 10571536
23:59:05 12358 8192000000 10883753376 11326570496 1940663392 19.152 100 28 56 4 1982683072 32493456 9526224
00:00:20 5506 8192000000 10919672160 11332386816 1945492752 19.191 100 62 123 0 1989359312 25133120 18733440
00:01:27 6895 8192000000 10904350848 11349934080 1959745680 19.304 100 35 71 1 1993621344 22248256 11627408
00:02:36 35845 8192000000 10899041760 11359993856 1948778240 19.217 99 29 58 0 1978344128 20633136 8932752
00:04:13 85762 8192000000 10921246512 11368898560 1966747856 19.360 100 16 33 2 1994351248 21985104 5618288
00:06:45 83014 8192000000 10919157664 11375140864 1967786528 19.368 100 31 62 0 2012668432 35395280 9486624
00:09:39 83309 8192000000 10876588736 11341938688 1897469776 18.806 96 13 25 1 1915836032 15274272 3091984
00:12:02 49665 8192000000 10892771248 11361964032 1936711232 19.121 98 9 18 1 1950482144 10974016 2796896
00:13:53 48331 8192000000 10896695408 11357261824 1969593376 19.382 100 13 26 0 1985326096 11825088 3907632
00:15:47 50966 8192000000 10903938080 11383799808 1985886880 19.511 100 22 44 1 2001499904 9250912 6362112
00:17:53 82727 8192000000 10926362464 11389079552 1964592240 19.343 98 29 58 1 1986599280 13648000 8359040
00:20:17 39479 8192000000 10904318016 11390734336 1987372576 19.523 100 13 26 0 2007828544 16489904 3966064
00:21:59 37022 8192000000 10929575312 11386167296 1994454384 19.579 100 19 38 0 2006343136 6683120 5205632
00:23:40 65895 8192000000 10917975280 11393142784 1987323328 19.523 99 13 26 0 2000327072 9318016 3685728
00:25:57 27185 8192000000 10927263456 11390578688 1981551440 19.477 99 37 74 0 2006181392 13763664 10866288
00:27:28 76794 8192000000 10908092992 11387457536 1941711280 19.160 97 14 26 1 1956633216 11221808 3700128
00:29:51 55715 8192000000 10916592336 11393933312 1981940048 19.480 99 11 22 0 1999663936 14606880 3117008
00:31:48 80763 8192000000 10926091280 11405369344 1972088464 19.402 99 15 31 1 1988988160 12768064 4131632
00:34:09 66103 8192000000 10900463888 11380064256 1909408512 18.902 96 11 22 1 1934950560 22111936 3430112
00:37:01 51569 8192000000 10884723648 11355451392 1875794512 18.631 95 17 34 0 1892309920 12005728 4509680
00:38:53 33407 8192000000 10891978944 11331719168 1904610272 18.863 97 31 62 0 1923920768 11264336 8046160
00:40:28 53971 8192000000 10902165680 11351629824 1915672144 18.952 97 30 60 1 1939847584 15219008 8956432
00:42:24 90440 8192000000 10884561344 11341877248 1894414384 18.781 96 12 24 0 1912994592 15377104 3203104
00:45:00 117175 8192000000 10870452064 11342372864 1843228144 18.367 94 20 40 0 1864003792 14761472 6014176
00:48:32 40210 8192000000 10880205984 11335303168 1925521088 19.031 98 24 48 1 1942989632 11048688 6419856
00:50:13 41255 8192000000 10846264112 11321712640 1903542416 18.855 98 17 34 0 1923804912 15238144 5024352
00:51:59 82561 8192000000 10838081520 11311202304 1844476624 18.377 95 8 16 0 1858936640 12087728 2372288
00:54:23 77995 8192000000 10834903584 11319435264 1889871968 18.745 98 4 8 0 1904753408 13843984 1037456
00:56:42 58782 8192000000 10850156224 11327340544 1889598496 18.743 98 17 34 0 1909175824 14755904 4821424
00:58:41 69064 8192000000 10877238784 11346235392 1924467136 19.023 99 18 36 0 1944592352 14983280 5141936
01:00:51 64545 8192000000 10847197424 11319603200 1909394736 18.902 98 11 22 0 1929396160 16849888 3151536
01:02:57 30157 8192000000 10896268976 11356495872 1954325872 19.261 100 23 45 1 1973837264 13472080 6039312
01:04:27 86266 8192000000 10881284704 11345776640 1909216736 18.900 97 29 58 0 1929441952 12574752 7650464
01:06:54 57715 8192000000 10898111600 11378487296 1955029568 19.267 99 14 25 1 1973408416 14970976 3407872
01:08:53 64053 8192000000 10920120576 11394859008 1993736656 19.573 100 29 58 3 2015036640 13621312 7678672
01:10:58 113166 8192000000 10920474640 11393323008 1964911632 19.345 98 32 64 2 1981005648 7005664 9088352
01:15:24 47098 8192000000 10903556288 11391250432 1976101808 19.434 100 8 16 0 1992170000 13906736 2161456
01:17:37 74987 8192000000 10914584208 11403513856 1973321760 19.412 99 11 22 1 1997969696 21084080 3563856
01:20:10 100716 8192000000 10864921680 11333808128 1903182928 18.852 96 13 26 0 1917454672 10502400 3769344
01:22:51 37703 8192000000 10898929936 11362902016 1968759136 19.376 100 27 54 1 1985835088 9586688 7489264
01:24:32 91768 8192000000 10890874576 11368275968 1880655056 18.670 96 8 16 0 1898824752 15880784 2288912
01:27:04 124306 8192000000 10905010720 11358887936 1889531840 18.742 96 10 20 0 1905621936 13592304 2497792
01:30:14 69462 8192000000 10879040192 11339431936 1918061088 18.971 98 29 58 1 1934351312 8281136 8009088
01:32:56 36092 8192000000 10858423392 11331633152 1905501840 18.871 98 17 34 0 1924489920 14271216 4716864
01:34:43 63252 8192000000 10847250272 11332513792 1913623200 18.936 99 10 20 0 1925556976 9316432 2617344
01:36:47 69796 8192000000 10095529392 10495848448 1203202352 12.806 62 11 22 1 1217902912 11351040 3349520

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-09-2021 01:40:40 to 21-09-2021 01:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-09-2021 01:40:55 to 21-09-2021 01:40:57.

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          239738
MON$OLDEST_ACTIVE               240691
MON$OLDEST_SNAPSHOT             240691
MON$NEXT_TRANSACTION            240691
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                       2018816
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Sep 21 01:38:08 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Sep 21 01:38:15 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
--- end of diff output ---

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

21.09.21 01:40:58. 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-21 01:41:00 No severe PSQL-related problems occured
21.09.21 01:41:02. End of report.