Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2021 07:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16554920 kB
MemAvailable 31078680 kB
Buffers 0 kB
Cached 15090720 kB
SwapCached 3892 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-24 07:26:58
DTS_END                         2021-02-24 07:26:58.2390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60580
TRN_ID                          226939
ATT_ID                          566
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: 8494.78 20607 1019373
customer order: creation 161.71 445 19405
customer order: refuse 13.84 366 1661
order to supplier: creation 42.92 1423 5150
order to supplier: removal 3.23 133627 387
invoice (draft): creation 43.62 3790 5234
invoice (draft): removal 47.13 1207 5656
invoice accept: apply 39.42 19815 4731
invoice accept: cancel 42.32 1079 5078
customer reserve: creation 3654.66 193 438559
customer reserve: removal 4171.70 82 500604
realization accept: apply 78.18 200 9381
realization accept: cancel 75.35 147 9042
payment from customer: creation 55.93 102 6712
payment from customer: removal 5.32 102 638
payment to supplier: creation 45.74 128 5489
payment to supplier: removal 3.52 112 422
service: total inventory turnovers 3.78 3452 454
service: total monetary turnovers 5.45 624 654
service: aggregate perf. data 0.93 15374 112
service: refresh index statistics 0.03 229880 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-02-2021 07:27:43 to 24-02-2021 07:27:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2279
TEST_TIME 2 2311
TEST_TIME 3 2587
TEST_TIME 4 3534
TEST_TIME 5 4107
TEST_TIME 6 4428
TEST_TIME 7 4553
TEST_TIME 8 4637
TEST_TIME 9 4703
TEST_TIME 10 4809
TEST_TIME 11 5039
TEST_TIME 12 5236
TEST_TIME 13 5320
TEST_TIME 14 5471
TEST_TIME 15 5458
TEST_TIME 16 5816
TEST_TIME 17 6044
TEST_TIME 18 6208
TEST_TIME 19 6395
TEST_TIME 20 6731
TEST_TIME 21 7524
TEST_TIME 22 7804
TEST_TIME 23 7799
TEST_TIME 24 7945
TEST_TIME 25 7962
TEST_TIME 26 7965
TEST_TIME 27 8151
TEST_TIME 28 8204
TEST_TIME 29 8279
TEST_TIME 30 8570
TEST_TIME 31 10345
TEST_TIME 32 10174
TEST_TIME 33 10005
TEST_TIME 34 9844
TEST_TIME 35 9626
TEST_TIME 36 9592
TEST_TIME 37 9411
TEST_TIME 38 9198
TEST_TIME 39 9288
TEST_TIME 40 9190
TEST_TIME 41 9090
TEST_TIME 42 9062
TEST_TIME 43 9030
TEST_TIME 44 9030
TEST_TIME 45 8882
TEST_TIME 46 8965
TEST_TIME 47 8999
TEST_TIME 48 8886
TEST_TIME 49 8789
TEST_TIME 50 8687
TEST_TIME 51 8808
TEST_TIME 52 8824
TEST_TIME 53 8730
TEST_TIME 54 8662
TEST_TIME 55 8724
TEST_TIME 56 8772
TEST_TIME 57 8768
TEST_TIME 58 8786
TEST_TIME 59 8817
TEST_TIME 60 8759
TEST_TIME 61 8883
TEST_TIME 62 8879
TEST_TIME 63 8910
TEST_TIME 64 8919
TEST_TIME 65 8857
TEST_TIME 66 8853
TEST_TIME 67 8731
TEST_TIME 68 8675
TEST_TIME 69 8604
TEST_TIME 70 8574
TEST_TIME 71 8529
TEST_TIME 72 8552
TEST_TIME 73 8500
TEST_TIME 74 8487
TEST_TIME 75 8520
TEST_TIME 76 8518
TEST_TIME 77 8526
TEST_TIME 78 8534
TEST_TIME 79 8555
TEST_TIME 80 8546
TEST_TIME 81 8510
TEST_TIME 82 8629
TEST_TIME 83 8698
TEST_TIME 84 8749
TEST_TIME 85 8740
TEST_TIME 86 8711
TEST_TIME 87 8699
TEST_TIME 88 8623
TEST_TIME 89 8563
TEST_TIME 90 8542
TEST_TIME 91 8506
TEST_TIME 92 8487
TEST_TIME 93 8517
TEST_TIME 94 8496
TEST_TIME 95 8568
TEST_TIME 96 8564
TEST_TIME 97 8593
TEST_TIME 98 8578
TEST_TIME 99 8580
TEST_TIME 100 8559
TEST_TIME 101 8578
TEST_TIME 102 8656
TEST_TIME 103 8634
TEST_TIME 104 8703
TEST_TIME 105 8706
TEST_TIME 106 8633
TEST_TIME 107 8567
TEST_TIME 108 8541
TEST_TIME 109 8513
TEST_TIME 110 8478
TEST_TIME 111 8479
TEST_TIME 112 8461
TEST_TIME 113 8442
TEST_TIME 114 8472
TEST_TIME 115 8447
TEST_TIME 116 8494
TEST_TIME 117 8477
TEST_TIME 118 8524
TEST_TIME 119 8508
TEST_TIME 120 8496

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-02-2021 07:27:47 to 24-02-2021 07:27:50.

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 1424798 1424798 0 0.00 0 20998 7 0 0
doc_list_biud 1424766 1424766 0 0.00 0 82328 37 0 0
sp_add_invoice_to_stock 4850 4731 119 2.45 27 185469 19816 0 119
sp_cancel_adding_invoice 5078 5078 0 0.00 4 73402 1079 0 0
sp_cancel_client_order 1661 1661 0 0.00 5 11381 366 0 0
sp_cancel_customer_reserve 500604 500604 0 0.00 1 82334 82 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 3 4977 102 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 10598 113 0 0
sp_cancel_supplier_invoice 5666 5656 10 0.17 1 50978 1207 0 10
sp_cancel_supplier_order 387 387 0 0.00 17 1240022 133627 0 0
sp_cancel_write_off 9042 9042 0 0.00 1 21581 147 0 0
sp_client_order 19405 19405 0 0.00 4 18799 445 0 0
sp_customer_reserve 438559 438559 0 0.00 0 51027 193 0 0
sp_fill_shopping_cart 29784 29784 0 0.00 1 12212 56 0 0
sp_fill_shopping_cart_clo_res 438557 438557 0 0.00 0 15232 19 0 0
sp_get_clo_for_invoice 4728 4728 0 0.00 0 37817 443 0 0
sp_kill_cost_storno 15180 15180 0 0.00 0 10599 69 0 0
sp_kill_qstorno_ret_qs2qd 1016293 1016293 0 0.00 0 82325 45 0 0
sp_kill_qty_storno 536510 536510 0 0.00 0 82327 96 0 0
sp_lock_selected_doc 19638 19638 0 0.00 0 9110 6 0 0
sp_make_cost_storno 26310 26310 0 0.00 0 15365 60 0 0
sp_multiply_rows_for_pdistr 26279 26279 0 0.00 0 9189 30 0 0
sp_multiply_rows_for_qdistr 19405 19405 0 0.00 0 13485 246 0 0
sp_pay_from_customer 6712 6712 0 0.00 4 13921 102 0 0
sp_pay_to_supplier 5489 5489 0 0.00 5 15389 128 0 0
sp_payment_common 12196 12196 0 0.00 1 11706 22 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 10790 332 0 0
sp_qd_handle_on_invoice_upd_sts 9794 9794 0 0.00 0 23704 442 0 0
sp_qd_handle_on_reserve_upd_sts 9366 9366 0 0.00 0 18371 61 0 0
sp_reserve_write_off 9381 9381 0 0.00 7 19500 200 0 0
sp_supplier_invoice 5234 5234 0 0.00 8 104803 3791 0 0
sp_supplier_order 5150 5150 0 0.00 6 133244 1423 0 0
srv_aggregate_perf_data 112 112 0 0.00 2567 151328 15374 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1915 158475 45286 0 0
srv_find_qd_qs_mism 974012 974012 0 0.00 0 20996 6 0 0
srv_make_invnt_saldo 454 454 0 0.00 54 101045 3452 0 0
srv_make_money_saldo 654 654 0 0.00 1 15861 625 0 0
srv_recalc_idx_stat 4 4 0 0.00 204805 256185 229881 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4731 4731 0 0.00 0 13700 160 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3804 130 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 1698 10 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 3710 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 876 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 343 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1170 13 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 14737 1463 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 3929 7 0 0
t$perf-norm:sp_client_order 19405 19405 0 0.00 0 8294 18 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 1977 9 0 0
t$perf-norm:sp_pay_from_customer 6712 6712 0 0.00 0 3756 9 0 0
t$perf-norm:sp_pay_to_supplier 5489 5489 0 0.00 0 6271 10 0 0
t$perf-norm:sp_reserve_write_off 9381 9381 0 0.00 0 2017 7 0 0
t$perf-norm:sp_supplier_invoice 5234 5234 0 0.00 0 4384 14 0 0
t$perf-norm:sp_supplier_order 5150 5150 0 0.00 0 8385 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 2072 51 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 495 14 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 1689 7 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 461 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 6 0 0
v_add_invoice_to_stock 9670 9432 238 2.46 0 6831 21 0 238
v_all_customers 35562 35562 0 0.00 0 40 1 0 0
v_all_suppliers 28628 28628 0 0.00 0 19 1 0 0
v_all_wares 38810 38810 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 357 7 0 0
v_cancel_client_order 3322 3322 0 0.00 0 2115 5 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 4929 18 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 4883 8 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 4720 17 0 0
v_cancel_supplier_invoice 852 832 20 2.34 0 5639 28 0 20
v_cancel_supplier_order 774 774 0 0.00 0 345 5 0 0
v_cancel_write_off 1936 1936 0 0.00 0 8753 18 0 0
v_max_id_clo_ord 5149 5149 0 0.00 0 1577 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 2373 14 0 0
v_max_id_ord_sup 5228 5228 0 0.00 0 6198 8 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 3597 8 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 1952 3 0 0
v_min_id_clo_ord 5149 5149 0 0.00 0 7834 9 0 0
v_min_id_clo_res 947 947 0 0.00 0 12006 34 0 0
v_min_id_ord_sup 5228 5228 0 0.00 0 8381 10 0 0
v_min_non_paid_invoice 5487 5487 0 0.00 0 6756 15 0 0
v_min_non_paid_realizn 6708 6708 0 0.00 0 6692 14 0 0
v_our_firm 9486 9486 0 0.00 0 16 1 0 0
v_reserve_write_off 18732 18732 0 0.00 0 5291 8 0 0
x_lock_depdocs_on_canc_invoice 10318 10318 0 0.00 0 15316 127 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 14096 428 0 0
x_make_qstorno_1000_1200 5150 5150 0 0.00 1 133141 1336 0 0
x_make_qstorno_1200_2000 5234 5234 0 0.00 4 102162 3570 0 0
x_make_qstorno_2100_3300 438559 438559 0 0.00 0 51026 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2021 07:27:51 to 24-02-2021 07:27:55.

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
04:57:02 3 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:03 21451 8192000000 9715385392 10007937024 790741664 8.802 100 86 171 1 818941264 7189504 21010096
04:59:27 9818 8192000000 9754596512 10054856704 830711440 9.206 100 91 182 0 864837248 11617024 22508784
05:00:42 16454 8192000000 9795088976 10110144512 864538768 9.546 100 81 161 2 902474864 19448352 18487744
05:02:05 11153 8192000000 9851877872 10183471104 913853184 10.035 100 72 141 2 957955920 28721808 15380928
05:03:27 9994 8192000000 9914097504 10256355328 962548080 10.514 100 84 168 1 1029446432 49119472 17778880
05:04:46 5769 8192000000 9964857600 10321924096 997944432 10.859 100 79 158 0 1084301504 70379120 15977952
05:05:54 3632 8192000000 10010692784 10368450560 1024472704 11.115 100 82 164 1 1131461648 90592944 16396000
05:06:59 1814 8192000000 10016942608 10384199680 1052357552 11.383 100 88 177 1 1128598336 58661600 17579184
05:08:03 1685 8192000000 10042305504 10415226880 1067833024 11.531 100 89 179 0 1155198512 68487184 18878304
05:09:05 9732 8192000000 9979983488 10377539584 1070842736 11.560 100 25 51 0 1102853728 27049440 4961552
05:10:17 10594 8192000000 9981613232 10383986688 1079551920 11.643 100 26 52 1 1105620592 20929952 5138720
05:11:28 6175 8192000000 10002054912 10408984576 1096396576 11.803 100 24 47 1 1119752752 18060464 5295712
05:12:34 68455 8192000000 10005904384 10413805568 1102346992 11.860 100 40 79 5 1137804816 25491104 9966720
05:14:45 6944 8192000000 10032694064 10439532544 1108076992 11.914 100 37 72 3 1152823600 37611696 7134912
05:15:53 3583 8192000000 10016052560 10416496640 1110092672 11.933 100 47 96 1 1130141152 8728896 11319584
05:17:00 5501 8192000000 10020946640 10411528192 1087293808 11.717 99 35 68 0 1112410400 17670048 7446544
05:18:06 31108 8192000000 9996001568 10408062976 1065151984 11.506 97 24 48 2 1094228064 24267168 4808912
05:19:37 4541 8192000000 9961676208 10335891456 1012044192 10.995 98 45 88 1 1039266128 17746128 9475808
05:20:44 4180 8192000000 9942547616 10325716992 1015918000 11.033 99 47 97 1 1042353056 15787632 10647424
05:21:52 68461 8192000000 9939697680 10337230848 1003500160 10.912 98 23 45 2 1026266608 18336896 4429552
05:24:03 8066 8192000000 9971111744 10369339392 1051875808 11.379 100 34 65 2 1090074048 29582800 8615440
05:25:13 6383 8192000000 9970977984 10365624320 1070834928 11.560 100 38 75 3 1090451584 10750544 8866112
05:26:23 7489 8192000000 9987842736 10393501696 1067212064 11.525 100 32 65 1 1107231952 33498832 6521056
05:27:35 86322 8192000000 10015054496 10418212864 1087292784 11.717 100 26 52 1 1122388304 29951488 5144032
05:30:07 4036 8192000000 10073656272 10472529920 1111636272 11.948 100 55 110 2 1181756688 59205504 10914912
05:31:16 17730 8192000000 10103853104 10509000704 1122595152 12.052 100 55 108 0 1201372096 67389776 11387168
05:32:38 9271 8192000000 10132877280 10539548672 1139308528 12.209 100 54 108 1 1244026720 93279344 11438848
05:33:53 6872 8192000000 10150197696 10559844352 1150640848 12.316 100 55 109 0 1261223136 98794112 11788176
05:35:06 6576 8192000000 10178261552 10595328000 1150858864 12.318 100 57 114 0 1290594864 127466288 12269712
05:36:23 9256 8192000000 10183941152 10598895616 1157646096 12.381 100 50 99 0 1286409984 117630240 11133648
05:37:40 9791 8192000000 10202994768 10628976640 1167323280 12.472 100 52 102 2 1307989888 129788960 10877648
05:38:57 6351 8192000000 10246979760 10670481408 1176985904 12.562 100 57 115 2 1362097024 172790416 12320704
05:40:07 8367 8192000000 10319611088 10726289408 1179503104 12.586 100 82 165 3 1417663888 219499088 18661696
05:41:22 3694 8192000000 10337605600 10750488576 1182704176 12.615 100 80 160 0 1433775280 232073696 18997408
05:42:33 3735 8192000000 10365330960 10781634560 1183794944 12.626 100 76 152 1 1461812160 260999936 17017280
05:43:44 1914 8192000000 10375321680 10783289344 1177228624 12.564 100 77 149 2 1475726112 281220960 17276528
05:44:50 4678 8192000000 10409450720 10821283840 1170544800 12.502 99 72 144 1 1496696736 309317088 16834848
05:45:57 1947 8192000000 10431680480 10859552768 1175039024 12.544 100 87 174 1 1530084848 334828784 20217040
05:47:03 3692 8192000000 10359066320 10822995968 1140657056 12.222 100 57 114 1 1460365408 305507840 14200512
05:48:13 3832 8192000000 10327624320 10736951296 1158930816 12.393 100 81 163 1 1418649904 240745120 18973968
05:49:22 8410 8192000000 10321398672 10737197056 1146228256 12.274 98 63 124 0 1406626672 245961760 14436656
05:50:44 6815 8192000000 10361351584 10792214528 1158496992 12.389 100 48 95 3 1451490832 280496320 12497520
05:51:58 7954 8192000000 10399594800 10844753920 1164895264 12.449 100 47 94 0 1513639424 337271984 11472176
05:53:19 5754 8192000000 10448743056 10877087744 1155120080 12.358 100 45 88 0 1540937760 374038336 11779344
05:54:26 19296 8192000000 10461483472 10898370560 1157933232 12.384 100 35 70 2 1571578384 404457360 9187792
05:55:56 18065 8192000000 10500770576 10973491200 1131686144 12.137 100 38 75 0 1585451568 443336304 10429120
05:57:15 11354 8192000000 10297716080 10753089536 1135260320 12.171 99 25 50 1 1417978672 276452976 6265376
05:58:43 37171 8192000000 10084676192 10532499456 1124216112 12.067 99 17 33 0 1167403696 39971408 3216176
06:00:23 158475 8192000000 10094080048 10576457728 1106160752 11.896 98 21 42 1 1172610608 62304048 4145808
06:04:02 69879 8192000000 10106963872 10539839488 1103367328 11.870 98 20 41 0 1184981856 76193568 5420960
06:06:13 69770 8192000000 10137108896 10578014208 1140628384 12.221 100 45 90 0 1229446080 79333904 9483792
06:08:31 47576 8192000000 10145442432 10596204544 1143642928 12.250 99 27 53 0 1247303056 97974848 5685280
06:10:27 134330 8192000000 10147348000 10629902336 1128354656 12.106 98 20 40 0 1245325088 111856992 5113440
06:13:55 71534 8192000000 10079884544 10537234432 1139624800 12.212 100 22 44 0 1177729648 33764944 4339904
06:16:24 43245 8192000000 10094177120 10602266624 1129156336 12.113 100 6 12 1 1218156016 87748752 1250928
06:18:15 54582 8192000000 10067015344 10546343936 1133276320 12.152 100 20 40 3 1189562896 51412064 4874512
06:20:23 64620 8192000000 10130737152 10591547392 1138465312 12.201 99 29 58 0 1231765504 87057504 6242688
06:22:28 54228 8192000000 10098115264 10553499648 1137899632 12.196 100 15 30 2 1191508096 50368464 3240000
06:24:23 47770 8192000000 10135412560 10609897472 1136961728 12.187 99 28 56 0 1230584752 87643184 5979840
06:26:44 50949 8192000000 10132543504 10633453568 1127923664 12.102 98 18 36 1 1215752448 84068512 3760272
06:28:41 104004 8192000000 10083508416 10525339648 1105558224 11.890 96 18 35 1 1135264720 26427520 3278976
06:31:28 19819 8192000000 10093553312 10527260672 1133087120 12.150 98 41 81 1 1166114048 24910176 8116752
06:32:48 83206 8192000000 10086332496 10567876608 1145911680 12.271 100 15 30 0 1192322704 43592848 2818176
06:35:15 68017 8192000000 10085647888 10541850624 1115224304 11.982 97 9 18 2 1165788000 48549472 2014224
06:38:41 30771 8192000000 10040812960 10508484608 1103346496 11.869 98 17 35 0 1131439008 24545056 3547456
06:40:13 65020 8192000000 10090826416 10551640064 1090262960 11.745 96 8 16 1 1155341920 63456272 1622688
06:42:18 38592 8192000000 10047360864 10486226944 1083379136 11.680 96 11 22 0 1104677296 18882416 2415744
06:44:06 92119 8192000000 10103061344 10572890112 1112573952 11.957 98 15 30 0 1174103712 58136896 3392864
06:46:39 79367 8192000000 10152241696 10601312256 1087062592 11.715 96 20 40 1 1201058544 108951936 5044016
06:48:59 55848 8192000000 10077739616 10535882752 1101954448 11.856 99 13 26 2 1152404880 47627760 2822672
06:51:02 30413 8192000000 10060622848 10494947328 1092761744 11.769 98 14 28 0 1136738640 41035440 2941456
06:53:00 135613 8192000000 10058760448 10556325888 1119473168 12.022 100 23 46 0 1185595792 60959552 5163072
06:56:16 73876 8192000000 10053697296 10511208448 1106609328 11.900 98 15 30 0 1144414624 34457648 3347648
06:58:49 68033 8192000000 10101359424 10589560832 1132048368 12.141 99 29 57 0 1206931184 68195504 6687312
07:00:57 95396 8192000000 10098209776 10537746432 1129201760 12.114 99 16 31 2 1169542176 37427488 2912928
07:03:40 29098 8192000000 10081186160 10508349440 1126000496 12.084 99 45 89 1 1162107360 27191904 8914960
07:05:10 14129 8192000000 10101908944 10526228480 1124385472 12.068 99 55 110 1 1181442912 46348064 10709376
07:06:25 20239 8192000000 10107673328 10530009088 1135365072 12.172 100 56 112 1 1195708016 49232000 11110944
07:07:45 46941 8192000000 10134498320 10599333888 1113159296 11.962 98 37 74 0 1199279616 78832448 7287872
07:09:34 52928 8192000000 10112296000 10590535680 1114426992 11.974 98 35 70 0 1181134528 59572912 7134624
07:11:29 150375 8192000000 10067236256 10519003136 1117402384 12.002 98 16 32 1 1141881904 20114608 4364912
07:15:07 42527 8192000000 10057754640 10536579072 1125472736 12.079 99 6 12 0 1148531744 21733872 1325136
07:16:51 69845 8192000000 10073764096 10545410048 1099127408 11.829 96 15 31 0 1147673376 44831744 3714224
07:19:07 32566 8192000000 10112391104 10564124672 1148858640 12.299 100 22 44 1 1195676928 41264288 5554000
07:21:00 106659 8192000000 10097303408 10548822016 1102709648 11.863 96 24 47 2 1182134480 73784416 5640416
07:23:55 85031 8192000000 10066008784 10513625088 1097973936 11.818 96 23 46 0 1146972880 44129872 4869072
07:26:29 37903 8192000000 9265584400 9577390080 389137248 4.534 35 7 14 0 416565968 26093136 1335584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-02-2021 07:27:57 to 24-02-2021 07:28:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 24-02-2021 07:28:13 to 24-02-2021 07:28:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          227187
MON$OLDEST_ACTIVE               227188
MON$OLDEST_SNAPSHOT             227188
MON$NEXT_TRANSACTION            227188
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2087072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2372 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:170: 
:171: mscs-test-161.budzdorov.ru    Wed Feb 24 07:27:10 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Wed Feb 24 07:27:10 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
:178: 
:179: mscs-test-161.budzdorov.ru    Wed Feb 24 07:27:11 2021
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:181: 
--- end of diff output ---

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

24.02.21 07:28:16. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-02-24 07:28:19 No severe PSQL-related problems occured
24.02.21 07:28:20. End of report.