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

Server and database settinfs

SERVER_MODE                     SuperServer 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-03-20 07:26:56
DTS_END                         2021-03-20 07:26:56.1220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47182
TRN_ID                          231169
ATT_ID                          605
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: 9025.03 22418 1083003
customer order: creation 167.98 413 20158
customer order: refuse 14.75 283 1770
order to supplier: creation 43.95 1414 5274
order to supplier: removal 3.33 122208 399
invoice (draft): creation 44.58 3992 5349
invoice (draft): removal 51.58 985 6189
invoice accept: apply 40.64 16939 4877
invoice accept: cancel 46.66 872 5599
customer reserve: creation 3762.81 165 451537
customer reserve: removal 4561.84 70 547421
realization accept: apply 80.27 163 9632
realization accept: cancel 83.11 120 9973
payment from customer: creation 56.78 101 6814
payment from customer: removal 5.13 108 616
payment to supplier: creation 47.78 123 5733
payment to supplier: removal 3.13 100 375
service: total inventory turnovers 3.93 3190 471
service: total monetary turnovers 5.92 624 710
service: aggregate perf. data 0.85 15767 102
service: refresh index statistics 0.03 280730 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-03-2021 07:28:16 to 20-03-2021 07:28:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4325
TEST_TIME 2 3256
TEST_TIME 3 4482
TEST_TIME 4 4667
TEST_TIME 5 5503
TEST_TIME 6 5191
TEST_TIME 7 5355
TEST_TIME 8 5470
TEST_TIME 9 5681
TEST_TIME 10 5828
TEST_TIME 11 5822
TEST_TIME 12 5779
TEST_TIME 13 5971
TEST_TIME 14 6287
TEST_TIME 15 6520
TEST_TIME 16 6799
TEST_TIME 17 7263
TEST_TIME 18 7796
TEST_TIME 19 8099
TEST_TIME 20 8520
TEST_TIME 21 8448
TEST_TIME 22 8562
TEST_TIME 23 8354
TEST_TIME 24 8650
TEST_TIME 25 9406
TEST_TIME 26 9369
TEST_TIME 27 10604
TEST_TIME 28 11162
TEST_TIME 29 11000
TEST_TIME 30 11006
TEST_TIME 31 10997
TEST_TIME 32 11019
TEST_TIME 33 10983
TEST_TIME 34 10760
TEST_TIME 35 10477
TEST_TIME 36 10240
TEST_TIME 37 10310
TEST_TIME 38 10199
TEST_TIME 39 10191
TEST_TIME 40 10194
TEST_TIME 41 10236
TEST_TIME 42 10164
TEST_TIME 43 10081
TEST_TIME 44 10060
TEST_TIME 45 9915
TEST_TIME 46 9824
TEST_TIME 47 9787
TEST_TIME 48 9721
TEST_TIME 49 9753
TEST_TIME 50 9754
TEST_TIME 51 9754
TEST_TIME 52 9677
TEST_TIME 53 9601
TEST_TIME 54 9834
TEST_TIME 55 9847
TEST_TIME 56 9862
TEST_TIME 57 9859
TEST_TIME 58 9891
TEST_TIME 59 9925
TEST_TIME 60 9899
TEST_TIME 61 9882
TEST_TIME 62 9725
TEST_TIME 63 9644
TEST_TIME 64 9526
TEST_TIME 65 9541
TEST_TIME 66 9506
TEST_TIME 67 9474
TEST_TIME 68 9431
TEST_TIME 69 9415
TEST_TIME 70 9409
TEST_TIME 71 9478
TEST_TIME 72 9440
TEST_TIME 73 9599
TEST_TIME 74 9559
TEST_TIME 75 9585
TEST_TIME 76 9650
TEST_TIME 77 9651
TEST_TIME 78 9746
TEST_TIME 79 9636
TEST_TIME 80 9597
TEST_TIME 81 9536
TEST_TIME 82 9447
TEST_TIME 83 9355
TEST_TIME 84 9374
TEST_TIME 85 9414
TEST_TIME 86 9369
TEST_TIME 87 9369
TEST_TIME 88 9375
TEST_TIME 89 9367
TEST_TIME 90 9324
TEST_TIME 91 9409
TEST_TIME 92 9439
TEST_TIME 93 9483
TEST_TIME 94 9501
TEST_TIME 95 9483
TEST_TIME 96 9472
TEST_TIME 97 9411
TEST_TIME 98 9350
TEST_TIME 99 9300
TEST_TIME 100 9257
TEST_TIME 101 9202
TEST_TIME 102 9253
TEST_TIME 103 9209
TEST_TIME 104 9263
TEST_TIME 105 9230
TEST_TIME 106 9195
TEST_TIME 107 9230
TEST_TIME 108 9204
TEST_TIME 109 9165
TEST_TIME 110 9175
TEST_TIME 111 9215
TEST_TIME 112 9237
TEST_TIME 113 9224
TEST_TIME 114 9151
TEST_TIME 115 9113
TEST_TIME 116 9099
TEST_TIME 117 9044
TEST_TIME 118 9078
TEST_TIME 119 9046
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-03-2021 07:28:20 to 20-03-2021 07:28:23.

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 1500753 1500753 0 0.00 0 16777 7 0 0
doc_list_biud 1500753 1500753 0 0.00 0 81411 32 0 0
sp_add_invoice_to_stock 4974 4877 97 1.95 22 184816 16940 0 97
sp_cancel_adding_invoice 5599 5599 0 0.00 5 66105 873 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 7663 283 0 0
sp_cancel_customer_reserve 547421 547421 0 0.00 1 81413 70 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 6089 109 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 13036 101 0 0
sp_cancel_supplier_invoice 6197 6189 8 0.12 2 20057 985 0 8
sp_cancel_supplier_order 399 399 0 0.00 5 1118892 122209 0 0
sp_cancel_write_off 9973 9973 0 0.00 1 10083 121 0 0
sp_client_order 20158 20158 0 0.00 4 72196 414 0 0
sp_customer_reserve 451537 451537 0 0.00 0 54335 165 0 0
sp_fill_shopping_cart 30771 30771 0 0.00 1 12982 42 0 0
sp_fill_shopping_cart_clo_res 451525 451525 0 0.00 0 33795 16 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 43840 358 0 0
sp_kill_cost_storno 16563 16563 0 0.00 0 8126 58 0 0
sp_kill_qstorno_ret_qs2qd 1111403 1111403 0 0.00 0 75660 38 0 0
sp_kill_qty_storno 585848 585848 0 0.00 0 81411 82 0 0
sp_lock_selected_doc 20164 20164 0 0.00 0 7346 4 0 0
sp_make_cost_storno 27044 27044 0 0.00 0 13323 53 0 0
sp_multiply_rows_for_pdistr 27044 27044 0 0.00 0 8456 29 0 0
sp_multiply_rows_for_qdistr 20158 20158 0 0.00 0 51482 229 0 0
sp_pay_from_customer 6814 6814 0 0.00 4 15774 101 0 0
sp_pay_to_supplier 5733 5733 0 0.00 4 15093 123 0 0
sp_payment_common 12547 12547 0 0.00 1 15079 19 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 7656 260 0 0
sp_qd_handle_on_invoice_upd_sts 10464 10464 0 0.00 0 35152 378 0 0
sp_qd_handle_on_reserve_upd_sts 9632 9632 0 0.00 0 12232 46 0 0
sp_reserve_write_off 9632 9632 0 0.00 6 16784 164 0 0
sp_supplier_invoice 5349 5349 0 0.00 7 143179 3993 0 0
sp_supplier_order 5274 5274 0 0.00 5 100399 1415 0 0
srv_aggregate_perf_data 102 102 0 0.00 2832 140872 15767 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1501 129635 41963 0 0
srv_find_qd_qs_mism 1036536 1036536 0 0.00 0 10294 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 62 122251 3190 0 0
srv_make_money_saldo 710 710 0 0.00 1 26314 624 0 0
srv_recalc_idx_stat 4 4 0 0.00 139544 431848 280731 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 13772 160 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 2740 87 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 4488 13 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3552 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 5881 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 217 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 5681 34 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 20855 1611 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1256 6 0 0
t$perf-norm:sp_client_order 20158 20158 0 0.00 0 17583 21 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 3298 7 0 0
t$perf-norm:sp_pay_from_customer 6814 6814 0 0.00 0 6092 11 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 13274 12 0 0
t$perf-norm:sp_reserve_write_off 9632 9632 0 0.00 0 5037 11 0 0
t$perf-norm:sp_supplier_invoice 5349 5349 0 0.00 0 7650 19 0 0
t$perf-norm:sp_supplier_order 5274 5274 0 0.00 0 4782 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 571 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 482 18 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1661 13 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 1392 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 70 21 0 0
v_add_invoice_to_stock 9924 9730 194 1.95 0 8879 15 0 194
v_all_customers 36902 36902 0 0.00 0 87 1 0 0
v_all_suppliers 29572 29572 0 0.00 0 24 1 0 0
v_all_wares 40314 40314 0 0.00 0 138 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1016 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 1565 3 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 1891 9 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1189 5 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 2004 6 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 1126 13 0 16
v_cancel_supplier_order 798 798 0 0.00 0 898 5 0 0
v_cancel_write_off 2010 2010 0 0.00 0 1519 14 0 0
v_max_id_clo_ord 5270 5270 0 0.00 0 526 1 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 3643 15 0 0
v_max_id_ord_sup 5344 5344 0 0.00 0 7473 7 0 0
v_max_non_paid_invoice 1561 1561 0 0.00 0 154 2 0 0
v_max_non_paid_realizn 3632 3632 0 0.00 0 1138 2 0 0
v_min_id_clo_ord 5270 5270 0 0.00 0 3222 5 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 3565 10 0 0
v_min_id_ord_sup 5344 5344 0 0.00 0 5755 10 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 15075 14 0 0
v_min_non_paid_realizn 6814 6814 0 0.00 0 4731 11 0 0
v_our_firm 9776 9776 0 0.00 0 23 1 0 0
v_reserve_write_off 19264 19264 0 0.00 0 1794 4 0 0
x_lock_depdocs_on_canc_invoice 11391 11391 0 0.00 0 29235 106 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 28991 451 0 0
x_make_qstorno_1000_1200 5274 5274 0 0.00 2 100392 1339 0 0
x_make_qstorno_1200_2000 5349 5349 0 0.00 3 143168 3825 0 0
x_make_qstorno_2100_3300 451537 451537 0 0.00 0 54323 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-03-2021 07:28:24 to 20-03-2021 07:28:28.

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:56:59 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:01 39510 8192000000 9716188960 10010370048 794934832 8.845 100 92 184 0 825492416 7988000 22569584
04:59:43 21259 8192000000 9754025712 10055811072 833190192 9.231 100 87 173 1 866719152 12672496 20856464
05:01:09 17523 8192000000 9791399328 10108182528 859892080 9.499 100 81 162 1 899018976 20275840 18851056
05:02:35 18564 8192000000 9870051088 10204160000 914350640 10.040 100 73 145 0 969765584 39780176 15634768
05:03:58 7053 8192000000 9931404112 10274025472 961422912 10.503 100 76 152 0 1041028736 63822592 15783232
05:05:08 3923 8192000000 9969483744 10323525632 994197424 10.822 100 82 164 1 1080195440 69227104 16770912
05:06:14 2937 8192000000 9983835872 10344697856 1030066528 11.169 100 86 170 1 1095329136 47661200 17601408
05:07:18 2013 8192000000 10021150624 10387083264 1051392000 11.374 100 83 167 0 1126983824 58585184 17006640
05:08:21 2960 8192000000 10000475808 10390188032 1059635840 11.453 100 47 94 0 1121570784 52035584 9899360
05:09:25 6845 8192000000 9986182496 10373324800 1071867264 11.570 100 38 77 0 1103902640 23673696 8361680
05:10:33 45997 8192000000 10003718624 10388709376 1078507616 11.633 100 61 122 2 1099763408 7934832 13320960
05:12:20 5842 8192000000 10015435136 10418675712 1088989696 11.733 100 53 104 3 1130589552 29430640 12169216
05:13:29 9204 8192000000 10017734416 10428141568 1092562576 11.767 100 32 64 1 1131302080 31660160 7079344
05:14:41 10735 8192000000 10002259248 10404917248 1099935440 11.837 100 24 45 3 1129412608 25423728 4053440
05:15:52 55115 8192000000 9994652080 10385539072 1042374208 11.287 95 37 75 1 1064332480 14130576 7827696
05:17:48 9792 8192000000 9972776352 10367148032 1014372016 11.018 94 26 51 2 1041960224 22131024 5457184
05:18:59 7153 8192000000 9919169488 10308456448 978955952 10.674 95 20 40 0 1003339408 20130544 4252912
05:20:09 10601 8192000000 9945679536 10340671488 1017999856 11.053 98 27 54 1 1053730752 29725056 6005840
05:21:23 26642 8192000000 9938259424 10335678464 1009867136 10.974 98 20 40 0 1040172704 25714256 4591312
05:22:50 7410 8192000000 9959715056 10337775616 1041808816 11.282 100 33 63 2 1068594208 19842880 6942512
05:23:59 11407 8192000000 9960990640 10359382016 1046204656 11.324 100 30 60 2 1079085856 26235696 6645504
05:25:14 17397 8192000000 9971276256 10363580416 1060210976 11.459 100 34 70 4 1081079616 13663968 7204672
05:26:31 8529 8192000000 9966323024 10370342912 1067152080 11.525 100 24 48 0 1102965552 30398896 5414576
05:27:43 16184 8192000000 10026592128 10430418944 1097746288 11.816 100 52 104 2 1142533184 33803104 10983792
05:29:12 5867 8192000000 10064562160 10468085760 1112482288 11.956 100 40 79 0 1183926576 63558992 7885296
05:30:20 14644 8192000000 10110962352 10512076800 1123363280 12.059 100 53 106 0 1211861888 77565424 10933184
05:31:40 6603 8192000000 10142296448 10549968896 1138528752 12.202 100 52 103 0 1243211200 92914544 11767904
05:32:53 13085 8192000000 10174641104 10587136000 1146802704 12.279 100 41 82 0 1275920368 119857264 9260400
05:34:13 15374 8192000000 10196111072 10616549376 1163776368 12.439 100 37 73 2 1311754832 139302000 8676464
05:35:36 28016 8192000000 10231345184 10661838848 1172559104 12.521 100 42 83 2 1356369280 173918080 9892096
05:37:17 13371 8192000000 10268516992 10681856000 1163588288 12.437 99 51 102 0 1362517296 187192960 11736048
05:38:43 9578 8192000000 10333016112 10742255616 1176688608 12.559 100 71 141 1 1437020624 244427136 15904880
05:40:06 1989 8192000000 10355211312 10763481088 1170557456 12.502 100 81 157 1 1456718272 267920560 18240256
05:41:14 1500 8192000000 10375685408 10779095040 1160345312 12.406 100 80 157 0 1473885488 295468048 18072128
05:42:21 3665 8192000000 10413185456 10831020032 1169909520 12.496 100 88 176 0 1512731232 322381120 20440592
05:43:29 2513 8192000000 10391763424 10818404352 1140343696 12.219 99 71 142 0 1485608192 327769888 17494608
05:44:35 3852 8192000000 10429262832 10841374720 1164350160 12.444 100 75 150 0 1524011872 342477248 17184464
05:45:50 4035 8192000000 10364353968 10772377600 1151894336 12.327 100 73 146 2 1455820224 286387184 17538704
05:46:58 10286 8192000000 10352185520 10770890752 1126062064 12.084 97 39 78 0 1414727536 279100800 9564672
05:48:28 4068 8192000000 10410264336 10836840448 1134292992 12.162 100 59 118 0 1508072416 359370992 14408432
05:49:44 4901 8192000000 10467817072 10888503296 1144851232 12.261 100 53 106 3 1561715888 403396048 13468608
05:50:52 15432 8192000000 10461302496 10929811456 1136545152 12.183 100 34 68 2 1594824832 448721152 9558528
05:52:23 21230 8192000000 10385140704 10848489472 1124261072 12.067 100 24 48 0 1482369392 351382272 6726048
05:53:46 39067 8192000000 10094492272 10622832640 1112368816 11.955 99 10 20 0 1264931760 149467008 3095936
05:55:33 50870 8192000000 10118055008 10539634688 1116458096 11.994 99 42 84 2 1185394304 59498096 9438112
05:57:26 61282 8192000000 10090545152 10544668672 1113489296 11.965 98 14 28 0 1186820880 70757744 2573840
05:59:28 129634 8192000000 10118346720 10553344000 1096290496 11.802 96 18 36 0 1138601200 38694592 3616112
06:02:57 27920 8192000000 10103247200 10540990464 1118176880 12.010 98 20 40 0 1178886576 56862432 3847264
06:04:26 46996 8192000000 10120359040 10568486912 1135085408 12.169 99 32 64 1 1202714720 60190432 7438880
06:06:17 51289 8192000000 10111613072 10578751488 1135520944 12.173 99 18 35 0 1208945360 69689888 3734528
06:08:11 60524 8192000000 10086353120 10573946880 1141701072 12.232 100 21 42 0 1199570016 53125392 4743552
06:10:25 31802 8192000000 10073994128 10526920704 1143459744 12.248 100 9 18 0 1176329984 31053872 1816368
06:12:03 45300 8192000000 10087150000 10564579328 1146563088 12.277 100 11 22 1 1204093392 55039776 2490528
06:13:49 63612 8192000000 10091257920 10541035520 1131231584 12.133 99 6 12 0 1164464800 31962688 1270528
06:16:07 37205 8192000000 10067072688 10541092864 1121580656 12.042 98 18 35 4 1163071984 37534784 3956544
06:17:45 41635 8192000000 10118902784 10579374080 1143539792 12.249 99 20 40 0 1208219696 60581232 4098672
06:19:27 42873 8192000000 10167333824 10617237504 1148520784 12.296 100 27 54 1 1266939376 112488752 5929840
06:21:13 55006 8192000000 10092188592 10561830912 1132370784 12.144 98 8 16 2 1190004720 56095392 1538544
06:23:08 42686 8192000000 10086769488 10534912000 1115899856 11.988 97 24 47 0 1168190688 47172048 5118784
06:24:51 47925 8192000000 10083655168 10547912704 1137744176 12.194 99 15 30 0 1173480464 33023456 2712832
06:26:44 38666 8192000000 10098296640 10555850752 1107671856 11.910 96 28 56 0 1157991360 44495728 5823776
06:28:23 112653 8192000000 10105111792 10560667648 1135117920 12.170 99 24 48 1 1194794832 54854992 4821920
06:32:19 57141 8192000000 10067740096 10530779136 1064408336 11.499 93 8 14 0 1101686992 36097488 1181168
06:34:17 25292 8192000000 10074442208 10511282176 1111115888 11.943 99 37 74 2 1166935184 47604624 8214672
06:35:49 62974 8192000000 10111743296 10574475264 1084598464 11.691 96 19 38 0 1167002992 78285968 4118560
06:37:56 46113 8192000000 10128987984 10553925632 1074344832 11.594 95 50 99 2 1160142288 75118384 10679072
06:39:46 79755 8192000000 10099423632 10576371712 1095824496 11.798 98 16 32 2 1163428128 64382688 3220944
06:42:10 53696 8192000000 10095279952 10524237824 1105505776 11.890 98 24 48 0 1163737616 53456800 4775040
06:44:07 54470 8192000000 10108923296 10588196864 1130687904 12.128 100 24 48 0 1227664880 92150752 4826224
06:46:07 95692 8192000000 10025707920 10472800256 1064054144 11.495 94 19 38 3 1100233840 32322528 3857168
06:48:45 77032 8192000000 10100382704 10545188864 1104114176 11.877 98 13 26 0 1161225712 54072992 3038544
06:51:03 60186 8192000000 10046511632 10547757056 1109532528 11.928 100 13 26 0 1172351440 59910368 2908544
06:53:08 58894 8192000000 10093324272 10570821632 1109166960 11.925 98 18 36 0 1189983648 76876880 3939808
06:55:18 41311 8192000000 10115002752 10557890560 1135557584 12.174 100 24 48 1 1185834176 44843104 5433488
06:57:00 54032 8192000000 10147552800 10654830592 1139119104 12.207 99 26 52 0 1239276688 94727376 5430208
06:58:56 60250 8192000000 10113701200 10592321536 1150009264 12.310 100 27 54 0 1218073280 62876816 5187200
07:00:56 25347 8192000000 10107296608 10537463808 1148189520 12.293 99 40 79 0 1188284896 31909600 8185776
07:02:22 61427 8192000000 10119142880 10616631296 1135781776 12.176 99 29 58 0 1195729360 54279360 5668224
07:05:15 55459 8192000000 10068618800 10533076992 1121457840 12.041 98 10 20 1 1150497664 26926848 2112976
07:07:20 46068 8192000000 10106221984 10595119104 1149041296 12.300 100 11 22 0 1208903216 57859568 2002352
07:09:07 56200 8192000000 10123694560 10590044160 1145520176 12.267 100 22 43 2 1218226960 67974544 4732240
07:11:06 45729 8192000000 10077861584 10523828224 1123163168 12.057 98 17 33 0 1148005696 22050576 2791952
07:12:57 44345 8192000000 10104392896 10579197952 1153901088 12.346 100 29 58 2 1218595120 58812112 5881920
07:14:57 33473 8192000000 10102895104 10536751104 1122597824 12.052 98 34 68 0 1155957952 26669552 6690576
07:16:34 66912 8192000000 10112902048 10576179200 1097498848 11.814 96 23 46 1 1184738304 82436032 4803424
07:18:41 83336 8192000000 10073605168 10536443904 1118940752 12.017 98 16 32 0 1154427296 32356576 3129968
07:21:05 72899 8192000000 10072014304 10561314816 1144972432 12.262 100 11 22 0 1181066960 33684576 2409952
07:23:38 61155 8192000000 10088437616 10602799104 1111499760 11.947 97 11 22 1 1181403856 67666752 2237344
07:25:47 67162 8192000000 10078736720 10532130816 1096722688 11.807 97 20 39 0 1138170352 37714096 3733568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-03-2021 07:28:30 to 20-03-2021 07:28:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-03-2021 07:28:46 to 20-03-2021 07:28:48.

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          229399
MON$OLDEST_ACTIVE               231318
MON$OLDEST_SNAPSHOT             231318
MON$NEXT_TRANSACTION            231318
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                       2098304
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Sat Mar 20 07:26:59 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Sat Mar 20 07:27:09 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Sat Mar 20 07:27:13 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

20.03.21 07:28:49. 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-03-20 07:28:52 No severe PSQL-related problems occured
20.03.21 07:28:53. End of report.