Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 19:39
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 16573124 kB
MemAvailable 30916432 kB
Buffers 0 kB
Cached 15137468 kB
SwapCached 340 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-04 19:37:50
DTS_END                         2021-09-04 19:37:50.8390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38778
TRN_ID                          250537
ATT_ID                          686
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: 9627.95 16468 1155354
customer order: creation 186.42 319 22370
customer order: refuse 15.06 375 1807
order to supplier: creation 48.44 1201 5813
order to supplier: removal 3.45 104390 414
invoice (draft): creation 48.57 3363 5828
invoice (draft): removal 54.38 791 6526
invoice accept: apply 43.31 18655 5197
invoice accept: cancel 48.53 787 5823
customer reserve: creation 4073.23 181 488788
customer reserve: removal 4792.90 58 575148
realization accept: apply 90.02 194 10802
realization accept: cancel 83.06 122 9967
payment from customer: creation 63.31 126 7597
payment from customer: removal 6.33 67 760
payment to supplier: creation 53.06 134 6367
payment to supplier: removal 3.89 110 467
service: total inventory turnovers 5.27 1175 632
service: total monetary turnovers 7.77 49 932
service: aggregate perf. data 0.93 16434 112
service: refresh index statistics 0.03 180833 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-09-2021 19:40:12 to 04-09-2021 19:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1787
TEST_TIME 2 4845
TEST_TIME 3 3706
TEST_TIME 4 4113
TEST_TIME 5 4452
TEST_TIME 6 5039
TEST_TIME 7 5036
TEST_TIME 8 4998
TEST_TIME 9 4870
TEST_TIME 10 5013
TEST_TIME 11 5342
TEST_TIME 12 5381
TEST_TIME 13 5407
TEST_TIME 14 6222
TEST_TIME 15 6341
TEST_TIME 16 6648
TEST_TIME 17 6893
TEST_TIME 18 7253
TEST_TIME 19 7465
TEST_TIME 20 7677
TEST_TIME 21 7924
TEST_TIME 22 8416
TEST_TIME 23 8485
TEST_TIME 24 8896
TEST_TIME 25 9204
TEST_TIME 26 9540
TEST_TIME 27 9415
TEST_TIME 28 10103
TEST_TIME 29 10915
TEST_TIME 30 10804
TEST_TIME 31 10708
TEST_TIME 32 10543
TEST_TIME 33 10637
TEST_TIME 34 10592
TEST_TIME 35 10626
TEST_TIME 36 10513
TEST_TIME 37 10523
TEST_TIME 38 10307
TEST_TIME 39 10261
TEST_TIME 40 10197
TEST_TIME 41 10013
TEST_TIME 42 10018
TEST_TIME 43 10035
TEST_TIME 44 9969
TEST_TIME 45 10034
TEST_TIME 46 10231
TEST_TIME 47 10081
TEST_TIME 48 10000
TEST_TIME 49 10033
TEST_TIME 50 9964
TEST_TIME 51 9952
TEST_TIME 52 9885
TEST_TIME 53 9809
TEST_TIME 54 9758
TEST_TIME 55 9797
TEST_TIME 56 9789
TEST_TIME 57 9783
TEST_TIME 58 9757
TEST_TIME 59 9736
TEST_TIME 60 9864
TEST_TIME 61 9808
TEST_TIME 62 9966
TEST_TIME 63 9924
TEST_TIME 64 10029
TEST_TIME 65 10044
TEST_TIME 66 10073
TEST_TIME 67 10125
TEST_TIME 68 10188
TEST_TIME 69 10206
TEST_TIME 70 10270
TEST_TIME 71 10130
TEST_TIME 72 10059
TEST_TIME 73 10029
TEST_TIME 74 10011
TEST_TIME 75 10036
TEST_TIME 76 9987
TEST_TIME 77 9933
TEST_TIME 78 9879
TEST_TIME 79 9792
TEST_TIME 80 9791
TEST_TIME 81 9777
TEST_TIME 82 9842
TEST_TIME 83 9819
TEST_TIME 84 9827
TEST_TIME 85 9812
TEST_TIME 86 9796
TEST_TIME 87 9785
TEST_TIME 88 9803
TEST_TIME 89 9766
TEST_TIME 90 9900
TEST_TIME 91 9894
TEST_TIME 92 9915
TEST_TIME 93 10018
TEST_TIME 94 9984
TEST_TIME 95 9914
TEST_TIME 96 9830
TEST_TIME 97 9838
TEST_TIME 98 9854
TEST_TIME 99 9844
TEST_TIME 100 9810
TEST_TIME 101 9762
TEST_TIME 102 9771
TEST_TIME 103 9706
TEST_TIME 104 9658
TEST_TIME 105 9612
TEST_TIME 106 9606
TEST_TIME 107 9700
TEST_TIME 108 9687
TEST_TIME 109 9672
TEST_TIME 110 9687
TEST_TIME 111 9687
TEST_TIME 112 9647
TEST_TIME 113 9720
TEST_TIME 114 9687
TEST_TIME 115 9728
TEST_TIME 116 9729
TEST_TIME 117 9723
TEST_TIME 118 9709
TEST_TIME 119 9672
TEST_TIME 120 9629

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-09-2021 19:40:16 to 04-09-2021 19:40:19.

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 1607846 1607846 0 0.00 0 18592 7 0 0
doc_list_biud 1607846 1607846 0 0.00 0 59165 27 0 0
sp_add_invoice_to_stock 5364 5197 167 3.11 34 191736 18655 0 167
sp_cancel_adding_invoice 5823 5823 0 0.00 5 58666 787 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 23013 375 0 0
sp_cancel_customer_reserve 575148 575148 0 0.00 1 59171 58 0 0
sp_cancel_pay_from_customer 760 760 0 0.00 3 5718 67 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 2 9133 110 0 0
sp_cancel_supplier_invoice 6537 6526 11 0.16 1 21314 792 0 11
sp_cancel_supplier_order 414 414 0 0.00 6 1128632 104390 0 0
sp_cancel_write_off 9967 9967 0 0.00 1 14470 122 0 0
sp_client_order 22370 22370 0 0.00 3 13090 319 0 0
sp_customer_reserve 488788 488788 0 0.00 0 31801 181 0 0
sp_fill_shopping_cart 34000 34000 0 0.00 1 18780 38 0 0
sp_fill_shopping_cart_clo_res 488787 488787 0 0.00 0 15141 13 0 0
sp_get_clo_for_invoice 5195 5195 0 0.00 0 46158 271 0 0
sp_kill_cost_storno 17017 17017 0 0.00 0 9285 61 0 0
sp_kill_qstorno_ret_qs2qd 1167203 1167203 0 0.00 0 59136 32 0 0
sp_kill_qty_storno 615682 615682 0 0.00 0 59163 69 0 0
sp_lock_selected_doc 22116 22116 0 0.00 0 9424 7 0 0
sp_make_cost_storno 29961 29961 0 0.00 0 16754 63 0 0
sp_multiply_rows_for_pdistr 29961 29961 0 0.00 0 6639 26 0 0
sp_multiply_rows_for_qdistr 22370 22370 0 0.00 0 12485 136 0 0
sp_pay_from_customer 7597 7597 0 0.00 4 10123 127 0 0
sp_pay_to_supplier 6367 6367 0 0.00 5 11316 134 0 0
sp_payment_common 13964 13964 0 0.00 2 9399 36 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 23004 343 0 0
sp_qd_handle_on_invoice_upd_sts 11018 11018 0 0.00 0 29333 335 0 0
sp_qd_handle_on_reserve_upd_sts 10802 10802 0 0.00 0 17555 62 0 0
sp_reserve_write_off 10802 10802 0 0.00 5 17603 194 0 0
sp_supplier_invoice 5828 5828 0 0.00 6 89728 3364 0 0
sp_supplier_order 5813 5813 0 0.00 5 26143 1202 0 0
srv_aggregate_perf_data 112 112 0 0.00 2887 79604 16434 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 921 131262 41992 0 0
srv_find_qd_qs_mism 1104174 1104174 0 0.00 0 18591 6 0 0
srv_make_invnt_saldo 632 632 0 0.00 21 78206 1175 0 0
srv_make_money_saldo 932 932 0 0.00 1 12309 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 103078 258906 180834 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5197 5197 0 0.00 0 21196 190 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 7642 140 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 2316 13 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1887 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 760 760 0 0.00 0 190 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 3846 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 4896 33 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 23993 1620 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 2436 11 0 0
t$perf-norm:sp_client_order 22370 22370 0 0.00 0 11189 24 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 7265 20 0 0
t$perf-norm:sp_pay_from_customer 7597 7597 0 0.00 0 5876 12 0 0
t$perf-norm:sp_pay_to_supplier 6367 6367 0 0.00 0 7263 14 0 0
t$perf-norm:sp_reserve_write_off 10802 10802 0 0.00 0 11075 13 0 0
t$perf-norm:sp_supplier_invoice 5828 5828 0 0.00 0 6799 19 0 0
t$perf-norm:sp_supplier_order 5813 5813 0 0.00 0 6444 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 757 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 271 6 0 0
t$perf-norm:srv_make_invnt_saldo 632 632 0 0.00 0 1366 21 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 1466 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 6 0 0
v_add_invoice_to_stock 10724 10390 334 3.11 0 9210 20 0 334
v_all_customers 40166 40166 0 0.00 0 132 1 0 0
v_all_suppliers 32762 32762 0 0.00 0 27 1 0 0
v_all_wares 44740 44740 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 5556 17 0 0
v_cancel_client_order 3614 3614 0 0.00 0 6784 7 0 0
v_cancel_customer_prepayment 1520 1520 0 0.00 0 2533 6 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 863 4 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 9041 18 0 0
v_cancel_supplier_invoice 908 886 22 2.42 0 4633 21 0 22
v_cancel_supplier_order 828 828 0 0.00 0 4721 17 0 0
v_cancel_write_off 2120 2120 0 0.00 0 2198 15 0 0
v_max_id_clo_ord 5813 5813 0 0.00 0 684 1 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 2015 14 0 0
v_max_id_ord_sup 5817 5817 0 0.00 0 5115 4 0 0
v_max_non_paid_invoice 1616 1616 0 0.00 0 2224 4 0 0
v_max_non_paid_realizn 4178 4178 0 0.00 0 3079 4 0 0
v_min_id_clo_ord 5813 5813 0 0.00 0 4108 9 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 3885 8 0 0
v_min_id_ord_sup 5817 5817 0 0.00 0 6412 7 0 0
v_min_non_paid_invoice 6367 6367 0 0.00 0 8423 24 0 0
v_min_non_paid_realizn 7597 7597 0 0.00 0 9395 28 0 0
v_our_firm 11412 11412 0 0.00 0 18 1 0 0
v_reserve_write_off 21604 21604 0 0.00 0 10563 8 0 0
x_lock_depdocs_on_canc_invoice 11906 11906 0 0.00 0 17025 121 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 8025 389 0 0
x_make_qstorno_1000_1200 5813 5813 0 0.00 2 25943 1118 0 0
x_make_qstorno_1200_2000 5828 5828 0 0.00 3 89700 3225 0 0
x_make_qstorno_2100_3300 488788 488788 0 0.00 0 31794 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-09-2021 19:40:20 to 04-09-2021 19:40:24.

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
17:07:54 4 8192000000 8804126208 8816132096 9862864 0.120 2 2 3 0 10041296 66144 112288
17:08:55 21381 8192000000 10377728272 10700976128 1417443360 14.750 100 88 176 0 1451527408 5926496 28157552
17:10:21 15645 8192000000 10440153824 10770571264 1491287760 15.400 100 89 178 1 1528748960 8685168 28776032
17:11:43 15739 8192000000 10481516064 10823790592 1538082928 15.807 100 86 172 1 1575986256 10427600 27475728
17:13:05 20264 8192000000 10563824624 10920914944 1611246192 16.435 100 78 156 2 1646356480 10560000 24550288
17:14:27 15481 8192000000 10625824144 10996965376 1696009648 17.152 100 83 166 2 1732379616 11658512 24711456
17:15:46 7275 8192000000 10714235664 11092406272 1771972416 17.783 100 82 164 1 1806007216 10431472 23603328
17:16:55 2200 8192000000 10754903392 11131752448 1810025600 18.096 100 89 176 1 1849510272 14850000 24634672
17:17:58 2018 8192000000 10791932480 11179991040 1844451344 18.377 100 84 166 1 1882527744 14378080 23698320
17:19:01 1309 8192000000 10813032992 11201277952 1870911936 18.592 100 90 178 1 1909462080 12935792 25614352
17:20:05 18342 8192000000 10796957360 11202875392 1868605136 18.573 100 39 79 0 1887335632 7344496 11386000
17:21:25 7371 8192000000 10798844832 11220369408 1884945696 18.705 100 42 84 2 1905584096 9223632 11414768
17:22:33 7530 8192000000 10798914576 11225858048 1885568800 18.710 100 19 38 1 1896555936 5949360 5037776
17:23:40 56459 8192000000 10842447440 11243986944 1900979232 18.834 100 49 96 4 1919560992 4874656 13707104
17:25:37 3943 8192000000 10824005456 11249606656 1901722240 18.840 100 30 60 1 1917863344 7698384 8442720
17:26:45 6294 8192000000 10819945328 11251073024 1902844160 18.849 100 19 38 0 1914747984 5884528 6019296
17:27:52 11657 8192000000 10793364800 11230494720 1873502848 18.613 99 9 17 2 1881461024 5713040 2245136
17:29:04 36474 8192000000 10783356576 11193159680 1811935024 18.112 96 53 105 2 1834030144 5991520 16103600
17:30:40 4408 8192000000 10728519952 11141988352 1798432480 18.001 99 32 63 0 1814131024 6042832 9655712
17:31:47 3585 8192000000 10701192944 11112792064 1762308800 17.703 97 30 61 2 1783990864 13473760 8208304
17:32:54 10634 8192000000 10734109696 11150356480 1778425728 17.837 97 10 20 1 1786773120 5765184 2582208
17:34:05 54586 8192000000 10766471696 11197739008 1857445808 18.483 100 26 52 2 1870517536 5380416 7691312
17:36:00 5705 8192000000 10789981904 11217743872 1879068496 18.658 100 23 45 1 1891210096 6031792 6109808
17:37:08 5361 8192000000 10821005280 11248549888 1902764480 18.849 100 31 61 1 1917119120 5290832 9063808
17:38:19 25913 8192000000 10864661664 11281186816 1930912112 19.074 100 48 92 0 1951805344 6676688 14216544
17:39:49 73014 8192000000 10867939088 11308748800 1946152112 19.196 100 45 90 0 1967987840 8816368 13019360
17:42:09 43197 8192000000 10892609840 11328577536 1958718224 19.296 100 33 65 1 1981991840 13393776 9879840
17:43:59 12163 8192000000 10921072256 11359191040 1982819280 19.487 100 45 90 0 2013286832 15751472 14716080
17:45:14 6739 8192000000 10942219248 11370541056 1988251520 19.530 100 55 110 1 2025369600 20876480 16241600
17:46:31 5752 8192000000 10958932640 11391041536 2004988928 19.662 100 64 127 1 2046923248 23331440 18602880
17:47:47 3256 8192000000 10980804480 11414269952 2021178768 19.789 100 57 114 1 2064057328 25883968 16994592
17:48:56 8023 8192000000 10972749888 11401662464 2008162240 19.687 100 66 131 2 2053184336 26471408 18550688
17:50:10 8054 8192000000 10952432304 11388731392 1990699424 19.549 99 50 100 1 2036527232 30717696 15110112
17:51:24 5426 8192000000 10971370816 11406626816 2008373648 19.689 100 64 128 0 2061511136 33520640 19616848
17:52:37 921 8192000000 10996938672 11418636288 2015632208 19.746 100 77 153 1 2074790880 36445904 22712768
17:53:44 2797 8192000000 10995434240 11417665536 1993517744 19.572 99 79 156 1 2059943408 42105264 24320400
17:54:54 6613 8192000000 10994079792 11419770880 2007172112 19.679 100 75 148 5 2075100368 44103440 23824816
17:56:05 7311 8192000000 10970658192 11390484480 1977774944 19.447 100 70 139 2 2050939792 50535264 22629584
17:57:20 4048 8192000000 10969664432 11394363392 1978266560 19.451 100 66 132 0 2050983424 50946800 21770064
17:58:33 1989 8192000000 11015049712 11430354944 2007031104 19.678 100 87 173 0 2093079504 58562624 27485776
17:59:39 13310 8192000000 10969682720 11414753280 1996060704 19.592 100 43 86 4 2060307120 49153216 15093200
18:01:08 21020 8192000000 10952609184 11400347648 1947810848 19.209 98 41 80 0 2019071776 56448096 14812832
18:02:32 28732 8192000000 10938790144 11402911744 1961278192 19.316 99 28 56 2 2017304800 45953152 10073456
18:04:21 26453 8192000000 10937651632 11399688192 1968501216 19.374 100 19 38 1 2022801952 47219616 7081120
18:06:28 46433 8192000000 10851516080 11294412800 1867410544 18.563 96 21 42 0 1881429520 8014544 6004432
18:08:23 73368 8192000000 10876931760 11309944832 1845782880 18.388 94 31 61 1 1865118832 10721936 8614016
18:10:42 32286 8192000000 10906894784 11344330752 1947655296 19.208 99 45 90 4 1965870768 6015456 12200016
18:12:19 131261 8192000000 10912627088 11380060160 1932003088 19.083 98 12 24 1 1955313904 19768640 3542176
18:15:32 88171 8192000000 10923823376 11377192960 1976629712 19.438 100 24 48 2 1992492080 8410480 7451888
18:18:01 43112 8192000000 10907439312 11362029568 1964490272 19.342 99 25 50 0 1983517952 12097680 6930000
18:19:49 22198 8192000000 10907728704 11364167680 1967579024 19.366 100 31 62 3 1990326208 13989120 8758064
18:21:16 24496 8192000000 10940797776 11391021056 1995431504 19.587 100 50 101 3 2023805728 13276464 15097760
18:22:44 16026 8192000000 10922323152 11403902976 1990604960 19.549 100 15 28 3 2010445024 15565936 4274128
18:24:02 38165 8192000000 10923581840 11398963200 1995488896 19.587 100 10 20 1 2010643632 12312800 2841936
18:25:40 37137 8192000000 10911894016 11394510848 1957008016 19.282 98 8 16 0 1974381232 14999216 2374000
18:27:17 61104 8192000000 10913077568 11401699328 1973987328 19.417 99 16 32 2 1997861344 19160144 4713872
18:29:51 61086 8192000000 10911321328 11390349312 1988088112 19.529 100 23 48 0 2004468016 9266832 7113072
18:32:05 60509 8192000000 10884294640 11368693760 1922855808 19.010 97 14 28 0 1941390992 14437248 4097936
18:34:06 19758 8192000000 10920094512 11370790912 1956591872 19.279 99 33 66 0 1973421280 7588624 9240784
18:35:30 13512 8192000000 10889875584 11354517504 1954487808 19.262 100 18 35 0 1970288784 11221888 4579088
18:36:46 54202 8192000000 10857164880 11330621440 1828334576 18.246 93 11 22 2 1842947968 11707760 2905632
18:38:41 17783 8192000000 10901570192 11354738688 1953448976 19.254 100 53 104 2 1980554048 12981424 14123648
18:40:03 55829 8192000000 10896398752 11364814848 1911879568 18.922 98 10 19 1 1931876608 16867120 3129920
18:42:01 38517 8192000000 10891253008 11354951680 1930772784 19.073 99 15 30 0 1947129056 12609792 3746480
18:43:39 96236 8192000000 10878022688 11344175104 1837481072 18.320 94 15 30 0 1858784112 16855328 4447712
18:46:19 112564 8192000000 10830686752 11316928512 1863214832 18.529 96 10 20 2 1885461648 19378288 2868528
18:49:13 68462 8192000000 10884839264 11346636800 1907426960 18.886 98 33 67 3 1931710320 14976544 9306816
18:51:28 24984 8192000000 10896837104 11355279360 1961781696 19.320 100 34 68 0 1981618944 10332800 9504448
18:52:54 40970 8192000000 10849900112 11328352256 1893549552 18.774 97 7 14 0 1902972176 7020288 2402336
18:54:38 60519 8192000000 10896305024 11369672704 1952282960 19.245 99 19 39 0 1966589984 8243056 6063968
18:56:43 59760 8192000000 10887071904 11372720128 1962762912 19.328 100 17 34 0 1982952496 14463504 5726080
18:59:00 74832 8192000000 10893645408 11380842496 1972731104 19.407 100 17 34 0 1990945872 13382752 4832016
19:01:39 41528 8192000000 10900116416 11368951808 1953499952 19.254 98 27 55 0 1967841520 6680848 7660720
19:03:21 21435 8192000000 10914112640 11377627136 1952460576 19.246 98 47 94 1 1978804656 13505040 12839040
19:04:43 57115 8192000000 10924141808 11402502144 1981111632 19.473 100 24 48 1 2009250352 21468304 6670416
19:06:46 112887 8192000000 10924470128 11404062720 1948179424 19.212 98 10 20 0 1966162544 15975184 2007936
19:09:40 56139 8192000000 10918511632 11396755456 1916936960 18.962 96 16 32 1 1930231392 9344032 3950400
19:11:50 47808 8192000000 10908424768 11372167168 1916020512 18.955 97 23 46 2 1930595344 9100384 5474448
19:13:39 46875 8192000000 10922438336 11385626624 1982488064 19.484 100 31 61 0 2001592384 10612512 8491808
19:15:26 18512 8192000000 10903329344 11389095936 1964183392 19.339 99 20 40 0 1987417008 17483264 5750352
19:16:45 65587 8192000000 10921164544 11413225472 2006791008 19.676 100 24 48 1 2020800848 7162176 6847664
19:19:30 53087 8192000000 10910494512 11400970240 1990194416 19.545 100 11 22 0 2001636656 7995136 3447104
19:21:25 62272 8192000000 10911757568 11382714368 1898260096 18.812 95 16 33 0 1915516688 12266576 4990016
19:23:33 65836 8192000000 10885459296 11380060160 1926838176 19.042 98 14 28 1 1949091744 18299376 3954192
19:25:39 39410 8192000000 10888425392 11374399488 1944650416 19.184 98 35 72 2 1965322320 9538944 11132960
19:27:19 31055 8192000000 10905344464 11375378432 1950073920 19.227 99 23 45 2 1965182288 8974928 6133440
19:28:58 64737 8192000000 10875054192 11336265728 1844744336 18.379 94 33 66 1 1867129120 13211472 9173312
19:31:05 33141 8192000000 10853304480 11314143232 1907969328 18.890 100 22 44 1 1929415504 15433504 6012672
19:32:39 55460 8192000000 10872632928 11337465856 1903540944 18.855 98 18 36 1 1918925792 10364128 5020720
19:34:36 60551 8192000000 10898596048 11338964992 1885236640 18.707 97 43 86 2 1909986464 13189648 11560176
19:36:39 101978 8192000000 10137665568 10540748800 1258529472 13.317 65 9 18 0 1270084112 9030368 2524272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-09-2021 19:40:26 to 04-09-2021 19:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 167 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-09-2021 19:40:42 to 04-09-2021 19:40:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          250808
MON$OLDEST_ACTIVE               250809
MON$OLDEST_SNAPSHOT             250809
MON$NEXT_TRANSACTION            250809
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-03 16:41:55.3470
MON$PAGES                       2045280
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Sat Sep  4 19:38:37 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
--- end of diff output ---

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

04.09.21 19:40:45. There are 3 logs in /var/tmp/logs-oltp30 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-09-04 19:40:47 No severe PSQL-related problems occured
04.09.21 19:40:48. End of report.