Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.08.2021 19:39
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 16482892 kB
MemAvailable 31061996 kB
Buffers 0 kB
Cached 15215496 kB
SwapCached 2576 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-08-06 19:37:42
DTS_END                         2021-08-06 19:37:42.6490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32886
TRN_ID                          240347
ATT_ID                          724
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: 9095.70 18252 1091484
customer order: creation 176.13 373 21135
customer order: refuse 14.73 339 1767
order to supplier: creation 47.11 1252 5653
order to supplier: removal 3.42 97285 410
invoice (draft): creation 46.73 3496 5608
invoice (draft): removal 50.03 774 6003
invoice accept: apply 42.87 19665 5144
invoice accept: cancel 44.84 831 5381
customer reserve: creation 3935.35 195 472242
customer reserve: removal 4432.53 59 531904
realization accept: apply 84.56 186 10147
realization accept: cancel 84.37 114 10124
payment from customer: creation 60.79 133 7295
payment from customer: removal 5.66 83 679
payment to supplier: creation 50.53 171 6064
payment to supplier: removal 3.44 65 413
service: total inventory turnovers 4.73 999 568
service: total monetary turnovers 7.00 24 840
service: aggregate perf. data 0.86 18300 103
service: refresh index statistics 0.03 220689 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-08-2021 19:39:31 to 06-08-2021 19:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4031
TEST_TIME 2 4090
TEST_TIME 3 4830
TEST_TIME 4 5121
TEST_TIME 5 5218
TEST_TIME 6 5206
TEST_TIME 7 5561
TEST_TIME 8 5788
TEST_TIME 9 5588
TEST_TIME 10 5562
TEST_TIME 11 5893
TEST_TIME 12 6212
TEST_TIME 13 6415
TEST_TIME 14 6577
TEST_TIME 15 6749
TEST_TIME 16 6906
TEST_TIME 17 7030
TEST_TIME 18 7418
TEST_TIME 19 8037
TEST_TIME 20 7871
TEST_TIME 21 8161
TEST_TIME 22 8614
TEST_TIME 23 8600
TEST_TIME 24 9230
TEST_TIME 25 9222
TEST_TIME 26 9416
TEST_TIME 27 9682
TEST_TIME 28 9856
TEST_TIME 29 10304
TEST_TIME 30 10177
TEST_TIME 31 10335
TEST_TIME 32 10162
TEST_TIME 33 10163
TEST_TIME 34 10140
TEST_TIME 35 10048
TEST_TIME 36 9940
TEST_TIME 37 9772
TEST_TIME 38 9809
TEST_TIME 39 9743
TEST_TIME 40 9697
TEST_TIME 41 9582
TEST_TIME 42 9456
TEST_TIME 43 9461
TEST_TIME 44 9387
TEST_TIME 45 9296
TEST_TIME 46 9262
TEST_TIME 47 9179
TEST_TIME 48 9145
TEST_TIME 49 9179
TEST_TIME 50 9089
TEST_TIME 51 8983
TEST_TIME 52 8903
TEST_TIME 53 8832
TEST_TIME 54 8762
TEST_TIME 55 8860
TEST_TIME 56 8856
TEST_TIME 57 8831
TEST_TIME 58 8798
TEST_TIME 59 8919
TEST_TIME 60 9042
TEST_TIME 61 9095
TEST_TIME 62 9087
TEST_TIME 63 9327
TEST_TIME 64 9295
TEST_TIME 65 9414
TEST_TIME 66 9329
TEST_TIME 67 9362
TEST_TIME 68 9422
TEST_TIME 69 9434
TEST_TIME 70 9323
TEST_TIME 71 9313
TEST_TIME 72 9284
TEST_TIME 73 9279
TEST_TIME 74 9248
TEST_TIME 75 9189
TEST_TIME 76 9190
TEST_TIME 77 9185
TEST_TIME 78 9133
TEST_TIME 79 9047
TEST_TIME 80 9059
TEST_TIME 81 8997
TEST_TIME 82 8985
TEST_TIME 83 8962
TEST_TIME 84 8992
TEST_TIME 85 9032
TEST_TIME 86 9029
TEST_TIME 87 9056
TEST_TIME 88 9058
TEST_TIME 89 9090
TEST_TIME 90 9097
TEST_TIME 91 9137
TEST_TIME 92 9144
TEST_TIME 93 9139
TEST_TIME 94 9158
TEST_TIME 95 9196
TEST_TIME 96 9181
TEST_TIME 97 9156
TEST_TIME 98 9083
TEST_TIME 99 9122
TEST_TIME 100 9107
TEST_TIME 101 9064
TEST_TIME 102 9030
TEST_TIME 103 9047
TEST_TIME 104 9043
TEST_TIME 105 9041
TEST_TIME 106 9052
TEST_TIME 107 9082
TEST_TIME 108 9092
TEST_TIME 109 9084
TEST_TIME 110 9100
TEST_TIME 111 9109
TEST_TIME 112 9146
TEST_TIME 113 9153
TEST_TIME 114 9161
TEST_TIME 115 9156
TEST_TIME 116 9191
TEST_TIME 117 9150
TEST_TIME 118 9104
TEST_TIME 119 9156
TEST_TIME 120 9097

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-08-2021 19:39:35 to 06-08-2021 19:39:38.

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 1529450 1529450 0 0.00 0 36577 7 0 0
doc_list_biud 1529450 1529450 0 0.00 0 43116 26 0 0
sp_add_invoice_to_stock 5241 5144 97 1.85 35 170161 19666 0 97
sp_cancel_adding_invoice 5381 5381 0 0.00 5 69756 832 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 8210 339 0 0
sp_cancel_customer_reserve 531904 531904 0 0.00 1 43118 60 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 3137 83 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 2560 65 0 0
sp_cancel_supplier_invoice 6008 6003 5 0.08 1 34872 774 0 5
sp_cancel_supplier_order 410 410 0 0.00 8 1013658 97286 0 0
sp_cancel_write_off 10124 10124 0 0.00 1 13134 115 0 0
sp_client_order 21135 21135 0 0.00 3 30410 373 0 0
sp_customer_reserve 472242 472242 0 0.00 0 56854 196 0 0
sp_fill_shopping_cart 32373 32373 0 0.00 1 18141 39 0 0
sp_fill_shopping_cart_clo_res 472237 472237 0 0.00 0 17519 14 0 0
sp_get_clo_for_invoice 5138 5138 0 0.00 0 27848 371 0 0
sp_kill_cost_storno 16597 16597 0 0.00 0 8511 57 0 0
sp_kill_qstorno_ret_qs2qd 1080345 1080345 0 0.00 0 43111 32 0 0
sp_kill_qty_storno 570872 570872 0 0.00 0 43116 70 0 0
sp_lock_selected_doc 21173 21173 0 0.00 0 8683 7 0 0
sp_make_cost_storno 28642 28642 0 0.00 0 24220 60 0 0
sp_multiply_rows_for_pdistr 28642 28642 0 0.00 0 22399 37 0 0
sp_multiply_rows_for_qdistr 21135 21135 0 0.00 0 10703 160 0 0
sp_pay_from_customer 7295 7295 0 0.00 4 21786 134 0 0
sp_pay_to_supplier 6064 6064 0 0.00 4 22412 171 0 0
sp_payment_common 13359 13359 0 0.00 1 12716 32 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 8205 307 0 0
sp_qd_handle_on_invoice_upd_sts 10517 10517 0 0.00 0 18022 343 0 0
sp_qd_handle_on_reserve_upd_sts 10147 10147 0 0.00 0 12777 55 0 0
sp_reserve_write_off 10147 10147 0 0.00 6 24251 187 0 0
sp_supplier_invoice 5608 5608 0 0.00 6 76609 3496 0 0
sp_supplier_order 5653 5653 0 0.00 5 28873 1252 0 0
srv_aggregate_perf_data 103 103 0 0.00 1884 106328 18300 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1624 122712 41960 0 0
srv_find_qd_qs_mism 1042948 1042948 0 0.00 0 36575 6 0 0
srv_make_invnt_saldo 568 568 0 0.00 40 23376 999 0 0
srv_make_money_saldo 840 840 0 0.00 1 734 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 129797 348789 220690 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 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5144 5144 0 0.00 0 17541 173 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 2629 88 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 1267 10 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2994 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 1201 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 2100 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 4542 32 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 28913 1589 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 6740 23 0 0
t$perf-norm:sp_client_order 21135 21135 0 0.00 0 6995 16 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 1175 4 0 0
t$perf-norm:sp_pay_from_customer 7295 7295 0 0.00 0 5950 10 0 0
t$perf-norm:sp_pay_to_supplier 6064 6064 0 0.00 0 4978 9 0 0
t$perf-norm:sp_reserve_write_off 10147 10147 0 0.00 0 10629 18 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 5669 17 0 0
t$perf-norm:sp_supplier_order 5653 5653 0 0.00 0 3930 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 231 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 628 10 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 1408 19 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 4880 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10460 10266 194 1.85 0 14086 26 0 194
v_all_customers 38574 38574 0 0.00 0 94 1 0 0
v_all_suppliers 31302 31302 0 0.00 0 16 1 0 0
v_all_wares 42270 42270 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 14127 24 0 0
v_cancel_client_order 3534 3534 0 0.00 0 4512 5 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 1646 6 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 13938 16 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 326 3 0 0
v_cancel_supplier_invoice 858 848 10 1.16 0 14038 32 0 10
v_cancel_supplier_order 820 820 0 0.00 0 1043 8 0 0
v_cancel_write_off 2134 2134 0 0.00 0 13072 20 0 0
v_max_id_clo_ord 5653 5653 0 0.00 0 3890 3 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1578 15 0 0
v_max_id_ord_sup 5585 5585 0 0.00 0 3832 4 0 0
v_max_non_paid_invoice 1651 1651 0 0.00 0 369 2 0 0
v_max_non_paid_realizn 3809 3809 0 0.00 0 2997 4 0 0
v_min_id_clo_ord 5653 5653 0 0.00 0 2772 5 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 4047 11 0 0
v_min_id_ord_sup 5585 5585 0 0.00 0 5868 6 0 0
v_min_non_paid_invoice 6064 6064 0 0.00 0 5721 19 0 0
v_min_non_paid_realizn 7295 7295 0 0.00 0 7467 22 0 0
v_our_firm 10668 10668 0 0.00 0 95 1 0 0
v_reserve_write_off 20294 20294 0 0.00 0 14150 11 0 0
x_lock_depdocs_on_canc_invoice 10960 10960 0 0.00 0 21021 123 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 11553 363 0 0
x_make_qstorno_1000_1200 5653 5653 0 0.00 2 28853 1170 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 2 76595 3341 0 0
x_make_qstorno_2100_3300 472242 472242 0 0.00 0 43283 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-08-2021 19:39:39 to 06-08-2021 19:39:43.

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
17:07:46 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:46 38137 8192000000 10389721392 10710593536 1428825568 14.851 100 83 166 2 1462507536 6231072 27450896
17:10:31 20434 8192000000 10449636896 10779398144 1492565888 15.411 100 85 170 0 1528423936 8626832 27231216
17:11:59 19871 8192000000 10510583632 10856960000 1555559136 15.958 100 83 166 1 1591235984 9906064 25770784
17:13:23 10674 8192000000 10578636704 10938589184 1638133520 16.664 100 80 160 0 1673679008 11229216 24316272
17:14:42 5142 8192000000 10640936784 11007078400 1701103584 17.194 100 81 163 0 1736717104 12705184 22908336
17:15:49 3667 8192000000 10700140304 11083948032 1769942080 17.767 100 77 152 1 1804539392 12721648 21875664
17:16:55 7622 8192000000 10746592896 11132878848 1810515568 18.100 100 80 160 0 1847607344 14976832 22114944
17:18:05 1304 8192000000 10808277184 11192680448 1866765808 18.558 100 92 184 2 1904640800 11936512 25938480
17:19:07 27818 8192000000 10803601216 11205050368 1871605376 18.597 100 46 93 4 1890964720 4936752 14422592
17:20:49 14490 8192000000 10778112832 11212513280 1874899120 18.624 100 16 32 0 1885777344 6250320 4627904
17:22:05 7498 8192000000 10794322384 11223924736 1883851744 18.696 100 11 22 2 1892861024 5636576 3372704
17:23:13 6475 8192000000 10798466000 11235139584 1897690448 18.808 100 27 54 3 1911932256 6445936 7795872
17:24:20 8161 8192000000 10800046000 11241963520 1901170176 18.836 100 28 56 0 1915544400 5919776 8454448
17:25:55 4945 8192000000 10822657680 11254472704 1907317184 18.885 100 31 62 2 1922625120 6454592 8853344
17:27:01 5232 8192000000 10818055376 11250069504 1893916832 18.777 99 37 74 1 1912037824 6890304 11230688
17:28:10 26729 8192000000 10778971392 11201568768 1819887040 18.177 96 38 76 1 1837031440 6963952 10180448
17:29:39 4477 8192000000 10746371728 11159126016 1816290064 18.147 99 40 80 1 1835298080 6817200 12190816
17:30:45 5381 8192000000 10711456512 11120246784 1763941344 17.717 98 30 60 2 1779052624 6353648 8757632
17:31:51 73182 8192000000 10702641776 11128532992 1711886128 17.284 94 20 39 1 1722321312 5075808 5359376
17:34:04 7904 8192000000 10763289440 11181293568 1840922384 18.348 100 18 36 1 1851137824 5082352 5133088
17:35:15 9509 8192000000 10773400080 11200086016 1859821344 18.502 100 16 31 1 1870154784 5354608 4978832
17:36:27 9812 8192000000 10789022672 11215290368 1864916000 18.543 100 22 43 2 1880866560 9710432 6240128
17:37:39 51214 8192000000 10837104016 11258060800 1897310272 18.805 100 39 78 1 1913472480 5638384 10523824
17:39:34 19266 8192000000 10868302256 11286704128 1930437312 19.070 100 60 120 0 1957582384 9780800 17364272
17:41:06 8891 8192000000 10892161280 11313291264 1952737840 19.248 100 59 116 2 1981412272 11291584 17382848
17:42:18 10333 8192000000 10907393408 11330674688 1963074992 19.330 100 52 104 2 1992330096 13438432 15816672
17:43:32 6850 8192000000 10915004576 11335491584 1967445040 19.365 100 67 133 1 2002556368 15453024 19658304
17:44:59 8996 8192000000 10907414928 11338379264 1953834528 19.257 99 42 84 1 1980071440 14159984 12076928
17:46:12 14095 8192000000 10939315424 11367690240 1996601520 19.596 100 58 115 1 2031365344 17119664 17644160
17:47:31 13413 8192000000 10929126400 11371319296 1979947504 19.464 99 45 90 3 2011964064 17818752 14197808
17:48:52 1623 8192000000 10985483488 11398266880 2012864960 19.724 100 80 159 2 2061793984 24411920 24517104
17:49:58 2509 8192000000 10953839984 11363868672 1965977488 19.354 98 83 165 1 2019268544 28586720 24704336
17:51:03 3244 8192000000 10959964272 11383971840 1998664384 19.612 100 82 164 1 2052617360 29336192 24616784
17:52:11 2805 8192000000 10920414128 11340472320 1954851184 19.265 100 67 133 3 2007430960 32374832 20204944
17:53:20 3919 8192000000 10929789712 11343667200 1937735664 19.129 98 77 154 1 1994553984 33307872 23510448
17:54:30 3532 8192000000 10922703680 11332956160 1938842368 19.138 99 79 158 1 2000366256 36808272 24715616
17:55:41 3022 8192000000 10948799696 11366989824 1968267424 19.372 100 81 161 1 2035008560 41310224 25430912
17:56:48 12474 8192000000 10918245264 11351486464 1958610560 19.295 100 65 130 1 2020724688 41247376 20866752
17:58:08 4039 8192000000 10938179088 11360956416 1960146272 19.307 100 67 134 3 2020200896 38720320 21334304
17:59:16 18973 8192000000 10920579424 11366596608 1963223536 19.332 100 32 64 1 2011317904 37211584 10882784
18:00:45 3702 8192000000 10927476816 11363225600 1955317072 19.269 100 42 84 2 2009222224 39435392 14469760
18:01:53 13332 8192000000 10919211088 11383181312 1966103264 19.355 100 20 40 3 2004836208 32059696 6673248
18:03:11 24548 8192000000 10914470800 11362250752 1944188416 19.180 100 25 50 2 1992581808 39935280 8458112
18:04:44 42916 8192000000 10876966896 11335446528 1883968432 18.697 97 21 42 1 1922087872 30817488 7301952
18:06:28 73717 8192000000 10860076032 11288633344 1893432320 18.773 98 30 59 1 1923984384 22034560 8517504
18:08:47 38690 8192000000 10883290448 11325067264 1922628960 19.008 99 23 44 0 1944445392 15843312 5973120
18:10:27 16424 8192000000 10906416656 11355082752 1968820752 19.376 100 52 101 1 1994649040 11330512 14497776
18:11:56 58200 8192000000 10885621088 11353559040 1957043376 19.283 100 12 21 2 1971410064 11645104 2721584
18:13:57 77707 8192000000 10893559696 11360567296 1957500640 19.286 99 9 19 1 1974064144 13799744 2763760
18:16:19 35139 8192000000 10872623344 11331006464 1941165696 19.156 99 18 36 3 1960651824 13910016 5576112
18:18:05 49023 8192000000 10892988160 11351130112 1944128976 19.180 99 20 40 1 1962979856 11792656 7058224
18:19:57 116120 8192000000 10893757616 11349921792 1959026320 19.298 100 24 48 0 1976417040 10749408 6641312
18:23:01 5658 8192000000 10919717024 11365781504 1986668592 19.517 100 63 125 3 2012145328 8267008 17209728
18:24:08 34558 8192000000 10901836464 11370147840 1966049136 19.354 99 32 64 2 1990042352 15108672 8884544
18:25:53 45555 8192000000 10884942416 11364274176 1968136192 19.371 100 7 15 0 1980831744 10907472 1788080
18:27:42 33515 8192000000 10888136576 11354271744 1964998736 19.346 100 13 26 0 1976302736 7869056 3434944
18:29:15 40993 8192000000 10889695504 11355095040 1945762496 19.193 98 31 58 1 1960322992 6257584 8302912
18:30:57 66028 8192000000 10883060928 11366379520 1956759680 19.280 99 10 20 0 1968053776 8679824 2614272
18:33:14 37795 8192000000 10873878144 11350274048 1917419904 18.966 97 9 18 0 1926769776 6874192 2475680
18:34:53 37178 8192000000 10905382832 11366182912 1956073744 19.275 99 28 56 1 1974093296 9542784 8476768
18:36:52 56312 8192000000 10864635760 11335462912 1910167792 18.908 97 15 30 1 1930428512 15615616 4645104
18:39:22 84140 8192000000 10866809984 11344384000 1913384208 18.934 98 13 26 0 1935329904 18137056 3808640
18:41:47 64793 8192000000 10876300832 11353022464 1930690208 19.072 99 22 44 1 1953395088 16635120 6069760
18:43:53 24191 8192000000 10883134240 11336605696 1933797792 19.097 99 49 98 2 1958255072 11038464 13418816
18:45:17 66283 8192000000 10851684352 11328053248 1876899872 18.640 97 11 22 2 1891880688 11853136 3127680
18:48:12 75760 8192000000 10850686704 11318419456 1879873376 18.664 97 29 58 2 1904353664 15943696 8536592
18:50:28 72479 8192000000 10824502496 11290185728 1797101728 17.990 93 13 26 0 1809208976 8285360 3821888
18:52:53 55105 8192000000 10846086848 11319521280 1883370976 18.692 98 10 21 0 1897470832 11468560 2631296
18:54:50 64141 8192000000 10833747872 11307171840 1887771104 18.728 98 13 25 2 1898519760 6407968 4340688
18:56:55 38617 8192000000 10866961120 11335049216 1945716768 19.192 100 30 60 2 1963859824 10134608 8008448
18:58:39 58379 8192000000 10854686048 11331125248 1926803536 19.041 99 18 36 0 1941950800 10098992 5048272
19:01:06 65750 8192000000 10880991648 11359834112 1913964656 18.938 98 12 25 0 1934850896 17567856 3318384
19:03:22 56629 8192000000 10861579216 11332173824 1937279840 19.125 100 19 37 1 1956350336 13220912 5849584
19:05:26 16817 8192000000 10889760288 11346006016 1951389712 19.238 100 40 80 0 1975623296 12392832 11840752
19:06:45 27221 8192000000 10903522080 11350777856 1959315120 19.301 100 39 78 2 1982004464 11335760 11353584
19:08:13 19665 8192000000 10910996848 11367321600 1960550944 19.310 99 48 96 3 1985090880 11830992 12708944
19:09:47 59912 8192000000 10902318144 11359592448 1932565056 19.087 98 31 62 1 1955361680 14670640 8125984
19:12:10 84339 8192000000 10893224848 11366641664 1917765024 18.969 97 23 46 0 1936025072 12000944 6259104
19:14:35 112182 8192000000 10899128096 11368792064 1959051232 19.298 99 24 47 1 1979169664 13413088 6705344
19:17:29 82094 8192000000 10897723328 11386781696 1949154016 19.220 98 11 22 0 1969320272 17022688 3143568
19:19:53 64678 8192000000 10858261776 11322920960 1836269056 18.310 93 7 14 0 1845175616 7352080 1554480
19:22:06 43670 8192000000 10891018512 11359510528 1943278160 19.173 99 16 31 0 1964184432 16439184 4467088
19:24:01 35278 8192000000 10875660416 11336368128 1916135040 18.956 98 18 35 3 1929787376 7905824 5746512
19:25:40 79392 8192000000 10879889824 11337895936 1864824096 18.542 95 18 36 1 1878315760 8403152 5088512
19:28:00 58080 8192000000 10899331376 11364986880 1946000432 19.195 99 41 81 1 1969854384 12370432 11483520
19:30:11 93336 8192000000 10880510832 11329445888 1805123344 18.056 92 26 52 1 1826039408 13892800 7023264
19:32:58 47341 8192000000 10838562048 11314679808 1882644752 18.686 98 22 42 3 1899610768 11409024 5556992
19:34:46 122712 8192000000 10851643280 11330076672 1877013376 18.641 97 19 38 2 1896584496 14391888 5179232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-08-2021 19:39:45 to 06-08-2021 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 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 06-08-2021 19:40:01 to 06-08-2021 19:40:03.

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          239905
MON$OLDEST_ACTIVE               240458
MON$OLDEST_SNAPSHOT             240458
MON$NEXT_TRANSACTION            240458
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                       2017856
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:263: 
:264: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:265:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:266: 
:267: 
:268: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
:271: 
:272: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:274: 
:275: 
:276: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:278: 
:279: 
:280: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:47 2021
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:286: 
:287: 
:288: mscs-test-161.budzdorov.ru    Fri Aug  6 19:37:51 2021
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:290: 
--- end of diff output ---

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

06.08.21 19:40:04. 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-08-06 19:40:06 No severe PSQL-related problems occured
06.08.21 19:40:08. End of report.