Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2021 16: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 16112716 kB
MemAvailable 30796744 kB
Buffers 0 kB
Cached 15602060 kB
SwapCached 1628 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-25 16:37:53
DTS_END                         2021-09-25 16:37:53.2980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55480
TRN_ID                          243006
ATT_ID                          680
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: 9007.43 23083 1080892
customer order: creation 173.35 363 20802
customer order: refuse 15.06 386 1807
order to supplier: creation 46.47 1403 5576
order to supplier: removal 3.46 93743 415
invoice (draft): creation 46.99 3507 5639
invoice (draft): removal 51.68 804 6201
invoice accept: apply 41.70 17613 5004
invoice accept: cancel 46.03 734 5523
customer reserve: creation 3799.37 176 455924
customer reserve: removal 4488.45 55 538614
realization accept: apply 83.08 188 9970
realization accept: cancel 81.13 120 9736
payment from customer: creation 60.58 115 7269
payment from customer: removal 5.53 63 663
payment to supplier: creation 48.80 144 5856
payment to supplier: removal 3.62 56 434
service: total inventory turnovers 4.50 1174 540
service: total monetary turnovers 6.83 36 819
service: aggregate perf. data 0.80 22245 96
service: refresh index statistics 0.03 318744 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2021 16:40:15 to 25-09-2021 16:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3057
TEST_TIME 2 3463
TEST_TIME 3 3848
TEST_TIME 4 3310
TEST_TIME 5 3702
TEST_TIME 6 4055
TEST_TIME 7 4283
TEST_TIME 8 4979
TEST_TIME 9 5106
TEST_TIME 10 5643
TEST_TIME 11 6033
TEST_TIME 12 6083
TEST_TIME 13 6266
TEST_TIME 14 6385
TEST_TIME 15 6976
TEST_TIME 16 7010
TEST_TIME 17 7217
TEST_TIME 18 7326
TEST_TIME 19 7783
TEST_TIME 20 8644
TEST_TIME 21 9173
TEST_TIME 22 9140
TEST_TIME 23 9533
TEST_TIME 24 9705
TEST_TIME 25 9805
TEST_TIME 26 9796
TEST_TIME 27 10220
TEST_TIME 28 10384
TEST_TIME 29 10160
TEST_TIME 30 10060
TEST_TIME 31 10278
TEST_TIME 32 10334
TEST_TIME 33 10166
TEST_TIME 34 9972
TEST_TIME 35 9839
TEST_TIME 36 9717
TEST_TIME 37 9580
TEST_TIME 38 9629
TEST_TIME 39 9591
TEST_TIME 40 9446
TEST_TIME 41 9414
TEST_TIME 42 9449
TEST_TIME 43 9351
TEST_TIME 44 9313
TEST_TIME 45 9293
TEST_TIME 46 9214
TEST_TIME 47 9167
TEST_TIME 48 9074
TEST_TIME 49 9005
TEST_TIME 50 8982
TEST_TIME 51 9036
TEST_TIME 52 8950
TEST_TIME 53 9001
TEST_TIME 54 8940
TEST_TIME 55 8930
TEST_TIME 56 9014
TEST_TIME 57 8988
TEST_TIME 58 8938
TEST_TIME 59 8893
TEST_TIME 60 9078
TEST_TIME 61 9230
TEST_TIME 62 9225
TEST_TIME 63 9260
TEST_TIME 64 9201
TEST_TIME 65 9271
TEST_TIME 66 9332
TEST_TIME 67 9271
TEST_TIME 68 9164
TEST_TIME 69 9141
TEST_TIME 70 9128
TEST_TIME 71 9129
TEST_TIME 72 9079
TEST_TIME 73 9064
TEST_TIME 74 9093
TEST_TIME 75 9109
TEST_TIME 76 9047
TEST_TIME 77 9027
TEST_TIME 78 9038
TEST_TIME 79 9007
TEST_TIME 80 9049
TEST_TIME 81 9026
TEST_TIME 82 9073
TEST_TIME 83 9102
TEST_TIME 84 9095
TEST_TIME 85 9139
TEST_TIME 86 9126
TEST_TIME 87 9103
TEST_TIME 88 9257
TEST_TIME 89 9224
TEST_TIME 90 9188
TEST_TIME 91 9325
TEST_TIME 92 9274
TEST_TIME 93 9183
TEST_TIME 94 9164
TEST_TIME 95 9159
TEST_TIME 96 9145
TEST_TIME 97 9116
TEST_TIME 98 9063
TEST_TIME 99 9061
TEST_TIME 100 9075
TEST_TIME 101 9039
TEST_TIME 102 9080
TEST_TIME 103 9124
TEST_TIME 104 9094
TEST_TIME 105 9106
TEST_TIME 106 9088
TEST_TIME 107 9107
TEST_TIME 108 9101
TEST_TIME 109 9090
TEST_TIME 110 9111
TEST_TIME 111 9129
TEST_TIME 112 9161
TEST_TIME 113 9201
TEST_TIME 114 9126
TEST_TIME 115 9096
TEST_TIME 116 9068
TEST_TIME 117 9062
TEST_TIME 118 9085
TEST_TIME 119 9032
TEST_TIME 120 9011

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-09-2021 16:40:19 to 25-09-2021 16:40:22.

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 1503439 1503439 0 0.00 0 20852 6 0 0
doc_list_biud 1503437 1503437 0 0.00 0 28879 26 0 0
sp_add_invoice_to_stock 5089 5004 85 1.67 36 179592 17614 0 85
sp_cancel_adding_invoice 5523 5523 0 0.00 5 49900 734 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 17229 386 0 0
sp_cancel_customer_reserve 538614 538614 0 0.00 1 28196 55 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 5526 63 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 1608 56 0 0
sp_cancel_supplier_invoice 6208 6201 7 0.11 1 28984 804 0 7
sp_cancel_supplier_order 415 415 0 0.00 15 1067079 93743 0 0
sp_cancel_write_off 9736 9736 0 0.00 1 19956 120 0 0
sp_client_order 20802 20802 0 0.00 3 18095 363 0 0
sp_customer_reserve 455924 455924 0 0.00 0 32315 176 0 0
sp_fill_shopping_cart 32007 32007 0 0.00 1 21675 45 0 0
sp_fill_shopping_cart_clo_res 455914 455914 0 0.00 0 23252 14 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 39105 330 0 0
sp_kill_cost_storno 16356 16356 0 0.00 0 15707 58 0 0
sp_kill_qstorno_ret_qs2qd 1093580 1093580 0 0.00 0 28877 30 0 0
sp_kill_qty_storno 577258 577258 0 0.00 0 28878 67 0 0
sp_lock_selected_doc 20874 20874 0 0.00 0 7025 4 0 0
sp_make_cost_storno 28087 28087 0 0.00 0 17318 56 0 0
sp_multiply_rows_for_pdistr 28087 28087 0 0.00 0 11958 32 0 0
sp_multiply_rows_for_qdistr 20802 20802 0 0.00 0 14730 165 0 0
sp_pay_from_customer 7269 7269 0 0.00 4 13349 115 0 0
sp_pay_to_supplier 5856 5856 0 0.00 5 12018 145 0 0
sp_payment_common 13125 13125 0 0.00 1 10353 36 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 17216 355 0 0
sp_qd_handle_on_invoice_upd_sts 10515 10515 0 0.00 0 28212 356 0 0
sp_qd_handle_on_reserve_upd_sts 9970 9970 0 0.00 0 14037 68 0 0
sp_reserve_write_off 9970 9970 0 0.00 6 14846 188 0 0
sp_supplier_invoice 5639 5639 0 0.00 7 91374 3508 0 0
sp_supplier_order 5576 5576 0 0.00 5 56520 1403 0 0
srv_aggregate_perf_data 96 96 0 0.00 2861 206237 22246 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1287 137371 45890 0 0
srv_find_qd_qs_mism 1033244 1033244 0 0.00 0 20851 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 49 88390 1175 0 0
srv_make_money_saldo 819 819 0 0.00 1 7341 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 131178 618891 318744 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5004 5004 0 0.00 0 19221 169 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 3372 85 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 1760 8 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1786 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 752 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 123 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 8575 44 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18963 1461 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 3183 13 0 0
t$perf-norm:sp_client_order 20802 20802 0 0.00 0 16558 16 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 3342 12 0 0
t$perf-norm:sp_pay_from_customer 7269 7269 0 0.00 0 10028 8 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 19097 10 0 0
t$perf-norm:sp_reserve_write_off 9970 9970 0 0.00 0 11616 12 0 0
t$perf-norm:sp_supplier_invoice 5639 5639 0 0.00 0 4422 17 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 5751 18 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 468 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 294 8 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 2961 29 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 1172 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 5 0 0
v_add_invoice_to_stock 10154 9984 170 1.67 0 6162 17 0 170
v_all_customers 37976 37976 0 0.00 0 98 1 0 0
v_all_suppliers 30546 30546 0 0.00 0 17 1 0 0
v_all_wares 41604 41604 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 419 6 0 0
v_cancel_client_order 3614 3614 0 0.00 0 6023 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1598 4 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 497 3 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 670 3 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 441 13 0 14
v_cancel_supplier_order 830 830 0 0.00 0 1682 5 0 0
v_cancel_write_off 2120 2120 0 0.00 0 710 12 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 1415 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2404 15 0 0
v_max_id_ord_sup 5630 5630 0 0.00 0 2239 3 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 3305 7 0 0
v_max_non_paid_realizn 3815 3815 0 0.00 0 2346 3 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 12577 15 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 1421 5 0 0
v_min_id_ord_sup 5630 5630 0 0.00 0 7691 11 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 8329 28 0 0
v_min_non_paid_realizn 7269 7269 0 0.00 0 10347 25 0 0
v_our_firm 10536 10536 0 0.00 0 14 1 0 0
v_reserve_write_off 19940 19940 0 0.00 0 7373 5 0 0
x_lock_depdocs_on_canc_invoice 11330 11330 0 0.00 0 19672 110 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 13138 398 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 1 56479 1311 0 0
x_make_qstorno_1200_2000 5639 5639 0 0.00 3 83965 3350 0 0
x_make_qstorno_2100_3300 455924 455924 0 0.00 0 32312 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2021 16:40:23 to 25-09-2021 16:40:27.

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:56 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:57 29292 8192000000 10387092432 10705924096 1415658528 14.734 100 89 178 0 1450615120 5480080 29476512
14:10:32 15461 8192000000 10440294160 10774020096 1492646304 15.412 100 81 163 0 1526742960 7856512 26240144
14:11:54 18064 8192000000 10510886000 10853756928 1558536832 15.984 100 84 168 0 1594108272 9299728 26271712
14:13:16 8079 8192000000 10567680336 10924007424 1625122752 16.553 100 83 166 0 1661001344 10444000 25434592
14:14:33 6845 8192000000 10638101120 11001757696 1686350224 17.071 100 80 158 1 1720596048 10804688 23441136
14:15:46 4697 8192000000 10693537776 11070205952 1755708000 17.649 100 74 146 0 1789178208 12455488 21014720
14:16:54 4227 8192000000 10738823776 11124240384 1801831792 18.029 100 79 156 1 1836804320 13607664 21364864
14:18:00 1935 8192000000 10782376464 11167780864 1843111360 18.366 100 89 178 0 1881229472 12563856 25554256
14:19:03 1296 8192000000 10825558096 11212660736 1878218832 18.651 100 92 188 0 1918326128 13514512 26592784
14:20:05 10795 8192000000 10818724784 11217285120 1885182192 18.707 100 70 139 1 1910658528 5681344 19794992
14:21:17 8443 8192000000 10795654416 11224805376 1885862336 18.712 100 26 51 3 1899873552 6591056 7420160
14:22:26 10570 8192000000 10807167392 11239616512 1890130176 18.747 100 25 50 1 1907697920 10307600 7260144
14:23:37 6740 8192000000 10805625488 11238408192 1896141056 18.795 100 15 31 0 1906437376 5427264 4869056
14:24:46 68114 8192000000 10832385920 11263815680 1908845824 18.897 100 27 54 1 1922356704 5496144 8014736
14:26:55 5237 8192000000 10810328576 11235553280 1872039232 18.601 98 41 82 3 1890693392 6141632 12512528
14:28:02 7843 8192000000 10779532128 11212656640 1848399232 18.409 98 18 36 0 1859706656 5504624 5802800
14:29:11 48642 8192000000 10760572800 11175841792 1757659744 17.665 95 19 39 0 1768545408 5028208 5857456
14:31:05 3778 8192000000 10693892560 11105673216 1768069824 17.751 99 36 72 1 1789460144 11610256 9780064
14:32:10 7995 8192000000 10706776336 11125161984 1787611056 17.912 100 36 70 1 1808621056 10426224 10583776
14:33:19 6435 8192000000 10750496176 11170619392 1829205312 18.253 100 42 84 3 1847850800 5969328 12676160
14:34:26 40941 8192000000 10764070288 11190853632 1847038672 18.398 100 23 45 0 1858769600 5329072 6401856
14:36:07 5482 8192000000 10783051584 11210502144 1867255312 18.562 100 35 69 2 1883916128 6405760 10255056
14:37:13 9879 8192000000 10816171024 11240296448 1894143984 18.779 100 28 56 0 1908583184 5065344 9373856
14:38:29 22716 8192000000 10858706704 11274502144 1918355552 18.974 100 47 95 0 1938980912 6934368 13690992
14:40:20 21339 8192000000 10879404768 11310227456 1947997600 19.211 100 38 76 1 1973859184 14288656 11572928
14:41:45 92204 8192000000 10881554064 11330416640 1951654432 19.240 100 23 46 1 1976874016 18473008 6746576
14:44:28 14897 8192000000 10918104528 11353731072 1971385936 19.396 100 44 88 0 2005656832 20878016 13392880
14:45:50 4376 8192000000 10922197680 11344707584 1967768288 19.368 99 63 126 1 2008395552 21151648 19475616
14:46:59 10249 8192000000 10937966592 11365183488 1979213744 19.458 100 53 106 0 2021425904 26794096 15418064
14:48:13 2048 8192000000 10971478832 11386470400 2006919008 19.677 100 91 181 2 2059955632 27012256 26024368
14:49:21 5719 8192000000 10952872192 11375177728 1989077872 19.537 100 68 136 3 2035484016 26781024 19625120
14:50:36 2505 8192000000 10975575264 11394719744 2002227760 19.640 100 69 137 0 2053975424 31150304 20597360
14:51:40 3030 8192000000 10972777680 11387797504 1990779504 19.550 100 83 165 1 2056902608 40907920 25215184
14:52:51 1286 8192000000 10970306928 11380051968 1976461728 19.437 100 89 178 1 2048946848 44349888 28135232
14:53:59 7014 8192000000 10949796656 11358556160 1957715712 19.288 99 77 154 2 2024378048 42362672 24299664
14:55:09 11110 8192000000 10904576912 11343527936 1929973040 19.067 99 46 92 0 1984166880 39463456 14730384
14:56:46 6911 8192000000 10909907264 11330260992 1930176384 19.068 100 54 108 1 1991207680 43184736 17846560
14:58:00 5048 8192000000 10887015408 11337392128 1933639968 19.096 100 33 64 0 1976970768 32491632 10839168
14:59:13 15698 8192000000 10861451920 11301441536 1887340016 18.724 98 24 48 1 1920658448 25227360 8091072
15:00:43 9717 8192000000 10893154512 11324309504 1901627792 18.839 98 36 70 0 1940439312 27268656 11542864
15:01:58 18373 8192000000 10893691776 11335331840 1935129568 19.108 100 25 48 0 1965789552 22431936 8228048
15:03:20 10031 8192000000 10882221744 11327844352 1931457168 19.079 100 28 56 5 1968797200 27676624 9663408
15:04:32 13321 8192000000 10876395248 11325329408 1907815520 18.889 99 19 38 1 1947524992 32919984 6789488
15:05:46 57612 8192000000 10892852240 11326816256 1923099264 19.012 99 42 84 0 1962792896 26371200 13322432
15:07:46 21962 8192000000 10891028640 11332874240 1935125232 19.108 99 56 111 1 1966719216 15495584 16098400
15:09:09 33492 8192000000 10870235824 11322310656 1894168848 18.779 97 11 22 1 1908174048 11470240 2534960
15:10:46 61658 8192000000 10889119856 11346735104 1960753552 19.312 100 17 35 2 1975034272 9925232 4355488
15:13:02 86640 8192000000 10922704832 11382300672 1963616848 19.335 99 15 30 0 1981903808 14611744 3675216
15:16:07 137367 8192000000 10917897648 11386236928 1960507504 19.310 98 30 60 2 1987719248 18406640 8805104
15:19:30 60753 8192000000 10911076784 11385348096 1993086352 19.568 100 25 48 0 2011439920 11527968 6825600
15:21:34 2221 8192000000 10960574480 11393056768 2008012560 19.686 100 68 136 1 2036136992 9010528 19113904
15:22:37 18731 8192000000 10921239456 11388555264 1999452016 19.618 100 23 46 0 2013790800 7498448 6840336
15:24:02 48011 8192000000 10922591152 11399929856 2006217312 19.672 100 19 39 2 2023610496 10974592 6418592
15:25:58 68103 8192000000 10931202736 11402223616 2002163328 19.640 100 14 28 0 2020390672 14085392 4141952
15:28:39 72067 8192000000 10909801696 11387936768 1959597936 19.303 98 10 20 1 1974222976 11941088 2683952
15:30:51 43367 8192000000 10894100624 11354361856 1924240176 19.021 96 23 48 3 1937434496 7043328 6150992
15:32:35 50971 8192000000 10897381040 11379855360 1953727600 19.256 98 21 42 0 1969261648 10112816 5421232
15:34:26 32244 8192000000 10889783088 11357315072 1947346864 19.205 99 13 25 2 1958909520 8093840 3468816
15:36:00 71198 8192000000 10878192720 11350249472 1872934048 18.608 94 22 44 0 1893150640 14093216 6123376
15:38:12 88722 8192000000 10877521456 11350753280 1901943648 18.842 97 12 24 1 1921757488 16370112 3443728
15:40:47 63197 8192000000 10877707072 11323887616 1841566720 18.354 94 24 48 1 1856002144 7826640 6608784
15:43:23 74662 8192000000 10849737664 11307249664 1774673312 17.806 91 10 20 0 1788540896 10714384 3153200
15:45:41 111483 8192000000 10873912624 11332444160 1901085536 18.835 98 19 38 0 1921187728 14422128 5680064
15:48:35 39841 8192000000 10879909584 11346931712 1944088480 19.179 100 14 28 1 1963178816 14818528 4271808
15:50:16 60930 8192000000 10861006416 11330134016 1925965696 19.035 99 11 22 1 1946491024 17247744 3277584
15:52:28 84051 8192000000 10820227168 11280797696 1776754128 17.823 92 15 30 0 1794458192 13153344 4550720
15:55:20 80430 8192000000 10860166416 11310051328 1861286128 18.514 97 15 31 0 1874334816 8540336 4508352
15:57:41 64474 8192000000 10880052800 11338211328 1932601216 19.088 100 24 48 1 1949725776 9985664 7138896
15:59:52 56753 8192000000 10875103200 11332882432 1911907088 18.922 98 15 30 1 1928947520 12191168 4849264
16:01:49 100364 8192000000 10900959616 11343503360 1932949712 19.090 99 17 34 0 1950700400 12950496 4800192
16:04:33 25396 8192000000 10919361168 11381747712 1953659920 19.256 99 28 56 2 1982194304 20663616 7870768
16:06:00 74899 8192000000 10911259984 11358851072 1874351040 18.619 95 15 30 0 1888470736 10039360 4080336
16:08:17 89629 8192000000 10888832064 11369144320 1949583424 19.223 99 9 18 0 1967974288 15870496 2520368
16:11:18 66064 8192000000 10901774832 11378909184 1962598000 19.327 99 18 36 0 1982356112 14688272 5069840
16:13:30 66063 8192000000 10903037408 11385843712 1946517616 19.199 98 12 22 1 1959879792 10304768 3057408
16:16:22 57558 8192000000 10908394400 11382743040 1971554848 19.398 99 16 31 2 1986434608 10772848 4106912
16:18:25 17201 8192000000 10932160352 11387060224 1979413312 19.460 99 57 111 1 2006805760 10608656 16783792
16:19:46 52352 8192000000 10918193200 11390148608 1957636336 19.287 98 24 48 1 1975859888 10974144 7249408
16:21:39 22405 8192000000 10930120688 11376201728 1983588544 19.493 100 43 86 0 2003057936 7620416 11848976
16:23:02 55013 8192000000 10895802384 11363897344 1922814960 19.009 97 16 32 0 1940030192 12945360 4269872
16:25:03 107154 8192000000 10878442896 11332362240 1777423536 17.828 91 29 58 0 1796502848 11087296 7992016
16:27:59 55852 8192000000 10881460064 11345395712 1949965104 19.226 100 19 38 0 1966588576 11278032 5345440
16:30:05 107058 8192000000 10876350592 11354279936 1944314976 19.181 100 9 18 1 1965843152 18849296 2678880
16:33:51 14931 8192000000 10885725872 11349942272 1949899520 19.226 100 21 42 0 1969848432 14042608 5906304
16:35:07 51342 8192000000 10860050064 11341193216 1938415520 19.134 100 15 30 1 1957569920 14875584 4278816
16:37:04 59141 8192000000 9684470272 10029191168 827980576 9.179 43 4 8 0 834858944 5640384 1237984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2021 16:40:29 to 25-09-2021 16:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2021 16:40:45 to 25-09-2021 16:40:47.

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          243261
MON$OLDEST_ACTIVE               243262
MON$OLDEST_SNAPSHOT             243262
MON$NEXT_TRANSACTION            243262
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                       2026080
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:234: 
:235: mscs-test-161.budzdorov.ru    Sat Sep 25 16:38:14 2021
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
--- end of diff output ---

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

25.09.21 16:40:48. 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-25 16:40:50 No severe PSQL-related problems occured
25.09.21 16:40:51. End of report.