Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.01.2021 13:28
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 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 16597848 kB
MemAvailable 31296048 kB
Buffers 0 kB
Cached 15105008 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 13:26:56
DTS_END                         2021-01-27 13:26:56.6180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57242
TRN_ID                          246640
ATT_ID                          667
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: 8966.43 21263 1075972
customer order: creation 182.42 411 21890
customer order: refuse 15.03 304 1803
order to supplier: creation 48.85 1394 5862
order to supplier: removal 3.26 116841 391
invoice (draft): creation 47.32 3877 5678
invoice (draft): removal 49.08 856 5889
invoice accept: apply 44.12 18412 5294
invoice accept: cancel 43.78 899 5253
customer reserve: creation 3972.43 185 476691
customer reserve: removal 4259.54 73 511145
realization accept: apply 87.84 163 10541
realization accept: cancel 75.72 127 9087
payment from customer: creation 63.66 108 7639
payment from customer: removal 5.98 88 718
payment to supplier: creation 52.27 119 6272
payment to supplier: removal 3.38 123 405
service: total inventory turnovers 4.13 3054 496
service: total monetary turnovers 6.70 518 804
service: aggregate perf. data 0.92 16870 110
service: refresh index statistics 0.03 260845 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-01-2021 13:28:30 to 27-01-2021 13:28:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2958
TEST_TIME 2 3601
TEST_TIME 3 3786
TEST_TIME 4 4133
TEST_TIME 5 4715
TEST_TIME 6 5584
TEST_TIME 7 5585
TEST_TIME 8 5647
TEST_TIME 9 5896
TEST_TIME 10 5731
TEST_TIME 11 6299
TEST_TIME 12 6455
TEST_TIME 13 6926
TEST_TIME 14 7090
TEST_TIME 15 7321
TEST_TIME 16 7201
TEST_TIME 17 7104
TEST_TIME 18 7345
TEST_TIME 19 7921
TEST_TIME 20 7912
TEST_TIME 21 8225
TEST_TIME 22 8509
TEST_TIME 23 8380
TEST_TIME 24 8418
TEST_TIME 25 8561
TEST_TIME 26 8748
TEST_TIME 27 9026
TEST_TIME 28 9432
TEST_TIME 29 9647
TEST_TIME 30 11039
TEST_TIME 31 10931
TEST_TIME 32 10901
TEST_TIME 33 10791
TEST_TIME 34 10740
TEST_TIME 35 10881
TEST_TIME 36 10611
TEST_TIME 37 10337
TEST_TIME 38 10151
TEST_TIME 39 10018
TEST_TIME 40 9972
TEST_TIME 41 9972
TEST_TIME 42 9990
TEST_TIME 43 9953
TEST_TIME 44 9860
TEST_TIME 45 9779
TEST_TIME 46 9644
TEST_TIME 47 9576
TEST_TIME 48 9647
TEST_TIME 49 9578
TEST_TIME 50 9562
TEST_TIME 51 9541
TEST_TIME 52 9601
TEST_TIME 53 9657
TEST_TIME 54 9710
TEST_TIME 55 9717
TEST_TIME 56 9669
TEST_TIME 57 9694
TEST_TIME 58 9675
TEST_TIME 59 9688
TEST_TIME 60 9598
TEST_TIME 61 9597
TEST_TIME 62 9515
TEST_TIME 63 9422
TEST_TIME 64 9375
TEST_TIME 65 9347
TEST_TIME 66 9372
TEST_TIME 67 9340
TEST_TIME 68 9294
TEST_TIME 69 9320
TEST_TIME 70 9421
TEST_TIME 71 9400
TEST_TIME 72 9476
TEST_TIME 73 9463
TEST_TIME 74 9441
TEST_TIME 75 9469
TEST_TIME 76 9525
TEST_TIME 77 9641
TEST_TIME 78 9601
TEST_TIME 79 9494
TEST_TIME 80 9443
TEST_TIME 81 9394
TEST_TIME 82 9307
TEST_TIME 83 9240
TEST_TIME 84 9192
TEST_TIME 85 9284
TEST_TIME 86 9257
TEST_TIME 87 9245
TEST_TIME 88 9235
TEST_TIME 89 9262
TEST_TIME 90 9359
TEST_TIME 91 9335
TEST_TIME 92 9350
TEST_TIME 93 9384
TEST_TIME 94 9331
TEST_TIME 95 9404
TEST_TIME 96 9450
TEST_TIME 97 9369
TEST_TIME 98 9301
TEST_TIME 99 9267
TEST_TIME 100 9210
TEST_TIME 101 9170
TEST_TIME 102 9193
TEST_TIME 103 9175
TEST_TIME 104 9154
TEST_TIME 105 9131
TEST_TIME 106 9126
TEST_TIME 107 9107
TEST_TIME 108 9098
TEST_TIME 109 9168
TEST_TIME 110 9137
TEST_TIME 111 9171
TEST_TIME 112 9187
TEST_TIME 113 9185
TEST_TIME 114 9167
TEST_TIME 115 9105
TEST_TIME 116 9067
TEST_TIME 117 9025
TEST_TIME 118 9004
TEST_TIME 119 9016
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-01-2021 13:28:34 to 27-01-2021 13:28:37.

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 1518136 1518136 0 0.00 0 19745 7 0 0
doc_list_biud 1518131 1518131 0 0.00 0 39900 31 0 0
sp_add_invoice_to_stock 5468 5294 174 3.18 29 174782 18413 0 174
sp_cancel_adding_invoice 5253 5253 0 0.00 6 56666 900 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 16265 304 0 0
sp_cancel_customer_reserve 511145 511145 0 0.00 1 19896 74 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 5523 88 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 8437 124 0 0
sp_cancel_supplier_invoice 5903 5889 14 0.23 2 35798 856 0 14
sp_cancel_supplier_order 391 391 0 0.00 8 1110404 116842 0 0
sp_cancel_write_off 9087 9087 0 0.00 1 11726 128 0 0
sp_client_order 21890 21890 0 0.00 4 20028 412 0 0
sp_customer_reserve 476691 476691 0 0.00 0 53026 185 0 0
sp_fill_shopping_cart 33427 33427 0 0.00 1 14867 45 0 0
sp_fill_shopping_cart_clo_res 476686 476686 0 0.00 0 26473 21 0 0
sp_get_clo_for_invoice 5288 5288 0 0.00 0 45548 428 0 0
sp_kill_cost_storno 15463 15463 0 0.00 0 11676 62 0 0
sp_kill_qstorno_ret_qs2qd 1037657 1037657 0 0.00 0 35681 39 0 0
sp_kill_qty_storno 549394 549394 0 0.00 0 39899 83 0 0
sp_lock_selected_doc 21810 21810 0 0.00 0 10707 5 0 0
sp_make_cost_storno 29740 29740 0 0.00 0 19701 53 0 0
sp_multiply_rows_for_pdistr 29738 29738 0 0.00 0 7081 29 0 0
sp_multiply_rows_for_qdistr 21890 21890 0 0.00 0 16101 242 0 0
sp_pay_from_customer 7639 7639 0 0.00 4 14323 108 0 0
sp_pay_to_supplier 6272 6272 0 0.00 5 22945 119 0 0
sp_payment_common 13911 13911 0 0.00 1 8441 18 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 16259 276 0 0
sp_qd_handle_on_invoice_upd_sts 10541 10541 0 0.00 0 39898 369 0 0
sp_qd_handle_on_reserve_upd_sts 10538 10538 0 0.00 0 10950 46 0 0
sp_reserve_write_off 10541 10541 0 0.00 6 23706 164 0 0
sp_supplier_invoice 5678 5678 0 0.00 6 97786 3877 0 0
sp_supplier_order 5862 5862 0 0.00 6 37586 1395 0 0
srv_aggregate_perf_data 110 110 0 0.00 2836 224002 16871 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1283 130564 46017 0 0
srv_find_qd_qs_mism 1026250 1026250 0 0.00 0 11204 6 0 0
srv_make_invnt_saldo 496 496 0 0.00 61 93885 3055 0 0
srv_make_money_saldo 804 804 0 0.00 1 14556 518 0 0
srv_recalc_idx_stat 4 4 0 0.00 183629 382126 260846 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5294 5294 0 0.00 0 13842 167 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 3634 79 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 11191 19 0 0
t$perf-norm:sp_cancel_customer_reserve 776 776 0 0.00 0 403 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 370 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1337 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2460 18 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 20356 1486 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 830 4 0 0
t$perf-norm:sp_client_order 21890 21890 0 0.00 0 9865 17 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 28525 34 0 0
t$perf-norm:sp_pay_from_customer 7639 7639 0 0.00 0 13136 9 0 0
t$perf-norm:sp_pay_to_supplier 6272 6272 0 0.00 0 6691 10 0 0
t$perf-norm:sp_reserve_write_off 10541 10541 0 0.00 0 6027 10 0 0
t$perf-norm:sp_supplier_invoice 5678 5678 0 0.00 0 8021 23 0 0
t$perf-norm:sp_supplier_order 5862 5862 0 0.00 0 5562 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 111 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 85 3 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 6845 20 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 7480 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10924 10576 348 3.18 0 6760 18 0 348
v_all_customers 39786 39786 0 0.00 0 20 1 0 0
v_all_suppliers 32192 32192 0 0.00 0 14 1 0 0
v_all_wares 43780 43780 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 3863 13 0 0
v_cancel_client_order 3606 3606 0 0.00 0 3200 6 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 3797 11 0 0
v_cancel_customer_reserve 1552 1552 0 0.00 0 3834 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3905 12 0 0
v_cancel_supplier_invoice 866 838 28 3.23 0 521 13 0 28
v_cancel_supplier_order 782 782 0 0.00 0 1319 8 0 0
v_cancel_write_off 2066 2066 0 0.00 0 6453 21 0 0
v_max_id_clo_ord 5862 5862 0 0.00 0 1453 2 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2075 15 0 0
v_max_id_ord_sup 5675 5675 0 0.00 0 4320 5 0 0
v_max_non_paid_invoice 1713 1713 0 0.00 0 684 2 0 0
v_max_non_paid_realizn 4208 4208 0 0.00 0 5755 4 0 0
v_min_id_clo_ord 5862 5862 0 0.00 0 8435 11 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 5594 11 0 0
v_min_id_ord_sup 5675 5675 0 0.00 0 11829 18 0 0
v_min_non_paid_invoice 6272 6272 0 0.00 0 4811 9 0 0
v_min_non_paid_realizn 7639 7639 0 0.00 0 8436 12 0 0
v_our_firm 10856 10856 0 0.00 0 42 1 0 0
v_reserve_write_off 21076 21076 0 0.00 0 2911 5 0 0
x_lock_depdocs_on_canc_invoice 10723 10723 0 0.00 0 15870 102 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3487 310 0 0
x_make_qstorno_1000_1200 5862 5862 0 0.00 2 37565 1310 0 0
x_make_qstorno_1200_2000 5678 5678 0 0.00 2 97767 3707 0 0
x_make_qstorno_2100_3300 476691 476691 0 0.00 0 53025 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 27-01-2021 13:28:38 to 27-01-2021 13:28:43.

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
10:57:00 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
10:58:01 34977 8192000000 9718525328 10010439680 792655440 8.822 100 90 180 0 822233856 7698704 21879712
10:59:43 12273 8192000000 9748184624 10048176128 822026976 9.119 100 89 178 1 856554544 12781104 21746464
11:01:10 10450 8192000000 9785040496 10102673408 856852496 9.469 100 74 148 1 896017552 21777856 17387200
11:02:26 11077 8192000000 9868255936 10203447296 923535696 10.131 100 81 161 0 977263920 36501472 17226752
11:03:40 7221 8192000000 9948203296 10293473280 976838448 10.653 100 90 179 1 1057995392 62440736 18716208
11:04:50 4555 8192000000 9992136256 10345590784 1011429744 10.989 100 88 176 1 1102805152 74059312 17316096
11:05:57 9209 8192000000 9986357504 10353065984 1036177456 11.228 100 66 132 0 1103337424 54147504 13012464
11:07:07 1046 8192000000 10024937072 10392641536 1054718064 11.406 100 91 182 0 1133325472 60434288 18173120
11:08:10 1070 8192000000 10054856176 10424971264 1069597840 11.548 100 94 189 0 1158415856 68942272 19875744
11:09:13 7247 8192000000 10015553232 10395611136 1073400880 11.585 100 65 129 1 1126088848 38923360 13764608
11:10:21 6004 8192000000 9985488672 10379685888 1076850800 11.617 100 31 60 1 1113214800 28504352 7859648
11:11:28 9958 8192000000 10004008736 10416418816 1086747408 11.712 100 18 35 1 1114195792 23822512 3625872
11:12:43 82091 8192000000 10003791072 10412126208 1093906752 11.780 100 26 50 1 1120093088 20986304 5200032
11:15:06 5526 8192000000 10041696464 10438590464 1103414464 11.870 100 50 99 0 1138381456 23915520 11051472
11:16:12 6927 8192000000 10003533808 10403385344 1085884064 11.704 99 52 102 2 1117473616 20839040 10750512
11:17:20 6846 8192000000 9982915216 10372603904 1039891440 11.264 96 22 42 3 1065914336 21139136 4883760
11:18:27 31176 8192000000 9944515056 10343792640 977889616 10.664 92 19 38 1 1006132848 24412416 3830816
11:19:59 3001 8192000000 9953760080 10328764416 1021512544 11.087 99 64 127 1 1056426480 21084064 13829872
11:21:02 6014 8192000000 9947168992 10333569024 1012066608 10.995 98 27 53 2 1051952944 34394256 5492080
11:22:12 7065 8192000000 9948377120 10338390016 1025275200 11.123 99 42 84 3 1049772912 15136480 9361232
11:23:21 39089 8192000000 9972169568 10380312576 1048014016 11.342 100 29 58 1 1079231808 25560640 5657152
11:25:00 11803 8192000000 9976921280 10371149824 1061901488 11.475 100 42 82 1 1079859856 8861424 9096944
11:26:12 6271 8192000000 9980440080 10371563520 1068957184 11.542 100 29 56 2 1086446720 11104720 6384816
11:27:26 21359 8192000000 10028684016 10418270208 1092577776 11.767 100 44 88 0 1117297744 15493184 9226784
11:28:52 80027 8192000000 10054938640 10461913088 1112031184 11.952 100 39 78 1 1185771632 65155744 8584704
11:31:19 8798 8192000000 10112516864 10513850368 1132502960 12.145 100 67 130 1 1229183056 83125424 13554672
11:32:33 30671 8192000000 10109894096 10508926976 1140672528 12.222 100 44 86 1 1221476000 71448736 9354736
11:34:12 9160 8192000000 10160684592 10567696384 1156539936 12.371 100 63 126 1 1265740944 94586016 14614992
11:35:23 20783 8192000000 10153070832 10569310208 1165319504 12.453 100 51 101 3 1265974944 89320000 11335440
11:36:48 3854 8192000000 10184249840 10585141248 1153617056 12.343 100 68 136 0 1286397200 117385568 15394576
11:37:55 3946 8192000000 10200511456 10608218112 1153585440 12.343 99 75 150 0 1297782032 126350288 17846304
11:39:02 3415 8192000000 10213184000 10618167296 1142822992 12.242 99 63 127 2 1314758048 158132128 13802928
11:40:12 2626 8192000000 10218787840 10626994176 1148533232 12.296 99 67 130 0 1312062144 149047472 14481440
11:41:20 2770 8192000000 10256470928 10655191040 1139467728 12.211 99 74 147 0 1350952224 194940512 16543984
11:42:31 11096 8192000000 10261919280 10661826560 1132529696 12.145 100 67 132 1 1361992336 214685088 14777552
11:44:04 3358 8192000000 10349696496 10751098880 1154923232 12.356 100 68 133 0 1443306000 273194640 15188128
11:45:14 1281 8192000000 10371749584 10782572544 1148810992 12.298 100 79 158 0 1466163328 298810512 18541824
11:46:21 10044 8192000000 10363144432 10792587264 1145823024 12.270 100 46 92 0 1470980752 313677168 11480560
11:47:53 12531 8192000000 10384663872 10816032768 1128872976 12.111 99 38 76 1 1473726400 335242736 9610688
11:49:12 14318 8192000000 10382427808 10799329280 1137332272 12.190 100 35 70 1 1467620080 321191216 9096592
11:50:29 7652 8192000000 10450023168 10865410048 1139887888 12.214 100 48 95 1 1547140384 394561872 12690624
11:51:43 21927 8192000000 10476997344 10897588224 1150942240 12.318 100 41 82 0 1562611808 401033488 10636080
11:53:06 16730 8192000000 10450007616 11011895296 1154036608 12.347 100 27 54 0 1587036432 425591872 7407952
11:56:03 59725 8192000000 10102199424 10617532416 1109795104 11.930 98 14 29 1 1315575616 202730704 3049808
11:58:03 105659 8192000000 10069464592 10516164608 1120324272 12.030 99 15 30 0 1166082992 42578416 3180304
12:00:57 60897 8192000000 10085076336 10594209792 1132141408 12.142 99 7 14 0 1219056528 85506960 1408160
12:03:08 92950 8192000000 10039438912 10474328064 1100718048 11.844 97 23 46 1 1123874656 18095520 5061088
12:05:51 35344 8192000000 10094757872 10533306368 1135842576 12.176 100 47 92 0 1201219840 54626176 10751088
12:07:34 48213 8192000000 10073048304 10561101824 1138297728 12.200 100 16 32 2 1197162560 55190080 3674752
12:09:23 28410 8192000000 10049048624 10503024640 1112869056 11.960 98 12 22 2 1139095312 23936896 2289360
12:10:52 38753 8192000000 10100982816 10555838464 1137119280 12.188 100 14 28 0 1194131312 53896464 3115568
12:12:34 51637 8192000000 10084944192 10560802816 1119006464 12.018 98 13 27 2 1190848368 68983200 2858704
12:14:42 81239 8192000000 10073184416 10537172992 1137292560 12.190 100 9 18 0 1192943088 53399136 2251392
12:17:04 41076 8192000000 10101542928 10537492480 1151812848 12.327 100 24 48 1 1204133024 47135872 5184304
12:18:50 40197 8192000000 10131911664 10589741056 1133335280 12.153 98 39 78 0 1195494992 54379744 7779968
12:20:33 60540 8192000000 10093699312 10534858752 1109974192 11.932 97 25 49 0 1135310160 20232800 5103168
12:22:34 26927 8192000000 10089104064 10517823488 1100890704 11.846 98 48 96 0 1149691552 39229216 9571632
12:24:05 12163 8192000000 10068314624 10498748416 1088698624 11.730 98 28 56 0 1134706112 40540160 5467328
12:25:18 26723 8192000000 10079359184 10512007168 1101732368 11.854 99 28 56 1 1146186336 38705712 5748256
12:26:46 130564 8192000000 10030346112 10500415488 1047364864 11.335 95 31 62 3 1109131552 55421136 6345552
12:29:57 71010 8192000000 10043721072 10478174208 1072035312 11.572 97 10 20 1 1097184976 23459888 1689776
12:32:09 48153 8192000000 10057629440 10518073344 1110312368 11.935 99 20 40 1 1176743792 61874672 4556752
12:34:00 39754 8192000000 10092102576 10538164224 1091355488 11.756 98 30 60 0 1169164336 71561952 6246896
12:35:49 56342 8192000000 10085865200 10550759424 1096661504 11.806 100 16 32 0 1205182992 104722160 3799328
12:37:46 40933 8192000000 10077384592 10510602240 1096846544 11.808 100 30 60 0 1164047184 60960032 6240608
12:39:35 51109 8192000000 10073949552 10492153856 1105764720 11.892 100 34 68 1 1182658784 69708256 7185808
12:41:26 59789 8192000000 10145067440 10569904128 1113798688 11.968 99 29 58 1 1216579424 96517056 6263680
12:43:51 52954 8192000000 10074126080 10512625664 1092354896 11.765 98 19 38 0 1130262368 34298288 3609184
12:45:45 92715 8192000000 10036940384 10484236288 1105687456 11.892 99 23 44 1 1140361328 30006720 4667152
12:48:18 50173 8192000000 10103544464 10555748352 1131118368 12.132 100 13 26 3 1199168096 65504720 2545008
12:50:31 57757 8192000000 10076083824 10592178176 1124893152 12.073 100 11 22 1 1216283136 88937760 2452224
12:52:42 37135 8192000000 10067202816 10518003712 1103974592 11.875 98 24 48 0 1164854016 55392128 5487296
12:54:24 69038 8192000000 10166038656 10613215232 1135901216 12.177 100 25 50 2 1217509344 75980480 5627648
12:56:54 82631 8192000000 10081133792 10525245440 1116209568 11.991 98 25 49 2 1158087040 36517808 5359664
12:59:17 64028 8192000000 10107573984 10561736704 1147258000 12.284 100 15 30 0 1204401312 54386560 2756752
13:01:29 102855 8192000000 10087948608 10532110336 1137062336 12.188 99 21 42 1 1166515328 25236400 4216592
13:04:17 93959 8192000000 10077008016 10540462080 1115446768 11.984 97 10 20 0 1153363344 36006992 1909584
13:06:59 53853 8192000000 10104493344 10564100096 1142122720 12.235 100 9 18 0 1193321760 49377936 1821104
13:08:55 70984 8192000000 10058051776 10501382144 1089572400 11.739 95 11 22 1 1119890320 28340032 1977888
13:11:12 47973 8192000000 10076859952 10513653760 1076478704 11.614 94 34 67 1 1119071536 35152976 7439856
13:13:01 72698 8192000000 10127933072 10562387968 1111040480 11.942 98 26 52 1 1196872688 80131008 5701200
13:15:14 73072 8192000000 10079921296 10526818304 1091021536 11.752 97 16 31 1 1138983664 44901376 3060752
13:17:29 87953 8192000000 10053787936 10509615104 1045128384 11.314 93 28 56 1 1132726112 81816144 5781584
13:19:57 42539 8192000000 10060729280 10504908800 1113562640 11.966 100 41 80 1 1177319424 55804720 7952064
13:21:50 74918 8192000000 10067064272 10509176832 1105459072 11.889 98 19 38 0 1157750448 48238384 4052992
13:24:05 58844 8192000000 10136245760 10595631104 1123465504 12.060 99 14 28 0 1204959680 78214816 3279360
13:26:04 64359 8192000000 8973932848 9224814592 124325200 1.494 11 9 18 0 163078864 36569152 2184512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-01-2021 13:28:44 to 27-01-2021 13:28:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-01-2021 13:29:00 to 27-01-2021 13:29:02.

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          247012
MON$OLDEST_ACTIVE               247013
MON$OLDEST_SNAPSHOT             247013
MON$NEXT_TRANSACTION            247013
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                       2105856
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             821
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 13:29:03. 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 13:29:06 No severe PSQL-related problems occured
27.01.21 13:29:07. End of report.