Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2020 04:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 1320.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16643428 kB
MemAvailable 31394144 kB
Buffers 20 kB
Cached 14912964 kB
SwapCached 3696 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-12-22 04:26:55
DTS_END                         2020-12-22 04:26:55.0400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57800
TRN_ID                          231143
ATT_ID                          649
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: 9031.51 28284 1083781
customer order: creation 158.59 434 19031
customer order: refuse 13.87 337 1664
order to supplier: creation 43.47 1456 5216
order to supplier: removal 3.48 120640 417
invoice (draft): creation 45.08 3877 5409
invoice (draft): removal 52.50 935 6300
invoice accept: apply 39.75 17011 4770
invoice accept: cancel 47.08 945 5650
customer reserve: creation 3707.32 164 444878
customer reserve: removal 4642.70 72 557124
realization accept: apply 78.31 181 9397
realization accept: cancel 77.27 132 9272
payment from customer: creation 55.33 108 6640
payment from customer: removal 5.56 105 667
payment to supplier: creation 47.10 127 5652
payment to supplier: removal 3.29 110 395
service: total inventory turnovers 3.50 3762 420
service: total monetary turnovers 6.32 481 758
service: aggregate perf. data 0.98 14658 117
service: refresh index statistics 0.03 400142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2020 04:28:37 to 22-12-2020 04:28:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2277
TEST_TIME 2 2846
TEST_TIME 3 4169
TEST_TIME 4 4170
TEST_TIME 5 4104
TEST_TIME 6 4443
TEST_TIME 7 4971
TEST_TIME 8 5050
TEST_TIME 9 5549
TEST_TIME 10 5561
TEST_TIME 11 5901
TEST_TIME 12 6151
TEST_TIME 13 6346
TEST_TIME 14 6902
TEST_TIME 15 7334
TEST_TIME 16 7438
TEST_TIME 17 7807
TEST_TIME 18 8039
TEST_TIME 19 7866
TEST_TIME 20 8236
TEST_TIME 21 8427
TEST_TIME 22 8515
TEST_TIME 23 9099
TEST_TIME 24 9308
TEST_TIME 25 9913
TEST_TIME 26 10626
TEST_TIME 27 11530
TEST_TIME 28 11487
TEST_TIME 29 11565
TEST_TIME 30 11482
TEST_TIME 31 11241
TEST_TIME 32 11018
TEST_TIME 33 10777
TEST_TIME 34 10593
TEST_TIME 35 10616
TEST_TIME 36 10532
TEST_TIME 37 10499
TEST_TIME 38 10594
TEST_TIME 39 10517
TEST_TIME 40 10384
TEST_TIME 41 10238
TEST_TIME 42 10094
TEST_TIME 43 9985
TEST_TIME 44 10003
TEST_TIME 45 9910
TEST_TIME 46 9896
TEST_TIME 47 9802
TEST_TIME 48 9893
TEST_TIME 49 9865
TEST_TIME 50 9890
TEST_TIME 51 9886
TEST_TIME 52 9990
TEST_TIME 53 9974
TEST_TIME 54 10026
TEST_TIME 55 9988
TEST_TIME 56 9990
TEST_TIME 57 9942
TEST_TIME 58 9898
TEST_TIME 59 9833
TEST_TIME 60 9723
TEST_TIME 61 9755
TEST_TIME 62 9670
TEST_TIME 63 9591
TEST_TIME 64 9570
TEST_TIME 65 9526
TEST_TIME 66 9515
TEST_TIME 67 9495
TEST_TIME 68 9478
TEST_TIME 69 9441
TEST_TIME 70 9459
TEST_TIME 71 9468
TEST_TIME 72 9549
TEST_TIME 73 9540
TEST_TIME 74 9772
TEST_TIME 75 9830
TEST_TIME 76 9758
TEST_TIME 77 9700
TEST_TIME 78 9634
TEST_TIME 79 9573
TEST_TIME 80 9490
TEST_TIME 81 9412
TEST_TIME 82 9353
TEST_TIME 83 9358
TEST_TIME 84 9305
TEST_TIME 85 9306
TEST_TIME 86 9288
TEST_TIME 87 9303
TEST_TIME 88 9276
TEST_TIME 89 9321
TEST_TIME 90 9288
TEST_TIME 91 9358
TEST_TIME 92 9329
TEST_TIME 93 9310
TEST_TIME 94 9342
TEST_TIME 95 9335
TEST_TIME 96 9268
TEST_TIME 97 9216
TEST_TIME 98 9160
TEST_TIME 99 9123
TEST_TIME 100 9134
TEST_TIME 101 9087
TEST_TIME 102 9102
TEST_TIME 103 9101
TEST_TIME 104 9068
TEST_TIME 105 9135
TEST_TIME 106 9116
TEST_TIME 107 9143
TEST_TIME 108 9128
TEST_TIME 109 9141
TEST_TIME 110 9113
TEST_TIME 111 9080
TEST_TIME 112 9238
TEST_TIME 113 9259
TEST_TIME 114 9277
TEST_TIME 115 9215
TEST_TIME 116 9149
TEST_TIME 117 9144
TEST_TIME 118 9099
TEST_TIME 119 9078
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2020 04:28:41 to 22-12-2020 04:28:44.

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 1495671 1495671 0 0.00 0 18566 6 0 0
doc_list_biud 1495667 1495667 0 0.00 0 58831 33 0 0
sp_add_invoice_to_stock 4857 4770 87 1.79 27 164294 17011 0 87
sp_cancel_adding_invoice 5650 5650 0 0.00 6 90485 945 0 0
sp_cancel_client_order 1664 1664 0 0.00 4 17077 338 0 0
sp_cancel_customer_reserve 557124 557124 0 0.00 1 58835 72 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 6579 105 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 6875 111 0 0
sp_cancel_supplier_invoice 6308 6300 8 0.12 3 17737 935 0 8
sp_cancel_supplier_order 417 417 0 0.00 9 905112 120641 0 0
sp_cancel_write_off 9272 9272 0 0.00 1 9806 132 0 0
sp_client_order 19031 19031 0 0.00 4 62653 435 0 0
sp_customer_reserve 444878 444878 0 0.00 0 97287 165 0 0
sp_fill_shopping_cart 29652 29652 0 0.00 1 22645 45 0 0
sp_fill_shopping_cart_clo_res 444868 444868 0 0.00 0 25327 18 0 0
sp_get_clo_for_invoice 4760 4760 0 0.00 0 47151 381 0 0
sp_kill_cost_storno 15984 15984 0 0.00 0 9803 62 0 0
sp_kill_qstorno_ret_qs2qd 1130237 1130237 0 0.00 0 58823 39 0 0
sp_kill_qty_storno 594583 594583 0 0.00 0 58831 83 0 0
sp_lock_selected_doc 19667 19667 0 0.00 0 9030 8 0 0
sp_make_cost_storno 26449 26449 0 0.00 0 10699 59 0 0
sp_multiply_rows_for_pdistr 26447 26447 0 0.00 0 5982 27 0 0
sp_multiply_rows_for_qdistr 19031 19031 0 0.00 0 62260 241 0 0
sp_pay_from_customer 6640 6640 0 0.00 4 11061 108 0 0
sp_pay_to_supplier 5652 5652 0 0.00 5 18119 127 0 0
sp_payment_common 12290 12290 0 0.00 1 6834 18 0 0
sp_qd_handle_on_cancel_clo 1664 1664 0 0.00 0 17070 309 0 0
sp_qd_handle_on_invoice_upd_sts 10409 10409 0 0.00 0 25436 376 0 0
sp_qd_handle_on_reserve_upd_sts 9397 9397 0 0.00 0 14085 54 0 0
sp_reserve_write_off 9397 9397 0 0.00 7 14112 182 0 0
sp_supplier_invoice 5409 5409 0 0.00 8 118069 3878 0 0
sp_supplier_order 5216 5216 0 0.00 5 58909 1456 0 0
srv_aggregate_perf_data 117 117 0 0.00 2969 228666 14659 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 960 147286 52394 0 0
srv_find_qd_qs_mism 1038452 1038452 0 0.00 0 18566 5 0 0
srv_make_invnt_saldo 420 420 0 0.00 38 91269 3762 0 0
srv_make_money_saldo 758 758 0 0.00 1 11729 482 0 0
srv_recalc_idx_stat 4 4 0 0.00 334384 526430 400143 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4770 4770 0 0.00 0 13868 186 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 2577 71 0 0
t$perf-norm:sp_cancel_client_order 1664 1664 0 0.00 0 10976 23 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 705 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2094 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1969 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 275 11 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 23716 1672 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 2900 10 0 0
t$perf-norm:sp_client_order 19031 19031 0 0.00 0 12124 19 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 4717 12 0 0
t$perf-norm:sp_pay_from_customer 6640 6640 0 0.00 0 11084 11 0 0
t$perf-norm:sp_pay_to_supplier 5652 5652 0 0.00 0 3648 7 0 0
t$perf-norm:sp_reserve_write_off 9397 9397 0 0.00 0 8154 11 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 3456 15 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 8631 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 421 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 527 4 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 378 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9689 9516 173 1.78 0 7001 16 0 173
v_all_customers 34856 34856 0 0.00 0 21 1 0 0
v_all_suppliers 29634 29634 0 0.00 0 21 1 0 0
v_all_wares 38062 38062 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 4509 20 0 0
v_cancel_client_order 3328 3328 0 0.00 0 3369 6 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 3732 10 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 1769 5 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 3035 15 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 1365 15 0 16
v_cancel_supplier_order 834 834 0 0.00 0 3728 10 0 0
v_cancel_write_off 1868 1868 0 0.00 0 3428 15 0 0
v_max_id_clo_ord 5214 5214 0 0.00 0 3037 2 0 0
v_max_id_clo_res 973 973 0 0.00 0 5104 25 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 9225 6 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 190 2 0 0
v_max_non_paid_realizn 3526 3526 0 0.00 0 2362 4 0 0
v_min_id_clo_ord 5214 5214 0 0.00 0 7388 6 0 0
v_min_id_clo_res 973 973 0 0.00 0 8788 14 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 6271 10 0 0
v_min_non_paid_invoice 5650 5650 0 0.00 0 2935 9 0 0
v_min_non_paid_realizn 6640 6640 0 0.00 0 3783 10 0 0
v_our_firm 9434 9434 0 0.00 0 23 1 0 0
v_reserve_write_off 18794 18794 0 0.00 0 6053 6 0 0
x_lock_depdocs_on_canc_invoice 11551 11551 0 0.00 0 13954 109 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 17757 402 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 2 58890 1374 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 4 117774 3711 0 0
x_make_qstorno_2100_3300 444878 444878 0 0.00 0 97258 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2020 04:28:45 to 22-12-2020 04:28:49.

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
01:56:58 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:58:00 23003 8192000000 9720075152 10014228480 795168624 8.847 100 87 174 0 823893920 7253536 21471760
01:59:26 24226 8192000000 9758937104 10063667200 835629088 9.256 100 85 170 0 866974656 11337216 20008352
02:00:52 16120 8192000000 9800956576 10119249920 872165936 9.622 100 82 164 0 909885968 18874192 18845840
02:02:13 15868 8192000000 9873774416 10206265344 924845344 10.144 100 83 165 1 983762784 41140544 17776896
02:03:34 8051 8192000000 9906589968 10250047488 955985680 10.450 100 80 160 3 1014141888 40955520 17200688
02:04:43 6765 8192000000 9950680368 10304327680 988927328 10.771 100 78 156 1 1063992608 59343872 15721408
02:05:51 3088 8192000000 9984690368 10344996864 1019395664 11.066 100 88 175 3 1104000448 67219744 17385040
02:06:56 2350 8192000000 10008051904 10374213632 1045944992 11.322 100 87 175 4 1126312048 62441520 17925536
02:07:59 1522 8192000000 10064186976 10429857792 1063582608 11.491 100 95 191 1 1168420176 85531696 19305872
02:09:01 38806 8192000000 9991365008 10389299200 1071531616 11.567 100 37 74 1 1109133264 29597120 8004528
02:10:41 4888 8192000000 10006344512 10399522816 1079554192 11.643 100 42 84 1 1119676640 30457808 9664640
02:11:48 4482 8192000000 10005187584 10401054720 1084184224 11.687 100 33 67 2 1118066656 27043024 6839408
02:12:56 11366 8192000000 10000197616 10402881536 1089912480 11.742 100 17 34 0 1114839584 21273840 3653264
02:14:11 48466 8192000000 10017921184 10412470272 1095544032 11.795 100 38 73 4 1121503888 18549232 7410624
02:16:01 9354 8192000000 10011212352 10417545216 1087722736 11.721 99 48 96 2 1127702016 29208160 10771120
02:17:11 10567 8192000000 10016405248 10424037376 1083237408 11.678 99 28 57 0 1117333456 28354864 5741184
02:18:24 7656 8192000000 9986191456 10386432000 1067015872 11.524 99 39 76 3 1092065472 16569904 8479696
02:19:36 32772 8192000000 9914689120 10307190784 912472208 10.022 87 18 36 1 942687104 26622000 3592896
02:21:13 4350 8192000000 9930898496 10311790592 996367456 10.843 97 41 79 2 1018530704 12042832 10120416
02:22:18 5264 8192000000 9958329024 10336296960 1020481456 11.077 99 57 114 2 1051327760 17393920 13452384
02:23:26 5584 8192000000 9962570464 10348003328 1043834128 11.302 100 39 79 3 1072287840 19837872 8615840
02:24:33 24772 8192000000 9962442928 10366763008 1052171440 11.381 100 32 63 3 1096018592 37457376 6389776
02:26:00 5806 8192000000 9982575728 10379173888 1065087216 11.505 100 41 82 2 1092393056 18147120 9158720
02:27:08 42008 8192000000 9997958208 10392903680 1074319040 11.593 100 46 92 2 1105818624 21060496 10439088
02:28:57 14490 8192000000 10047948576 10444034048 1098473712 11.823 100 45 88 2 1149408656 40486256 10448688
02:30:16 75394 8192000000 10111642448 10518994944 1115864192 11.988 100 39 78 0 1218904096 94463424 8576480
02:32:41 30077 8192000000 10140344576 10560004096 1126421872 12.088 100 42 82 1 1248320448 112397088 9501488
02:34:16 8094 8192000000 10162278944 10568724480 1138073616 12.197 100 51 102 3 1276053056 126767984 11211456
02:35:30 26607 8192000000 10176828608 10602606592 1143550704 12.249 100 41 81 4 1290011392 136535984 9924704
02:37:01 9200 8192000000 10231966800 10655830016 1171899344 12.515 100 59 118 0 1356877792 170315984 14662464
02:38:16 2975 8192000000 10296242288 10698809344 1179699952 12.587 100 88 176 1 1397591760 196895536 20996272
02:39:23 4211 8192000000 10325222992 10734866432 1192583760 12.707 100 69 136 0 1425639952 217775008 15281184
02:40:33 958 8192000000 10337476752 10753060864 1202616256 12.801 100 88 176 0 1439180832 216596720 19967856
02:41:38 2166 8192000000 10328263088 10742497280 1183815056 12.626 100 81 162 0 1431835936 229702528 18318352
02:42:44 1969 8192000000 10365331952 10767568896 1171070624 12.507 100 86 170 0 1462627456 271842320 19714512
02:43:50 4813 8192000000 10361027648 10768056320 1183757792 12.625 100 67 132 2 1457004256 257736208 15510256
02:45:01 13664 8192000000 10384486240 10795192320 1162102672 12.423 98 48 96 0 1461408528 287309952 11995904
02:46:18 13176 8192000000 10413110208 10860834816 1164764880 12.448 98 40 80 0 1503882640 328893328 10224432
02:47:36 8816 8192000000 10434796896 10876559360 1173417984 12.529 100 50 100 0 1552453008 365848112 13186912
02:48:51 7362 8192000000 10484311792 10940497920 1155021472 12.357 100 43 85 0 1577383456 410967072 11394912
02:50:01 21745 8192000000 10402517888 10866470912 1149164464 12.302 100 35 70 1 1541023392 381918576 9940352
02:51:24 22829 8192000000 10388202784 10866069504 1121243344 12.039 99 27 54 2 1515296400 386465840 7587216
02:52:48 53078 8192000000 10126170256 10654351360 1093199536 11.773 97 10 20 0 1275821440 180001184 2620720
02:55:13 90403 8192000000 10034229408 10474242048 1034240000 11.209 92 15 31 1 1078930976 41255792 3435184
02:57:51 91504 8192000000 10071668304 10491904000 1089319056 11.736 98 31 58 0 1146798656 50582272 6897328
03:00:30 129789 8192000000 10079590848 10511101952 1080674784 11.654 97 43 86 0 1153356896 63264672 9417440
03:03:46 34062 8192000000 10092739920 10554814464 1104489984 11.880 99 25 50 2 1197443088 87314592 5638512
03:05:29 32517 8192000000 10024095920 10478252032 1103393408 11.870 99 5 10 0 1134297904 29906448 998048
03:07:04 22306 8192000000 10048282048 10487463936 1120117424 12.028 99 18 36 1 1144456176 20573072 3765680
03:08:35 100877 8192000000 10058109200 10548994048 1135367488 12.172 100 22 44 3 1205509904 65438672 4703744
03:11:17 37524 8192000000 10077665168 10523901952 1133878752 12.158 100 13 26 1 1173370576 37111504 2380320
03:12:55 52087 8192000000 10132913408 10580594688 1136411264 12.182 99 17 35 2 1210641408 70371856 3858288
03:14:48 62611 8192000000 10124666896 10590683136 1143748128 12.251 100 18 35 0 1216166736 68284288 4134320
03:16:59 35649 8192000000 10157821408 10579279872 1152739280 12.335 100 42 84 1 1233499168 71959184 8800704
03:18:36 36678 8192000000 10115428688 10546495488 1157049904 12.376 100 45 90 2 1202809232 35923312 9836016
03:20:17 92581 8192000000 10077678368 10544283648 1100124544 11.839 96 19 38 0 1171561344 67657936 3778864
03:22:50 29107 8192000000 10089931680 10532872192 1133444496 12.154 99 22 43 4 1185145664 47293648 4407520
03:24:20 141709 8192000000 10115508448 10573012992 1139526928 12.211 100 32 64 0 1241059648 94801200 6731520
03:27:52 41807 8192000000 10064855904 10527789056 1139266400 12.209 100 7 14 0 1184495712 43680608 1548704
03:29:47 58813 8192000000 10068282080 10527748096 1099499360 11.833 97 14 28 1 1138298944 35915920 2883664
03:31:46 33996 8192000000 10130140416 10570977280 1109500656 11.928 97 37 74 3 1175244656 57206032 8537968
03:33:37 59593 8192000000 10110725072 10557288448 1078466272 11.633 95 24 48 0 1164201280 80305712 5429296
03:35:42 65188 8192000000 10159587136 10635894784 1106869200 11.903 97 27 53 3 1230326048 117180160 6276688
03:37:56 45940 8192000000 10198055200 10644557824 1129065840 12.113 99 17 34 0 1311618144 178118640 4433664
03:39:46 58735 8192000000 10086199472 10627923968 1070013456 11.552 96 17 34 0 1186762144 113160128 3588560
03:41:45 130784 8192000000 10036485392 10479702016 1026930624 11.139 92 20 40 0 1060980288 29970992 4078672
03:45:01 55442 8192000000 10010457408 10457247744 1080574544 11.653 98 8 17 1 1114445136 32274096 1596496
03:46:59 94844 8192000000 10060300640 10537332736 1116061232 11.990 100 17 35 2 1171680304 51198000 4421072
03:49:41 133442 8192000000 10079574800 10530099200 1071229312 11.564 95 13 26 2 1144822224 70947344 2645568
03:52:55 77886 8192000000 10090831104 10552545280 1109511296 11.928 98 25 50 2 1191385936 76540480 5334160
03:55:13 53694 8192000000 10082946896 10517983232 1121489856 12.041 99 21 41 1 1184380400 58299264 4591280
03:57:09 85364 8192000000 10047846656 10481475584 1073191216 11.583 95 7 14 1 1108879168 34305168 1382784
03:59:35 25000 8192000000 10110711760 10547703808 1132228080 12.142 100 40 80 3 1187525392 47414496 7882816
04:01:12 109166 8192000000 10064031328 10519711744 1117272720 12.001 99 24 48 0 1179720864 57598480 4849664
04:05:05 57248 8192000000 10091683616 10565591040 1127834128 12.101 99 5 10 0 1171532992 42841440 857424
04:07:02 113365 8192000000 10106793824 10593116160 1149815840 12.308 100 15 30 1 1202123872 49329664 2978368
04:10:02 87902 8192000000 10099477488 10592669696 1123545856 12.060 97 21 42 0 1178417728 50428752 4443120
04:12:33 33844 8192000000 10105643872 10546995200 1127992224 12.102 98 27 52 2 1183106464 49685360 5428880
04:14:08 48962 8192000000 10107835088 10559152128 1144287280 12.256 99 24 48 2 1194318560 45034576 4996704
04:15:58 36018 8192000000 10138899632 10594996224 1148584064 12.296 99 24 48 2 1220308432 67034704 4689664
04:17:35 55626 8192000000 10159207792 10681749504 1094545136 11.786 95 14 28 0 1251229168 153226288 3457744
04:19:31 147286 8192000000 10084169184 10552446976 1097086496 11.810 96 11 22 0 1140308768 41191744 2030528
04:23:00 61600 8192000000 10074087520 10567159808 1134888064 12.167 100 7 14 1 1192643536 56247200 1508272
04:25:02 71368 8192000000 10031364384 10492231680 1080012512 11.648 96 16 33 0 1110647664 27250528 3384624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2020 04:28:51 to 22-12-2020 04:29:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2020 04:29:07 to 22-12-2020 04:29:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229794
MON$OLDEST_ACTIVE               231259
MON$OLDEST_SNAPSHOT             231259
MON$NEXT_TRANSACTION            231259
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-30 07:30:08.6030 GMT
MON$PAGES                       2107456
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Tue Dec 22 04:27:09 2020
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
--- end of diff output ---

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

22.12.20 04:29:10. There are 3 logs in /var/tmp/logs-oltp40 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
2020-12-22 04:29:12 No severe PSQL-related problems occured
22.12.20 04:29:13. End of report.