Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2021 10:30
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 1200.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 16681532 kB
MemAvailable 31352100 kB
Buffers 0 kB
Cached 14931712 kB
SwapCached 4864 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 2021-01-02 10:26:56
DTS_END                         2021-01-02 10:26:56.2190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37216
TRN_ID                          231899
ATT_ID                          664
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: 8761.56 26072 1051387
customer order: creation 168.07 428 20168
customer order: refuse 13.83 335 1660
order to supplier: creation 45.08 1412 5410
order to supplier: removal 3.16 114284 379
invoice (draft): creation 44.34 3601 5321
invoice (draft): removal 46.93 916 5631
invoice accept: apply 41.64 18396 4997
invoice accept: cancel 42.51 931 5101
customer reserve: creation 3829.67 179 459560
customer reserve: removal 4248.14 69 509777
realization accept: apply 80.71 169 9685
realization accept: cancel 71.91 126 8629
payment from customer: creation 58.08 123 6970
payment from customer: removal 5.68 84 681
payment to supplier: creation 47.77 124 5732
payment to supplier: removal 3.18 75 381
service: total inventory turnovers 3.65 3669 438
service: total monetary turnovers 6.13 552 736
service: aggregate perf. data 1.06 15135 127
service: refresh index statistics 0.03 360841 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2021 10:30:22 to 02-01-2021 10:30:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1301
TEST_TIME 2 2751
TEST_TIME 3 3129
TEST_TIME 4 4004
TEST_TIME 5 4322
TEST_TIME 6 4623
TEST_TIME 7 4709
TEST_TIME 8 4709
TEST_TIME 9 4869
TEST_TIME 10 5200
TEST_TIME 11 5287
TEST_TIME 12 5714
TEST_TIME 13 5665
TEST_TIME 14 6060
TEST_TIME 15 6438
TEST_TIME 16 6677
TEST_TIME 17 7086
TEST_TIME 18 7481
TEST_TIME 19 7703
TEST_TIME 20 8272
TEST_TIME 21 8404
TEST_TIME 22 8556
TEST_TIME 23 8765
TEST_TIME 24 8966
TEST_TIME 25 8949
TEST_TIME 26 9387
TEST_TIME 27 9819
TEST_TIME 28 9720
TEST_TIME 29 9804
TEST_TIME 30 10571
TEST_TIME 31 10650
TEST_TIME 32 10600
TEST_TIME 33 10537
TEST_TIME 34 10231
TEST_TIME 35 10091
TEST_TIME 36 10022
TEST_TIME 37 10066
TEST_TIME 38 9971
TEST_TIME 39 9849
TEST_TIME 40 9672
TEST_TIME 41 9650
TEST_TIME 42 9533
TEST_TIME 43 9532
TEST_TIME 44 9462
TEST_TIME 45 9341
TEST_TIME 46 9261
TEST_TIME 47 9204
TEST_TIME 48 9116
TEST_TIME 49 9076
TEST_TIME 50 8955
TEST_TIME 51 9072
TEST_TIME 52 9027
TEST_TIME 53 9176
TEST_TIME 54 9189
TEST_TIME 55 9137
TEST_TIME 56 9175
TEST_TIME 57 9180
TEST_TIME 58 9274
TEST_TIME 59 9252
TEST_TIME 60 9172
TEST_TIME 61 9219
TEST_TIME 62 9256
TEST_TIME 63 9283
TEST_TIME 64 9182
TEST_TIME 65 9098
TEST_TIME 66 9028
TEST_TIME 67 8958
TEST_TIME 68 8938
TEST_TIME 69 8909
TEST_TIME 70 8876
TEST_TIME 71 8861
TEST_TIME 72 8830
TEST_TIME 73 8881
TEST_TIME 74 8899
TEST_TIME 75 8942
TEST_TIME 76 8977
TEST_TIME 77 8963
TEST_TIME 78 9028
TEST_TIME 79 9130
TEST_TIME 80 9158
TEST_TIME 81 9212
TEST_TIME 82 9109
TEST_TIME 83 9045
TEST_TIME 84 9003
TEST_TIME 85 8955
TEST_TIME 86 8938
TEST_TIME 87 8911
TEST_TIME 88 8901
TEST_TIME 89 8870
TEST_TIME 90 8911
TEST_TIME 91 8922
TEST_TIME 92 8896
TEST_TIME 93 8916
TEST_TIME 94 8903
TEST_TIME 95 8922
TEST_TIME 96 9004
TEST_TIME 97 9018
TEST_TIME 98 8988
TEST_TIME 99 9011
TEST_TIME 100 9022
TEST_TIME 101 8949
TEST_TIME 102 8886
TEST_TIME 103 8861
TEST_TIME 104 8817
TEST_TIME 105 8786
TEST_TIME 106 8741
TEST_TIME 107 8721
TEST_TIME 108 8689
TEST_TIME 109 8647
TEST_TIME 110 8635
TEST_TIME 111 8659
TEST_TIME 112 8637
TEST_TIME 113 8709
TEST_TIME 114 8767
TEST_TIME 115 8744
TEST_TIME 116 8742
TEST_TIME 117 8795
TEST_TIME 118 8796
TEST_TIME 119 8829
TEST_TIME 120 8764

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2021 10:30:26 to 02-01-2021 10:30:29.

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 1475182 1475182 0 0.00 0 25329 7 0 0
doc_list_biud 1475177 1475177 0 0.00 0 44979 30 0 0
sp_add_invoice_to_stock 5113 4997 116 2.26 27 192999 18396 0 116
sp_cancel_adding_invoice 5101 5101 0 0.00 5 97225 932 0 0
sp_cancel_client_order 1660 1660 0 0.00 4 15018 335 0 0
sp_cancel_customer_reserve 509777 509777 0 0.00 1 33487 69 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 2 7357 85 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 4812 76 0 0
sp_cancel_supplier_invoice 5639 5631 8 0.14 1 17090 917 0 8
sp_cancel_supplier_order 379 379 0 0.00 18 918731 114285 0 0
sp_cancel_write_off 8629 8629 0 0.00 1 17808 127 0 0
sp_client_order 20168 20168 0 0.00 4 16782 429 0 0
sp_customer_reserve 459560 459560 0 0.00 0 66434 180 0 0
sp_fill_shopping_cart 30886 30886 0 0.00 1 10965 46 0 0
sp_fill_shopping_cart_clo_res 459544 459544 0 0.00 0 20208 16 0 0
sp_get_clo_for_invoice 4981 4981 0 0.00 0 57372 412 0 0
sp_kill_cost_storno 14792 14792 0 0.00 0 6234 58 0 0
sp_kill_qstorno_ret_qs2qd 1034193 1034193 0 0.00 0 22899 37 0 0
sp_kill_qty_storno 545843 545843 0 0.00 0 44979 80 0 0
sp_lock_selected_doc 20194 20194 0 0.00 0 7608 5 0 0
sp_make_cost_storno 27368 27368 0 0.00 0 12469 54 0 0
sp_multiply_rows_for_pdistr 27368 27368 0 0.00 0 6984 30 0 0
sp_multiply_rows_for_qdistr 20168 20168 0 0.00 0 10192 231 0 0
sp_pay_from_customer 6970 6970 0 0.00 4 13614 124 0 0
sp_pay_to_supplier 5732 5732 0 0.00 5 10556 125 0 0
sp_payment_common 12702 12702 0 0.00 1 8145 26 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 15012 298 0 0
sp_qd_handle_on_invoice_upd_sts 10082 10082 0 0.00 0 44979 387 0 0
sp_qd_handle_on_reserve_upd_sts 9685 9685 0 0.00 0 9695 49 0 0
sp_reserve_write_off 9685 9685 0 0.00 6 12494 170 0 0
sp_supplier_invoice 5321 5321 0 0.00 6 98279 3602 0 0
sp_supplier_order 5410 5410 0 0.00 6 35581 1413 0 0
srv_aggregate_perf_data 127 127 0 0.00 2414 359403 15135 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1396 151820 47073 0 0
srv_find_qd_qs_mism 1003642 1003642 0 0.00 0 25329 6 0 0
srv_make_invnt_saldo 438 438 0 0.00 45 88059 3670 0 0
srv_make_money_saldo 736 736 0 0.00 1 12843 552 0 0
srv_recalc_idx_stat 4 4 0 0.00 214013 521400 360842 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 4997 4997 0 0.00 0 12455 190 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 2942 76 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 743 9 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 1075 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 1788 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 456 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1280 15 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 21692 1570 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 646 4 0 0
t$perf-norm:sp_client_order 20168 20168 0 0.00 0 8827 18 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 674 4 0 0
t$perf-norm:sp_pay_from_customer 6970 6970 0 0.00 0 10674 9 0 0
t$perf-norm:sp_pay_to_supplier 5732 5732 0 0.00 0 6549 8 0 0
t$perf-norm:sp_reserve_write_off 9685 9685 0 0.00 0 5829 11 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 10113 19 0 0
t$perf-norm:sp_supplier_order 5410 5410 0 0.00 0 5989 16 0 0
t$perf-norm:srv_aggregate_perf_data 127 127 0 0.00 0 685 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 428 5 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 566 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10192 9960 232 2.27 0 5405 16 0 232
v_all_customers 36352 36352 0 0.00 0 259 1 0 0
v_all_suppliers 29602 29602 0 0.00 0 259 1 0 0
v_all_wares 40336 40336 0 0.00 0 117 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2573 10 0 0
v_cancel_client_order 3320 3320 0 0.00 0 8441 8 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 4136 7 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 187 3 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 3604 8 0 0
v_cancel_supplier_invoice 784 768 16 2.04 0 6307 24 0 16
v_cancel_supplier_order 758 758 0 0.00 0 2078 8 0 0
v_cancel_write_off 2028 2028 0 0.00 0 3943 15 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 682 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 2910 21 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 5332 5 0 0
v_max_non_paid_invoice 1649 1649 0 0.00 0 767 3 0 0
v_max_non_paid_realizn 3897 3897 0 0.00 0 2016 3 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 1642 5 0 0
v_min_id_clo_res 993 993 0 0.00 0 8396 20 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 8772 8 0 0
v_min_non_paid_invoice 5732 5732 0 0.00 0 5682 13 0 0
v_min_non_paid_realizn 6970 6970 0 0.00 0 5928 18 0 0
v_our_firm 10130 10130 0 0.00 0 14 1 0 0
v_reserve_write_off 19370 19370 0 0.00 0 6405 5 0 0
x_lock_depdocs_on_canc_invoice 10348 10348 0 0.00 0 9353 114 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 8503 379 0 0
x_make_qstorno_1000_1200 5410 5410 0 0.00 2 35485 1335 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 2 88817 3415 0 0
x_make_qstorno_2100_3300 459560 459560 0 0.00 0 66433 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2021 10:30:30 to 02-01-2021 10:30:34.

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
07:56:59 10 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:00 34653 8192000000 9744390992 10044387328 820603472 9.105 100 84 168 3 849306624 8239120 20464032
07:59:37 28975 8192000000 9786644608 10095697920 852476768 9.425 100 89 177 1 888577680 15094464 21006448
08:01:11 14165 8192000000 9821284976 10142814208 881209712 9.712 100 85 170 1 931064512 29918528 19936272
08:02:30 13392 8192000000 9880138176 10211196928 914917872 10.046 100 85 168 2 988956624 55150304 18888448
08:03:47 5838 8192000000 9922874496 10267820032 951219776 10.403 100 75 150 0 1041246480 74189584 15837120
08:04:55 6465 8192000000 9958322688 10314141696 994591744 10.826 100 85 168 3 1073307904 61821424 16894736
08:06:04 3012 8192000000 9993186992 10356654080 1029979760 11.168 100 83 164 0 1105327504 58470848 16876896
08:07:09 1902 8192000000 10045612992 10415460352 1058900832 11.446 100 92 183 4 1156388208 78993712 18493664
08:08:12 1285 8192000000 10044025424 10418339840 1075777328 11.607 100 93 186 2 1156148976 60853440 19518208
08:09:14 35154 8192000000 10008023376 10393968640 1082555008 11.672 100 56 111 1 1115391472 21080656 11755808
08:10:50 11194 8192000000 10007752944 10411151360 1082706016 11.673 100 31 62 0 1114148672 24839376 6603280
08:12:03 7987 8192000000 9991854256 10395545600 1090905328 11.751 100 32 64 2 1113947440 15551904 7490208
08:13:16 8256 8192000000 9997139968 10417950720 1093593056 11.777 100 32 63 2 1142327072 41479840 7254176
08:14:25 11716 8192000000 10010512464 10419552256 1092790720 11.769 100 30 60 0 1116520448 17523520 6206208
08:15:37 55029 8192000000 9984510464 10408689664 1086119408 11.706 99 36 73 1 1106894736 12872464 7902864
08:17:33 5703 8192000000 10030467696 10433126400 1086096416 11.706 100 32 64 2 1155952624 63495136 6361072
08:18:41 5125 8192000000 9955826256 10344431616 1030574192 11.174 98 39 79 1 1059419840 20739344 8106304
08:19:49 7337 8192000000 9929555632 10312200192 968031920 10.567 94 19 36 1 991346128 18554880 4759328
08:20:58 64425 8192000000 9915528800 10303721472 966522976 10.553 95 28 56 4 989278096 17003200 5751920
08:23:09 9181 8192000000 9948874592 10334466048 1032727680 11.195 100 39 76 2 1069364000 28047488 8588832
08:24:19 8848 8192000000 9961508016 10357784576 1041118832 11.275 100 36 70 0 1076396144 28132224 7145088
08:25:28 11346 8192000000 9958994096 10355609600 1054147504 11.400 100 23 47 1 1092341904 32688528 5505872
08:26:39 101940 8192000000 10004354672 10399830016 1066110224 11.515 100 38 73 2 1096349152 22548448 7690480
08:29:26 19104 8192000000 10070512672 10467319808 1105130496 11.886 100 50 97 0 1179201904 63765952 10305456
08:30:54 4980 8192000000 10084514096 10488524800 1119006368 12.018 100 60 118 2 1188567872 57218912 12342592
08:32:08 14020 8192000000 10104248144 10505805824 1128527104 12.107 100 56 112 0 1212294752 72068352 11699296
08:33:34 10880 8192000000 10129166720 10537201664 1130691936 12.128 100 47 94 2 1228216416 87137920 10386560
08:34:50 6758 8192000000 10148813776 10556129280 1140503520 12.220 100 49 94 4 1261089856 109814768 10771568
08:36:03 6131 8192000000 10197098608 10606272512 1156545264 12.371 100 65 129 1 1307890416 137511104 13834048
08:37:13 6731 8192000000 10210816240 10619736064 1160688560 12.410 100 57 111 2 1312003696 139301488 12013648
08:38:32 8057 8192000000 10226460496 10649829376 1174855296 12.542 100 52 102 2 1331322080 145247520 11219264
08:39:45 2690 8192000000 10265900528 10665742336 1168455472 12.482 100 72 144 0 1365475120 180926624 16093024
08:40:54 5049 8192000000 10275307264 10678566912 1172395568 12.519 100 79 153 4 1377840992 188631056 16814368
08:42:05 6336 8192000000 10314545536 10720362496 1171017808 12.506 100 78 154 1 1413937568 225843648 17076112
08:43:16 1391 8192000000 10338434336 10740699136 1181677232 12.606 100 93 186 3 1434630368 231606752 21346384
08:44:20 8022 8192000000 10289629712 10702438400 1148111664 12.292 99 75 149 0 1384143584 218644624 17387296
08:45:32 1804 8192000000 10278404064 10696863744 1129882528 12.120 98 80 157 0 1367162928 218061184 19219216
08:46:38 8661 8192000000 10257728512 10688552960 1148898800 12.299 100 49 98 1 1354646176 194301968 11445408
08:47:51 8685 8192000000 10268119776 10685911040 1130038016 12.122 99 41 81 2 1365022800 224983056 10001728
08:49:02 6995 8192000000 10287436592 10704150528 1133664512 12.156 99 42 85 0 1377600000 233842880 10092608
08:50:14 15080 8192000000 10254300928 10689974272 1107734464 11.911 99 35 69 1 1369158048 253056240 8367344
08:51:30 16904 8192000000 10282141712 10703867904 1094675504 11.787 99 35 68 4 1380164640 276963616 8525520
08:52:59 33820 8192000000 10191236992 10643849216 1105674832 11.891 99 26 51 2 1335813824 223235344 6903648
08:54:34 27314 8192000000 10231966672 10684846080 1097711456 11.816 99 15 30 0 1321211040 219261888 4237696
08:56:29 14287 8192000000 10077438944 10561196032 1107805040 11.912 100 14 28 2 1257390000 146112544 3472416
08:57:52 73426 8192000000 10083179680 10503057408 1110608160 11.938 99 23 46 2 1176292688 61055984 4628544
09:00:07 78144 8192000000 10097047296 10526113792 1123416960 12.059 99 49 95 1 1189140880 56078320 9645600
09:02:27 20761 8192000000 10076800448 10499596288 1106437728 11.899 99 43 86 1 1169666768 54218144 9010896
09:03:49 47205 8192000000 10037424960 10494943232 1110054064 11.933 99 12 24 0 1151065088 38784224 2226800
09:05:47 46761 8192000000 10084518528 10520059904 1135151760 12.170 100 30 61 1 1182644528 41246352 6246416
09:07:35 39672 8192000000 10072529712 10514911232 1126580480 12.089 99 13 26 0 1173897936 44344592 2972864
09:09:15 69247 8192000000 10081959200 10555756544 1143369872 12.247 100 12 23 0 1183531808 38294144 1867792
09:11:31 83923 8192000000 10087906032 10541309952 1137574928 12.193 99 23 46 0 1187846592 45319168 4952496
09:13:56 43487 8192000000 10096114592 10541903872 1130437392 12.125 98 21 42 2 1187833936 52760640 4635904
09:15:40 89888 8192000000 10186576864 10677288960 1152268384 12.331 100 13 26 1 1258269968 102700048 3301536
09:18:49 54296 8192000000 10088092624 10558509056 1119236592 12.020 97 9 18 1 1172872768 51772416 1863760
09:21:26 54891 8192000000 10108027424 10556096512 1138378064 12.200 99 25 48 0 1189633888 46341888 4913936
09:23:21 79407 8192000000 10145445280 10588217344 1143846496 12.252 99 16 32 0 1230145808 82704384 3594928
09:25:41 33540 8192000000 10091353728 10517704704 1165129536 12.451 100 38 76 0 1191687328 18493872 8063920
09:27:15 65957 8192000000 10081053888 10544377856 1109378752 11.927 96 21 42 0 1184060112 70481088 4200272
09:29:24 151820 8192000000 10076315616 10519973888 1113528464 11.966 97 16 32 1 1156007264 39570832 2907968
09:33:01 68496 8192000000 10076302880 10550247424 1122053840 12.046 98 8 16 1 1158677632 35091504 1532288
09:35:24 20901 8192000000 10078946704 10505977856 1097487312 11.814 97 37 73 0 1136562800 31857808 7217680
09:36:45 36319 8192000000 10136511344 10566963200 1137265536 12.190 100 30 60 0 1221345824 77251072 6829216
09:38:34 52401 8192000000 10162198352 10635415552 1127132048 12.094 98 24 48 0 1256019920 123661664 5226208
09:40:26 59823 8192000000 10179587008 10609909760 1083978992 11.685 95 25 50 0 1223993056 133907440 6106624
09:42:26 53286 8192000000 10136693488 10592784384 1076501376 11.614 96 17 34 1 1209851520 129519168 3830976
09:44:30 26815 8192000000 10152388128 10578337792 1112348896 11.955 100 33 66 1 1242503920 123451088 6703936
09:46:06 43386 8192000000 10102306848 10554613760 1082067040 11.667 97 41 82 2 1176363728 85655712 8640976
09:47:53 115318 8192000000 10035827200 10475941888 1099791936 11.836 99 16 32 0 1134800448 30762016 4246496
09:50:58 61174 8192000000 10043642464 10497556480 1106015184 11.895 99 10 20 0 1135622080 27628400 1978496
09:53:41 85305 8192000000 10083155696 10546077696 1090158848 11.744 97 16 32 0 1135696192 42255728 3281616
09:56:07 142126 8192000000 10072581488 10507694080 1111366384 11.945 99 10 20 0 1154050864 40826656 1857824
09:59:29 92582 8192000000 10100258464 10549694464 1089815312 11.741 96 17 34 1 1121359696 28440784 3103600
10:02:12 91903 8192000000 10102083296 10537975808 1131869248 12.139 99 12 24 0 1185981664 51852240 2260176
10:04:47 59596 8192000000 10112470144 10576306176 1131578496 12.136 99 33 64 1 1186729040 48628816 6521728
10:06:50 111806 8192000000 10088772384 10532663296 1133935888 12.158 99 29 58 1 1195100768 55182832 5982048
10:10:16 77879 8192000000 10098824768 10554007552 1155131936 12.358 100 10 21 0 1203086624 45396736 2557952
10:13:03 63568 8192000000 10074094048 10541875200 1134756496 12.166 98 6 12 1 1173984992 37769232 1459264
10:15:07 45920 8192000000 10116643104 10560114688 1131295472 12.134 98 22 44 1 1193340080 57108512 4936096
10:16:53 75465 8192000000 10134782352 10600341504 1137080752 12.188 98 20 39 0 1227552800 85800160 4671888
10:19:10 65167 8192000000 10163853664 10682478592 1151077712 12.320 99 27 52 2 1270722096 113827808 5816576
10:21:15 46720 8192000000 10141534256 10584203264 1119911632 12.026 97 26 52 1 1196765712 70975664 5878416
10:23:29 39171 8192000000 10096361984 10522021888 1117758800 12.006 97 32 62 0 1166856992 43063168 6035024
10:25:12 62276 8192000000 10099542528 10521260032 1129527536 12.117 99 35 70 2 1207674560 71057632 7089392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-01-2021 10:30:36 to 02-01-2021 10:30:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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:01, from 02-01-2021 10:30:52 to 02-01-2021 10:30:53.

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          232279
MON$OLDEST_ACTIVE               232280
MON$OLDEST_SNAPSHOT             232280
MON$NEXT_TRANSACTION            232280
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                       2106496
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             787
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
--- end of diff output ---

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

02.01.21 10:30:54. 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
2021-01-02 10:30:57 No severe PSQL-related problems occured
02.01.21 10:30:58. End of report.