Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.2021 07:48
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 16504404 kB
MemAvailable 31486520 kB
Buffers 0 kB
Cached 15141148 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.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-02 07:48:13
DTS_END                         2021-12-02 07:48:13.3350
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/39968
TRN_ID                          239688
ATT_ID                          710
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: 9240.73 24774 1108887
customer order: creation 175.93 432 21112
customer order: refuse 14.47 320 1736
order to supplier: creation 46.59 1359 5591
order to supplier: removal 3.43 125025 412
invoice (draft): creation 47.53 3277 5704
invoice (draft): removal 53.28 1135 6394
invoice accept: apply 42.12 20128 5054
invoice accept: cancel 47.86 909 5743
customer reserve: creation 3788.52 204 454622
customer reserve: removal 4722.86 69 566743
realization accept: apply 87.10 182 10452
realization accept: cancel 81.22 125 9746
payment from customer: creation 59.92 118 7190
payment from customer: removal 5.75 68 690
payment to supplier: creation 49.48 139 5938
payment to supplier: removal 3.57 97 428
service: total inventory turnovers 3.64 3787 437
service: total monetary turnovers 6.49 519 779
service: aggregate perf. data 0.93 16219 112
service: refresh index statistics 0.03 321371 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2021 07:48:54 to 02-12-2021 07:48:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4119
TEST_TIME 2 4616
TEST_TIME 3 5482
TEST_TIME 4 4970
TEST_TIME 5 5278
TEST_TIME 6 5649
TEST_TIME 7 5602
TEST_TIME 8 5695
TEST_TIME 9 5792
TEST_TIME 10 6043
TEST_TIME 11 6172
TEST_TIME 12 6322
TEST_TIME 13 6664
TEST_TIME 14 6945
TEST_TIME 15 7001
TEST_TIME 16 7043
TEST_TIME 17 7225
TEST_TIME 18 7257
TEST_TIME 19 7259
TEST_TIME 20 7318
TEST_TIME 21 7365
TEST_TIME 22 8619
TEST_TIME 23 8970
TEST_TIME 24 9164
TEST_TIME 25 9196
TEST_TIME 26 10556
TEST_TIME 27 11725
TEST_TIME 28 11750
TEST_TIME 29 11429
TEST_TIME 30 11244
TEST_TIME 31 11370
TEST_TIME 32 11040
TEST_TIME 33 10875
TEST_TIME 34 10721
TEST_TIME 35 10681
TEST_TIME 36 10597
TEST_TIME 37 10502
TEST_TIME 38 10498
TEST_TIME 39 10464
TEST_TIME 40 10444
TEST_TIME 41 10322
TEST_TIME 42 10247
TEST_TIME 43 10222
TEST_TIME 44 10057
TEST_TIME 45 10022
TEST_TIME 46 10225
TEST_TIME 47 10175
TEST_TIME 48 10159
TEST_TIME 49 10143
TEST_TIME 50 10118
TEST_TIME 51 10060
TEST_TIME 52 10025
TEST_TIME 53 10085
TEST_TIME 54 10183
TEST_TIME 55 10185
TEST_TIME 56 10113
TEST_TIME 57 10144
TEST_TIME 58 10159
TEST_TIME 59 10018
TEST_TIME 60 10012
TEST_TIME 61 9966
TEST_TIME 62 9920
TEST_TIME 63 9885
TEST_TIME 64 9795
TEST_TIME 65 9787
TEST_TIME 66 9758
TEST_TIME 67 9734
TEST_TIME 68 9692
TEST_TIME 69 9672
TEST_TIME 70 9925
TEST_TIME 71 9952
TEST_TIME 72 9889
TEST_TIME 73 9869
TEST_TIME 74 9856
TEST_TIME 75 9819
TEST_TIME 76 9830
TEST_TIME 77 9961
TEST_TIME 78 9851
TEST_TIME 79 9769
TEST_TIME 80 9692
TEST_TIME 81 9694
TEST_TIME 82 9595
TEST_TIME 83 9588
TEST_TIME 84 9564
TEST_TIME 85 9552
TEST_TIME 86 9532
TEST_TIME 87 9503
TEST_TIME 88 9468
TEST_TIME 89 9460
TEST_TIME 90 9456
TEST_TIME 91 9473
TEST_TIME 92 9441
TEST_TIME 93 9500
TEST_TIME 94 9557
TEST_TIME 95 9535
TEST_TIME 96 9549
TEST_TIME 97 9577
TEST_TIME 98 9596
TEST_TIME 99 9584
TEST_TIME 100 9543
TEST_TIME 101 9589
TEST_TIME 102 9517
TEST_TIME 103 9442
TEST_TIME 104 9427
TEST_TIME 105 9403
TEST_TIME 106 9346
TEST_TIME 107 9351
TEST_TIME 108 9298
TEST_TIME 109 9269
TEST_TIME 110 9250
TEST_TIME 111 9262
TEST_TIME 112 9238
TEST_TIME 113 9209
TEST_TIME 114 9237
TEST_TIME 115 9279
TEST_TIME 116 9274
TEST_TIME 117 9249
TEST_TIME 118 9313
TEST_TIME 119 9311
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-12-2021 07:48:57 to 02-12-2021 07:49:01.

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 1531337 1531337 0 0.00 0 28418 8 0 0
doc_list_biud 1531337 1531337 0 0.00 0 71852 33 0 0
sp_add_invoice_to_stock 5133 5054 79 1.53 27 197428 20128 0 79
sp_cancel_adding_invoice 5743 5743 0 0.00 6 72882 909 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 21255 320 0 0
sp_cancel_customer_reserve 566743 566743 0 0.00 1 66054 70 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 1597 69 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 14549 98 0 0
sp_cancel_supplier_invoice 6399 6394 5 0.07 2 71920 1136 0 5
sp_cancel_supplier_order 412 412 0 0.00 7 1392104 125026 0 0
sp_cancel_write_off 9746 9746 0 0.00 1 18490 126 0 0
sp_client_order 21112 21112 0 0.00 4 68051 433 0 0
sp_customer_reserve 454622 454622 0 0.00 0 134049 205 0 0
sp_fill_shopping_cart 32389 32389 0 0.00 1 10632 42 0 0
sp_fill_shopping_cart_clo_res 454619 454619 0 0.00 0 19767 16 0 0
sp_get_clo_for_invoice 5051 5051 0 0.00 0 34258 382 0 0
sp_kill_cost_storno 16607 16607 0 0.00 0 18488 61 0 0
sp_kill_qstorno_ret_qs2qd 1150038 1150038 0 0.00 0 71849 38 0 0
sp_kill_qty_storno 606275 606275 0 0.00 0 71852 82 0 0
sp_lock_selected_doc 21301 21301 0 0.00 0 9275 5 0 0
sp_make_cost_storno 28629 28629 0 0.00 0 20553 58 0 0
sp_multiply_rows_for_pdistr 28629 28629 0 0.00 0 12701 33 0 0
sp_multiply_rows_for_qdistr 21112 21112 0 0.00 0 68035 234 0 0
sp_pay_from_customer 7190 7190 0 0.00 4 15399 118 0 0
sp_pay_to_supplier 5938 5938 0 0.00 5 18606 140 0 0
sp_payment_common 13128 13128 0 0.00 1 12330 23 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 21248 299 0 0
sp_qd_handle_on_invoice_upd_sts 10792 10792 0 0.00 0 26805 374 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 25196 56 0 0
sp_reserve_write_off 10452 10452 0 0.00 7 25229 183 0 0
sp_supplier_invoice 5704 5704 0 0.00 7 70073 3277 0 0
sp_supplier_order 5591 5591 0 0.00 6 56377 1359 0 0
srv_aggregate_perf_data 112 112 0 0.00 2462 162023 16219 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1247 164115 47297 0 0
srv_find_qd_qs_mism 1061693 1061693 0 0.00 0 28417 7 0 0
srv_make_invnt_saldo 437 437 0 0.00 46 90935 3788 0 0
srv_make_money_saldo 779 779 0 0.00 1 11225 519 0 0
srv_recalc_idx_stat 4 4 0 0.00 202811 422520 321371 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5054 5054 0 0.00 0 16584 164 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 5915 103 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 2132 12 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 3645 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 662 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1217 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 2183 21 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 23525 1595 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2197 7 0 0
t$perf-norm:sp_client_order 21112 21112 0 0.00 0 13852 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 593 4 0 0
t$perf-norm:sp_pay_from_customer 7190 7190 0 0.00 0 3641 7 0 0
t$perf-norm:sp_pay_to_supplier 5938 5938 0 0.00 0 6804 10 0 0
t$perf-norm:sp_reserve_write_off 10452 10452 0 0.00 0 25648 15 0 0
t$perf-norm:sp_supplier_invoice 5704 5704 0 0.00 0 6635 21 0 0
t$perf-norm:sp_supplier_order 5591 5591 0 0.00 0 3782 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 168 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 299 7 0 0
t$perf-norm:srv_make_invnt_saldo 437 437 0 0.00 0 705 5 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 495 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10256 10098 158 1.54 0 4930 15 0 158
v_all_customers 38452 38452 0 0.00 0 121 1 0 0
v_all_suppliers 31138 31138 0 0.00 0 16 1 0 0
v_all_wares 42212 42212 0 0.00 0 221 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 325 6 0 0
v_cancel_client_order 3472 3472 0 0.00 0 1507 3 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1569 7 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 1569 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 1569 6 0 0
v_cancel_supplier_invoice 756 746 10 1.32 0 1999 16 0 10
v_cancel_supplier_order 824 824 0 0.00 0 946 6 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4624 16 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 4198 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 2655 10 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 5119 5 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 312 2 0 0
v_max_non_paid_realizn 3853 3853 0 0.00 0 747 2 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 3626 5 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 3642 17 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 8952 14 0 0
v_min_non_paid_invoice 5938 5938 0 0.00 0 12208 15 0 0
v_min_non_paid_realizn 7190 7190 0 0.00 0 12325 18 0 0
v_our_firm 10434 10434 0 0.00 0 18 1 0 0
v_reserve_write_off 20904 20904 0 0.00 0 3380 6 0 0
x_lock_depdocs_on_canc_invoice 11764 11764 0 0.00 0 16383 120 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 40661 405 0 0
x_make_qstorno_1000_1200 5591 5591 0 0.00 2 56363 1273 0 0
x_make_qstorno_1200_2000 5704 5704 0 0.00 3 69869 3126 0 0
x_make_qstorno_2100_3300 454622 454622 0 0.00 0 134048 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2021 07:49:02 to 02-12-2021 07:49:06.

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:18:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:17 36202 8192000000 9737483984 10037489664 811840816 9.016 100 84 168 0 839447664 7579584 20027264
05:21:00 20404 8192000000 9778111024 10086289408 851173120 9.412 100 94 188 0 887407392 14208560 22025712
05:22:26 17997 8192000000 9817979360 10139287552 876975120 9.670 100 80 158 1 925226448 30225168 18026160
05:23:46 11233 8192000000 9879033648 10213376000 930908704 10.204 100 82 164 1 987266160 38806624 17550832
05:25:00 7862 8192000000 9934442704 10281197568 966531328 10.553 100 83 162 1 1041120992 57632336 16957328
05:26:10 5339 8192000000 9985219904 10339475456 1002607440 10.904 100 90 180 1 1095936896 74904416 18425040
05:27:17 3194 8192000000 9999338064 10365128704 1025236880 11.123 100 78 156 0 1117607312 76739856 15630576
05:28:22 1313 8192000000 10028393456 10395832320 1047832464 11.340 100 91 182 0 1143952800 77368048 18752288
05:29:24 1693 8192000000 10038816496 10410663936 1064400896 11.499 100 95 190 0 1154452464 70162544 19889024
05:30:26 15419 8192000000 9980754960 10376183808 1067459248 11.528 100 47 92 3 1107855280 30577664 9818368
05:31:42 5939 8192000000 10006257472 10403934208 1079889552 11.646 100 47 93 2 1129580256 39739520 9951184
05:32:50 7428 8192000000 9992519344 10396672000 1085915104 11.704 100 42 84 1 1110311792 15823520 8573168
05:33:58 9209 8192000000 10008408144 10417815552 1094198928 11.783 100 27 54 0 1123395600 23727968 5468704
05:35:09 50118 8192000000 10004015248 10411565056 1098310432 11.822 100 42 84 2 1128618384 19776176 10531776
05:37:01 7554 8192000000 10026525776 10426765312 1106221856 11.897 100 47 94 2 1142128336 26090544 9815936
05:38:12 5568 8192000000 10016049968 10429149184 1091848400 11.760 99 27 54 0 1135248624 37606352 5793872
05:39:18 4794 8192000000 9966345904 10358910976 1041199824 11.276 97 35 70 1 1060236672 10892032 8144816
05:40:24 53256 8192000000 9940445872 10335682560 955821680 10.448 90 31 60 1 974903712 12672560 6409472
05:42:18 4154 8192000000 9953341664 10341347328 1012382944 10.998 97 63 125 1 1053341008 27820304 13137760
05:43:25 8813 8192000000 9927710800 10309894144 977807088 10.663 95 35 69 1 1005314576 19846176 7661312
05:44:35 9413 8192000000 9963175952 10357985280 1051012544 11.370 100 38 74 1 1080842752 20389664 9440544
05:45:45 40062 8192000000 9982403520 10390392832 1058127104 11.439 100 28 56 0 1082422480 18054336 6241040
05:47:26 8815 8192000000 9985974688 10377420800 1066060992 11.514 100 45 89 0 1089108368 13254576 9792800
05:48:42 18131 8192000000 10013338944 10402922496 1090529168 11.748 100 36 69 0 1114911296 17392912 6989216
05:50:03 21621 8192000000 10070982528 10470899712 1108250848 11.916 100 55 109 0 1178707440 59396752 11059840
05:51:40 7617 8192000000 10106380080 10518700032 1125934816 12.083 100 53 106 1 1216101648 78121888 12044944
05:52:49 21103 8192000000 10124603936 10532241408 1129904240 12.120 100 57 114 2 1238599088 96602400 12092448
05:54:17 27726 8192000000 10159799280 10579509248 1138694128 12.203 100 50 100 0 1266998416 117838368 10465920
05:55:52 17212 8192000000 10138387920 10562826240 1149195280 12.302 100 34 67 0 1257483216 100904864 7383072
05:57:23 4151 8192000000 10214953984 10623361024 1160619280 12.409 100 66 131 1 1320771744 145798656 14353808
05:58:32 1951 8192000000 10266840544 10673909760 1172659552 12.522 100 70 140 0 1367600240 179685296 15255392
05:59:40 1681 8192000000 10302863104 10706190336 1182548528 12.614 100 86 171 1 1404387248 202842656 18996064
06:00:45 4815 8192000000 10342953568 10749296640 1181300528 12.602 100 72 142 1 1445191792 248589072 15302192
06:01:55 2546 8192000000 10364598736 10774458368 1186803008 12.654 100 83 165 0 1465045360 260041600 18200752
06:03:01 2809 8192000000 10402332208 10813026304 1184630272 12.633 100 78 156 0 1503230992 300213760 18386960
06:04:09 2561 8192000000 10433700384 10835968000 1185425440 12.641 100 82 163 1 1530708976 326354032 18929504
06:05:17 1244 8192000000 10498060288 10890407936 1173324128 12.528 100 92 181 1 1589100240 394629552 21146560
06:06:21 2039 8192000000 10557527952 10953035776 1183413040 12.622 100 92 181 1 1647745696 442948544 21384112
06:07:28 1741 8192000000 10616706064 11025911808 1182699728 12.615 100 74 146 1 1715701328 515233760 17767840
06:08:39 6244 8192000000 10652493392 11072073728 1165133984 12.451 100 58 116 0 1756130496 575636896 15359616
06:09:50 2213 8192000000 10681550128 11096469504 1158715888 12.391 100 89 175 0 1778116800 596664192 22736720
06:10:56 10618 8192000000 10539852256 10996187136 1176977728 12.562 100 50 96 0 1653422080 463400752 13043600
06:12:13 14629 8192000000 10502796832 10989481984 1150737040 12.316 99 35 70 0 1602239808 442015296 9487472
06:13:32 22200 8192000000 10510178160 10968838144 1129049424 12.112 100 22 44 0 1622665536 486670400 6945712
06:15:15 55123 8192000000 10102595104 10597920768 1100994160 11.847 97 16 32 0 1183069888 79062848 3012880
06:17:14 96331 8192000000 10097189072 10541547520 1109086768 11.924 98 41 82 1 1157072304 38885168 9100368
06:19:52 93181 8192000000 10077688944 10509737984 1120055744 12.028 98 30 58 2 1151837600 23804496 7977360
06:22:27 60997 8192000000 10044520896 10462412800 1093223952 11.773 96 18 34 2 1121963472 25800400 2939120
06:24:35 29552 8192000000 10096860096 10529644544 1132090256 12.141 99 22 44 1 1192828880 55762816 4975808
06:26:13 29353 8192000000 10131522464 10588688384 1118859920 12.016 99 31 62 0 1228670112 103250016 6560176
06:27:43 159180 8192000000 10129789248 10578141184 1122470352 12.050 99 13 26 1 1200233664 75046608 2716704
06:31:34 52074 8192000000 10129293424 10576068608 1144275856 12.256 100 15 30 0 1232958352 85587472 3095024
06:33:39 60340 8192000000 10110327712 10633666560 1144085328 12.254 100 20 39 1 1243509056 94720496 4703232
06:36:08 16759 8192000000 10069134720 10488446976 1121655616 12.043 99 54 108 0 1169849712 37043648 11150448
06:37:29 60976 8192000000 10055462096 10519973888 1121308688 12.039 98 13 26 0 1160354464 36373280 2672496
06:39:37 61335 8192000000 10096056128 10543779840 1108005152 11.914 97 16 32 0 1168145248 57014240 3125856
06:41:38 38638 8192000000 10095490304 10527064064 1116766128 11.996 99 31 62 0 1162678448 39267104 6645216
06:43:21 68402 8192000000 10042814032 10477703168 1053054688 11.390 94 18 35 0 1098707312 42023360 3629264
06:45:30 17514 8192000000 10077515520 10513887232 1111455008 11.946 100 43 86 0 1175510704 55802192 8253504
06:46:55 125871 8192000000 10082865360 10554429440 1084703616 11.692 97 35 70 0 1158002496 65554320 7744560
06:50:36 73467 8192000000 10093156016 10565410816 1124310448 12.068 100 11 22 0 1205989824 79594208 2085168
06:52:54 38673 8192000000 10047691168 10513383424 1084594368 11.691 96 6 12 1 1126096400 40455472 1046560
06:54:33 61611 8192000000 10113429616 10553069568 1080735248 11.654 95 19 38 1 1158876224 73905472 4235504
06:56:39 86065 8192000000 10159430448 10608926720 1084872240 11.694 94 31 63 1 1226664736 134591104 7201392
06:59:13 66052 8192000000 10056823120 10492084224 1106183120 11.896 99 19 38 1 1153226816 43553280 3490416
07:01:20 30495 8192000000 10087665664 10521030656 1127147920 12.094 100 25 50 1 1168592400 36477024 4967456
07:02:58 27759 8192000000 10083113728 10507431936 1105267216 11.888 98 40 80 0 1159372160 46535088 7569856
07:04:27 16336 8192000000 10145278272 10578153472 1122170336 12.047 99 52 104 0 1224732192 92203888 10357968
07:05:51 164115 8192000000 10076669424 10534854656 1120289168 12.030 99 18 36 0 1164979872 40691728 3998976
07:09:52 59095 8192000000 10089909792 10549579776 1112125552 11.953 99 10 20 0 1192533824 78191232 2217040
07:12:04 81056 8192000000 10064080240 10546429952 1111399840 11.946 99 16 31 1 1177093600 62045760 3648000
07:14:54 74314 8192000000 10066811824 10529038336 1097023136 11.809 98 14 28 0 1135640032 35855536 2761360
07:17:11 87088 8192000000 10061055360 10541645824 1070858352 11.560 95 15 30 0 1130575040 56586528 3130160
07:19:39 70573 8192000000 10084412848 10532941824 1111657904 11.948 98 17 34 0 1162398752 47494848 3246000
07:21:55 66540 8192000000 10053551136 10541527040 1072692800 11.578 94 8 16 0 1120027984 45920224 1414960
07:24:02 26529 8192000000 10110416944 10537222144 1109258192 11.925 98 51 102 0 1176729440 57081760 10389488
07:25:36 65286 8192000000 10089573008 10581725184 1124226224 12.067 99 23 46 0 1184852000 55774368 4851408
07:27:41 40713 8192000000 10079977088 10520408064 1117438800 12.003 98 34 68 2 1138539408 13598688 7501920
07:29:30 145844 8192000000 10057847008 10489995264 1084044176 11.686 96 29 58 0 1130948208 41297936 5606096
07:33:06 65505 8192000000 10049745952 10510581760 1098291328 11.821 97 10 20 0 1147535840 47018896 2225616
07:35:15 48406 8192000000 10034647696 10504376320 1095607680 11.796 97 12 24 0 1139836432 41551632 2677120
07:37:03 112474 8192000000 10083429296 10566017024 1125349728 12.078 99 13 26 0 1171342464 43260784 2731952
07:39:56 57072 8192000000 10103405648 10552991744 1115986768 11.989 97 19 38 0 1160001536 39705296 4309472
07:41:58 87303 8192000000 10088213632 10567213056 1092276496 11.764 95 26 51 3 1206031136 107891008 5863632
07:44:37 48112 8192000000 10138756352 10580062208 1151778304 12.326 100 28 55 1 1214425264 56769520 5877440
07:46:29 58629 8192000000 10056660608 10512621568 1067961408 11.533 94 15 30 0 1095206816 24387184 2858224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-12-2021 07:49:07 to 02-12-2021 07:49:22.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-12-2021 07:49:23 to 02-12-2021 07:49:25.

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          237312
MON$OLDEST_ACTIVE               239811
MON$OLDEST_SNAPSHOT             239811
MON$NEXT_TRANSACTION            239811
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                       2058208
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2673 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2673 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2673 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2673 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Dec  2 07:48:14 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Dec  2 07:48:15 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Dec  2 07:48:16 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Dec  2 07:48:22 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu Dec  2 07:48:22 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

02.12.21 07:49:26. 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-02 07:49:28 No severe PSQL-related problems occured
02.12.21 07:49:30. End of report.