Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.09.2021 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16185256 kB
MemAvailable 30839788 kB
Buffers 0 kB
Cached 15489168 kB
SwapCached 388 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-10 16:37:50
DTS_END                         2021-09-10 16:37:50.8020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46890
TRN_ID                          236829
ATT_ID                          664
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: 9350.96 20094 1122115
customer order: creation 168.14 346 20177
customer order: refuse 13.79 343 1655
order to supplier: creation 44.88 1234 5385
order to supplier: removal 3.59 100184 431
invoice (draft): creation 45.35 3236 5442
invoice (draft): removal 53.39 783 6407
invoice accept: apply 41.03 19406 4924
invoice accept: cancel 48.49 791 5819
customer reserve: creation 3836.73 190 460408
customer reserve: removal 4803.86 59 576463
realization accept: apply 81.69 184 9803
realization accept: cancel 83.93 116 10071
payment from customer: creation 57.11 111 6853
payment from customer: removal 5.55 62 666
payment to supplier: creation 48.08 102 5770
payment to supplier: removal 3.60 64 432
service: total inventory turnovers 4.65 1124 558
service: total monetary turnovers 6.33 28 760
service: aggregate perf. data 0.73 21764 87
service: refresh index statistics 0.03 251747 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 16:38:43 to 10-09-2021 16:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2428
TEST_TIME 2 3599
TEST_TIME 3 4314
TEST_TIME 4 4362
TEST_TIME 5 5632
TEST_TIME 6 5313
TEST_TIME 7 5476
TEST_TIME 8 5514
TEST_TIME 9 5617
TEST_TIME 10 5833
TEST_TIME 11 6006
TEST_TIME 12 6338
TEST_TIME 13 6586
TEST_TIME 14 6978
TEST_TIME 15 7520
TEST_TIME 16 7343
TEST_TIME 17 7897
TEST_TIME 18 8366
TEST_TIME 19 8472
TEST_TIME 20 8587
TEST_TIME 21 8632
TEST_TIME 22 8728
TEST_TIME 23 9170
TEST_TIME 24 9593
TEST_TIME 25 10990
TEST_TIME 26 10820
TEST_TIME 27 10671
TEST_TIME 28 10683
TEST_TIME 29 10337
TEST_TIME 30 10339
TEST_TIME 31 10250
TEST_TIME 32 10316
TEST_TIME 33 10111
TEST_TIME 34 10094
TEST_TIME 35 9979
TEST_TIME 36 10001
TEST_TIME 37 10035
TEST_TIME 38 9991
TEST_TIME 39 9893
TEST_TIME 40 9769
TEST_TIME 41 10083
TEST_TIME 42 10149
TEST_TIME 43 10135
TEST_TIME 44 10028
TEST_TIME 45 10059
TEST_TIME 46 9924
TEST_TIME 47 10025
TEST_TIME 48 10258
TEST_TIME 49 10180
TEST_TIME 50 10076
TEST_TIME 51 10141
TEST_TIME 52 10228
TEST_TIME 53 10172
TEST_TIME 54 10049
TEST_TIME 55 9992
TEST_TIME 56 9921
TEST_TIME 57 9842
TEST_TIME 58 9788
TEST_TIME 59 9896
TEST_TIME 60 9855
TEST_TIME 61 9762
TEST_TIME 62 9633
TEST_TIME 63 9644
TEST_TIME 64 9788
TEST_TIME 65 9803
TEST_TIME 66 9783
TEST_TIME 67 9786
TEST_TIME 68 9802
TEST_TIME 69 9775
TEST_TIME 70 9858
TEST_TIME 71 10015
TEST_TIME 72 9943
TEST_TIME 73 9951
TEST_TIME 74 9931
TEST_TIME 75 9938
TEST_TIME 76 9890
TEST_TIME 77 9864
TEST_TIME 78 9849
TEST_TIME 79 9805
TEST_TIME 80 9747
TEST_TIME 81 9756
TEST_TIME 82 9709
TEST_TIME 83 9648
TEST_TIME 84 9612
TEST_TIME 85 9593
TEST_TIME 86 9595
TEST_TIME 87 9525
TEST_TIME 88 9533
TEST_TIME 89 9476
TEST_TIME 90 9460
TEST_TIME 91 9458
TEST_TIME 92 9443
TEST_TIME 93 9481
TEST_TIME 94 9507
TEST_TIME 95 9496
TEST_TIME 96 9528
TEST_TIME 97 9549
TEST_TIME 98 9566
TEST_TIME 99 9519
TEST_TIME 100 9577
TEST_TIME 101 9565
TEST_TIME 102 9556
TEST_TIME 103 9562
TEST_TIME 104 9542
TEST_TIME 105 9476
TEST_TIME 106 9440
TEST_TIME 107 9437
TEST_TIME 108 9394
TEST_TIME 109 9353
TEST_TIME 110 9289
TEST_TIME 111 9301
TEST_TIME 112 9295
TEST_TIME 113 9286
TEST_TIME 114 9265
TEST_TIME 115 9289
TEST_TIME 116 9298
TEST_TIME 117 9319
TEST_TIME 118 9386
TEST_TIME 119 9381
TEST_TIME 120 9362

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-09-2021 16:38:46 to 10-09-2021 16:38: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 1548502 1548502 0 0.00 0 10885 6 0 0
doc_list_biud 1548502 1548502 0 0.00 0 58987 28 0 0
sp_add_invoice_to_stock 5077 4924 153 3.01 39 234463 19407 0 153
sp_cancel_adding_invoice 5819 5819 0 0.00 4 75689 791 0 0
sp_cancel_client_order 1655 1655 0 0.00 4 9502 344 0 0
sp_cancel_customer_reserve 576463 576463 0 0.00 1 58989 59 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 2371 63 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 3061 65 0 0
sp_cancel_supplier_invoice 6415 6407 8 0.12 1 19894 784 0 8
sp_cancel_supplier_order 431 431 0 0.00 12 1026059 100185 0 0
sp_cancel_write_off 10071 10071 0 0.00 1 14304 116 0 0
sp_client_order 20177 20177 0 0.00 3 23816 346 0 0
sp_customer_reserve 460408 460408 0 0.00 0 27669 191 0 0
sp_fill_shopping_cart 30997 30997 0 0.00 1 11577 35 0 0
sp_fill_shopping_cart_clo_res 460402 460402 0 0.00 0 14223 14 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 19764 306 0 0
sp_kill_cost_storno 16988 16988 0 0.00 0 5203 53 0 0
sp_kill_qstorno_ret_qs2qd 1169835 1169835 0 0.00 0 58982 32 0 0
sp_kill_qty_storno 615556 615556 0 0.00 0 58987 70 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 10605 6 0 0
sp_make_cost_storno 27333 27333 0 0.00 0 9726 51 0 0
sp_multiply_rows_for_pdistr 27333 27333 0 0.00 0 7255 27 0 0
sp_multiply_rows_for_qdistr 20177 20177 0 0.00 0 14301 159 0 0
sp_pay_from_customer 6853 6853 0 0.00 3 11199 112 0 0
sp_pay_to_supplier 5770 5770 0 0.00 5 9979 102 0 0
sp_payment_common 12623 12623 0 0.00 1 11193 26 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 9491 316 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 33900 340 0 0
sp_qd_handle_on_reserve_upd_sts 9803 9803 0 0.00 0 16546 66 0 0
sp_reserve_write_off 9803 9803 0 0.00 6 16568 185 0 0
sp_supplier_invoice 5442 5442 0 0.00 6 109235 3237 0 0
sp_supplier_order 5385 5385 0 0.00 5 50118 1234 0 0
srv_aggregate_perf_data 87 87 0 0.00 2552 388144 21764 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2837 149450 45564 0 0
srv_find_qd_qs_mism 1075173 1075173 0 0.00 0 10785 6 0 0
srv_make_invnt_saldo 558 558 0 0.00 34 69682 1124 0 0
srv_make_money_saldo 760 760 0 0.00 1 4711 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 139395 362521 251747 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 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 4925 4925 0 0.00 0 9258 142 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 2277 95 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 2248 12 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 1232 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1137 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 561 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1009 12 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 24071 1418 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 552 3 0 0
t$perf-norm:sp_client_order 20177 20177 0 0.00 0 9242 15 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 10890 23 0 0
t$perf-norm:sp_pay_from_customer 6853 6853 0 0.00 0 13117 10 0 0
t$perf-norm:sp_pay_to_supplier 5770 5770 0 0.00 0 4482 9 0 0
t$perf-norm:sp_reserve_write_off 9803 9803 0 0.00 0 7093 10 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 10109 23 0 0
t$perf-norm:sp_supplier_order 5386 5386 0 0.00 0 3530 15 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 2106 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 3171 27 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 2931 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10116 9810 306 3.02 0 5341 22 0 306
v_all_customers 37092 37092 0 0.00 0 64 1 0 0
v_all_suppliers 30246 30246 0 0.00 0 14 1 0 0
v_all_wares 40354 40354 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 1017 9 0 0
v_cancel_client_order 3310 3310 0 0.00 0 1415 4 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 1959 7 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 2466 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3048 9 0 0
v_cancel_supplier_invoice 780 764 16 2.05 0 745 16 0 16
v_cancel_supplier_order 862 862 0 0.00 0 257 3 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5533 17 0 0
v_max_id_clo_ord 5383 5383 0 0.00 0 447 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 2538 11 0 0
v_max_id_ord_sup 5437 5437 0 0.00 0 6174 5 0 0
v_max_non_paid_invoice 1467 1467 0 0.00 0 3115 4 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 1807 4 0 0
v_min_id_clo_ord 5383 5383 0 0.00 0 3204 7 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 1500 8 0 0
v_min_id_ord_sup 5437 5437 0 0.00 0 5400 8 0 0
v_min_non_paid_invoice 5770 5770 0 0.00 0 3667 14 0 0
v_min_non_paid_realizn 6853 6853 0 0.00 0 8175 19 0 0
v_our_firm 9890 9890 0 0.00 0 64 1 0 0
v_reserve_write_off 19604 19604 0 0.00 0 5035 6 0 0
x_lock_depdocs_on_canc_invoice 11844 11844 0 0.00 0 16157 117 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 19624 418 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 2 50110 1155 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 3 109207 3116 0 0
x_make_qstorno_2100_3300 460408 460408 0 0.00 0 27513 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 16:38:51 to 10-09-2021 16:38: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
14:07:54 3 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:54 28514 8192000000 10380035968 10702729216 1429074640 14.853 100 86 172 0 1462523568 6244096 27204832
14:10:32 17216 8192000000 10438206224 10767896576 1491471648 15.402 100 90 180 1 1529408832 8952784 28984400
14:11:56 18478 8192000000 10507079376 10854006784 1557883936 15.978 100 84 168 0 1594521168 10113328 26523904
14:13:19 13603 8192000000 10562666384 10923167744 1624097200 16.545 100 72 144 1 1656471184 11213120 21160864
14:14:35 10260 8192000000 10639661264 11009110016 1696403696 17.155 100 76 150 2 1730320240 11460272 22456272
14:15:48 7633 8192000000 10692186608 11073826816 1751432672 17.613 100 71 140 1 1784381408 13246752 19701984
14:16:58 2536 8192000000 10747623072 11129679872 1806270240 18.065 100 76 152 0 1840544464 12515104 21759120
14:18:02 2203 8192000000 10801575280 11185655808 1853091360 18.447 100 95 190 1 1893684624 13908464 26684800
14:19:05 16561 8192000000 10800730960 11198603264 1867577088 18.565 100 65 130 0 1895747888 9048256 19122544
14:20:23 6609 8192000000 10786761856 11207421952 1874798512 18.623 100 31 59 2 1890230224 7080416 8351296
14:21:31 4441 8192000000 10802327776 11225305088 1887549264 18.726 100 28 56 2 1902420752 7198512 7672976
14:22:38 60276 8192000000 10813223968 11245744128 1894224352 18.780 100 16 32 0 1904555024 5366896 4963776
14:24:42 7534 8192000000 10830417744 11259744256 1910378016 18.910 100 26 52 1 1924089440 6152560 7558864
14:25:50 4760 8192000000 10822506416 11261452288 1913184560 18.932 100 25 50 2 1926720256 6500032 7035664
14:26:56 36562 8192000000 10790308832 11233271808 1846119408 18.391 97 21 42 2 1857025872 4877840 6028624
14:28:54 4545 8192000000 10751417136 11177050112 1799781920 18.012 96 38 77 2 1816668512 5824832 11061760
14:29:59 6600 8192000000 10697636800 11106672640 1725499472 17.398 95 27 54 1 1739220592 5840336 7880784
14:31:08 6718 8192000000 10695020368 11109330944 1740880448 17.526 97 19 36 1 1751319296 5556816 4882032
14:32:18 34509 8192000000 10732717984 11158671360 1811453936 18.108 99 30 60 2 1826705008 6388400 8862672
14:33:52 7100 8192000000 10757350048 11173302272 1835137312 18.301 100 30 60 3 1850547792 6679344 8731136
14:35:02 10816 8192000000 10783859904 11210264576 1869336640 18.579 100 21 42 2 1881729168 6278480 6114048
14:36:17 63587 8192000000 10817770224 11247558656 1898582944 18.815 100 41 82 0 1916252784 6540384 11129456
14:38:26 37047 8192000000 10867448384 11295449088 1935213840 19.109 100 40 80 1 1955814528 9495312 11105376
14:40:09 17246 8192000000 10890187984 11316793344 1957060496 19.283 100 50 99 1 1983523696 12755120 13708080
14:41:45 11478 8192000000 10923714336 11349528576 1975371872 19.428 100 50 100 2 2009561200 19828608 14360720
14:43:29 16944 8192000000 10916141296 11355471872 1964812336 19.344 99 39 78 1 1995466784 18387568 12266880
14:44:55 15217 8192000000 10940737152 11378688000 1992483632 19.563 100 50 100 0 2028293168 21598608 14210928
14:46:21 3620 8192000000 10966037888 11405217792 2016131616 19.750 100 52 103 2 2055492560 24632512 14728432
14:47:28 8477 8192000000 10957227856 11391184896 1998204624 19.609 99 64 126 2 2046084432 29299968 18579840
14:48:43 4434 8192000000 10986814048 11412602880 2001449952 19.634 99 76 149 0 2061321264 37809840 22061472
14:49:50 3212 8192000000 10984132080 11416645632 2017106720 19.757 100 72 145 3 2080808480 41407008 22294752
14:50:57 3561 8192000000 10990092464 11415056384 2006189184 19.672 100 72 144 0 2072445344 43899808 22356352
14:52:03 3783 8192000000 10961756448 11376771072 1970355152 19.388 100 82 164 1 2043717200 48093824 25268224
14:53:11 14504 8192000000 10931352960 11353485312 1937769600 19.129 99 64 128 3 2001965248 43975536 20220112
14:54:32 2837 8192000000 10969819232 11378163712 1971623472 19.398 100 83 167 2 2044910240 48480240 24806528
14:55:40 9136 8192000000 10941402016 11368484864 1964418368 19.341 100 57 114 2 2027007168 44595136 17993664
14:56:54 22952 8192000000 10925572208 11367809024 1938272864 19.133 98 37 73 0 1994228464 43998848 11956752
14:58:29 14064 8192000000 10956445792 11389956096 1950266240 19.229 98 51 103 1 2014730688 47461728 17002720
14:59:48 27833 8192000000 10946500112 11392327680 1956751856 19.280 99 30 60 3 2020739936 53181296 10806784
15:01:17 56500 8192000000 10924311232 11384696832 1935485600 19.111 99 21 42 1 1998849216 55215168 8148448
15:03:20 40845 8192000000 10895391680 11329024000 1880452704 18.669 96 41 84 2 1901901312 8906592 12542016
15:05:04 75082 8192000000 10861418112 11333922816 1914953456 18.946 99 26 52 0 1939905584 17454960 7497168
15:07:52 43205 8192000000 10902019664 11335921664 1940825200 19.153 99 43 86 2 1965099600 12537536 11736864
15:09:36 54968 8192000000 10876663472 11339382784 1920110656 18.988 98 12 24 0 1936565056 13311264 3143136
15:11:34 34231 8192000000 10905479776 11355435008 1962331472 19.325 100 21 41 1 1980938016 13166512 5440032
15:13:08 37090 8192000000 10924503456 11381366784 1959033264 19.298 99 23 46 1 1988141280 21716400 7391616
15:15:04 94024 8192000000 10921582608 11393568768 1990956656 19.551 100 23 46 0 2014067856 15896944 7214256
15:18:08 71149 8192000000 10920601152 11420524544 1989612272 19.541 100 17 34 1 2018073840 22927744 5533824
15:20:20 59481 8192000000 10906836880 11382857728 1953861952 19.257 98 11 23 0 1965588688 8820240 2906496
15:22:24 43324 8192000000 10939299328 11383603200 1986906544 19.519 100 24 47 1 2002557968 8873504 6777920
15:24:09 43123 8192000000 10939920592 11412099072 1998691792 19.612 100 22 44 0 2017733168 13125056 5916320
15:26:17 54444 8192000000 10925270112 11393343488 1962895072 19.329 98 10 20 0 1976810784 11207760 2707952
15:28:34 64489 8192000000 10922234896 11400212480 1968585520 19.374 99 16 32 1 1984282288 11452608 4244160
15:30:39 149450 8192000000 10951961008 11417202688 1977525088 19.445 98 24 46 2 1996990736 11771216 7694432
15:34:21 38976 8192000000 10951411104 11413188608 2009537504 19.698 100 38 76 3 2035828080 14952928 11337648
15:36:02 43367 8192000000 10866514368 11343142912 1897668352 18.808 96 29 58 3 1928081376 21822784 8590240
15:37:53 55848 8192000000 10873387152 11344957440 1899749008 18.824 97 14 28 0 1917376208 13596496 4030704
15:39:50 65879 8192000000 10913509280 11398664192 1975702368 19.431 100 19 38 0 2005559440 24425568 5431504
15:42:05 55188 8192000000 10877913632 11333373952 1855632480 18.468 94 21 41 0 1873037152 10587680 6816992
15:44:04 82759 8192000000 10889903360 11359813632 1932892496 19.090 99 24 47 1 1955004272 15243824 6867952
15:46:38 64962 8192000000 10902047920 11371450368 1927139968 19.044 98 18 37 0 1951766592 19222864 5403760
15:49:20 138170 8192000000 10868372176 11330785280 1776444976 17.820 90 10 20 0 1789942160 10781504 2715680
15:52:44 39264 8192000000 10897630032 11359891456 1927643856 19.048 99 26 51 0 1947192992 12494640 7054496
15:54:31 50633 8192000000 10882089504 11344588800 1918732592 18.977 98 51 102 0 1949332176 16076704 14522880
15:56:46 53208 8192000000 10870276032 11344699392 1938069728 19.131 100 17 34 0 1956263744 13324400 4869616
15:58:40 40667 8192000000 10871596608 11354222592 1952305776 19.245 100 12 24 1 1967222320 11370592 3545952
16:00:57 53413 8192000000 10869907808 11343462400 1930311040 19.069 99 15 30 0 1943087232 8502448 4273744
16:02:51 61331 8192000000 10852054512 11335155712 1893696096 18.776 97 16 31 3 1906842368 8973264 4173008
16:05:11 80934 8192000000 10852219008 11335753728 1916754832 18.961 99 15 30 1 1934073296 13851072 3467392
16:07:32 60321 8192000000 10873319184 11352694784 1948619344 19.215 100 16 32 2 1963387344 10017296 4750704
16:09:33 43823 8192000000 10884167440 11360833536 1944137456 19.180 99 19 38 0 1962598960 12982176 5479328
16:11:19 16082 8192000000 10914614656 11372183552 1978311792 19.451 100 51 103 2 2004189328 11128000 14749536
16:12:43 58100 8192000000 10914670144 11391610880 1958825776 19.297 99 24 48 0 1986325984 20305936 7194272
16:14:45 64169 8192000000 10915819584 11389710336 1967334336 19.364 99 22 44 0 1984003824 10483920 6185568
16:16:50 45979 8192000000 10909330272 11402199040 1987951296 19.528 100 24 48 1 2009071968 13662256 7458416
16:18:36 37956 8192000000 10915443424 11366649856 1925049312 19.027 97 20 40 0 1939253408 8595872 5608224
16:20:15 24976 8192000000 10904008752 11358552064 1955500432 19.270 99 37 74 0 1977119232 11549840 10068960
16:21:43 104421 8192000000 10886430688 11358744576 1931444272 19.078 98 24 48 3 1947457760 8680448 7333040
16:24:54 90819 8192000000 10884309856 11367489536 1933021648 19.091 98 5 10 0 1943017040 8846576 1148816
16:27:25 35829 8192000000 10874412464 11352760320 1941866304 19.162 99 15 28 0 1953881328 7863040 4151984
16:30:02 59555 8192000000 10879290608 11350011904 1919353216 18.982 97 20 40 3 1944563504 19342112 5868176
16:32:04 66115 8192000000 10890654832 11362779136 1883700768 18.695 95 17 34 1 1906185760 17493776 4991216
16:34:14 53981 8192000000 10871037504 11332763648 1870397456 18.587 96 17 34 0 1893913552 18352576 5163520
16:36:08 28857 8192000000 10861347152 11321802752 1908791744 18.897 100 26 52 1 1930164704 14400176 6972784
16:37:46 17150 8192000000 9039969648 9241628672 202402048 2.411 11 2 2 1 207513328 4695424 415856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 16:38:56 to 10-09-2021 16:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 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 10-09-2021 16:39:12 to 10-09-2021 16:39:14.

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          237136
MON$OLDEST_ACTIVE               237137
MON$OLDEST_SNAPSHOT             237137
MON$NEXT_TRANSACTION            237137
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                       2023936
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.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 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 ---
--- end of diff output ---

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

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