Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2020 13:29
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16984996 kB
MemAvailable 31620004 kB
Buffers 0 kB
Cached 14711996 kB
SwapCached 5428 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-10-15 13:26:48
DTS_END                         2020-10-15 13:26:48.1320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/36896
TRN_ID                          237909
ATT_ID                          660
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: 8824.07 20545 1058888
customer order: creation 175.50 358 21060
customer order: refuse 15.10 337 1812
order to supplier: creation 46.80 1372 5616
order to supplier: removal 3.38 112130 406
invoice (draft): creation 46.42 3589 5570
invoice (draft): removal 47.84 1011 5741
invoice accept: apply 42.61 18769 5113
invoice accept: cancel 42.97 899 5156
customer reserve: creation 3853.55 187 462426
customer reserve: removal 4259.98 70 511198
realization accept: apply 83.99 172 10079
realization accept: cancel 75.08 129 9010
payment from customer: creation 60.68 110 7281
payment from customer: removal 6.05 100 726
payment to supplier: creation 50.08 124 6009
payment to supplier: removal 3.54 106 425
service: total inventory turnovers 3.74 3433 449
service: total monetary turnovers 6.00 629 720
service: aggregate perf. data 0.72 24795 86
service: refresh index statistics 0.04 242574 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2020 13:29:26 to 15-10-2020 13:29:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4368
TEST_TIME 2 5383
TEST_TIME 3 5784
TEST_TIME 4 5278
TEST_TIME 5 5575
TEST_TIME 6 5513
TEST_TIME 7 5681
TEST_TIME 8 5632
TEST_TIME 9 5835
TEST_TIME 10 6173
TEST_TIME 11 6393
TEST_TIME 12 7000
TEST_TIME 13 7131
TEST_TIME 14 7315
TEST_TIME 15 7638
TEST_TIME 16 7947
TEST_TIME 17 8062
TEST_TIME 18 8321
TEST_TIME 19 8264
TEST_TIME 20 8509
TEST_TIME 21 8537
TEST_TIME 22 8366
TEST_TIME 23 8691
TEST_TIME 24 9187
TEST_TIME 25 9715
TEST_TIME 26 9699
TEST_TIME 27 9992
TEST_TIME 28 10598
TEST_TIME 29 11153
TEST_TIME 30 11139
TEST_TIME 31 10990
TEST_TIME 32 10942
TEST_TIME 33 10696
TEST_TIME 34 10833
TEST_TIME 35 10614
TEST_TIME 36 10326
TEST_TIME 37 10109
TEST_TIME 38 10099
TEST_TIME 39 9976
TEST_TIME 40 10000
TEST_TIME 41 9915
TEST_TIME 42 9869
TEST_TIME 43 9923
TEST_TIME 44 9834
TEST_TIME 45 9829
TEST_TIME 46 9758
TEST_TIME 47 9661
TEST_TIME 48 9559
TEST_TIME 49 9501
TEST_TIME 50 9397
TEST_TIME 51 9317
TEST_TIME 52 9318
TEST_TIME 53 9356
TEST_TIME 54 9312
TEST_TIME 55 9323
TEST_TIME 56 9270
TEST_TIME 57 9253
TEST_TIME 58 9405
TEST_TIME 59 9368
TEST_TIME 60 9429
TEST_TIME 61 9432
TEST_TIME 62 9454
TEST_TIME 63 9403
TEST_TIME 64 9311
TEST_TIME 65 9305
TEST_TIME 66 9213
TEST_TIME 67 9152
TEST_TIME 68 9111
TEST_TIME 69 9088
TEST_TIME 70 9114
TEST_TIME 71 9049
TEST_TIME 72 9015
TEST_TIME 73 9068
TEST_TIME 74 9114
TEST_TIME 75 9095
TEST_TIME 76 9216
TEST_TIME 77 9166
TEST_TIME 78 9196
TEST_TIME 79 9306
TEST_TIME 80 9284
TEST_TIME 81 9239
TEST_TIME 82 9135
TEST_TIME 83 9127
TEST_TIME 84 9106
TEST_TIME 85 9093
TEST_TIME 86 9042
TEST_TIME 87 9038
TEST_TIME 88 8996
TEST_TIME 89 8980
TEST_TIME 90 8959
TEST_TIME 91 8944
TEST_TIME 92 8948
TEST_TIME 93 8959
TEST_TIME 94 9173
TEST_TIME 95 9139
TEST_TIME 96 9208
TEST_TIME 97 9253
TEST_TIME 98 9264
TEST_TIME 99 9206
TEST_TIME 100 9142
TEST_TIME 101 9083
TEST_TIME 102 9066
TEST_TIME 103 9008
TEST_TIME 104 8954
TEST_TIME 105 8933
TEST_TIME 106 8969
TEST_TIME 107 8943
TEST_TIME 108 8933
TEST_TIME 109 8907
TEST_TIME 110 8901
TEST_TIME 111 8961
TEST_TIME 112 8921
TEST_TIME 113 8901
TEST_TIME 114 8977
TEST_TIME 115 8974
TEST_TIME 116 8956
TEST_TIME 117 8937
TEST_TIME 118 8908
TEST_TIME 119 8878
TEST_TIME 120 8830

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2020 13:29:30 to 15-10-2020 13:29:33.

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 1488369 1488369 0 0.00 0 16220 7 0 0
doc_list_biud 1488365 1488365 0 0.00 0 30683 31 0 0
sp_add_invoice_to_stock 5232 5113 119 2.27 36 271539 18769 0 119
sp_cancel_adding_invoice 5156 5156 0 0.00 5 64045 899 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 10794 337 0 0
sp_cancel_customer_reserve 511198 511198 0 0.00 1 30687 70 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 3464 100 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 3006 107 0 0
sp_cancel_supplier_invoice 5750 5741 9 0.15 2 18195 1012 0 9
sp_cancel_supplier_order 406 406 0 0.00 6 1013445 112131 0 0
sp_cancel_write_off 9010 9010 0 0.00 1 12565 129 0 0
sp_client_order 21060 21060 0 0.00 4 23241 359 0 0
sp_customer_reserve 462426 462426 0 0.00 0 154254 187 0 0
sp_fill_shopping_cart 32237 32237 0 0.00 1 23534 46 0 0
sp_fill_shopping_cart_clo_res 462412 462412 0 0.00 0 21639 18 0 0
sp_get_clo_for_invoice 5098 5098 0 0.00 0 42167 371 0 0
sp_kill_cost_storno 15317 15317 0 0.00 0 5121 60 0 0
sp_kill_qstorno_ret_qs2qd 1037553 1037553 0 0.00 0 30668 38 0 0
sp_kill_qty_storno 548500 548500 0 0.00 0 30683 82 0 0
sp_lock_selected_doc 21096 21096 0 0.00 0 11858 6 0 0
sp_make_cost_storno 28467 28467 0 0.00 0 14488 51 0 0
sp_multiply_rows_for_pdistr 28467 28467 0 0.00 0 9032 32 0 0
sp_multiply_rows_for_qdistr 21060 21060 0 0.00 0 18948 189 0 0
sp_pay_from_customer 7281 7281 0 0.00 4 9237 111 0 0
sp_pay_to_supplier 6009 6009 0 0.00 5 11687 125 0 0
sp_payment_common 13290 13290 0 0.00 1 8834 21 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10784 315 0 0
sp_qd_handle_on_invoice_upd_sts 10254 10254 0 0.00 0 25585 385 0 0
sp_qd_handle_on_reserve_upd_sts 10079 10079 0 0.00 0 19638 46 0 0
sp_reserve_write_off 10079 10079 0 0.00 6 19650 172 0 0
sp_supplier_invoice 5570 5570 0 0.00 5 137797 3590 0 0
sp_supplier_order 5616 5616 0 0.00 6 25114 1373 0 0
srv_aggregate_perf_data 86 86 0 0.00 2448 553536 24796 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1010 177555 45743 0 0
srv_find_qd_qs_mism 1011221 1011221 0 0.00 0 12533 6 0 0
srv_make_invnt_saldo 449 449 0 0.00 48 91359 3434 0 0
srv_make_money_saldo 720 720 0 0.00 1 16888 630 0 0
srv_recalc_idx_stat 5 5 0 0.00 146214 367201 242574 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5114 5114 0 0.00 0 29759 231 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 5584 111 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 6133 20 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 942 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 793 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 175 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1058 15 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 20932 1507 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1785 10 0 0
t$perf-norm:sp_client_order 21060 21060 0 0.00 0 26768 21 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1997 8 0 0
t$perf-norm:sp_pay_from_customer 7281 7281 0 0.00 0 15744 16 0 0
t$perf-norm:sp_pay_to_supplier 6009 6009 0 0.00 0 14713 14 0 0
t$perf-norm:sp_reserve_write_off 10079 10079 0 0.00 0 11544 8 0 0
t$perf-norm:sp_supplier_invoice 5570 5570 0 0.00 0 6467 20 0 0
t$perf-norm:sp_supplier_order 5616 5616 0 0.00 0 15337 21 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 82 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 207 7 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 4071 13 0 0
t$perf-norm:srv_make_money_saldo 720 720 0 0.00 0 4384 22 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 6 0 0
v_add_invoice_to_stock 10432 10194 238 2.28 0 5900 18 0 238
v_all_customers 38070 38070 0 0.00 0 148 1 0 0
v_all_suppliers 30894 30894 0 0.00 0 24 1 0 0
v_all_wares 42120 42120 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1271 10 0 0
v_cancel_client_order 3624 3624 0 0.00 0 3219 4 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 3412 15 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2832 6 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2419 14 0 0
v_cancel_supplier_invoice 852 834 18 2.11 0 1452 18 0 18
v_cancel_supplier_order 812 812 0 0.00 0 2640 8 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1370 14 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 550 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 3800 13 0 0
v_max_id_ord_sup 5558 5558 0 0.00 0 5906 5 0 0
v_max_non_paid_invoice 1736 1736 0 0.00 0 3754 4 0 0
v_max_non_paid_realizn 4036 4036 0 0.00 0 2591 4 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 1745 5 0 0
v_min_id_clo_res 994 994 0 0.00 0 8211 18 0 0
v_min_id_ord_sup 5558 5558 0 0.00 0 6443 9 0 0
v_min_non_paid_invoice 6009 6009 0 0.00 0 6838 14 0 0
v_min_non_paid_realizn 7281 7281 0 0.00 0 4912 12 0 0
v_our_firm 10540 10540 0 0.00 0 14 1 0 0
v_reserve_write_off 20158 20158 0 0.00 0 3510 7 0 0
x_lock_depdocs_on_canc_invoice 10480 10480 0 0.00 0 17724 120 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 8115 302 0 0
x_make_qstorno_1000_1200 5616 5616 0 0.00 2 25098 1293 0 0
x_make_qstorno_1200_2000 5570 5570 0 0.00 2 137478 3403 0 0
x_make_qstorno_2100_3300 462426 462426 0 0.00 0 154252 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2020 13:29:34 to 15-10-2020 13:29:38.

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:51 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:52 25003 8192000000 9727420464 10024046592 802539184 8.922 100 89 178 0 831858688 7711072 21608432
10:59:20 17878 8192000000 9767347824 10075668480 842210976 9.322 100 93 186 0 876216128 11842512 22162640
11:00:46 17816 8192000000 9803476912 10121277440 871157424 9.612 100 94 187 1 912381456 19589456 21634576
11:02:09 12864 8192000000 9850991312 10180894720 909519184 9.993 100 81 163 2 963527744 35730272 18278288
11:03:26 7953 8192000000 9932160288 10277117952 954947344 10.440 100 84 168 0 1036406160 63842224 17616592
11:04:36 6031 8192000000 9962328096 10314022912 992274848 10.804 100 87 174 1 1075307744 65192800 17840096
11:05:45 4095 8192000000 10003571680 10368372736 1020669216 11.078 100 88 178 4 1121891088 83571664 17650208
11:06:51 1575 8192000000 10008592512 10377826304 1047752480 11.339 100 90 181 2 1121097344 53771984 19572880
11:07:54 2451 8192000000 10026357920 10407231488 1063803376 11.493 100 67 131 1 1140436560 63287888 13345296
11:09:00 8405 8192000000 9993818160 10382688256 1071798000 11.569 100 35 70 2 1129911824 50925088 7188736
11:10:09 6791 8192000000 9984183760 10383200256 1075664432 11.606 100 36 72 2 1101227824 17222128 8341264
11:11:18 5890 8192000000 9997203072 10396291072 1082719104 11.673 100 42 84 3 1116946192 25486816 8740272
11:12:29 35897 8192000000 9995292192 10392477696 1082497216 11.671 100 36 74 1 1112142192 21783904 7861072
11:14:06 5195 8192000000 10001806592 10405072896 1087660336 11.720 100 41 82 1 1121809760 25236416 8913008
11:15:16 9417 8192000000 10007538384 10419146752 1095112240 11.791 100 23 46 1 1118949792 19161888 4675664
11:16:26 10301 8192000000 10006098976 10417569792 1082602000 11.672 99 19 38 2 1105474960 18771968 4100992
11:17:38 30572 8192000000 10009794032 10397016064 1071797968 11.569 99 62 124 2 1104927312 19842336 13287008
11:19:09 4014 8192000000 9992293168 10386505728 1067156416 11.525 100 27 51 2 1105590464 33271056 5162992
11:20:13 8510 8192000000 9925646624 10318123008 964539040 10.533 94 30 60 3 991362160 19767344 7055776
11:21:23 72371 8192000000 9941555200 10340257792 1008439168 10.960 98 37 73 2 1025027600 8785648 7802784
11:23:37 3007 8192000000 9966269808 10347442176 1044289856 11.306 100 58 114 1 1073349648 14829056 14230736
11:24:40 3683 8192000000 9982326880 10365288448 1060573840 11.462 100 52 102 3 1087334208 14952560 11807808
11:25:47 13210 8192000000 9984741488 10382483456 1071087904 11.562 100 33 66 2 1105127152 26564016 7475232
11:27:01 21580 8192000000 10008879168 10406203392 1094103024 11.782 100 40 80 2 1117723728 14454192 9166512
11:28:28 7395 8192000000 10071571728 10468671488 1107536016 11.909 100 63 126 0 1177475408 56971600 12967792
11:29:39 4366 8192000000 10083536976 10481152000 1121416960 12.040 100 56 111 0 1195202208 62204064 11581184
11:30:47 29207 8192000000 10100468112 10507972608 1141506000 12.230 100 61 120 1 1204535648 50699344 12330304
11:32:21 8022 8192000000 10158188224 10562523136 1155026064 12.357 100 66 127 2 1266463440 97761008 13676368
11:33:35 18798 8192000000 10188181088 10601484288 1162370864 12.425 100 33 66 0 1294022592 123996144 7655584
11:34:58 3908 8192000000 10234269296 10640031744 1167949664 12.478 100 61 120 1 1335442016 154501616 12990736
11:36:12 4441 8192000000 10251661616 10656645120 1169204800 12.489 100 73 146 2 1352275984 167076720 15994464
11:37:19 4936 8192000000 10247549328 10650869760 1166639760 12.465 100 69 138 1 1349972016 168204880 15127376
11:38:29 2255 8192000000 10263244208 10681888768 1163032032 12.432 99 61 121 2 1357218000 180384032 13801936
11:39:35 8663 8192000000 10257613984 10666164224 1151907920 12.327 99 55 109 3 1346757648 183148128 11701600
11:40:57 1045 8192000000 10261252224 10655928320 1156215296 12.368 100 85 170 3 1359219664 182890160 20114208
11:42:00 1007 8192000000 10297403056 10692308992 1164866896 12.449 100 99 198 1 1395701680 208871808 21962976
11:43:03 2644 8192000000 10271518960 10682871808 1141379888 12.229 99 80 159 0 1362146112 203053952 17712272
11:44:12 2974 8192000000 10302143312 10696736768 1139510288 12.211 99 78 155 0 1401001632 243775728 17715616
11:45:20 1264 8192000000 10336377392 10725949440 1145359936 12.266 100 84 168 0 1428308896 264256432 18692528
11:46:26 1953 8192000000 10374804000 10774773760 1165703264 12.457 100 77 150 0 1473939920 290808640 17428016
11:47:31 11867 8192000000 10384456384 10795876352 1153073984 12.338 100 43 86 1 1483239088 319148352 11016752
11:48:51 1609 8192000000 10470763104 10872807424 1152301344 12.331 100 87 174 0 1566699904 393255888 21142672
11:49:55 6467 8192000000 10453443648 10888548352 1146272992 12.274 100 47 94 2 1560527440 401214528 13039920
11:51:08 5296 8192000000 10361794208 10830258176 1155494720 12.361 100 47 93 0 1485384992 317857744 12032528
11:52:55 16044 8192000000 10294797248 10775609344 1122574864 12.051 99 17 34 0 1396468064 269168496 4724704
11:54:16 33767 8192000000 10168757904 10735616000 1125866352 12.082 100 14 28 1 1363253040 233429056 3957632
11:55:51 72139 8192000000 10051850160 10527752192 1098095360 11.820 98 7 14 2 1136410528 36268976 2046192
11:58:05 122420 8192000000 10103345024 10519732224 1081068960 11.658 96 20 39 0 1120132400 35233232 3830208
12:01:11 108777 8192000000 10055921792 10484510720 1098853072 11.827 98 41 82 0 1134866816 27709072 8304672
12:04:54 31678 8192000000 10072929840 10496376832 1103508480 11.871 98 49 98 0 1152809072 38274640 11025952
12:06:33 177554 8192000000 10080517120 10543046656 1123642240 12.061 99 19 38 0 1181629280 53864880 4122160
12:10:37 72074 8192000000 10061682880 10524643328 1117058144 11.999 98 19 38 0 1167416000 46017408 4340448
12:12:52 93593 8192000000 10039942896 10491637760 1123785184 12.063 99 15 30 1 1151817504 25048064 2984256
12:15:26 47500 8192000000 10045950384 10501619712 1112984128 11.961 98 18 35 1 1133221792 16626544 3611120
12:17:16 82047 8192000000 10112573792 10572673024 1108343616 11.917 98 14 27 3 1161884784 50264176 3276992
12:20:03 38839 8192000000 10036628560 10470957056 1115538960 11.985 99 21 42 5 1135952704 16319200 4094544
12:21:43 24401 8192000000 10051766272 10480910336 1114176592 11.972 99 20 40 0 1151917856 34071088 3670176
12:23:16 65636 8192000000 10074081376 10588262400 1113777584 11.968 99 19 38 0 1234964800 117422560 3764656
12:25:27 65711 8192000000 10134108496 10560139264 1127611616 12.099 100 33 66 0 1214472880 80135568 6725696
12:27:36 46488 8192000000 10058779488 10515197952 1113216224 11.963 100 18 36 1 1168170944 50475936 4478784
12:29:26 22408 8192000000 10065889344 10488406016 1103467456 11.871 99 49 98 0 1149208160 36125248 9615456
12:31:33 70377 8192000000 10025738432 10487410688 1106619344 11.900 99 16 30 2 1136200816 26612176 2969296
12:34:54 77226 8192000000 10067974496 10521694208 1077763840 11.626 96 22 44 1 1137032960 54295232 4973888
12:37:20 62269 8192000000 10087280496 10513944576 1071769424 11.569 95 32 62 1 1144438704 66109744 6559536
12:39:23 58434 8192000000 10113917888 10545266688 1117164080 12.000 100 14 28 0 1210006688 89701808 3140800
12:41:24 73981 8192000000 10098935776 10575790080 1105635808 11.891 98 19 38 0 1174361584 64878736 3847040
12:43:41 22771 8192000000 10106141536 10522550272 1102522736 11.862 98 45 90 1 1180673152 69537120 8613296
12:45:07 59277 8192000000 10080668528 10554269696 1096113680 11.801 98 12 24 0 1167686160 69300736 2271744
12:47:49 103468 8192000000 10062074112 10529972224 1110428064 11.936 99 15 30 1 1185746048 71814736 3503248
12:50:39 54989 8192000000 10081593328 10548846592 1124247632 12.067 100 14 28 0 1179144576 51745296 3151648
12:53:25 50695 8192000000 10047089776 10484101120 1095097744 11.791 97 23 46 0 1119896912 20418416 4380752
12:55:19 65460 8192000000 10112781792 10555121664 1113126272 11.962 99 23 46 1 1189184896 70350976 5707648
12:57:31 58455 8192000000 10170589648 10603094016 1120919984 12.036 99 21 41 2 1235273200 109588528 4764688
12:59:47 70772 8192000000 10118463664 10679308288 1130735936 12.128 100 12 24 1 1239684224 106567216 2381072
13:01:58 49177 8192000000 10133378720 10622304256 1128138816 12.104 99 24 48 0 1188201040 55617568 4444656
13:03:58 166977 8192000000 10067720432 10526359552 1080985440 11.657 95 21 42 0 1104699200 19208016 4505744
13:07:58 61484 8192000000 10073293296 10552434688 1125446128 12.078 99 13 26 1 1164541040 36465152 2629760
13:10:03 38285 8192000000 10071129584 10553958400 1125144352 12.076 99 6 12 0 1170860688 44483264 1233072
13:12:03 93362 8192000000 10069047472 10536615936 1102789216 11.864 97 12 23 1 1151043136 45145408 3108512
13:15:27 64365 8192000000 10102782480 10542133248 1127390352 12.097 98 22 44 1 1187991760 56029200 4572208
13:17:43 69998 8192000000 10062249712 10504876032 1039029744 11.255 92 14 27 0 1092394240 50465680 2898816
13:20:00 59058 8192000000 10061402096 10527350784 1082885888 11.675 97 13 26 3 1160539840 75005536 2648416
13:22:16 24814 8192000000 10076112848 10500210688 1074222032 11.592 96 39 78 1 1118231200 36753920 7255248
13:23:43 24085 8192000000 10112660672 10537684992 1117460624 12.003 100 54 108 0 1206337344 77177200 11699520
13:25:08 85455 8192000000 10035352016 10487586816 1097390224 11.813 98 22 44 3 1143752144 41721696 4640224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-10-2020 13:29:40 to 15-10-2020 13:29:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 119 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2020 13:29:55 to 15-10-2020 13:29:57.

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          237404
MON$OLDEST_ACTIVE               238060
MON$OLDEST_SNAPSHOT             238060
MON$NEXT_TRANSACTION            238060
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                       2099968
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             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 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.2226 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Oct 15 13:27:31 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Oct 15 13:27:31 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

15.10.20 13:29:58. 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-10-15 13:30:01 No severe PSQL-related problems occured
15.10.20 13:30:02. End of report.