Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2022 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 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 16306460 kB
MemAvailable 31365060 kB
Buffers 0 kB
Cached 15414404 kB
SwapCached 1220 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 2022-01-06 10:37:09
DTS_END                         2022-01-06 10:37:09.3880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51842
TRN_ID                          208969
ATT_ID                          638
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: 9009.76 25908 1081171
customer order: creation 161.78 476 19414
customer order: refuse 13.64 446 1637
order to supplier: creation 44.65 1371 5358
order to supplier: removal 3.43 192830 412
invoice (draft): creation 43.47 3322 5216
invoice (draft): removal 51.79 1100 6215
invoice accept: apply 38.98 28075 4678
invoice accept: cancel 47.08 1291 5649
customer reserve: creation 3668.87 263 440264
customer reserve: removal 4644.86 112 557383
realization accept: apply 78.53 297 9423
realization accept: cancel 88.00 184 10560
payment from customer: creation 56.27 181 6752
payment from customer: removal 5.73 146 687
payment to supplier: creation 47.32 201 5678
payment to supplier: removal 3.20 132 384
service: total inventory turnovers 4.22 3880 507
service: total monetary turnovers 7.11 414 853
service: aggregate perf. data 0.80 17961 96
service: refresh index statistics 0.04 265478 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2022 10:39:18 to 06-01-2022 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8330
TEST_TIME 2 7098
TEST_TIME 3 5973
TEST_TIME 4 5786
TEST_TIME 5 5333
TEST_TIME 6 5127
TEST_TIME 7 5164
TEST_TIME 8 5046
TEST_TIME 9 5375
TEST_TIME 10 5548
TEST_TIME 11 5634
TEST_TIME 12 5625
TEST_TIME 13 5891
TEST_TIME 14 5894
TEST_TIME 15 6163
TEST_TIME 16 6188
TEST_TIME 17 6275
TEST_TIME 18 6469
TEST_TIME 19 6502
TEST_TIME 20 6683
TEST_TIME 21 6780
TEST_TIME 22 6764
TEST_TIME 23 7132
TEST_TIME 24 7398
TEST_TIME 25 7398
TEST_TIME 26 7698
TEST_TIME 27 7666
TEST_TIME 28 7811
TEST_TIME 29 8570
TEST_TIME 30 8817
TEST_TIME 31 8821
TEST_TIME 32 9365
TEST_TIME 33 9804
TEST_TIME 34 9973
TEST_TIME 35 10011
TEST_TIME 36 10132
TEST_TIME 37 10157
TEST_TIME 38 10073
TEST_TIME 39 10306
TEST_TIME 40 10385
TEST_TIME 41 10485
TEST_TIME 42 10370
TEST_TIME 43 10146
TEST_TIME 44 10000
TEST_TIME 45 9944
TEST_TIME 46 9924
TEST_TIME 47 9847
TEST_TIME 48 9729
TEST_TIME 49 9751
TEST_TIME 50 9758
TEST_TIME 51 9717
TEST_TIME 52 9685
TEST_TIME 53 9650
TEST_TIME 54 9624
TEST_TIME 55 9571
TEST_TIME 56 9500
TEST_TIME 57 9419
TEST_TIME 58 9397
TEST_TIME 59 9341
TEST_TIME 60 9359
TEST_TIME 61 9265
TEST_TIME 62 9180
TEST_TIME 63 9224
TEST_TIME 64 9157
TEST_TIME 65 9136
TEST_TIME 66 9244
TEST_TIME 67 9245
TEST_TIME 68 9252
TEST_TIME 69 9322
TEST_TIME 70 9341
TEST_TIME 71 9300
TEST_TIME 72 9357
TEST_TIME 73 9337
TEST_TIME 74 9318
TEST_TIME 75 9379
TEST_TIME 76 9344
TEST_TIME 77 9281
TEST_TIME 78 9252
TEST_TIME 79 9186
TEST_TIME 80 9189
TEST_TIME 81 9113
TEST_TIME 82 9113
TEST_TIME 83 9069
TEST_TIME 84 9027
TEST_TIME 85 8981
TEST_TIME 86 9015
TEST_TIME 87 8988
TEST_TIME 88 9023
TEST_TIME 89 9033
TEST_TIME 90 9018
TEST_TIME 91 8993
TEST_TIME 92 8983
TEST_TIME 93 9023
TEST_TIME 94 9117
TEST_TIME 95 9199
TEST_TIME 96 9176
TEST_TIME 97 9189
TEST_TIME 98 9175
TEST_TIME 99 9146
TEST_TIME 100 9084
TEST_TIME 101 9046
TEST_TIME 102 9023
TEST_TIME 103 8965
TEST_TIME 104 8921
TEST_TIME 105 8889
TEST_TIME 106 8856
TEST_TIME 107 8838
TEST_TIME 108 8855
TEST_TIME 109 8861
TEST_TIME 110 8848
TEST_TIME 111 8856
TEST_TIME 112 8842
TEST_TIME 113 8812
TEST_TIME 114 8849
TEST_TIME 115 8951
TEST_TIME 116 8985
TEST_TIME 117 9080
TEST_TIME 118 9098
TEST_TIME 119 9118
TEST_TIME 120 9042

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2022 10:39:21 to 06-01-2022 10:39:25.

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 1488306 1488306 0 0.00 0 29571 14 0 0
doc_list_biud 1488283 1488283 0 0.00 0 33781 48 0 0
sp_add_invoice_to_stock 4758 4678 80 1.68 30 200689 28076 0 80
sp_cancel_adding_invoice 5649 5649 0 0.00 5 64084 1291 0 0
sp_cancel_client_order 1637 1637 0 0.00 7 9873 446 0 0
sp_cancel_customer_reserve 557383 557383 0 0.00 1 30861 112 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 4 8502 147 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 3874 132 0 0
sp_cancel_supplier_invoice 6222 6215 7 0.11 3 25704 1101 0 7
sp_cancel_supplier_order 412 412 0 0.00 12 1571321 192831 0 0
sp_cancel_write_off 10560 10560 0 0.00 2 33909 184 0 0
sp_client_order 19414 19414 0 0.00 5 14815 477 0 0
sp_customer_reserve 440264 440264 0 0.00 1 81529 263 0 0
sp_fill_shopping_cart 29973 29973 0 0.00 1 20831 87 0 0
sp_fill_shopping_cart_clo_res 440222 440222 0 0.00 0 18170 20 0 0
sp_get_clo_for_invoice 4622 4622 0 0.00 0 38730 326 0 0
sp_kill_cost_storno 17280 17280 0 0.00 0 23654 82 0 0
sp_kill_qstorno_ret_qs2qd 1131953 1131953 0 0.00 0 33779 49 0 0
sp_kill_qty_storno 595899 595899 0 0.00 0 33781 112 0 0
sp_lock_selected_doc 19640 19640 0 0.00 0 9580 7 0 0
sp_make_cost_storno 26473 26473 0 0.00 0 29566 83 0 0
sp_multiply_rows_for_pdistr 26473 26473 0 0.00 0 8683 39 0 0
sp_multiply_rows_for_qdistr 19412 19412 0 0.00 0 11113 205 0 0
sp_pay_from_customer 6752 6752 0 0.00 5 19839 181 0 0
sp_pay_to_supplier 5678 5678 0 0.00 6 13427 201 0 0
sp_payment_common 12430 12430 0 0.00 2 13480 46 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 9864 379 0 0
sp_qd_handle_on_invoice_upd_sts 10269 10269 0 0.00 0 20425 566 0 0
sp_qd_handle_on_reserve_upd_sts 9423 9423 0 0.00 0 17791 62 0 0
sp_reserve_write_off 9423 9423 0 0.00 8 29579 297 0 0
sp_supplier_invoice 5216 5216 0 0.00 11 97969 3322 0 0
sp_supplier_order 5358 5358 0 0.00 7 35615 1372 0 0
srv_aggregate_perf_data 96 96 0 0.00 2321 170157 17961 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 752 109431 32812 0 0
srv_find_qd_qs_mism 1035372 1035372 0 0.00 0 24754 11 0 0
srv_make_invnt_saldo 507 507 0 0.00 62 53949 3880 0 0
srv_make_money_saldo 853 853 0 0.00 1 15508 415 0 0
srv_recalc_idx_stat 5 5 0 0.00 102466 375973 265478 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4683 4683 0 0.00 1 18045 983 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 3 12612 551 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 1363 29 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 2064 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2271 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 366 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 760 33 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 117931 7750 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 4310 25 0 0
t$perf-norm:sp_client_order 19414 19414 0 0.00 0 10850 40 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 2018 17 0 0
t$perf-norm:sp_pay_from_customer 6752 6752 0 0.00 0 3453 19 0 0
t$perf-norm:sp_pay_to_supplier 5678 5678 0 0.00 0 12770 27 0 0
t$perf-norm:sp_reserve_write_off 9423 9423 0 0.00 0 11208 28 0 0
t$perf-norm:sp_supplier_invoice 5216 5216 0 0.00 0 6000 38 0 0
t$perf-norm:sp_supplier_order 5358 5358 0 0.00 0 3624 33 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 285 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 117 7 0 0
t$perf-norm:srv_make_invnt_saldo 507 507 0 0.00 0 1184 18 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 2377 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 193 66 0 0
v_add_invoice_to_stock 9398 9238 160 1.70 0 7608 23 0 160
v_all_customers 35592 35592 0 0.00 0 263 4 0 0
v_all_suppliers 29966 29966 0 0.00 0 175 4 0 0
v_all_wares 38824 38824 0 0.00 0 263 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 275 13 0 0
v_cancel_client_order 3274 3274 0 0.00 0 743 9 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1803 11 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 1 1775 11 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 3851 18 0 0
v_cancel_supplier_invoice 868 854 14 1.61 0 1634 25 0 14
v_cancel_supplier_order 824 824 0 0.00 0 811 12 0 0
v_cancel_write_off 2030 2030 0 0.00 0 1516 23 0 0
v_max_id_clo_ord 5354 5354 0 0.00 0 183 5 0 0
v_max_id_clo_res 960 960 0 0.00 0 6632 25 0 0
v_max_id_ord_sup 5207 5207 0 0.00 0 1587 5 0 0
v_max_non_paid_invoice 1256 1256 0 0.00 0 84 8 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 3147 8 0 0
v_min_id_clo_ord 5354 5354 0 0.00 0 3066 10 0 0
v_min_id_clo_res 960 960 0 0.00 0 2801 10 0 0
v_min_id_ord_sup 5207 5207 0 0.00 0 5132 8 0 0
v_min_non_paid_invoice 5678 5678 0 0.00 0 9157 18 0 0
v_min_non_paid_realizn 6752 6752 0 0.00 0 13463 18 0 0
v_our_firm 9752 9752 0 0.00 0 268 5 0 0
v_reserve_write_off 18846 18846 0 0.00 0 3881 10 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 22356 144 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 17600 414 0 0
x_make_qstorno_1000_1200 5358 5358 0 0.00 2 35529 1190 0 0
x_make_qstorno_1200_2000 5216 5216 0 0.00 6 77283 3090 0 0
x_make_qstorno_2100_3300 440262 440262 0 0.00 0 81527 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2022 10:39:26 to 06-01-2022 10:39:30.

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:13 54 8192000000 8813837520 8831365120 12280464 0.149 6 1 2 0 12385280 69040 35776
08:08:14 41895 8192000000 9746911680 10047848448 820918736 9.108 100 89 177 1 849651200 7835040 20897424
08:10:00 31970 8192000000 9770076208 10078986240 843585392 9.336 100 85 170 0 878061728 14258288 20218048
08:11:39 15449 8192000000 9814061888 10132406272 874504768 9.645 100 80 160 0 918252176 25834672 17912736
08:13:00 10755 8192000000 9864847904 10200395776 919261808 10.089 100 83 166 0 979878864 42033312 18583744
08:14:17 5842 8192000000 9909410208 10252247040 954030640 10.431 100 78 152 0 1028047232 58286624 15729968
08:15:29 3231 8192000000 9964716096 10314440704 994268832 10.823 100 92 179 1 1087588016 75095696 18223488
08:16:37 1465 8192000000 9987012816 10345603072 1021649968 11.088 100 89 171 1 1106964944 67753568 17561408
08:17:40 1604 8192000000 10023017600 10389590016 1039636576 11.261 100 90 175 2 1142077680 84720288 17720816
08:18:44 986 8192000000 10011139728 10381234176 1047415104 11.336 100 90 178 0 1132537072 66860912 18261056
08:19:48 947 8192000000 10032232080 10409504768 1055744384 11.416 100 94 182 2 1155346240 80605488 18996368
08:20:52 1291 8192000000 10050270944 10427555840 1061614176 11.472 100 92 182 0 1172812464 92731872 18466416
08:21:57 1062 8192000000 10050871504 10429026304 1075022496 11.600 100 96 174 3 1171520848 78110976 18387376
08:23:00 1154 8192000000 10064256624 10442809344 1078517280 11.633 100 96 185 2 1182008112 84858400 18632432
08:24:03 1030 8192000000 10055663680 10442391552 1085237744 11.697 100 83 166 0 1177075632 74894592 16943296
08:25:05 1082 8192000000 10050795648 10438275072 1089596000 11.739 100 92 174 0 1173412592 65155200 18661392
08:26:08 1114 8192000000 10063072736 10448097280 1092355360 11.765 100 88 170 2 1186006896 76940752 16710784
08:27:10 964 8192000000 10073944512 10461782016 1095339344 11.793 100 82 163 0 1193068000 81209760 16518896
08:28:12 1292 8192000000 10066769808 10450399232 1094642496 11.787 100 98 190 0 1187655440 74053648 18959296
08:29:15 1336 8192000000 10036683296 10419453952 1075766768 11.607 100 96 179 2 1158633968 65609328 17257872
08:30:17 1343 8192000000 10044862160 10428092416 1087784912 11.722 100 90 169 2 1167601920 62741456 17075552
08:31:21 1166 8192000000 10047901008 10433171456 1080881648 11.656 100 90 172 0 1169353744 71046864 17425232
08:32:23 875 8192000000 10027388448 10411593728 1070351056 11.555 100 75 146 0 1151711200 66268832 15091312
08:33:25 952 8192000000 10024682368 10406113280 1067446384 11.528 100 89 171 0 1146966288 61697872 17822032
08:34:27 940 8192000000 10011584048 10384859136 1033369600 11.201 99 87 166 1 1133408576 83003856 17035120
08:35:30 3099 8192000000 9986951408 10365706240 1039594480 11.261 100 71 137 1 1113219824 60553520 13071824
08:36:34 913 8192000000 9991728720 10362474496 1029797808 11.166 99 95 173 2 1117487984 68906048 18784128
08:37:42 11394 8192000000 9999181072 10373312512 1047828272 11.340 100 72 127 0 1120989280 61498608 11662400
08:39:00 1039 8192000000 10024596880 10397044736 1064671840 11.501 100 87 158 1 1140466128 60717408 15076880
08:40:05 8960 8192000000 10045878768 10426564608 1065273904 11.507 100 69 136 1 1164134864 84590448 14270512
08:41:39 2362 8192000000 10087787920 10473746432 1085335024 11.698 100 72 139 1 1204270960 103764528 15171408
08:42:47 14366 8192000000 10123147536 10516426752 1102393504 11.860 100 69 137 0 1241034160 123760128 14880528
08:44:09 6485 8192000000 10141141008 10523734016 1108795280 11.921 100 87 160 3 1256029936 129200608 18034048
08:45:26 3245 8192000000 10207364528 10595524608 1131679552 12.137 100 83 162 4 1320674080 171310112 17684416
08:46:32 1070 8192000000 10273255552 10661949440 1146235120 12.274 100 98 189 0 1385980864 219535824 20209920
08:47:37 5773 8192000000 10281846880 10684821504 1139915776 12.215 100 72 142 0 1401322368 245350944 16055648
08:48:48 1860 8192000000 10303045792 10694615040 1158126688 12.386 100 82 164 1 1414549536 237927824 18495024
08:49:54 1104 8192000000 10352024944 10759106560 1172656384 12.522 100 92 179 0 1462767440 269090272 21020784
08:51:00 1331 8192000000 10373013808 10779041792 1178831584 12.579 100 85 160 0 1486465472 287101280 20532608
08:52:06 1912 8192000000 10396103344 10804752384 1169426544 12.491 99 85 167 0 1502829312 313800336 19602432
08:53:14 1045 8192000000 10414717744 10823974912 1185896720 12.645 100 89 175 1 1523176016 316841248 20438048
08:54:19 3130 8192000000 10476480032 10882482176 1199990640 12.776 100 93 181 1 1595188224 373697840 21499744
08:55:26 1093 8192000000 10493127344 10913406976 1199904288 12.775 100 91 174 0 1603563504 382729104 20930112
08:56:33 872 8192000000 10539037296 10947760128 1196401136 12.743 100 93 182 1 1644662736 425978640 22282960
08:57:38 828 8192000000 10577670448 11008319488 1204472784 12.818 100 97 192 0 1679891152 452181776 23236592
08:58:42 2078 8192000000 10558281040 10983706624 1183096784 12.619 99 96 177 1 1665881568 460830608 21954176
08:59:48 969 8192000000 10588438560 11055988736 1195004704 12.730 100 95 187 0 1693196128 476044320 22147104
09:00:54 1319 8192000000 10573054720 11022348288 1200226816 12.778 100 94 185 2 1675893296 452594224 23072256
09:01:59 3560 8192000000 10577856800 11035590656 1198278352 12.760 100 76 148 0 1675237440 457806192 19152896
09:03:06 1110 8192000000 10602967216 11062853632 1192623328 12.708 100 81 161 0 1705354848 492783328 19948192
09:04:13 8868 8192000000 10640619200 11079208960 1188017408 12.665 100 54 106 0 1741209424 538744608 14447408
09:05:38 6770 8192000000 10599681440 11130228736 1183643328 12.624 100 47 94 1 1742230272 545182368 13404576
09:07:00 16712 8192000000 10454475072 10954342400 1169353200 12.491 99 41 82 0 1549101888 369230000 10518688
09:09:01 8432 8192000000 10432808304 10926383104 1154101056 12.348 100 35 68 0 1531851040 368793024 8956960
09:10:13 19528 8192000000 10221563792 10688512000 1160218672 12.405 100 28 56 2 1361482368 194719648 6544048
09:11:42 34593 8192000000 10182699024 10643742720 1139731840 12.213 98 39 78 0 1289206656 140199296 9275520
09:13:18 17649 8192000000 10184765680 10637246464 1159142544 12.395 99 37 75 0 1294384112 125948816 9292752
09:14:39 44626 8192000000 10187121680 10631327744 1150270880 12.312 99 33 64 2 1287530464 128551712 8707872
09:16:25 15639 8192000000 10175255296 10646745088 1122869840 12.054 98 27 53 0 1273665472 144816224 5979408
09:17:44 50002 8192000000 10129523792 10543259648 1113122576 11.962 98 52 91 5 1223060672 98908256 11029840
09:19:36 109430 8192000000 10135164720 10588114944 1143561920 12.249 100 29 52 1 1202234368 53442352 5230096
09:22:26 51359 8192000000 10118322128 10609913856 1141402816 12.229 100 16 28 1 1216544352 72427024 2714512
09:24:20 37905 8192000000 10106641280 10560712704 1140487888 12.220 99 23 46 1 1201375840 56160672 4727280
09:26:01 51439 8192000000 10107593344 10602225664 1125167968 12.076 98 30 57 1 1205233792 73641424 6424400
09:27:58 53045 8192000000 10084057696 10538201088 1117082912 11.999 98 14 28 1 1199543968 78979680 3481376
09:29:51 28247 8192000000 10083616144 10533437440 1138542944 12.202 100 21 41 1 1196263504 52669104 5051456
09:31:20 40313 8192000000 10080002384 10540322816 1110723488 11.939 97 24 48 3 1177809984 61841648 5244848
09:33:08 109390 8192000000 10071347120 10524590080 1129076576 12.113 99 26 52 0 1195887680 61261328 5549776
09:36:09 745 8192000000 10081765264 10514325504 1141244032 12.227 100 90 171 3 1185099872 24354832 19501008
09:37:12 86911 8192000000 10090886800 10581458944 1073869824 11.589 94 19 38 1 1127675296 48948624 4856848
09:39:45 72649 8192000000 10050007808 10535186432 1110931200 11.941 99 16 31 0 1181876496 67771200 3174096
09:41:58 43224 8192000000 10126035440 10574827520 1103701440 11.873 97 36 72 0 1181971520 71003344 7266736
09:43:48 57435 8192000000 10112310000 10553950208 1128287424 12.105 99 26 52 0 1168021856 33704448 6029984
09:45:46 78722 8192000000 10067323280 10534416384 1088959408 11.733 96 19 38 2 1177026528 83949824 4117296
09:48:09 9459 8192000000 10081304384 10504228864 1111196288 11.944 99 55 108 0 1174755776 53024400 10535088
09:49:19 40392 8192000000 10079553152 10504704000 1100517648 11.843 98 47 94 2 1148040768 37545440 9977680
09:51:03 12103 8192000000 10094718656 10512941056 1117683392 12.005 100 59 118 5 1191495664 62288560 11523712
09:52:17 11337 8192000000 10138415264 10569125888 1127331536 12.096 100 54 108 1 1230028848 91795456 10901856
09:53:29 64082 8192000000 10113561616 10557820928 1140188320 12.217 100 58 116 3 1225417120 72552496 12676304
09:55:40 75085 8192000000 10099970288 10554404864 1126883984 12.092 99 43 86 2 1195071776 59175200 9012592
09:57:56 62829 8192000000 10062187952 10513174528 1130023312 12.122 100 9 18 1 1178931168 46494000 2413856
10:00:05 79025 8192000000 10067366992 10522963968 1126496384 12.088 99 13 26 0 1170806496 41691296 2618816
10:02:24 70821 8192000000 10114104704 10597646336 1132001712 12.140 99 23 45 1 1216712208 79563488 5147008
10:04:40 43579 8192000000 10083716448 10583949312 1111001136 11.942 98 24 47 1 1175894272 59082384 5810752
10:06:36 43902 8192000000 10073147760 10517766144 1064479392 11.499 93 29 56 1 1135303632 65538016 5286224
10:08:26 16712 8192000000 10168944528 10594246656 1128298672 12.105 100 48 96 2 1251338256 113056496 9983088
10:09:44 77120 8192000000 10151253264 10611707904 1117958480 12.008 98 33 66 0 1264626224 139766912 6900832
10:12:20 51004 8192000000 10055152272 10506493952 1097974320 11.818 97 17 34 1 1140710864 39615376 3121168
10:14:18 87378 8192000000 10078261856 10573975552 1128881424 12.111 99 12 24 0 1196448432 65305232 2261776
10:17:17 14353 8192000000 10062276928 10523258880 1141037504 12.225 100 39 73 1 1174607648 26272464 7297680
10:18:36 25262 8192000000 10119385568 10562662400 1146446912 12.276 100 40 80 0 1209649552 54539584 8663056
10:20:02 91314 8192000000 10113820288 10562953216 1115090416 11.981 97 9 18 0 1179023488 61761696 2171376
10:22:39 64411 8192000000 10157921168 10602172416 1130406928 12.125 98 28 57 0 1247723424 110811328 6505168
10:24:50 34679 8192000000 10189935376 10656751616 1151187984 12.321 100 31 60 1 1290801936 132516096 7097856
10:26:35 38167 8192000000 10193917920 10634330112 1136517808 12.183 99 40 77 0 1261628544 115984864 9125872
10:28:14 34005 8192000000 10180074128 10645520384 1133793360 12.157 99 29 58 2 1280166464 139341216 7031888
10:29:56 44494 8192000000 10229193760 10664255488 1132941568 12.149 98 36 70 1 1306882288 165745584 8195136
10:31:47 49384 8192000000 10163562640 10652880896 1124581360 12.070 99 16 32 2 1288527152 160093360 3852432
10:33:56 60736 8192000000 10112317040 10604429312 1106282032 11.897 98 21 43 1 1208338480 97493872 4562576
10:35:57 72764 8192000000 9903230368 10309697536 938797424 10.281 83 19 39 2 988280400 45289760 4193216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2022 10:39:32 to 06-01-2022 10:39:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2022 10:39:49 to 06-01-2022 10:39:51.

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          208068
MON$OLDEST_ACTIVE               209111
MON$OLDEST_SNAPSHOT             209111
MON$NEXT_TRANSACTION            209111
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                       2062624
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             755
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Thu Jan  6 10:37:24 2022
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
--- end of diff output ---

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

06.01.22 10:39:52. 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
2022-01-06 10:39:54 No severe PSQL-related problems occured
06.01.22 10:39:56. End of report.