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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-04 01:37:57
DTS_END                         2021-12-04 01:37:57.9440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52522
TRN_ID                          247176
ATT_ID                          669
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: 9346.23 19192 1121548
customer order: creation 182.10 343 21852
customer order: refuse 15.58 371 1869
order to supplier: creation 48.52 1286 5822
order to supplier: removal 3.73 83356 447
invoice (draft): creation 48.19 3431 5783
invoice (draft): removal 52.39 709 6287
invoice accept: apply 43.90 17658 5268
invoice accept: cancel 47.20 807 5664
customer reserve: creation 3952.38 180 474286
customer reserve: removal 4646.13 53 557535
realization accept: apply 88.11 179 10573
realization accept: cancel 81.43 115 9772
payment from customer: creation 62.15 122 7458
payment from customer: removal 5.86 75 703
payment to supplier: creation 52.55 120 6306
payment to supplier: removal 3.53 56 423
service: total inventory turnovers 5.27 1086 632
service: total monetary turnovers 6.44 37 773
service: aggregate perf. data 0.76 25391 91
service: refresh index statistics 0.03 248474 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-12-2021 01:39:46 to 04-12-2021 01:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3277
TEST_TIME 2 4007
TEST_TIME 3 4350
TEST_TIME 4 4914
TEST_TIME 5 4226
TEST_TIME 6 4202
TEST_TIME 7 4268
TEST_TIME 8 5191
TEST_TIME 9 5992
TEST_TIME 10 5974
TEST_TIME 11 6591
TEST_TIME 12 6598
TEST_TIME 13 7042
TEST_TIME 14 7096
TEST_TIME 15 7343
TEST_TIME 16 7322
TEST_TIME 17 7186
TEST_TIME 18 7371
TEST_TIME 19 7761
TEST_TIME 20 8050
TEST_TIME 21 8323
TEST_TIME 22 8530
TEST_TIME 23 9009
TEST_TIME 24 9291
TEST_TIME 25 9450
TEST_TIME 26 9755
TEST_TIME 27 10188
TEST_TIME 28 10436
TEST_TIME 29 10402
TEST_TIME 30 10415
TEST_TIME 31 10273
TEST_TIME 32 10364
TEST_TIME 33 10444
TEST_TIME 34 10357
TEST_TIME 35 10281
TEST_TIME 36 10418
TEST_TIME 37 10355
TEST_TIME 38 10208
TEST_TIME 39 10140
TEST_TIME 40 9931
TEST_TIME 41 10045
TEST_TIME 42 10026
TEST_TIME 43 9868
TEST_TIME 44 9853
TEST_TIME 45 9767
TEST_TIME 46 9780
TEST_TIME 47 9856
TEST_TIME 48 9771
TEST_TIME 49 9804
TEST_TIME 50 9827
TEST_TIME 51 9718
TEST_TIME 52 9678
TEST_TIME 53 9635
TEST_TIME 54 9699
TEST_TIME 55 9662
TEST_TIME 56 9733
TEST_TIME 57 9702
TEST_TIME 58 9751
TEST_TIME 59 9750
TEST_TIME 60 9698
TEST_TIME 61 9734
TEST_TIME 62 9845
TEST_TIME 63 9810
TEST_TIME 64 9898
TEST_TIME 65 10012
TEST_TIME 66 10096
TEST_TIME 67 10022
TEST_TIME 68 9994
TEST_TIME 69 9934
TEST_TIME 70 9859
TEST_TIME 71 9836
TEST_TIME 72 9766
TEST_TIME 73 9733
TEST_TIME 74 9772
TEST_TIME 75 9684
TEST_TIME 76 9667
TEST_TIME 77 9682
TEST_TIME 78 9628
TEST_TIME 79 9571
TEST_TIME 80 9498
TEST_TIME 81 9495
TEST_TIME 82 9428
TEST_TIME 83 9365
TEST_TIME 84 9490
TEST_TIME 85 9500
TEST_TIME 86 9500
TEST_TIME 87 9496
TEST_TIME 88 9533
TEST_TIME 89 9543
TEST_TIME 90 9549
TEST_TIME 91 9541
TEST_TIME 92 9568
TEST_TIME 93 9593
TEST_TIME 94 9638
TEST_TIME 95 9589
TEST_TIME 96 9533
TEST_TIME 97 9507
TEST_TIME 98 9491
TEST_TIME 99 9459
TEST_TIME 100 9403
TEST_TIME 101 9373
TEST_TIME 102 9324
TEST_TIME 103 9352
TEST_TIME 104 9342
TEST_TIME 105 9308
TEST_TIME 106 9320
TEST_TIME 107 9353
TEST_TIME 108 9343
TEST_TIME 109 9361
TEST_TIME 110 9358
TEST_TIME 111 9351
TEST_TIME 112 9348
TEST_TIME 113 9368
TEST_TIME 114 9447
TEST_TIME 115 9432
TEST_TIME 116 9422
TEST_TIME 117 9447
TEST_TIME 118 9437
TEST_TIME 119 9397
TEST_TIME 120 9350

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-12-2021 01:39:50 to 04-12-2021 01:39:54.

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 1562265 1562265 0 0.00 0 12141 6 0 0
doc_list_biud 1562261 1562261 0 0.00 0 33208 24 0 0
sp_add_invoice_to_stock 5370 5268 102 1.89 31 219432 17659 0 102
sp_cancel_adding_invoice 5664 5664 0 0.00 4 63399 808 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 12663 372 0 0
sp_cancel_customer_reserve 557535 557535 0 0.00 1 26260 53 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 4690 76 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 1102 56 0 0
sp_cancel_supplier_invoice 6299 6287 12 0.19 1 33220 709 0 12
sp_cancel_supplier_order 447 447 0 0.00 5 1006697 83356 0 0
sp_cancel_write_off 9772 9772 0 0.00 1 21000 115 0 0
sp_client_order 21852 21852 0 0.00 3 29856 344 0 0
sp_customer_reserve 474286 474286 0 0.00 0 113515 181 0 0
sp_fill_shopping_cart 33445 33445 0 0.00 1 22649 38 0 0
sp_fill_shopping_cart_clo_res 474280 474280 0 0.00 0 16670 12 0 0
sp_get_clo_for_invoice 5262 5262 0 0.00 0 12550 211 0 0
sp_kill_cost_storno 16562 16562 0 0.00 0 11156 56 0 0
sp_kill_qstorno_ret_qs2qd 1131576 1131576 0 0.00 0 33200 29 0 0
sp_kill_qty_storno 597408 597408 0 0.00 0 33205 63 0 0
sp_lock_selected_doc 21925 21925 0 0.00 0 9435 6 0 0
sp_make_cost_storno 29598 29598 0 0.00 0 10909 50 0 0
sp_multiply_rows_for_pdistr 29595 29595 0 0.00 0 12139 28 0 0
sp_multiply_rows_for_qdistr 21852 21852 0 0.00 0 25343 137 0 0
sp_pay_from_customer 7458 7458 0 0.00 4 13326 123 0 0
sp_pay_to_supplier 6306 6306 0 0.00 5 10924 120 0 0
sp_payment_common 13760 13760 0 0.00 1 13294 28 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 12657 323 0 0
sp_qd_handle_on_invoice_upd_sts 10925 10925 0 0.00 0 25133 329 0 0
sp_qd_handle_on_reserve_upd_sts 10573 10573 0 0.00 0 29748 64 0 0
sp_reserve_write_off 10573 10573 0 0.00 6 29808 179 0 0
sp_supplier_invoice 5783 5783 0 0.00 6 98580 3431 0 0
sp_supplier_order 5822 5822 0 0.00 5 28540 1287 0 0
srv_aggregate_perf_data 91 91 0 0.00 2810 358838 25391 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1938 128415 50665 0 0
srv_find_qd_qs_mism 1072400 1072400 0 0.00 0 10210 6 0 0
srv_make_invnt_saldo 632 632 0 0.00 45 73968 1086 0 0
srv_make_money_saldo 773 773 0 0.00 0 3908 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 163401 328038 248475 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 5269 5269 0 0.00 0 14398 170 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 1 6582 108 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 4116 11 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 3869 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 371 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 4374 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 746 16 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 28008 1536 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 966 6 0 0
t$perf-norm:sp_client_order 21852 21852 0 0.00 0 12040 17 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 4714 10 0 0
t$perf-norm:sp_pay_from_customer 7458 7458 0 0.00 0 9298 14 0 0
t$perf-norm:sp_pay_to_supplier 6306 6306 0 0.00 0 6432 11 0 0
t$perf-norm:sp_reserve_write_off 10573 10573 0 0.00 0 12836 17 0 0
t$perf-norm:sp_supplier_invoice 5783 5783 0 0.00 0 11134 23 0 0
t$perf-norm:sp_supplier_order 5822 5822 0 0.00 0 12988 22 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 359 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 310 6 0 0
t$perf-norm:srv_make_invnt_saldo 632 632 0 0.00 0 1199 16 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 14338 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10724 10520 204 1.90 0 6024 19 0 204
v_all_customers 39828 39828 0 0.00 0 128 1 0 0
v_all_suppliers 32316 32316 0 0.00 0 24 1 0 0
v_all_wares 43702 43702 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 54 5 0 0
v_cancel_client_order 3738 3738 0 0.00 0 3966 8 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 4682 11 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 6031 7 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1091 5 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 6640 23 0 24
v_cancel_supplier_order 894 894 0 0.00 0 348 4 0 0
v_cancel_write_off 2130 2130 0 0.00 0 1549 12 0 0
v_max_id_clo_ord 5816 5816 0 0.00 0 580 1 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 2488 8 0 0
v_max_id_ord_sup 5776 5776 0 0.00 0 14371 7 0 0
v_max_non_paid_invoice 1738 1738 0 0.00 0 1854 4 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 3195 6 0 0
v_min_id_clo_ord 5816 5816 0 0.00 0 3488 7 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 10056 20 0 0
v_min_id_ord_sup 5776 5776 0 0.00 0 7156 13 0 0
v_min_non_paid_invoice 6304 6304 0 0.00 0 9674 18 0 0
v_min_non_paid_realizn 7456 7456 0 0.00 0 13287 23 0 0
v_our_firm 10954 10954 0 0.00 0 18 1 0 0
v_reserve_write_off 21144 21144 0 0.00 0 6036 6 0 0
x_lock_depdocs_on_canc_invoice 11541 11541 0 0.00 0 18194 111 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 11551 292 0 0
x_make_qstorno_1000_1200 5822 5822 0 0.00 1 28530 1205 0 0
x_make_qstorno_1200_2000 5783 5783 0 0.00 2 98571 3291 0 0
x_make_qstorno_2100_3300 474286 474286 0 0.00 0 113513 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 01:39:55 to 04-12-2021 01:39:59.

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:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:02 27331 8192000000 9912670608 10223312896 989370768 10.775 100 95 190 0 1021063456 6604464 25088224
23:10:36 19132 8192000000 9948970144 10271698944 1031130288 11.179 100 88 176 0 1062512656 8891936 22490432
23:12:00 17722 8192000000 9984740144 10318594048 1068283104 11.536 100 82 164 1 1099168176 10402304 20482768
23:13:21 14358 8192000000 10037179552 10383482880 1124174032 12.066 100 80 159 0 1154791040 11937584 18679424
23:14:38 6593 8192000000 10073015424 10434535424 1163784352 12.439 100 69 138 0 1192272400 12601792 15886256
23:15:50 4813 8192000000 10119422960 10488053760 1212688496 12.894 100 83 164 0 1242576448 11529936 18358016
23:16:59 4103 8192000000 10156383584 10527510528 1242460080 13.169 100 91 181 2 1275903680 13600128 19843472
23:18:05 2067 8192000000 10193642752 10574393344 1277780048 13.493 100 93 183 1 1311471920 12983200 20708672
23:19:09 1437 8192000000 10215835952 10599096320 1299589040 13.692 100 91 184 2 1332438512 12041936 20807536
23:20:11 59363 8192000000 10198030048 10608164864 1308572752 13.773 100 22 44 0 1319481888 5050368 5858768
23:22:19 5876 8192000000 10208832688 10621267968 1317554112 13.855 100 32 65 0 1331342768 5402560 8386096
23:23:27 10606 8192000000 10214255344 10629632000 1322740896 13.902 100 18 36 2 1332829712 6184480 3904336
23:24:39 18216 8192000000 10212459632 10635157504 1329622048 13.964 100 24 48 0 1340851168 5572000 5657120
23:26:06 23595 8192000000 10219355552 10642436096 1330604736 13.973 100 19 37 3 1340403328 5703136 4095456
23:27:30 5705 8192000000 10216309968 10637062144 1318844512 13.866 99 28 56 2 1331309520 6006608 6458400
23:28:38 7835 8192000000 10186374640 10596311040 1255259792 13.287 96 15 29 2 1263387088 4674576 3452720
23:29:46 16575 8192000000 10166255168 10561294336 1196340288 12.742 93 23 45 1 1206636432 4717264 5578880
23:31:05 17756 8192000000 10125781824 10523738112 1187747168 12.662 95 23 46 3 1198455936 6079840 4628928
23:32:28 7898 8192000000 10162420704 10555035648 1241339184 13.159 99 31 62 2 1253937648 5506544 7091920
23:33:38 7260 8192000000 10170798864 10582220800 1281164128 13.524 100 37 73 1 1296071872 5941936 8965808
23:34:48 60757 8192000000 10174196384 10594041856 1292373616 13.626 100 25 50 0 1303997120 5589664 6033840
23:36:51 6253 8192000000 10198209520 10612019200 1304693008 13.738 100 19 38 0 1314520496 5645648 4181840
23:37:58 49030 8192000000 10235032896 10643243008 1325607584 13.927 100 35 71 2 1340419504 6618752 8193168
23:39:49 27972 8192000000 10270135808 10682273792 1355982144 14.201 100 53 105 1 1377741712 9969648 11789920
23:41:24 30203 8192000000 10269245104 10694483968 1369240864 14.320 100 42 85 2 1388154144 8335232 10578048
23:43:02 22773 8192000000 10288919744 10712518656 1380270384 14.419 100 45 90 1 1402225280 11579328 10375568
23:44:31 32225 8192000000 10302560560 10724192256 1389657184 14.503 100 51 102 1 1419165184 17053552 12454448
23:46:18 11295 8192000000 10313488816 10742464512 1394974016 14.550 100 50 100 1 1431149776 24137360 12038400
23:47:39 2220 8192000000 10331243632 10744049664 1397226960 14.570 100 73 140 0 1435813088 20934992 17651136
23:48:45 6401 8192000000 10320906608 10746605568 1399926864 14.594 100 54 108 1 1434333664 21661952 12744848
23:49:54 9328 8192000000 10341837808 10770214912 1409939568 14.683 100 67 133 1 1454799312 28191680 16668064
23:51:12 1936 8192000000 10326136160 10731933696 1376982240 14.390 98 77 154 1 1419872544 24437312 18452992
23:52:20 10345 8192000000 10328939680 10748030976 1397205744 14.570 100 64 127 2 1441422704 28484144 15732816
23:53:37 7387 8192000000 10327435840 10739957760 1368317968 14.312 98 59 118 0 1411214624 28557840 14338816
23:54:49 3286 8192000000 10314070208 10711625728 1356345648 14.205 97 86 172 3 1413391136 35453472 21592016
23:55:57 4226 8192000000 10343516512 10749001728 1368379312 14.313 98 83 165 1 1425702912 36908464 20415136
23:57:08 21605 8192000000 10306518096 10733023232 1363558880 14.269 99 31 62 2 1413368384 40706672 9102832
23:58:46 2561 8192000000 10330748656 10746499072 1377993504 14.399 100 61 121 2 1433205168 40353824 14857840
23:59:56 14407 8192000000 10336683648 10764980224 1384716624 14.459 100 38 77 2 1441936688 46757232 10462832
00:01:19 3358 8192000000 10335176464 10752958464 1385978560 14.470 100 70 142 0 1435965824 32255872 17731392
00:02:45 21051 8192000000 10293744352 10735071232 1360711376 14.244 99 25 50 0 1396977024 29549760 6715888
00:04:17 104813 8192000000 10282875088 10740174848 1327413664 13.944 97 8 16 0 1361726208 32105808 2206736
00:07:03 68566 8192000000 10278796320 10702618624 1333038096 13.995 98 11 19 0 1351890544 16603456 2248992
00:10:00 119154 8192000000 10314075680 10752991232 1355463424 14.197 98 11 23 0 1379448016 21810544 2174048
00:12:59 48847 8192000000 10300110976 10745430016 1381497008 14.430 100 14 28 1 1398584832 14219280 2868544
00:15:07 52074 8192000000 10321076224 10752241664 1382190160 14.436 99 31 62 0 1402525008 12891616 7443232
00:17:11 83836 8192000000 10312586112 10760351744 1381143056 14.427 99 38 71 1 1402418128 12779408 8495664
00:19:40 70262 8192000000 10295880624 10757660672 1379270736 14.410 99 8 16 0 1390657152 9774576 1611840
00:21:54 42784 8192000000 10313976848 10763681792 1385135840 14.462 99 30 60 1 1405754624 13508224 7110560
00:23:43 22754 8192000000 10306702352 10766880768 1379702944 14.414 99 32 64 1 1407192288 19611024 7878320
00:25:13 119226 8192000000 10311854560 10780975104 1405343968 14.643 100 16 32 0 1430255488 21031280 3880240
00:28:18 62050 8192000000 10316895616 10784059392 1388488752 14.492 99 14 28 0 1410895072 19018944 3387376
00:30:24 76172 8192000000 10302433648 10767044608 1379838592 14.415 99 17 33 0 1403189728 18716976 4634160
00:32:44 40270 8192000000 10298515904 10756501504 1373485808 14.358 99 20 40 2 1397382000 19032848 4863344
00:34:32 37083 8192000000 10285066864 10735276032 1321913600 13.894 95 31 61 2 1341292992 12100848 7278544
00:36:23 39558 8192000000 10280232864 10732707840 1338158352 14.041 97 27 54 0 1357919024 13662800 6097872
00:38:03 42067 8192000000 10299639312 10750332928 1362073520 14.256 98 37 74 1 1384980448 14838880 8068048
00:39:54 66587 8192000000 10300574048 10758557696 1361394544 14.250 98 9 18 0 1378957280 15784752 1777984
00:42:02 30605 8192000000 10278248288 10731712512 1324244240 13.915 96 11 22 0 1336724112 10284128 2195744
00:43:33 86027 8192000000 10283279120 10728804352 1333701536 14.001 97 4 8 0 1347360496 12974448 684512
00:46:07 28409 8192000000 10252500640 10678968320 1299925408 13.695 97 45 87 1 1318746208 8804752 10016048
00:47:41 85944 8192000000 10253166336 10701897728 1325316048 13.925 98 21 42 0 1339234000 9651776 4266176
00:50:09 30279 8192000000 10253028384 10689490944 1286652048 13.574 95 38 76 0 1305435984 10057440 8726496
00:51:43 82218 8192000000 10248777968 10694438912 1320525328 13.881 98 7 14 0 1334978368 12746560 1706480
00:54:32 66138 8192000000 10253608848 10696900608 1315172720 13.833 98 9 18 0 1324176976 7041664 1962592
00:56:46 84148 8192000000 10238908944 10694651904 1337208000 14.032 100 19 38 2 1351322640 9690112 4424528
00:59:11 54148 8192000000 10273922960 10726752256 1352086048 14.166 99 17 34 0 1371092928 15157696 3849184
01:01:05 75685 8192000000 10281629088 10737156096 1368623728 14.315 100 18 36 0 1393655728 20672896 4359104
01:03:33 27546 8192000000 10287099488 10739044352 1369085440 14.319 99 25 50 1 1382660688 7910336 5664912
01:05:01 19781 8192000000 10301815584 10742681600 1368915120 14.317 99 51 103 2 1390213040 9135360 12162560
01:06:28 93128 8192000000 10287253056 10734268416 1316252336 13.843 95 24 48 0 1330928624 9591120 5085168
01:09:04 71693 8192000000 10294887392 10756947968 1374073136 14.364 99 16 32 1 1391686256 14013024 3600096
01:11:27 128415 8192000000 10274074816 10729869312 1333266736 13.997 96 10 20 0 1348562608 12762624 2533248
01:14:37 33711 8192000000 10286073424 10733023232 1379246992 14.410 100 35 71 2 1394465520 7460800 7757728
01:16:19 84982 8192000000 10280774304 10739048448 1360618656 14.243 98 11 22 0 1370022784 7220080 2184048
01:18:44 76090 8192000000 10292901888 10751422464 1363593904 14.270 98 9 18 1 1376931504 11356816 1980784
01:22:31 39936 8192000000 10291885264 10743226368 1330639408 13.973 95 18 37 0 1345197600 10435776 4122416
01:24:13 111687 8192000000 10298203792 10753601536 1301083744 13.705 94 18 36 1 1316123904 11187040 3853120
01:27:11 98739 8192000000 10278888592 10738823168 1329959696 13.967 96 14 28 1 1346001088 12700928 3340464
01:29:58 44487 8192000000 10291404112 10733002752 1339934912 14.057 98 38 76 1 1365487680 17270048 8282720
01:31:46 99575 8192000000 10278427888 10731307008 1303018240 13.723 95 8 16 0 1325975328 21314128 1642960
01:34:42 58853 8192000000 10287710592 10741428224 1358234352 14.222 99 16 32 0 1373059104 10935632 3889120
01:36:46 100568 8192000000 9217310592 9486733312 376116368 4.389 28 7 13 0 380383664 2800688 1466608

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-12-2021 01:40:00 to 04-12-2021 01:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 04-12-2021 01:40:16 to 04-12-2021 01:40:18.

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          247432
MON$OLDEST_ACTIVE               247433
MON$OLDEST_SNAPSHOT             247433
MON$NEXT_TRANSACTION            247433
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                       2036896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:789: 
:790: mscs-test-161.budzdorov.ru    Sat Dec  4 01:38:31 2021
:791:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:792: 
:793: 
:794: mscs-test-161.budzdorov.ru    Sat Dec  4 01:38:31 2021
:795:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:796: 
:797: 
:798: mscs-test-161.budzdorov.ru    Sat Dec  4 01:38:31 2021
:799:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:800: 
--- end of diff output ---

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

04.12.21 01:40:19. 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-12-04 01:40:21 No severe PSQL-related problems occured
04.12.21 01:40:23. End of report.