Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 07:37
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16216908 kB
MemAvailable 31352532 kB
Buffers 0 kB
Cached 15430260 kB
SwapCached 436 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-12-27 07:37:10
DTS_END                         2021-12-27 07:37:10.4420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35096
TRN_ID                          238148
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: 9226.66 23475 1107199
customer order: creation 171.96 407 20635
customer order: refuse 15.29 318 1835
order to supplier: creation 45.43 1386 5452
order to supplier: removal 3.26 130281 391
invoice (draft): creation 46.87 3691 5624
invoice (draft): removal 52.39 1046 6287
invoice accept: apply 41.68 18711 5001
invoice accept: cancel 47.15 945 5658
customer reserve: creation 3873.78 182 464854
customer reserve: removal 4639.39 71 556727
realization accept: apply 83.52 191 10022
realization accept: cancel 78.80 137 9456
payment from customer: creation 58.40 130 7008
payment from customer: removal 5.79 75 695
payment to supplier: creation 48.74 136 5849
payment to supplier: removal 3.51 96 421
service: total inventory turnovers 3.88 3773 465
service: total monetary turnovers 5.88 628 705
service: aggregate perf. data 0.92 16046 110
service: refresh index statistics 0.03 291243 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2021 07:38:22 to 27-12-2021 07:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4795
TEST_TIME 2 4733
TEST_TIME 3 3804
TEST_TIME 4 4365
TEST_TIME 5 4532
TEST_TIME 6 4912
TEST_TIME 7 4972
TEST_TIME 8 5342
TEST_TIME 9 5393
TEST_TIME 10 5771
TEST_TIME 11 6529
TEST_TIME 12 6736
TEST_TIME 13 7142
TEST_TIME 14 7400
TEST_TIME 15 7422
TEST_TIME 16 7943
TEST_TIME 17 8391
TEST_TIME 18 8465
TEST_TIME 19 8470
TEST_TIME 20 8442
TEST_TIME 21 8351
TEST_TIME 22 8608
TEST_TIME 23 8799
TEST_TIME 24 9352
TEST_TIME 25 9412
TEST_TIME 26 9308
TEST_TIME 27 9277
TEST_TIME 28 10036
TEST_TIME 29 11769
TEST_TIME 30 11640
TEST_TIME 31 11554
TEST_TIME 32 11693
TEST_TIME 33 11519
TEST_TIME 34 11327
TEST_TIME 35 11159
TEST_TIME 36 10939
TEST_TIME 37 10920
TEST_TIME 38 10931
TEST_TIME 39 10742
TEST_TIME 40 10648
TEST_TIME 41 10545
TEST_TIME 42 10436
TEST_TIME 43 10455
TEST_TIME 44 10446
TEST_TIME 45 10486
TEST_TIME 46 10332
TEST_TIME 47 10407
TEST_TIME 48 10344
TEST_TIME 49 10328
TEST_TIME 50 10351
TEST_TIME 51 10369
TEST_TIME 52 10250
TEST_TIME 53 10209
TEST_TIME 54 10169
TEST_TIME 55 10288
TEST_TIME 56 10244
TEST_TIME 57 10229
TEST_TIME 58 10136
TEST_TIME 59 10262
TEST_TIME 60 10290
TEST_TIME 61 10342
TEST_TIME 62 10190
TEST_TIME 63 10079
TEST_TIME 64 10066
TEST_TIME 65 9990
TEST_TIME 66 9953
TEST_TIME 67 9926
TEST_TIME 68 9865
TEST_TIME 69 9819
TEST_TIME 70 9766
TEST_TIME 71 9765
TEST_TIME 72 9747
TEST_TIME 73 9736
TEST_TIME 74 9741
TEST_TIME 75 9729
TEST_TIME 76 9681
TEST_TIME 77 9732
TEST_TIME 78 9719
TEST_TIME 79 9720
TEST_TIME 80 9772
TEST_TIME 81 9736
TEST_TIME 82 9735
TEST_TIME 83 9775
TEST_TIME 84 9668
TEST_TIME 85 9639
TEST_TIME 86 9649
TEST_TIME 87 9592
TEST_TIME 88 9549
TEST_TIME 89 9498
TEST_TIME 90 9427
TEST_TIME 91 9426
TEST_TIME 92 9398
TEST_TIME 93 9364
TEST_TIME 94 9359
TEST_TIME 95 9330
TEST_TIME 96 9300
TEST_TIME 97 9361
TEST_TIME 98 9406
TEST_TIME 99 9525
TEST_TIME 100 9514
TEST_TIME 101 9526
TEST_TIME 102 9561
TEST_TIME 103 9555
TEST_TIME 104 9572
TEST_TIME 105 9569
TEST_TIME 106 9492
TEST_TIME 107 9452
TEST_TIME 108 9424
TEST_TIME 109 9380
TEST_TIME 110 9371
TEST_TIME 111 9342
TEST_TIME 112 9296
TEST_TIME 113 9266
TEST_TIME 114 9224
TEST_TIME 115 9283
TEST_TIME 116 9302
TEST_TIME 117 9274
TEST_TIME 118 9250
TEST_TIME 119 9282
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 07:38:26 to 27-12-2021 07:38:29.

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 1536081 1536081 0 0.00 0 20036 7 0 0
doc_list_biud 1536075 1536075 0 0.00 0 65044 33 0 0
sp_add_invoice_to_stock 5111 5001 110 2.15 36 160956 18712 0 110
sp_cancel_adding_invoice 5658 5658 0 0.00 6 75361 946 0 0
sp_cancel_client_order 1835 1835 0 0.00 4 8928 318 0 0
sp_cancel_customer_reserve 556727 556727 0 0.00 1 22830 72 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 3469 76 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 6799 97 0 0
sp_cancel_supplier_invoice 6301 6287 14 0.22 2 13937 1046 0 14
sp_cancel_supplier_order 391 391 0 0.00 8 1256152 130281 0 0
sp_cancel_write_off 9456 9456 0 0.00 1 9809 138 0 0
sp_client_order 20635 20635 0 0.00 4 17911 407 0 0
sp_customer_reserve 464854 464854 0 0.00 0 37168 183 0 0
sp_fill_shopping_cart 31682 31682 0 0.00 1 14441 42 0 0
sp_fill_shopping_cart_clo_res 464850 464850 0 0.00 0 13093 16 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 22522 355 0 0
sp_kill_cost_storno 16230 16230 0 0.00 0 15661 69 0 0
sp_kill_qstorno_ret_qs2qd 1129588 1129588 0 0.00 0 22825 39 0 0
sp_kill_qty_storno 595370 595370 0 0.00 0 65043 84 0 0
sp_lock_selected_doc 20908 20908 0 0.00 0 8867 6 0 0
sp_make_cost_storno 27876 27876 0 0.00 0 19950 57 0 0
sp_multiply_rows_for_pdistr 27875 27875 0 0.00 0 14970 36 0 0
sp_multiply_rows_for_qdistr 20635 20635 0 0.00 0 11868 207 0 0
sp_pay_from_customer 7008 7008 0 0.00 4 15084 131 0 0
sp_pay_to_supplier 5849 5849 0 0.00 4 15142 136 0 0
sp_payment_common 12855 12855 0 0.00 1 14724 21 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 8920 291 0 0
sp_qd_handle_on_invoice_upd_sts 10654 10654 0 0.00 0 65042 402 0 0
sp_qd_handle_on_reserve_upd_sts 10020 10020 0 0.00 0 14417 60 0 0
sp_reserve_write_off 10022 10022 0 0.00 7 15598 192 0 0
sp_supplier_invoice 5624 5624 0 0.00 6 67202 3691 0 0
sp_supplier_order 5452 5452 0 0.00 5 40796 1386 0 0
srv_aggregate_perf_data 110 110 0 0.00 2360 342974 16047 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 947 128953 42436 0 0
srv_find_qd_qs_mism 1058752 1058752 0 0.00 0 10321 6 0 0
srv_make_invnt_saldo 465 465 0 0.00 71 117583 3773 0 0
srv_make_money_saldo 705 705 0 0.00 1 11849 628 0 0
srv_recalc_idx_stat 4 4 0 0.00 170861 399729 291243 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5001 5001 0 0.00 0 22848 186 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 5234 123 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 2795 15 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 6471 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3400 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 1889 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 671 16 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 25649 1682 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1910 5 0 0
t$perf-norm:sp_client_order 20635 20635 0 0.00 0 9876 21 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 5056 13 0 0
t$perf-norm:sp_pay_from_customer 7008 7008 0 0.00 0 9308 14 0 0
t$perf-norm:sp_pay_to_supplier 5849 5849 0 0.00 0 5502 11 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 5773 11 0 0
t$perf-norm:sp_supplier_invoice 5624 5624 0 0.00 0 13813 21 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 4222 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 225 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 103 3 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 1076 10 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 3769 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10208 9988 220 2.15 0 3335 17 0 220
v_all_customers 37138 37138 0 0.00 0 44 1 0 0
v_all_suppliers 30326 30326 0 0.00 0 29 1 0 0
v_all_wares 41270 41270 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 3489 16 0 0
v_cancel_client_order 3670 3670 0 0.00 0 3321 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3440 10 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 2323 7 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2183 13 0 0
v_cancel_supplier_invoice 876 848 28 3.19 0 423 13 0 28
v_cancel_supplier_order 782 782 0 0.00 0 2838 9 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2081 16 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 2898 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 3596 14 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 6096 5 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 250 2 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 446 2 0 0
v_min_id_clo_ord 5450 5450 0 0.00 0 2233 7 0 0
v_min_id_clo_res 979 979 0 0.00 0 7896 20 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 5771 7 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 8639 14 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 6149 13 0 0
v_our_firm 10274 10274 0 0.00 0 14 1 0 0
v_reserve_write_off 20038 20038 0 0.00 0 5014 8 0 0
x_lock_depdocs_on_canc_invoice 11521 11521 0 0.00 0 16118 135 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 9554 401 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 40784 1300 0 0
x_make_qstorno_1200_2000 5624 5624 0 0.00 3 64881 3542 0 0
x_make_qstorno_2100_3300 464854 464854 0 0.00 0 37166 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 07:38:30 to 27-12-2021 07:38:34.

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
05:07:14 2 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:14 33798 8192000000 9728223216 10026819584 803750352 8.934 100 85 170 1 831575296 7450384 20374560
05:09:54 22992 8192000000 9771157744 10078019584 838605040 9.286 100 90 180 0 873643472 14052496 20985936
05:11:22 14892 8192000000 9807272016 10129494016 869963776 9.600 100 83 166 0 920055600 31790624 18301200
05:12:40 12701 8192000000 9886268272 10221391872 925818112 10.153 100 81 160 1 997216432 54542608 16855712
05:13:55 8374 8192000000 9928396896 10275463168 964017936 10.528 100 77 153 0 1031278688 51643792 15616960
05:15:06 5161 8192000000 9972254528 10326085632 1002650544 10.904 100 85 168 0 1081806416 62191024 16964848
05:16:14 8040 8192000000 9996956672 10368204800 1030482928 11.173 100 77 152 0 1114534880 69053040 14998912
05:17:26 1396 8192000000 10021243872 10392313856 1056815632 11.426 100 87 172 0 1133540304 59543456 17181216
05:18:29 2670 8192000000 10038601360 10415149056 1067006160 11.523 100 86 176 4 1150551984 66418000 17127824
05:19:32 6739 8192000000 9989289360 10380480512 1074636896 11.596 100 48 96 0 1107867248 22509520 10720832
05:20:40 15775 8192000000 9998960848 10398707712 1077337232 11.622 100 60 115 1 1097315184 8090624 11887328
05:21:57 3020 8192000000 10014768640 10406068224 1085413600 11.699 100 63 125 2 1124280064 26062400 12804064
05:23:01 4457 8192000000 10036733392 10431983616 1088991632 11.733 100 35 70 2 1160306592 64570720 6744240
05:24:07 73694 8192000000 10003233904 10418724864 1095764496 11.797 100 28 56 1 1120049376 18020480 6264400
05:26:21 5519 8192000000 10016212768 10418307072 1087312400 11.717 99 36 71 0 1149665840 54348768 8004672
05:27:26 4576 8192000000 10006086704 10399993856 1087196304 11.716 100 47 94 2 1118843536 21145152 10502080
05:28:34 6639 8192000000 9961609200 10357788672 1039544128 11.260 98 38 76 3 1062022192 13752704 8725360
05:29:41 19764 8192000000 9956185936 10343534592 976286176 10.648 92 35 70 3 1006574096 22018416 8269504
05:31:02 4928 8192000000 9927697712 10309607424 995243024 10.832 98 47 95 2 1030603184 25178000 10182160
05:32:09 6249 8192000000 9949311888 10325590016 1011516256 10.990 98 39 76 1 1045501008 25420784 8563968
05:33:16 5831 8192000000 9969601888 10352889856 1049152032 11.353 100 40 80 0 1072755072 14966576 8636464
05:34:29 12208 8192000000 9961491024 10357800960 1041334592 11.277 99 22 44 0 1064934256 19196208 4403456
05:35:48 7086 8192000000 9980999696 10376040448 1072741888 11.578 100 47 92 1 1099966432 17558128 9666416
05:36:59 9980 8192000000 10001800608 10401452032 1081409040 11.661 100 37 74 0 1113268704 23290880 8568784
05:38:12 5643 8192000000 10048533808 10447290368 1109811440 11.931 100 49 97 2 1158829040 39197152 9820448
05:39:19 100069 8192000000 10088275968 10496593920 1119724320 12.024 100 32 63 1 1182624768 56476608 6423840
05:42:04 29034 8192000000 10120738400 10528481280 1142142304 12.236 100 42 82 0 1225574352 74457696 8974352
05:43:38 4241 8192000000 10189183216 10597257216 1150295680 12.312 100 57 115 1 1297539168 134472752 12770736
05:44:51 19504 8192000000 10193975792 10626686976 1157340608 12.378 100 40 78 1 1294072848 128305408 8426832
05:46:13 4324 8192000000 10218091104 10625556480 1167410480 12.473 100 59 119 0 1333273584 153233808 12629296
05:47:21 10295 8192000000 10267477840 10679332864 1165774704 12.457 100 65 128 0 1377168240 197058864 14334672
05:48:40 1764 8192000000 10271526944 10701266944 1163500192 12.436 99 75 150 1 1375932336 196420800 16011344
05:49:44 1472 8192000000 10311108896 10714591232 1171997952 12.515 100 84 168 2 1410669504 220784576 17886976
05:50:50 5261 8192000000 10288537392 10709217280 1147789808 12.289 100 77 150 2 1386330640 222120768 16420064
05:52:02 1625 8192000000 10286636800 10687270912 1136583392 12.183 100 64 126 0 1389605248 238853792 14168064
05:53:07 3413 8192000000 10340759584 10743676928 1124957328 12.074 98 70 135 0 1423658160 282641872 16058960
05:54:16 2191 8192000000 10306480656 10695307264 1134409072 12.163 99 89 175 2 1399530448 245511568 19609808
05:55:24 1662 8192000000 10294071200 10688036864 1127987008 12.102 100 81 159 1 1392153648 246139040 18027600
05:56:29 945 8192000000 10335433760 10736005120 1141794880 12.232 100 79 156 1 1427262192 267570176 17897136
05:57:34 3934 8192000000 10385208352 10792411136 1134816432 12.167 99 65 128 2 1471597520 321550592 15230496
05:58:46 1381 8192000000 10458068080 10855673856 1156056416 12.366 100 91 181 2 1551285072 373843728 21384928
05:59:55 3697 8192000000 10493836192 10899075072 1155978096 12.366 99 73 144 1 1586475536 412691744 17805696
06:01:06 9395 8192000000 10528515696 10976501760 1170021120 12.497 100 57 114 1 1631634672 447479440 14134112
06:02:20 13193 8192000000 10452136144 10894925824 1158909776 12.393 100 38 76 0 1562533552 393876096 9747680
06:03:47 18455 8192000000 10527853232 10991443968 1144643024 12.259 100 34 68 2 1643612400 489760704 9208672
06:05:11 27224 8192000000 10415754496 10946367488 1119606032 12.023 100 23 46 1 1556950256 430470992 6873232
06:06:41 11961 8192000000 10101832704 10714419200 1128787104 12.110 100 15 30 2 1224957600 92829712 3340784
06:07:59 49218 8192000000 10079141136 10504589312 1127165568 12.095 99 21 43 0 1175952480 44837072 3949840
06:09:53 68251 8192000000 10061622368 10490777600 1116682864 11.996 98 12 21 1 1148156448 29460320 2013264
06:12:05 33329 8192000000 10055833120 10498158592 1129128288 12.113 99 25 49 0 1156793104 22119392 5545424
06:13:38 20906 8192000000 10079306704 10508709888 1130355440 12.125 99 61 122 1 1155863616 12078800 13429376
06:15:02 84475 8192000000 10081988784 10565734400 1133362336 12.153 99 15 30 0 1183214736 47052928 2799472
06:17:37 24373 8192000000 10094306928 10531540992 1135792400 12.176 99 30 60 0 1186752000 44702352 6257248
06:19:06 21002 8192000000 10165668384 10605551616 1163209808 12.433 100 35 70 1 1280375664 108701488 8464368
06:20:29 47567 8192000000 10178160880 10654146560 1154259488 12.349 100 18 36 0 1286303008 127484896 4558624
06:22:17 28993 8192000000 10110226752 10576494592 1134963728 12.168 99 6 12 0 1223771024 87480976 1326320
06:23:47 30113 8192000000 10132833296 10605666304 1128881664 12.111 99 18 36 1 1230239440 96859664 4498112
06:25:18 72756 8192000000 10099666752 10586972160 1135371632 12.172 99 18 36 2 1218123456 78725984 4025840
06:27:40 61929 8192000000 10054942560 10538405888 1086072592 11.705 95 15 28 1 1141127728 51264432 3790704
06:29:47 25330 8192000000 10106445216 10552127488 1138788608 12.204 100 64 127 2 1211939584 60023456 13127520
06:31:18 74389 8192000000 10095876272 10566782976 1088644464 11.730 96 16 32 0 1174440976 82284384 3512128
06:33:44 22773 8192000000 10086361472 10523058176 1130862416 12.129 100 29 58 1 1164990384 28781968 5346000
06:35:19 55238 8192000000 10144604416 10601742336 1113562272 11.966 98 41 82 1 1200481472 78347776 8571424
06:37:16 52971 8192000000 10080389568 10508947456 1096708832 11.806 96 35 68 1 1152112112 48821136 6582144
06:39:10 128952 8192000000 10077227872 10516643840 1102072368 11.857 97 24 48 1 1128417376 21755664 4589344
06:42:19 42940 8192000000 10045958000 10484682752 1101186464 11.849 98 18 36 0 1137085904 32283408 3616032
06:44:02 60295 8192000000 10040442256 10513846272 1097984960 11.819 98 8 16 0 1148447696 49079520 1383216
06:46:08 62482 8192000000 10057767040 10521313280 1081170128 11.659 97 13 26 0 1159105168 75236656 2698384
06:49:09 65304 8192000000 10032635760 10489315328 1051876624 11.379 94 13 26 0 1096470064 42072032 2521408
06:51:16 70214 8192000000 10061031008 10499231744 1089594688 11.739 97 24 48 2 1133752080 39620208 4537184
06:53:29 55775 8192000000 10107310512 10536091648 1101881936 11.855 98 32 64 0 1181346528 72709504 6755088
06:55:29 51048 8192000000 10090574912 10534744064 1113743808 11.968 99 38 76 0 1169329280 48120848 7464624
06:57:25 101442 8192000000 10089544656 10527776768 1091856944 11.760 96 23 46 0 1152820304 56004016 4959344
07:00:09 96844 8192000000 10044318416 10480697344 1112837968 11.959 98 10 20 0 1148964144 34149440 1976736
07:02:51 48921 8192000000 10080995328 10574364672 1130954560 12.130 100 11 22 0 1204047232 70681856 2410816
07:05:12 54705 8192000000 10046218928 10494808064 1110307072 11.935 98 23 44 0 1141244448 26167952 4769424
07:10:08 41199 8192000000 10105095488 10558017536 1148679792 12.297 100 36 71 3 1189110480 33521216 6909472
07:11:49 57522 8192000000 10149515360 10606350336 1146079296 12.273 99 22 44 0 1242512960 91748848 4684816
07:13:47 88142 8192000000 10180441136 10687598592 1142165840 12.236 98 21 42 1 1290062912 142882704 5014368
07:16:19 67647 8192000000 10085559728 10591576064 1090880448 11.751 95 13 26 1 1149175600 55987600 2307552
07:18:39 58044 8192000000 10079464112 10548891648 1123818560 12.063 98 23 46 1 1191197136 62776128 4602448
07:20:44 83614 8192000000 10081119552 10579644416 1135765056 12.176 100 26 52 0 1178349504 37526528 5057920
07:23:08 122311 8192000000 10060907200 10506203136 1124435824 12.069 99 20 40 2 1182944880 54293072 4215984
07:26:12 55657 8192000000 10105494240 10582872064 1122672880 12.052 98 8 16 0 1191104912 66660432 1771600
07:28:08 49827 8192000000 10096275984 10574671872 1134038400 12.159 100 13 27 2 1189411616 51808944 3564272
07:30:16 104061 8192000000 10134148032 10592501760 1081347296 11.660 95 13 26 0 1201064576 116786816 2930464
07:33:00 58502 8192000000 10045766544 10490331136 1091871744 11.760 97 21 42 1 1125280704 28742160 4666800
07:35:03 45035 8192000000 10093080864 10527662080 1079505488 11.643 96 25 48 2 1150618304 66034688 5078128
07:36:51 33422 8192000000 9203205296 9516806144 336273232 3.943 30 8 13 1 361122704 23440224 1409248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-12-2021 07:38:36 to 27-12-2021 07:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2021 07:38:52 to 27-12-2021 07:38:54.

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          238431
MON$OLDEST_ACTIVE               238432
MON$OLDEST_SNAPSHOT             238432
MON$NEXT_TRANSACTION            238432
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                       2073824
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             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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'

27.12.21 07:38:55. 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-12-27 07:38:57 No severe PSQL-related problems occured
27.12.21 07:38:59. End of report.