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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-24 13:26:52
DTS_END                         2020-11-24 13:26:52.6910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40566
TRN_ID                          228526
ATT_ID                          605
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: 8853.96 19260 1062475
customer order: creation 166.53 422 19984
customer order: refuse 14.08 387 1689
order to supplier: creation 43.88 1329 5266
order to supplier: removal 3.57 121045 428
invoice (draft): creation 43.30 3312 5196
invoice (draft): removal 52.08 991 6250
invoice accept: apply 39.86 20639 4783
invoice accept: cancel 46.51 909 5581
customer reserve: creation 3597.91 208 431749
customer reserve: removal 4558.45 74 547014
realization accept: apply 79.72 194 9567
realization accept: cancel 87.02 122 10442
payment from customer: creation 56.15 108 6738
payment from customer: removal 5.43 89 652
payment to supplier: creation 45.87 130 5504
payment to supplier: removal 3.15 93 378
service: total inventory turnovers 3.70 3527 444
service: total monetary turnovers 5.94 597 713
service: aggregate perf. data 0.78 14715 93
service: refresh index statistics 0.03 216302 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-11-2020 13:28:03 to 24-11-2020 13:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5613
TEST_TIME 2 4938
TEST_TIME 3 5282
TEST_TIME 4 5657
TEST_TIME 5 6354
TEST_TIME 6 5968
TEST_TIME 7 5585
TEST_TIME 8 5552
TEST_TIME 9 5809
TEST_TIME 10 5856
TEST_TIME 11 5952
TEST_TIME 12 6087
TEST_TIME 13 5991
TEST_TIME 14 6422
TEST_TIME 15 6531
TEST_TIME 16 6760
TEST_TIME 17 6740
TEST_TIME 18 6996
TEST_TIME 19 7447
TEST_TIME 20 7526
TEST_TIME 21 7813
TEST_TIME 22 8061
TEST_TIME 23 8074
TEST_TIME 24 8028
TEST_TIME 25 9026
TEST_TIME 26 9155
TEST_TIME 27 9165
TEST_TIME 28 9329
TEST_TIME 29 10833
TEST_TIME 30 10625
TEST_TIME 31 10710
TEST_TIME 32 10921
TEST_TIME 33 10909
TEST_TIME 34 10732
TEST_TIME 35 10481
TEST_TIME 36 10351
TEST_TIME 37 10166
TEST_TIME 38 10151
TEST_TIME 39 10027
TEST_TIME 40 10090
TEST_TIME 41 10029
TEST_TIME 42 10023
TEST_TIME 43 9897
TEST_TIME 44 9754
TEST_TIME 45 9605
TEST_TIME 46 9516
TEST_TIME 47 9775
TEST_TIME 48 9876
TEST_TIME 49 9818
TEST_TIME 50 9784
TEST_TIME 51 9808
TEST_TIME 52 9842
TEST_TIME 53 9878
TEST_TIME 54 9858
TEST_TIME 55 9896
TEST_TIME 56 9921
TEST_TIME 57 9938
TEST_TIME 58 9971
TEST_TIME 59 9818
TEST_TIME 60 9762
TEST_TIME 61 9697
TEST_TIME 62 9588
TEST_TIME 63 9497
TEST_TIME 64 9474
TEST_TIME 65 9411
TEST_TIME 66 9436
TEST_TIME 67 9457
TEST_TIME 68 9468
TEST_TIME 69 9433
TEST_TIME 70 9413
TEST_TIME 71 9422
TEST_TIME 72 9575
TEST_TIME 73 9653
TEST_TIME 74 9619
TEST_TIME 75 9635
TEST_TIME 76 9716
TEST_TIME 77 9643
TEST_TIME 78 9541
TEST_TIME 79 9511
TEST_TIME 80 9446
TEST_TIME 81 9366
TEST_TIME 82 9302
TEST_TIME 83 9235
TEST_TIME 84 9255
TEST_TIME 85 9251
TEST_TIME 86 9209
TEST_TIME 87 9264
TEST_TIME 88 9342
TEST_TIME 89 9305
TEST_TIME 90 9295
TEST_TIME 91 9288
TEST_TIME 92 9316
TEST_TIME 93 9304
TEST_TIME 94 9315
TEST_TIME 95 9310
TEST_TIME 96 9265
TEST_TIME 97 9227
TEST_TIME 98 9154
TEST_TIME 99 9105
TEST_TIME 100 9055
TEST_TIME 101 9009
TEST_TIME 102 9011
TEST_TIME 103 9010
TEST_TIME 104 9007
TEST_TIME 105 8988
TEST_TIME 106 8963
TEST_TIME 107 9002
TEST_TIME 108 9023
TEST_TIME 109 8997
TEST_TIME 110 9019
TEST_TIME 111 9031
TEST_TIME 112 9046
TEST_TIME 113 9077
TEST_TIME 114 9019
TEST_TIME 115 8978
TEST_TIME 116 8929
TEST_TIME 117 8909
TEST_TIME 118 8910
TEST_TIME 119 8877
TEST_TIME 120 8859

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-11-2020 13:28:07 to 24-11-2020 13:28:10.

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 1464431 1464431 0 0.00 0 19185 7 0 0
doc_list_biud 1464431 1464431 0 0.00 0 42209 35 0 0
sp_add_invoice_to_stock 4902 4783 119 2.42 38 245127 20640 0 119
sp_cancel_adding_invoice 5581 5581 0 0.00 6 73450 909 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 11843 387 0 0
sp_cancel_customer_reserve 547014 547014 0 0.00 1 22490 75 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 3296 90 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 3871 94 0 0
sp_cancel_supplier_invoice 6256 6250 6 0.09 2 19466 991 0 6
sp_cancel_supplier_order 428 428 0 0.00 6 1348871 121046 0 0
sp_cancel_write_off 10442 10442 0 0.00 1 13510 122 0 0
sp_client_order 19984 19984 0 0.00 4 23713 422 0 0
sp_customer_reserve 431749 431749 0 0.00 0 45290 209 0 0
sp_fill_shopping_cart 30430 30430 0 0.00 1 13183 40 0 0
sp_fill_shopping_cart_clo_res 431745 431745 0 0.00 0 29066 19 0 0
sp_get_clo_for_invoice 4777 4777 0 0.00 0 28942 366 0 0
sp_kill_cost_storno 17053 17053 0 0.00 0 8333 61 0 0
sp_kill_qstorno_ret_qs2qd 1111148 1111148 0 0.00 0 22487 40 0 0
sp_kill_qty_storno 585747 585747 0 0.00 0 42208 87 0 0
sp_lock_selected_doc 19916 19916 0 0.00 0 10601 8 0 0
sp_make_cost_storno 26585 26585 0 0.00 0 15243 62 0 0
sp_multiply_rows_for_pdistr 26585 26585 0 0.00 0 6338 30 0 0
sp_multiply_rows_for_qdistr 19984 19984 0 0.00 0 23673 223 0 0
sp_pay_from_customer 6738 6738 0 0.00 4 11556 109 0 0
sp_pay_to_supplier 5504 5504 0 0.00 5 13515 130 0 0
sp_payment_common 12242 12242 0 0.00 1 9025 19 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 11836 358 0 0
sp_qd_handle_on_invoice_upd_sts 10357 10357 0 0.00 0 42207 438 0 0
sp_qd_handle_on_reserve_upd_sts 9567 9567 0 0.00 0 10064 50 0 0
sp_reserve_write_off 9567 9567 0 0.00 6 23886 194 0 0
sp_supplier_invoice 5196 5196 0 0.00 7 86846 3313 0 0
sp_supplier_order 5266 5266 0 0.00 5 65283 1329 0 0
srv_aggregate_perf_data 93 93 0 0.00 2781 132606 14715 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1536 116188 40560 0 0
srv_find_qd_qs_mism 1018462 1018462 0 0.00 0 19184 6 0 0
srv_make_invnt_saldo 444 444 0 0.00 32 97242 3528 0 0
srv_make_money_saldo 713 713 0 0.00 1 13505 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 79478 488481 216303 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4783 4783 0 0.00 0 27688 184 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 9336 115 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 2904 14 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 755 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 844 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1339 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 536 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 13725 1335 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 1713 10 0 0
t$perf-norm:sp_client_order 19984 19984 0 0.00 0 12590 18 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 2741 9 0 0
t$perf-norm:sp_pay_from_customer 6738 6738 0 0.00 0 3979 7 0 0
t$perf-norm:sp_pay_to_supplier 5504 5504 0 0.00 0 4977 10 0 0
t$perf-norm:sp_reserve_write_off 9567 9567 0 0.00 0 14328 12 0 0
t$perf-norm:sp_supplier_invoice 5196 5196 0 0.00 0 5989 18 0 0
t$perf-norm:sp_supplier_order 5266 5266 0 0.00 0 4549 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 445 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 829 24 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 829 15 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 4970 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9788 9550 238 2.43 0 10217 21 0 238
v_all_customers 36258 36258 0 0.00 0 202 1 0 0
v_all_suppliers 28996 28996 0 0.00 0 204 1 0 0
v_all_wares 39968 39968 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 3134 13 0 0
v_cancel_client_order 3378 3378 0 0.00 0 2416 5 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 3290 16 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 947 5 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 1100 5 0 0
v_cancel_supplier_invoice 838 826 12 1.43 0 763 15 0 12
v_cancel_supplier_order 856 856 0 0.00 0 1060 4 0 0
v_cancel_write_off 1876 1876 0 0.00 0 2742 17 0 0
v_max_id_clo_ord 5263 5263 0 0.00 0 793 2 0 0
v_max_id_clo_res 946 946 0 0.00 0 6569 23 0 0
v_max_id_ord_sup 5182 5182 0 0.00 0 5322 4 0 0
v_max_non_paid_invoice 1450 1450 0 0.00 0 606 2 0 0
v_max_non_paid_realizn 3586 3586 0 0.00 0 2001 4 0 0
v_min_id_clo_ord 5263 5263 0 0.00 0 1754 6 0 0
v_min_id_clo_res 946 946 0 0.00 0 7338 18 0 0
v_min_id_ord_sup 5182 5182 0 0.00 0 5234 7 0 0
v_min_non_paid_invoice 5504 5504 0 0.00 0 9018 14 0 0
v_min_non_paid_realizn 6738 6738 0 0.00 0 4547 9 0 0
v_our_firm 10014 10014 0 0.00 0 15 1 0 0
v_reserve_write_off 19134 19134 0 0.00 0 5574 7 0 0
x_lock_depdocs_on_canc_invoice 11418 11418 0 0.00 0 18683 107 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10773 328 0 0
x_make_qstorno_1000_1200 5266 5266 0 0.00 2 65244 1247 0 0
x_make_qstorno_1200_2000 5196 5196 0 0.00 4 86838 3166 0 0
x_make_qstorno_2100_3300 431749 431749 0 0.00 0 45288 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2020 13:28:11 to 24-11-2020 13:28:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:56 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:57 32955 8192000000 9722820304 10018557952 797360336 8.870 100 87 174 2 826418784 7628400 21430048
10:59:38 21003 8192000000 9760664368 10066030592 838602512 9.286 100 87 172 1 871114160 12120640 20391008
11:01:09 15359 8192000000 9798084656 10113241088 863230272 9.532 100 87 174 1 908942848 25587584 20124992
11:02:39 12902 8192000000 9873188160 10204504064 915165984 10.048 100 75 150 1 978037104 46829280 16041840
11:03:53 8448 8192000000 9926718960 10272915456 962306096 10.512 100 77 154 2 1037524736 58853088 16365552
11:05:06 5290 8192000000 9972038752 10324766720 999015648 10.869 100 89 175 1 1089691328 72628944 18046736
11:06:17 2596 8192000000 9996931424 10360008704 1023467456 11.105 100 88 176 2 1109122112 68015632 17639024
11:07:22 2348 8192000000 10029915680 10396209152 1041694880 11.281 100 88 174 0 1138315584 78305104 18315600
11:08:27 2028 8192000000 10035949840 10412605440 1059246016 11.449 100 93 187 1 1151660912 73904944 18509952
11:09:30 5346 8192000000 10025627232 10423373824 1063130736 11.486 100 69 139 0 1144345648 66806800 14408112
11:10:49 5879 8192000000 9979589584 10364764160 1071772624 11.569 100 59 117 1 1095756096 10526608 13456864
11:11:56 8516 8192000000 9985721920 10385367040 1074155712 11.592 100 33 66 3 1102496992 20895392 7445888
11:13:08 9253 8192000000 9993967680 10399035392 1082455568 11.671 100 29 59 1 1130674176 42212592 6006016
11:14:18 49961 8192000000 10001408032 10399481856 1081683856 11.664 100 45 91 3 1113231248 21590096 9957296
11:16:09 7319 8192000000 10031463136 10435723264 1089421440 11.737 100 42 81 0 1121061552 22734976 8905136
11:17:19 6931 8192000000 10006620192 10394664960 1074926768 11.599 99 49 96 0 1101279568 15565536 10787264
11:18:29 4883 8192000000 9970272896 10345332736 1030560880 11.174 97 40 78 2 1060621312 21238064 8822368
11:19:37 26914 8192000000 9956579712 10367377408 1011382736 10.989 96 30 60 3 1054486608 37079664 6024208
11:21:07 4519 8192000000 9936397872 10309758976 966307920 10.551 95 42 81 2 1010590304 34677744 9604640
11:22:12 10417 8192000000 9958026496 10360578048 1028496608 11.154 99 57 114 3 1068077216 26427312 13153296
11:23:24 4922 8192000000 9967884080 10356969472 1049448832 11.355 100 48 96 1 1075374288 14928720 10996736
11:24:29 43929 8192000000 9970575984 10382729216 1062991120 11.485 100 15 30 2 1086574912 20034688 3549104
11:26:17 5779 8192000000 10002474768 10395480064 1082461232 11.671 100 49 96 4 1112412784 18983728 10967824
11:27:29 6660 8192000000 10033811696 10426781696 1098625776 11.825 100 53 106 1 1130481968 19577248 12278944
11:28:40 51270 8192000000 10090051360 10498490368 1111520352 11.947 100 54 108 3 1218419408 96017696 10881360
11:30:42 13009 8192000000 10129668656 10543140864 1128695264 12.109 100 53 105 0 1228674880 88662112 11317504
11:31:58 6434 8192000000 10105044256 10513477632 1141044656 12.225 100 49 96 3 1219641472 67833920 10762896
11:33:11 28519 8192000000 10128061936 10541121536 1147315072 12.284 100 41 80 3 1233260400 77047840 8897488
11:34:46 17084 8192000000 10202678464 10621870080 1155378048 12.360 100 50 100 0 1305755584 139534784 10842752
11:36:10 18914 8192000000 10232794080 10650873856 1162223664 12.424 100 58 116 1 1333493264 158471984 12797616
11:37:43 6215 8192000000 10261477648 10674282496 1175906960 12.552 100 73 146 1 1369762992 177446976 16409056
11:38:56 2380 8192000000 10285584688 10692214784 1174614960 12.540 100 74 147 2 1383249776 192253584 16381232
11:40:05 7034 8192000000 10298959664 10723233792 1174152320 12.536 99 60 118 0 1417974416 229069824 14752272
11:41:15 1887 8192000000 10333316192 10750652416 1186468816 12.650 100 78 155 1 1439023344 234393568 18160960
11:42:24 2200 8192000000 10396138064 10824118272 1183638480 12.624 100 75 148 1 1497084512 296141824 17304208
11:43:33 3393 8192000000 10408646864 10814771200 1177766640 12.569 100 80 160 0 1504994112 308668384 18559088
11:44:43 1535 8192000000 10424665600 10840584192 1164795392 12.448 100 84 168 1 1521329088 336231184 20302512
11:45:52 2144 8192000000 10412238448 10845442048 1146667840 12.278 99 77 155 0 1519180624 353568864 18943920
11:46:58 18258 8192000000 10391159584 10838597632 1112626224 11.957 97 42 85 3 1509763200 386193888 10943088
11:48:22 3666 8192000000 10457241408 10857500672 1123415792 12.059 98 79 158 1 1548862240 405908400 19538048
11:49:30 8075 8192000000 10441363872 10870181888 1134392016 12.163 100 48 93 2 1537966576 390833296 12741264
11:50:51 4240 8192000000 10469798320 10890514432 1129819216 12.120 100 80 159 1 1569789888 419844640 20126032
11:51:58 9844 8192000000 10354677248 10795823104 1143626176 12.250 100 41 81 0 1458898800 305086080 10186544
11:53:28 36024 8192000000 10378421488 10830204928 1096180544 11.801 98 26 53 1 1454852800 351350320 7321936
11:55:06 37173 8192000000 10122770368 10789933056 1098529632 11.824 100 15 30 0 1349095472 246372976 4192864
11:56:50 28069 8192000000 10113930656 10544238592 1107927680 11.913 98 45 91 1 1155094736 37637488 9529568
11:58:20 93642 8192000000 10075673376 10536013824 1100279008 11.840 98 6 12 0 1168154848 66779680 1096160
12:01:06 51245 8192000000 10064894992 10496102400 1117321312 12.002 100 50 97 1 1156186112 28087968 10776832
12:03:11 34326 8192000000 10056538736 10496143360 1128847616 12.110 100 10 20 2 1154230000 23379888 2002496
12:04:46 31844 8192000000 10094357440 10517061632 1111156352 11.943 98 55 108 3 1183283072 60540960 11585760
12:06:21 19253 8192000000 10111658800 10545704960 1133383808 12.153 100 46 92 1 1206437936 62999696 10054432
12:07:53 79529 8192000000 10066459888 10521780224 1110192816 11.934 98 15 30 1 1179974784 66127360 3654608
12:10:15 22932 8192000000 10115543760 10570887168 1139363488 12.210 100 11 22 1 1216675024 74890016 2421520
12:11:52 44658 8192000000 10159767792 10635317248 1137152128 12.189 100 18 36 0 1259363536 118087712 4123696
12:14:38 43698 8192000000 10062499200 10504056832 1110725728 11.939 98 27 53 2 1165943824 49809200 5408896
12:16:31 32419 8192000000 10092632528 10567536640 1136056224 12.178 100 45 90 2 1195482752 50919184 8507344
12:18:10 45263 8192000000 10088322912 10531684352 1142458672 12.239 100 14 28 0 1196000240 50672768 2868800
12:20:00 21176 8192000000 10106418656 10539745280 1143874384 12.252 100 29 57 1 1194812496 44495744 6442368
12:21:22 83588 8192000000 10081970080 10542837760 1122845600 12.054 98 13 26 0 1193580272 67901040 2833632
12:23:48 61762 8192000000 10072642704 10548736000 1077845040 11.627 94 16 32 2 1134559696 53607824 3106832
12:26:04 88883 8192000000 10067291376 10541944832 1128785568 12.110 99 19 38 0 1166917424 34253952 3877904
12:28:39 66307 8192000000 10088585856 10551697408 1136727968 12.185 99 16 32 0 1180523152 40545840 3249344
12:30:47 73896 8192000000 10107717008 10572861440 1115657712 11.986 98 15 30 0 1186200128 67426896 3115520
12:33:11 57591 8192000000 10061818480 10506973184 1076911424 11.618 95 19 38 1 1136752992 55770992 4070576
12:35:09 63878 8192000000 10133425760 10573004800 1112421936 11.955 98 21 43 1 1173751552 56656544 4673072
12:37:14 59088 8192000000 10203035600 10661036032 1121524240 12.041 98 26 52 0 1268210752 140901200 5785312
12:39:13 47524 8192000000 10047608112 10528739328 1077408448 11.623 96 14 28 2 1121333648 40831344 3093856
12:41:04 30930 8192000000 10115483904 10536095744 1097287280 11.812 98 56 110 3 1164749024 56612400 10849344
12:42:37 57013 8192000000 10066141408 10495983616 1085769056 11.702 97 26 52 2 1130045152 38930304 5345792
12:44:43 89249 8192000000 10029380096 10488664064 1068940288 11.542 97 13 26 0 1128843264 57278448 2624528
12:47:19 39140 8192000000 10035805264 10495365120 1088525456 11.729 99 14 27 1 1126710432 35397888 2787088
12:49:07 45901 8192000000 10088010624 10536861696 1100403696 11.841 99 12 24 1 1170515632 67477472 2634464
12:50:53 45675 8192000000 10089019536 10543099904 1099004560 11.828 98 14 28 0 1182132448 79539392 3588496
12:52:39 87114 8192000000 10105176208 10673655808 1134415520 12.163 100 22 44 1 1257356896 118078512 4862864
12:55:22 34454 8192000000 10067950736 10509430784 1094422320 11.785 97 18 36 1 1146897952 48833264 3642368
12:57:02 26022 8192000000 10081719856 10512941056 1109070208 11.924 98 42 84 1 1163014528 45951360 7992960
12:58:29 36245 8192000000 10102466032 10562826240 1111718128 11.949 98 44 88 1 1199155536 78857248 8580160
13:00:11 29501 8192000000 10121258496 10579656704 1127169520 12.095 99 50 100 0 1185683168 48680032 9833616
13:02:58 93064 8192000000 10043634288 10516701184 1102164192 11.858 97 12 24 1 1151643632 46959872 2519568
13:05:34 57833 8192000000 10047888736 10525261824 1110723488 11.939 98 4 8 1 1130631008 19001440 906080
13:08:09 100470 8192000000 10099965424 10577932288 1146033968 12.272 100 16 32 1 1224060816 74280400 3746448
13:10:51 71701 8192000000 10102107904 10572693504 1112828832 11.959 97 10 20 0 1168814368 53469024 2516512
13:13:03 67235 8192000000 10079847744 10619822080 1141571328 12.230 99 21 42 2 1224580160 78245200 4763632
13:15:49 52928 8192000000 10122510144 10599350272 1147629488 12.287 99 38 76 0 1221722960 66518736 7574736
13:17:51 69656 8192000000 10066194464 10511740928 1077245952 11.621 95 30 60 3 1127179936 43560768 6373216
13:20:03 116188 8192000000 10064442768 10548162560 1109793328 11.930 99 14 28 0 1163841136 51378928 2668880
13:23:17 50987 8192000000 10049882320 10521051136 1126242368 12.086 100 2 4 0 1145201872 18693984 265520
13:25:09 26628 8192000000 10065645744 10542305280 1114141952 11.972 99 24 49 0 1139773984 20279696 5352336
13:26:48 12438 8192000000 8893727696 9068703744 59390224 0.719 6 1 2 0 64348784 4923072 35488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2020 13:28:17 to 24-11-2020 13:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 24-11-2020 13:28:33 to 24-11-2020 13:28:35.

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          228927
MON$OLDEST_ACTIVE               228928
MON$OLDEST_SNAPSHOT             228928
MON$NEXT_TRANSACTION            228928
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                       2097728
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             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

24.11.20 13:28:36. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-11-24 13:28:39 No severe PSQL-related problems occured
24.11.20 13:28:40. End of report.