Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.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 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 16185272 kB
MemAvailable 30835164 kB
Buffers 0 kB
Cached 15484748 kB
SwapCached 388 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-12 01:37:48
DTS_END                         2021-09-12 01:37:48.7890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40484
TRN_ID                          247715
ATT_ID                          701
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: 9404.14 21075 1128497
customer order: creation 179.34 326 21521
customer order: refuse 15.31 342 1837
order to supplier: creation 48.53 1158 5823
order to supplier: removal 3.59 113785 431
invoice (draft): creation 47.40 3014 5688
invoice (draft): removal 53.53 864 6423
invoice accept: apply 42.65 17067 5118
invoice accept: cancel 48.51 836 5821
customer reserve: creation 3965.33 167 475840
customer reserve: removal 4693.62 67 563234
realization accept: apply 87.63 178 10515
realization accept: cancel 83.76 133 10051
payment from customer: creation 61.39 143 7367
payment from customer: removal 6.08 86 730
payment to supplier: creation 51.47 154 6176
payment to supplier: removal 3.48 73 418
service: total inventory turnovers 5.00 967 600
service: total monetary turnovers 6.68 19 801
service: aggregate perf. data 0.83 18454 99
service: refresh index statistics 0.03 263669 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-09-2021 01:38:44 to 12-09-2021 01:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1274
TEST_TIME 2 1266
TEST_TIME 3 2993
TEST_TIME 4 3083
TEST_TIME 5 3798
TEST_TIME 6 3787
TEST_TIME 7 4333
TEST_TIME 8 4324
TEST_TIME 9 4262
TEST_TIME 10 4408
TEST_TIME 11 4424
TEST_TIME 12 4570
TEST_TIME 13 4733
TEST_TIME 14 4888
TEST_TIME 15 5004
TEST_TIME 16 5364
TEST_TIME 17 5399
TEST_TIME 18 5598
TEST_TIME 19 6382
TEST_TIME 20 6821
TEST_TIME 21 7889
TEST_TIME 22 8065
TEST_TIME 23 8172
TEST_TIME 24 8460
TEST_TIME 25 9621
TEST_TIME 26 9928
TEST_TIME 27 10733
TEST_TIME 28 10617
TEST_TIME 29 11037
TEST_TIME 30 10790
TEST_TIME 31 10661
TEST_TIME 32 10386
TEST_TIME 33 10305
TEST_TIME 34 10200
TEST_TIME 35 10194
TEST_TIME 36 10010
TEST_TIME 37 10122
TEST_TIME 38 10052
TEST_TIME 39 10027
TEST_TIME 40 10037
TEST_TIME 41 9922
TEST_TIME 42 9989
TEST_TIME 43 10015
TEST_TIME 44 9814
TEST_TIME 45 9795
TEST_TIME 46 9653
TEST_TIME 47 9760
TEST_TIME 48 9779
TEST_TIME 49 9708
TEST_TIME 50 9665
TEST_TIME 51 9724
TEST_TIME 52 9700
TEST_TIME 53 9666
TEST_TIME 54 9735
TEST_TIME 55 9803
TEST_TIME 56 9797
TEST_TIME 57 9847
TEST_TIME 58 9824
TEST_TIME 59 9849
TEST_TIME 60 9906
TEST_TIME 61 9823
TEST_TIME 62 9728
TEST_TIME 63 9712
TEST_TIME 64 9633
TEST_TIME 65 9585
TEST_TIME 66 9538
TEST_TIME 67 9436
TEST_TIME 68 9611
TEST_TIME 69 9570
TEST_TIME 70 9550
TEST_TIME 71 9498
TEST_TIME 72 9433
TEST_TIME 73 9500
TEST_TIME 74 9565
TEST_TIME 75 9527
TEST_TIME 76 9603
TEST_TIME 77 9646
TEST_TIME 78 9698
TEST_TIME 79 9650
TEST_TIME 80 9667
TEST_TIME 81 9579
TEST_TIME 82 9572
TEST_TIME 83 9552
TEST_TIME 84 9501
TEST_TIME 85 9421
TEST_TIME 86 9405
TEST_TIME 87 9404
TEST_TIME 88 9372
TEST_TIME 89 9357
TEST_TIME 90 9378
TEST_TIME 91 9361
TEST_TIME 92 9377
TEST_TIME 93 9388
TEST_TIME 94 9452
TEST_TIME 95 9452
TEST_TIME 96 9450
TEST_TIME 97 9504
TEST_TIME 98 9497
TEST_TIME 99 9482
TEST_TIME 100 9536
TEST_TIME 101 9530
TEST_TIME 102 9599
TEST_TIME 103 9617
TEST_TIME 104 9539
TEST_TIME 105 9506
TEST_TIME 106 9484
TEST_TIME 107 9496
TEST_TIME 108 9454
TEST_TIME 109 9426
TEST_TIME 110 9422
TEST_TIME 111 9355
TEST_TIME 112 9330
TEST_TIME 113 9350
TEST_TIME 114 9355
TEST_TIME 115 9352
TEST_TIME 116 9375
TEST_TIME 117 9342
TEST_TIME 118 9357
TEST_TIME 119 9424
TEST_TIME 120 9407

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-09-2021 01:38:48 to 12-09-2021 01:38:52.

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 1569319 1569319 0 0.00 0 23509 6 0 0
doc_list_biud 1569318 1569318 0 0.00 0 20263 30 0 0
sp_add_invoice_to_stock 5312 5118 194 3.65 68 179237 17067 0 194
sp_cancel_adding_invoice 5821 5821 0 0.00 5 68329 836 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 11101 343 0 0
sp_cancel_customer_reserve 563234 563234 0 0.00 1 20266 68 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 9623 87 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 5562 74 0 0
sp_cancel_supplier_invoice 6433 6423 10 0.15 2 13683 864 0 10
sp_cancel_supplier_order 431 431 0 0.00 6 1185430 113785 0 0
sp_cancel_write_off 10051 10051 0 0.00 1 19921 133 0 0
sp_client_order 21521 21521 0 0.00 3 12836 327 0 0
sp_customer_reserve 475840 475840 0 0.00 0 97273 167 0 0
sp_fill_shopping_cart 33031 33031 0 0.00 1 13217 33 0 0
sp_fill_shopping_cart_clo_res 475837 475837 0 0.00 0 16436 15 0 0
sp_get_clo_for_invoice 5115 5115 0 0.00 0 42293 308 0 0
sp_kill_cost_storno 17020 17020 0 0.00 0 9829 69 0 0
sp_kill_qstorno_ret_qs2qd 1143373 1143373 0 0.00 0 20253 37 0 0
sp_kill_qty_storno 603427 603427 0 0.00 0 20263 78 0 0
sp_lock_selected_doc 21675 21675 0 0.00 0 7762 5 0 0
sp_make_cost_storno 29173 29173 0 0.00 0 15476 55 0 0
sp_multiply_rows_for_pdistr 29173 29173 0 0.00 0 20765 32 0 0
sp_multiply_rows_for_qdistr 21521 21521 0 0.00 0 12530 155 0 0
sp_pay_from_customer 7367 7367 0 0.00 4 22804 143 0 0
sp_pay_to_supplier 6176 6176 0 0.00 5 23250 155 0 0
sp_payment_common 13543 13543 0 0.00 1 21112 45 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 9902 307 0 0
sp_qd_handle_on_invoice_upd_sts 10936 10936 0 0.00 0 19060 342 0 0
sp_qd_handle_on_reserve_upd_sts 10515 10515 0 0.00 0 15222 58 0 0
sp_reserve_write_off 10515 10515 0 0.00 6 17260 178 0 0
sp_supplier_invoice 5688 5688 0 0.00 6 88866 3015 0 0
sp_supplier_order 5823 5823 0 0.00 5 34593 1158 0 0
srv_aggregate_perf_data 99 99 0 0.00 3031 175682 18455 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1569 169464 46591 0 0
srv_find_qd_qs_mism 1079029 1079029 0 0.00 0 21949 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 26 29889 968 0 0
srv_make_money_saldo 801 801 0 0.00 1 490 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 70215 402440 263670 0 0
t$perf-abend:sp_add_invoice_to_stock 194 194 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5118 5118 0 0.00 0 10738 179 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 5316 98 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 1127 9 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 1978 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 2187 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1275 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 5342 19 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 35278 1966 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 1210 6 0 0
t$perf-norm:sp_client_order 21521 21521 0 0.00 0 11810 21 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 5014 15 0 0
t$perf-norm:sp_pay_from_customer 7367 7367 0 0.00 0 8461 15 0 0
t$perf-norm:sp_pay_to_supplier 6176 6176 0 0.00 0 7739 11 0 0
t$perf-norm:sp_reserve_write_off 10515 10515 0 0.00 0 13319 18 0 0
t$perf-norm:sp_supplier_invoice 5688 5688 0 0.00 0 10938 22 0 0
t$perf-norm:sp_supplier_order 5823 5823 0 0.00 0 6275 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 224 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4752 84 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 3073 30 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 2713 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 869 221 0 0
v_add_invoice_to_stock 10618 10230 388 3.65 0 16459 23 0 388
v_all_customers 39060 39060 0 0.00 0 15 1 0 0
v_all_suppliers 32550 32550 0 0.00 0 29 1 0 0
v_all_wares 43042 43042 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 2864 16 0 0
v_cancel_client_order 3674 3674 0 0.00 0 3316 6 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 2508 8 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 7550 16 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 2043 8 0 0
v_cancel_supplier_invoice 898 878 20 2.22 0 3600 26 0 20
v_cancel_supplier_order 862 862 0 0.00 0 1169 5 0 0
v_cancel_write_off 2042 2042 0 0.00 0 3466 15 0 0
v_max_id_clo_ord 5823 5823 0 0.00 0 5937 2 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 1243 9 0 0
v_max_id_ord_sup 5687 5687 0 0.00 0 6433 6 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 2043 5 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 6230 8 0 0
v_min_id_clo_ord 5823 5823 0 0.00 0 2887 6 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 5393 9 0 0
v_min_id_ord_sup 5687 5687 0 0.00 0 4546 10 0 0
v_min_non_paid_invoice 6176 6176 0 0.00 0 20457 30 0 0
v_min_non_paid_realizn 7367 7367 0 0.00 0 20788 31 0 0
v_our_firm 10848 10848 0 0.00 0 26 1 0 0
v_reserve_write_off 21030 21030 0 0.00 0 8659 7 0 0
x_lock_depdocs_on_canc_invoice 11805 11805 0 0.00 0 39526 126 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 3887 343 0 0
x_make_qstorno_1000_1200 5823 5823 0 0.00 1 34573 1088 0 0
x_make_qstorno_1200_2000 5688 5688 0 0.00 2 79845 2900 0 0
x_make_qstorno_2100_3300 475840 475840 0 0.00 0 97272 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 01:38:53 to 12-09-2021 01:38:57.

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:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 38562 8192000000 10402506736 10724261888 1447611248 15.017 100 93 186 0 1484109696 6483808 30014640
23:10:36 29056 8192000000 10471391104 10807689216 1511918464 15.580 100 83 166 0 1547091248 8983552 26189232
23:12:08 15650 8192000000 10501929856 10847260672 1556483936 15.966 100 82 164 0 1592217456 10311856 25421664
23:13:36 14263 8192000000 10585815568 10946715648 1641745184 16.695 100 77 154 0 1675575088 10813552 23016352
23:14:54 10693 8192000000 10657045600 11029819392 1716895696 17.326 100 76 153 3 1750089856 11961536 21232624
23:16:09 3855 8192000000 10723755824 11106574336 1783416864 17.878 100 75 150 1 1814826176 10466480 20942832
23:17:15 2644 8192000000 10775643200 11157209088 1826308336 18.229 100 92 184 0 1866862576 14971536 25582704
23:18:19 2029 8192000000 10813835200 11201744896 1862651792 18.525 100 85 170 1 1898675472 12170832 23852848
23:19:23 2595 8192000000 10807655600 11206197248 1872443728 18.604 100 59 118 2 1899981920 10974416 16563776
23:20:26 3902 8192000000 10795204752 11212382208 1878904672 18.656 100 41 82 1 1899027872 8895664 11227536
23:21:32 10825 8192000000 10803726432 11227860992 1890374672 18.749 100 21 43 0 1902485472 5998368 6112432
23:22:44 13113 8192000000 10795496576 11234922496 1902658912 18.848 100 23 46 2 1914681888 5573136 6449840
23:24:01 39595 8192000000 10821880592 11253264384 1904767232 18.865 100 28 55 2 1919243424 6450112 8026080
23:25:44 7426 8192000000 10838768112 11271892992 1917979328 18.971 100 34 68 1 1934390032 6100720 10309984
23:26:52 11204 8192000000 10833223216 11266867200 1915912192 18.954 100 19 38 0 1926921056 5426848 5582016
23:28:04 46797 8192000000 10757237872 11183083520 1771965040 17.783 94 24 48 3 1783621440 4898976 6757424
23:30:05 5332 8192000000 10726507680 11152592896 1815430352 18.140 100 31 62 0 1834854128 10599232 8824544
23:31:11 8984 8192000000 10724742208 11147350016 1768911824 17.758 97 29 58 1 1783417728 6244688 8261216
23:32:20 7984 8192000000 10739925968 11160641536 1811830640 18.111 99 23 46 0 1828907232 9696752 7379840
23:33:29 52277 8192000000 10756833648 11190140928 1838792896 18.331 99 24 47 0 1850742304 5368336 6581072
23:35:23 10051 8192000000 10798416720 11223851008 1876465040 18.637 100 34 68 2 1892241696 5831376 9945280
23:36:36 12958 8192000000 10798921664 11230625792 1888546592 18.734 100 26 52 0 1902104784 5718416 7839776
23:37:50 23477 8192000000 10857560208 11278802944 1925945360 19.034 100 33 64 2 1940005216 4965392 9094464
23:39:56 53573 8192000000 10876637936 11314278400 1945787200 19.193 100 40 79 3 1972799248 14876000 12136048
23:41:55 52217 8192000000 10898692880 11339862016 1967345808 19.364 100 26 51 1 1991457280 16833664 7277808
23:44:12 17269 8192000000 10940677936 11376873472 1992789008 19.566 100 48 96 0 2030487296 23418832 14279456
23:45:39 7188 8192000000 10962557968 11403403264 2016998896 19.757 100 49 98 2 2055804304 23772384 15033024
23:46:52 8001 8192000000 10980854800 11410395136 2017684768 19.762 100 60 120 0 2064316144 28899968 17731408
23:48:15 12601 8192000000 10994872144 11435065344 2036150288 19.907 100 53 106 0 2087106096 34282096 16673712
23:49:33 3119 8192000000 11035223376 11457282048 2041072240 19.945 99 76 150 2 2103248720 39259360 22917120
23:50:45 4888 8192000000 11034493024 11466190848 2053337072 20.041 100 62 124 0 2117375744 44178976 19859696
23:51:56 2259 8192000000 11055810272 11478167552 2064792304 20.130 100 93 183 0 2142714400 49035936 28886160
23:53:03 3669 8192000000 11053765984 11482939392 2057717456 20.075 100 71 143 2 2136342720 55891456 22733808
23:54:13 4009 8192000000 11024749248 11441483776 2013158144 19.726 99 85 170 0 2102411440 61474816 27778480
23:55:28 5061 8192000000 11035039552 11454070784 2017466944 19.760 100 82 163 3 2117265120 72047152 27751024
23:56:39 3294 8192000000 11027804048 11448012800 2009479232 19.697 100 83 166 1 2111526560 74983728 27063600
23:57:49 1566 8192000000 11050435024 11471044608 2019885712 19.779 100 86 170 1 2127507584 80206768 27415104
23:58:53 20405 8192000000 11004678432 11468558336 2020838816 19.787 100 40 80 1 2100852080 66737792 13275472
00:00:33 25561 8192000000 11007711424 11467431936 2012495568 19.721 100 33 66 0 2089752656 65325616 11931472
00:02:06 21254 8192000000 10967709088 11439947776 1979933264 19.464 100 22 43 3 2052737264 64995600 7808400
00:03:40 62302 8192000000 10900018816 11371249664 1942873616 19.170 98 13 26 0 1986021680 38687872 4460192
00:05:43 39028 8192000000 10877714944 11351265280 1896464112 18.798 97 15 30 1 1928138336 26745744 4928480
00:07:29 169463 8192000000 10858417328 11314548736 1809173920 18.089 92 24 48 0 1827096544 11541872 6380752
00:11:27 25040 8192000000 10900110480 11349757952 1954592272 19.263 100 53 105 0 1982292080 13713264 13986544
00:12:58 33720 8192000000 10829134736 11278426112 1882004976 18.681 97 11 22 0 1894973744 10336416 2632352
00:14:42 12813 8192000000 10856504032 11301310464 1905095792 18.867 99 39 77 1 1924188912 8966608 10126512
00:15:59 73035 8192000000 10881912976 11336863744 1914878864 18.946 98 22 43 0 1935076560 13799216 6398480
00:18:19 129069 8192000000 10878586560 11331223552 1916587584 18.959 99 23 46 0 1939521456 16277776 6656096
00:21:42 52457 8192000000 10854750000 11324112896 1911432064 18.918 99 10 20 0 1929850576 15579680 2838832
00:23:34 50501 8192000000 10853291824 11318304768 1923394416 19.014 99 18 36 0 1938936896 10773936 4768544
00:25:26 60419 8192000000 10870752320 11355717632 1931315552 19.077 99 7 14 0 1944732912 11598432 1818928
00:27:27 42311 8192000000 10891975008 11371003904 1970229168 19.387 100 28 56 1 1988477040 10736832 7511040
00:29:14 22682 8192000000 10925503936 11383005184 1988342384 19.531 100 50 101 2 2011661088 8371632 14947072
00:30:50 56212 8192000000 10907137584 11383095296 1963063296 19.330 99 14 28 1 1979499600 12462160 3974144
00:32:48 15160 8192000000 10927772560 11384639488 1993486752 19.571 100 57 112 4 2016758976 6974064 16298160
00:34:10 110666 8192000000 10864829920 11323113472 1825884384 18.226 92 21 43 1 1840932496 9437776 5610336
00:37:03 80491 8192000000 10894506832 11341930496 1831697168 18.273 93 17 34 1 1848256464 11867184 4692112
00:39:25 56731 8192000000 10896961824 11357843456 1960252512 19.308 100 36 72 0 1980466416 9854176 10359728
00:42:49 70149 8192000000 10896761136 11378511872 1962772544 19.328 99 13 26 0 1980794272 14047520 3974208
00:45:11 58927 8192000000 10888245184 11358949376 1932604208 19.088 98 13 26 0 1945180576 8978224 3598144
00:47:11 23627 8192000000 10909170288 11351756800 1926004656 19.035 98 44 88 0 1945583488 7764896 11813936
00:48:43 31047 8192000000 10901927760 11366359040 1938099056 19.132 98 22 44 1 1959152368 14409264 6644048
00:50:15 56332 8192000000 10874986912 11342213120 1894152432 18.779 97 21 42 2 1917458752 17052288 6254032
00:52:12 20313 8192000000 10912180448 11365064704 1967137072 19.363 100 43 87 2 1995701888 16708512 11856304
00:53:36 48259 8192000000 10879258512 11370471424 1942843744 19.169 99 15 30 0 1961041424 13385488 4812192
00:55:24 82418 8192000000 10880962352 11328053248 1810497664 18.100 92 22 44 1 1828130224 11826640 5805920
00:57:51 101408 8192000000 10833561328 11302260736 1857401424 18.482 97 21 42 0 1879921824 16280544 6239856
01:00:35 55625 8192000000 10826326528 11300876288 1852187312 18.440 96 17 34 0 1869411680 13001216 4223152
01:02:31 96476 8192000000 10834173776 11312443392 1863658432 18.533 97 7 14 0 1875492624 10082368 1751824
01:05:12 48049 8192000000 10833285424 11296354304 1838619264 18.330 95 16 29 0 1849534000 7353504 3561232
01:07:06 38315 8192000000 10865032272 11328380928 1904811712 18.865 98 47 94 1 1927710336 9634992 13263632
01:08:49 78524 8192000000 10875906048 11339743232 1915942496 18.954 98 20 40 1 1931970544 10595216 5432832
01:11:43 55444 8192000000 10900792208 11361415168 1960565712 19.311 100 16 32 0 1975544128 9730944 5247472
01:13:39 111471 8192000000 10896038192 11372843008 1940403472 19.150 98 20 40 0 1959772800 13611008 5758320
01:16:31 21484 8192000000 10921348288 11381063680 1977929056 19.448 100 32 64 3 1998890880 11986656 8975168
01:17:55 58524 8192000000 10925544176 11391225856 1955282160 19.269 99 12 24 0 1977547312 18585888 3679264
01:19:53 60919 8192000000 10902509616 11370217472 1899985536 18.826 96 7 14 1 1917843328 15695440 2162352
01:21:56 66654 8192000000 10870939552 11331850240 1876871088 18.640 95 24 48 2 1893944704 9832416 7241200
01:24:04 26514 8192000000 10857647712 11341488128 1900011648 18.826 97 15 30 0 1918013568 13784800 4217120
01:25:36 63424 8192000000 10868612416 11349835776 1939582672 19.143 99 12 25 0 1949110848 6823936 2704240
01:27:40 70523 8192000000 10889355088 11357347840 1960818832 19.313 100 9 16 1 1972788944 9352144 2617968
01:29:52 59854 8192000000 10857249616 11333636096 1894878400 18.785 96 13 26 0 1908932592 10802208 3251984
01:32:00 37519 8192000000 10847758192 11316969472 1839192608 18.334 94 24 48 2 1854486384 8243840 7049936
01:33:39 55237 8192000000 10890062240 11358449664 1922928096 19.010 98 23 46 0 1940993776 11942032 6123648
01:35:34 76639 8192000000 10881539136 11374620672 1939320656 19.141 99 12 24 1 1955085200 11881408 3883136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-09-2021 01:38:58 to 12-09-2021 01:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 194 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 01:39:14 to 12-09-2021 01:39:16.

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          246323
MON$OLDEST_ACTIVE               247824
MON$OLDEST_SNAPSHOT             247824
MON$NEXT_TRANSACTION            247824
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                       2018368
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Sep 12 01:37:58 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Sep 12 01:37:58 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

12.09.21 01:39:17. 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-12 01:39:20 No severe PSQL-related problems occured
12.09.21 01:39:21. End of report.