Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 01: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 16128708 kB
MemAvailable 30790340 kB
Buffers 0 kB
Cached 15594776 kB
SwapCached 3072 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-28 01:37:52
DTS_END                         2021-09-28 01:37:52.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35336
TRN_ID                          238207
ATT_ID                          667
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: 9248.27 21480 1109792
customer order: creation 168.75 371 20250
customer order: refuse 14.13 349 1695
order to supplier: creation 44.87 1295 5384
order to supplier: removal 3.55 86785 426
invoice (draft): creation 45.97 3745 5516
invoice (draft): removal 53.38 676 6405
invoice accept: apply 41.51 20422 4981
invoice accept: cancel 48.37 725 5804
customer reserve: creation 3819.57 203 458348
customer reserve: removal 4709.95 51 565194
realization accept: apply 83.07 195 9968
realization accept: cancel 86.33 103 10359
payment from customer: creation 58.19 119 6983
payment from customer: removal 5.53 74 664
payment to supplier: creation 49.12 149 5894
payment to supplier: removal 3.56 83 427
service: total inventory turnovers 4.86 1119 583
service: total monetary turnovers 6.83 27 819
service: aggregate perf. data 0.73 22707 88
service: refresh index statistics 0.03 290399 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 01:39:57 to 28-09-2021 01:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4584
TEST_TIME 2 4841
TEST_TIME 3 4369
TEST_TIME 4 5149
TEST_TIME 5 5322
TEST_TIME 6 5588
TEST_TIME 7 5807
TEST_TIME 8 5905
TEST_TIME 9 6786
TEST_TIME 10 6933
TEST_TIME 11 6793
TEST_TIME 12 6802
TEST_TIME 13 7090
TEST_TIME 14 7248
TEST_TIME 15 7170
TEST_TIME 16 7125
TEST_TIME 17 7761
TEST_TIME 18 8408
TEST_TIME 19 8341
TEST_TIME 20 8585
TEST_TIME 21 8753
TEST_TIME 22 8855
TEST_TIME 23 9132
TEST_TIME 24 9303
TEST_TIME 25 9430
TEST_TIME 26 10123
TEST_TIME 27 10406
TEST_TIME 28 10326
TEST_TIME 29 10274
TEST_TIME 30 10147
TEST_TIME 31 9919
TEST_TIME 32 9760
TEST_TIME 33 10197
TEST_TIME 34 9992
TEST_TIME 35 9777
TEST_TIME 36 9723
TEST_TIME 37 9680
TEST_TIME 38 9730
TEST_TIME 39 9744
TEST_TIME 40 9741
TEST_TIME 41 9586
TEST_TIME 42 9530
TEST_TIME 43 9460
TEST_TIME 44 9413
TEST_TIME 45 9541
TEST_TIME 46 9438
TEST_TIME 47 9484
TEST_TIME 48 9414
TEST_TIME 49 9324
TEST_TIME 50 9363
TEST_TIME 51 9319
TEST_TIME 52 9241
TEST_TIME 53 9142
TEST_TIME 54 9127
TEST_TIME 55 9055
TEST_TIME 56 9012
TEST_TIME 57 9012
TEST_TIME 58 9086
TEST_TIME 59 9026
TEST_TIME 60 9014
TEST_TIME 61 9054
TEST_TIME 62 9113
TEST_TIME 63 9243
TEST_TIME 64 9330
TEST_TIME 65 9354
TEST_TIME 66 9433
TEST_TIME 67 9515
TEST_TIME 68 9519
TEST_TIME 69 9488
TEST_TIME 70 9525
TEST_TIME 71 9511
TEST_TIME 72 9403
TEST_TIME 73 9372
TEST_TIME 74 9289
TEST_TIME 75 9240
TEST_TIME 76 9388
TEST_TIME 77 9331
TEST_TIME 78 9291
TEST_TIME 79 9215
TEST_TIME 80 9121
TEST_TIME 81 9203
TEST_TIME 82 9185
TEST_TIME 83 9212
TEST_TIME 84 9230
TEST_TIME 85 9226
TEST_TIME 86 9248
TEST_TIME 87 9216
TEST_TIME 88 9242
TEST_TIME 89 9285
TEST_TIME 90 9278
TEST_TIME 91 9323
TEST_TIME 92 9326
TEST_TIME 93 9333
TEST_TIME 94 9380
TEST_TIME 95 9345
TEST_TIME 96 9307
TEST_TIME 97 9267
TEST_TIME 98 9246
TEST_TIME 99 9252
TEST_TIME 100 9206
TEST_TIME 101 9140
TEST_TIME 102 9114
TEST_TIME 103 9194
TEST_TIME 104 9205
TEST_TIME 105 9183
TEST_TIME 106 9188
TEST_TIME 107 9202
TEST_TIME 108 9212
TEST_TIME 109 9248
TEST_TIME 110 9371
TEST_TIME 111 9384
TEST_TIME 112 9366
TEST_TIME 113 9389
TEST_TIME 114 9369
TEST_TIME 115 9386
TEST_TIME 116 9312
TEST_TIME 117 9279
TEST_TIME 118 9294
TEST_TIME 119 9309
TEST_TIME 120 9254

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-09-2021 01:40:00 to 28-09-2021 01:40:04.

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 1533473 1533473 0 0.00 0 18541 6 0 0
doc_list_biud 1533471 1533471 0 0.00 0 21128 24 0 0
sp_add_invoice_to_stock 5074 4981 93 1.83 32 225296 20422 0 93
sp_cancel_adding_invoice 5804 5804 0 0.00 4 69928 725 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 12866 349 0 0
sp_cancel_customer_reserve 565194 565194 0 0.00 1 21069 51 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 4956 74 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 4005 84 0 0
sp_cancel_supplier_invoice 6412 6405 7 0.10 2 21158 676 0 7
sp_cancel_supplier_order 426 426 0 0.00 11 869515 86785 0 0
sp_cancel_write_off 10359 10359 0 0.00 1 12072 103 0 0
sp_client_order 20250 20250 0 0.00 3 21054 372 0 0
sp_customer_reserve 458348 458348 0 0.00 0 70166 203 0 0
sp_fill_shopping_cart 31123 31123 0 0.00 1 18495 38 0 0
sp_fill_shopping_cart_clo_res 458330 458330 0 0.00 0 18620 14 0 0
sp_get_clo_for_invoice 4963 4963 0 0.00 0 27662 305 0 0
sp_kill_cost_storno 17254 17254 0 0.00 0 9975 53 0 0
sp_kill_qstorno_ret_qs2qd 1147578 1147578 0 0.00 0 21099 28 0 0
sp_kill_qty_storno 604814 604814 0 0.00 0 21123 60 0 0
sp_lock_selected_doc 20648 20648 0 0.00 0 8195 6 0 0
sp_make_cost_storno 27808 27808 0 0.00 0 18529 63 0 0
sp_multiply_rows_for_pdistr 27808 27808 0 0.00 0 15290 32 0 0
sp_multiply_rows_for_qdistr 20250 20250 0 0.00 0 13337 150 0 0
sp_pay_from_customer 6983 6983 0 0.00 4 15299 120 0 0
sp_pay_to_supplier 5894 5894 0 0.00 4 17575 150 0 0
sp_payment_common 12877 12877 0 0.00 1 14241 31 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 12832 318 0 0
sp_qd_handle_on_invoice_upd_sts 10767 10767 0 0.00 0 19377 309 0 0
sp_qd_handle_on_reserve_upd_sts 9968 9968 0 0.00 0 18384 56 0 0
sp_reserve_write_off 9968 9968 0 0.00 6 18420 195 0 0
sp_supplier_invoice 5516 5516 0 0.00 7 126537 3746 0 0
sp_supplier_order 5384 5384 0 0.00 5 68673 1295 0 0
srv_aggregate_perf_data 88 88 0 0.00 3092 472941 22707 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2353 141695 46897 0 0
srv_find_qd_qs_mism 1062180 1062180 0 0.00 0 15561 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 42 69708 1119 0 0
srv_make_money_saldo 819 819 0 0.00 1 3675 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 176616 483146 290399 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 4981 4981 0 0.00 0 40085 191 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 8436 121 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 4047 13 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2412 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 2272 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 5776 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1684 23 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 41376 1601 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 8420 25 0 0
t$perf-norm:sp_client_order 20250 20250 0 0.00 0 8369 20 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 3062 7 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 6674 13 0 0
t$perf-norm:sp_pay_to_supplier 5894 5894 0 0.00 0 8671 11 0 0
t$perf-norm:sp_reserve_write_off 9968 9968 0 0.00 0 10751 16 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 11716 20 0 0
t$perf-norm:sp_supplier_order 5384 5384 0 0.00 0 5434 17 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 346 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4335 77 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 4613 38 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 1062 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10112 9926 186 1.83 0 4585 18 0 186
v_all_customers 37132 37132 0 0.00 0 86 1 0 0
v_all_suppliers 30228 30228 0 0.00 0 16 1 0 0
v_all_wares 40488 40488 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3664 11 0 0
v_cancel_client_order 3390 3390 0 0.00 0 3725 7 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 4653 10 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 769 5 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 3940 9 0 0
v_cancel_supplier_invoice 786 772 14 1.78 0 3555 22 0 14
v_cancel_supplier_order 852 852 0 0.00 0 587 3 0 0
v_cancel_write_off 2016 2016 0 0.00 0 6364 16 0 0
v_max_id_clo_ord 5383 5383 0 0.00 0 608 1 0 0
v_max_id_clo_res 916 916 0 0.00 0 2770 20 0 0
v_max_id_ord_sup 5496 5496 0 0.00 0 3414 4 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 4804 8 0 0
v_max_non_paid_realizn 3616 3616 0 0.00 0 2355 4 0 0
v_min_id_clo_ord 5383 5383 0 0.00 0 3473 6 0 0
v_min_id_clo_res 916 916 0 0.00 0 980 4 0 0
v_min_id_ord_sup 5496 5496 0 0.00 0 9822 5 0 0
v_min_non_paid_invoice 5894 5894 0 0.00 0 14234 21 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 7805 20 0 0
v_our_firm 10090 10090 0 0.00 0 14 1 0 0
v_reserve_write_off 19936 19936 0 0.00 0 4806 7 0 0
x_lock_depdocs_on_canc_invoice 11823 11823 0 0.00 0 21738 114 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 17383 418 0 0
x_make_qstorno_1000_1200 5384 5384 0 0.00 2 66879 1221 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 2 126532 3600 0 0
x_make_qstorno_2100_3300 458348 458348 0 0.00 0 70165 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 01:40:05 to 28-09-2021 01:40:09.

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
23:07:56 14 8192000000 8807245536 8820330496 10405584 0.126 3 1 2 0 10526320 58176 62560
23:08:56 21629 8192000000 10362281792 10678902784 1411957840 14.701 100 92 184 1 1447694160 5832496 29903824
23:10:31 21383 8192000000 10440468256 10768285696 1491706432 15.404 100 95 190 0 1530642864 8513696 30422736
23:11:57 12966 8192000000 10467462848 10809102336 1526183664 15.704 100 83 166 0 1562420192 9694576 26541952
23:13:12 13434 8192000000 10549578336 10907770880 1606003696 16.391 100 76 152 0 1639272528 10575296 22693536
23:14:30 11150 8192000000 10629618048 10995122176 1686915648 17.075 100 78 156 1 1722222496 12375328 22931520
23:15:46 7757 8192000000 10697839296 11076210688 1756508256 17.655 100 80 156 2 1791595632 12124592 22962784
23:16:56 7103 8192000000 10731207024 11121106944 1804295648 18.049 100 72 144 1 1835265168 11214576 19754944
23:18:05 2217 8192000000 10801140288 11185504256 1857849232 18.486 100 86 173 3 1894883776 13396256 23638288
23:19:09 1500 8192000000 10819135440 11213279232 1879498160 18.661 100 84 169 3 1917866368 13638464 24729744
23:20:12 10588 8192000000 10782244432 11209859072 1877940064 18.648 100 22 44 0 1890784000 6492432 6351504
23:21:27 13876 8192000000 10807546976 11236048896 1896863584 18.801 100 15 30 1 1907630672 6224176 4542912
23:22:47 54356 8192000000 10835582272 11261644800 1910385664 18.910 100 29 52 1 1923028256 3518592 9124000
23:24:42 5647 8192000000 10842841920 11275902976 1920300224 18.989 100 24 48 0 1933963120 6795792 6867104
23:25:48 6788 8192000000 10841180336 11277443072 1925121712 19.028 100 21 42 0 1936774400 5596608 6056080
23:26:57 8479 8192000000 10827306288 11263565824 1900112128 18.827 99 21 42 2 1916861024 10795520 5953376
23:28:06 21399 8192000000 10808452848 11245395968 1890513216 18.750 99 46 91 2 1909997408 5552256 13931936
23:29:34 5670 8192000000 10714344576 11137019904 1761146400 17.694 97 17 34 0 1771816512 5409840 5260272
23:30:47 6967 8192000000 10708704368 11121315840 1761578752 17.697 98 27 55 1 1779151872 9869744 7703376
23:31:54 22852 8192000000 10696199648 11115290624 1721633024 17.366 95 16 30 1 1730376048 4507104 4235920
23:33:43 7525 8192000000 10738765760 11166806016 1828394160 18.246 100 33 66 1 1844959456 7468016 9097280
23:34:51 12882 8192000000 10766928192 11199418368 1856581568 18.476 100 18 36 2 1866838256 5405904 4850784
23:36:09 7699 8192000000 10786152016 11219165184 1872487888 18.604 100 20 42 1 1884595920 5485616 6622416
23:37:20 76252 8192000000 10834320624 11259551744 1904743792 18.864 100 46 91 2 1924082880 6376608 12962480
23:39:47 18487 8192000000 10865035712 11295870976 1941476688 19.159 100 44 88 1 1962757888 9204512 12076688
23:41:20 17952 8192000000 10889889216 11331997696 1963015328 19.330 100 44 88 0 1990085632 13517520 13552784
23:42:48 13288 8192000000 10895701264 11339907072 1961457808 19.318 99 50 100 2 1991935216 15399840 15077568
23:44:08 4284 8192000000 10939116976 11367866368 1990504752 19.548 100 58 113 0 2023033952 16349680 16179520
23:45:15 15736 8192000000 10935778000 11372949504 1993403232 19.571 100 51 102 0 2028838640 20043616 15391792
23:46:44 11195 8192000000 10939637712 11388473344 2008142976 19.687 100 54 108 1 2043901184 19476624 16281584
23:48:00 13429 8192000000 10957753104 11393077248 2009824400 19.700 100 70 140 1 2049015632 18847568 20343664
23:49:16 2462 8192000000 10966943888 11387138048 2001659392 19.636 100 76 152 0 2048961136 24180592 23121152
23:50:23 7211 8192000000 10969297536 11396263936 2002369840 19.641 100 75 149 1 2057762192 33415520 21976832
23:51:36 2353 8192000000 10956141712 11375960064 1986462592 19.516 100 80 160 2 2043443264 31027088 25953584
23:52:43 5906 8192000000 10928201872 11355779072 1960185568 19.308 100 67 134 0 2019626288 38152048 21288672
23:53:54 9501 8192000000 10916741696 11344101376 1928090768 19.052 99 56 111 1 1991671952 45614784 17966400
23:55:08 7005 8192000000 10912980096 11337121792 1928087360 19.052 99 67 134 0 1989194896 40232864 20874672
23:56:19 5583 8192000000 10899239408 11332825088 1922647952 19.008 99 49 98 1 1970723440 32927872 15147616
23:57:39 6598 8192000000 10893223488 11319709696 1918898848 18.978 99 50 99 1 1968228384 33643568 15685968
23:58:53 23598 8192000000 10864128512 11310862336 1893240976 18.772 98 27 54 1 1938547536 36670240 8636320
00:00:19 13728 8192000000 10876618976 11322671104 1895188640 18.788 99 22 43 1 1943726400 40460928 8076832
00:01:35 28846 8192000000 10864866736 11310366720 1867662704 18.565 97 20 40 1 1914949520 40413552 6873264
00:03:15 29585 8192000000 10862862592 11316260864 1894115328 18.779 99 19 38 1 1942859312 41424656 7319328
00:05:20 32081 8192000000 10891041520 11343437824 1953620880 19.255 100 23 45 2 1974991632 14981536 6389216
00:06:54 141695 8192000000 10871699392 11319013376 1895825648 18.793 97 34 65 2 1922618960 16897824 9895488
00:10:20 62237 8192000000 10852260368 11309223936 1887771696 18.728 97 6 12 0 1899923584 10869888 1282000
00:12:23 12232 8192000000 10905885184 11327631360 1954906688 19.266 100 62 123 3 1980371376 7555232 17909456
00:13:37 42835 8192000000 10910826896 11364077568 1957849120 19.289 99 39 78 3 1982468000 12952432 11666448
00:15:29 110786 8192000000 10889330416 11375570944 1971972320 19.401 100 14 29 0 1989365024 13671600 3721104
00:18:36 36925 8192000000 10893855040 11345530880 1947722192 19.208 98 16 32 0 1961918656 9765440 4431024
00:20:13 50103 8192000000 10925338128 11386556416 1983477152 19.492 100 27 55 2 2005565312 13764240 8323920
00:23:06 28020 8192000000 10932372928 11384717312 1995965888 19.591 100 52 104 0 2018525872 7221104 15338880
00:24:38 60777 8192000000 10919623296 11396853760 1994813664 19.582 100 26 51 0 2015813168 13506480 7493024
00:26:40 68520 8192000000 10906935072 11388760064 1960808528 19.312 98 11 22 0 1976951936 13145600 2997808
00:28:48 42106 8192000000 10914612176 11381288960 1998481984 19.611 100 12 24 0 2007569664 6230400 2857280
00:30:41 66104 8192000000 10915029616 11388850176 1999130832 19.616 100 28 54 0 2014668624 7598512 7939280
00:32:58 46765 8192000000 10924081472 11399548928 2009271568 19.696 100 22 44 0 2029840880 13514864 7054448
00:35:47 36910 8192000000 10889882192 11364974592 1885307920 18.708 94 8 16 0 1893645680 6507280 1830480
00:37:36 45036 8192000000 10895100144 11366866944 1940031056 19.147 98 22 44 0 1961086464 14269232 6786176
00:39:26 50181 8192000000 10910148256 11367239680 1923068688 19.011 97 28 56 2 1944868400 13969408 7830304
00:41:25 84320 8192000000 10897144816 11364974592 1909297056 18.901 97 10 19 2 1933263344 20828736 3137552
00:43:52 73066 8192000000 10896036288 11366756352 1924974704 19.027 98 15 30 0 1943135504 13580048 4580752
00:46:43 28052 8192000000 10880391456 11327598592 1893574112 18.775 97 38 76 2 1918261696 14068272 10619312
00:48:16 49063 8192000000 10865085440 11316858880 1894517040 18.782 98 29 59 1 1913607472 10944560 8145872
00:50:09 66435 8192000000 10898033264 11337605120 1915185760 18.948 98 44 88 0 1938277408 10543696 12547952
00:52:20 59703 8192000000 10830322512 11320958976 1896243952 18.796 98 23 46 1 1918329616 15280512 6805152
00:54:31 33864 8192000000 10864099184 11322802176 1937501456 19.127 100 13 26 0 1947413392 6652128 3259808
00:56:18 69054 8192000000 10858527488 11323199488 1878430272 18.652 96 13 26 0 1893395696 10748384 4217040
00:58:27 60981 8192000000 10854642848 11345612800 1919903168 18.986 99 13 26 0 1941066048 17192256 3970624
01:01:28 62021 8192000000 10881228208 11341631488 1931995808 19.083 99 12 24 0 1944179264 8912912 3270544
01:03:40 23021 8192000000 10861627216 11318452224 1905773920 18.873 98 28 56 0 1920569232 7330480 7464832
01:05:04 41317 8192000000 10896285984 11362021376 1967154608 19.363 100 36 72 0 1989643728 12655568 9833552
01:06:50 50589 8192000000 10904461120 11374178304 1974156464 19.418 100 19 39 0 1991579808 12520576 4902768
01:08:47 114567 8192000000 10917507632 11381678080 1927815952 19.049 97 19 36 0 1940557824 7832448 4909424
01:11:44 66602 8192000000 10915601648 11372253184 1927077328 19.044 97 14 28 0 1941666528 11106992 3482208
01:13:53 119796 8192000000 10895562672 11373821952 1942867648 19.170 98 30 60 0 1961648672 10234736 8546288
01:17:48 58453 8192000000 10912269824 11388125184 1966689456 19.359 99 8 16 0 1981998272 13047440 2261376
01:19:47 96603 8192000000 10898354384 11399454720 1979534880 19.461 100 11 22 0 1999851232 16801520 3514832
01:22:25 67338 8192000000 10915569568 11387883520 1971649472 19.399 99 19 38 2 1990107232 13162048 5295712
01:24:37 45204 8192000000 10904275088 11368558592 1914520752 18.943 96 18 36 0 1943357504 23122352 5714400
01:26:24 73922 8192000000 10929234096 11411529728 1979536576 19.461 100 24 48 1 2012247584 25711808 6999200
01:28:39 77557 8192000000 10912920352 11366191104 1907141088 18.884 96 23 46 0 1925245104 11691520 6412496
01:30:58 61656 8192000000 10895220224 11334332416 1854190032 18.456 94 33 66 0 1874230432 11035232 9005168
01:33:00 123907 8192000000 10870203360 11342135296 1930183968 19.068 99 37 74 0 1957424032 16784784 10455280
01:36:05 54073 8192000000 10850366288 11338596352 1919166368 18.980 99 8 15 2 1935259200 13285936 2806896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-09-2021 01:40:11 to 28-09-2021 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 28-09-2021 01:40:26 to 28-09-2021 01:40:28.

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          237189
MON$OLDEST_ACTIVE               238316
MON$OLDEST_SNAPSHOT             238316
MON$NEXT_TRANSACTION            238316
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                       2023424
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 ---
--- end of diff output ---

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

28.09.21 01:40:29. 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-28 01:40:31 No severe PSQL-related problems occured
28.09.21 01:40:33. End of report.