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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-21 10:37:35
DTS_END                         2021-10-21 10:37:04.5420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36536
TRN_ID                          239295
ATT_ID                          259
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: 9084.83 21664 1090179
customer order: creation 171.32 392 20558
customer order: refuse 15.10 312 1812
order to supplier: creation 46.00 1320 5520
order to supplier: removal 3.65 110722 438
invoice (draft): creation 46.10 3332 5532
invoice (draft): removal 50.88 1028 6105
invoice accept: apply 41.71 20019 5005
invoice accept: cancel 45.71 846 5485
customer reserve: creation 3824.51 200 458941
customer reserve: removal 4542.25 69 545070
realization accept: apply 85.07 172 10208
realization accept: cancel 82.82 127 9938
payment from customer: creation 59.73 125 7167
payment from customer: removal 5.93 78 711
payment to supplier: creation 49.41 118 5929
payment to supplier: removal 3.59 56 431
service: total inventory turnovers 3.87 3263 464
service: total monetary turnovers 6.20 483 744
service: aggregate perf. data 0.98 12984 117
service: refresh index statistics 0.03 277630 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2021 10:38:55 to 21-10-2021 10:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4629
TEST_TIME 2 5453
TEST_TIME 3 5369
TEST_TIME 4 5135
TEST_TIME 5 5362
TEST_TIME 6 5815
TEST_TIME 7 5641
TEST_TIME 8 5795
TEST_TIME 9 6117
TEST_TIME 10 6314
TEST_TIME 11 6280
TEST_TIME 12 6168
TEST_TIME 13 6333
TEST_TIME 14 6353
TEST_TIME 15 6416
TEST_TIME 16 6544
TEST_TIME 17 6806
TEST_TIME 18 6958
TEST_TIME 19 7826
TEST_TIME 20 7923
TEST_TIME 21 8642
TEST_TIME 22 9157
TEST_TIME 23 10150
TEST_TIME 24 11367
TEST_TIME 25 11377
TEST_TIME 26 11534
TEST_TIME 27 11428
TEST_TIME 28 11161
TEST_TIME 29 11041
TEST_TIME 30 10827
TEST_TIME 31 10706
TEST_TIME 32 10582
TEST_TIME 33 10456
TEST_TIME 34 10358
TEST_TIME 35 10282
TEST_TIME 36 10419
TEST_TIME 37 10330
TEST_TIME 38 10224
TEST_TIME 39 10016
TEST_TIME 40 9953
TEST_TIME 41 9923
TEST_TIME 42 9814
TEST_TIME 43 9679
TEST_TIME 44 9662
TEST_TIME 45 9704
TEST_TIME 46 9734
TEST_TIME 47 9634
TEST_TIME 48 9684
TEST_TIME 49 9701
TEST_TIME 50 9717
TEST_TIME 51 9972
TEST_TIME 52 10158
TEST_TIME 53 10135
TEST_TIME 54 10103
TEST_TIME 55 10021
TEST_TIME 56 9883
TEST_TIME 57 9786
TEST_TIME 58 9782
TEST_TIME 59 9761
TEST_TIME 60 9660
TEST_TIME 61 9567
TEST_TIME 62 9583
TEST_TIME 63 9665
TEST_TIME 64 9676
TEST_TIME 65 9685
TEST_TIME 66 9633
TEST_TIME 67 9625
TEST_TIME 68 9595
TEST_TIME 69 9620
TEST_TIME 70 9676
TEST_TIME 71 9725
TEST_TIME 72 9746
TEST_TIME 73 9745
TEST_TIME 74 9792
TEST_TIME 75 9833
TEST_TIME 76 9714
TEST_TIME 77 9657
TEST_TIME 78 9627
TEST_TIME 79 9577
TEST_TIME 80 9554
TEST_TIME 81 9492
TEST_TIME 82 9445
TEST_TIME 83 9391
TEST_TIME 84 9398
TEST_TIME 85 9414
TEST_TIME 86 9384
TEST_TIME 87 9368
TEST_TIME 88 9409
TEST_TIME 89 9396
TEST_TIME 90 9382
TEST_TIME 91 9432
TEST_TIME 92 9446
TEST_TIME 93 9445
TEST_TIME 94 9458
TEST_TIME 95 9459
TEST_TIME 96 9499
TEST_TIME 97 9454
TEST_TIME 98 9389
TEST_TIME 99 9391
TEST_TIME 100 9380
TEST_TIME 101 9349
TEST_TIME 102 9307
TEST_TIME 103 9278
TEST_TIME 104 9254
TEST_TIME 105 9238
TEST_TIME 106 9214
TEST_TIME 107 9191
TEST_TIME 108 9172
TEST_TIME 109 9275
TEST_TIME 110 9264
TEST_TIME 111 9229
TEST_TIME 112 9254
TEST_TIME 113 9266
TEST_TIME 114 9266
TEST_TIME 115 9293
TEST_TIME 116 9230
TEST_TIME 117 9184
TEST_TIME 118 9182
TEST_TIME 119 9140
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2021 10:38:59 to 21-10-2021 10:39:02.

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 1516238 1516238 0 0.00 0 21669 7 0 0
doc_list_biud 1516238 1516238 0 0.00 0 36556 31 0 0
sp_add_invoice_to_stock 5084 5005 79 1.55 28 205367 20019 0 79
sp_cancel_adding_invoice 5485 5485 0 0.00 5 64882 847 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 9220 313 0 0
sp_cancel_customer_reserve 545070 545070 0 0.00 1 24865 70 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 5412 79 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 2 2111 56 0 0
sp_cancel_supplier_invoice 6111 6105 6 0.09 2 36565 1028 0 6
sp_cancel_supplier_order 438 438 0 0.00 8 1292182 110722 0 0
sp_cancel_write_off 9938 9938 0 0.00 1 8358 128 0 0
sp_client_order 20558 20558 0 0.00 4 27257 393 0 0
sp_customer_reserve 458941 458941 0 0.00 0 134717 201 0 0
sp_fill_shopping_cart 31597 31597 0 0.00 1 10825 39 0 0
sp_fill_shopping_cart_clo_res 458934 458934 0 0.00 0 19706 18 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 24091 347 0 0
sp_kill_cost_storno 16565 16565 0 0.00 0 8353 57 0 0
sp_kill_qstorno_ret_qs2qd 1106621 1106621 0 0.00 0 36554 38 0 0
sp_kill_qty_storno 584052 584052 0 0.00 0 36556 81 0 0
sp_lock_selected_doc 21068 21068 0 0.00 0 8858 6 0 0
sp_make_cost_storno 28300 28300 0 0.00 0 21665 57 0 0
sp_multiply_rows_for_pdistr 28300 28300 0 0.00 0 9327 30 0 0
sp_multiply_rows_for_qdistr 20558 20558 0 0.00 0 14125 205 0 0
sp_pay_from_customer 7167 7167 0 0.00 4 22409 125 0 0
sp_pay_to_supplier 5929 5929 0 0.00 5 8853 119 0 0
sp_payment_common 13096 13096 0 0.00 1 8460 20 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 8928 284 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 22752 352 0 0
sp_qd_handle_on_reserve_upd_sts 10208 10208 0 0.00 0 17862 51 0 0
sp_reserve_write_off 10208 10208 0 0.00 7 21784 172 0 0
sp_supplier_invoice 5532 5532 0 0.00 8 96208 3332 0 0
sp_supplier_order 5520 5520 0 0.00 5 56687 1321 0 0
srv_aggregate_perf_data 117 117 0 0.00 2555 149575 12984 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1373 161315 48492 0 0
srv_find_qd_qs_mism 1043033 1043033 0 0.00 0 19612 7 0 0
srv_make_invnt_saldo 464 464 0 0.00 41 90484 3264 0 0
srv_make_money_saldo 744 744 0 0.00 0 11850 484 0 0
srv_recalc_idx_stat 4 4 0 0.00 181624 385765 277630 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5005 5005 0 0.00 0 18943 157 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 4838 75 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 3819 10 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1053 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 2379 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 9721 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 810 10 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 13694 1205 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 10252 21 0 0
t$perf-norm:sp_client_order 20558 20558 0 0.00 0 9517 17 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 3788 6 0 0
t$perf-norm:sp_pay_from_customer 7167 7167 0 0.00 0 13646 12 0 0
t$perf-norm:sp_pay_to_supplier 5929 5929 0 0.00 0 9809 13 0 0
t$perf-norm:sp_reserve_write_off 10208 10208 0 0.00 0 18731 11 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 4463 16 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 3590 14 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 207 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1358 23 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 738 7 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 464 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 120 32 0 0
v_add_invoice_to_stock 10150 9992 158 1.55 0 8634 19 0 158
v_all_customers 37814 37814 0 0.00 0 19 1 0 0
v_all_suppliers 30972 30972 0 0.00 0 16 1 0 0
v_all_wares 41116 41116 0 0.00 0 233 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 327 6 0 0
v_cancel_client_order 3624 3624 0 0.00 0 2603 7 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1606 8 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2602 7 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 2076 7 0 0
v_cancel_supplier_invoice 860 848 12 1.39 0 1382 14 0 12
v_cancel_supplier_order 876 876 0 0.00 0 1233 5 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2963 16 0 0
v_max_id_clo_ord 5520 5520 0 0.00 0 4064 3 0 0
v_max_id_clo_res 987 987 0 0.00 0 3491 22 0 0
v_max_id_ord_sup 5519 5519 0 0.00 0 2396 3 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 1402 3 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 3979 4 0 0
v_min_id_clo_ord 5520 5520 0 0.00 0 2186 5 0 0
v_min_id_clo_res 987 987 0 0.00 0 5830 11 0 0
v_min_id_ord_sup 5519 5519 0 0.00 0 6955 7 0 0
v_min_non_paid_invoice 5929 5929 0 0.00 0 4679 11 0 0
v_min_non_paid_realizn 7167 7167 0 0.00 0 7164 15 0 0
v_our_firm 10224 10224 0 0.00 0 14 1 0 0
v_reserve_write_off 20416 20416 0 0.00 0 2839 5 0 0
x_lock_depdocs_on_canc_invoice 11166 11166 0 0.00 0 18341 126 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 4346 298 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 2 56679 1246 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 4 96097 3191 0 0
x_make_qstorno_2100_3300 458941 458941 0 0.00 0 134715 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2021 10:39:03 to 21-10-2021 10:39:07.

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
08:07:08 16 8192000000 8802636384 8815370240 7421248 0.090 3 1 2 0 7520672 63648 35776
08:08:08 23636 8192000000 9720360400 10020327424 809716880 8.995 100 87 174 0 838146912 7515136 20914896
08:09:36 25644 8192000000 9761653488 10069897216 839633888 9.296 100 94 188 0 873788192 12124880 22029424
08:11:07 15394 8192000000 9810663168 10128596992 873582576 9.636 100 86 170 2 917522016 24919376 19020064
08:12:23 11560 8192000000 9878318944 10210250752 911155024 10.009 100 83 166 0 987111984 57339280 18617680
08:13:38 6850 8192000000 9924842304 10268082176 956631296 10.456 100 80 161 0 1027844992 54154112 17059584
08:14:47 4146 8192000000 9957066560 10314129408 1003006896 10.908 100 78 155 1 1069208480 50332016 15869568
08:15:55 5180 8192000000 10008860720 10378448896 1027666768 11.146 100 78 156 1 1123063728 79784912 15612048
08:17:02 1405 8192000000 10035361232 10404491264 1058203120 11.439 100 94 187 0 1150119536 73559312 18357104
08:18:04 6352 8192000000 10028934832 10407342080 1067307968 11.526 100 76 150 0 1146239568 63557152 15374448
08:19:11 8071 8192000000 9991363008 10394025984 1072581600 11.577 100 31 62 0 1103790048 25364512 5843936
08:20:20 8494 8192000000 9981134624 10388750336 1080541920 11.653 100 31 60 1 1109855616 22510096 6803600
08:21:29 6841 8192000000 9987739920 10392526848 1091504848 11.757 100 26 51 0 1119410688 22618256 5287584
08:22:36 55996 8192000000 10016001328 10421624832 1092737472 11.769 100 46 90 2 1112519296 10161760 9620064
08:24:36 4809 8192000000 10001257376 10412728320 1097965184 11.818 100 41 80 2 1120484832 12885712 9633936
08:25:43 7026 8192000000 10010027632 10416181248 1068632512 11.539 97 22 44 0 1106661024 33246832 4781680
08:26:54 10783 8192000000 9977773952 10386714624 1056591696 11.424 97 20 36 2 1086102144 25132224 4378224
08:28:05 28239 8192000000 9973374016 10386460672 1023194688 11.103 95 24 45 1 1048621728 20541264 4885776
08:29:34 4927 8192000000 9968481024 10349662208 1000822912 10.887 96 47 91 2 1040060016 29271840 9965264
08:30:39 7795 8192000000 9949845760 10342932480 1014244240 11.016 98 39 77 0 1044044176 21378352 8421584
08:31:48 19327 8192000000 9926437920 10316472320 996438976 10.844 96 43 85 4 1029392576 23249952 9703648
08:33:07 13715 8192000000 9942111616 10332434432 1005096224 10.928 97 19 38 0 1020437776 11271984 4069568
08:34:24 5681 8192000000 9985132784 10379001856 1061618944 11.472 100 46 92 2 1083614608 11363408 10632256
08:35:32 13929 8192000000 9985558640 10395185152 1068287232 11.536 100 37 74 1 1104361856 28402640 7671984
08:36:50 93919 8192000000 10003976928 10402238464 1080416128 11.651 100 38 74 1 1105100864 16205024 8479712
08:39:33 20601 8192000000 10058160000 10472148992 1115301760 11.983 100 33 66 1 1183088176 60801296 6985120
08:41:02 24664 8192000000 10121422256 10520477696 1136677872 12.184 100 58 112 2 1221651968 73746416 11227680
08:42:30 11456 8192000000 10152593472 10564124672 1143919568 12.252 100 52 104 1 1267680144 112255216 11505360
08:43:45 22133 8192000000 10167417152 10588049408 1155700256 12.363 100 45 90 1 1273310384 107046752 10563376
08:45:10 6408 8192000000 10214488512 10622992384 1165429696 12.454 100 55 109 1 1314540400 137189776 11920928
08:46:20 4903 8192000000 10265450096 10672750592 1178113056 12.573 100 69 138 1 1364096720 170358336 15625328
08:47:28 2251 8192000000 10252916624 10657140736 1155569440 12.362 98 55 109 0 1355848448 187764672 12514336
08:48:32 4387 8192000000 10341771152 10750722048 1173307696 12.528 99 64 127 1 1438490304 250473088 14709520
08:49:44 9957 8192000000 10385797072 10792812544 1178523248 12.576 100 62 123 1 1481744368 288654448 14566672
08:51:16 3923 8192000000 10423586336 10823831552 1168625600 12.484 98 89 176 1 1512642720 323577248 20439872
08:52:25 2344 8192000000 10498169552 10897637376 1191083728 12.693 100 87 172 0 1589060192 377401312 20575152
08:53:33 1365 8192000000 10530179504 10936844288 1192356912 12.705 100 80 158 0 1626262048 414163232 19741904
08:54:41 1934 8192000000 10573477536 10978041856 1163704896 12.438 100 84 168 0 1670326000 485439504 21181600
08:55:46 4609 8192000000 10585926944 11008823296 1161757056 12.420 100 74 148 0 1683867760 503522176 18588528
08:56:54 15658 8192000000 10515457424 10989666304 1152667504 12.335 100 50 100 0 1635165616 469259344 13238768
08:58:11 35057 8192000000 10491822544 10965741568 1137531376 12.192 99 36 70 0 1600838688 453188784 10118528
08:59:55 18821 8192000000 10376558608 10872664064 1120732848 12.034 99 19 38 1 1492277680 365882448 5662384
09:01:18 23764 8192000000 10128954240 10653515776 1117065296 11.999 98 23 46 0 1286835632 164100208 5670128
09:02:43 56304 8192000000 10105537104 10577899520 1119733760 12.024 99 15 30 0 1197019808 73658720 3627328
09:04:40 82188 8192000000 10075229264 10501894144 1104646448 11.882 98 36 71 1 1162680512 49806656 8227408
09:07:03 72257 8192000000 10075551312 10510626816 1116309232 11.992 99 43 85 5 1184232624 58912240 9011152
09:09:24 2229 8192000000 10077217264 10501533696 1131186768 12.133 100 60 118 0 1176398896 32807344 12404784
09:10:29 79680 8192000000 10138607712 10600177664 1095907232 11.799 96 19 38 0 1166227312 66261824 4058256
09:13:54 161315 8192000000 10055841152 10494218240 1067549472 11.529 94 12 24 0 1093353648 23533424 2270752
09:17:54 52632 8192000000 10039917760 10502303744 1123898048 12.064 99 13 26 0 1165863312 39299744 2665520
09:19:51 66508 8192000000 10086389376 10545209344 1117665056 12.005 98 10 20 1 1176052416 56245680 2141680
09:21:57 42385 8192000000 10118239696 10583941120 1140576608 12.221 100 26 52 1 1219718912 73286672 5855632
09:23:49 69300 8192000000 10121785024 10586304512 1135959120 12.178 99 13 26 0 1201037248 62063072 3015056
09:26:06 50606 8192000000 10189857520 10646253568 1146231728 12.274 100 27 54 2 1299561952 147144768 6185456
09:28:16 90809 8192000000 10077982512 10576322560 1151562832 12.324 100 14 28 2 1256049296 101262192 3224272
09:30:47 65598 8192000000 10086681776 10528833536 1122258528 12.048 97 19 38 0 1150576544 24040880 4277136
09:32:59 123270 8192000000 10078586848 10518659072 1133078480 12.150 99 24 48 0 1189756976 51801392 4877104
09:36:02 56400 8192000000 10102806528 10585042944 1134041248 12.159 99 16 31 0 1222677184 85360880 3275056
09:38:05 50066 8192000000 10095179968 10541686784 1100685536 11.844 96 11 22 0 1169922144 67043520 2193088
09:40:07 55532 8192000000 10092671360 10602942464 1130585392 12.127 99 13 26 1 1199808592 66383120 2840080
09:42:20 53730 8192000000 10060596928 10511380480 1100260208 11.840 97 15 31 1 1141079792 38087520 2732064
09:44:17 35003 8192000000 10093254512 10528534528 1111081072 11.943 98 31 62 1 1170648816 53885968 5681776
09:45:55 87081 8192000000 10120581456 10582200320 1094370112 11.784 97 17 34 1 1194965808 96984672 3611024
09:48:27 55544 8192000000 10075303392 10524585984 1096740512 11.807 98 32 64 0 1135722784 32897328 6084944
09:50:28 42550 8192000000 10104900032 10551013376 1120550960 12.032 99 48 96 1 1194334128 64004592 9778576
09:52:16 100234 8192000000 10119274112 10592366592 1118212832 12.010 99 32 64 0 1193434064 68866560 6354672
09:55:04 2892 8192000000 10060785312 10482241536 1120203776 12.029 100 58 116 2 1156391024 23688288 12498960
09:56:07 52434 8192000000 10062412112 10528956416 1099685152 11.835 98 5 10 0 1161654704 61064880 904672
09:58:12 74168 8192000000 10090417664 10556571648 1135593744 12.174 100 10 20 0 1207275584 69049408 2632432
10:00:34 73676 8192000000 10074244080 10537041920 1109061264 11.924 98 11 21 0 1164009600 52897056 2051280
10:02:48 40278 8192000000 10045431328 10483339264 1063186224 11.487 94 32 64 0 1097800832 27999056 6615552
10:04:33 77632 8192000000 10077633904 10546823168 1104186032 11.877 98 25 50 1 1182016832 72657344 5173456
10:06:55 58771 8192000000 10107737728 10559365120 1113907968 11.969 98 28 56 0 1163194352 43582144 5704240
10:09:49 53184 8192000000 10078229168 10532282368 1080157936 11.649 96 29 59 2 1126231744 40358608 5715200
10:11:55 33842 8192000000 10086949216 10515939328 1107561552 11.909 98 31 62 0 1143915680 30720912 5633216
10:13:34 50552 8192000000 10072765072 10504142848 1124431856 12.069 99 25 50 0 1153990320 24057872 5500592
10:15:27 110261 8192000000 10089309392 10553483264 1108043680 11.914 97 33 66 0 1182511328 67062656 7404992
10:18:27 59274 8192000000 10068389840 10516209664 1100707072 11.844 97 22 44 0 1142028448 36781968 4539408
10:20:27 61927 8192000000 10049365040 10523537408 1128768768 12.110 100 11 22 1 1175589744 44536496 2284480
10:22:48 39206 8192000000 10052129968 10494730240 1093051200 11.772 96 12 20 2 1130510000 35148992 2309808
10:24:51 56186 8192000000 10128232576 10557612032 1115746640 11.987 99 30 60 1 1201979824 79879440 6353744
10:26:56 31739 8192000000 10071009440 10499670016 1103932640 11.875 98 24 48 1 1144917872 36376320 4608912
10:28:30 57528 8192000000 10067900368 10531987456 1093695808 11.778 97 32 62 0 1163818976 63729504 6393664
10:30:37 87049 8192000000 10076922576 10532421632 1047053760 11.332 93 11 22 0 1105821712 56224624 2543328
10:33:04 14169 8192000000 10061264688 10479144960 1088755856 11.731 98 47 94 0 1126499472 28187104 9556512
10:34:19 112289 8192000000 10080454720 10577055744 1111139792 11.943 100 32 64 0 1189072800 71210784 6722224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2021 10:39:09 to 21-10-2021 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2021 10:39:25 to 21-10-2021 10:39:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          241825
MON$OLDEST_ACTIVE               241826
MON$OLDEST_SNAPSHOT             241826
MON$NEXT_TRANSACTION            241826
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2037088
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2636 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2636 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2636 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2636 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

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