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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16672384 kB
MemAvailable 31364980 kB
Buffers 0 kB
Cached 14943428 kB
SwapCached 4844 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-01 10:26:55
DTS_END                         2021-01-01 10:26:55.5100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49838
TRN_ID                          223266
ATT_ID                          585
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: 8678.54 25197 1041425
customer order: creation 159.95 414 19194
customer order: refuse 13.18 374 1581
order to supplier: creation 42.03 1410 5043
order to supplier: removal 3.13 135025 376
invoice (draft): creation 42.99 4019 5159
invoice (draft): removal 49.45 1085 5934
invoice accept: apply 39.05 19617 4686
invoice accept: cancel 44.51 1026 5341
customer reserve: creation 3648.13 187 437775
customer reserve: removal 4356.14 76 522737
realization accept: apply 78.50 203 9420
realization accept: cancel 83.72 119 10046
payment from customer: creation 54.85 125 6582
payment from customer: removal 5.23 90 627
payment to supplier: creation 44.73 149 5368
payment to supplier: removal 3.17 107 380
service: total inventory turnovers 3.25 4719 390
service: total monetary turnovers 5.68 670 681
service: aggregate perf. data 0.83 18058 100
service: refresh index statistics 0.04 316458 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3469
TEST_TIME 2 3955
TEST_TIME 3 3600
TEST_TIME 4 4066
TEST_TIME 5 4020
TEST_TIME 6 4130
TEST_TIME 7 4115
TEST_TIME 8 4630
TEST_TIME 9 4986
TEST_TIME 10 5319
TEST_TIME 11 5586
TEST_TIME 12 6284
TEST_TIME 13 6388
TEST_TIME 14 6597
TEST_TIME 15 6800
TEST_TIME 16 7036
TEST_TIME 17 7265
TEST_TIME 18 7457
TEST_TIME 19 7482
TEST_TIME 20 7608
TEST_TIME 21 7569
TEST_TIME 22 7865
TEST_TIME 23 8123
TEST_TIME 24 8153
TEST_TIME 25 8453
TEST_TIME 26 8420
TEST_TIME 27 8729
TEST_TIME 28 8556
TEST_TIME 29 8916
TEST_TIME 30 9054
TEST_TIME 31 9614
TEST_TIME 32 10923
TEST_TIME 33 11049
TEST_TIME 34 11140
TEST_TIME 35 11020
TEST_TIME 36 10737
TEST_TIME 37 10653
TEST_TIME 38 10462
TEST_TIME 39 10419
TEST_TIME 40 10311
TEST_TIME 41 10180
TEST_TIME 42 10026
TEST_TIME 43 9924
TEST_TIME 44 9798
TEST_TIME 45 9674
TEST_TIME 46 9528
TEST_TIME 47 9583
TEST_TIME 48 9482
TEST_TIME 49 9423
TEST_TIME 50 9286
TEST_TIME 51 9281
TEST_TIME 52 9232
TEST_TIME 53 9236
TEST_TIME 54 9270
TEST_TIME 55 9309
TEST_TIME 56 9253
TEST_TIME 57 9189
TEST_TIME 58 9126
TEST_TIME 59 9073
TEST_TIME 60 9026
TEST_TIME 61 8957
TEST_TIME 62 8868
TEST_TIME 63 8831
TEST_TIME 64 8762
TEST_TIME 65 8742
TEST_TIME 66 8746
TEST_TIME 67 8842
TEST_TIME 68 8864
TEST_TIME 69 8920
TEST_TIME 70 8898
TEST_TIME 71 8931
TEST_TIME 72 8894
TEST_TIME 73 9002
TEST_TIME 74 8949
TEST_TIME 75 9007
TEST_TIME 76 8960
TEST_TIME 77 8972
TEST_TIME 78 9029
TEST_TIME 79 8948
TEST_TIME 80 8859
TEST_TIME 81 8851
TEST_TIME 82 8811
TEST_TIME 83 8733
TEST_TIME 84 8703
TEST_TIME 85 8663
TEST_TIME 86 8720
TEST_TIME 87 8743
TEST_TIME 88 8703
TEST_TIME 89 8760
TEST_TIME 90 8776
TEST_TIME 91 8821
TEST_TIME 92 8774
TEST_TIME 93 8800
TEST_TIME 94 8954
TEST_TIME 95 8941
TEST_TIME 96 8943
TEST_TIME 97 8937
TEST_TIME 98 8917
TEST_TIME 99 8887
TEST_TIME 100 8851
TEST_TIME 101 8829
TEST_TIME 102 8793
TEST_TIME 103 8750
TEST_TIME 104 8708
TEST_TIME 105 8692
TEST_TIME 106 8688
TEST_TIME 107 8743
TEST_TIME 108 8746
TEST_TIME 109 8879
TEST_TIME 110 8843
TEST_TIME 111 8816
TEST_TIME 112 8871
TEST_TIME 113 8879
TEST_TIME 114 8875
TEST_TIME 115 8912
TEST_TIME 116 8838
TEST_TIME 117 8784
TEST_TIME 118 8762
TEST_TIME 119 8723
TEST_TIME 120 8683

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-01-2021 10:29:44 to 01-01-2021 10:29:48.

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 1444420 1444420 0 0.00 0 24343 7 0 0
doc_list_biud 1444420 1444420 0 0.00 0 38184 35 0 0
sp_add_invoice_to_stock 4773 4686 87 1.82 53 182456 19618 0 87
sp_cancel_adding_invoice 5341 5341 0 0.00 6 94069 1027 0 0
sp_cancel_client_order 1581 1581 0 0.00 4 9928 374 0 0
sp_cancel_customer_reserve 522737 522737 0 0.00 1 38191 76 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 6479 90 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 5257 108 0 0
sp_cancel_supplier_invoice 5941 5934 7 0.11 2 16743 1086 0 7
sp_cancel_supplier_order 376 376 0 0.00 7 1072462 135026 0 0
sp_cancel_write_off 10046 10046 0 0.00 1 23631 119 0 0
sp_client_order 19194 19194 0 0.00 4 22394 414 0 0
sp_customer_reserve 437775 437775 0 0.00 0 54481 188 0 0
sp_fill_shopping_cart 29380 29380 0 0.00 1 15810 51 0 0
sp_fill_shopping_cart_clo_res 437763 437763 0 0.00 0 35414 18 0 0
sp_get_clo_for_invoice 4674 4674 0 0.00 0 34669 456 0 0
sp_kill_cost_storno 16394 16394 0 0.00 0 11220 69 0 0
sp_kill_qstorno_ret_qs2qd 1061830 1061830 0 0.00 0 37745 41 0 0
sp_kill_qty_storno 560109 560109 0 0.00 0 38183 89 0 0
sp_lock_selected_doc 19314 19314 0 0.00 0 11585 7 0 0
sp_make_cost_storno 26044 26044 0 0.00 0 24329 69 0 0
sp_multiply_rows_for_pdistr 26044 26044 0 0.00 0 10615 38 0 0
sp_multiply_rows_for_qdistr 19194 19194 0 0.00 0 22379 203 0 0
sp_pay_from_customer 6582 6582 0 0.00 4 18375 126 0 0
sp_pay_to_supplier 5368 5368 0 0.00 5 17187 149 0 0
sp_payment_common 11950 11950 0 0.00 1 7590 30 0 0
sp_qd_handle_on_cancel_clo 1581 1581 0 0.00 0 9919 327 0 0
sp_qd_handle_on_invoice_upd_sts 10015 10015 0 0.00 0 21466 448 0 0
sp_qd_handle_on_reserve_upd_sts 9420 9420 0 0.00 0 24921 59 0 0
sp_reserve_write_off 9420 9420 0 0.00 6 24974 203 0 0
sp_supplier_invoice 5159 5159 0 0.00 7 71243 4019 0 0
sp_supplier_order 5043 5043 0 0.00 6 36655 1411 0 0
srv_aggregate_perf_data 100 100 0 0.00 2763 186186 18059 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1078 142155 48134 0 0
srv_find_qd_qs_mism 995800 995800 0 0.00 0 14063 6 0 0
srv_make_invnt_saldo 390 390 0 0.00 32 150068 4719 0 0
srv_make_money_saldo 681 681 0 0.00 1 15975 670 0 0
srv_recalc_idx_stat 5 5 0 0.00 118753 439449 316459 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 0 15731 197 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 1982 91 0 0
t$perf-norm:sp_cancel_client_order 1581 1581 0 0.00 0 4435 13 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 3720 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 2293 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 1062 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1675 16 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 31929 1796 0 0
t$perf-norm:sp_cancel_write_off 877 877 0 0.00 0 948 5 0 0
t$perf-norm:sp_client_order 19194 19194 0 0.00 0 11844 21 0 0
t$perf-norm:sp_customer_reserve 907 907 0 0.00 0 2825 9 0 0
t$perf-norm:sp_pay_from_customer 6582 6582 0 0.00 0 5881 13 0 0
t$perf-norm:sp_pay_to_supplier 5368 5368 0 0.00 0 6636 8 0 0
t$perf-norm:sp_reserve_write_off 9420 9420 0 0.00 0 5019 11 0 0
t$perf-norm:sp_supplier_invoice 5159 5159 0 0.00 0 8535 20 0 0
t$perf-norm:sp_supplier_order 5043 5043 0 0.00 0 7258 19 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 479 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 266 10 0 0
t$perf-norm:srv_make_invnt_saldo 390 390 0 0.00 0 420 6 0 0
t$perf-norm:srv_make_money_saldo 681 681 0 0.00 0 3419 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9516 9342 174 1.82 0 7011 19 0 174
v_all_customers 34674 34674 0 0.00 0 17 1 0 0
v_all_suppliers 27954 27954 0 0.00 0 24 1 0 0
v_all_wares 38384 38384 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 586 8 0 0
v_cancel_client_order 3162 3162 0 0.00 0 2730 5 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 6275 11 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 484 4 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1514 7 0 0
v_cancel_supplier_invoice 744 730 14 1.88 0 3673 21 0 14
v_cancel_supplier_order 752 752 0 0.00 0 5299 11 0 0
v_cancel_write_off 1754 1754 0 0.00 0 4550 16 0 0
v_max_id_clo_ord 5038 5038 0 0.00 0 2332 3 0 0
v_max_id_clo_res 907 907 0 0.00 0 4604 25 0 0
v_max_id_ord_sup 5150 5150 0 0.00 0 4956 8 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 1037 3 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 2470 3 0 0
v_min_id_clo_ord 5038 5038 0 0.00 0 2257 7 0 0
v_min_id_clo_res 907 907 0 0.00 0 4545 18 0 0
v_min_id_ord_sup 5150 5150 0 0.00 0 7830 10 0 0
v_min_non_paid_invoice 5368 5368 0 0.00 0 7322 19 0 0
v_min_non_paid_realizn 6582 6582 0 0.00 0 7586 24 0 0
v_our_firm 9824 9824 0 0.00 0 13 1 0 0
v_reserve_write_off 18838 18838 0 0.00 0 5869 6 0 0
x_lock_depdocs_on_canc_invoice 10910 10910 0 0.00 0 20422 146 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 10114 501 0 0
x_make_qstorno_1000_1200 5043 5043 0 0.00 2 36598 1319 0 0
x_make_qstorno_1200_2000 5159 5159 0 0.00 3 71238 3829 0 0
x_make_qstorno_2100_3300 437775 437775 0 0.00 0 54480 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2021 10:29:49 to 01-01-2021 10:29:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:58 9 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:59 33522 8192000000 9722135936 10017669120 796284000 8.859 100 84 168 0 824299472 7525920 20489552
07:59:42 19703 8192000000 9767801760 10077380608 842377408 9.324 100 86 172 0 875165232 12247408 20540416
08:01:07 17660 8192000000 9815097232 10132377600 874826400 9.648 100 85 168 0 921416848 26673712 19916736
08:02:33 17346 8192000000 9861622832 10193129472 917077920 10.067 100 85 170 0 975044528 39297168 18669440
08:03:55 11346 8192000000 9913910064 10256330752 957513376 10.465 100 76 150 3 1025480128 53027968 14938784
08:05:10 7013 8192000000 9946184864 10295836672 990344416 10.785 100 78 156 2 1053619040 47274752 15999872
08:06:19 3239 8192000000 10030226208 10389413888 1013626976 11.010 100 91 183 0 1143803168 111640640 18535552
08:07:25 2402 8192000000 10007407344 10374033408 1044366016 11.307 100 85 166 1 1120436352 59370096 16700240
08:08:29 1847 8192000000 10038741104 10412281856 1063116528 11.486 100 90 179 1 1153976256 72588240 18271488
08:09:32 4373 8192000000 10008980928 10405654528 1073128288 11.582 100 39 78 3 1127526528 46345216 8053024
08:10:38 23554 8192000000 9995053616 10383351808 1077664400 11.625 100 53 105 4 1113165072 22715536 12785136
08:12:02 5168 8192000000 10004205328 10396246016 1085141040 11.696 100 37 75 0 1121477856 28521792 7815024
08:13:08 5786 8192000000 10018171632 10414047232 1093182368 11.773 100 39 79 2 1132970608 31770976 8017264
08:14:14 76687 8192000000 10012792128 10431643648 1102961104 11.866 100 33 67 1 1134727456 23862656 7903696
08:16:31 4926 8192000000 10026947088 10422980608 1100022880 11.838 100 49 95 2 1130792464 19575632 11193952
08:17:37 11121 8192000000 10021475168 10434052096 1089152784 11.735 99 49 98 1 1116603600 17428432 10022384
08:18:49 65912 8192000000 9981487472 10380750848 1004169776 10.919 93 20 40 0 1037988400 29172720 4645904
08:21:02 6072 8192000000 9908059520 10282868736 997470464 10.854 96 47 92 2 1033391808 24698080 11223264
08:22:09 6298 8192000000 9929289184 10310483968 983383840 10.717 98 42 84 3 1019920912 27108576 9428496
08:23:16 46556 8192000000 9951627264 10333872128 1031850864 11.186 100 36 72 2 1053372416 13950048 7571504
08:25:04 6959 8192000000 9947716832 10339713024 1036145440 11.228 99 42 84 2 1063273264 17279888 9847936
08:26:13 6438 8192000000 9983860480 10386010112 1069286992 11.545 100 42 81 1 1091566960 13779344 8500624
08:27:23 35283 8192000000 10021834304 10415464448 1091262320 11.755 100 45 89 0 1128506080 28211040 9032720
08:29:02 82750 8192000000 10051993792 10483142656 1106126720 11.896 100 25 50 1 1165224048 53533792 5563536
08:31:48 10216 8192000000 10124388048 10524880896 1123869664 12.064 100 66 132 5 1237552480 99767248 13915568
08:33:04 10833 8192000000 10146878224 10548174848 1134966304 12.168 100 67 133 1 1256915232 107229824 14719104
08:34:18 7849 8192000000 10184891760 10595565568 1142718976 12.241 100 50 100 0 1291177936 137296592 11162368
08:35:32 2500 8192000000 10230574128 10637664256 1154979424 12.356 100 72 144 1 1337672592 167537616 15155552
08:36:39 4119 8192000000 10207920464 10613313536 1153306912 12.341 100 66 132 0 1307194048 139974672 13912464
08:37:47 3293 8192000000 10260043344 10658988032 1150037664 12.310 99 81 162 4 1358445744 190566672 17841408
08:38:55 2484 8192000000 10235602496 10644905984 1161368704 12.416 100 70 139 0 1339283760 162742544 15172512
08:40:01 2888 8192000000 10258669744 10665009152 1165206544 12.452 100 78 155 2 1361264320 179226320 16831456
08:41:09 9660 8192000000 10209261088 10625826816 1161864384 12.421 100 45 88 0 1312604496 140204256 10535856
08:42:27 10836 8192000000 10298635872 10718932992 1171139088 12.507 100 75 150 0 1393676272 205277024 17260160
08:43:47 1844 8192000000 10298296096 10701983744 1167615616 12.475 100 74 143 1 1400415904 216826672 15973616
08:44:54 3345 8192000000 10344461840 10750484480 1169112672 12.489 99 86 171 4 1438887712 249089808 20685232
08:46:05 1249 8192000000 10362254416 10761969664 1159065280 12.395 100 85 169 2 1460169024 281052976 20050768
08:47:12 1856 8192000000 10399988944 10798186496 1152918704 12.337 100 92 182 3 1492395104 317759824 21716576
08:48:18 13972 8192000000 10395170480 10803560448 1137994544 12.197 99 53 105 2 1510424080 358374992 14054544
08:49:38 1077 8192000000 10443247936 10837364736 1166590352 12.465 100 96 190 1 1532945520 344062912 22292256
08:50:44 2910 8192000000 10452723824 10851008512 1144290864 12.256 99 73 139 1 1540797248 379689680 16816704
08:51:51 11091 8192000000 10470253520 10884231168 1153684704 12.344 100 45 88 0 1575940288 410807072 11448512
08:53:23 11542 8192000000 10539505520 10979778560 1157381552 12.379 100 43 86 4 1637951888 469332320 11238016
08:54:53 4453 8192000000 10520802384 10972626944 1145922800 12.271 100 47 94 0 1635750448 477615184 12212464
08:56:08 21707 8192000000 10531382080 10983600128 1135784192 12.176 100 24 48 0 1619738080 477077616 6876272
08:57:31 16668 8192000000 10422998992 10935640064 1135174208 12.170 100 29 58 0 1548651008 405246816 8229984
08:59:23 80777 8192000000 10078468032 10551771136 1117739504 12.006 99 16 31 2 1165241760 44613968 2888288
09:02:02 55668 8192000000 10089408896 10505392128 1130414016 12.125 100 54 107 2 1186511888 45302800 10795072
09:04:06 54567 8192000000 10088751664 10540957696 1141628912 12.231 100 15 30 2 1199960848 55039008 3292928
09:06:02 75236 8192000000 10066134320 10524135424 1124035936 12.065 98 17 32 1 1148222736 20897824 3288976
09:08:53 142155 8192000000 10091359248 10539327488 1107399568 11.908 97 21 42 0 1141870848 30027840 4443440
09:12:27 86607 8192000000 10080801408 10542940160 1142856608 12.242 100 37 73 0 1223189856 72057504 8275744
09:14:57 100646 8192000000 10111897584 10562203648 1143777664 12.251 99 20 40 2 1224116832 76378704 3960464
09:17:55 39102 8192000000 10124999472 10565296128 1143368048 12.247 100 19 37 1 1229334368 81578432 4387888
09:19:56 69528 8192000000 10143957168 10622468096 1147039488 12.282 100 30 60 2 1262782496 108493040 7249968
09:22:45 66546 8192000000 10079922288 10543038464 1147377408 12.285 100 7 14 0 1183338800 34395456 1565936
09:25:09 100949 8192000000 10056343920 10545836032 1131964176 12.140 99 15 30 1 1164092848 28642400 3486272
09:28:08 38866 8192000000 10051714064 10502156288 1134183744 12.161 99 21 42 2 1160137472 21815776 4137952
09:29:48 104495 8192000000 10078761600 10549829632 1105076128 11.886 96 12 24 0 1131606624 24182752 2347744
09:32:33 118520 8192000000 10094188832 10555133952 1110556560 11.938 97 17 34 0 1217671440 103937264 3177616
09:35:32 31344 8192000000 10064215424 10497388544 1109733168 11.930 97 19 38 0 1138819952 25142848 3943936
09:37:05 19913 8192000000 10074542496 10506555392 1108244512 11.916 98 45 90 2 1167230560 49518384 9467664
09:38:26 56533 8192000000 10082144416 10549780480 1057574000 11.433 94 17 34 1 1142343312 80348624 4420688
09:40:24 26556 8192000000 10063606336 10484375552 1094398848 11.784 98 44 89 3 1140892176 37445344 9047984
09:41:59 27898 8192000000 10014826272 10439172096 1068976368 11.542 97 20 40 1 1096905472 23914848 4014256
09:43:35 80240 8192000000 10042250240 10481713152 1095394560 11.794 98 15 30 0 1148539440 49817456 3327424
09:45:56 83159 8192000000 10077254576 10515468288 1104726640 11.882 99 13 26 1 1154890112 47178064 2985408
09:48:30 74518 8192000000 10076769408 10529439744 1112392096 11.955 99 12 23 0 1138779728 23684464 2703168
09:50:45 96759 8192000000 10123639584 10581192704 1110707680 11.939 99 15 30 1 1195058080 80517920 3832480
09:53:25 89008 8192000000 10122461904 10556825600 1107606560 11.910 98 21 42 1 1204726592 92699792 4420240
09:55:55 65849 8192000000 10103416560 10563141632 1071663872 11.568 95 18 36 2 1164142464 88301056 4177536
09:58:02 60068 8192000000 10145809696 10579509248 1104789872 11.883 97 18 36 1 1197589776 88717584 4082320
10:00:03 81753 8192000000 10066934400 10499604480 1126312336 12.087 100 11 22 0 1184705504 56490992 1902176
10:02:25 35800 8192000000 10117976128 10569060352 1139058032 12.207 100 27 54 1 1197820576 53297440 5465104
10:04:06 106973 8192000000 10084815696 10562482176 1142279888 12.237 100 38 76 0 1231340624 81067840 7992896
10:06:56 59092 8192000000 10073992512 10540482560 1119847616 12.026 98 6 12 1 1139485232 18524624 1112992
10:09:08 54255 8192000000 10100253840 10536005632 1108977104 11.923 97 20 41 0 1171867744 58239920 4650720
10:11:02 57373 8192000000 10136439216 10569932800 1072452016 11.575 94 22 44 3 1171417600 93976544 4989040
10:13:06 80077 8192000000 10167160256 10609307648 1133733104 12.157 100 25 50 1 1214459168 75355200 5370864
10:15:26 95069 8192000000 10043185408 10481524736 1100163424 11.839 97 7 14 1 1161669232 59861104 1644704
10:18:08 35428 8192000000 10116156624 10571350016 1140053760 12.216 100 28 56 0 1232074960 86155056 5866144
10:19:48 70992 8192000000 10118953056 10570190848 1110676544 11.939 97 16 32 0 1153307584 39442720 3188320
10:22:18 100426 8192000000 10073594800 10506305536 1124403824 12.069 99 31 62 0 1158674448 27420240 6850384
10:25:23 87051 8192000000 10081979920 10536779776 1121566112 12.042 98 11 20 1 1157837568 33769776 2501680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2021 10:29:54 to 01-01-2021 10:30:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          220860
MON$OLDEST_ACTIVE               223381
MON$OLDEST_SNAPSHOT             223381
MON$NEXT_TRANSACTION            223381
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                       2107712
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             774
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Jan  1 10:27:39 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

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