Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2021 10:29
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 16767316 kB
MemAvailable 31303708 kB
Buffers 0 kB
Cached 14849284 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-11 10:26:54
DTS_END                         2021-01-11 10:26:54.5030
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/36784
TRN_ID                          227144
ATT_ID                          678
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: 8697.98 26024 1043757
customer order: creation 152.71 426 18325
customer order: refuse 13.54 337 1625
order to supplier: creation 42.11 1385 5053
order to supplier: removal 3.25 112789 390
invoice (draft): creation 41.59 3696 4991
invoice (draft): removal 50.69 862 6083
invoice accept: apply 38.58 19507 4629
invoice accept: cancel 46.38 856 5565
customer reserve: creation 3569.59 191 428351
customer reserve: removal 4468.27 65 536193
realization accept: apply 75.38 164 9045
realization accept: cancel 78.24 121 9389
payment from customer: creation 53.67 128 6440
payment from customer: removal 5.47 94 656
payment to supplier: creation 44.89 112 5387
payment to supplier: removal 3.16 75 379
service: total inventory turnovers 3.92 3323 470
service: total monetary turnovers 5.72 487 686
service: aggregate perf. data 0.79 16622 95
service: refresh index statistics 0.04 359234 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2021 10:29:29 to 11-01-2021 10:29:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3053
TEST_TIME 2 4234
TEST_TIME 3 3623
TEST_TIME 4 3997
TEST_TIME 5 4097
TEST_TIME 6 4296
TEST_TIME 7 4559
TEST_TIME 8 4918
TEST_TIME 9 5229
TEST_TIME 10 5440
TEST_TIME 11 5522
TEST_TIME 12 6106
TEST_TIME 13 6189
TEST_TIME 14 6430
TEST_TIME 15 6387
TEST_TIME 16 6538
TEST_TIME 17 6605
TEST_TIME 18 6726
TEST_TIME 19 7274
TEST_TIME 20 7791
TEST_TIME 21 7804
TEST_TIME 22 8345
TEST_TIME 23 8280
TEST_TIME 24 8676
TEST_TIME 25 8959
TEST_TIME 26 9339
TEST_TIME 27 9513
TEST_TIME 28 9724
TEST_TIME 29 9903
TEST_TIME 30 10017
TEST_TIME 31 9894
TEST_TIME 32 9765
TEST_TIME 33 9572
TEST_TIME 34 9476
TEST_TIME 35 9273
TEST_TIME 36 9098
TEST_TIME 37 9073
TEST_TIME 38 9127
TEST_TIME 39 9133
TEST_TIME 40 9038
TEST_TIME 41 8887
TEST_TIME 42 8764
TEST_TIME 43 8893
TEST_TIME 44 8842
TEST_TIME 45 8777
TEST_TIME 46 8745
TEST_TIME 47 8699
TEST_TIME 48 8646
TEST_TIME 49 8637
TEST_TIME 50 8759
TEST_TIME 51 8721
TEST_TIME 52 8778
TEST_TIME 53 8881
TEST_TIME 54 8832
TEST_TIME 55 8962
TEST_TIME 56 9036
TEST_TIME 57 9014
TEST_TIME 58 8991
TEST_TIME 59 9044
TEST_TIME 60 9009
TEST_TIME 61 8905
TEST_TIME 62 8838
TEST_TIME 63 8838
TEST_TIME 64 8777
TEST_TIME 65 8729
TEST_TIME 66 8666
TEST_TIME 67 8650
TEST_TIME 68 8578
TEST_TIME 69 8613
TEST_TIME 70 8651
TEST_TIME 71 8651
TEST_TIME 72 8701
TEST_TIME 73 8750
TEST_TIME 74 8816
TEST_TIME 75 8813
TEST_TIME 76 8751
TEST_TIME 77 8791
TEST_TIME 78 8847
TEST_TIME 79 9064
TEST_TIME 80 9015
TEST_TIME 81 9000
TEST_TIME 82 8992
TEST_TIME 83 8897
TEST_TIME 84 8849
TEST_TIME 85 8794
TEST_TIME 86 8763
TEST_TIME 87 8709
TEST_TIME 88 8708
TEST_TIME 89 8698
TEST_TIME 90 8711
TEST_TIME 91 8676
TEST_TIME 92 8665
TEST_TIME 93 8724
TEST_TIME 94 8747
TEST_TIME 95 8822
TEST_TIME 96 8882
TEST_TIME 97 8855
TEST_TIME 98 8901
TEST_TIME 99 8896
TEST_TIME 100 8816
TEST_TIME 101 8769
TEST_TIME 102 8775
TEST_TIME 103 8732
TEST_TIME 104 8742
TEST_TIME 105 8726
TEST_TIME 106 8692
TEST_TIME 107 8716
TEST_TIME 108 8684
TEST_TIME 109 8712
TEST_TIME 110 8724
TEST_TIME 111 8734
TEST_TIME 112 8784
TEST_TIME 113 8773
TEST_TIME 114 8756
TEST_TIME 115 8820
TEST_TIME 116 8842
TEST_TIME 117 8833
TEST_TIME 118 8816
TEST_TIME 119 8748
TEST_TIME 120 8698

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2021 10:29:33 to 11-01-2021 10:29:36.

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 1441631 1441631 0 0.00 0 17739 7 0 0
doc_list_biud 1441630 1441630 0 0.00 0 89136 31 0 0
sp_add_invoice_to_stock 4751 4629 122 2.56 35 271813 19508 0 122
sp_cancel_adding_invoice 5565 5565 0 0.00 5 89086 857 0 0
sp_cancel_client_order 1625 1625 0 0.00 4 15707 338 0 0
sp_cancel_customer_reserve 536193 536193 0 0.00 1 18863 66 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 6406 94 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 4167 75 0 0
sp_cancel_supplier_invoice 6088 6083 5 0.08 2 89145 863 0 5
sp_cancel_supplier_order 390 390 0 0.00 8 913221 112789 0 0
sp_cancel_write_off 9389 9389 0 0.00 1 10945 122 0 0
sp_client_order 18325 18325 0 0.00 4 34524 426 0 0
sp_customer_reserve 428351 428351 0 0.00 0 57573 192 0 0
sp_fill_shopping_cart 28360 28360 0 0.00 1 31410 50 0 0
sp_fill_shopping_cart_clo_res 428350 428350 0 0.00 0 21882 18 0 0
sp_get_clo_for_invoice 4628 4628 0 0.00 0 52584 426 0 0
sp_kill_cost_storno 15989 15989 0 0.00 0 6402 55 0 0
sp_kill_qstorno_ret_qs2qd 1088248 1088248 0 0.00 0 89134 35 0 0
sp_kill_qty_storno 572917 572917 0 0.00 0 89136 76 0 0
sp_lock_selected_doc 19037 19037 0 0.00 0 8494 6 0 0
sp_make_cost_storno 25500 25500 0 0.00 0 16800 49 0 0
sp_multiply_rows_for_pdistr 25499 25499 0 0.00 0 7932 33 0 0
sp_multiply_rows_for_qdistr 18325 18325 0 0.00 0 28777 231 0 0
sp_pay_from_customer 6440 6440 0 0.00 4 51063 128 0 0
sp_pay_to_supplier 5387 5387 0 0.00 5 10283 113 0 0
sp_payment_common 11827 11827 0 0.00 1 51056 21 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 0 15701 311 0 0
sp_qd_handle_on_invoice_upd_sts 10192 10192 0 0.00 0 25075 363 0 0
sp_qd_handle_on_reserve_upd_sts 9045 9045 0 0.00 0 10353 46 0 0
sp_reserve_write_off 9045 9045 0 0.00 7 16847 165 0 0
sp_supplier_invoice 4991 4991 0 0.00 6 89293 3697 0 0
sp_supplier_order 5053 5053 0 0.00 6 89043 1386 0 0
srv_aggregate_perf_data 95 95 0 0.00 2743 228426 16623 0 0
srv_fill_mon_cache_memory 57 57 0 0.00 1286 153025 56992 0 0
srv_find_qd_qs_mism 1000847 1000847 0 0.00 0 17739 6 0 0
srv_make_invnt_saldo 470 470 0 0.00 50 108296 3323 0 0
srv_make_money_saldo 686 686 0 0.00 1 13450 488 0 0
srv_recalc_idx_stat 5 5 0 0.00 251105 524862 359235 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4629 4629 0 0.00 0 11020 175 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 8319 113 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 2431 10 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 3216 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1509 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 485 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 336 336 0 0.00 0 3505 26 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 15061 1407 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 3299 8 0 0
t$perf-norm:sp_client_order 18327 18327 0 0.00 0 6827 16 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 645 4 0 0
t$perf-norm:sp_pay_from_customer 6440 6440 0 0.00 0 3487 7 0 0
t$perf-norm:sp_pay_to_supplier 5387 5387 0 0.00 0 5237 9 0 0
t$perf-norm:sp_reserve_write_off 9045 9045 0 0.00 0 5801 9 0 0
t$perf-norm:sp_supplier_invoice 4991 4991 0 0.00 0 7424 23 0 0
t$perf-norm:sp_supplier_order 5053 5053 0 0.00 0 7420 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 389 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 57 57 0 0.00 0 3151 79 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 2398 10 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 358 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 26 6 0 0
v_add_invoice_to_stock 9490 9246 244 2.57 0 4398 18 0 244
v_all_customers 33460 33460 0 0.00 0 98 1 0 0
v_all_suppliers 27836 27836 0 0.00 0 101 1 0 0
v_all_wares 36644 36644 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 275 7 0 0
v_cancel_client_order 3250 3250 0 0.00 0 3445 6 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 2062 11 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2926 8 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 194 3 0 0
v_cancel_supplier_invoice 682 672 10 1.46 0 739 12 0 10
v_cancel_supplier_order 780 780 0 0.00 0 1598 12 0 0
v_cancel_write_off 1818 1818 0 0.00 0 4104 21 0 0
v_max_id_clo_ord 5050 5050 0 0.00 0 311 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 2867 9 0 0
v_max_id_ord_sup 4987 4987 0 0.00 0 7984 7 0 0
v_max_non_paid_invoice 1506 1506 0 0.00 0 893 2 0 0
v_max_non_paid_realizn 3442 3442 0 0.00 0 1499 4 0 0
v_min_id_clo_ord 5050 5050 0 0.00 0 2705 9 0 0
v_min_id_clo_res 944 944 0 0.00 0 5697 12 0 0
v_min_id_ord_sup 4987 4987 0 0.00 0 7634 8 0 0
v_min_non_paid_invoice 5387 5387 0 0.00 0 8979 9 0 0
v_min_non_paid_realizn 6440 6440 0 0.00 0 8740 13 0 0
v_our_firm 9180 9180 0 0.00 0 15 1 0 0
v_reserve_write_off 18090 18090 0 0.00 0 4160 7 0 0
x_lock_depdocs_on_canc_invoice 11312 11312 0 0.00 0 24324 104 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 14326 353 0 0
x_make_qstorno_1000_1200 5053 5053 0 0.00 1 89036 1297 0 0
x_make_qstorno_1200_2000 4991 4991 0 0.00 3 89279 3504 0 0
x_make_qstorno_2100_3300 428351 428351 0 0.00 0 52459 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2021 10:29:37 to 11-01-2021 10:29:41.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:58 10 8192000000 8799288720 8810844160 5309888 0.064 2 1 2 0 5407504 61840 35776
07:57:59 25904 8192000000 9718542320 10014081024 790747440 8.802 100 87 174 1 820892784 8001408 22143936
07:59:36 9678 8192000000 9765999920 10072936448 842255680 9.322 100 92 185 1 877286144 13124368 21906096
08:00:56 24281 8192000000 9809239520 10128842752 871779312 9.618 100 79 158 2 910183984 20384416 18020256
08:02:27 15973 8192000000 9870935088 10202423296 915562256 10.052 100 86 171 2 980408336 46302464 18543616
08:03:51 9415 8192000000 9919500640 10266226688 956240032 10.452 100 83 165 0 1038292704 64586048 17466624
08:05:03 5400 8192000000 9980102112 10328571904 990972816 10.791 100 87 173 0 1086623024 78431712 17218496
08:06:11 2220 8192000000 9988907776 10345693184 1019114672 11.063 100 91 182 0 1103998112 66291120 18592320
08:07:15 2128 8192000000 10021579664 10384932864 1042738416 11.291 100 95 187 2 1137746688 75368016 19640256
08:08:19 1277 8192000000 10030825408 10397655040 1064849024 11.503 100 96 192 1 1142174192 57625600 19699568
08:09:21 14081 8192000000 9989252944 10384863232 1067710000 11.530 100 27 54 1 1100710112 26906304 6093808
08:10:36 5200 8192000000 9989896352 10384732160 1076571072 11.615 100 29 59 3 1103022208 20605312 5845824
08:11:44 10120 8192000000 10003280944 10396987392 1082693392 11.673 100 38 76 0 1120733456 29857760 8182304
08:12:56 60086 8192000000 9996010464 10400038912 1087576128 11.720 100 30 59 1 1121027936 26933248 6518560
08:14:56 2886 8192000000 10011304592 10415067136 1091949984 11.761 100 48 99 1 1122514544 20091680 10472880
08:16:01 5239 8192000000 10016697568 10407464960 1087608864 11.720 100 44 86 2 1125395184 28235888 9550432
08:17:11 5932 8192000000 9999807744 10391052288 1060556448 11.462 99 37 75 4 1093462288 24659664 8246176
08:18:18 70284 8192000000 9931260864 10326773760 986680976 10.749 95 31 62 0 1009854896 16407488 6766432
08:20:29 6153 8192000000 9936172624 10321977344 1019220400 11.064 99 34 68 0 1063086864 36602624 7263840
08:21:35 5576 8192000000 9946693408 10329907200 1013286208 11.007 98 53 106 2 1042829680 17662704 11880768
08:22:42 6301 8192000000 9971252112 10357592064 1033384032 11.201 99 39 78 0 1058071904 15868480 8819392
08:23:49 51448 8192000000 9961343552 10361253888 1045225920 11.315 99 20 40 0 1068114160 18613488 4274752
08:25:45 9659 8192000000 9989359728 10390372352 1065395264 11.508 100 40 79 3 1094696016 20600976 8699776
08:26:57 9332 8192000000 9995401280 10389184512 1078593920 11.634 100 32 63 1 1103882784 17985568 7303296
08:28:16 9875 8192000000 10041004816 10446163968 1094077248 11.781 100 44 87 0 1155610128 52129952 9402928
08:29:46 103018 8192000000 10056496912 10481684480 1111976528 11.951 100 48 96 0 1202044080 79625520 10442032
08:32:38 42003 8192000000 10116864688 10545500160 1119558736 12.023 100 40 80 3 1211621248 82747024 9315488
08:34:23 8372 8192000000 10146063616 10551037952 1132262064 12.143 100 60 119 1 1245404320 100456400 12685856
08:35:36 13288 8192000000 10186262064 10594234368 1140043008 12.216 100 58 117 3 1286609536 134800672 11765856
08:37:10 8604 8192000000 10178469456 10587316224 1148854256 12.299 100 47 95 0 1275103504 115911056 10338192
08:38:22 4103 8192000000 10201203312 10633269248 1169789520 12.495 100 69 136 1 1322590032 137310720 15489792
08:39:32 5876 8192000000 10218696016 10621366272 1166962048 12.468 100 68 136 2 1320315584 138978864 14374672
08:40:42 2327 8192000000 10233340816 10635993088 1158130832 12.386 100 85 170 2 1337299200 160778912 18389456
08:41:48 5970 8192000000 10263470864 10663510016 1153220928 12.340 100 85 169 2 1364243616 192434896 18587792
08:42:59 3487 8192000000 10280089552 10675175424 1148981856 12.300 98 80 159 0 1378629760 212229248 17418656
08:44:08 3270 8192000000 10334133552 10731413504 1154289328 12.350 100 82 161 3 1430184576 257440176 18455072
08:45:16 4637 8192000000 10392740272 10789240832 1142177456 12.236 99 80 155 1 1476156064 315431920 18546688
08:46:25 1286 8192000000 10352512848 10753695744 1139618368 12.212 100 85 171 2 1450068576 289763088 20687120
08:47:31 4530 8192000000 10331512608 10735812608 1139163664 12.208 100 66 132 0 1426893152 271586240 16143248
08:48:48 21939 8192000000 10301322864 10726531072 1143254192 12.246 100 29 56 3 1395727504 244994144 7479168
08:50:23 13458 8192000000 10292138016 10721112064 1123461136 12.060 99 36 72 1 1381901376 249329664 9110576
08:51:41 26347 8192000000 10225099392 10660524032 1120857984 12.035 100 19 36 1 1323746416 197793776 5094656
08:53:44 35520 8192000000 10202099376 10674077696 1112578448 11.957 99 17 34 0 1318666656 201750256 4337952
08:55:23 116835 8192000000 10078540816 10493403136 1119657712 12.024 100 35 69 2 1227172752 100273024 7242016
08:58:22 115956 8192000000 10059026336 10521022464 1055597824 11.414 95 23 47 1 1120298048 59207824 5492400
09:01:19 112804 8192000000 10080516176 10543951872 1081386144 11.661 98 12 24 0 1157312864 73172848 2753872
09:04:18 109444 8192000000 10050893984 10494709760 1097145952 11.811 98 23 44 2 1128631312 26276944 5208416
09:07:11 41182 8192000000 10078222608 10502385664 1118100448 12.009 100 22 44 1 1187333120 64686288 4546384
09:08:53 46186 8192000000 10148951904 10609102848 1134717920 12.166 100 27 51 4 1246538896 105681520 6139456
09:11:39 89892 8192000000 10064725120 10513432576 1124048496 12.065 99 7 14 0 1149298688 24222960 1027232
09:14:13 77207 8192000000 10135567360 10583040000 1127732144 12.100 98 27 54 0 1190180000 56412752 6035104
09:17:09 71801 8192000000 10132125296 10579091456 1118224576 12.010 97 20 40 2 1192086320 69083312 4778432
09:19:46 71864 8192000000 10148234352 10621124608 1143315872 12.247 100 17 34 1 1244338656 97072352 3950432
09:21:58 136178 8192000000 10084821504 10523373568 1127501504 12.098 98 9 18 0 1159415648 30231776 1682368
09:25:22 68768 8192000000 10065238992 10520932352 1087036512 11.714 95 23 46 2 1108118976 15520416 5562048
09:27:31 153025 8192000000 10035726288 10532585472 1081473216 11.662 96 17 34 0 1161736832 76525504 3738112
09:31:21 41895 8192000000 10049000912 10512039936 1119776128 12.025 100 11 22 0 1167180112 44700432 2703552
09:33:04 51963 8192000000 10075535120 10543054848 1128839088 12.110 100 11 22 0 1191776032 60515296 2421648
09:35:30 57895 8192000000 10091172128 10537127936 1096790864 11.807 96 17 34 0 1167940384 67599360 3550160
09:37:31 66588 8192000000 10096472144 10568990720 1090469232 11.747 96 28 56 1 1186894352 90684672 5740448
09:39:41 83381 8192000000 10080356080 10523492352 1079632336 11.644 95 15 30 0 1165700992 82395696 3672960
09:42:29 26193 8192000000 10167864960 10585747456 1105490384 11.890 97 47 94 1 1214340800 98901792 9948624
09:44:00 59007 8192000000 10158290816 10693767168 1105359648 11.888 98 20 40 0 1263765216 154024960 4380608
09:46:18 41503 8192000000 10049635232 10472046592 1056590608 11.424 94 38 77 1 1083396944 19036880 7769456
09:48:04 107908 8192000000 10061277072 10530533376 1103974592 11.875 100 27 54 1 1167275152 58139696 5160864
09:50:54 70277 8192000000 10090133120 10540367872 1105120304 11.886 99 12 24 1 1182907296 75288880 2498112
09:53:35 88932 8192000000 10091902992 10541154304 1081634368 11.663 97 9 18 1 1173246528 89542288 2069872
09:56:04 61829 8192000000 10071806272 10501787648 1094205552 11.783 98 20 40 1 1169400832 70819808 4375472
09:58:17 72625 8192000000 10131568304 10593992704 1096850976 11.808 97 26 51 2 1221210432 118058240 6301216
10:00:34 63873 8192000000 10111930768 10629197824 1103011264 11.866 98 16 32 0 1244781968 137737328 4033376
10:02:38 41356 8192000000 10109568288 10541826048 1127504688 12.098 100 25 49 1 1196433872 63867840 5061344
10:04:22 24216 8192000000 10081203296 10519146496 1121342464 12.040 99 27 54 0 1177728800 51298592 5087744
10:05:49 91178 8192000000 10079953632 10541142016 1128691296 12.109 100 27 54 0 1186958976 52787472 5480208
10:08:35 52035 8192000000 10057536144 10546753536 1112396880 11.955 99 6 12 0 1153124608 39485344 1242384
10:10:27 150787 8192000000 10097348976 10548817920 1099974288 11.837 97 13 27 0 1131596416 29265392 2356736
10:14:50 54165 8192000000 10082781552 10542792704 1126156592 12.085 99 26 52 0 1164346112 33065456 5124064
10:16:45 55940 8192000000 10128756672 10560311296 1133623728 12.156 99 31 62 1 1193481808 53704544 6153536
10:18:44 61699 8192000000 10078455440 10517168128 1099551248 11.833 96 15 30 0 1132147104 29894960 2700896
10:20:52 151333 8192000000 10091776576 10584113152 1066927008 11.523 93 29 58 3 1152962304 80544720 5490576
10:24:24 133550 8192000000 10084376640 10529312768 1141193376 12.227 100 25 50 0 1188016576 41715808 5107392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2021 10:29:58 to 11-01-2021 10:30:00.

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          227143
MON$OLDEST_ACTIVE               228071
MON$OLDEST_SNAPSHOT             228071
MON$NEXT_TRANSACTION            228071
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                       2087776
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             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 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.2324 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 ---
:95: 
:96: mscs-test-161.budzdorov.ru    Mon Jan 11 10:27:12 2021
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Mon Jan 11 10:27:22 2021
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
--- end of diff output ---

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

11.01.21 10:30:01. 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-11 10:30:04 No severe PSQL-related problems occured
11.01.21 10:30:05. End of report.