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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 17055084 kB
MemAvailable 31378244 kB
Buffers 20 kB
Cached 14556328 kB
SwapCached 32 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.1
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-06-14 04:27:00
DTS_END                         2021-06-14 04:27:00.5420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41910
TRN_ID                          230284
ATT_ID                          589
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: 8464.49 24883 1015739
customer order: creation 168.98 437 20278
customer order: refuse 14.21 314 1705
order to supplier: creation 44.27 1377 5312
order to supplier: removal 3.18 105375 382
invoice (draft): creation 44.12 3571 5294
invoice (draft): removal 45.38 955 5446
invoice accept: apply 39.83 17863 4779
invoice accept: cancel 41.14 866 4937
customer reserve: creation 3707.43 173 444892
customer reserve: removal 4072.75 65 488730
realization accept: apply 82.78 181 9934
realization accept: cancel 73.13 113 8775
payment from customer: creation 58.63 120 7035
payment from customer: removal 5.61 94 673
payment to supplier: creation 48.65 132 5838
payment to supplier: removal 3.48 76 418
service: total inventory turnovers 3.98 2859 477
service: total monetary turnovers 6.06 458 727
service: aggregate perf. data 0.86 14382 103
service: refresh index statistics 0.03 348245 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2021 04:28:26 to 14-06-2021 04:28:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4990
TEST_TIME 2 5909
TEST_TIME 3 5388
TEST_TIME 4 5250
TEST_TIME 5 5693
TEST_TIME 6 5396
TEST_TIME 7 5312
TEST_TIME 8 5625
TEST_TIME 9 5484
TEST_TIME 10 6076
TEST_TIME 11 6136
TEST_TIME 12 6582
TEST_TIME 13 6984
TEST_TIME 14 7653
TEST_TIME 15 8295
TEST_TIME 16 8711
TEST_TIME 17 8881
TEST_TIME 18 9525
TEST_TIME 19 9672
TEST_TIME 20 9732
TEST_TIME 21 9530
TEST_TIME 22 10300
TEST_TIME 23 11340
TEST_TIME 24 11221
TEST_TIME 25 11009
TEST_TIME 26 10929
TEST_TIME 27 10675
TEST_TIME 28 10523
TEST_TIME 29 10481
TEST_TIME 30 10348
TEST_TIME 31 10250
TEST_TIME 32 10059
TEST_TIME 33 10070
TEST_TIME 34 9893
TEST_TIME 35 9778
TEST_TIME 36 9623
TEST_TIME 37 9551
TEST_TIME 38 9430
TEST_TIME 39 9402
TEST_TIME 40 9320
TEST_TIME 41 9132
TEST_TIME 42 9021
TEST_TIME 43 9015
TEST_TIME 44 8935
TEST_TIME 45 8915
TEST_TIME 46 8997
TEST_TIME 47 8963
TEST_TIME 48 9033
TEST_TIME 49 8931
TEST_TIME 50 9137
TEST_TIME 51 9043
TEST_TIME 52 9034
TEST_TIME 53 8997
TEST_TIME 54 9131
TEST_TIME 55 9149
TEST_TIME 56 9272
TEST_TIME 57 9137
TEST_TIME 58 9119
TEST_TIME 59 9017
TEST_TIME 60 9034
TEST_TIME 61 8940
TEST_TIME 62 8933
TEST_TIME 63 8860
TEST_TIME 64 8881
TEST_TIME 65 8928
TEST_TIME 66 8907
TEST_TIME 67 8886
TEST_TIME 68 9019
TEST_TIME 69 9014
TEST_TIME 70 9060
TEST_TIME 71 9024
TEST_TIME 72 9105
TEST_TIME 73 9122
TEST_TIME 74 9124
TEST_TIME 75 9110
TEST_TIME 76 9191
TEST_TIME 77 9135
TEST_TIME 78 9047
TEST_TIME 79 8971
TEST_TIME 80 8926
TEST_TIME 81 8893
TEST_TIME 82 8842
TEST_TIME 83 8837
TEST_TIME 84 8827
TEST_TIME 85 8800
TEST_TIME 86 8760
TEST_TIME 87 8756
TEST_TIME 88 8730
TEST_TIME 89 8833
TEST_TIME 90 8811
TEST_TIME 91 8823
TEST_TIME 92 8845
TEST_TIME 93 8820
TEST_TIME 94 8798
TEST_TIME 95 8719
TEST_TIME 96 8740
TEST_TIME 97 8713
TEST_TIME 98 8714
TEST_TIME 99 8706
TEST_TIME 100 8760
TEST_TIME 101 8727
TEST_TIME 102 8684
TEST_TIME 103 8757
TEST_TIME 104 8740
TEST_TIME 105 8743
TEST_TIME 106 8720
TEST_TIME 107 8758
TEST_TIME 108 8799
TEST_TIME 109 8783
TEST_TIME 110 8745
TEST_TIME 111 8688
TEST_TIME 112 8666
TEST_TIME 113 8616
TEST_TIME 114 8591
TEST_TIME 115 8565
TEST_TIME 116 8543
TEST_TIME 117 8520
TEST_TIME 118 8539
TEST_TIME 119 8540
TEST_TIME 120 8469

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-06-2021 04:28:30 to 14-06-2021 04:28:33.

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 1426051 1426051 0 0.00 0 17611 7 0 0
doc_list_biud 1426049 1426049 0 0.00 0 89690 29 0 0
sp_add_invoice_to_stock 4967 4779 188 3.78 27 135259 17864 0 188
sp_cancel_adding_invoice 4937 4937 0 0.00 5 93633 866 0 0
sp_cancel_client_order 1705 1705 0 0.00 4 8957 315 0 0
sp_cancel_customer_reserve 488730 488730 0 0.00 1 89692 66 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 8207 95 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 5297 76 0 0
sp_cancel_supplier_invoice 5461 5446 15 0.27 2 31686 956 0 15
sp_cancel_supplier_order 382 382 0 0.00 8 1128410 105375 0 0
sp_cancel_write_off 8775 8775 0 0.00 1 9603 114 0 0
sp_client_order 20278 20278 0 0.00 4 35360 438 0 0
sp_customer_reserve 444892 444892 0 0.00 0 100969 174 0 0
sp_fill_shopping_cart 30881 30881 0 0.00 1 17255 48 0 0
sp_fill_shopping_cart_clo_res 444886 444886 0 0.00 0 30836 19 0 0
sp_get_clo_for_invoice 4773 4773 0 0.00 0 29249 427 0 0
sp_kill_cost_storno 14803 14803 0 0.00 0 5785 53 0 0
sp_kill_qstorno_ret_qs2qd 992063 992063 0 0.00 0 89686 36 0 0
sp_kill_qty_storno 524681 524681 0 0.00 0 89690 77 0 0
sp_lock_selected_doc 20346 20346 0 0.00 0 9711 6 0 0
sp_make_cost_storno 27580 27580 0 0.00 0 17598 65 0 0
sp_multiply_rows_for_pdistr 27580 27580 0 0.00 0 8195 30 0 0
sp_multiply_rows_for_qdistr 20278 20278 0 0.00 0 35342 241 0 0
sp_pay_from_customer 7035 7035 0 0.00 4 13161 120 0 0
sp_pay_to_supplier 5838 5838 0 0.00 4 17615 133 0 0
sp_payment_common 12873 12873 0 0.00 1 10996 23 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 8917 289 0 0
sp_qd_handle_on_invoice_upd_sts 9710 9710 0 0.00 0 19031 360 0 0
sp_qd_handle_on_reserve_upd_sts 9933 9933 0 0.00 0 10613 58 0 0
sp_reserve_write_off 9934 9934 0 0.00 6 17151 181 0 0
sp_supplier_invoice 5294 5294 0 0.00 7 116140 3572 0 0
sp_supplier_order 5312 5312 0 0.00 5 84114 1378 0 0
srv_aggregate_perf_data 103 103 0 0.00 2118 140778 14382 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1292 171942 51532 0 0
srv_find_qd_qs_mism 968386 968386 0 0.00 0 10097 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 36 122301 2859 0 0
srv_make_money_saldo 727 727 0 0.00 0 16715 459 0 0
srv_recalc_idx_stat 4 4 0 0.00 175682 566376 348245 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4779 4779 0 0.00 0 14451 159 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 8855 91 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 7976 12 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 514 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 4681 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 665 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 954 16 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 16493 1277 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 293 2 0 0
t$perf-norm:sp_client_order 20278 20278 0 0.00 0 7588 15 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 4316 7 0 0
t$perf-norm:sp_pay_from_customer 7035 7035 0 0.00 0 5014 8 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 8182 9 0 0
t$perf-norm:sp_reserve_write_off 9934 9934 0 0.00 0 9143 10 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 8943 16 0 0
t$perf-norm:sp_supplier_order 5312 5312 0 0.00 0 8853 14 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 46 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 192 4 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 2964 11 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 3129 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9922 9546 376 3.78 0 5564 19 0 376
v_all_customers 36988 36988 0 0.00 0 15 1 0 0
v_all_suppliers 29542 29542 0 0.00 0 20 1 0 0
v_all_wares 40556 40556 0 0.00 0 125 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 2477 12 0 0
v_cancel_client_order 3410 3410 0 0.00 0 3582 5 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3584 16 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 3762 9 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4165 14 0 0
v_cancel_supplier_invoice 804 774 30 3.73 0 3228 18 0 30
v_cancel_supplier_order 764 764 0 0.00 0 2181 7 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2326 14 0 0
v_max_id_clo_ord 5312 5312 0 0.00 0 3819 3 0 0
v_max_id_clo_res 979 979 0 0.00 0 3276 14 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 4115 5 0 0
v_max_non_paid_invoice 1670 1670 0 0.00 0 477 2 0 0
v_max_non_paid_realizn 3755 3755 0 0.00 0 2410 4 0 0
v_min_id_clo_ord 5312 5312 0 0.00 0 5548 7 0 0
v_min_id_clo_res 979 979 0 0.00 0 4785 11 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 2792 5 0 0
v_min_non_paid_invoice 5838 5838 0 0.00 0 5923 16 0 0
v_min_non_paid_realizn 7035 7035 0 0.00 0 10992 14 0 0
v_our_firm 10128 10128 0 0.00 0 17 1 0 0
v_reserve_write_off 19866 19866 0 0.00 0 4348 6 0 0
x_lock_depdocs_on_canc_invoice 9996 9996 0 0.00 0 17377 113 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 7503 331 0 0
x_make_qstorno_1000_1200 5312 5312 0 0.00 2 84109 1300 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 3 116122 3379 0 0
x_make_qstorno_2100_3300 444892 444892 0 0.00 0 100969 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2021 04:28:34 to 14-06-2021 04:28:38.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:57:04 12 8192000000 8797116480 8807698432 3189120 0.038 1 1 2 0 3286736 61840 35776
01:58:04 13288 8192000000 9708389904 10001821696 786394176 8.758 100 89 177 0 816316576 7897744 22024656
01:59:28 25986 8192000000 9763995792 10070380544 835036096 9.250 100 89 178 1 867391296 11392896 20962304
02:00:57 19300 8192000000 9801220624 10117095424 872026320 9.620 100 85 170 0 909434064 18060496 19347248
02:02:24 16841 8192000000 9865649904 10199732224 924371280 10.139 100 81 162 1 973225216 31452480 17401456
02:03:47 9312 8192000000 9919989312 10259509248 956066688 10.451 100 86 172 1 1030410544 56247696 18096160
02:04:59 4060 8192000000 9960924704 10312474624 985051888 10.733 100 81 160 1 1074841856 73649952 16140016
02:06:06 4046 8192000000 9993859664 10354741248 1009128192 10.967 100 83 166 0 1099136720 73115936 16892592
02:07:11 3258 8192000000 10025077712 10390781952 1045825200 11.321 100 92 183 0 1140036112 75566784 18644128
02:08:17 1926 8192000000 10052946976 10426658816 1064333024 11.498 100 89 179 0 1156821008 74658928 17829056
02:09:20 2704 8192000000 10028346944 10407297024 1072879120 11.580 100 60 121 0 1134948912 49246768 12823024
02:10:23 6178 8192000000 9998246928 10399240192 1077072880 11.620 100 30 60 1 1117369008 34558496 5737632
02:11:33 57928 8192000000 9986645888 10393817088 1084003984 11.686 100 37 74 0 1106322480 14394688 7923808
02:13:35 6732 8192000000 10010331632 10419879936 1090338240 11.746 100 44 88 1 1123778880 24126096 9314544
02:14:42 6395 8192000000 10022450944 10420232192 1098159184 11.820 100 44 88 5 1129256336 22129200 8967952
02:15:52 9437 8192000000 10017851360 10419466240 1100402448 11.841 100 30 58 1 1135111120 27600976 7107696
02:17:03 6803 8192000000 10042367632 10429521920 1103371488 11.870 100 62 124 1 1137553728 20539120 13643120
02:18:10 9066 8192000000 10004456640 10410848256 1056477808 11.423 97 34 68 2 1083797536 20252720 7067008
02:19:24 5460 8192000000 9937219248 10310557696 992274208 10.804 95 38 76 1 1015849632 15175056 8400368
02:20:31 40502 8192000000 9945680912 10345598976 1017689712 11.050 98 30 59 1 1053464048 29382480 6391856
02:22:17 7446 8192000000 9905303824 10292084736 977217744 10.657 97 31 62 1 1007240336 22913408 7109184
02:23:29 5449 8192000000 9935047072 10326081536 1036824768 11.234 100 55 108 4 1061620912 12829616 11966528
02:24:37 9690 8192000000 9956303504 10358284288 1043428864 11.298 99 40 80 0 1079102944 26282672 9391408
02:25:48 52959 8192000000 9974344368 10385276928 1058225920 11.440 100 26 52 1 1079889456 16124432 5539104
02:27:47 9422 8192000000 10009256752 10400354304 1079032384 11.638 100 51 102 0 1112000224 22870976 10096864
02:29:02 19820 8192000000 10070539248 10474065920 1102905152 11.865 100 56 111 1 1176452128 61761808 11785168
02:30:25 28542 8192000000 10125497392 10535133184 1125258576 12.077 100 52 104 0 1232699120 96348528 11092016
02:31:59 12629 8192000000 10174778224 10580791296 1135031728 12.169 100 54 108 0 1275783712 129181968 11570016
02:33:13 12435 8192000000 10186857840 10602774528 1146007184 12.272 100 36 70 2 1298014144 144269328 7737632
02:34:32 7947 8192000000 10248253472 10657177600 1156667168 12.372 100 55 110 0 1349974400 181296016 12011216
02:35:47 7454 8192000000 10259216800 10685349888 1164304944 12.444 100 46 92 1 1368709680 193089392 11315344
02:37:00 3849 8192000000 10326447040 10741952512 1176005968 12.553 100 61 120 2 1431262256 240788336 14467952
02:38:14 3130 8192000000 10345741520 10763071488 1182299520 12.612 100 69 137 0 1449797728 251510512 15987696
02:39:24 5465 8192000000 10390948688 10808938496 1182387008 12.612 100 79 158 3 1500126784 300205888 17533888
02:40:34 5580 8192000000 10385842160 10796797952 1162963376 12.431 99 74 148 0 1486597648 306413712 17220560
02:41:45 1291 8192000000 10405869376 10813841408 1174422016 12.538 100 88 176 0 1505279104 310202704 20654384
02:42:51 2058 8192000000 10353164512 10771435520 1141313824 12.228 98 57 114 0 1438215808 283257840 13644144
02:43:57 1837 8192000000 10348637136 10788904960 1160218352 12.405 100 62 124 1 1449156272 273723776 15214144
02:45:01 2680 8192000000 10363151456 10787618816 1159408448 12.398 100 62 125 1 1466362816 291796080 15158288
02:46:06 20882 8192000000 10299151104 10771894272 1150578240 12.315 100 26 52 0 1428467600 271126304 6763056
02:47:28 33690 8192000000 10308563616 10764558336 1101090928 11.848 97 25 50 1 1386007344 278606272 6310144
02:49:04 30149 8192000000 10270144032 10704695296 1078643440 11.635 98 24 48 0 1336446112 251464224 6338448
02:51:00 37254 8192000000 10079027968 10491043840 1055306656 11.412 95 20 39 0 1141873296 82074608 4492032
02:52:40 135182 8192000000 10066305120 10499948544 1023273264 11.104 92 30 61 1 1093297744 63619904 6404576
02:55:57 52679 8192000000 10050863520 10546200576 1094151696 11.782 99 8 16 1 1149658544 53493824 2013024
02:57:51 74969 8192000000 10050250256 10501599232 1074017280 11.590 96 18 36 1 1116964816 39722288 3225248
03:00:12 64257 8192000000 10035302720 10476998656 1055095616 11.410 95 18 36 0 1101707072 42715952 3895504
03:02:28 87134 8192000000 10038119408 10472951808 1113012816 11.961 100 34 69 2 1141575888 20523024 8040048
03:04:55 35758 8192000000 10061421440 10495426560 1123792960 12.063 100 24 47 0 1186213808 57792544 4628304
03:06:31 48169 8192000000 10019177488 10474590208 1106466384 11.899 99 17 34 0 1142895696 32587968 3841344
03:08:30 86840 8192000000 10050379472 10510512128 1113848400 11.969 99 25 50 2 1157074000 37678672 5546928
03:11:05 51366 8192000000 10047588416 10488209408 1103026288 11.866 98 12 24 0 1131466640 26082368 2357984
03:13:09 102838 8192000000 10084253984 10530496512 1137881520 12.196 100 12 24 0 1221139008 80644832 2612656
03:16:00 69046 8192000000 10139585600 10610139136 1148221312 12.293 100 16 32 1 1243083568 91744656 3117600
03:18:14 33550 8192000000 10090463488 10511847424 1138747008 12.204 99 32 64 1 1170143712 24371312 7025392
03:19:48 68156 8192000000 10159242512 10613714944 1140572384 12.221 99 23 46 1 1236809584 91117664 5119536
03:22:17 92196 8192000000 10124830304 10617483264 1142600528 12.240 99 19 38 0 1226019536 79741744 3677264
03:24:49 92806 8192000000 10107120064 10591109120 1148056176 12.291 100 27 54 1 1239212672 84714080 6442416
03:27:23 18408 8192000000 10069627776 10527100928 1150599760 12.315 100 14 25 0 1178187232 25159312 2428160
03:28:41 61906 8192000000 10086731424 10563547136 1134360736 12.162 98 5 10 1 1184438336 49182432 895168
03:30:43 56580 8192000000 10075773616 10521415680 1068120000 11.534 93 17 34 1 1133071760 61428704 3523056
03:32:40 58342 8192000000 10075929792 10537353216 1140497152 12.220 100 12 25 0 1186620944 43389248 2734544
03:34:41 77452 8192000000 10097122048 10622660608 1107233120 11.906 97 22 44 0 1204993552 93092016 4668416
03:37:01 71240 8192000000 10047548560 10484342784 1077852480 11.627 95 14 28 0 1147171248 66074208 3244560
03:39:20 74566 8192000000 10060559184 10537263104 1118464960 12.012 100 19 38 0 1179032928 56006672 4561296
03:41:39 48975 8192000000 10066372816 10489573376 1068044416 11.533 96 14 28 0 1095412304 24716992 2650896
03:43:29 171942 8192000000 10060067712 10568077312 1081921296 11.666 97 17 34 0 1150991744 65561168 3509280
03:47:28 48290 8192000000 10039544544 10508374016 1085000192 11.695 98 14 27 0 1147773168 59792416 2980560
03:49:19 87880 8192000000 10042472992 10510532608 1051559776 11.376 95 14 25 1 1119652240 65095072 2997392
03:51:57 35930 8192000000 10061205328 10500890624 1101302432 11.850 99 28 56 2 1132913168 25925088 5685648
03:53:34 46577 8192000000 10089991712 10518335488 1099325632 11.831 98 28 56 0 1151637520 45889712 6422176
03:55:25 48385 8192000000 10130808848 10594660352 1118999648 12.018 100 36 72 1 1206561680 79748160 7813872
03:57:23 84158 8192000000 10066025392 10503094272 1081775488 11.664 96 18 36 0 1130835632 45507056 3553088
03:59:49 46790 8192000000 10065842544 10494935040 1104695552 11.882 98 24 48 0 1144309808 35082656 4531600
04:01:41 101705 8192000000 10103805664 10580529152 1123080016 12.056 99 23 46 1 1208086864 80060608 4946240
04:04:32 68952 8192000000 10100078240 10586689536 1139672048 12.212 100 13 26 1 1204475648 62198544 2605056
04:06:49 79153 8192000000 10071456976 10536386560 1119565648 12.023 98 7 14 0 1177523920 56538912 1419360
04:09:08 24695 8192000000 10132101344 10561515520 1156432064 12.370 100 54 106 2 1207699024 39873056 11393904
04:10:38 53048 8192000000 10081029216 10510508032 1131766544 12.138 99 24 48 0 1160499248 24039568 4693136
04:12:31 63805 8192000000 10098283632 10535542784 1130425248 12.125 98 12 24 1 1151545904 18822144 2298512
04:14:39 104372 8192000000 10094319696 10563194880 1136168784 12.179 98 11 22 0 1175862448 37734592 1959072
04:17:26 135147 8192000000 10094506688 10553020416 1143423920 12.248 99 31 62 0 1193497056 43293408 6779728
04:20:42 33754 8192000000 10082193104 10560245760 1157304960 12.378 100 5 10 0 1192506816 34254576 947280
04:22:20 24430 8192000000 10074235792 10516783104 1138878528 12.205 99 20 40 0 1168549216 25091856 4578832
04:23:45 40823 8192000000 10114252560 10559352832 1128102064 12.103 97 28 56 0 1190633920 56021728 6510128
04:25:29 86521 8192000000 10136661984 10600243200 1107050784 11.904 96 22 44 0 1211844480 99739856 5053840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-06-2021 04:28:40 to 14-06-2021 04:28:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 188 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2021 04:28:56 to 14-06-2021 04:28:58.

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          223823
MON$OLDEST_ACTIVE               230397
MON$OLDEST_SNAPSHOT             230397
MON$NEXT_TRANSACTION            230397
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                       2056992
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2516 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2516 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2516 Firebird 4.0/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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Mon Jun 14 04:27:12 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

14.06.21 04:28:59. 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-06-14 04:29:01 No severe PSQL-related problems occured
14.06.21 04:29:03. End of report.