Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 01:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 17025720 kB
MemAvailable 31613136 kB
Buffers 0 kB
Cached 14669120 kB
SwapCached 0 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-11-17 01:37:55
DTS_END                         2021-11-17 01:37:55.9550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39328
TRN_ID                          236677
ATT_ID                          617
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: 9005.27 24403 1080632
customer order: creation 165.65 397 19878
customer order: refuse 14.08 352 1690
order to supplier: creation 45.01 1379 5401
order to supplier: removal 3.48 95476 418
invoice (draft): creation 44.18 3376 5302
invoice (draft): removal 51.17 802 6140
invoice accept: apply 40.63 16531 4876
invoice accept: cancel 46.38 711 5565
customer reserve: creation 3767.06 162 452047
customer reserve: removal 4539.63 57 544756
realization accept: apply 81.33 178 9759
realization accept: cancel 81.79 111 9815
payment from customer: creation 57.15 105 6858
payment from customer: removal 5.68 77 682
payment to supplier: creation 46.91 117 5629
payment to supplier: removal 3.43 113 412
service: total inventory turnovers 4.49 965 539
service: total monetary turnovers 6.40 20 768
service: aggregate perf. data 0.78 19709 93
service: refresh index statistics 0.03 347423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 01:39:20 to 17-11-2021 01:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2092
TEST_TIME 2 2141
TEST_TIME 3 3397
TEST_TIME 4 3743
TEST_TIME 5 4154
TEST_TIME 6 4353
TEST_TIME 7 4536
TEST_TIME 8 5499
TEST_TIME 9 5560
TEST_TIME 10 6208
TEST_TIME 11 6105
TEST_TIME 12 6087
TEST_TIME 13 5997
TEST_TIME 14 6461
TEST_TIME 15 6742
TEST_TIME 16 7139
TEST_TIME 17 7774
TEST_TIME 18 7772
TEST_TIME 19 8297
TEST_TIME 20 8283
TEST_TIME 21 8393
TEST_TIME 22 8664
TEST_TIME 23 8476
TEST_TIME 24 9133
TEST_TIME 25 9352
TEST_TIME 26 9937
TEST_TIME 27 10563
TEST_TIME 28 10398
TEST_TIME 29 10440
TEST_TIME 30 10107
TEST_TIME 31 10064
TEST_TIME 32 10040
TEST_TIME 33 9938
TEST_TIME 34 9814
TEST_TIME 35 9683
TEST_TIME 36 9608
TEST_TIME 37 9531
TEST_TIME 38 9378
TEST_TIME 39 9305
TEST_TIME 40 9281
TEST_TIME 41 9218
TEST_TIME 42 9209
TEST_TIME 43 9097
TEST_TIME 44 9045
TEST_TIME 45 9108
TEST_TIME 46 9061
TEST_TIME 47 8974
TEST_TIME 48 9007
TEST_TIME 49 8847
TEST_TIME 50 8769
TEST_TIME 51 8772
TEST_TIME 52 8734
TEST_TIME 53 8837
TEST_TIME 54 8849
TEST_TIME 55 8833
TEST_TIME 56 8803
TEST_TIME 57 8959
TEST_TIME 58 8889
TEST_TIME 59 8851
TEST_TIME 60 9069
TEST_TIME 61 9210
TEST_TIME 62 9301
TEST_TIME 63 9329
TEST_TIME 64 9261
TEST_TIME 65 9152
TEST_TIME 66 9092
TEST_TIME 67 9050
TEST_TIME 68 9100
TEST_TIME 69 9112
TEST_TIME 70 9063
TEST_TIME 71 9046
TEST_TIME 72 9062
TEST_TIME 73 9030
TEST_TIME 74 8989
TEST_TIME 75 9037
TEST_TIME 76 9076
TEST_TIME 77 9095
TEST_TIME 78 9071
TEST_TIME 79 9065
TEST_TIME 80 9040
TEST_TIME 81 9205
TEST_TIME 82 9218
TEST_TIME 83 9230
TEST_TIME 84 9150
TEST_TIME 85 9136
TEST_TIME 86 9073
TEST_TIME 87 9079
TEST_TIME 88 9090
TEST_TIME 89 9069
TEST_TIME 90 9030
TEST_TIME 91 9026
TEST_TIME 92 8998
TEST_TIME 93 8951
TEST_TIME 94 8907
TEST_TIME 95 8902
TEST_TIME 96 8939
TEST_TIME 97 8932
TEST_TIME 98 8938
TEST_TIME 99 8966
TEST_TIME 100 8986
TEST_TIME 101 8963
TEST_TIME 102 9026
TEST_TIME 103 9090
TEST_TIME 104 9085
TEST_TIME 105 9154
TEST_TIME 106 9131
TEST_TIME 107 9127
TEST_TIME 108 9177
TEST_TIME 109 9183
TEST_TIME 110 9120
TEST_TIME 111 9061
TEST_TIME 112 9062
TEST_TIME 113 9053
TEST_TIME 114 9002
TEST_TIME 115 9017
TEST_TIME 116 9004
TEST_TIME 117 8956
TEST_TIME 118 9006
TEST_TIME 119 8987
TEST_TIME 120 9007

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-11-2021 01:39:24 to 17-11-2021 01:39:27.

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 1498770 1498770 0 0.00 0 16071 6 0 0
doc_list_biud 1498770 1498770 0 0.00 0 36884 27 0 0
sp_add_invoice_to_stock 4950 4876 74 1.49 23 146609 16532 0 74
sp_cancel_adding_invoice 5565 5565 0 0.00 2 67284 711 0 0
sp_cancel_client_order 1690 1690 0 0.00 5 23186 353 0 0
sp_cancel_customer_reserve 544756 544756 0 0.00 1 31829 58 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 5842 77 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 10687 113 0 0
sp_cancel_supplier_invoice 6146 6140 6 0.09 2 28997 803 0 6
sp_cancel_supplier_order 418 418 0 0.00 5 834164 95476 0 0
sp_cancel_write_off 9815 9815 0 0.00 1 10303 111 0 0
sp_client_order 19878 19878 0 0.00 3 34670 398 0 0
sp_customer_reserve 452047 452047 0 0.00 0 49229 163 0 0
sp_fill_shopping_cart 30557 30557 0 0.00 1 14126 39 0 0
sp_fill_shopping_cart_clo_res 452039 452039 0 0.00 0 14869 14 0 0
sp_get_clo_for_invoice 4868 4868 0 0.00 0 18474 332 0 0
sp_kill_cost_storno 16474 16474 0 0.00 0 7692 52 0 0
sp_kill_qstorno_ret_qs2qd 1105885 1105885 0 0.00 0 31815 31 0 0
sp_kill_qty_storno 583011 583011 0 0.00 0 36882 68 0 0
sp_lock_selected_doc 20224 20224 0 0.00 0 7118 4 0 0
sp_make_cost_storno 27114 27114 0 0.00 0 16070 46 0 0
sp_multiply_rows_for_pdistr 27114 27114 0 0.00 0 6879 27 0 0
sp_multiply_rows_for_qdistr 19878 19878 0 0.00 0 13284 171 0 0
sp_pay_from_customer 6858 6858 0 0.00 4 21484 106 0 0
sp_pay_to_supplier 5629 5629 0 0.00 5 16076 117 0 0
sp_payment_common 12487 12487 0 0.00 1 21407 24 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 23153 319 0 0
sp_qd_handle_on_invoice_upd_sts 10433 10433 0 0.00 0 36880 321 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 22988 66 0 0
sp_reserve_write_off 9759 9759 0 0.00 6 23165 178 0 0
sp_supplier_invoice 5302 5302 0 0.00 6 93516 3376 0 0
sp_supplier_order 5401 5401 0 0.00 5 55503 1379 0 0
srv_aggregate_perf_data 93 93 0 0.00 2900 302811 19709 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2625 193451 49721 0 0
srv_find_qd_qs_mism 1034113 1034113 0 0.00 0 13672 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 40 36917 965 0 0
srv_make_money_saldo 768 768 0 0.00 1 1352 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 187296 424545 347423 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4876 4876 0 0.00 0 14105 159 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 1734 53 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 3021 13 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 17451 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 199 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3297 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1790 17 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26066 1535 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 4618 10 0 0
t$perf-norm:sp_client_order 19878 19878 0 0.00 0 14089 16 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 571 3 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 11137 13 0 0
t$perf-norm:sp_pay_to_supplier 5629 5629 0 0.00 0 4134 11 0 0
t$perf-norm:sp_reserve_write_off 9759 9759 0 0.00 0 4486 8 0 0
t$perf-norm:sp_supplier_invoice 5302 5302 0 0.00 0 8230 18 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 17466 20 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 85 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 6410 108 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 4145 32 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 1242 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9884 9736 148 1.49 0 5974 18 0 148
v_all_customers 36020 36020 0 0.00 0 101 0 0 0
v_all_suppliers 29598 29598 0 0.00 0 11 0 0 0
v_all_wares 39756 39756 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 2131 10 0 0
v_cancel_client_order 3380 3380 0 0.00 0 4054 10 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2918 9 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 3127 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 9754 24 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 6051 27 0 12
v_cancel_supplier_order 836 836 0 0.00 0 3726 10 0 0
v_cancel_write_off 1918 1918 0 0.00 0 9390 22 0 0
v_max_id_clo_ord 5393 5393 0 0.00 0 403 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 2366 9 0 0
v_max_id_ord_sup 5286 5286 0 0.00 0 4458 3 0 0
v_max_non_paid_invoice 1509 1509 0 0.00 0 254 2 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 6175 5 0 0
v_min_id_clo_ord 5393 5393 0 0.00 0 4764 6 0 0
v_min_id_clo_res 992 992 0 0.00 0 9430 13 0 0
v_min_id_ord_sup 5286 5286 0 0.00 0 7886 8 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 8716 16 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 21383 20 0 0
v_our_firm 9980 9980 0 0.00 0 16 1 0 0
v_reserve_write_off 19518 19518 0 0.00 0 6461 8 0 0
x_lock_depdocs_on_canc_invoice 11322 11322 0 0.00 0 10882 113 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 9801 354 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 2 55486 1303 0 0
x_make_qstorno_1200_2000 5302 5302 0 0.00 2 86018 3236 0 0
x_make_qstorno_2100_3300 452047 452047 0 0.00 0 49228 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 01:39:28 to 17-11-2021 01:39:32.

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
23:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 30446 8192000000 9901766656 10214252544 978894912 10.673 100 84 168 0 1007025792 6401344 21729536
23:10:35 12060 8192000000 9940797792 10262417408 1022591680 11.097 100 92 184 0 1055468672 8767088 24109904
23:11:54 16652 8192000000 9975128272 10306641920 1058444704 11.442 100 89 178 1 1090977776 10130784 22402288
23:13:14 14751 8192000000 10023682848 10369253376 1108797504 11.921 100 82 164 2 1139939216 11567984 19573728
23:14:32 10781 8192000000 10064454432 10427072512 1157575600 12.381 100 74 148 0 1186421984 11708048 17138336
23:15:44 3808 8192000000 10119417984 10486636544 1209003648 12.860 100 78 154 0 1238824320 12549616 17271056
23:16:50 3571 8192000000 10154382624 10533011456 1241480976 13.160 100 71 142 1 1269881008 12428832 15971200
23:17:55 1611 8192000000 10201387600 10583703552 1281313424 13.525 100 93 186 1 1318739568 15667824 21758320
23:18:57 16197 8192000000 10217276352 10605731840 1298866752 13.685 100 75 149 2 1326277984 9829488 17581744
23:20:14 9455 8192000000 10198779552 10610331648 1314399296 13.826 100 25 50 1 1325944080 5952848 5591936
23:21:26 26557 8192000000 10203723408 10622824448 1321122736 13.887 100 12 24 0 1329407392 5651392 2633264
23:22:58 14234 8192000000 10214087520 10637365248 1325574368 13.927 100 29 58 2 1339256544 6807392 6874784
23:24:13 3922 8192000000 10222349744 10641752064 1328886576 13.957 100 32 65 2 1342737504 6223952 7626976
23:25:19 7251 8192000000 10225431392 10645569536 1331066544 13.977 100 19 38 2 1341570496 6241632 4262320
23:26:28 4786 8192000000 10214718896 10633027584 1302186144 13.715 98 21 42 1 1312560080 5373344 5000592
23:27:39 31227 8192000000 10217096480 10625605632 1308718272 13.774 99 47 95 1 1325750528 5685312 11346944
23:29:13 7562 8192000000 10144144496 10537213952 1191588400 12.698 93 23 46 0 1202633840 5100256 5945184
23:30:21 4363 8192000000 10121193984 10513006592 1211027376 12.879 98 32 63 2 1224177936 6168288 6982272
23:31:29 7648 8192000000 10134177872 10537750528 1244978480 13.192 100 30 60 1 1258000352 5746352 7275520
23:32:45 36691 8192000000 10146620880 10554658816 1237536560 13.124 98 32 62 0 1251364720 5977056 7851104
23:34:22 6842 8192000000 10181607008 10590527488 1283923728 13.549 100 24 48 1 1295155232 5546448 5685056
23:35:30 6983 8192000000 10187120752 10599972864 1298339920 13.680 100 24 47 2 1310454656 5421920 6692816
23:36:40 10515 8192000000 10193885952 10609008640 1303813104 13.730 100 20 40 2 1314256064 5309792 5133168
23:37:53 43936 8192000000 10220583840 10633601024 1320578912 13.882 100 34 68 1 1333081200 4678896 7823392
23:39:43 17753 8192000000 10254257744 10664120320 1348200320 14.131 100 53 106 0 1372289568 12150048 11939200
23:41:12 35425 8192000000 10273963456 10692096000 1365619696 14.288 100 45 88 5 1391503904 15075760 10808448
23:42:50 23619 8192000000 10293113616 10713206784 1379142432 14.409 100 45 87 1 1405225744 16157472 9925840
23:44:17 3920 8192000000 10298791712 10709303296 1372004064 14.345 99 79 158 3 1410555376 19264960 19286352
23:45:29 7420 8192000000 10317044368 10737623040 1393173920 14.534 100 52 104 1 1426766944 20861504 12731520
23:46:40 3070 8192000000 10344399792 10759544832 1407185200 14.659 100 73 145 2 1448322112 23935040 17201872
23:47:47 3563 8192000000 10330281392 10749509632 1400107648 14.596 100 62 124 4 1439914736 24002864 15804224
23:48:57 11182 8192000000 10330949168 10743726080 1391204064 14.517 99 75 147 1 1435580496 26716464 17659968
23:50:23 21175 8192000000 10347675296 10772467712 1409796240 14.682 100 52 104 3 1457216416 33815616 13604560
23:51:49 3974 8192000000 10346176608 10759733248 1390808480 14.513 99 71 143 1 1447937408 38160176 18968752
23:52:57 8862 8192000000 10326340512 10742935552 1367735712 14.307 99 51 102 0 1422333952 40821632 13776608
23:54:18 2622 8192000000 10338562400 10752909312 1385337136 14.464 100 61 123 0 1440699616 39294912 16067568
23:55:27 15807 8192000000 10312153024 10732867584 1349997104 14.147 98 40 80 2 1396733808 35858144 10878560
23:56:46 33189 8192000000 10322300128 10751733760 1379200320 14.409 100 37 74 2 1430065824 40721120 10144384
23:58:26 23286 8192000000 10305485664 10733752320 1339512224 14.053 98 32 64 1 1391736816 43391664 8832928
23:59:51 19885 8192000000 10293216464 10721255424 1343743680 14.091 99 29 58 2 1391636592 39883840 8009072
00:01:13 25339 8192000000 10286769744 10726174720 1356644576 14.207 100 25 50 2 1409456912 45209072 7603264
00:02:46 26307 8192000000 10289458656 10715881472 1320701808 13.883 98 21 42 0 1377712480 50670720 6339952
00:04:15 15659 8192000000 10249672080 10694168576 1338448704 14.043 100 22 42 1 1361712848 18447920 4816224
00:05:34 59480 8192000000 10237219168 10676817920 1292164064 13.624 96 24 48 1 1315692736 18076320 5452352
00:07:36 59935 8192000000 10239148176 10675748864 1332225088 13.987 100 45 90 0 1353265792 10253408 10787296
00:09:55 68795 8192000000 10253006320 10692186112 1322964416 13.904 98 19 38 0 1334792992 7548912 4279664
00:12:04 35765 8192000000 10256995168 10683539456 1338601728 14.045 99 28 56 1 1351008368 6287824 6118816
00:13:43 34742 8192000000 10257918224 10699198464 1351746080 14.163 100 11 23 0 1365263216 10832272 2684864
00:15:18 89305 8192000000 10278074208 10724007936 1364367408 14.277 99 28 55 1 1382104480 11472288 6264784
00:17:56 90292 8192000000 10276061856 10728378368 1372736608 14.352 100 37 74 1 1395421520 13782384 8902528
00:20:42 4468 8192000000 10300285088 10736537600 1384989328 14.461 100 48 95 1 1402923840 7263696 10670816
00:21:48 49669 8192000000 10282421696 10748325888 1388456768 14.492 100 18 36 0 1408777408 15633952 4686688
00:23:40 68877 8192000000 10286758320 10738929664 1388165984 14.489 100 12 25 1 1401007808 10304624 2537200
00:25:51 75493 8192000000 10279288576 10748772352 1387639184 14.485 100 6 12 0 1398753680 9535520 1578976
00:29:05 44638 8192000000 10309092736 10778013696 1400053536 14.595 100 9 18 2 1413872048 11381360 2437152
00:30:49 74426 8192000000 10306249536 10763132928 1403622080 14.627 100 25 51 0 1420520400 11224704 5673616
00:33:06 41610 8192000000 10322966288 10773090304 1395551264 14.555 99 32 64 0 1415060416 12574592 6934560
00:35:45 86417 8192000000 10319986928 10780405760 1392481440 14.528 100 14 28 0 1419650368 24054912 3114016
00:38:12 193451 8192000000 10298808272 10760048640 1304421952 13.735 93 11 22 1 1323181344 16652336 2107056
00:42:26 91248 8192000000 10282492336 10737008640 1355600128 14.198 97 28 56 1 1373455600 11269424 6586048
00:45:25 92364 8192000000 10279955600 10761129984 1353443456 14.178 98 7 14 0 1371170560 16374208 1352896
00:48:17 32120 8192000000 10277272528 10727481344 1360119904 14.238 99 35 71 0 1379286224 10455488 8710832
00:49:57 61315 8192000000 10266878352 10718277632 1320928224 13.885 96 13 25 0 1334832912 10800864 3103824
00:52:03 64894 8192000000 10254946304 10705350656 1307131552 13.760 96 30 60 0 1327569168 13710592 6727024
00:54:11 39531 8192000000 10239613520 10683478016 1299685088 13.692 97 24 48 0 1316228720 10698496 5845136
00:55:51 42326 8192000000 10255424800 10689687552 1302571536 13.719 97 25 50 0 1319758864 11877584 5309744
00:57:34 35282 8192000000 10257300640 10695012352 1330622768 13.973 99 36 72 0 1357292816 18702560 7967488
00:59:20 75340 8192000000 10250827392 10697154560 1319604656 13.873 98 11 22 1 1334861984 12104208 3153120
01:01:35 123796 8192000000 10263968288 10702471168 1319643456 13.873 97 31 62 3 1337012960 10176752 7192752
01:04:40 44221 8192000000 10264368032 10715553792 1347972368 14.129 99 18 36 0 1372783696 20673168 4138160
01:06:28 56194 8192000000 10253006528 10707750912 1351374304 14.160 99 8 16 0 1359367488 6223696 1769488
01:08:27 63439 8192000000 10275050736 10719158272 1341093248 14.067 97 15 30 0 1356089664 11453712 3542704
01:10:36 36049 8192000000 10268480304 10728153088 1376518208 14.385 100 10 20 1 1386908720 8083488 2307024
01:12:16 88850 8192000000 10291067280 10758299648 1376272496 14.383 99 13 24 1 1394093952 15394768 2426688
01:14:46 26299 8192000000 10294114480 10746957824 1392337648 14.527 100 37 74 2 1409883024 7347632 10197744
01:16:16 43826 8192000000 10302219344 10755080192 1388767712 14.495 99 39 78 2 1408876144 11249392 8859040
01:18:07 47685 8192000000 10325168976 10775482368 1404565664 14.636 100 25 50 1 1425137216 14944224 5627328
01:20:18 78885 8192000000 10326159264 10771582976 1405586656 14.645 100 15 29 1 1421528928 12394880 3547392
01:22:38 106511 8192000000 10303444352 10750251008 1324630096 13.919 94 16 33 0 1340703968 12486704 3587168
01:25:26 107912 8192000000 10287034736 10752327680 1375380000 14.375 99 17 34 1 1403194512 23986096 3828416
01:28:27 38262 8192000000 10281036256 10733936640 1358791984 14.227 98 22 44 0 1374096176 9848272 5455920
01:30:10 68820 8192000000 10285530336 10747650048 1387349776 14.482 100 7 14 0 1401618688 12537024 1731888
01:32:26 66949 8192000000 10294524256 10755641344 1382236144 14.437 99 12 24 2 1395609344 11038768 2334432
01:34:36 58081 8192000000 10266181104 10725421056 1320998256 13.886 96 15 30 0 1344284960 20206176 3080528
01:36:38 69083 8192000000 10277846544 10739281920 1344379408 14.097 98 10 19 0 1360053088 13405008 2268672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2021 01:39:34 to 17-11-2021 01:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2021 01:39:49 to 17-11-2021 01:39:51.

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          235503
MON$OLDEST_ACTIVE               236791
MON$OLDEST_SNAPSHOT             236791
MON$NEXT_TRANSACTION            236791
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                       2010176
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:465: 
:466: mscs-test-161.budzdorov.ru    Wed Nov 17 01:38:01 2021
:467:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:468: 
:469: 
:470: mscs-test-161.budzdorov.ru    Wed Nov 17 01:38:06 2021
:471:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:472: 
--- end of diff output ---

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

17.11.21 01:39:52. 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-11-17 01:39:55 No severe PSQL-related problems occured
17.11.21 01:39:56. End of report.