Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.03.2021 07:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16597356 kB
MemAvailable 31479740 kB
Buffers 0 kB
Cached 15013204 kB
SwapCached 0 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.0
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-03-24 07:26:55
DTS_END                         2021-03-24 07:26:55.0120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53062
TRN_ID                          243094
ATT_ID                          663
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: 9361.45 20644 1123374
customer order: creation 179.04 391 21485
customer order: refuse 15.07 303 1808
order to supplier: creation 47.50 1307 5700
order to supplier: removal 3.33 106160 400
invoice (draft): creation 47.28 3292 5673
invoice (draft): removal 51.29 929 6155
invoice accept: apply 42.79 17221 5135
invoice accept: cancel 46.99 864 5639
customer reserve: creation 4010.62 166 481274
customer reserve: removal 4614.96 60 553795
realization accept: apply 85.48 175 10258
realization accept: cancel 84.28 125 10113
payment from customer: creation 61.22 114 7346
payment from customer: removal 5.48 64 658
payment to supplier: creation 51.68 116 6201
payment to supplier: removal 3.68 67 441
service: total inventory turnovers 3.89 3444 467
service: total monetary turnovers 5.86 680 703
service: aggregate perf. data 0.99 14586 119
service: refresh index statistics 0.03 262822 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-03-2021 07:27:39 to 24-03-2021 07:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3910
TEST_TIME 2 4910
TEST_TIME 3 5142
TEST_TIME 4 5496
TEST_TIME 5 5395
TEST_TIME 6 5646
TEST_TIME 7 5914
TEST_TIME 8 6346
TEST_TIME 9 6157
TEST_TIME 10 6692
TEST_TIME 11 6662
TEST_TIME 12 7112
TEST_TIME 13 7548
TEST_TIME 14 7492
TEST_TIME 15 7475
TEST_TIME 16 7548
TEST_TIME 17 7756
TEST_TIME 18 8006
TEST_TIME 19 8562
TEST_TIME 20 8641
TEST_TIME 21 9033
TEST_TIME 22 8965
TEST_TIME 23 9337
TEST_TIME 24 9583
TEST_TIME 25 10450
TEST_TIME 26 10889
TEST_TIME 27 10826
TEST_TIME 28 11199
TEST_TIME 29 11207
TEST_TIME 30 10988
TEST_TIME 31 10807
TEST_TIME 32 10785
TEST_TIME 33 10939
TEST_TIME 34 10883
TEST_TIME 35 10872
TEST_TIME 36 10768
TEST_TIME 37 10788
TEST_TIME 38 10627
TEST_TIME 39 10793
TEST_TIME 40 10598
TEST_TIME 41 10379
TEST_TIME 42 10360
TEST_TIME 43 10282
TEST_TIME 44 10190
TEST_TIME 45 10093
TEST_TIME 46 10035
TEST_TIME 47 10174
TEST_TIME 48 10125
TEST_TIME 49 10034
TEST_TIME 50 10039
TEST_TIME 51 9912
TEST_TIME 52 9989
TEST_TIME 53 9903
TEST_TIME 54 9898
TEST_TIME 55 9954
TEST_TIME 56 9871
TEST_TIME 57 9926
TEST_TIME 58 9909
TEST_TIME 59 9969
TEST_TIME 60 9964
TEST_TIME 61 9956
TEST_TIME 62 9956
TEST_TIME 63 10024
TEST_TIME 64 10035
TEST_TIME 65 10038
TEST_TIME 66 10010
TEST_TIME 67 9977
TEST_TIME 68 9973
TEST_TIME 69 9926
TEST_TIME 70 9796
TEST_TIME 71 9730
TEST_TIME 72 9698
TEST_TIME 73 9659
TEST_TIME 74 9601
TEST_TIME 75 9544
TEST_TIME 76 9521
TEST_TIME 77 9564
TEST_TIME 78 9521
TEST_TIME 79 9509
TEST_TIME 80 9528
TEST_TIME 81 9493
TEST_TIME 82 9458
TEST_TIME 83 9474
TEST_TIME 84 9545
TEST_TIME 85 9649
TEST_TIME 86 9636
TEST_TIME 87 9607
TEST_TIME 88 9519
TEST_TIME 89 9536
TEST_TIME 90 9480
TEST_TIME 91 9489
TEST_TIME 92 9450
TEST_TIME 93 9453
TEST_TIME 94 9415
TEST_TIME 95 9389
TEST_TIME 96 9415
TEST_TIME 97 9438
TEST_TIME 98 9412
TEST_TIME 99 9397
TEST_TIME 100 9429
TEST_TIME 101 9503
TEST_TIME 102 9476
TEST_TIME 103 9487
TEST_TIME 104 9577
TEST_TIME 105 9531
TEST_TIME 106 9642
TEST_TIME 107 9581
TEST_TIME 108 9498
TEST_TIME 109 9463
TEST_TIME 110 9401
TEST_TIME 111 9353
TEST_TIME 112 9305
TEST_TIME 113 9284
TEST_TIME 114 9304
TEST_TIME 115 9315
TEST_TIME 116 9381
TEST_TIME 117 9365
TEST_TIME 118 9363
TEST_TIME 119 9376
TEST_TIME 120 9366

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-03-2021 07:27:43 to 24-03-2021 07:27:46.

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 1566481 1566481 0 0.00 0 24569 7 0 0
doc_list_biud 1566479 1566479 0 0.00 0 60528 28 0 0
sp_add_invoice_to_stock 5238 5135 103 1.96 37 190218 17222 0 103
sp_cancel_adding_invoice 5639 5639 0 0.00 5 74814 865 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 11346 303 0 0
sp_cancel_customer_reserve 553795 553795 0 0.00 1 35466 60 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 2317 64 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 1545 67 0 0
sp_cancel_supplier_invoice 6169 6155 14 0.22 3 60601 930 0 14
sp_cancel_supplier_order 400 400 0 0.00 13 1198774 106161 0 0
sp_cancel_write_off 10113 10113 0 0.00 1 10054 125 0 0
sp_client_order 21485 21485 0 0.00 4 21736 391 0 0
sp_customer_reserve 481274 481274 0 0.00 0 84868 167 0 0
sp_fill_shopping_cart 32837 32837 0 0.00 1 14041 41 0 0
sp_fill_shopping_cart_clo_res 481253 481253 0 0.00 0 22461 15 0 0
sp_get_clo_for_invoice 5114 5114 0 0.00 0 34647 332 0 0
sp_kill_cost_storno 16851 16851 0 0.00 0 24565 58 0 0
sp_kill_qstorno_ret_qs2qd 1124258 1124258 0 0.00 0 60527 33 0 0
sp_kill_qty_storno 593281 593281 0 0.00 0 60527 72 0 0
sp_lock_selected_doc 21365 21365 0 0.00 0 9415 6 0 0
sp_make_cost_storno 28919 28919 0 0.00 0 20434 58 0 0
sp_multiply_rows_for_pdistr 28918 28918 0 0.00 0 10774 30 0 0
sp_multiply_rows_for_qdistr 21485 21485 0 0.00 0 12669 210 0 0
sp_pay_from_customer 7346 7346 0 0.00 4 39117 114 0 0
sp_pay_to_supplier 6201 6201 0 0.00 5 15923 117 0 0
sp_payment_common 13547 13547 0 0.00 1 7533 20 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 11337 276 0 0
sp_qd_handle_on_invoice_upd_sts 10752 10752 0 0.00 0 17936 353 0 0
sp_qd_handle_on_reserve_upd_sts 10258 10258 0 0.00 0 9371 49 0 0
sp_reserve_write_off 10258 10258 0 0.00 6 22723 176 0 0
sp_supplier_invoice 5673 5673 0 0.00 7 81643 3293 0 0
sp_supplier_order 5700 5700 0 0.00 5 93368 1307 0 0
srv_aggregate_perf_data 119 119 0 0.00 2905 212752 14586 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1196 180433 49244 0 0
srv_find_qd_qs_mism 1072574 1072574 0 0.00 0 20342 6 0 0
srv_make_invnt_saldo 467 467 0 0.00 56 94430 3444 0 0
srv_make_money_saldo 703 703 0 0.00 1 19813 680 0 0
srv_recalc_idx_stat 4 4 0 0.00 139238 386808 262823 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 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 5135 5135 0 0.00 0 31864 170 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 6722 123 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 1122 8 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 2463 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 2061 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1730 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 2713 24 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 19386 1633 0 0
t$perf-norm:sp_cancel_write_off 1105 1105 0 0.00 0 2228 8 0 0
t$perf-norm:sp_client_order 21485 21485 0 0.00 0 7430 17 0 0
t$perf-norm:sp_customer_reserve 1125 1125 0 0.00 0 3085 8 0 0
t$perf-norm:sp_pay_from_customer 7346 7346 0 0.00 0 5447 9 0 0
t$perf-norm:sp_pay_to_supplier 6201 6201 0 0.00 0 4763 8 0 0
t$perf-norm:sp_reserve_write_off 10258 10258 0 0.00 0 8110 10 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 5920 17 0 0
t$perf-norm:sp_supplier_order 5700 5700 0 0.00 0 4911 15 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 322 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 86 3 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1322 5 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 446 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10426 10220 206 1.97 0 4485 15 0 206
v_all_customers 39572 39572 0 0.00 0 32 1 0 0
v_all_suppliers 31846 31846 0 0.00 0 32 1 0 0
v_all_wares 42968 42968 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 7045 15 0 0
v_cancel_client_order 3616 3616 0 0.00 0 2454 4 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2013 5 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 3448 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1054 5 0 0
v_cancel_supplier_invoice 796 768 28 3.51 0 465 13 0 28
v_cancel_supplier_order 800 800 0 0.00 0 1656 5 0 0
v_cancel_write_off 2210 2210 0 0.00 0 5299 15 0 0
v_max_id_clo_ord 5685 5685 0 0.00 0 296 1 0 0
v_max_id_clo_res 1125 1125 0 0.00 0 9519 23 0 0
v_max_id_ord_sup 5659 5659 0 0.00 0 6505 10 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 5648 6 0 0
v_max_non_paid_realizn 3704 3704 0 0.00 0 1064 3 0 0
v_min_id_clo_ord 5685 5685 0 0.00 0 2039 5 0 0
v_min_id_clo_res 1125 1125 0 0.00 0 4494 6 0 0
v_min_id_ord_sup 5659 5659 0 0.00 0 3544 5 0 0
v_min_non_paid_invoice 6201 6201 0 0.00 0 5416 13 0 0
v_min_non_paid_realizn 7346 7346 0 0.00 0 6418 12 0 0
v_our_firm 10680 10680 0 0.00 0 140 1 0 0
v_reserve_write_off 20516 20516 0 0.00 0 3613 5 0 0
x_lock_depdocs_on_canc_invoice 11410 11410 0 0.00 0 11756 102 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 3103 259 0 0
x_make_qstorno_1000_1200 5700 5700 0 0.00 2 93341 1235 0 0
x_make_qstorno_1200_2000 5673 5673 0 0.00 4 79205 3135 0 0
x_make_qstorno_2100_3300 481274 481274 0 0.00 0 84867 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-03-2021 07:27:47 to 24-03-2021 07:27:52.

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
04:56:58 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:57:59 26897 8192000000 9720155856 10014265344 799558080 8.892 100 88 175 0 828296544 7608432 21130032
04:59:31 12874 8192000000 9762673872 10067058688 838933904 9.289 100 87 174 0 871792320 12164992 20693424
05:00:50 15128 8192000000 9793838832 10108764160 867507280 9.575 100 89 179 0 908727104 20394880 20824944
05:02:21 12244 8192000000 9866385648 10197086208 910926656 10.006 100 79 158 1 972027168 43925616 17174896
05:03:35 8435 8192000000 9923686256 10267504640 965516208 10.543 100 80 161 0 1039762592 57363328 16883056
05:04:47 4111 8192000000 9978711504 10334658560 1000962688 10.888 100 77 156 3 1095701168 78868736 15869744
05:05:52 3202 8192000000 10002244944 10364321792 1028334064 11.152 100 86 170 0 1112842992 66996160 17512768
05:07:03 2415 8192000000 10020236112 10386731008 1055987296 11.418 100 86 175 0 1129158352 55226096 17944960
05:08:06 16776 8192000000 10026968288 10403975168 1067083792 11.524 100 75 150 1 1143332752 61029744 15219216
05:09:24 7306 8192000000 10003058368 10390392832 1077396864 11.623 100 47 94 1 1124381600 37023248 9961488
05:10:34 7869 8192000000 10003892096 10402824192 1091335056 11.755 100 43 86 3 1126837904 26632304 8870544
05:11:44 19599 8192000000 10002862816 10401013760 1094550048 11.786 100 70 138 1 1118285184 8301360 15433776
05:13:04 8050 8192000000 10021811840 10430828544 1098455584 11.823 100 25 48 1 1129765568 26386432 4923552
05:14:13 3998 8192000000 10019306160 10410557440 1102023024 11.857 100 42 83 3 1117153920 7001792 8129104
05:15:17 49383 8192000000 10015659584 10410815488 1103591344 11.872 100 36 72 1 1123127136 11400080 8135712
05:17:07 7084 8192000000 10026191504 10423312384 1083561648 11.681 99 55 109 0 1128267280 32540752 12164880
05:18:18 8684 8192000000 9972635744 10372894720 1040946256 11.274 97 30 61 1 1078713056 31341888 6424912
05:19:28 3254 8192000000 9929618816 10298269696 1001672944 10.895 99 51 101 1 1033653488 20434208 11546336
05:20:32 51073 8192000000 9951436160 10324795392 966581984 10.553 93 39 77 3 996954272 22261024 8111264
05:22:23 4484 8192000000 9955074704 10344906752 1038260464 11.248 100 46 89 1 1056678736 7973536 10444736
05:23:28 10631 8192000000 9961552528 10359705600 1029464960 11.163 98 42 84 0 1061221488 21839920 9916608
05:24:42 7927 8192000000 9995233120 10386866176 1062231920 11.478 100 37 71 1 1083636336 14051072 7353344
05:25:53 59859 8192000000 9986911856 10393128960 1076665840 11.616 100 26 52 1 1106529584 24420048 5443696
05:27:57 12643 8192000000 10041466288 10437619712 1095240288 11.792 100 53 106 0 1146321216 40122320 10958608
05:29:19 24021 8192000000 10087525632 10493448192 1112978032 11.961 100 59 118 2 1197587504 71777744 12831728
05:30:45 3800 8192000000 10103548752 10507198464 1129184288 12.114 100 60 115 2 1214744480 73524896 12035296
05:31:52 3082 8192000000 10157460944 10569617408 1138352688 12.200 100 54 108 0 1266302192 116045392 11904112
05:32:58 13937 8192000000 10184464848 10609340416 1147036288 12.282 100 53 105 1 1278297360 119696656 11564416
05:34:18 19672 8192000000 10201124704 10626834432 1161047568 12.413 100 49 98 1 1314081776 142382080 10652128
05:35:43 8564 8192000000 10289239712 10709676032 1170270080 12.499 100 62 124 0 1393621440 209575968 13775392
05:36:58 2702 8192000000 10278428560 10686648320 1175194256 12.545 100 83 166 1 1387545776 194180608 18170912
05:38:07 2799 8192000000 10321898192 10741460992 1170416416 12.501 100 66 130 0 1431674576 246113088 15145072
05:39:14 1301 8192000000 10296754176 10719870976 1156397632 12.370 99 78 154 1 1390572352 216434224 17740496
05:40:21 2868 8192000000 10324513008 10731802624 1168109872 12.479 100 74 147 1 1427169312 243167968 15891472
05:41:36 5853 8192000000 10336955920 10748416000 1143454688 12.248 100 59 117 0 1445911600 288922224 13534688
05:42:47 1195 8192000000 10345183328 10748194816 1124305104 12.068 99 94 186 1 1438859184 293937728 20616352
05:43:55 1431 8192000000 10347362320 10763481088 1131606848 12.137 100 84 166 0 1445591200 294703360 19280992
05:45:04 1453 8192000000 10376963040 10791636992 1144174880 12.255 100 96 189 3 1471472352 305632848 21664624
05:46:10 8462 8192000000 10327384144 10746507264 1118533920 12.013 99 38 75 1 1428085456 300231040 9320496
05:47:21 4453 8192000000 10346328592 10763157504 1129757808 12.119 100 46 92 2 1448726144 307885776 11082560
05:48:29 3268 8192000000 10371794544 10782818304 1124157296 12.066 99 68 135 2 1459863184 320565136 15140752
05:49:35 26814 8192000000 10357041504 10834890752 1100476256 11.842 98 26 52 1 1482492768 374704608 7311904
05:51:07 12700 8192000000 10269458368 10749399040 1119325936 12.021 100 29 58 1 1366291776 239881840 7084000
05:52:21 23857 8192000000 10122425600 10577653760 1117704816 12.005 100 21 42 1 1253502800 130852624 4945360
05:53:49 33178 8192000000 10128894112 10600976384 1117910272 12.007 99 16 30 1 1224416544 103330288 3175984
05:55:30 70066 8192000000 10030419024 10457231360 1071555408 11.567 95 13 25 2 1102399632 27930560 2913664
05:57:40 37808 8192000000 10102011216 10540273664 1136470960 12.182 100 24 48 2 1166665920 25262288 4932672
05:59:19 85071 8192000000 10089235504 10584854528 1129834080 12.120 99 8 16 0 1187886192 56557984 1494128
06:01:45 77228 8192000000 10098288256 10553212928 1110528944 11.937 97 19 38 0 1151786720 37698512 3559264
06:05:00 48079 8192000000 10073905040 10554175488 1129672224 12.118 99 6 12 0 1206356496 75320352 1363920
06:06:49 96979 8192000000 10130998352 10569056256 1144264512 12.256 100 39 78 0 1200254288 47858048 8131728
06:09:32 86873 8192000000 10112185808 10561220608 1118880720 12.016 97 21 42 4 1167127520 43513328 4733472
06:12:00 33759 8192000000 10149133360 10601947136 1151682544 12.325 100 29 58 1 1247585152 89537536 6365072
06:13:49 105693 8192000000 10115858896 10570379264 1149016736 12.300 99 15 31 0 1194904032 42704880 3182416
06:16:35 70497 8192000000 10131772768 10575056896 1138768624 12.204 99 17 32 0 1192690816 50120880 3801312
06:18:46 56563 8192000000 10156913632 10647711744 1153166464 12.339 100 21 42 0 1263648416 105051728 5430224
06:20:43 74101 8192000000 10092204240 10594967552 1123888064 12.064 98 6 12 1 1202933408 77921760 1123584
06:23:02 75469 8192000000 10095173376 10579283968 1065975872 11.514 93 14 28 0 1100519424 31818880 2724672
06:25:18 40767 8192000000 10096786528 10581499904 1099624272 11.834 97 44 87 1 1176130144 67658032 8847840
06:27:01 37312 8192000000 10059000992 10494464000 1044519632 11.308 93 35 70 2 1086466032 35491040 6455360
06:28:44 141771 8192000000 10041388976 10523668480 1017639648 11.049 91 4 8 0 1070297392 51786960 870784
06:32:06 52953 8192000000 10031284336 10473000960 1050205184 11.363 95 17 34 0 1087812480 34226912 3380384
06:34:00 65052 8192000000 10070017504 10530791424 1098296416 11.821 99 34 68 1 1161929344 56851888 6781040
06:36:05 108462 8192000000 10105003520 10564661248 1122281504 12.049 100 22 44 0 1199847056 72756960 4808592
06:39:05 92152 8192000000 10090382560 10582282240 1120022896 12.027 100 9 18 0 1179482480 57592992 1866592
06:42:12 67679 8192000000 10063589648 10547253248 1109081456 11.924 98 15 30 0 1174720736 62482016 3157264
06:44:40 50349 8192000000 10096901888 10534350848 1128458896 12.107 99 31 63 0 1187796704 52618496 6719312
06:46:43 53818 8192000000 10120540752 10568257536 1141638064 12.231 100 18 36 1 1216271648 70766912 3866672
06:48:42 77991 8192000000 10167431792 10644930560 1120814368 12.035 98 20 40 1 1240768144 115158176 4795600
06:51:00 74091 8192000000 10077331424 10575978496 1123638560 12.061 99 11 22 0 1240967872 114903792 2425520
06:53:22 180433 8192000000 10096822976 10594385920 1130596320 12.127 99 28 56 1 1206703136 70329648 5777168
06:57:25 39712 8192000000 10087935280 10627362816 1119301328 12.020 98 5 10 0 1197898048 77643728 952992
06:59:22 74447 8192000000 10097142576 10555940864 1134526416 12.164 98 18 36 0 1191228800 52544880 4157504
07:01:43 56363 8192000000 10113982144 10557247488 1135792176 12.176 99 16 32 0 1216338976 77335744 3211056
07:03:39 42656 8192000000 10116386944 10558013440 1126836400 12.092 97 33 66 1 1186551328 52301632 7413296
07:05:24 49502 8192000000 10156288608 10593939456 1152949312 12.337 100 27 54 1 1235151680 76784480 5417888
07:07:26 43027 8192000000 10090196928 10555944960 1138730480 12.204 99 18 35 1 1195471824 53291056 3450288
07:09:12 30939 8192000000 10171410032 10600800256 1106104480 11.896 96 43 86 1 1204799264 90041152 8653632
07:10:43 59900 8192000000 10089271136 10553061376 1078980224 11.638 95 7 13 1 1122288880 41997392 1311264
07:12:55 134313 8192000000 10033164032 10467811328 1081006256 11.657 96 10 20 0 1099583488 16466688 2110544
07:16:13 38528 8192000000 10044841120 10511306752 1105808816 11.893 99 4 8 0 1154160176 47651696 699664
07:17:52 104585 8192000000 10140978480 10586910720 1086819408 11.712 97 13 26 0 1164467872 74781584 2866880
07:20:38 65236 8192000000 10099662352 10562060288 1107227984 11.906 99 28 56 0 1210324080 96308064 6788032
07:22:44 49113 8192000000 10078072624 10528321536 1095125952 11.791 98 20 40 2 1140183088 41027536 4029600
07:24:34 85556 8192000000 10092302800 10564640768 1107679424 11.910 99 25 50 0 1174839968 62007392 5153152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-03-2021 07:27:53 to 24-03-2021 07:28:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 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 24-03-2021 07:28:09 to 24-03-2021 07:28:11.

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          239862
MON$OLDEST_ACTIVE               243203
MON$OLDEST_SNAPSHOT             243203
MON$NEXT_TRANSACTION            243203
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                       2123008
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             822
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.03.21 07:28:12. 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-03-24 07:28:14 No severe PSQL-related problems occured
24.03.21 07:28:16. End of report.