Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.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 16789000 kB
MemAvailable 31486884 kB
Buffers 0 kB
Cached 14767244 kB
SwapCached 1932 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-12-02 13:26:52
DTS_END                         2020-12-02 13:26:52.0110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59514
TRN_ID                          235071
ATT_ID                          654
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: 8830.73 27345 1059687
customer order: creation 162.67 406 19520
customer order: refuse 14.81 371 1777
order to supplier: creation 43.64 1358 5237
order to supplier: removal 3.13 122833 376
invoice (draft): creation 44.64 3687 5357
invoice (draft): removal 50.25 875 6030
invoice accept: apply 39.78 16946 4774
invoice accept: cancel 45.50 841 5460
customer reserve: creation 3678.75 164 441450
customer reserve: removal 4463.43 69 535612
realization accept: apply 80.01 164 9601
realization accept: cancel 80.90 117 9708
payment from customer: creation 56.43 112 6771
payment from customer: removal 5.69 73 683
payment to supplier: creation 47.20 124 5664
payment to supplier: removal 3.35 81 402
service: total inventory turnovers 3.63 3488 436
service: total monetary turnovers 5.98 578 717
service: aggregate perf. data 0.90 17095 108
service: refresh index statistics 0.03 377511 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2020 13:28:07 to 02-12-2020 13:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2220
TEST_TIME 2 5235
TEST_TIME 3 4895
TEST_TIME 4 4576
TEST_TIME 5 4353
TEST_TIME 6 4667
TEST_TIME 7 4886
TEST_TIME 8 4996
TEST_TIME 9 5554
TEST_TIME 10 5808
TEST_TIME 11 5802
TEST_TIME 12 6152
TEST_TIME 13 6501
TEST_TIME 14 7175
TEST_TIME 15 7259
TEST_TIME 16 7561
TEST_TIME 17 7756
TEST_TIME 18 7954
TEST_TIME 19 8337
TEST_TIME 20 8610
TEST_TIME 21 8668
TEST_TIME 22 8612
TEST_TIME 23 8612
TEST_TIME 24 8912
TEST_TIME 25 8795
TEST_TIME 26 9171
TEST_TIME 27 9904
TEST_TIME 28 11163
TEST_TIME 29 11214
TEST_TIME 30 10957
TEST_TIME 31 10768
TEST_TIME 32 10646
TEST_TIME 33 10531
TEST_TIME 34 10355
TEST_TIME 35 10198
TEST_TIME 36 10091
TEST_TIME 37 10011
TEST_TIME 38 9842
TEST_TIME 39 9801
TEST_TIME 40 9574
TEST_TIME 41 9488
TEST_TIME 42 9583
TEST_TIME 43 9468
TEST_TIME 44 9430
TEST_TIME 45 9569
TEST_TIME 46 9604
TEST_TIME 47 9578
TEST_TIME 48 9652
TEST_TIME 49 9603
TEST_TIME 50 9613
TEST_TIME 51 9660
TEST_TIME 52 9775
TEST_TIME 53 9713
TEST_TIME 54 9645
TEST_TIME 55 9760
TEST_TIME 56 9605
TEST_TIME 57 9457
TEST_TIME 58 9442
TEST_TIME 59 9362
TEST_TIME 60 9264
TEST_TIME 61 9198
TEST_TIME 62 9133
TEST_TIME 63 9152
TEST_TIME 64 9097
TEST_TIME 65 9058
TEST_TIME 66 9044
TEST_TIME 67 9034
TEST_TIME 68 9115
TEST_TIME 69 9200
TEST_TIME 70 9230
TEST_TIME 71 9224
TEST_TIME 72 9359
TEST_TIME 73 9310
TEST_TIME 74 9380
TEST_TIME 75 9362
TEST_TIME 76 9305
TEST_TIME 77 9235
TEST_TIME 78 9160
TEST_TIME 79 9110
TEST_TIME 80 9026
TEST_TIME 81 8998
TEST_TIME 82 9061
TEST_TIME 83 9078
TEST_TIME 84 9077
TEST_TIME 85 9066
TEST_TIME 86 9100
TEST_TIME 87 9106
TEST_TIME 88 9107
TEST_TIME 89 9098
TEST_TIME 90 9172
TEST_TIME 91 9136
TEST_TIME 92 9251
TEST_TIME 93 9196
TEST_TIME 94 9112
TEST_TIME 95 9052
TEST_TIME 96 9019
TEST_TIME 97 8991
TEST_TIME 98 8965
TEST_TIME 99 8927
TEST_TIME 100 8938
TEST_TIME 101 8898
TEST_TIME 102 8929
TEST_TIME 103 8932
TEST_TIME 104 8954
TEST_TIME 105 8992
TEST_TIME 106 8973
TEST_TIME 107 9024
TEST_TIME 108 9049
TEST_TIME 109 9060
TEST_TIME 110 9087
TEST_TIME 111 9118
TEST_TIME 112 9108
TEST_TIME 113 9030
TEST_TIME 114 8977
TEST_TIME 115 8947
TEST_TIME 116 8913
TEST_TIME 117 8881
TEST_TIME 118 8861
TEST_TIME 119 8855
TEST_TIME 120 8834

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-12-2020 13:28:11 to 02-12-2020 13:28:14.

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 1469832 1469832 0 0.00 0 25101 6 0 0
doc_list_biud 1469831 1469831 0 0.00 0 87165 31 0 0
sp_add_invoice_to_stock 4890 4774 116 2.37 49 154750 16947 0 116
sp_cancel_adding_invoice 5460 5460 0 0.00 5 113859 842 0 0
sp_cancel_client_order 1777 1777 0 0.00 5 9793 372 0 0
sp_cancel_customer_reserve 535612 535612 0 0.00 1 87167 69 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 3674 73 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 4499 82 0 0
sp_cancel_supplier_invoice 6036 6030 6 0.09 2 24241 876 0 6
sp_cancel_supplier_order 376 376 0 0.00 11 1192440 122833 0 0
sp_cancel_write_off 9708 9708 0 0.00 1 12244 117 0 0
sp_client_order 19520 19520 0 0.00 4 88806 406 0 0
sp_customer_reserve 441450 441450 0 0.00 0 122809 164 0 0
sp_fill_shopping_cart 30096 30096 0 0.00 1 21181 48 0 0
sp_fill_shopping_cart_clo_res 441446 441446 0 0.00 0 33690 19 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 61201 481 0 0
sp_kill_cost_storno 16253 16253 0 0.00 0 8005 61 0 0
sp_kill_qstorno_ret_qs2qd 1087338 1087338 0 0.00 0 87159 37 0 0
sp_kill_qty_storno 573334 573334 0 0.00 0 87164 80 0 0
sp_lock_selected_doc 20042 20042 0 0.00 0 8434 6 0 0
sp_make_cost_storno 26806 26806 0 0.00 0 13870 49 0 0
sp_multiply_rows_for_pdistr 26806 26806 0 0.00 0 6851 31 0 0
sp_multiply_rows_for_qdistr 19520 19520 0 0.00 0 88795 248 0 0
sp_pay_from_customer 6771 6771 0 0.00 4 11111 113 0 0
sp_pay_to_supplier 5664 5664 0 0.00 5 12811 125 0 0
sp_payment_common 12435 12435 0 0.00 1 9760 22 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 9785 343 0 0
sp_qd_handle_on_invoice_upd_sts 10230 10230 0 0.00 0 26547 379 0 0
sp_qd_handle_on_reserve_upd_sts 9601 9601 0 0.00 0 8129 52 0 0
sp_reserve_write_off 9601 9601 0 0.00 7 12815 164 0 0
sp_supplier_invoice 5357 5357 0 0.00 4 105651 3688 0 0
sp_supplier_order 5237 5237 0 0.00 5 60891 1358 0 0
srv_aggregate_perf_data 108 108 0 0.00 2235 391714 17096 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1039 150769 46918 0 0
srv_find_qd_qs_mism 1014833 1014833 0 0.00 0 25101 5 0 0
srv_make_invnt_saldo 436 436 0 0.00 52 131768 3488 0 0
srv_make_money_saldo 717 717 0 0.00 1 13470 578 0 0
srv_recalc_idx_stat 4 4 0 0.00 213356 513932 377511 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 4774 4774 0 0.00 0 18639 171 0 0
t$perf-norm:sp_cancel_adding_invoice 365 365 0 0.00 0 3942 108 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 3472 13 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 5232 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 485 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 106 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 514 12 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 33165 1616 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1114 7 0 0
t$perf-norm:sp_client_order 19520 19520 0 0.00 0 6980 16 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 1889 7 0 0
t$perf-norm:sp_pay_from_customer 6771 6771 0 0.00 0 2767 6 0 0
t$perf-norm:sp_pay_to_supplier 5664 5664 0 0.00 0 9236 11 0 0
t$perf-norm:sp_reserve_write_off 9601 9601 0 0.00 0 5144 8 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 7436 16 0 0
t$perf-norm:sp_supplier_order 5237 5237 0 0.00 0 5611 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 285 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 559 14 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 748 5 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 1119 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 38 11 0 0
v_add_invoice_to_stock 9770 9538 232 2.37 0 6135 18 0 232
v_all_customers 35598 35598 0 0.00 0 95 1 0 0
v_all_suppliers 29408 29408 0 0.00 0 33 1 0 0
v_all_wares 39036 39036 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 730 730 0 0.00 0 1718 9 0 0
v_cancel_client_order 3554 3554 0 0.00 0 1390 4 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 817 6 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 916 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4490 17 0 0
v_cancel_supplier_invoice 804 792 12 1.49 0 525 14 0 12
v_cancel_supplier_order 752 752 0 0.00 0 875 6 0 0
v_cancel_write_off 2006 2006 0 0.00 0 4430 15 0 0
v_max_id_clo_ord 5232 5232 0 0.00 0 271 1 0 0
v_max_id_clo_res 930 930 0 0.00 0 5059 18 0 0
v_max_id_ord_sup 5346 5346 0 0.00 0 6939 7 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 2891 5 0 0
v_max_non_paid_realizn 3565 3565 0 0.00 0 1364 3 0 0
v_min_id_clo_ord 5232 5232 0 0.00 0 4902 13 0 0
v_min_id_clo_res 930 930 0 0.00 0 9728 32 0 0
v_min_id_ord_sup 5346 5346 0 0.00 0 11646 11 0 0
v_min_non_paid_invoice 5664 5664 0 0.00 0 9755 16 0 0
v_min_non_paid_realizn 6771 6771 0 0.00 0 7686 11 0 0
v_our_firm 9850 9850 0 0.00 0 122 1 0 0
v_reserve_write_off 19202 19202 0 0.00 0 2694 4 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 23717 116 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 24791 375 0 0
x_make_qstorno_1000_1200 5237 5237 0 0.00 2 60884 1274 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 2 105471 3507 0 0
x_make_qstorno_2100_3300 441450 441450 0 0.00 0 122808 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2020 13:28:15 to 02-12-2020 13:28:19.

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:55 7 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:56 35714 8192000000 9738580256 10038763520 808883296 8.986 100 86 172 0 836768320 7393040 20491984
10:59:39 22143 8192000000 9767554768 10075918336 841015712 9.310 100 92 184 0 876059456 13327312 21716432
11:01:07 18231 8192000000 9818764000 10139205632 877098256 9.671 100 83 166 1 927877648 32060608 18718784
11:02:30 13274 8192000000 9884865088 10220830720 929131776 10.186 100 77 154 1 982926928 37283120 16512032
11:03:45 7120 8192000000 9954265408 10302402560 973128112 10.617 100 74 149 0 1062358608 73679872 15550624
11:04:54 3649 8192000000 9983952864 10337742848 1003290224 10.910 100 88 173 2 1098692768 77736048 17666496
11:06:00 1804 8192000000 9994553792 10355679232 1028184208 11.151 100 92 184 0 1111929632 65083680 18661744
11:07:03 2713 8192000000 10023850768 10389151744 1045508944 11.318 100 92 184 3 1137384560 72663920 19211696
11:08:09 2152 8192000000 10024698384 10398158848 1062171936 11.477 100 90 180 0 1136407824 55387088 18848800
11:09:11 30346 8192000000 9982031424 10367418368 1068191120 11.535 100 60 121 1 1108105760 26339856 13574784
11:10:42 5742 8192000000 9979720112 10370764800 1073699728 11.587 100 44 90 1 1093104896 9750592 9654576
11:11:51 6799 8192000000 9983064800 10388779008 1079724784 11.645 100 29 58 1 1111244096 24970112 6549200
11:13:01 7516 8192000000 10008063760 10419240960 1085738848 11.702 100 29 57 2 1113330960 21069408 6522704
11:14:12 61656 8192000000 10013709088 10403741696 1092265280 11.764 100 55 111 2 1130322640 25877536 12179824
11:16:14 6167 8192000000 10017685616 10417283072 1090005056 11.743 100 48 94 2 1118743760 18203232 10535472
11:17:24 9297 8192000000 9973855792 10359738368 1044727040 11.310 98 35 70 3 1080616944 28493792 7396112
11:18:36 3952 8192000000 9929802528 10306404352 995928064 10.839 97 34 68 1 1013362880 10074384 7360432
11:19:41 16624 8192000000 9948877824 10331205632 1014260160 11.017 98 69 138 0 1048197392 18750688 15186544
11:21:04 7796 8192000000 9941049248 10334834688 1006850800 10.945 97 38 77 3 1038192448 22008624 9333024
11:22:13 7745 8192000000 9951205536 10340515840 1029439072 11.163 100 37 75 3 1063989920 25796560 8754288
11:23:21 12048 8192000000 9956422176 10348519424 1044888512 11.312 100 38 75 3 1069987584 15527648 9571424
11:24:33 33510 8192000000 9958344848 10361188352 1051742768 11.377 100 18 35 3 1093537072 38124384 3669920
11:26:09 8886 8192000000 9987429024 10390097920 1059304112 11.450 100 40 80 3 1091140240 22784624 9051504
11:27:22 18090 8192000000 9996614640 10391576576 1074244688 11.593 100 56 108 4 1100807520 15806688 10756144
11:28:45 75105 8192000000 10078164448 10497650688 1099907328 11.837 100 54 108 0 1187780992 76138000 11735664
11:31:07 27236 8192000000 10126812288 10527485952 1113222944 11.963 99 49 99 0 1223459168 98432240 11803984
11:32:40 29174 8192000000 10148145648 10571776000 1126304576 12.087 100 42 84 1 1278360336 142285696 9770064
11:34:12 5050 8192000000 10201213904 10602864640 1132832848 12.148 100 70 137 1 1296252736 148544544 14875344
11:35:25 2524 8192000000 10202006240 10613161984 1138950480 12.206 100 59 118 0 1310934704 158493984 13490240
11:36:33 7785 8192000000 10250617552 10669457408 1147457952 12.286 99 53 105 2 1358615536 200043488 11114096
11:37:45 3990 8192000000 10279501456 10688221184 1150867216 12.318 100 59 116 1 1385100176 220011088 14221872
11:38:55 4375 8192000000 10306064784 10718580736 1149626528 12.306 100 73 144 0 1405571936 239455424 16489984
11:40:04 3612 8192000000 10269711456 10718638080 1161621776 12.418 100 60 120 1 1392665152 217915984 13127392
11:41:11 4012 8192000000 10246284032 10637238272 1127707408 12.100 98 81 161 0 1329626576 184630112 17289056
11:42:19 3077 8192000000 10295447664 10695376896 1141858656 12.233 99 74 146 1 1401006464 243310096 15837712
11:43:27 4300 8192000000 10292875712 10692866048 1149627680 12.306 100 82 158 1 1393956576 225371440 18957456
11:44:37 1038 8192000000 10354824992 10751647744 1150913232 12.318 100 92 183 0 1451257184 279098672 21245280
11:45:41 18902 8192000000 10309621392 10727915520 1140449984 12.220 99 43 86 1 1423473936 272390896 10633056
11:47:04 14332 8192000000 10362109584 10793185280 1161327200 12.416 100 35 70 1 1454479232 284299776 8852256
11:48:30 2987 8192000000 10393502384 10800365568 1147958720 12.290 99 67 132 1 1485196480 321149440 16088320
11:49:39 8128 8192000000 10458302960 10893545472 1169265664 12.490 100 41 82 0 1551603712 371608800 10729248
11:50:57 10582 8192000000 10479516128 10908823552 1147588448 12.287 98 44 88 0 1566333344 407510928 11233968
11:52:09 14920 8192000000 10497480096 10986975232 1150217168 12.312 100 35 70 2 1591053808 431421920 9414720
11:53:26 23081 8192000000 10406314608 10899116032 1131199136 12.133 99 24 48 0 1520252096 382293056 6759904
11:54:51 9131 8192000000 10084326944 10543980544 1106110128 11.896 98 20 38 2 1232355008 122279120 3965760
11:56:12 27217 8192000000 10047427680 10477797376 1078275040 11.631 96 35 70 2 1113315664 27220224 7820400
11:57:41 150769 8192000000 10060169456 10521931776 1093952528 11.780 98 16 32 0 1114440528 17631824 2856176
12:01:12 45469 8192000000 10059948368 10508398592 1116956240 11.998 100 9 18 1 1176079056 57265168 1857648
12:02:58 22184 8192000000 10085561408 10530672640 1123441984 12.059 100 20 39 1 1186866128 59554544 3869600
12:04:24 70307 8192000000 10096023152 10581954560 1134013040 12.159 100 13 25 2 1215695904 78158688 3524176
12:07:38 64583 8192000000 10083763504 10598821888 1112883584 11.960 98 9 18 1 1217242448 102477280 1881584
12:09:46 90171 8192000000 10119729328 10616610816 1150964048 12.319 100 16 32 0 1220686736 66600448 3122240
12:12:54 47721 8192000000 10111019840 10552512512 1140649728 12.222 99 31 61 1 1208950416 61729936 6570752
12:14:44 90351 8192000000 10131064608 10596196352 1149785408 12.307 99 22 42 2 1205490384 51183440 4521536
12:17:15 81830 8192000000 10082561632 10586533888 1135198160 12.170 98 14 28 0 1226850768 88385488 3267120
12:19:39 100897 8192000000 10121036368 10586398720 1077795552 11.626 93 29 58 0 1122412288 38963568 5653168
12:22:20 95393 8192000000 10082314272 10555310080 1124556720 12.070 99 15 30 0 1200039856 72147584 3335552
12:24:56 84336 8192000000 10065501872 10517364736 1128223552 12.105 99 12 24 1 1159385904 28543504 2618848
12:27:20 32288 8192000000 10052779584 10508726272 1106774512 11.902 97 12 24 0 1149409264 40318464 2316288
12:28:58 36598 8192000000 10047766752 10529021952 1098215040 11.821 97 4 8 0 1151650720 52647600 788080
12:30:35 66385 8192000000 10097083296 10544013312 1139174544 12.208 100 16 31 1 1206169488 63394832 3600112
12:32:41 59425 8192000000 10125158192 10579058688 1118296704 12.011 98 21 42 1 1205663744 82933552 4433488
12:34:59 61019 8192000000 10077839136 10547986432 1093042512 11.772 97 37 74 0 1184649824 83782160 7825152
12:37:02 57355 8192000000 10121054912 10577625088 1138643040 12.203 100 28 56 1 1207052304 62825312 5583952
12:39:07 70092 8192000000 10112374160 10539368448 1086716416 11.711 95 22 44 0 1115809424 24514000 4579008
12:41:17 28324 8192000000 10097902800 10555228160 1122817568 12.054 99 23 45 0 1194747408 67293376 4636464
12:42:49 79632 8192000000 10043127328 10515787776 1112076048 11.952 98 29 58 1 1157271872 39094496 6101328
12:45:16 97112 8192000000 10105066144 10563641344 1105511472 11.890 98 10 20 0 1174633760 66897152 2225136
12:47:54 72196 8192000000 10086156960 10625236992 1105098256 11.886 98 10 20 0 1200775616 93431120 2246240
12:50:20 80723 8192000000 10090514816 10544721920 1101970800 11.856 97 19 38 0 1184331680 77982352 4378528
12:52:54 47680 8192000000 10107691088 10558160896 1105534400 11.890 98 25 50 0 1164912016 53934784 5442832
12:54:42 36296 8192000000 10113164000 10540535808 1123527408 12.060 99 32 64 2 1180787248 50601536 6658304
12:56:19 41040 8192000000 10086769744 10578948096 1115063616 11.980 99 18 36 1 1200918752 81852576 4002560
12:58:04 72800 8192000000 10062400960 10559553536 1094961920 11.790 98 16 32 1 1143589072 45510016 3117136
13:00:17 98321 8192000000 10053527568 10506567680 1106435504 11.899 99 15 30 1 1152190976 42689344 3066128
13:02:59 42989 8192000000 10045072480 10514169856 1102138000 11.858 99 5 10 1 1141596592 38321248 1137344
13:04:43 38939 8192000000 10060142640 10503204864 1115559072 11.985 99 22 42 2 1138815296 18552592 4703632
13:06:34 67839 8192000000 10127790112 10560270336 1144154112 12.255 100 25 48 1 1202117360 52481872 5481376
13:09:17 98108 8192000000 10123595392 10606886912 1132223088 12.142 98 20 40 1 1214895328 78702944 3969296
13:11:55 34650 8192000000 10101089248 10534555648 1113448608 11.965 97 23 46 0 1136949280 19026176 4474496
13:13:35 32224 8192000000 10131250656 10621984768 1145634992 12.269 100 16 32 0 1224921072 76269312 3016768
13:15:10 26719 8192000000 10108114912 10541256704 1140477088 12.220 99 45 88 1 1191315376 41387744 9450544
13:16:38 68739 8192000000 10101920800 10573549568 1054784224 11.407 92 11 22 0 1114067728 56877360 2406144
13:18:47 99722 8192000000 10046802384 10502406144 1120914224 12.036 100 24 48 1 1152238448 26228144 5096080
13:21:27 60983 8192000000 10028631104 10477060096 1103776800 11.873 98 12 24 0 1130318272 24087728 2453744
13:23:29 62778 8192000000 10044699344 10495963136 1053169328 11.391 94 10 20 1 1079637408 24533040 1935040
13:25:34 69043 8192000000 10039230496 10503782400 1101477696 11.852 99 12 24 0 1137957536 34171360 2308480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-12-2020 13:28:21 to 02-12-2020 13:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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 02-12-2020 13:28:37 to 02-12-2020 13:28:39.

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          234450
MON$OLDEST_ACTIVE               235195
MON$OLDEST_SNAPSHOT             235195
MON$NEXT_TRANSACTION            235195
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                       2104832
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             801
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2287 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.2287 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Dec  2 13:26:54 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Dec  2 13:26:54 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

02.12.20 13:28:40. 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-12-02 13:28:42 No severe PSQL-related problems occured
02.12.20 13:28:44. End of report.