Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.06.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 16326052 kB
MemAvailable 31376740 kB
Buffers 0 kB
Cached 15410204 kB
SwapCached 2444 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.2
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-06-02 10:37:16
DTS_END                         2022-06-02 10:37:16.2610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48192
TRN_ID                          207353
ATT_ID                          642
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: 8668.79 22495 1040255
customer order: creation 158.13 509 18976
customer order: refuse 13.84 497 1661
order to supplier: creation 41.15 1446 4938
order to supplier: removal 3.39 183475 407
invoice (draft): creation 41.38 3354 4966
invoice (draft): removal 49.63 1134 5955
invoice accept: apply 39.08 26711 4690
invoice accept: cancel 44.87 1242 5384
customer reserve: creation 3601.53 255 432183
customer reserve: removal 4407.32 111 528878
realization accept: apply 77.58 316 9309
realization accept: cancel 72.72 194 8727
payment from customer: creation 53.73 172 6447
payment from customer: removal 5.13 141 615
payment to supplier: creation 44.79 176 5375
payment to supplier: removal 3.20 108 384
service: total inventory turnovers 3.66 4381 439
service: total monetary turnovers 6.73 401 807
service: aggregate perf. data 0.91 17674 109
service: refresh index statistics 0.04 207602 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-06-2022 10:38:47 to 02-06-2022 10:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9546
TEST_TIME 2 6714
TEST_TIME 3 6348
TEST_TIME 4 5752
TEST_TIME 5 5657
TEST_TIME 6 5574
TEST_TIME 7 5556
TEST_TIME 8 5687
TEST_TIME 9 5816
TEST_TIME 10 5805
TEST_TIME 11 6011
TEST_TIME 12 6410
TEST_TIME 13 6381
TEST_TIME 14 6601
TEST_TIME 15 6733
TEST_TIME 16 6969
TEST_TIME 17 6895
TEST_TIME 18 6953
TEST_TIME 19 7030
TEST_TIME 20 7208
TEST_TIME 21 7309
TEST_TIME 22 7510
TEST_TIME 23 7677
TEST_TIME 24 7857
TEST_TIME 25 8030
TEST_TIME 26 8037
TEST_TIME 27 8272
TEST_TIME 28 8209
TEST_TIME 29 8596
TEST_TIME 30 8719
TEST_TIME 31 9005
TEST_TIME 32 9292
TEST_TIME 33 9652
TEST_TIME 34 10286
TEST_TIME 35 10594
TEST_TIME 36 10442
TEST_TIME 37 10341
TEST_TIME 38 10110
TEST_TIME 39 9856
TEST_TIME 40 9925
TEST_TIME 41 9737
TEST_TIME 42 9648
TEST_TIME 43 9584
TEST_TIME 44 9779
TEST_TIME 45 9734
TEST_TIME 46 9598
TEST_TIME 47 9510
TEST_TIME 48 9459
TEST_TIME 49 9338
TEST_TIME 50 9257
TEST_TIME 51 9191
TEST_TIME 52 9114
TEST_TIME 53 9037
TEST_TIME 54 9030
TEST_TIME 55 9035
TEST_TIME 56 9072
TEST_TIME 57 9051
TEST_TIME 58 9049
TEST_TIME 59 9008
TEST_TIME 60 9100
TEST_TIME 61 9107
TEST_TIME 62 9097
TEST_TIME 63 9160
TEST_TIME 64 9174
TEST_TIME 65 9149
TEST_TIME 66 9176
TEST_TIME 67 9164
TEST_TIME 68 9085
TEST_TIME 69 9002
TEST_TIME 70 8959
TEST_TIME 71 8957
TEST_TIME 72 8871
TEST_TIME 73 8872
TEST_TIME 74 8827
TEST_TIME 75 8801
TEST_TIME 76 8795
TEST_TIME 77 8782
TEST_TIME 78 8799
TEST_TIME 79 8821
TEST_TIME 80 8786
TEST_TIME 81 8794
TEST_TIME 82 8826
TEST_TIME 83 8853
TEST_TIME 84 8965
TEST_TIME 85 8938
TEST_TIME 86 8979
TEST_TIME 87 8919
TEST_TIME 88 8905
TEST_TIME 89 8952
TEST_TIME 90 8935
TEST_TIME 91 8863
TEST_TIME 92 8822
TEST_TIME 93 8790
TEST_TIME 94 8803
TEST_TIME 95 8776
TEST_TIME 96 8766
TEST_TIME 97 8740
TEST_TIME 98 8738
TEST_TIME 99 8741
TEST_TIME 100 8710
TEST_TIME 101 8692
TEST_TIME 102 8802
TEST_TIME 103 8855
TEST_TIME 104 8876
TEST_TIME 105 8957
TEST_TIME 106 8990
TEST_TIME 107 9019
TEST_TIME 108 8952
TEST_TIME 109 8933
TEST_TIME 110 8889
TEST_TIME 111 8899
TEST_TIME 112 8857
TEST_TIME 113 8818
TEST_TIME 114 8858
TEST_TIME 115 8808
TEST_TIME 116 8781
TEST_TIME 117 8807
TEST_TIME 118 8782
TEST_TIME 119 8761
TEST_TIME 120 8701

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-06-2022 10:38:50 to 02-06-2022 10:38:54.

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 1438198 1438198 0 0.00 0 14437 13 0 0
doc_list_biud 1438176 1438176 0 0.00 0 60528 48 0 0
sp_add_invoice_to_stock 4834 4690 144 2.97 63 253701 26712 0 144
sp_cancel_adding_invoice 5384 5384 0 0.00 7 67471 1243 0 0
sp_cancel_client_order 1661 1661 0 0.00 6 22085 497 0 0
sp_cancel_customer_reserve 528878 528878 0 0.00 1 27855 111 0 0
sp_cancel_pay_from_customer 615 615 0 0.00 4 8343 141 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 2948 109 0 0
sp_cancel_supplier_invoice 5971 5955 16 0.26 3 60570 1135 0 16
sp_cancel_supplier_order 407 407 0 0.00 19 1611554 183476 0 0
sp_cancel_write_off 8727 8727 0 0.00 2 10301 194 0 0
sp_client_order 18976 18976 0 0.00 6 32813 510 0 0
sp_customer_reserve 432183 432183 0 0.00 0 109836 255 0 0
sp_fill_shopping_cart 28870 28870 0 0.00 1 17930 85 0 0
sp_fill_shopping_cart_clo_res 432136 432136 0 0.00 0 17443 22 0 0
sp_get_clo_for_invoice 4636 4636 0 0.00 0 43562 364 0 0
sp_kill_cost_storno 15110 15110 0 0.00 0 11661 85 0 0
sp_kill_qstorno_ret_qs2qd 1072845 1072845 0 0.00 0 60519 49 0 0
sp_kill_qty_storno 564951 564951 0 0.00 0 60525 113 0 0
sp_lock_selected_doc 19259 19259 0 0.00 0 7200 6 0 0
sp_make_cost_storno 25766 25766 0 0.00 0 14418 75 0 0
sp_multiply_rows_for_pdistr 25760 25760 0 0.00 0 13981 40 0 0
sp_multiply_rows_for_qdistr 18976 18976 0 0.00 0 30143 226 0 0
sp_pay_from_customer 6447 6447 0 0.00 6 20888 172 0 0
sp_pay_to_supplier 5375 5375 0 0.00 7 10001 177 0 0
sp_payment_common 11821 11821 0 0.00 2 5493 40 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 1 22028 433 0 0
sp_qd_handle_on_invoice_upd_sts 10017 10017 0 0.00 0 38100 569 0 0
sp_qd_handle_on_reserve_upd_sts 9306 9306 0 0.00 0 25494 105 0 0
sp_reserve_write_off 9309 9309 0 0.00 9 25832 316 0 0
sp_supplier_invoice 4966 4966 0 0.00 8 148974 3355 0 0
sp_supplier_order 4938 4938 0 0.00 8 27791 1446 0 0
srv_aggregate_perf_data 109 109 0 0.00 2452 154352 17674 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 869 133843 34261 0 0
srv_find_qd_qs_mism 995176 995176 0 0.00 0 14181 10 0 0
srv_make_invnt_saldo 439 439 0 0.00 66 81755 4382 0 0
srv_make_money_saldo 807 807 0 0.00 1 17676 401 0 0
srv_recalc_idx_stat 5 5 0 0.00 93243 370476 207602 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4696 4696 0 0.00 1 13565 976 0 0
t$perf-norm:sp_cancel_adding_invoice 347 347 0 0.00 1 7506 531 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 1585 27 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1242 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 615 615 0 0.00 0 1193 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 4549 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 2964 55 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 111550 7356 0 0
t$perf-norm:sp_cancel_write_off 907 907 0 0.00 0 1602 25 0 0
t$perf-norm:sp_client_order 18976 18976 0 0.00 0 11452 36 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 974 14 0 0
t$perf-norm:sp_pay_from_customer 6447 6447 0 0.00 0 6455 17 0 0
t$perf-norm:sp_pay_to_supplier 5375 5375 0 0.00 0 12161 21 0 0
t$perf-norm:sp_reserve_write_off 9310 9310 0 0.00 0 2846 21 0 0
t$perf-norm:sp_supplier_invoice 4967 4967 0 0.00 0 9649 32 0 0
t$perf-norm:sp_supplier_order 4938 4938 0 0.00 0 2905 33 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1459 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 442 14 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 2178 20 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 522 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 165 56 0 0
v_add_invoice_to_stock 9554 9266 288 3.01 0 4684 25 0 288
v_all_customers 35266 35266 0 0.00 0 196 4 0 0
v_all_suppliers 27668 27668 0 0.00 0 196 4 0 0
v_all_wares 37946 37946 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 694 694 0 0.00 0 175 11 0 0
v_cancel_client_order 3322 3322 0 0.00 0 2063 9 0 0
v_cancel_customer_prepayment 1230 1230 0 0.00 0 8300 19 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 1297 10 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 487 10 0 0
v_cancel_supplier_invoice 746 714 32 4.28 0 1172 26 0 32
v_cancel_supplier_order 814 814 0 0.00 0 717 12 0 0
v_cancel_write_off 1814 1814 0 0.00 0 397 20 0 0
v_max_id_clo_ord 4936 4936 0 0.00 0 591 5 0 0
v_max_id_clo_res 971 971 0 0.00 0 1871 12 0 0
v_max_id_ord_sup 4961 4961 0 0.00 0 2586 5 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 4551 10 0 0
v_max_non_paid_realizn 3153 3153 0 0.00 0 803 7 0 0
v_min_id_clo_ord 4936 4936 0 0.00 0 1243 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 7735 22 0 0
v_min_id_ord_sup 4961 4961 0 0.00 0 7108 10 0 0
v_min_non_paid_invoice 5374 5374 0 0.00 0 1094 9 0 0
v_min_non_paid_realizn 6447 6447 0 0.00 0 5488 14 0 0
v_our_firm 9266 9266 0 0.00 0 119 4 0 0
v_reserve_write_off 18608 18608 0 0.00 0 4426 10 0 0
x_lock_depdocs_on_canc_invoice 10982 10982 0 0.00 0 7025 113 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 8575 382 0 0
x_make_qstorno_1000_1200 4938 4938 0 0.00 2 27778 1267 0 0
x_make_qstorno_1200_2000 4966 4966 0 0.00 4 141810 3121 0 0
x_make_qstorno_2100_3300 432181 432181 0 0.00 0 104746 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 10:38:55 to 02-06-2022 10:38:59.

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:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 13673 8192000000 9721299728 10021052416 800064240 8.897 100 82 161 2 827174320 7417248 19692832
08:09:40 30293 8192000000 9774733152 10086416384 848716640 9.387 100 89 178 0 881560816 11948480 20895696
08:11:13 17559 8192000000 9812962112 10133684224 877194464 9.672 100 89 179 0 916318416 18607632 20516320
08:12:38 9070 8192000000 9858258880 10191749120 910770304 10.005 100 84 168 0 973163376 43697584 18695488
08:13:51 5629 8192000000 9913452208 10253611008 946453344 10.356 100 90 177 0 1027279200 61767744 19058112
08:14:59 3408 8192000000 9953499552 10304225280 988586304 10.768 100 85 168 0 1072806112 67223392 16996416
08:16:05 1864 8192000000 9985831328 10344460288 1013970160 11.014 100 96 187 1 1110977504 78014272 18993072
08:17:09 962 8192000000 10026963504 10393980928 1036481136 11.231 100 96 187 0 1145979184 90911536 18586512
08:18:12 1050 8192000000 10034485728 10405339136 1056454880 11.423 100 96 183 0 1150114096 75372992 18286224
08:19:16 987 8192000000 10040091104 10418806784 1064885040 11.503 100 89 171 0 1156778816 74962064 16931712
08:20:18 1938 8192000000 10051093744 10425864192 1071418672 11.566 100 97 179 0 1166163520 76233056 18511792
08:21:21 1283 8192000000 10063643040 10441961472 1080971088 11.657 100 100 192 3 1183510320 82547840 19991392
08:22:24 860 8192000000 10040244080 10431373312 1084698704 11.692 100 92 172 0 1165657056 63360016 17598336
08:23:25 1399 8192000000 10049308720 10436403200 1086802720 11.712 100 96 184 0 1164850160 59768448 18278992
08:24:29 1429 8192000000 10056185920 10445475840 1092804816 11.769 100 85 160 2 1171923776 63168640 15950320
08:25:31 954 8192000000 10065814080 10449997824 1092717728 11.768 100 95 181 0 1184054640 71789936 19546976
08:26:34 3938 8192000000 10044274416 10444890112 1092959120 11.771 100 71 133 1 1163723456 57090752 13673584
08:27:40 1580 8192000000 10035009728 10424872960 1092418672 11.766 100 81 159 0 1158055472 50722672 14914128
08:28:42 7095 8192000000 10047197808 10439864320 1090823088 11.750 100 67 132 1 1170803392 66608496 13371808
08:29:50 1314 8192000000 10027388560 10414772224 1074428864 11.594 100 86 164 0 1149606240 57814272 17363104
08:30:55 1037 8192000000 10037561712 10419527680 1059229232 11.449 98 94 180 0 1157960912 80435952 18295728
08:31:57 1424 8192000000 10023383600 10402615296 1058065584 11.438 99 97 188 3 1144980912 67251104 19664224
08:32:59 1358 8192000000 9967291280 10334392320 1029558544 11.164 99 91 168 1 1090769968 43039104 18172320
08:34:03 1151 8192000000 10018223056 10391818240 1039380032 11.259 99 96 183 1 1139885776 81925808 18579936
08:35:05 1115 8192000000 10000008992 10369339392 1040780016 11.272 100 93 180 0 1119065552 59540080 18745456
08:36:07 1241 8192000000 9966547920 10337951744 1020377600 11.076 99 94 170 2 1088447040 50224576 17844864
08:37:09 1043 8192000000 9994551664 10370093056 1043565728 11.299 100 85 162 3 1121378352 61872576 15940048
08:38:20 1849 8192000000 10011245456 10384478208 1047673568 11.338 100 97 189 1 1130073456 62539104 19860784
08:39:26 34981 8192000000 10050604896 10439409664 1072167952 11.573 100 58 108 0 1168626816 85567296 10891568
08:41:12 14707 8192000000 10068783552 10464342016 1092449328 11.766 100 56 107 1 1189810848 86296336 11065184
08:42:33 7775 8192000000 10114489008 10514669568 1107351712 11.907 100 69 128 0 1231130384 109409248 14369424
08:43:46 1583 8192000000 10151189824 10533347328 1110846512 11.940 100 91 177 0 1264306144 133802480 19657152
08:44:53 6595 8192000000 10202834048 10591109120 1123020944 12.056 100 83 157 2 1316440496 176377552 17042000
08:46:01 1172 8192000000 10220710816 10618183680 1143178096 12.245 100 94 184 1 1334717600 172694624 18844880
08:47:06 1199 8192000000 10245921776 10639572992 1149381376 12.304 100 96 185 1 1362117264 190391184 22344704
08:48:12 1326 8192000000 10319461616 10714480640 1164299488 12.444 100 96 191 2 1434174864 248828528 21046848
08:49:16 857 8192000000 10347881296 10759831552 1175176288 12.545 100 97 186 0 1457189088 261161616 20851184
08:50:21 883 8192000000 10336740640 10738790400 1167035856 12.469 99 95 185 2 1444598096 256862992 20699248
08:51:25 1119 8192000000 10377138800 10795118592 1179277280 12.583 100 94 188 3 1489396784 288585136 21534368
08:52:32 2066 8192000000 10383847712 10797092864 1176430880 12.557 99 93 182 0 1493350272 295299376 21620016
08:53:37 2805 8192000000 10395170112 10816786432 1189556176 12.679 100 82 162 1 1512706496 304736832 18413488
08:54:46 891 8192000000 10487120944 10896695296 1209260016 12.862 100 100 196 1 1588246832 357118432 21868384
08:55:53 881 8192000000 10501654048 10911305728 1203186608 12.806 100 93 182 1 1607590960 381072720 23331632
08:56:57 1245 8192000000 10511955232 10936569856 1204380592 12.817 100 98 191 0 1622600576 394638336 23581648
08:58:01 3842 8192000000 10504225120 10959527936 1212797616 12.895 100 93 171 0 1603180992 370330160 20053216
08:59:11 2017 8192000000 10556476144 10982928384 1219524400 12.957 100 92 182 0 1657397280 416830736 21042144
09:00:15 1216 8192000000 10558774000 11016003584 1223490640 12.994 100 88 175 0 1671400816 427332880 20577296
09:01:22 879 8192000000 10536630704 10972291072 1192907872 12.710 100 92 181 0 1643186880 427947824 22331184
09:02:29 2640 8192000000 10569398704 11043299328 1209012144 12.860 100 67 134 1 1674543712 448344624 17186944
09:03:38 9694 8192000000 10570116384 11017080832 1199984192 12.776 100 54 108 0 1673841952 459729824 14127936
09:04:56 10717 8192000000 10554722304 11001716736 1203260720 12.807 100 47 92 0 1659406544 443824608 12321216
09:06:20 18036 8192000000 10519358096 11025567744 1187838608 12.663 99 37 73 2 1634839280 436971536 10029136
09:07:57 20958 8192000000 10432161344 10895273984 1184746592 12.634 100 28 54 5 1551767664 360045968 6975104
09:09:19 22686 8192000000 10393280336 10879606784 1180236960 12.592 99 36 70 0 1493948000 305709472 8001568
09:10:56 34337 8192000000 10198726608 10676518912 1148551984 12.296 98 22 44 1 1342996960 188843536 5601440
09:12:42 14361 8192000000 10063744272 10490650624 1114533424 11.975 97 51 88 3 1141645040 17061968 10049648
09:14:00 20828 8192000000 10143134304 10568859648 1151550560 12.324 100 74 142 0 1236331040 69849216 14931264
09:15:23 93284 8192000000 10153297488 10586955776 1140538064 12.221 99 68 121 5 1245825424 89549072 15738288
09:17:58 54000 8192000000 10151372448 10593218560 1144787616 12.261 99 45 90 1 1235725232 81261232 9676384
09:19:53 42066 8192000000 10160362528 10635079680 1143736912 12.251 100 28 55 1 1272007216 121606288 6664016
09:23:09 40068 8192000000 10070866160 10520444928 1116534192 11.994 98 26 49 1 1146015776 23594944 5886640
09:25:18 49759 8192000000 10069576240 10521325568 1120636704 12.033 99 16 31 3 1163806384 39639584 3530096
09:27:08 57974 8192000000 10091673680 10572312576 1123610496 12.061 99 13 26 0 1201896432 74846144 3439792
09:29:14 27412 8192000000 10101719696 10585174016 1140510000 12.220 100 28 54 2 1201229232 55083584 5635648
09:30:42 30045 8192000000 10111478096 10569052160 1129059520 12.112 100 18 34 3 1218874368 86287920 3526928
09:32:41 82296 8192000000 10080188496 10571722752 1095163920 11.792 97 22 44 2 1167870464 67798336 4908208
09:35:04 32305 8192000000 10119445152 10557341696 1111117280 11.943 98 44 88 0 1172659328 52654064 8887984
09:36:40 74125 8192000000 10089460672 10588532736 1131318464 12.134 100 25 50 0 1220133424 83515968 5298992
09:38:58 3883 8192000000 10104044224 10535407616 1149764624 12.307 100 65 130 2 1192235856 29387296 13083936
09:40:04 32017 8192000000 10137122768 10587615232 1128628880 12.108 99 35 70 2 1226271504 90422432 7220192
09:41:38 92201 8192000000 10105959728 10552344576 1095089248 11.791 96 27 54 0 1144387552 44125664 5172640
09:44:12 24747 8192000000 10090662928 10514915328 1087560176 11.719 96 48 91 1 1143934816 47254592 9120048
09:45:38 84757 8192000000 10069788544 10490867712 1104647392 11.882 98 61 120 0 1176649120 59109504 12892224
09:48:06 41467 8192000000 10080879392 10552463360 1122488032 12.050 100 24 48 0 1202076752 74926368 4662352
09:49:48 46820 8192000000 10075146320 10546880512 1119316304 12.021 99 6 13 0 1192913104 72205360 1391440
09:51:35 41449 8192000000 10057319344 10503856128 1103966288 11.875 98 21 42 2 1136515280 27496192 5052800
09:53:22 47826 8192000000 10084117552 10533064704 1111938496 11.951 99 29 58 1 1196571376 77759776 6873104
09:55:12 37051 8192000000 10109019376 10567249920 1131508192 12.136 100 33 66 0 1230735232 91404560 7822480
09:56:50 20963 8192000000 10134370992 10590117888 1145435744 12.267 100 32 64 0 1220813296 68226224 7151328
09:58:16 55054 8192000000 10152324384 10621689856 1134758352 12.166 99 26 52 0 1247681648 106636912 6286384
10:00:15 65718 8192000000 10113527008 10574069760 1111008336 11.942 99 16 32 2 1196445568 80936064 4501168
10:02:22 23206 8192000000 10131898240 10562871296 1113367552 11.964 98 67 130 3 1187348416 60397792 13583072
10:03:45 101099 8192000000 10162803312 10649337856 1144503760 12.258 100 18 36 0 1270991392 122489552 3998080
10:06:49 83214 8192000000 10065440288 10571448320 1136796576 12.185 100 8 16 0 1203067456 64410224 1860656
10:10:50 38618 8192000000 10070079216 10529628160 1144510816 12.258 100 15 30 0 1180340016 32668512 3160688
10:12:42 28606 8192000000 10115902768 10555150336 1122004768 12.046 98 38 76 1 1174349888 44187664 8157456
10:14:11 16180 8192000000 10112750976 10545692672 1088492656 11.728 96 31 62 0 1173987392 78387856 7106880
10:15:30 50452 8192000000 10158270464 10593218560 1102932400 11.865 97 26 52 0 1215925056 106926448 6066208
10:17:21 19125 8192000000 10165347552 10596192256 1125455488 12.079 99 34 68 2 1259178880 126074000 7649392
10:18:42 29215 8192000000 10221117168 10688372736 1130783904 12.129 99 41 82 4 1299824352 160319264 8721184
10:20:12 52313 8192000000 10137817920 10622226432 1107432784 11.908 98 11 22 0 1214245888 104007264 2805840
10:22:10 101011 8192000000 10101520880 10613276672 1096658912 11.806 98 6 12 0 1155655424 57785632 1210880
10:25:13 133842 8192000000 10053431376 10497900544 1105742720 11.892 98 21 42 0 1174576368 63880912 4952736
10:28:55 130093 8192000000 10117073792 10571182080 1090114912 11.744 96 16 32 1 1177393920 83762480 3516528
10:32:11 48690 8192000000 10081317536 10542141440 1116836144 11.997 99 14 29 0 1184235040 63946688 3452208
10:34:00 75913 8192000000 10084167744 10538819584 1140177936 12.217 100 16 32 0 1183807392 40281792 3347664
10:36:32 44995 8192000000 9623338016 9986035712 681326432 7.678 59 26 48 2 752974512 66108528 5539552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          207616
MON$OLDEST_ACTIVE               207617
MON$OLDEST_SNAPSHOT             207617
MON$NEXT_TRANSACTION            207617
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                       2044256
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             753
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

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

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

02.06.22 10:39:21. 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-06-02 10:39:23 No severe PSQL-related problems occured
02.06.22 10:39:24. End of report.