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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16404776 kB
MemAvailable 31160008 kB
Buffers 0 kB
Cached 15305340 kB
SwapCached 3892 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-02-21 07:37:06
DTS_END                         2021-02-21 07:37:06.2160
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/42632
TRN_ID                          223239
ATT_ID                          626
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: 8885.87 24756 1066304
customer order: creation 157.54 423 18905
customer order: refuse 13.15 370 1578
order to supplier: creation 41.48 1404 4978
order to supplier: removal 3.35 140419 402
invoice (draft): creation 42.18 3620 5062
invoice (draft): removal 52.88 947 6346
invoice accept: apply 38.32 19828 4598
invoice accept: cancel 47.91 937 5749
customer reserve: creation 3524.21 195 422905
customer reserve: removal 4685.52 80 562263
realization accept: apply 75.02 207 9002
realization accept: cancel 87.74 137 10529
payment from customer: creation 53.69 100 6443
payment from customer: removal 5.13 117 616
payment to supplier: creation 44.73 120 5368
payment to supplier: removal 2.99 72 359
service: total inventory turnovers 3.48 4011 417
service: total monetary turnovers 5.78 659 694
service: aggregate perf. data 0.72 20232 86
service: refresh index statistics 0.03 301247 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-02-2021 07:38:58 to 21-02-2021 07:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5961
TEST_TIME 2 5116
TEST_TIME 3 3997
TEST_TIME 4 4361
TEST_TIME 5 4550
TEST_TIME 6 5133
TEST_TIME 7 5845
TEST_TIME 8 5784
TEST_TIME 9 5773
TEST_TIME 10 6003
TEST_TIME 11 6419
TEST_TIME 12 6530
TEST_TIME 13 6503
TEST_TIME 14 6638
TEST_TIME 15 6748
TEST_TIME 16 6732
TEST_TIME 17 6811
TEST_TIME 18 7053
TEST_TIME 19 6948
TEST_TIME 20 6946
TEST_TIME 21 7159
TEST_TIME 22 7206
TEST_TIME 23 7476
TEST_TIME 24 8103
TEST_TIME 25 8143
TEST_TIME 26 8223
TEST_TIME 27 8573
TEST_TIME 28 8767
TEST_TIME 29 10591
TEST_TIME 30 10535
TEST_TIME 31 10475
TEST_TIME 32 10530
TEST_TIME 33 10789
TEST_TIME 34 10516
TEST_TIME 35 10258
TEST_TIME 36 10242
TEST_TIME 37 10101
TEST_TIME 38 9978
TEST_TIME 39 9934
TEST_TIME 40 9826
TEST_TIME 41 9791
TEST_TIME 42 9866
TEST_TIME 43 9812
TEST_TIME 44 9705
TEST_TIME 45 9762
TEST_TIME 46 9660
TEST_TIME 47 9669
TEST_TIME 48 9593
TEST_TIME 49 9657
TEST_TIME 50 9678
TEST_TIME 51 9773
TEST_TIME 52 9729
TEST_TIME 53 9750
TEST_TIME 54 9682
TEST_TIME 55 9724
TEST_TIME 56 9798
TEST_TIME 57 9760
TEST_TIME 58 9856
TEST_TIME 59 9701
TEST_TIME 60 9546
TEST_TIME 61 9545
TEST_TIME 62 9497
TEST_TIME 63 9436
TEST_TIME 64 9387
TEST_TIME 65 9359
TEST_TIME 66 9465
TEST_TIME 67 9406
TEST_TIME 68 9385
TEST_TIME 69 9425
TEST_TIME 70 9437
TEST_TIME 71 9413
TEST_TIME 72 9658
TEST_TIME 73 9589
TEST_TIME 74 9578
TEST_TIME 75 9574
TEST_TIME 76 9595
TEST_TIME 77 9581
TEST_TIME 78 9478
TEST_TIME 79 9458
TEST_TIME 80 9392
TEST_TIME 81 9336
TEST_TIME 82 9307
TEST_TIME 83 9267
TEST_TIME 84 9282
TEST_TIME 85 9226
TEST_TIME 86 9226
TEST_TIME 87 9202
TEST_TIME 88 9197
TEST_TIME 89 9201
TEST_TIME 90 9348
TEST_TIME 91 9387
TEST_TIME 92 9435
TEST_TIME 93 9432
TEST_TIME 94 9447
TEST_TIME 95 9404
TEST_TIME 96 9319
TEST_TIME 97 9280
TEST_TIME 98 9239
TEST_TIME 99 9171
TEST_TIME 100 9134
TEST_TIME 101 9073
TEST_TIME 102 9028
TEST_TIME 103 9094
TEST_TIME 104 9076
TEST_TIME 105 9030
TEST_TIME 106 9053
TEST_TIME 107 9108
TEST_TIME 108 9103
TEST_TIME 109 9104
TEST_TIME 110 9177
TEST_TIME 111 9158
TEST_TIME 112 9178
TEST_TIME 113 9166
TEST_TIME 114 9182
TEST_TIME 115 9130
TEST_TIME 116 9056
TEST_TIME 117 9035
TEST_TIME 118 8994
TEST_TIME 119 8960
TEST_TIME 120 8890

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-02-2021 07:39:01 to 21-02-2021 07:39:05.

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 1460765 1460765 0 0.00 0 19623 7 0 0
doc_list_biud 1460765 1460765 0 0.00 0 43118 38 0 0
sp_add_invoice_to_stock 4749 4598 151 3.17 52 204419 19828 0 151
sp_cancel_adding_invoice 5749 5749 0 0.00 4 107698 937 0 0
sp_cancel_client_order 1578 1578 0 0.00 5 17244 370 0 0
sp_cancel_customer_reserve 562263 562263 0 0.00 1 21602 81 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 11652 118 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 3 1795 72 0 0
sp_cancel_supplier_invoice 6352 6346 6 0.09 3 20693 948 0 6
sp_cancel_supplier_order 402 402 0 0.00 17 1202176 140419 0 0
sp_cancel_write_off 10529 10529 0 0.00 1 17068 138 0 0
sp_client_order 18905 18905 0 0.00 4 19323 423 0 0
sp_customer_reserve 422905 422905 0 0.00 0 123690 195 0 0
sp_fill_shopping_cart 28937 28937 0 0.00 1 14195 44 0 0
sp_fill_shopping_cart_clo_res 422898 422898 0 0.00 0 19226 18 0 0
sp_get_clo_for_invoice 4590 4590 0 0.00 0 21794 412 0 0
sp_kill_cost_storno 17253 17253 0 0.00 0 6032 65 0 0
sp_kill_qstorno_ret_qs2qd 1141803 1141803 0 0.00 0 20674 43 0 0
sp_kill_qty_storno 600459 600459 0 0.00 0 43118 91 0 0
sp_lock_selected_doc 18871 18871 0 0.00 0 8846 5 0 0
sp_make_cost_storno 25403 25403 0 0.00 0 16151 61 0 0
sp_multiply_rows_for_pdistr 25403 25403 0 0.00 0 10468 30 0 0
sp_multiply_rows_for_qdistr 18905 18905 0 0.00 0 13816 241 0 0
sp_pay_from_customer 6443 6443 0 0.00 4 11521 100 0 0
sp_pay_to_supplier 5368 5368 0 0.00 5 18000 120 0 0
sp_payment_common 11811 11811 0 0.00 1 9415 19 0 0
sp_qd_handle_on_cancel_clo 1578 1578 0 0.00 0 17235 334 0 0
sp_qd_handle_on_invoice_upd_sts 10339 10339 0 0.00 0 43118 433 0 0
sp_qd_handle_on_reserve_upd_sts 9002 9002 0 0.00 0 19958 66 0 0
sp_reserve_write_off 9002 9002 0 0.00 7 20039 207 0 0
sp_supplier_invoice 5062 5062 0 0.00 8 84883 3621 0 0
sp_supplier_order 4978 4978 0 0.00 5 25749 1405 0 0
srv_aggregate_perf_data 86 86 0 0.00 2645 285666 20232 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 924 120425 49853 0 0
srv_find_qd_qs_mism 1024223 1024223 0 0.00 0 19622 6 0 0
srv_make_invnt_saldo 417 417 0 0.00 57 98485 4012 0 0
srv_make_money_saldo 694 694 0 0.00 1 13833 659 0 0
srv_recalc_idx_stat 4 4 0 0.00 140016 535037 301248 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4598 4598 0 0.00 0 17754 239 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 7017 106 0 0
t$perf-norm:sp_cancel_client_order 1578 1578 0 0.00 0 5288 18 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 569 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 1315 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 739 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 5316 20 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 31029 1804 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 4345 12 0 0
t$perf-norm:sp_client_order 18905 18905 0 0.00 0 4246 17 0 0
t$perf-norm:sp_customer_reserve 898 898 0 0.00 0 3481 8 0 0
t$perf-norm:sp_pay_from_customer 6443 6443 0 0.00 0 5335 11 0 0
t$perf-norm:sp_pay_to_supplier 5368 5368 0 0.00 0 13788 13 0 0
t$perf-norm:sp_reserve_write_off 9002 9002 0 0.00 0 9945 13 0 0
t$perf-norm:sp_supplier_invoice 5062 5062 0 0.00 0 4676 18 0 0
t$perf-norm:sp_supplier_order 4978 4978 0 0.00 0 6450 19 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 239 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 242 8 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 2067 11 0 0
t$perf-norm:srv_make_money_saldo 694 694 0 0.00 0 860 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 6 0 0
v_add_invoice_to_stock 9480 9178 302 3.18 0 9457 22 0 302
v_all_customers 34486 34486 0 0.00 0 61 1 0 0
v_all_suppliers 28136 28136 0 0.00 0 75 1 0 0
v_all_wares 37810 37810 0 0.00 0 77 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 3826 12 0 0
v_cancel_client_order 3156 3156 0 0.00 0 2991 7 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 9509 19 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 0 3276 7 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 1601 6 0 0
v_cancel_supplier_invoice 774 762 12 1.55 0 1674 14 0 12
v_cancel_supplier_order 804 804 0 0.00 0 4118 10 0 0
v_cancel_write_off 1878 1878 0 0.00 0 3403 15 0 0
v_max_id_clo_ord 4976 4976 0 0.00 0 271 1 0 0
v_max_id_clo_res 898 898 0 0.00 0 2312 16 0 0
v_max_id_ord_sup 5056 5056 0 0.00 0 5821 3 0 0
v_max_non_paid_invoice 1332 1332 0 0.00 0 498 2 0 0
v_max_non_paid_realizn 3294 3294 0 0.00 0 2008 3 0 0
v_min_id_clo_ord 4976 4976 0 0.00 0 3385 6 0 0
v_min_id_clo_res 898 898 0 0.00 0 2849 6 0 0
v_min_id_ord_sup 5056 5056 0 0.00 0 7544 14 0 0
v_min_non_paid_invoice 5368 5368 0 0.00 0 6351 11 0 0
v_min_non_paid_realizn 6443 6443 0 0.00 0 6602 14 0 0
v_our_firm 9622 9622 0 0.00 0 18 1 0 0
v_reserve_write_off 18004 18004 0 0.00 0 11173 7 0 0
x_lock_depdocs_on_canc_invoice 11714 11714 0 0.00 0 17848 117 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 45264 520 0 0
x_make_qstorno_1000_1200 4978 4978 0 0.00 2 24384 1324 0 0
x_make_qstorno_1200_2000 5062 5062 0 0.00 4 84871 3454 0 0
x_make_qstorno_2100_3300 422905 422905 0 0.00 0 123689 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2021 07:39:06 to 21-02-2021 07:39:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:10 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:08:10 31005 8192000000 9724601696 10021122048 799221632 8.888 100 87 174 0 828332768 7929344 21181792
05:09:43 26702 8192000000 9779353776 10087280640 846752096 9.368 100 88 176 0 880368976 13209872 20407008
05:11:19 12232 8192000000 9816749200 10134683648 875433824 9.654 100 84 166 2 919390592 25077232 18879536
05:12:36 15499 8192000000 9874363152 10203758592 911162928 10.009 100 81 161 1 973666736 45007056 17496752
05:13:54 7728 8192000000 9906360864 10250280960 942183152 10.314 100 71 143 1 1018874320 61464000 15227168
05:15:05 5543 8192000000 9965366560 10320609280 1000757424 10.886 100 82 164 0 1080501296 63432432 16311440
05:16:12 3426 8192000000 10007354768 10372161536 1031315104 11.181 100 73 146 0 1121822352 75581280 14925968
05:17:18 2011 8192000000 10031473312 10396479488 1052676304 11.386 100 89 177 1 1132956736 62422512 17857920
05:18:23 30911 8192000000 9974609824 10363342848 1064819072 11.503 100 44 89 1 1129118384 55455552 8843760
05:19:57 7768 8192000000 10000523296 10389155840 1078130048 11.630 100 39 80 2 1119884736 33580176 8174512
05:21:06 4696 8192000000 9996592272 10391044096 1087502192 11.719 100 35 72 1 1108596048 14338352 6755504
05:22:12 63759 8192000000 10003563488 10427269120 1088767216 11.731 100 38 76 2 1119136896 22398336 7971344
05:24:19 4406 8192000000 10015369840 10409611264 1097053744 11.810 100 40 79 0 1140903248 32929984 10919520
05:25:23 11737 8192000000 10014697760 10417569792 1097138384 11.810 100 21 41 1 1123913328 22551248 4223696
05:26:38 10578 8192000000 10012557504 10416357376 1064387280 11.498 97 24 47 0 1099600160 29900656 5312224
05:27:49 54118 8192000000 9990111040 10403811328 1021831008 11.090 94 38 74 1 1053343568 23902736 7609824
05:29:49 6165 8192000000 9961014176 10353405952 1033489744 11.202 98 33 68 0 1059615472 18823776 7301952
05:30:56 5377 8192000000 9911454288 10291527680 982699968 10.710 97 37 75 0 1007941040 15814384 9426688
05:32:03 7217 8192000000 9924944512 10296479744 988478352 10.767 96 37 73 0 1017465840 21014176 7973312
05:33:11 70153 8192000000 9961304080 10367164416 1051888608 11.379 100 25 50 2 1074398944 17033280 5477056
05:35:22 4037 8192000000 9986960368 10379579392 1066593040 11.520 100 50 97 2 1104973920 26922208 11458672
05:36:27 6414 8192000000 9988552560 10386677760 1075049376 11.600 100 29 55 1 1103639376 21976528 6613472
05:37:36 35155 8192000000 10049259296 10449108992 1103036976 11.866 100 52 104 0 1150922384 37422416 10462992
05:39:16 67024 8192000000 10057891536 10469187584 1118660608 12.014 100 38 73 4 1162676400 36236224 7779568
05:41:25 18579 8192000000 10104766688 10528112640 1133332736 12.153 100 36 71 0 1221594896 80027712 8234448
05:42:52 25847 8192000000 10130029312 10553671680 1150640672 12.316 100 41 81 0 1244542288 84894544 9007072
05:44:22 8034 8192000000 10162663312 10571481088 1156458640 12.370 100 72 143 1 1270690688 98252720 15979328
05:45:36 26714 8192000000 10151184016 10568749056 1160481824 12.408 100 49 98 1 1265574112 94239504 10852784
05:47:13 3178 8192000000 10179648656 10591330304 1174242320 12.536 100 60 118 1 1281807680 94514704 13050656
05:48:19 4079 8192000000 10238775280 10657439744 1177940848 12.571 100 70 138 1 1337858608 144798496 15119264
05:49:33 8650 8192000000 10216119136 10633846784 1169609552 12.493 99 61 123 1 1321900240 138321920 13968768
05:50:46 5102 8192000000 10242947440 10667139072 1181063984 12.600 100 73 143 1 1355175744 158290272 15821488
05:51:58 5835 8192000000 10278492656 10690281472 1188417024 12.669 100 67 134 0 1380069536 176104320 15548192
05:53:16 3688 8192000000 10305067616 10725335040 1190278128 12.686 100 66 132 0 1409694608 204460192 14956288
05:54:28 3213 8192000000 10362824016 10772959232 1189950832 12.683 100 68 136 1 1465799264 260154256 15694176
05:55:35 3495 8192000000 10417086384 10827665408 1189850064 12.682 100 88 176 1 1518491600 308302928 20338608
05:56:44 3402 8192000000 10441588560 10851110912 1183248544 12.620 100 86 172 0 1539149152 334854864 21045744
05:57:51 1578 8192000000 10498174528 10902499328 1182625040 12.615 100 95 188 0 1592147664 386961296 22561328
05:59:02 923 8192000000 10521512640 10933469184 1192180208 12.704 100 94 188 0 1616086416 400709504 23196704
06:00:08 9266 8192000000 10576815888 10996199424 1176190032 12.555 100 70 140 1 1677214080 483092976 17931072
06:01:37 21198 8192000000 10462013904 10921771008 1172445824 12.520 100 44 87 1 1582347856 398901248 11000784
06:03:07 11749 8192000000 10489195968 10952015872 1163865328 12.439 100 41 78 1 1600427744 426227456 10334960
06:04:21 18340 8192000000 10477004224 10961526784 1138956368 12.206 99 24 48 0 1571128144 425465648 6706128
06:05:41 33489 8192000000 10366453568 10825453568 1100004640 11.838 98 26 52 2 1523390800 415963120 7423040
06:07:19 50650 8192000000 10082403872 10533195776 1086722480 11.711 96 15 30 1 1148696752 58866192 3108080
06:09:10 100140 8192000000 10077911536 10638725120 1121212352 12.038 100 9 18 0 1192683168 69720144 1750672
06:11:51 118930 8192000000 10049801440 10501206016 1101071200 11.848 98 35 70 1 1126032128 17564816 7396112
06:15:00 93629 8192000000 10085053696 10538577920 1116313616 11.992 99 30 60 1 1173432336 50968544 6150176
06:17:41 25905 8192000000 10107904560 10551197696 1107569680 11.909 98 37 73 0 1193774032 78143776 8060576
06:19:16 36628 8192000000 10045518176 10531311616 1078337952 11.632 97 11 22 1 1143199712 62130080 2731680
06:22:12 50486 8192000000 10047887184 10535309312 1115897296 11.988 100 11 22 1 1173707744 55194864 2615584
06:24:03 54849 8192000000 10053964352 10489237504 1118977712 12.017 99 22 43 1 1156741312 33242112 4521488
06:26:03 54493 8192000000 10069153504 10533203968 1130281568 12.124 100 14 28 0 1184847360 51783840 2781952
06:27:58 57005 8192000000 10058445520 10545328128 1117357472 12.002 98 25 50 1 1163237216 40162592 5717152
06:30:16 45602 8192000000 10073062848 10525716480 1126703184 12.090 99 14 28 0 1172911552 43416048 2792320
06:32:08 92910 8192000000 10083967552 10560978944 1106633856 11.901 97 17 34 1 1174921280 64943648 3343776
06:34:42 65734 8192000000 10107647936 10579714048 1109158704 11.924 97 15 30 0 1173239888 60501280 3579904
06:36:48 120425 8192000000 10133002224 10616717312 1127995856 12.102 98 42 84 1 1223565504 86684352 8885296
06:39:54 71319 8192000000 10133689584 10596397056 1140089248 12.216 99 9 18 2 1214153744 72153680 1910816
06:42:05 67589 8192000000 10127937824 10594508800 1122652432 12.052 98 19 38 1 1221814256 95107968 4053856
06:44:19 58233 8192000000 10141030720 10582388736 1109391632 11.927 96 28 55 1 1202516704 87314608 5810464
06:46:18 51171 8192000000 10162271024 10611884032 1154931872 12.356 100 29 58 0 1273009120 111163312 6913936
06:48:27 78765 8192000000 10121641312 10667302912 1118633552 12.014 97 14 28 1 1210710560 88904912 3172096
06:50:46 40268 8192000000 10094064944 10536734720 1093795216 11.779 96 30 60 0 1169809664 70013568 6000880
06:52:29 9217 8192000000 10114954160 10534780928 1117653328 12.005 99 56 113 1 1196206512 67510512 11042672
06:53:42 46012 8192000000 10079018544 10530844672 1104710816 11.882 98 34 68 1 1182391824 71064784 6616224
06:55:39 94073 8192000000 10059442240 10528485376 1114817376 11.978 99 13 26 0 1179093280 61519616 2756288
06:58:43 84668 8192000000 10062172192 10552868864 1090811840 11.750 97 5 10 0 1118513184 26776576 924768
07:01:08 78644 8192000000 10104966496 10572767232 1095432400 11.794 97 20 40 1 1165560656 65476032 4652224
07:03:33 89748 8192000000 10159461952 10620252160 1091081328 11.753 96 20 40 1 1191510576 96005584 4423664
07:06:04 59688 8192000000 10177637648 10676887552 1104486960 11.880 98 20 39 1 1256449840 147312848 4650032
07:08:09 58284 8192000000 10137130944 10633519104 1128198288 12.104 100 12 24 0 1232805728 102192912 2414528
07:10:07 71917 8192000000 10073726592 10522271744 1079339584 11.641 95 22 44 0 1112080448 28578032 4162832
07:12:22 85262 8192000000 10076778896 10526384128 1116898512 11.998 99 29 58 1 1155590016 33103712 5587792
07:14:51 65035 8192000000 10069010032 10533810176 1130883744 12.130 100 14 29 2 1166676272 32995184 2797344
07:17:08 89438 8192000000 10128783056 10593935360 1138663744 12.203 100 16 32 1 1194137184 51781872 3691568
07:19:44 108181 8192000000 10130074032 10628063232 1140266176 12.218 99 17 34 0 1265681488 121480864 3934448
07:23:13 86998 8192000000 10092600688 10599346176 1133386704 12.153 99 14 28 0 1245891888 109082352 3422832
07:26:18 37015 8192000000 10144715856 10613374976 1138986032 12.206 99 21 42 2 1214760752 71512016 4262704
07:28:04 83613 8192000000 10105202928 10551443456 1144059024 12.254 100 23 45 3 1183435136 34643376 4732736
07:30:28 114946 8192000000 10079556896 10550849536 1108514528 11.918 96 23 46 2 1152454816 39428512 4511776
07:33:32 68059 8192000000 10058175600 10520522752 1117902496 12.007 98 29 56 0 1147423536 23968352 5552688
07:36:07 56954 8192000000 10082800000 10537684992 1128123776 12.104 99 6 12 0 1172240448 42919504 1197168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-02-2021 07:39:12 to 21-02-2021 07:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          221311
MON$OLDEST_ACTIVE               223687
MON$OLDEST_SNAPSHOT             223687
MON$NEXT_TRANSACTION            223687
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                       2111552
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 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.2372 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Feb 21 07:37:34 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Feb 21 07:37:35 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

21.02.21 07:39:30. 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-02-21 07:39:33 No severe PSQL-related problems occured
21.02.21 07:39:34. End of report.