Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.08.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15997604 kB
MemAvailable 30913992 kB
Buffers 0 kB
Cached 15654248 kB
SwapCached 1320 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.1
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-08-27 10:37:07
DTS_END                         2021-08-27 10:37:07.6200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52852
TRN_ID                          235025
ATT_ID                          621
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: 9372.14 17892 1124657
customer order: creation 174.90 409 20988
customer order: refuse 15.28 341 1833
order to supplier: creation 45.50 1499 5460
order to supplier: removal 3.37 127685 404
invoice (draft): creation 44.94 3591 5393
invoice (draft): removal 55.12 1121 6614
invoice accept: apply 40.98 19660 4917
invoice accept: cancel 50.24 828 6029
customer reserve: creation 3756.08 196 450730
customer reserve: removal 4880.73 66 585687
realization accept: apply 82.74 195 9929
realization accept: cancel 93.78 127 11253
payment from customer: creation 58.70 131 7044
payment from customer: removal 5.72 103 686
payment to supplier: creation 50.03 137 6003
payment to supplier: removal 3.74 97 449
service: total inventory turnovers 3.40 4211 408
service: total monetary turnovers 5.95 518 714
service: aggregate perf. data 0.93 16785 112
service: refresh index statistics 0.03 180144 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-08-2021 10:37:56 to 27-08-2021 10:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3916
TEST_TIME 2 4102
TEST_TIME 3 3968
TEST_TIME 4 5526
TEST_TIME 5 6253
TEST_TIME 6 6610
TEST_TIME 7 6310
TEST_TIME 8 6155
TEST_TIME 9 6287
TEST_TIME 10 6383
TEST_TIME 11 6457
TEST_TIME 12 6682
TEST_TIME 13 6864
TEST_TIME 14 6920
TEST_TIME 15 7004
TEST_TIME 16 7108
TEST_TIME 17 7230
TEST_TIME 18 7805
TEST_TIME 19 7743
TEST_TIME 20 7875
TEST_TIME 21 8049
TEST_TIME 22 8175
TEST_TIME 23 8069
TEST_TIME 24 8279
TEST_TIME 25 9097
TEST_TIME 26 10113
TEST_TIME 27 11889
TEST_TIME 28 11862
TEST_TIME 29 11835
TEST_TIME 30 11610
TEST_TIME 31 11619
TEST_TIME 32 11705
TEST_TIME 33 11436
TEST_TIME 34 11169
TEST_TIME 35 10962
TEST_TIME 36 10869
TEST_TIME 37 10750
TEST_TIME 38 10752
TEST_TIME 39 10671
TEST_TIME 40 10574
TEST_TIME 41 10673
TEST_TIME 42 10624
TEST_TIME 43 10505
TEST_TIME 44 10353
TEST_TIME 45 10258
TEST_TIME 46 10232
TEST_TIME 47 10290
TEST_TIME 48 10173
TEST_TIME 49 10085
TEST_TIME 50 9942
TEST_TIME 51 9876
TEST_TIME 52 9827
TEST_TIME 53 9859
TEST_TIME 54 9775
TEST_TIME 55 9764
TEST_TIME 56 9948
TEST_TIME 57 9895
TEST_TIME 58 9901
TEST_TIME 59 9891
TEST_TIME 60 9876
TEST_TIME 61 9858
TEST_TIME 62 10059
TEST_TIME 63 10087
TEST_TIME 64 10079
TEST_TIME 65 9955
TEST_TIME 66 9885
TEST_TIME 67 9941
TEST_TIME 68 9837
TEST_TIME 69 9796
TEST_TIME 70 9692
TEST_TIME 71 9718
TEST_TIME 72 9689
TEST_TIME 73 9591
TEST_TIME 74 9626
TEST_TIME 75 9649
TEST_TIME 76 9625
TEST_TIME 77 9615
TEST_TIME 78 9630
TEST_TIME 79 9676
TEST_TIME 80 9647
TEST_TIME 81 9738
TEST_TIME 82 9693
TEST_TIME 83 9742
TEST_TIME 84 9848
TEST_TIME 85 9784
TEST_TIME 86 9711
TEST_TIME 87 9631
TEST_TIME 88 9620
TEST_TIME 89 9577
TEST_TIME 90 9544
TEST_TIME 91 9497
TEST_TIME 92 9512
TEST_TIME 93 9520
TEST_TIME 94 9489
TEST_TIME 95 9456
TEST_TIME 96 9585
TEST_TIME 97 9548
TEST_TIME 98 9576
TEST_TIME 99 9580
TEST_TIME 100 9611
TEST_TIME 101 9634
TEST_TIME 102 9590
TEST_TIME 103 9675
TEST_TIME 104 9659
TEST_TIME 105 9590
TEST_TIME 106 9544
TEST_TIME 107 9526
TEST_TIME 108 9494
TEST_TIME 109 9463
TEST_TIME 110 9443
TEST_TIME 111 9464
TEST_TIME 112 9411
TEST_TIME 113 9378
TEST_TIME 114 9425
TEST_TIME 115 9422
TEST_TIME 116 9449
TEST_TIME 117 9444
TEST_TIME 118 9436
TEST_TIME 119 9447
TEST_TIME 120 9374

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-08-2021 10:37:59 to 27-08-2021 10:38:03.

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 1542071 1542071 0 0.00 0 24010 7 0 0
doc_list_biud 1542070 1542070 0 0.00 0 53185 33 0 0
sp_add_invoice_to_stock 5050 4917 133 2.63 46 166748 19660 0 133
sp_cancel_adding_invoice 6029 6029 0 0.00 5 65148 828 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 16900 341 0 0
sp_cancel_customer_reserve 585687 585687 0 0.00 1 53187 67 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 9290 104 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 7637 98 0 0
sp_cancel_supplier_invoice 6628 6614 14 0.21 3 24106 1122 0 14
sp_cancel_supplier_order 404 404 0 0.00 7 1145359 127685 0 0
sp_cancel_write_off 11253 11253 0 0.00 1 24011 127 0 0
sp_client_order 20988 20988 0 0.00 4 29819 409 0 0
sp_customer_reserve 450730 450730 0 0.00 0 121762 196 0 0
sp_fill_shopping_cart 31837 31837 0 0.00 1 17554 45 0 0
sp_fill_shopping_cart_clo_res 450726 450726 0 0.00 0 19616 16 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 26820 381 0 0
sp_kill_cost_storno 18417 18417 0 0.00 0 24008 64 0 0
sp_kill_qstorno_ret_qs2qd 1189645 1189645 0 0.00 0 53182 37 0 0
sp_kill_qty_storno 626661 626661 0 0.00 0 53185 79 0 0
sp_lock_selected_doc 20685 20685 0 0.00 0 9293 7 0 0
sp_make_cost_storno 27889 27889 0 0.00 0 16754 58 0 0
sp_multiply_rows_for_pdistr 27888 27888 0 0.00 0 21020 33 0 0
sp_multiply_rows_for_qdistr 20988 20988 0 0.00 0 12772 193 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 21028 132 0 0
sp_pay_to_supplier 6003 6003 0 0.00 5 15439 138 0 0
sp_payment_common 13047 13047 0 0.00 1 11840 25 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 16893 301 0 0
sp_qd_handle_on_invoice_upd_sts 10942 10942 0 0.00 0 25890 382 0 0
sp_qd_handle_on_reserve_upd_sts 9928 9928 0 0.00 0 14075 61 0 0
sp_reserve_write_off 9929 9929 0 0.00 7 14267 196 0 0
sp_supplier_invoice 5393 5393 0 0.00 6 87413 3592 0 0
sp_supplier_order 5460 5460 0 0.00 6 93170 1499 0 0
srv_aggregate_perf_data 112 112 0 0.00 2981 275866 16785 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1313 135720 44039 0 0
srv_find_qd_qs_mism 1077277 1077277 0 0.00 0 18527 6 0 0
srv_make_invnt_saldo 408 408 0 0.00 66 104040 4211 0 0
srv_make_money_saldo 714 714 0 0.00 1 17199 519 0 0
srv_recalc_idx_stat 4 4 0 0.00 136012 221905 180145 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 0 11879 153 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 0 2466 86 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 2244 10 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1946 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 6258 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 2265 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 972 17 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 20252 1666 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 8972 21 0 0
t$perf-norm:sp_client_order 20988 20988 0 0.00 0 13761 17 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 1262 6 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 3385 7 0 0
t$perf-norm:sp_pay_to_supplier 6003 6003 0 0.00 0 4640 7 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 7706 13 0 0
t$perf-norm:sp_supplier_invoice 5393 5393 0 0.00 0 5335 17 0 0
t$perf-norm:sp_supplier_order 5460 5460 0 0.00 0 3708 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 157 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6928 111 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 1685 12 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 8061 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10090 9824 266 2.63 0 5935 16 0 266
v_all_customers 38372 38372 0 0.00 0 22 1 0 0
v_all_suppliers 30284 30284 0 0.00 0 33 1 0 0
v_all_wares 41976 41976 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 70 5 0 0
v_cancel_client_order 3666 3666 0 0.00 0 2871 7 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1975 7 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 598 5 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 5758 16 0 0
v_cancel_supplier_invoice 824 796 28 3.39 0 5120 21 0 28
v_cancel_supplier_order 808 808 0 0.00 0 3343 9 0 0
v_cancel_write_off 2008 2008 0 0.00 0 3770 18 0 0
v_max_id_clo_ord 5459 5459 0 0.00 0 816 2 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 4386 17 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 5273 7 0 0
v_max_non_paid_invoice 1710 1710 0 0.00 0 688 3 0 0
v_max_non_paid_realizn 3684 3684 0 0.00 0 714 3 0 0
v_min_id_clo_ord 5459 5459 0 0.00 0 5932 10 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 1463 8 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 4447 6 0 0
v_min_non_paid_invoice 6003 6003 0 0.00 0 6456 12 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 7008 17 0 0
v_our_firm 10324 10324 0 0.00 0 24 1 0 0
v_reserve_write_off 19856 19856 0 0.00 0 3944 7 0 0
x_lock_depdocs_on_canc_invoice 12245 12245 0 0.00 0 16339 113 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 6309 326 0 0
x_make_qstorno_1000_1200 5460 5460 0 0.00 2 93164 1410 0 0
x_make_qstorno_1200_2000 5393 5393 0 0.00 3 87362 3424 0 0
x_make_qstorno_2100_3300 450730 450730 0 0.00 0 121758 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 10:38:04 to 27-08-2021 10:38:08.

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
08:07:11 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 23500 8192000000 9712160048 10006351872 790303408 8.798 100 89 178 0 819773344 7658432 21811504
08:09:43 9591 8192000000 9746734224 10049376256 823842480 9.137 100 91 182 0 857637216 11860464 21934272
08:11:01 14640 8192000000 9785375056 10101325824 855859520 9.459 100 77 154 0 893003744 18730144 18414080
08:12:20 12158 8192000000 9850638208 10183766016 910317344 10.000 100 76 152 2 969867456 41925392 17624720
08:13:35 7817 8192000000 9920917152 10259828736 959936288 10.488 100 88 174 0 1027366720 49269984 18160448
08:14:45 3918 8192000000 9963022448 10316476416 989349072 10.775 100 80 158 1 1078754864 73548816 15856976
08:15:50 2293 8192000000 9968212944 10327494656 1013482928 11.009 100 87 170 1 1087402192 56910064 17009200
08:16:55 6389 8192000000 10028126800 10391515136 1036383664 11.230 100 82 163 2 1138434672 85794416 16256592
08:18:03 1330 8192000000 10047059840 10418626560 1062164384 11.477 100 92 180 0 1158904256 78404480 18335392
08:19:05 3659 8192000000 10018275360 10397437952 1067454528 11.528 100 63 124 2 1136943536 56337888 13151120
08:20:10 5437 8192000000 9985575824 10379960320 1067490816 11.528 100 38 76 1 1091522304 16150880 7880608
08:21:15 5570 8192000000 10013630592 10404552704 1074366496 11.594 100 46 92 0 1107307136 23869888 9070752
08:22:23 29320 8192000000 10000508224 10419302400 1080260992 11.650 100 31 62 2 1114500512 25938304 8301216
08:24:04 6593 8192000000 10015769792 10425335808 1081880016 11.665 100 31 61 1 1140437376 52223712 6333648
08:25:11 4991 8192000000 10019805952 10417143808 1092122304 11.763 100 49 99 0 1135912192 31766320 12023568
08:26:18 15922 8192000000 9999193904 10403831808 1078870480 11.637 99 48 95 2 1103218608 13888208 10459920
08:27:34 6980 8192000000 9991632208 10387611648 1072798080 11.579 99 33 64 3 1094379056 14501392 7079584
08:28:42 7106 8192000000 9966845744 10365968384 1030073136 11.169 97 24 48 1 1054252192 18043984 6135072
08:29:52 16574 8192000000 9942455456 10327445504 979632128 10.681 94 39 77 0 1002560864 13860016 9068720
08:31:09 3716 8192000000 9929673472 10309898240 999347008 10.872 99 65 132 0 1033867904 19972784 14548112
08:32:16 3694 8192000000 9964301056 10347540480 1042243120 11.286 100 53 109 1 1071899728 17339888 12316720
08:33:21 10205 8192000000 9957536064 10345623552 1042284176 11.287 100 33 66 1 1071687776 21928208 7475392
08:34:32 46893 8192000000 9981361632 10390056960 1054496224 11.404 100 36 67 1 1082243904 20187232 7560448
08:36:19 12237 8192000000 9993847104 10399248384 1064081568 11.496 100 41 81 3 1096956336 23633648 9241120
08:37:39 54571 8192000000 10031092080 10426400768 1087997376 11.724 100 52 102 1 1125247536 27241344 10008816
08:39:38 92805 8192000000 10121096176 10543783936 1106445280 11.899 100 49 97 2 1226940304 109658208 10836816
08:42:16 6455 8192000000 10106138688 10510237696 1117685184 12.005 100 56 110 1 1215273680 85627984 11960512
08:43:24 1467 8192000000 10132485488 10538860544 1129383488 12.116 100 61 124 1 1239350960 96630256 13337216
08:44:29 9342 8192000000 10138946800 10548592640 1141374752 12.228 100 55 108 2 1235349472 82892816 11081904
08:45:43 10528 8192000000 10197488864 10608533504 1161337552 12.416 100 57 114 1 1305758528 131408784 13012192
08:46:58 6281 8192000000 10262589072 10671509504 1169967104 12.497 100 78 156 0 1366097616 178420800 17709712
08:48:10 2230 8192000000 10338605872 10738774016 1164112544 12.442 99 90 178 0 1427777712 244331008 19334160
08:49:16 3053 8192000000 10348873136 10758639616 1178755216 12.579 100 74 148 0 1447893104 252528304 16609584
08:50:23 9515 8192000000 10353012624 10768814080 1169902768 12.496 99 53 100 3 1447060800 265411792 11746240
08:51:38 1925 8192000000 10429640544 10843623424 1174109680 12.535 100 77 154 0 1535698464 343285520 18303264
08:52:44 1312 8192000000 10438890384 10841538560 1178266432 12.574 100 88 170 1 1534169344 336086160 19816752
08:53:47 2129 8192000000 10516997392 10921033728 1177028704 12.562 100 79 157 0 1615963424 420098912 18835808
08:54:57 2801 8192000000 10564375136 10967818240 1171692432 12.513 100 91 183 1 1658881360 465945744 21243184
08:56:03 4198 8192000000 10551209744 10976845824 1180152176 12.592 100 54 109 1 1654608432 461057728 13398528
08:57:08 10034 8192000000 10594174368 11036676096 1178777984 12.579 100 40 80 0 1706442336 517058544 10605808
08:58:22 13533 8192000000 10613500048 11055415296 1177895168 12.571 100 42 85 0 1721323888 532410112 11018608
08:59:42 9058 8192000000 10634939616 11068461056 1158685584 12.391 100 38 76 0 1735246224 566011616 10549024
09:00:55 13658 8192000000 10694129584 11134185472 1148869872 12.299 100 36 72 1 1782320608 623104048 10346688
09:02:42 25043 8192000000 10551974816 11052466176 1125800960 12.082 99 30 60 1 1682242864 547356368 9085536
09:04:39 11610 8192000000 10129490032 10600067072 1121129072 12.038 98 31 62 2 1207026672 79370944 6526656
09:05:53 44715 8192000000 10055500752 10541649920 1106974400 11.904 99 18 37 0 1176879808 65928320 3977088
09:07:45 38259 8192000000 10094842432 10541191168 1132750144 12.147 100 28 56 1 1188981136 49585232 6645760
09:09:24 72485 8192000000 10073144016 10546962432 1111576880 11.947 98 15 30 2 1172462352 58034192 2851280
09:11:38 42558 8192000000 10130514400 10571173888 1143566368 12.249 100 49 98 0 1213311968 58152176 11593424
09:13:23 40287 8192000000 10131099632 10584961024 1139515376 12.211 100 44 88 2 1220216048 71523504 9177168
09:15:06 33056 8192000000 10106711184 10561945600 1118374128 12.012 99 8 16 0 1192537408 72395056 1768224
09:16:41 40189 8192000000 10136905296 10586886144 1136426096 12.182 100 42 84 1 1227555136 81537168 9591872
09:18:27 135720 8192000000 10092356720 10552102912 1120557168 12.032 98 28 56 3 1170152912 43630784 5964960
09:21:43 53841 8192000000 10140011216 10587561984 1148569952 12.296 100 25 50 0 1245148944 90452032 6126960
09:23:58 28093 8192000000 10109461120 10557071360 1134936704 12.168 100 33 67 0 1208186144 66407136 6842304
09:25:48 73410 8192000000 10053553584 10522849280 1140242656 12.218 100 20 40 0 1181576528 37595712 3738160
09:29:39 67984 8192000000 10053309936 10515451904 1116222400 11.991 97 16 32 0 1170900896 51256400 3422096
09:31:49 34825 8192000000 10123820848 10565459968 1135744816 12.175 99 34 67 2 1207975248 64861248 7369184
09:33:28 48626 8192000000 10059062688 10506924032 1099379840 11.832 96 17 34 1 1158460912 55125200 3955872
09:35:17 33259 8192000000 10076242000 10526130176 1091575744 11.758 96 38 76 0 1147028464 47717232 7735488
09:36:59 42335 8192000000 10115561840 10584883200 1112694640 11.958 98 23 44 1 1201344864 84319504 4330720
09:38:42 51476 8192000000 10109092928 10577432576 1105985392 11.894 97 10 20 0 1163424624 55568624 1870608
09:40:39 97593 8192000000 10046975264 10486374400 1102442992 11.861 96 18 36 1 1155313920 49504656 3366272
09:43:17 86573 8192000000 10070846608 10582683648 1093994832 11.781 96 36 72 0 1196935072 95350000 7590240
09:45:57 33623 8192000000 10068722288 10518839296 1124806192 12.072 99 44 86 0 1166904112 33050176 9047744
09:47:35 43852 8192000000 10090521616 10575458304 1126728320 12.091 99 16 32 0 1208081536 77783504 3569712
09:49:20 55738 8192000000 10065981920 10571505664 1118577760 12.014 99 7 14 0 1157737456 37594976 1564720
09:51:15 61993 8192000000 10065165040 10550755328 1115784288 11.987 98 22 43 3 1156761728 35138096 5839344
09:53:18 69748 8192000000 10101554192 10562023424 1118705280 12.015 98 24 48 2 1178726576 55381488 4639808
09:55:32 76405 8192000000 10078929504 10586394624 1098333904 11.822 96 19 38 0 1186992768 84748336 3910528
09:58:12 53810 8192000000 10094335760 10575790080 1133431616 12.154 100 22 44 1 1195887152 57729248 4726288
10:00:06 44190 8192000000 10102515120 10531127296 1126321008 12.087 100 36 70 2 1189517440 55492992 7703440
10:01:50 66591 8192000000 10042577728 10490281984 1079779040 11.645 96 14 28 0 1121397696 38437744 3180912
10:04:32 58845 8192000000 10065617296 10540199936 1122839264 12.054 100 21 42 0 1202131568 74859840 4432464
10:07:18 58765 8192000000 10079788944 10570313728 1132313632 12.143 100 11 22 0 1179129456 44562320 2253504
10:09:18 60059 8192000000 10043694688 10526355456 1109728848 11.930 98 15 30 1 1163516448 51102960 2684640
10:11:18 51169 8192000000 10128091344 10577252352 1143264560 12.246 99 27 53 1 1215593696 66961408 5367728
10:13:10 89403 8192000000 10128632752 10636013568 1146216640 12.274 100 25 49 2 1287408912 135635456 5556816
10:15:41 40784 8192000000 10086919856 10523418624 1127939136 12.102 99 30 60 0 1162752528 28714144 6099248
10:17:22 85890 8192000000 10112687424 10616233984 1108754720 11.921 97 26 51 1 1185110592 71221984 5133888
10:20:01 45776 8192000000 10099024512 10606112768 1144058176 12.254 100 27 54 3 1235399200 85736144 5604880
10:21:48 124614 8192000000 10087520432 10542514176 1093774640 11.779 96 34 67 0 1130217280 29698000 6744640
10:24:58 47727 8192000000 10088477328 10534215680 1114941408 11.979 99 36 72 0 1178246384 55505872 7799104
10:27:51 58649 8192000000 10058014432 10547453952 1100026096 11.838 98 8 16 0 1170198624 68318464 1854064
10:29:59 85544 8192000000 10106259680 10598576128 1114048064 11.971 99 15 30 0 1218114016 100574368 3491584
10:32:26 53518 8192000000 10082772016 10536435712 1107490800 11.909 98 19 39 1 1179190352 67434208 4265344
10:34:22 63115 8192000000 10075713472 10536050688 1111795648 11.949 98 20 40 0 1159937744 43826944 4315152
10:36:40 31792 8192000000 9353939680 9671110656 478561392 5.519 44 19 36 1 505615248 23540160 3513696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-08-2021 10:38:10 to 27-08-2021 10:38:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-08-2021 10:38:26 to 27-08-2021 10:38:28.

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          235300
MON$OLDEST_ACTIVE               235301
MON$OLDEST_SNAPSHOT             235301
MON$NEXT_TRANSACTION            235301
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                       2050720
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2573 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2573 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:71: 
:72: mscs-test-161.budzdorov.ru    Fri Aug 27 10:37:15 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Fri Aug 27 10:37:15 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

27.08.21 10:38:29. 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-08-27 10:38:32 No severe PSQL-related problems occured
27.08.21 10:38:33. End of report.