Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2021 22: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 15943720 kB
MemAvailable 30664332 kB
Buffers 0 kB
Cached 15733892 kB
SwapCached 3872 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-10-11 22:37:52
DTS_END                         2021-10-11 22:37:52.7100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53346
TRN_ID                          241880
ATT_ID                          705
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: 9501.97 19011 1140236
customer order: creation 175.92 361 21110
customer order: refuse 14.93 340 1791
order to supplier: creation 46.13 1212 5535
order to supplier: removal 3.58 90933 429
invoice (draft): creation 47.00 3242 5640
invoice (draft): removal 53.50 723 6420
invoice accept: apply 43.36 18154 5203
invoice accept: cancel 48.35 713 5802
customer reserve: creation 3985.18 182 478221
customer reserve: removal 4796.59 53 575591
realization accept: apply 83.92 186 10070
realization accept: cancel 72.03 120 8643
payment from customer: creation 60.43 126 7252
payment from customer: removal 5.93 82 711
payment to supplier: creation 48.58 139 5830
payment to supplier: removal 3.33 86 399
service: total inventory turnovers 5.12 961 614
service: total monetary turnovers 7.15 23 858
service: aggregate perf. data 0.94 20318 113
service: refresh index statistics 0.03 242274 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2021 22:38:37 to 11-10-2021 22:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2753
TEST_TIME 2 2496
TEST_TIME 3 3785
TEST_TIME 4 4064
TEST_TIME 5 4403
TEST_TIME 6 4547
TEST_TIME 7 4611
TEST_TIME 8 4845
TEST_TIME 9 4783
TEST_TIME 10 5516
TEST_TIME 11 5706
TEST_TIME 12 5856
TEST_TIME 13 5857
TEST_TIME 14 6034
TEST_TIME 15 6257
TEST_TIME 16 6491
TEST_TIME 17 6730
TEST_TIME 18 7592
TEST_TIME 19 7832
TEST_TIME 20 7940
TEST_TIME 21 8176
TEST_TIME 22 8916
TEST_TIME 23 9358
TEST_TIME 24 9572
TEST_TIME 25 10156
TEST_TIME 26 10475
TEST_TIME 27 10558
TEST_TIME 28 10399
TEST_TIME 29 10228
TEST_TIME 30 10225
TEST_TIME 31 10048
TEST_TIME 32 10122
TEST_TIME 33 10157
TEST_TIME 34 9975
TEST_TIME 35 9932
TEST_TIME 36 9802
TEST_TIME 37 9956
TEST_TIME 38 9833
TEST_TIME 39 9856
TEST_TIME 40 9784
TEST_TIME 41 9642
TEST_TIME 42 9463
TEST_TIME 43 9352
TEST_TIME 44 9547
TEST_TIME 45 9694
TEST_TIME 46 9703
TEST_TIME 47 9731
TEST_TIME 48 9715
TEST_TIME 49 9787
TEST_TIME 50 9714
TEST_TIME 51 9699
TEST_TIME 52 9719
TEST_TIME 53 9695
TEST_TIME 54 9782
TEST_TIME 55 9730
TEST_TIME 56 9801
TEST_TIME 57 9893
TEST_TIME 58 9760
TEST_TIME 59 9676
TEST_TIME 60 9641
TEST_TIME 61 9559
TEST_TIME 62 9580
TEST_TIME 63 9563
TEST_TIME 64 9520
TEST_TIME 65 9431
TEST_TIME 66 9332
TEST_TIME 67 9343
TEST_TIME 68 9345
TEST_TIME 69 9328
TEST_TIME 70 9405
TEST_TIME 71 9367
TEST_TIME 72 9438
TEST_TIME 73 9403
TEST_TIME 74 9490
TEST_TIME 75 9488
TEST_TIME 76 9461
TEST_TIME 77 9482
TEST_TIME 78 9503
TEST_TIME 79 9479
TEST_TIME 80 9465
TEST_TIME 81 9423
TEST_TIME 82 9391
TEST_TIME 83 9334
TEST_TIME 84 9316
TEST_TIME 85 9268
TEST_TIME 86 9237
TEST_TIME 87 9253
TEST_TIME 88 9297
TEST_TIME 89 9361
TEST_TIME 90 9387
TEST_TIME 91 9376
TEST_TIME 92 9439
TEST_TIME 93 9450
TEST_TIME 94 9502
TEST_TIME 95 9549
TEST_TIME 96 9597
TEST_TIME 97 9582
TEST_TIME 98 9537
TEST_TIME 99 9537
TEST_TIME 100 9478
TEST_TIME 101 9436
TEST_TIME 102 9454
TEST_TIME 103 9437
TEST_TIME 104 9427
TEST_TIME 105 9364
TEST_TIME 106 9352
TEST_TIME 107 9324
TEST_TIME 108 9289
TEST_TIME 109 9310
TEST_TIME 110 9359
TEST_TIME 111 9370
TEST_TIME 112 9372
TEST_TIME 113 9359
TEST_TIME 114 9386
TEST_TIME 115 9407
TEST_TIME 116 9449
TEST_TIME 117 9433
TEST_TIME 118 9508
TEST_TIME 119 9482
TEST_TIME 120 9504

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2021 22:38:41 to 11-10-2021 22:38:44.

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 1582311 1582311 0 0.00 0 21876 6 0 0
doc_list_biud 1582311 1582311 0 0.00 0 42195 25 0 0
sp_add_invoice_to_stock 5316 5203 113 2.12 54 203643 18154 0 113
sp_cancel_adding_invoice 5802 5802 0 0.00 3 62144 713 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 21867 341 0 0
sp_cancel_customer_reserve 575591 575591 0 0.00 1 25314 54 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 4592 83 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 4671 86 0 0
sp_cancel_supplier_invoice 6425 6420 5 0.07 2 16825 724 0 5
sp_cancel_supplier_order 429 429 0 0.00 5 1123333 90934 0 0
sp_cancel_write_off 8643 8643 0 0.00 1 22068 120 0 0
sp_client_order 21110 21110 0 0.00 3 21336 362 0 0
sp_customer_reserve 478221 478221 0 0.00 0 34606 182 0 0
sp_fill_shopping_cart 32267 32267 0 0.00 1 13311 37 0 0
sp_fill_shopping_cart_clo_res 478218 478218 0 0.00 0 15292 14 0 0
sp_get_clo_for_invoice 5200 5200 0 0.00 0 30159 285 0 0
sp_kill_cost_storno 15555 15555 0 0.00 0 13978 53 0 0
sp_kill_qstorno_ret_qs2qd 1166674 1166674 0 0.00 0 25281 29 0 0
sp_kill_qty_storno 613946 613946 0 0.00 0 42194 63 0 0
sp_lock_selected_doc 21171 21171 0 0.00 0 7738 6 0 0
sp_make_cost_storno 28352 28352 0 0.00 0 21864 56 0 0
sp_multiply_rows_for_pdistr 28352 28352 0 0.00 0 11809 28 0 0
sp_multiply_rows_for_qdistr 21110 21110 0 0.00 0 19310 166 0 0
sp_pay_from_customer 7252 7252 0 0.00 4 16747 127 0 0
sp_pay_to_supplier 5830 5830 0 0.00 5 18768 139 0 0
sp_payment_common 13082 13082 0 0.00 1 16709 33 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 21863 302 0 0
sp_qd_handle_on_invoice_upd_sts 11002 11002 0 0.00 0 42192 310 0 0
sp_qd_handle_on_reserve_upd_sts 10070 10070 0 0.00 0 13715 53 0 0
sp_reserve_write_off 10070 10070 0 0.00 6 14542 187 0 0
sp_supplier_invoice 5640 5640 0 0.00 6 95958 3242 0 0
sp_supplier_order 5535 5535 0 0.00 5 28604 1213 0 0
srv_aggregate_perf_data 113 113 0 0.00 3126 503437 20319 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1727 136179 46587 0 0
srv_find_qd_qs_mism 1091042 1091042 0 0.00 0 13230 6 0 0
srv_make_invnt_saldo 614 614 0 0.00 41 48000 962 0 0
srv_make_money_saldo 858 858 0 0.00 0 1978 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 57800 420155 242274 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5203 5203 0 0.00 0 23123 133 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 2770 75 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 5161 13 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 4098 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 1225 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 607 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1772 15 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 21254 1560 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 5152 12 0 0
t$perf-norm:sp_client_order 21110 21110 0 0.00 0 8599 16 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 1987 7 0 0
t$perf-norm:sp_pay_from_customer 7252 7252 0 0.00 0 4780 12 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 20037 13 0 0
t$perf-norm:sp_reserve_write_off 10070 10070 0 0.00 0 11270 10 0 0
t$perf-norm:sp_supplier_invoice 5640 5640 0 0.00 0 2106 11 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 2020 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 235 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 1310 14 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 2990 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 5 0 0
v_add_invoice_to_stock 10626 10400 226 2.12 0 7632 23 0 226
v_all_customers 38198 38198 0 0.00 0 78 0 0 0
v_all_suppliers 30528 30528 0 0.00 0 51 1 0 0
v_all_wares 42220 42220 0 0.00 0 77 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 447 6 0 0
v_cancel_client_order 3582 3582 0 0.00 0 7261 7 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 4569 20 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 3921 11 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 4619 17 0 0
v_cancel_supplier_invoice 820 810 10 1.21 0 4471 30 0 10
v_cancel_supplier_order 858 858 0 0.00 0 2310 7 0 0
v_cancel_write_off 2094 2094 0 0.00 0 4149 18 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 833 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 5949 27 0 0
v_max_id_ord_sup 5624 5624 0 0.00 0 7104 4 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 743 3 0 0
v_max_non_paid_realizn 3962 3962 0 0.00 0 6255 5 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 10007 10 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 6516 15 0 0
v_min_id_ord_sup 5624 5624 0 0.00 0 13302 14 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 10125 20 0 0
v_min_non_paid_realizn 7252 7252 0 0.00 0 16700 31 0 0
v_our_firm 10602 10602 0 0.00 0 10 1 0 0
v_reserve_write_off 20140 20140 0 0.00 0 7645 9 0 0
x_lock_depdocs_on_canc_invoice 11817 11817 0 0.00 0 16801 107 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 5081 294 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 1 28552 1137 0 0
x_make_qstorno_1200_2000 5640 5640 0 0.00 2 95943 3107 0 0
x_make_qstorno_2100_3300 478221 478221 0 0.00 0 34603 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2021 22:38:45 to 11-10-2021 22:38:49.

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
20:07:56 6 8192000000 8804886400 8818102272 10222128 0.124 3 2 3 0 10383920 77840 83952
20:08:57 14999 8192000000 9898054592 10207711232 980703168 10.691 100 87 174 0 1009526560 5619744 23203648
20:10:16 21446 8192000000 9937714192 10261909504 1019794144 11.070 100 87 174 0 1050200864 7700368 22706352
20:11:41 20302 8192000000 9975941552 10307416064 1058424640 11.441 100 86 172 0 1090162992 9869984 21868368
20:13:04 15041 8192000000 10027766864 10372816896 1111060112 11.942 100 81 162 0 1141543408 10875504 19607792
20:14:25 9642 8192000000 10080909184 10444492800 1168198288 12.480 100 78 156 0 1197934608 11851408 17884912
20:15:36 4432 8192000000 10129413792 10496778240 1215814672 12.923 100 82 161 0 1246256304 12936688 17504944
20:16:43 5221 8192000000 10142959456 10525458432 1237420096 13.122 100 62 124 0 1263077152 12138048 13519008
20:17:49 2012 8192000000 10202363488 10583957504 1285648464 13.565 100 93 187 0 1319621776 12451200 21522112
20:18:52 12954 8192000000 10206551424 10594611200 1295801152 13.657 100 66 132 2 1321737744 11861840 14074752
20:20:06 7633 8192000000 10197564928 10605600768 1305972352 13.750 100 31 62 0 1320301872 7566528 6762992
20:21:16 12211 8192000000 10210570160 10615803904 1315468592 13.836 100 32 61 0 1327682144 5121040 7092512
20:22:28 17268 8192000000 10206208160 10627416064 1320095936 13.878 100 30 60 0 1333366304 7069312 6201056
20:23:49 7233 8192000000 10215901472 10632581120 1323206096 13.906 100 37 74 0 1337682544 5903552 8572896
20:25:01 9011 8192000000 10219035024 10638921728 1327222048 13.942 100 22 44 1 1338001216 5805360 4973808
20:26:12 55978 8192000000 10220556352 10642214912 1319882912 13.876 99 31 62 1 1332837856 5123488 7831456
20:28:13 5205 8192000000 10205948592 10612899840 1284403072 13.553 98 29 58 1 1296928608 6541072 5984464
20:29:21 10036 8192000000 10160852000 10562981888 1228603936 13.041 96 20 40 0 1237993520 4593968 4795616
20:30:32 65568 8192000000 10128774096 10530185216 1169321728 12.490 93 14 27 0 1176945936 4375376 3248832
20:32:41 5379 8192000000 10155305712 10558132224 1245669984 13.198 98 29 59 0 1257870192 5783712 6416496
20:33:47 6182 8192000000 10171581232 10577219584 1276059632 13.477 100 18 36 0 1285919312 5721568 4138112
20:34:57 17636 8192000000 10182827536 10594111488 1292156720 13.624 100 29 58 2 1305593760 5978688 7458352
20:36:15 19253 8192000000 10180070144 10602319872 1298454016 13.681 100 26 52 1 1310625696 6524480 5647200
20:37:34 8554 8192000000 10213215072 10632331264 1324576928 13.918 100 13 27 1 1332253040 4599120 3076992
20:38:50 27346 8192000000 10259412544 10670764032 1352269744 14.168 100 44 89 1 1370379904 8324624 9785536
20:40:22 54148 8192000000 10268458560 10695647232 1368647840 14.315 100 33 66 1 1389770240 13449120 7673280
20:42:23 44680 8192000000 10291331472 10713108480 1373543840 14.359 99 41 82 0 1396614080 13640704 9429536
20:44:10 56290 8192000000 10312436448 10738548736 1395469984 14.555 100 46 92 1 1423732640 17252192 11010464
20:46:11 8837 8192000000 10321249936 10743873536 1396312128 14.562 100 57 111 2 1430138880 21009168 12817584
20:47:22 17417 8192000000 10322560240 10756427776 1387821200 14.486 98 41 81 0 1418840976 20937360 10082416
20:48:44 6072 8192000000 10338962608 10761605120 1399458960 14.590 99 54 107 1 1436283904 23880480 12944464
20:50:04 2686 8192000000 10366474080 10777071616 1418530272 14.760 99 80 156 2 1465281504 27435680 19315552
20:51:12 3554 8192000000 10361563744 10777260032 1414094912 14.720 100 78 156 1 1467236464 33702672 19438880
20:52:21 3277 8192000000 10361594880 10775715840 1410755168 14.691 100 86 172 0 1465860768 33727536 21378064
20:53:34 3683 8192000000 10366639536 10785587200 1419759136 14.771 100 83 165 1 1478119584 37966784 20393664
20:54:47 1725 8192000000 10370008144 10776076288 1397663504 14.574 100 79 159 0 1467005984 49194000 20148480
20:55:52 13923 8192000000 10312406112 10739109888 1367451184 14.304 98 37 73 3 1418603248 41738288 9413776
20:57:23 12007 8192000000 10322481664 10743676928 1359702432 14.235 99 45 90 0 1420305680 47625344 12977904
20:58:42 7109 8192000000 10354709760 10775748608 1384835776 14.460 100 72 144 1 1462474272 59072592 18565904
20:59:57 13846 8192000000 10304992048 10740371456 1360864816 14.245 100 32 64 0 1413585952 43931488 8789648
21:01:14 41820 8192000000 10291351120 10723282944 1354866608 14.191 99 24 48 1 1402380384 40670000 6843776
21:02:57 49159 8192000000 10268083360 10714398720 1329430064 13.962 98 13 26 0 1357222288 24284448 3507776
21:04:48 43828 8192000000 10275822688 10708017152 1357514368 14.215 100 25 50 1 1372324480 8138928 6671184
21:06:33 61857 8192000000 10255915440 10704941056 1334036208 14.004 98 23 46 0 1349938288 10282640 5619440
21:09:27 136179 8192000000 10264159024 10712330240 1330918944 13.975 97 7 14 1 1355871360 23654752 1297664
21:12:48 36346 8192000000 10289083488 10716712960 1350322400 14.150 97 38 76 1 1367575568 8098368 9154800
21:14:32 66088 8192000000 10278391920 10733654016 1379053872 14.408 100 16 31 0 1392641440 10291488 3296080
21:16:48 25643 8192000000 10276510720 10728431616 1378343984 14.402 100 15 30 1 1391966800 9981408 3641408
21:18:32 56209 8192000000 10275767648 10723954688 1366904336 14.299 98 11 21 1 1383743248 13974016 2864896
21:20:29 53019 8192000000 10299765680 10753904640 1379940640 14.416 99 16 32 1 1401266096 17483664 3841792
21:22:33 107758 8192000000 10290833360 10760806400 1379728608 14.414 99 11 22 0 1396203696 13971952 2503136
21:25:23 41410 8192000000 10280846480 10727727104 1361250880 14.249 98 28 57 1 1380742992 13446032 6046080
21:27:10 12987 8192000000 10307447568 10743676928 1385959936 14.470 100 55 110 1 1405652112 5734848 13957328
21:28:31 34267 8192000000 10302649600 10749014016 1388331536 14.491 100 28 56 0 1404735680 10555584 5848560
21:30:06 39125 8192000000 10292206112 10736549888 1352218544 14.167 97 30 60 0 1369188112 8979696 7989872
21:31:47 86449 8192000000 10310182512 10766733312 1374431744 14.367 99 14 28 0 1390628112 13235680 2960688
21:34:15 70164 8192000000 10291314224 10750279680 1371813008 14.343 99 12 24 0 1393677072 18538208 3325856
21:36:27 66500 8192000000 10266789392 10699522048 1248661584 13.226 90 23 46 0 1259732352 5783712 5287056
21:38:34 39123 8192000000 10258922384 10689867776 1334803328 14.011 98 38 76 0 1357117472 13568800 8745344
21:40:15 42281 8192000000 10258702208 10705158144 1345469744 14.107 99 46 92 0 1364915616 9295536 10150336
21:42:05 42225 8192000000 10265620720 10717900800 1357561088 14.215 99 10 20 0 1373182288 13148848 2472352
21:44:32 70082 8192000000 10278497712 10737602560 1349817008 14.146 98 10 20 1 1369009760 16688832 2503920
21:46:44 35037 8192000000 10305563264 10751102976 1390598528 14.511 100 45 90 1 1413751408 13215024 9937856
21:48:21 48100 8192000000 10270597856 10723627008 1318576592 13.864 95 15 30 0 1337874400 15503776 3794032
21:50:09 31182 8192000000 10287763056 10726912000 1361873312 14.254 99 43 84 1 1381193488 10154784 9165392
21:52:26 52123 8192000000 10273361216 10726440960 1347096208 14.121 98 30 60 0 1367531456 13164080 7271168
21:54:23 16454 8192000000 10296963728 10723586048 1366632336 14.297 100 49 98 1 1395011248 17865504 10513408
21:55:41 44496 8192000000 10296941168 10731290624 1354432144 14.187 98 20 40 0 1372135632 13512672 4190816
21:57:27 96387 8192000000 10288859904 10735546368 1367322992 14.303 99 30 59 1 1383665968 9835600 6507376
22:00:03 17679 8192000000 10274456384 10729136128 1372466320 14.349 100 20 40 2 1387438016 10197968 4773728
22:01:21 54309 8192000000 10255793024 10716377088 1354523264 14.188 99 10 19 1 1371196880 14253184 2420432
22:03:21 47391 8192000000 10285188400 10733969408 1365454512 14.286 100 15 30 1 1382015840 12535552 4025776
22:05:08 58566 8192000000 10288452544 10748547072 1360232512 14.239 98 20 40 0 1382841520 17694256 4914752
22:07:33 99859 8192000000 10295897632 10754068480 1371942720 14.344 98 21 42 0 1390713920 14116896 4654304
22:10:16 110360 8192000000 10304045040 10767597568 1369283008 14.321 98 3 6 0 1381763392 12215232 265152
22:13:34 101632 8192000000 10288740736 10734632960 1324911648 13.921 95 13 26 0 1344012944 16010784 3090512
22:16:18 59420 8192000000 10294948800 10747346944 1382814416 14.442 99 52 104 1 1406523008 12005120 11703472
22:18:30 51165 8192000000 10299513408 10743951360 1375804864 14.379 98 25 49 3 1399472032 18666624 5000544
22:22:15 31987 8192000000 10290549424 10738446336 1373707072 14.360 99 19 38 1 1388998272 10669520 4621680
22:23:47 83480 8192000000 10286682864 10746445824 1353810432 14.182 97 16 32 0 1370834512 13394032 3630048
22:26:25 55561 8192000000 10300315584 10773344256 1383733600 14.450 99 15 30 0 1411206656 23529888 3943168
22:28:57 44175 8192000000 10284599008 10735251456 1325034880 13.922 95 18 34 2 1344385936 15956576 3394480
22:30:44 47024 8192000000 10296013328 10751315968 1368970336 14.318 98 25 50 1 1386864384 12141312 5752736
22:32:36 20352 8192000000 10290632080 10731802624 1353573824 14.180 98 38 76 3 1373216384 11274336 8368224
22:34:00 57801 8192000000 10308825792 10770870272 1388192192 14.490 100 20 40 0 1410295792 17613056 4490544
22:35:58 124640 8192000000 9458688112 9774014464 551980496 6.312 40 5 10 0 557420288 4458592 981200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2021 22:38:51 to 11-10-2021 22:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2021 22:39:06 to 11-10-2021 22:39:08.

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          242125
MON$OLDEST_ACTIVE               242126
MON$OLDEST_SNAPSHOT             242126
MON$NEXT_TRANSACTION            242126
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                       2030560
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:164: 
:165: mscs-test-161.budzdorov.ru    Mon Oct 11 22:38:08 2021
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
--- end of diff output ---

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

11.10.21 22:39:09. 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-10-11 22:39:12 No severe PSQL-related problems occured
11.10.21 22:39:13. End of report.