Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.01.2021 10:27
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 16684972 kB
MemAvailable 31242824 kB
Buffers 0 kB
Cached 14960360 kB
SwapCached 4868 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-27 10:26:55
DTS_END                         2021-01-27 10:26:55.4280
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/55336
TRN_ID                          235154
ATT_ID                          673
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: 8906.86 20027 1068823
customer order: creation 174.08 376 20890
customer order: refuse 14.90 288 1788
order to supplier: creation 45.23 1396 5428
order to supplier: removal 3.38 102537 406
invoice (draft): creation 45.91 3674 5509
invoice (draft): removal 50.34 794 6041
invoice accept: apply 41.13 18345 4935
invoice accept: cancel 45.00 790 5400
customer reserve: creation 3783.28 180 453994
customer reserve: removal 4409.63 63 529156
realization accept: apply 83.45 162 10014
realization accept: cancel 82.03 113 9844
payment from customer: creation 59.35 102 7122
payment from customer: removal 5.67 67 680
payment to supplier: creation 48.47 111 5816
payment to supplier: removal 3.33 62 399
service: total inventory turnovers 4.52 2665 542
service: total monetary turnovers 6.19 553 743
service: aggregate perf. data 0.93 14555 111
service: refresh index statistics 0.04 253707 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-01-2021 10:27:43 to 27-01-2021 10:27:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1428
TEST_TIME 2 2448
TEST_TIME 3 3579
TEST_TIME 4 4566
TEST_TIME 5 4547
TEST_TIME 6 5028
TEST_TIME 7 5067
TEST_TIME 8 5419
TEST_TIME 9 5419
TEST_TIME 10 5762
TEST_TIME 11 5726
TEST_TIME 12 5847
TEST_TIME 13 6120
TEST_TIME 14 6124
TEST_TIME 15 6875
TEST_TIME 16 7054
TEST_TIME 17 7564
TEST_TIME 18 7661
TEST_TIME 19 7901
TEST_TIME 20 8195
TEST_TIME 21 8636
TEST_TIME 22 8925
TEST_TIME 23 9238
TEST_TIME 24 9356
TEST_TIME 25 10252
TEST_TIME 26 10595
TEST_TIME 27 10456
TEST_TIME 28 10283
TEST_TIME 29 10522
TEST_TIME 30 10314
TEST_TIME 31 10004
TEST_TIME 32 9902
TEST_TIME 33 9872
TEST_TIME 34 9844
TEST_TIME 35 9889
TEST_TIME 36 9733
TEST_TIME 37 9577
TEST_TIME 38 9507
TEST_TIME 39 9477
TEST_TIME 40 9468
TEST_TIME 41 9577
TEST_TIME 42 9456
TEST_TIME 43 9685
TEST_TIME 44 9647
TEST_TIME 45 9558
TEST_TIME 46 9588
TEST_TIME 47 9557
TEST_TIME 48 9552
TEST_TIME 49 9527
TEST_TIME 50 9505
TEST_TIME 51 9583
TEST_TIME 52 9551
TEST_TIME 53 9380
TEST_TIME 54 9373
TEST_TIME 55 9256
TEST_TIME 56 9199
TEST_TIME 57 9213
TEST_TIME 58 9142
TEST_TIME 59 9186
TEST_TIME 60 9184
TEST_TIME 61 9164
TEST_TIME 62 9206
TEST_TIME 63 9171
TEST_TIME 64 9130
TEST_TIME 65 9261
TEST_TIME 66 9229
TEST_TIME 67 9313
TEST_TIME 68 9340
TEST_TIME 69 9260
TEST_TIME 70 9166
TEST_TIME 71 9092
TEST_TIME 72 9075
TEST_TIME 73 8993
TEST_TIME 74 8946
TEST_TIME 75 8965
TEST_TIME 76 8942
TEST_TIME 77 8944
TEST_TIME 78 8957
TEST_TIME 79 8969
TEST_TIME 80 8979
TEST_TIME 81 8931
TEST_TIME 82 9006
TEST_TIME 83 9054
TEST_TIME 84 9083
TEST_TIME 85 9064
TEST_TIME 86 9005
TEST_TIME 87 8918
TEST_TIME 88 8896
TEST_TIME 89 8934
TEST_TIME 90 8905
TEST_TIME 91 8913
TEST_TIME 92 8914
TEST_TIME 93 8913
TEST_TIME 94 8902
TEST_TIME 95 8892
TEST_TIME 96 8919
TEST_TIME 97 9055
TEST_TIME 98 9018
TEST_TIME 99 9012
TEST_TIME 100 8985
TEST_TIME 101 8983
TEST_TIME 102 9051
TEST_TIME 103 9140
TEST_TIME 104 9106
TEST_TIME 105 9098
TEST_TIME 106 9020
TEST_TIME 107 8971
TEST_TIME 108 8917
TEST_TIME 109 8860
TEST_TIME 110 8850
TEST_TIME 111 8866
TEST_TIME 112 8870
TEST_TIME 113 8862
TEST_TIME 114 8822
TEST_TIME 115 8809
TEST_TIME 116 8855
TEST_TIME 117 8849
TEST_TIME 118 8894
TEST_TIME 119 8911
TEST_TIME 120 8909

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-01-2021 10:27:46 to 27-01-2021 10:27:50.

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 1488766 1488766 0 0.00 0 19984 7 0 0
doc_list_biud 1488766 1488766 0 0.00 0 58335 28 0 0
sp_add_invoice_to_stock 5072 4935 137 2.70 56 219397 18346 0 137
sp_cancel_adding_invoice 5400 5400 0 0.00 4 93819 790 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 12437 288 0 0
sp_cancel_customer_reserve 529156 529156 0 0.00 1 58336 64 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 3639 68 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 5630 63 0 0
sp_cancel_supplier_invoice 6049 6041 8 0.13 2 26409 795 0 8
sp_cancel_supplier_order 406 406 0 0.00 7 1147306 102538 0 0
sp_cancel_write_off 9844 9844 0 0.00 1 14083 113 0 0
sp_client_order 20890 20890 0 0.00 4 25247 377 0 0
sp_customer_reserve 453994 453994 0 0.00 0 65510 181 0 0
sp_fill_shopping_cart 31810 31810 0 0.00 1 21724 45 0 0
sp_fill_shopping_cart_clo_res 453989 453989 0 0.00 0 17180 17 0 0
sp_get_clo_for_invoice 4930 4930 0 0.00 0 31653 418 0 0
sp_kill_cost_storno 16323 16323 0 0.00 0 12050 52 0 0
sp_kill_qstorno_ret_qs2qd 1074603 1074603 0 0.00 0 58305 34 0 0
sp_kill_qty_storno 567579 567579 0 0.00 0 58335 73 0 0
sp_lock_selected_doc 20715 20715 0 0.00 0 7333 6 0 0
sp_make_cost_storno 27882 27882 0 0.00 0 19975 52 0 0
sp_multiply_rows_for_pdistr 27882 27882 0 0.00 0 11266 26 0 0
sp_multiply_rows_for_qdistr 20890 20890 0 0.00 0 21095 214 0 0
sp_pay_from_customer 7122 7122 0 0.00 4 12629 103 0 0
sp_pay_to_supplier 5816 5816 0 0.00 5 14289 112 0 0
sp_payment_common 12938 12938 0 0.00 1 7688 18 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 12430 267 0 0
sp_qd_handle_on_invoice_upd_sts 10330 10330 0 0.00 0 25484 338 0 0
sp_qd_handle_on_reserve_upd_sts 10014 10014 0 0.00 0 12220 44 0 0
sp_reserve_write_off 10014 10014 0 0.00 6 18461 162 0 0
sp_supplier_invoice 5509 5509 0 0.00 7 112979 3675 0 0
sp_supplier_order 5428 5428 0 0.00 6 82757 1396 0 0
srv_aggregate_perf_data 111 111 0 0.00 2933 266580 14556 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1242 141173 45494 0 0
srv_find_qd_qs_mism 1021174 1021174 0 0.00 0 17300 6 0 0
srv_make_invnt_saldo 542 542 0 0.00 37 88588 2666 0 0
srv_make_money_saldo 743 743 0 0.00 1 21617 553 0 0
srv_recalc_idx_stat 5 5 0 0.00 153460 382547 253708 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 4935 4935 0 0.00 0 25712 154 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 10499 131 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 6834 18 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 2002 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 4055 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1127 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1320 18 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21524 1238 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 2194 6 0 0
t$perf-norm:sp_client_order 20890 20890 0 0.00 0 7160 15 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 4956 12 0 0
t$perf-norm:sp_pay_from_customer 7122 7122 0 0.00 0 10062 10 0 0
t$perf-norm:sp_pay_to_supplier 5816 5816 0 0.00 0 11401 14 0 0
t$perf-norm:sp_reserve_write_off 10014 10014 0 0.00 0 6722 9 0 0
t$perf-norm:sp_supplier_invoice 5509 5509 0 0.00 0 6411 18 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 5970 13 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1465 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4086 111 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 10011 23 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 1280 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10134 9860 274 2.70 0 5270 19 0 274
v_all_customers 37724 37724 0 0.00 0 84 1 0 0
v_all_suppliers 30148 30148 0 0.00 0 84 1 0 0
v_all_wares 41780 41780 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2850 12 0 0
v_cancel_client_order 3576 3576 0 0.00 0 1940 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2611 5 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 4261 7 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 694 5 0 0
v_cancel_supplier_invoice 842 826 16 1.90 0 1480 16 0 16
v_cancel_supplier_order 812 812 0 0.00 0 500 4 0 0
v_cancel_write_off 1936 1936 0 0.00 0 4527 17 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 151 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 2112 12 0 0
v_max_id_ord_sup 5492 5492 0 0.00 0 6523 8 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 151 2 0 0
v_max_non_paid_realizn 3854 3854 0 0.00 0 2528 3 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 651 3 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 1282 4 0 0
v_min_id_ord_sup 5492 5492 0 0.00 0 7435 7 0 0
v_min_non_paid_invoice 5816 5816 0 0.00 0 5149 10 0 0
v_min_non_paid_realizn 7122 7122 0 0.00 0 5157 13 0 0
v_our_firm 10592 10592 0 0.00 0 16 1 0 0
v_reserve_write_off 20028 20028 0 0.00 0 4354 7 0 0
x_lock_depdocs_on_canc_invoice 11028 11028 0 0.00 0 20308 110 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 22107 360 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 82747 1327 0 0
x_make_qstorno_1200_2000 5509 5509 0 0.00 3 112959 3490 0 0
x_make_qstorno_2100_3300 453994 453994 0 0.00 0 65508 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-01-2021 10:27:51 to 27-01-2021 10:27:55.

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 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:57:59 39766 8192000000 9730663600 10027532288 807363168 8.971 100 85 170 0 834967968 7593664 20011136
07:59:49 22212 8192000000 9762479344 10067996672 837092976 9.271 100 88 176 0 872322448 14456208 20773264
08:01:17 18685 8192000000 9805299584 10125676544 872255280 9.623 100 88 177 0 917245504 25134832 19855392
08:02:39 11094 8192000000 9879834896 10210168832 921956736 10.115 100 90 180 1 993954352 52382080 19615536
08:04:00 5510 8192000000 9943958864 10287071232 966973520 10.557 100 90 181 2 1059792976 73851568 18967888
08:05:08 3538 8192000000 9953963328 10311471104 996052752 10.840 100 75 148 1 1078617984 67658176 14907056
08:06:15 3564 8192000000 9995167536 10356109312 1015525744 11.029 100 70 139 0 1101971584 72463392 13982448
08:07:21 1925 8192000000 10016350096 10382450688 1047930928 11.341 100 95 190 1 1125754160 58191184 19632048
08:08:25 1474 8192000000 10044297344 10418057216 1063052064 11.486 100 86 173 0 1156189168 76134288 17002816
08:09:28 2086 8192000000 10028724384 10411032576 1069303488 11.545 100 75 150 1 1135156016 50227552 15624976
08:10:32 4029 8192000000 10017647552 10414997504 1079499616 11.643 100 39 78 0 1120770128 33350656 7919856
08:11:40 6130 8192000000 10004474560 10410631168 1084531968 11.691 100 32 64 2 1113255152 21545728 7177456
08:12:48 16444 8192000000 10014585168 10425257984 1085416928 11.699 100 31 62 2 1122516896 30987408 6112560
08:14:11 6024 8192000000 10006543904 10406735872 1093630800 11.777 100 36 71 0 1125782368 24118544 8033024
08:15:17 9300 8192000000 10016150160 10417680384 1093538864 11.776 100 45 88 2 1122726016 19579200 9607952
08:16:30 60792 8192000000 9987967264 10403250176 1062811488 11.483 98 33 66 0 1097652512 27236096 7604928
08:18:32 7959 8192000000 9960504304 10349465600 1013372320 11.008 95 41 83 1 1048945296 26477904 9095072
08:19:42 10954 8192000000 9965338496 10359459840 1024602784 11.116 97 35 69 0 1050110672 17954528 7553360
08:20:53 3415 8192000000 9931542192 10301194240 1001928992 10.897 97 37 71 2 1020871616 11348336 7594288
08:21:58 46856 8192000000 9918636416 10309476352 931987216 10.214 90 27 52 0 960130976 22619280 5524480
08:23:46 3816 8192000000 9966530512 10353590272 1049821792 11.359 100 52 102 2 1075981648 15366640 10793216
08:24:50 12387 8192000000 9982569808 10379538432 1053622384 11.395 100 37 74 2 1082165136 20141520 8401232
08:26:03 58264 8192000000 9992519024 10390577152 1079553776 11.643 100 34 68 0 1106006256 18848720 7603760
08:28:13 1753 8192000000 10036938576 10425372672 1098096160 11.820 100 60 118 2 1143609776 32629488 12884128
08:29:19 7788 8192000000 10057190400 10458193920 1107444080 11.908 100 38 75 0 1169387360 53969952 7973328
08:30:30 20081 8192000000 10108978752 10507460608 1121279392 12.039 100 53 108 0 1213816432 81508016 11029024
08:31:54 18274 8192000000 10107668336 10528333824 1131194112 12.133 100 48 96 1 1225963824 85063920 9705792
08:33:15 13710 8192000000 10131636352 10541928448 1139023312 12.206 100 48 96 1 1244562560 95023248 10516000
08:34:40 6624 8192000000 10178697520 10591854592 1155813792 12.364 100 58 117 2 1285333680 116881536 12638352
08:35:51 8909 8192000000 10202400608 10612322304 1167453648 12.473 100 54 108 0 1307847312 128247392 12146272
08:37:04 3443 8192000000 10219587536 10626777088 1170436896 12.501 100 63 126 0 1325093536 140746336 13910304
08:38:14 6467 8192000000 10285795984 10692661248 1180370064 12.594 100 64 125 0 1385694064 191608064 13715936
08:39:25 3871 8192000000 10339866928 10754117632 1180838512 12.598 100 71 142 0 1441948464 244636656 16473296
08:40:33 5718 8192000000 10346986464 10758348800 1184164016 12.629 100 77 154 0 1445235728 242785888 18285824
08:41:45 1242 8192000000 10370128720 10789322752 1186415024 12.650 100 85 169 1 1473216752 267697456 19104272
08:42:50 2178 8192000000 10356974080 10760167424 1159801584 12.401 99 81 156 2 1453105328 275990624 17313120
08:43:58 4773 8192000000 10343551504 10752397312 1144026720 12.253 98 47 93 3 1438561808 283381600 11153488
08:45:06 7101 8192000000 10382918848 10787999744 1117745232 12.006 98 69 136 6 1472754128 337281024 17727872
08:46:18 24754 8192000000 10394072464 10826309632 1122171120 12.047 98 38 76 1 1486599264 354183056 10245088
08:47:49 9974 8192000000 10378048992 10802823168 1151315408 12.322 100 37 74 0 1481362576 320377616 9669552
08:49:01 16256 8192000000 10334998080 10791809024 1129800912 12.119 99 32 64 0 1432971184 295498832 7671440
08:50:20 32107 8192000000 10272943680 10744791040 1119396224 12.021 100 15 29 0 1376788592 253206048 4186320
08:51:54 25521 8192000000 10071453984 10561400832 1106152672 11.896 99 5 8 1 1211495424 104454784 887968
08:53:44 51512 8192000000 10102851440 10525798400 1098259680 11.821 98 22 45 3 1190779808 87482304 5037824
08:55:39 33790 8192000000 10062740048 10490998784 1091965168 11.761 98 51 103 2 1140211984 37715728 10531088
08:57:15 89954 8192000000 10093247872 10526146560 1116951680 11.998 100 27 54 1 1194883488 71455056 6476752
09:00:06 14677 8192000000 10058826640 10505543680 1114204944 11.972 99 32 64 3 1164467856 43502720 6760192
09:01:22 71820 8192000000 10081230336 10561642496 1110494128 11.937 98 17 34 2 1193568944 79094736 3980080
09:03:34 22558 8192000000 10113886576 10545733632 1129435840 12.116 99 30 59 1 1207237984 71192176 6609968
09:04:57 37496 8192000000 10094024672 10547908608 1105975248 11.894 98 24 48 0 1191732864 80303344 5454272
09:06:36 44329 8192000000 10079309472 10513375232 1100364256 11.841 98 12 24 0 1166673696 63483072 2826368
09:08:20 37164 8192000000 10126535616 10549968896 1143993392 12.253 100 41 80 0 1212939008 60183376 8762240
09:10:03 74354 8192000000 10071824752 10521288704 1135442400 12.173 99 12 24 1 1173017152 35363648 2211104
09:12:22 40622 8192000000 10109001648 10557808640 1126598928 12.089 98 24 48 0 1195623456 64288240 4736288
09:14:42 48274 8192000000 10105164192 10536603648 1133868592 12.158 99 31 62 0 1178509776 38176672 6464512
09:16:33 30111 8192000000 10131840992 10561638400 1152401904 12.332 100 31 62 1 1215313312 56782736 6128672
09:18:10 124726 8192000000 10095825280 10531053568 1122094592 12.047 97 21 42 0 1178646912 51732480 4819840
09:21:15 44883 8192000000 10091524256 10578657280 1147503040 12.286 100 3 6 0 1188008992 39988336 517616
09:23:42 65947 8192000000 10080297136 10524233728 1084091904 11.686 95 18 36 1 1140420080 52562160 3766016
09:25:51 41902 8192000000 10080152048 10528337920 1112823408 11.959 98 28 56 0 1146458576 27454480 6180688
09:27:38 82650 8192000000 10073415712 10540150784 1102815280 11.864 97 20 38 3 1143622048 36881264 3925504
09:30:01 41157 8192000000 10100893744 10539851776 1113207648 11.963 98 33 64 0 1188999904 68410912 7381344
09:31:48 55462 8192000000 10092026464 10556235776 1071185776 11.563 95 31 62 1 1116618448 39490720 5941952
09:33:50 55318 8192000000 10069125744 10517209088 1105835504 11.893 98 23 45 1 1146465008 35745616 4883888
09:35:48 109948 8192000000 10063239504 10507780096 1120681536 12.033 99 34 68 3 1176249120 49045056 6522528
09:38:38 56384 8192000000 10058159376 10520694784 1115107536 11.981 99 16 32 0 1160826384 42282912 3435936
09:40:36 40352 8192000000 10039708592 10547896320 1110253232 11.935 99 6 12 0 1147789120 36421056 1114832
09:43:03 38861 8192000000 10083736496 10526412800 1078880720 11.637 95 24 48 0 1122405216 38274640 5249856
09:44:50 55505 8192000000 10075942848 10520883200 1090996576 11.752 97 23 46 1 1142102448 46352128 4753744
09:47:34 72556 8192000000 10097643424 10588352512 1103922384 11.875 98 24 48 1 1184520768 75726080 4872304
09:49:56 83707 8192000000 10070819888 10523951104 1077577392 11.624 96 22 44 0 1131977616 49977600 4422624
09:52:41 141173 8192000000 10122497376 10598658048 1125322016 12.077 100 14 28 0 1219335376 90931712 3081648
09:57:52 83667 8192000000 10103084688 10574196736 1119249760 12.020 99 13 26 0 1186688992 64760624 2678608
10:00:36 73912 8192000000 10110386736 10539495424 1114484336 11.975 98 13 26 0 1177169712 60115712 2569664
10:03:32 90436 8192000000 10090655056 10516328448 1096719488 11.807 96 10 20 0 1187804768 89109360 1975920
10:06:08 39889 8192000000 10117213552 10530414592 1114493600 11.975 98 50 98 0 1173846512 50074864 9278048
10:07:49 8226 8192000000 10145000992 10559574016 1143510608 12.249 100 57 114 0 1228235152 73309472 11415072
10:09:24 85434 8192000000 10128348976 10600300544 1150443872 12.314 100 11 22 0 1204891008 52518896 1928240
10:14:19 96523 8192000000 10098953024 10557980672 1136115312 12.179 99 4 8 0 1186503440 49545072 843056
10:16:56 70458 8192000000 10075906496 10562744320 1120119056 12.028 98 7 14 0 1178431456 56999728 1312672
10:19:16 83986 8192000000 10082925600 10520465408 1098204304 11.821 96 16 32 1 1139211376 37696544 3310528
10:21:40 118503 8192000000 10122158032 10561581056 1098563472 11.824 96 24 49 3 1200194592 96448720 5182400
10:24:38 61568 8192000000 10057726288 10500775936 1068051632 11.533 95 12 24 0 1105999568 34941328 3006608
10:26:45 21871 8192000000 9014549184 9242005504 153939520 1.844 15 1 2 0 163677808 9702880 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-01-2021 10:27:57 to 27-01-2021 10:28:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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 27-01-2021 10:28:12 to 27-01-2021 10:28:14.

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          235561
MON$OLDEST_ACTIVE               235562
MON$OLDEST_SNAPSHOT             235562
MON$NEXT_TRANSACTION            235562
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                       2087328
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2342 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.2342 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'

27.01.21 10:28:15. 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-27 10:28:18 No severe PSQL-related problems occured
27.01.21 10:28:19. End of report.