Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2020 07:38
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 16928584 kB
MemAvailable 31372808 kB
Buffers 0 kB
Cached 14694384 kB
SwapCached 4820 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-31 07:36:15
DTS_END                         2020-12-31 07:36:15.0850
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/60424
TRN_ID                          228565
ATT_ID                          590
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: 8719.16 24720 1046299
customer order: creation 164.84 436 19781
customer order: refuse 13.64 333 1637
order to supplier: creation 44.31 1371 5317
order to supplier: removal 3.06 117225 367
invoice (draft): creation 45.13 3501 5416
invoice (draft): removal 47.72 907 5726
invoice accept: apply 41.08 16329 4930
invoice accept: cancel 43.88 974 5266
customer reserve: creation 3752.58 160 450310
customer reserve: removal 4279.70 67 513564
realization accept: apply 80.30 184 9636
realization accept: cancel 79.15 120 9498
payment from customer: creation 57.61 131 6913
payment from customer: removal 5.59 105 671
payment to supplier: creation 46.87 132 5624
payment to supplier: removal 3.38 74 406
service: total inventory turnovers 3.81 3656 457
service: total monetary turnovers 5.58 603 669
service: aggregate perf. data 0.89 13269 107
service: refresh index statistics 0.03 334814 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2020 07:38:51 to 31-12-2020 07:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3613
TEST_TIME 2 5612
TEST_TIME 3 6076
TEST_TIME 4 5602
TEST_TIME 5 5403
TEST_TIME 6 5618
TEST_TIME 7 6133
TEST_TIME 8 6843
TEST_TIME 9 6869
TEST_TIME 10 7060
TEST_TIME 11 7186
TEST_TIME 12 7150
TEST_TIME 13 7637
TEST_TIME 14 8099
TEST_TIME 15 8403
TEST_TIME 16 8419
TEST_TIME 17 8547
TEST_TIME 18 9006
TEST_TIME 19 9021
TEST_TIME 20 8927
TEST_TIME 21 9121
TEST_TIME 22 9669
TEST_TIME 23 9453
TEST_TIME 24 9667
TEST_TIME 25 10047
TEST_TIME 26 10626
TEST_TIME 27 11388
TEST_TIME 28 11263
TEST_TIME 29 11004
TEST_TIME 30 10669
TEST_TIME 31 10703
TEST_TIME 32 10535
TEST_TIME 33 10338
TEST_TIME 34 10137
TEST_TIME 35 10031
TEST_TIME 36 9911
TEST_TIME 37 9746
TEST_TIME 38 9818
TEST_TIME 39 9626
TEST_TIME 40 9629
TEST_TIME 41 9594
TEST_TIME 42 9550
TEST_TIME 43 9419
TEST_TIME 44 9346
TEST_TIME 45 9296
TEST_TIME 46 9305
TEST_TIME 47 9315
TEST_TIME 48 9300
TEST_TIME 49 9262
TEST_TIME 50 9234
TEST_TIME 51 9347
TEST_TIME 52 9394
TEST_TIME 53 9346
TEST_TIME 54 9256
TEST_TIME 55 9274
TEST_TIME 56 9483
TEST_TIME 57 9498
TEST_TIME 58 9594
TEST_TIME 59 9447
TEST_TIME 60 9335
TEST_TIME 61 9286
TEST_TIME 62 9204
TEST_TIME 63 9093
TEST_TIME 64 9101
TEST_TIME 65 9041
TEST_TIME 66 9034
TEST_TIME 67 9000
TEST_TIME 68 8976
TEST_TIME 69 8953
TEST_TIME 70 8920
TEST_TIME 71 8958
TEST_TIME 72 8950
TEST_TIME 73 8903
TEST_TIME 74 9075
TEST_TIME 75 9325
TEST_TIME 76 9362
TEST_TIME 77 9300
TEST_TIME 78 9382
TEST_TIME 79 9320
TEST_TIME 80 9238
TEST_TIME 81 9152
TEST_TIME 82 9132
TEST_TIME 83 9070
TEST_TIME 84 9028
TEST_TIME 85 8955
TEST_TIME 86 8940
TEST_TIME 87 8994
TEST_TIME 88 8982
TEST_TIME 89 8968
TEST_TIME 90 8936
TEST_TIME 91 8978
TEST_TIME 92 8957
TEST_TIME 93 9004
TEST_TIME 94 9011
TEST_TIME 95 9061
TEST_TIME 96 9059
TEST_TIME 97 9014
TEST_TIME 98 9116
TEST_TIME 99 9138
TEST_TIME 100 9065
TEST_TIME 101 8986
TEST_TIME 102 8934
TEST_TIME 103 8910
TEST_TIME 104 8888
TEST_TIME 105 8871
TEST_TIME 106 8867
TEST_TIME 107 8850
TEST_TIME 108 8819
TEST_TIME 109 8797
TEST_TIME 110 8817
TEST_TIME 111 8837
TEST_TIME 112 8842
TEST_TIME 113 8806
TEST_TIME 114 8907
TEST_TIME 115 8869
TEST_TIME 116 8877
TEST_TIME 117 8879
TEST_TIME 118 8831
TEST_TIME 119 8781
TEST_TIME 120 8724

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2020 07:38:55 to 31-12-2020 07:38:58.

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 1462862 1462862 0 0.00 0 26759 6 0 0
doc_list_biud 1462862 1462862 0 0.00 0 31194 30 0 0
sp_add_invoice_to_stock 5062 4930 132 2.60 34 161050 16330 0 132
sp_cancel_adding_invoice 5266 5266 0 0.00 5 84402 974 0 0
sp_cancel_client_order 1637 1637 0 0.00 4 12576 334 0 0
sp_cancel_customer_reserve 513564 513564 0 0.00 1 26506 68 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 9124 106 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 7088 74 0 0
sp_cancel_supplier_invoice 5736 5726 10 0.17 2 24864 907 0 10
sp_cancel_supplier_order 367 367 0 0.00 7 1025806 117225 0 0
sp_cancel_write_off 9498 9498 0 0.00 1 6895 120 0 0
sp_client_order 19781 19781 0 0.00 4 23709 437 0 0
sp_customer_reserve 450310 450310 0 0.00 0 77453 161 0 0
sp_fill_shopping_cart 30510 30510 0 0.00 1 18937 50 0 0
sp_fill_shopping_cart_clo_res 450306 450306 0 0.00 0 16527 18 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 29591 455 0 0
sp_kill_cost_storno 15841 15841 0 0.00 0 6766 54 0 0
sp_kill_qstorno_ret_qs2qd 1042719 1042719 0 0.00 0 26502 37 0 0
sp_kill_qty_storno 550619 550619 0 0.00 0 31194 79 0 0
sp_lock_selected_doc 19977 19977 0 0.00 0 8625 6 0 0
sp_make_cost_storno 27098 27098 0 0.00 0 23216 62 0 0
sp_multiply_rows_for_pdistr 27098 27098 0 0.00 0 18869 31 0 0
sp_multiply_rows_for_qdistr 19781 19781 0 0.00 0 18791 260 0 0
sp_pay_from_customer 6913 6913 0 0.00 4 23802 132 0 0
sp_pay_to_supplier 5624 5624 0 0.00 4 18909 132 0 0
sp_payment_common 12537 12537 0 0.00 1 13817 23 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 12571 306 0 0
sp_qd_handle_on_invoice_upd_sts 10191 10191 0 0.00 0 31193 384 0 0
sp_qd_handle_on_reserve_upd_sts 9636 9636 0 0.00 0 18857 49 0 0
sp_reserve_write_off 9636 9636 0 0.00 7 22938 185 0 0
sp_supplier_invoice 5416 5416 0 0.00 6 114611 3501 0 0
sp_supplier_order 5317 5317 0 0.00 5 43098 1372 0 0
srv_aggregate_perf_data 107 107 0 0.00 2719 182165 13270 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2161 158047 50221 0 0
srv_find_qd_qs_mism 999937 999937 0 0.00 0 26758 5 0 0
srv_make_invnt_saldo 457 457 0 0.00 61 189274 3657 0 0
srv_make_money_saldo 669 669 0 0.00 1 18912 603 0 0
srv_recalc_idx_stat 4 4 0 0.00 147626 451273 334814 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4932 4932 0 0.00 0 16111 149 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 1 2708 90 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 1625 10 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 853 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 2838 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 584 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 641 15 0 0
t$perf-norm:sp_cancel_supplier_order 367 367 0 0.00 0 16055 1431 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1518 8 0 0
t$perf-norm:sp_client_order 19781 19781 0 0.00 0 5881 16 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 5297 12 0 0
t$perf-norm:sp_pay_from_customer 6913 6913 0 0.00 0 4086 7 0 0
t$perf-norm:sp_pay_to_supplier 5624 5624 0 0.00 0 5926 8 0 0
t$perf-norm:sp_reserve_write_off 9636 9636 0 0.00 0 4416 11 0 0
t$perf-norm:sp_supplier_invoice 5416 5416 0 0.00 0 3884 16 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 3492 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 315 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1039 34 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 468 6 0 0
t$perf-norm:srv_make_money_saldo 669 669 0 0.00 0 2781 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10114 9850 264 2.61 0 5681 18 0 264
v_all_customers 36198 36198 0 0.00 0 72 1 0 0
v_all_suppliers 29150 29150 0 0.00 0 89 1 0 0
v_all_wares 39562 39562 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 936 8 0 0
v_cancel_client_order 3274 3274 0 0.00 0 1233 4 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3445 12 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 0 1750 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1108 5 0 0
v_cancel_supplier_invoice 768 748 20 2.60 0 2718 20 0 20
v_cancel_supplier_order 734 734 0 0.00 0 294 3 0 0
v_cancel_write_off 1940 1940 0 0.00 0 4387 17 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 409 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2390 25 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 6955 6 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 998 2 0 0
v_max_non_paid_realizn 3591 3591 0 0.00 0 4488 4 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 3763 8 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 4343 18 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 6085 9 0 0
v_min_non_paid_invoice 5624 5624 0 0.00 0 11248 13 0 0
v_min_non_paid_realizn 6913 6913 0 0.00 0 13813 19 0 0
v_our_firm 10008 10008 0 0.00 0 26 1 0 0
v_reserve_write_off 19272 19272 0 0.00 0 3781 5 0 0
x_lock_depdocs_on_canc_invoice 10618 10618 0 0.00 0 17213 117 0 0
x_lock_depdocs_on_canc_sup_ord 367 367 0 0.00 0 16436 322 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 2 43080 1291 0 0
x_make_qstorno_1200_2000 5416 5416 0 0.00 3 106156 3307 0 0
x_make_qstorno_2100_3300 450310 450310 0 0.00 0 77452 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2020 07:38:59 to 31-12-2020 07:39:03.

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
05:06:18 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:07:19 35774 8192000000 9729411424 10027438080 806196688 8.959 100 89 178 0 836236368 8136688 21902992
05:09:19 20864 8192000000 9773547440 10083082240 845480320 9.355 100 89 178 0 881913472 15186496 21246656
05:10:47 26176 8192000000 9826157712 10152140800 876195488 9.662 100 73 146 0 929338656 37123840 16019328
05:12:22 7780 8192000000 9887739440 10226479104 929091344 10.186 100 79 158 0 1003010976 57234368 16685264
05:13:33 6536 8192000000 9929679152 10282573824 963735136 10.526 100 71 142 1 1044219024 65610064 14873824
05:14:42 5540 8192000000 9961496240 10317148160 1004317472 10.920 100 83 166 0 1080663904 58815600 17530832
05:15:54 2813 8192000000 10003493168 10368573440 1038563088 11.251 100 80 160 1 1114486304 59486896 16436320
05:16:59 1934 8192000000 10030155584 10395521024 1053464752 11.394 100 93 186 2 1153321712 80356624 19500336
05:18:04 4439 8192000000 10018029776 10393718784 1067122048 11.525 100 66 132 3 1132511808 52593040 12796720
05:19:10 4329 8192000000 9989088688 10389135360 1074505520 11.595 100 43 85 1 1127341760 44131712 8704528
05:20:14 7327 8192000000 10010560992 10417659904 1084961232 11.695 100 31 62 4 1137586448 45757840 6867376
05:21:22 69018 8192000000 10002894112 10408636416 1090186208 11.744 100 43 86 0 1118989136 18241728 10561200
05:23:32 7436 8192000000 10021838352 10419855360 1100381040 11.841 100 38 75 1 1144362688 35959168 8022480
05:24:42 7530 8192000000 10015817296 10421854208 1102550112 11.862 100 40 81 0 1127252240 16005072 8697056
05:25:50 42900 8192000000 10019050752 10441302016 1103844736 11.874 100 33 66 1 1151810528 40512384 7453408
05:27:36 6317 8192000000 9989244288 10389716992 1061073136 11.467 98 30 61 1 1096715408 29186656 6455616
05:28:46 9260 8192000000 9979372864 10382675968 1049023712 11.351 98 27 54 0 1085681040 31017536 5639792
05:29:55 3657 8192000000 9954537328 10333290496 1017349072 11.046 98 54 108 2 1050002688 19733552 12920064
05:31:00 27999 8192000000 9926228704 10314686464 937350000 10.267 90 30 62 0 960671888 16318928 7002960
05:32:30 6018 8192000000 9958074640 10340659200 1031992480 11.188 100 36 70 4 1070789312 30622304 8174528
05:33:37 4572 8192000000 9953696000 10343276544 1044274336 11.306 100 41 82 2 1066675904 12954224 9447344
05:34:45 12755 8192000000 9968761584 10369724416 1060396240 11.460 100 30 60 1 1083796736 17155168 6245328
05:35:58 43331 8192000000 9991510864 10391379968 1072371440 11.575 100 20 38 4 1105868864 29548992 3948432
05:37:48 11440 8192000000 10061726720 10457899008 1103237200 11.868 100 55 110 1 1165984048 51113008 11633840
05:39:05 17061 8192000000 10070935824 10484535296 1116870624 11.997 100 51 101 1 1186938144 58726304 11341216
05:40:25 17130 8192000000 10105727120 10510737408 1129250512 12.114 100 51 102 0 1206959040 67001056 10707472
05:42:04 9440 8192000000 10151820096 10558238720 1140853248 12.224 100 48 93 1 1262868432 111763920 10251264
05:43:19 5091 8192000000 10206092992 10620829696 1152385744 12.332 100 65 130 0 1321571984 154744096 14442144
05:44:31 10699 8192000000 10176617856 10610888704 1159049616 12.394 100 52 103 0 1305622816 134692064 11881136
05:45:50 3479 8192000000 10180369456 10590752768 1159871776 12.402 100 67 134 3 1289795696 114364320 15559600
05:46:59 3925 8192000000 10215215728 10613084160 1153599904 12.343 100 74 147 3 1320034176 151038448 15395824
05:48:07 5279 8192000000 10242489072 10654142464 1164274736 12.443 100 67 131 2 1350136112 171591600 14269776
05:49:18 3841 8192000000 10276894688 10684375040 1170722256 12.504 100 68 135 1 1373995600 187641968 15631376
05:50:28 6812 8192000000 10271909376 10674499584 1154386832 12.351 100 74 142 2 1364713568 194618720 15708016
05:51:39 13932 8192000000 10262396848 10677182464 1150930800 12.318 99 53 106 1 1365142384 202693040 11518544
05:52:59 4583 8192000000 10296171328 10721140736 1170269216 12.499 100 61 120 2 1413979632 229884304 13826112
05:54:10 2160 8192000000 10325355920 10727796736 1166494192 12.464 100 93 183 2 1415249968 227470208 21285568
05:55:16 11022 8192000000 10306416144 10728460288 1148313952 12.294 99 36 72 2 1401543392 244530528 8698912
05:56:32 18181 8192000000 10362361440 10801602560 1137549952 12.192 98 36 72 2 1453067808 306793216 8724640
05:57:54 31880 8192000000 10310493904 10775347200 1142015504 12.234 100 31 62 4 1439012144 288618384 8378256
05:59:30 19815 8192000000 10348477568 10773086208 1125537552 12.079 98 27 54 0 1424488464 292129616 6821296
06:01:00 21935 8192000000 10340103104 10796257280 1100787584 11.845 98 22 44 0 1429458272 322983600 5687088
06:02:33 47738 8192000000 10131817888 10576003072 1070775040 11.559 97 20 40 0 1321298768 244961216 5562512
06:04:22 35361 8192000000 10066868192 10473422848 1107923600 11.913 100 53 106 1 1155542976 35589232 12030144
06:05:59 93949 8192000000 10091132176 10513625088 1088486336 11.728 98 51 98 3 1183228912 83380256 11362320
06:08:35 72917 8192000000 10046356176 10479697920 1070800640 11.560 95 20 40 1 1096894496 22662656 3431200
06:11:04 158047 8192000000 10110201792 10542219264 1120835760 12.035 99 22 44 1 1205800160 79892752 5071648
06:14:47 89806 8192000000 10125231392 10587160576 1126521568 12.089 99 23 46 2 1218103856 86225056 5357232
06:17:26 34571 8192000000 10090829328 10533793792 1128998544 12.112 99 19 38 1 1183084864 49916944 4169376
06:19:01 84750 8192000000 10115321232 10571726848 1109823392 11.931 97 18 36 2 1208305744 94181376 4300976
06:21:50 68677 8192000000 10060091792 10496040960 1115372688 11.983 98 13 27 0 1156576352 38330128 2873536
06:23:59 44980 8192000000 10113609072 10558201856 1133514544 12.154 99 22 43 1 1181115568 43258016 4343008
06:25:57 45178 8192000000 10123361968 10562183168 1126577488 12.089 98 26 51 3 1207965616 76159072 5229056
06:27:43 82024 8192000000 10096956272 10578718720 1146635888 12.278 100 15 30 1 1230573984 80305536 3632560
06:30:06 74031 8192000000 10181434272 10625994752 1123835472 12.063 97 15 31 1 1237060096 109983968 3240656
06:32:20 64820 8192000000 10128580480 10567569408 1127685456 12.100 98 27 54 0 1210016544 76965280 5365808
06:34:31 132151 8192000000 10063156544 10499108864 1113663120 11.967 97 15 30 1 1126869808 10541968 2664720
06:37:49 27497 8192000000 10069216592 10514874368 1143159984 12.245 100 16 32 2 1163428048 17490976 2777088
06:39:16 36341 8192000000 10072840672 10520584192 1127015680 12.093 99 11 22 0 1159649312 30497200 2136432
06:41:04 53506 8192000000 10063524128 10559733760 1128652512 12.109 100 4 8 0 1167961856 38684688 624656
06:42:58 37355 8192000000 10089641280 10531000320 1124929280 12.074 98 22 43 3 1162681984 33017648 4735056
06:44:44 56383 8192000000 10146440336 10595565568 1112385568 11.955 97 29 58 0 1207694928 89229568 6079792
06:47:26 67059 8192000000 10163777328 10612330496 1100422544 11.842 97 28 56 2 1237715712 130996320 6296848
06:49:37 83149 8192000000 10226932960 10764091392 1128068992 12.103 99 34 68 2 1359731600 223825456 7837152
06:54:21 48325 8192000000 10054304736 10526928896 1094152320 11.782 99 16 32 2 1144924704 47554640 3217744
06:56:13 111546 8192000000 10039215360 10501681152 1117154672 12.000 100 19 38 1 1173611040 52426688 4029680
06:59:09 49808 8192000000 10054027248 10517159936 1109871760 11.931 99 15 30 1 1154989584 42331280 2786544
07:02:04 79264 8192000000 10103636160 10595254272 1118760864 12.015 99 15 30 0 1222678896 100553888 3364144
07:04:24 58141 8192000000 10070172528 10524823552 1094876992 11.789 97 19 38 1 1151698576 52784304 4037280
07:06:39 52112 8192000000 10067346272 10518290432 1097558592 11.814 97 17 34 1 1177770224 76765968 3445664
07:08:32 89164 8192000000 10093577136 10542424064 1095024576 11.790 96 22 45 0 1153749136 54286368 4438192
07:11:07 44947 8192000000 10056111456 10502131712 1102601872 11.862 97 12 24 0 1135302576 30467424 2233280
07:12:58 43243 8192000000 10119901696 10545967104 1129137264 12.113 99 22 44 1 1204993744 71492320 4364160
07:14:46 148803 8192000000 10049361616 10520932352 1117608016 12.004 98 15 30 2 1177290096 56108096 3573984
07:18:15 69919 8192000000 10077181104 10548842496 1126651552 12.090 99 19 38 0 1189645856 58698816 4295488
07:20:31 80870 8192000000 10076156352 10586124288 1130112128 12.122 100 4 8 0 1181869312 51075296 681888
07:22:52 46371 8192000000 10064634336 10518450176 1131472960 12.135 99 16 31 1 1156749968 22220224 3056784
07:24:41 36010 8192000000 10113125856 10547974144 1129216176 12.114 98 24 48 1 1187255488 52953168 5086144
07:26:17 59889 8192000000 10138955552 10577514496 1156615088 12.372 100 17 34 2 1219887632 59506064 3766480
07:28:19 64381 8192000000 10084720688 10519310336 1116714048 11.996 98 11 21 1 1150060416 30775872 2570496
07:30:29 106229 8192000000 10076976112 10531254272 1115094928 11.981 98 8 16 0 1192495584 75813920 1586736
07:33:19 76180 8192000000 10077538368 10529624064 1106467728 11.899 97 20 40 1 1134523344 23717504 4338112
07:35:36 46831 8192000000 9276980240 9571860480 420850816 4.886 38 7 14 0 434642672 12661952 1129904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2020 07:39:05 to 31-12-2020 07:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2020 07:39:20 to 31-12-2020 07:39:22.

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          228952
MON$OLDEST_ACTIVE               228953
MON$OLDEST_SNAPSHOT             228953
MON$NEXT_TRANSACTION            228953
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                       2077856
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             782
MON$NEXT_STATEMENT              53


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

31.12.20 07:39:23. 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-31 07:39:26 No severe PSQL-related problems occured
31.12.20 07:39:27. End of report.