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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16203968 kB
MemAvailable 30745520 kB
Buffers 0 kB
Cached 15517928 kB
SwapCached 4684 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-10-04 19:37:52
DTS_END                         2021-10-04 19:37:52.0290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55544
TRN_ID                          229251
ATT_ID                          691
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: 9012.63 24909 1081516
customer order: creation 160.68 376 19281
customer order: refuse 14.02 363 1682
order to supplier: creation 42.77 1275 5132
order to supplier: removal 3.21 111067 385
invoice (draft): creation 42.64 3441 5117
invoice (draft): removal 52.84 888 6341
invoice accept: apply 38.96 18862 4675
invoice accept: cancel 47.88 738 5746
customer reserve: creation 3674.83 183 440980
customer reserve: removal 4642.85 59 557142
realization accept: apply 78.80 184 9456
realization accept: cancel 92.13 111 11055
payment from customer: creation 55.64 108 6677
payment from customer: removal 5.05 93 606
payment to supplier: creation 45.78 146 5494
payment to supplier: removal 3.12 84 374
service: total inventory turnovers 4.55 1021 546
service: total monetary turnovers 6.10 23 732
service: aggregate perf. data 0.76 24795 91
service: refresh index statistics 0.03 334370 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-10-2021 19:39:40 to 04-10-2021 19:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1554
TEST_TIME 2 2852
TEST_TIME 3 2415
TEST_TIME 4 2473
TEST_TIME 5 2825
TEST_TIME 6 3183
TEST_TIME 7 3316
TEST_TIME 8 3894
TEST_TIME 9 4078
TEST_TIME 10 4293
TEST_TIME 11 4297
TEST_TIME 12 4787
TEST_TIME 13 5622
TEST_TIME 14 5805
TEST_TIME 15 5973
TEST_TIME 16 6514
TEST_TIME 17 6670
TEST_TIME 18 7845
TEST_TIME 19 8090
TEST_TIME 20 7870
TEST_TIME 21 8191
TEST_TIME 22 8310
TEST_TIME 23 8235
TEST_TIME 24 8703
TEST_TIME 25 8574
TEST_TIME 26 8786
TEST_TIME 27 9317
TEST_TIME 28 9903
TEST_TIME 29 10497
TEST_TIME 30 10331
TEST_TIME 31 10087
TEST_TIME 32 9997
TEST_TIME 33 9879
TEST_TIME 34 9905
TEST_TIME 35 9875
TEST_TIME 36 9863
TEST_TIME 37 9885
TEST_TIME 38 9745
TEST_TIME 39 9759
TEST_TIME 40 9577
TEST_TIME 41 9561
TEST_TIME 42 9495
TEST_TIME 43 9353
TEST_TIME 44 9255
TEST_TIME 45 9381
TEST_TIME 46 9333
TEST_TIME 47 9426
TEST_TIME 48 9450
TEST_TIME 49 9351
TEST_TIME 50 9283
TEST_TIME 51 9203
TEST_TIME 52 9285
TEST_TIME 53 9236
TEST_TIME 54 9216
TEST_TIME 55 9256
TEST_TIME 56 9203
TEST_TIME 57 9355
TEST_TIME 58 9371
TEST_TIME 59 9363
TEST_TIME 60 9448
TEST_TIME 61 9500
TEST_TIME 62 9453
TEST_TIME 63 9412
TEST_TIME 64 9449
TEST_TIME 65 9526
TEST_TIME 66 9422
TEST_TIME 67 9340
TEST_TIME 68 9321
TEST_TIME 69 9248
TEST_TIME 70 9228
TEST_TIME 71 9245
TEST_TIME 72 9213
TEST_TIME 73 9218
TEST_TIME 74 9210
TEST_TIME 75 9147
TEST_TIME 76 9124
TEST_TIME 77 9112
TEST_TIME 78 9079
TEST_TIME 79 9077
TEST_TIME 80 9051
TEST_TIME 81 9113
TEST_TIME 82 9113
TEST_TIME 83 9133
TEST_TIME 84 9169
TEST_TIME 85 9192
TEST_TIME 86 9236
TEST_TIME 87 9289
TEST_TIME 88 9276
TEST_TIME 89 9276
TEST_TIME 90 9331
TEST_TIME 91 9362
TEST_TIME 92 9273
TEST_TIME 93 9208
TEST_TIME 94 9199
TEST_TIME 95 9177
TEST_TIME 96 9162
TEST_TIME 97 9146
TEST_TIME 98 9110
TEST_TIME 99 9083
TEST_TIME 100 9046
TEST_TIME 101 9025
TEST_TIME 102 8958
TEST_TIME 103 8930
TEST_TIME 104 8938
TEST_TIME 105 8967
TEST_TIME 106 8959
TEST_TIME 107 8945
TEST_TIME 108 9012
TEST_TIME 109 9027
TEST_TIME 110 9048
TEST_TIME 111 9054
TEST_TIME 112 9074
TEST_TIME 113 9082
TEST_TIME 114 9087
TEST_TIME 115 9104
TEST_TIME 116 9126
TEST_TIME 117 9101
TEST_TIME 118 9040
TEST_TIME 119 9066
TEST_TIME 120 9016

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2021 19:39:44 to 04-10-2021 19:39:47.

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 1489450 1489450 0 0.00 0 28678 6 0 0
doc_list_biud 1489449 1489449 0 0.00 0 49318 28 0 0
sp_add_invoice_to_stock 4769 4675 94 1.97 41 175310 18862 0 94
sp_cancel_adding_invoice 5746 5746 0 0.00 5 83032 739 0 0
sp_cancel_client_order 1682 1682 0 0.00 4 13195 364 0 0
sp_cancel_customer_reserve 557142 557142 0 0.00 1 49334 59 0 0
sp_cancel_pay_from_customer 606 606 0 0.00 3 10895 93 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 2 4725 84 0 0
sp_cancel_supplier_invoice 6347 6341 6 0.09 2 13341 888 0 6
sp_cancel_supplier_order 385 385 0 0.00 8 1033279 111068 0 0
sp_cancel_write_off 11055 11055 0 0.00 1 17587 111 0 0
sp_client_order 19281 19281 0 0.00 3 21651 377 0 0
sp_customer_reserve 440980 440980 0 0.00 0 127814 184 0 0
sp_fill_shopping_cart 29526 29526 0 0.00 1 14938 39 0 0
sp_fill_shopping_cart_clo_res 440975 440975 0 0.00 0 15651 15 0 0
sp_get_clo_for_invoice 4668 4668 0 0.00 0 26738 372 0 0
sp_kill_cost_storno 17781 17781 0 0.00 0 13203 57 0 0
sp_kill_qstorno_ret_qs2qd 1132065 1132065 0 0.00 0 49304 33 0 0
sp_kill_qty_storno 596473 596473 0 0.00 0 49318 70 0 0
sp_lock_selected_doc 19448 19448 0 0.00 0 6353 4 0 0
sp_make_cost_storno 26294 26294 0 0.00 0 28386 54 0 0
sp_multiply_rows_for_pdistr 26293 26293 0 0.00 0 19628 32 0 0
sp_multiply_rows_for_qdistr 19281 19281 0 0.00 0 21004 179 0 0
sp_pay_from_customer 6677 6677 0 0.00 4 19735 108 0 0
sp_pay_to_supplier 5494 5494 0 0.00 5 23392 147 0 0
sp_payment_common 12171 12171 0 0.00 1 18339 31 0 0
sp_qd_handle_on_cancel_clo 1682 1682 0 0.00 0 13147 336 0 0
sp_qd_handle_on_invoice_upd_sts 10413 10413 0 0.00 0 15342 331 0 0
sp_qd_handle_on_reserve_upd_sts 9455 9455 0 0.00 0 17015 61 0 0
sp_reserve_write_off 9456 9456 0 0.00 6 22374 185 0 0
sp_supplier_invoice 5117 5117 0 0.00 6 69603 3442 0 0
sp_supplier_order 5132 5132 0 0.00 5 26863 1276 0 0
srv_aggregate_perf_data 91 91 0 0.00 2456 313209 24795 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2884 183770 52495 0 0
srv_find_qd_qs_mism 1036389 1036389 0 0.00 0 16570 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 28 35098 1021 0 0
srv_make_money_saldo 732 732 0 0.00 1 661 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 188241 496814 334371 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4675 4675 0 0.00 0 8496 158 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 3283 92 0 0
t$perf-norm:sp_cancel_client_order 1682 1682 0 0.00 0 2635 12 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 2172 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 606 606 0 0.00 0 451 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 3580 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 345 345 0 0.00 0 1738 20 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 27129 1954 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 1861 7 0 0
t$perf-norm:sp_client_order 19281 19281 0 0.00 0 7715 17 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 1207 4 0 0
t$perf-norm:sp_pay_from_customer 6677 6677 0 0.00 0 11001 13 0 0
t$perf-norm:sp_pay_to_supplier 5494 5494 0 0.00 0 10678 15 0 0
t$perf-norm:sp_reserve_write_off 9456 9456 0 0.00 0 8090 15 0 0
t$perf-norm:sp_supplier_invoice 5117 5117 0 0.00 0 7635 23 0 0
t$perf-norm:sp_supplier_order 5132 5132 0 0.00 0 6508 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1032 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 895 27 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 1651 15 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 429 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 28 9 0 0
v_add_invoice_to_stock 9522 9334 188 1.97 0 7659 22 0 188
v_all_customers 35292 35292 0 0.00 0 110 1 0 0
v_all_suppliers 28564 28564 0 0.00 0 109 1 0 0
v_all_wares 38562 38562 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 3773 13 0 0
v_cancel_client_order 3364 3364 0 0.00 0 2797 6 0 0
v_cancel_customer_prepayment 1212 1212 0 0.00 0 1182 5 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 3018 11 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 3024 8 0 0
v_cancel_supplier_invoice 702 690 12 1.70 0 3041 24 0 12
v_cancel_supplier_order 770 770 0 0.00 0 1400 7 0 0
v_cancel_write_off 1876 1876 0 0.00 0 4884 20 0 0
v_max_id_clo_ord 5131 5131 0 0.00 0 307 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 2529 8 0 0
v_max_id_ord_sup 5113 5113 0 0.00 0 2829 5 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 1191 3 0 0
v_max_non_paid_realizn 3397 3397 0 0.00 0 3356 3 0 0
v_min_id_clo_ord 5131 5131 0 0.00 0 3941 6 0 0
v_min_id_clo_res 947 947 0 0.00 0 6136 11 0 0
v_min_id_ord_sup 5113 5113 0 0.00 0 5594 12 0 0
v_min_non_paid_invoice 5494 5494 0 0.00 0 15634 28 0 0
v_min_non_paid_realizn 6677 6677 0 0.00 0 18331 17 0 0
v_our_firm 9830 9830 0 0.00 0 15 1 0 0
v_reserve_write_off 18910 18910 0 0.00 0 3029 7 0 0
x_lock_depdocs_on_canc_invoice 11742 11742 0 0.00 0 9156 108 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 32730 453 0 0
x_make_qstorno_1000_1200 5132 5132 0 0.00 2 26857 1199 0 0
x_make_qstorno_1200_2000 5117 5117 0 0.00 3 69582 3298 0 0
x_make_qstorno_2100_3300 440979 440979 0 0.00 0 127765 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-10-2021 19:39:48 to 04-10-2021 19:39:53.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:56 3 8192000000 8800621264 8811937792 6781056 0.082 2 1 2 0 6900432 56816 62560
17:08:57 24752 8192000000 10363053248 10681671680 1404428896 14.634 100 91 182 0 1439755040 5634336 29691808
17:10:28 32293 8192000000 10428533504 10758426624 1480706384 15.308 100 86 172 0 1517545680 8825280 28014016
17:12:09 18408 8192000000 10479938144 10826575872 1527666880 15.717 100 82 164 0 1562934288 9514560 25752848
17:13:34 13755 8192000000 10569322320 10928427008 1633280608 16.623 100 79 158 0 1667789440 10642432 23866400
17:14:54 10768 8192000000 10653089504 11024191488 1714490816 17.306 100 79 158 1 1749860512 12123776 23245920
17:16:07 5871 8192000000 10709275664 11090485248 1768216272 17.752 100 71 140 2 1800303664 12393456 19693936
17:17:15 2622 8192000000 10757840512 11143438336 1819253856 18.172 100 79 157 1 1854234240 12511200 22469184
17:18:20 1595 8192000000 10798379984 11187216384 1855335616 18.465 100 87 172 2 1893536208 13583072 24617520
17:19:23 2140 8192000000 10828283328 11215831040 1879176480 18.658 100 93 185 1 1918192096 12781808 26233808
17:20:26 14895 8192000000 10823258288 11214442496 1880845872 18.672 100 66 132 0 1907939440 7614336 19479232
17:21:42 8040 8192000000 10809908896 11228647424 1890191312 18.747 100 43 85 3 1909774864 7248864 12334688
17:22:52 5889 8192000000 10806383392 11234795520 1899314080 18.821 100 29 56 2 1912842768 5424160 8104528
17:23:59 8318 8192000000 10813701920 11244040192 1902067216 18.843 100 24 49 1 1915406112 6257008 7081888
17:25:08 8864 8192000000 10821011008 11250511872 1909340368 18.901 100 23 46 0 1921326448 6063680 5922400
17:26:24 31008 8192000000 10866815328 11268595712 1918568144 18.975 100 69 140 1 1945686304 5863008 21255152
17:27:55 7513 8192000000 10821375552 11256705024 1890676416 18.751 99 22 43 1 1902631616 5482896 6472304
17:29:04 49848 8192000000 10787292752 11214639104 1806140416 18.064 95 31 62 1 1820793344 6020416 8632512
17:30:57 4434 8192000000 10727879328 11138379776 1789479408 17.927 99 38 77 2 1807167792 7001904 10686480
17:32:02 7779 8192000000 10673852736 11088551936 1678694416 17.006 94 23 46 1 1690246720 4745152 6807152
17:33:14 57228 8192000000 10723876016 11145678848 1755906848 17.651 96 17 34 1 1765532064 5082912 4542304
17:35:18 6242 8192000000 10768851280 11189190656 1851099136 18.431 100 41 83 2 1870641744 6926800 12615808
17:36:25 7355 8192000000 10762793696 11196424192 1856955408 18.479 100 26 51 1 1870578912 5985952 7637552
17:37:36 8671 8192000000 10812945744 11238486016 1888422384 18.733 100 23 46 2 1900936288 5542384 6971520
17:38:55 73712 8192000000 10858210624 11283247104 1924817440 19.025 100 38 76 0 1947096832 10926160 11353232
17:41:33 19429 8192000000 10883480032 11309551616 1933151632 19.092 99 49 98 0 1963009264 15922848 13934784
17:42:59 30877 8192000000 10897232320 11329937408 1959872368 19.305 100 37 73 1 1986307536 15770144 10665024
17:44:44 33152 8192000000 10932948992 11363696640 1984647728 19.501 100 55 110 0 2024613536 23940960 16024848
17:46:25 15192 8192000000 10913723648 11346567168 1969513840 19.382 99 40 79 0 2002296144 20220352 12561952
17:47:45 4391 8192000000 10949218368 11379789824 1998195248 19.608 100 66 132 3 2039781984 22135760 19450976
17:49:01 3612 8192000000 10982744256 11406639104 2011048448 19.710 100 60 118 0 2062678464 32432512 19197504
17:50:10 2896 8192000000 10978012256 11405598720 2004889040 19.661 100 74 146 1 2066436208 38932752 22614416
17:51:17 5591 8192000000 10996067344 11425538048 2024777504 19.818 100 75 151 1 2082738352 35387328 22573520
17:52:26 5155 8192000000 10966197584 11394572288 1995144064 19.584 100 70 140 3 2051521120 35276416 21100640
17:53:38 2881 8192000000 10996572624 11415699456 2006968544 19.678 100 80 160 1 2075777520 44281872 24527104
17:54:44 3945 8192000000 10978604096 11412738048 1998637104 19.612 100 59 118 1 2065561920 48260368 18664448
17:55:53 12412 8192000000 10933518880 11383689216 1963930496 19.337 99 42 84 0 2016673280 39657472 13085312
17:57:10 17892 8192000000 10930794464 11379625984 1960382112 19.309 99 27 54 0 2006089296 36705760 9001424
17:58:41 4333 8192000000 10945622448 11390504960 1981357136 19.475 100 37 72 0 2032834128 39584928 11892064
17:59:51 8463 8192000000 10967525360 11410403328 1994526560 19.580 100 46 92 0 2051898432 41986000 15385872
18:01:05 25138 8192000000 10958360048 11401048064 1938947456 19.138 98 31 61 1 2002394864 52783616 10663792
18:02:31 23252 8192000000 10942734448 11401797632 1969103456 19.378 100 27 54 1 2030619312 51653600 9862256
18:03:56 17958 8192000000 10907186480 11350208512 1885349104 18.708 97 29 58 3 1951848352 56059296 10439952
18:05:14 18274 8192000000 10882092608 11342045184 1880286112 18.667 97 28 53 1 1933905392 43983584 9635696
18:06:36 55183 8192000000 10859387920 11313336320 1898297280 18.813 98 39 77 3 1917256272 7395088 11563904
18:09:42 66435 8192000000 10882173072 11332280320 1912154576 18.924 98 35 69 0 1939801728 16808544 10838608
18:11:53 146392 8192000000 10839391136 11316932608 1840839696 18.348 94 13 26 1 1871537536 27011600 3686240
18:15:21 142977 8192000000 10855146784 11303673856 1901310384 18.837 99 17 34 1 1917672736 11617632 4744720
18:18:49 85436 8192000000 10886992800 11338379264 1908912144 18.898 98 21 42 0 1929779488 15341392 5525952
18:21:15 64510 8192000000 10901922736 11374338048 1962961152 19.330 100 12 24 1 1988738656 21737008 4040496
18:23:29 68590 8192000000 10916159696 11390951424 1982028432 19.481 100 27 54 1 2012242368 22185312 8028624
18:25:39 20971 8192000000 10904262176 11380129792 1980014080 19.465 100 20 39 0 2005690592 20260704 5415808
18:27:03 66320 8192000000 10909226064 11395645440 1990829712 19.550 100 11 20 0 2009601120 15902368 2869040
18:29:10 44770 8192000000 10900505424 11375566848 1970624960 19.390 99 7 14 1 1983660928 11226480 1809488
18:31:02 17893 8192000000 10925762080 11369467904 1962359152 19.325 99 66 130 2 1990699232 10845264 17494816
18:32:21 116603 8192000000 10919331840 11399544832 1928343904 19.054 97 15 30 2 1955755280 23214640 4196736
18:35:18 51865 8192000000 10911158432 11394113536 1979363168 19.460 100 17 33 1 2000821808 16362272 5096368
18:37:11 151806 8192000000 10913251008 11379662848 1894459152 18.782 95 14 28 0 1909254288 10980976 3814160
18:40:45 30764 8192000000 10934518800 11384291328 1971654128 19.399 99 41 82 2 1994810384 11314176 11842080
18:42:23 68679 8192000000 10919224672 11399852032 1987266944 19.522 100 22 44 0 2010722752 16825616 6630192
18:44:32 63458 8192000000 10901241072 11372953600 1938642496 19.136 97 37 74 1 1955223424 6923744 9657184
18:46:36 42232 8192000000 10926239440 11376357376 1982142720 19.482 100 57 114 0 2010923584 12792224 15988640
18:48:24 45138 8192000000 10874681664 11354107904 1929891872 19.066 97 10 20 1 1944276352 11520240 2864240
18:50:10 72262 8192000000 10883877648 11363811328 1939960928 19.146 98 20 39 1 1963567264 18404928 5201408
18:52:22 78761 8192000000 10874678432 11351580672 1947233184 19.204 99 12 25 0 1958781776 7782352 3766240
18:54:47 45033 8192000000 10841029200 11314311168 1886419344 18.717 97 9 18 0 1896019360 6863680 2736336
18:56:38 71425 8192000000 10851360096 11328819200 1883101904 18.690 96 11 22 0 1903071984 15814672 4155408
18:59:44 49060 8192000000 10872985376 11350401024 1893993968 18.778 97 14 28 1 1914782112 16512400 4275744
19:01:34 57480 8192000000 10868917632 11342221312 1922339056 19.006 99 25 49 1 1943142464 13519424 7283984
19:03:33 82182 8192000000 10881279408 11342979072 1914895904 18.946 98 17 34 1 1936193760 16463824 4834032
19:05:55 35400 8192000000 10884533712 11321929728 1873835024 18.615 96 42 82 1 1894148416 8503376 11810016
19:07:32 183770 8192000000 10857466032 11320008704 1823209888 18.204 93 20 40 1 1837997984 9419744 5368352
19:11:38 57910 8192000000 10855945808 11318636544 1890958544 18.754 97 12 24 0 1902668912 8417552 3292816
19:13:40 64298 8192000000 10845690768 11317465088 1897644928 18.807 98 8 16 0 1908998880 9054864 2299088
19:15:48 76590 8192000000 10851388464 11315949568 1932672128 19.088 99 21 42 0 1945588048 7010016 5905904
19:18:21 57013 8192000000 10863676384 11339218944 1952818368 19.249 100 10 20 0 1961246112 6014000 2413744
19:20:26 86989 8192000000 10899418096 11381305344 1969534592 19.382 100 13 26 0 1986712896 13730912 3447392
19:22:56 54867 8192000000 10908729216 11380379648 1963565280 19.334 99 13 26 0 1979359552 11248064 4546208
19:25:00 43712 8192000000 10898487936 11370954752 1963721648 19.336 100 15 28 1 1983394176 15457648 4214880
19:26:45 47459 8192000000 10900102944 11363225600 1951730576 19.240 99 27 55 0 1969439744 10189808 7519360
19:28:34 115500 8192000000 10874422736 11353354240 1862149712 18.521 94 23 45 0 1879753104 11291552 6311840
19:31:31 26364 8192000000 10878284320 11332866048 1934691056 19.104 100 41 76 2 1954023840 7480624 11852160
19:32:57 71178 8192000000 10894333456 11368472576 1946415392 19.198 99 14 28 1 1959946848 10120304 3411152
19:35:20 93789 8192000000 10911781264 11388227584 1987198944 19.522 100 26 52 1 2008348544 13682928 7466672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 19:39:54 to 04-10-2021 19:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2021 19:40:10 to 04-10-2021 19:40:12.

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          227929
MON$OLDEST_ACTIVE               229362
MON$OLDEST_SNAPSHOT             229362
MON$NEXT_TRANSACTION            229362
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                       2004288
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:187: 
:188: mscs-test-161.budzdorov.ru    Mon Oct  4 19:37:56 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Mon Oct  4 19:37:56 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
--- end of diff output ---

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

04.10.21 19:40:13. 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-10-04 19:40:15 No severe PSQL-related problems occured
04.10.21 19:40:16. End of report.