Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.2021 07:49
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15802052 kB
MemAvailable 30893240 kB
Buffers 0 kB
Cached 15866540 kB
SwapCached 336 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-09-03 07:49:19
DTS_END                         2021-09-03 07:49:06.0790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47560
TRN_ID                          228785
ATT_ID                          259
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: 9290.31 25467 1114837
customer order: creation 162.81 390 19537
customer order: refuse 14.38 384 1726
order to supplier: creation 43.98 1460 5277
order to supplier: removal 3.41 113390 409
invoice (draft): creation 42.78 3707 5134
invoice (draft): removal 54.50 864 6540
invoice accept: apply 40.62 20671 4874
invoice accept: cancel 50.13 888 6016
customer reserve: creation 3696.42 205 443570
customer reserve: removal 4895.12 61 587414
realization accept: apply 78.67 245 9440
realization accept: cancel 84.30 121 10116
payment from customer: creation 56.49 153 6779
payment from customer: removal 5.77 103 692
payment to supplier: creation 46.97 155 5636
payment to supplier: removal 3.37 77 404
service: total inventory turnovers 3.50 4374 420
service: total monetary turnovers 6.18 578 741
service: aggregate perf. data 0.90 17475 108
service: refresh index statistics 0.03 344046 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 07:49:49 to 03-09-2021 07:49:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6551
TEST_TIME 2 5105
TEST_TIME 3 5611
TEST_TIME 4 5391
TEST_TIME 5 5171
TEST_TIME 6 5257
TEST_TIME 7 5461
TEST_TIME 8 6638
TEST_TIME 9 7200
TEST_TIME 10 7222
TEST_TIME 11 7874
TEST_TIME 12 7893
TEST_TIME 13 8221
TEST_TIME 14 8080
TEST_TIME 15 8137
TEST_TIME 16 8650
TEST_TIME 17 8781
TEST_TIME 18 8736
TEST_TIME 19 8816
TEST_TIME 20 9078
TEST_TIME 21 9015
TEST_TIME 22 9579
TEST_TIME 23 9477
TEST_TIME 24 9621
TEST_TIME 25 9945
TEST_TIME 26 9883
TEST_TIME 27 9956
TEST_TIME 28 10493
TEST_TIME 29 10628
TEST_TIME 30 11137
TEST_TIME 31 11240
TEST_TIME 32 11375
TEST_TIME 33 11356
TEST_TIME 34 11512
TEST_TIME 35 11616
TEST_TIME 36 11567
TEST_TIME 37 11357
TEST_TIME 38 11089
TEST_TIME 39 10957
TEST_TIME 40 10779
TEST_TIME 41 10640
TEST_TIME 42 10501
TEST_TIME 43 10377
TEST_TIME 44 10281
TEST_TIME 45 10158
TEST_TIME 46 10197
TEST_TIME 47 10099
TEST_TIME 48 9980
TEST_TIME 49 9923
TEST_TIME 50 9742
TEST_TIME 51 9765
TEST_TIME 52 9691
TEST_TIME 53 9757
TEST_TIME 54 9770
TEST_TIME 55 9697
TEST_TIME 56 9734
TEST_TIME 57 9673
TEST_TIME 58 9573
TEST_TIME 59 9598
TEST_TIME 60 9689
TEST_TIME 61 9826
TEST_TIME 62 9825
TEST_TIME 63 9812
TEST_TIME 64 9854
TEST_TIME 65 9893
TEST_TIME 66 9945
TEST_TIME 67 9911
TEST_TIME 68 10062
TEST_TIME 69 9963
TEST_TIME 70 10018
TEST_TIME 71 9883
TEST_TIME 72 9807
TEST_TIME 73 9861
TEST_TIME 74 9786
TEST_TIME 75 9761
TEST_TIME 76 9688
TEST_TIME 77 9687
TEST_TIME 78 9680
TEST_TIME 79 9631
TEST_TIME 80 9556
TEST_TIME 81 9537
TEST_TIME 82 9472
TEST_TIME 83 9430
TEST_TIME 84 9472
TEST_TIME 85 9435
TEST_TIME 86 9421
TEST_TIME 87 9414
TEST_TIME 88 9425
TEST_TIME 89 9393
TEST_TIME 90 9442
TEST_TIME 91 9442
TEST_TIME 92 9580
TEST_TIME 93 9624
TEST_TIME 94 9609
TEST_TIME 95 9557
TEST_TIME 96 9614
TEST_TIME 97 9541
TEST_TIME 98 9468
TEST_TIME 99 9433
TEST_TIME 100 9410
TEST_TIME 101 9384
TEST_TIME 102 9350
TEST_TIME 103 9327
TEST_TIME 104 9298
TEST_TIME 105 9276
TEST_TIME 106 9255
TEST_TIME 107 9232
TEST_TIME 108 9194
TEST_TIME 109 9230
TEST_TIME 110 9216
TEST_TIME 111 9184
TEST_TIME 112 9165
TEST_TIME 113 9247
TEST_TIME 114 9275
TEST_TIME 115 9262
TEST_TIME 116 9275
TEST_TIME 117 9293
TEST_TIME 118 9310
TEST_TIME 119 9364
TEST_TIME 120 9293

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-09-2021 07:49:53 to 03-09-2021 07:49:56.

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 1525366 1525366 0 0.00 0 20680 7 0 0
doc_list_biud 1525366 1525366 0 0.00 0 32145 30 0 0
sp_add_invoice_to_stock 4972 4874 98 1.97 40 225287 20672 0 98
sp_cancel_adding_invoice 6016 6016 0 0.00 6 92489 889 0 0
sp_cancel_client_order 1726 1726 0 0.00 5 21292 385 0 0
sp_cancel_customer_reserve 587414 587414 0 0.00 1 32148 61 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 4989 103 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 3028 77 0 0
sp_cancel_supplier_invoice 6549 6540 9 0.13 3 18493 864 0 9
sp_cancel_supplier_order 409 409 0 0.00 7 1181655 113390 0 0
sp_cancel_write_off 10116 10116 0 0.00 1 13649 122 0 0
sp_client_order 19537 19537 0 0.00 4 46640 391 0 0
sp_customer_reserve 443570 443570 0 0.00 0 58145 205 0 0
sp_fill_shopping_cart 29943 29943 0 0.00 1 21876 48 0 0
sp_fill_shopping_cart_clo_res 443566 443566 0 0.00 0 19517 15 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 38197 425 0 0
sp_kill_cost_storno 17228 17228 0 0.00 0 17450 65 0 0
sp_kill_qstorno_ret_qs2qd 1191893 1191893 0 0.00 0 32139 33 0 0
sp_kill_qty_storno 626531 626531 0 0.00 0 32144 73 0 0
sp_lock_selected_doc 19953 19953 0 0.00 0 8619 4 0 0
sp_make_cost_storno 26725 26725 0 0.00 0 16057 70 0 0
sp_multiply_rows_for_pdistr 26725 26725 0 0.00 0 15637 45 0 0
sp_multiply_rows_for_qdistr 19537 19537 0 0.00 0 41962 161 0 0
sp_pay_from_customer 6779 6779 0 0.00 4 24179 154 0 0
sp_pay_to_supplier 5636 5636 0 0.00 5 15852 156 0 0
sp_payment_common 12415 12415 0 0.00 1 15632 35 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 21280 352 0 0
sp_qd_handle_on_invoice_upd_sts 10886 10886 0 0.00 0 27734 408 0 0
sp_qd_handle_on_reserve_upd_sts 9440 9440 0 0.00 0 18598 74 0 0
sp_reserve_write_off 9440 9440 0 0.00 7 20818 245 0 0
sp_supplier_invoice 5134 5134 0 0.00 7 91509 3708 0 0
sp_supplier_order 5277 5277 0 0.00 5 43266 1461 0 0
srv_aggregate_perf_data 108 108 0 0.00 2375 228579 17475 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1582 151504 46334 0 0
srv_find_qd_qs_mism 1069197 1069197 0 0.00 0 11974 6 0 0
srv_make_invnt_saldo 420 420 0 0.00 59 97886 4375 0 0
srv_make_money_saldo 741 741 0 0.00 1 15261 578 0 0
srv_recalc_idx_stat 4 4 0 0.00 156031 533064 344046 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4874 4874 0 0.00 0 25249 268 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 5377 101 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 4620 14 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 4222 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 1792 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 657 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 5025 31 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 29570 1648 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 3968 13 0 0
t$perf-norm:sp_client_order 19537 19537 0 0.00 0 14873 24 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 5857 15 0 0
t$perf-norm:sp_pay_from_customer 6779 6779 0 0.00 0 5641 11 0 0
t$perf-norm:sp_pay_to_supplier 5636 5636 0 0.00 0 8181 16 0 0
t$perf-norm:sp_reserve_write_off 9440 9440 0 0.00 0 15594 16 0 0
t$perf-norm:sp_supplier_invoice 5134 5134 0 0.00 0 9070 25 0 0
t$perf-norm:sp_supplier_order 5277 5277 0 0.00 0 7867 23 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 806 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 177 5 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1786 15 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 1574 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9930 9734 196 1.97 0 12052 18 0 196
v_all_customers 35802 35802 0 0.00 0 16 1 0 0
v_all_suppliers 28786 28786 0 0.00 0 71 1 0 0
v_all_wares 39074 39074 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2407 10 0 0
v_cancel_client_order 3452 3452 0 0.00 0 3694 9 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 4117 10 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 6830 12 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 3019 8 0 0
v_cancel_supplier_invoice 742 724 18 2.42 0 1684 14 0 18
v_cancel_supplier_order 818 818 0 0.00 0 2076 9 0 0
v_cancel_write_off 1994 1994 0 0.00 0 12083 28 0 0
v_max_id_clo_ord 5277 5277 0 0.00 0 125 1 0 0
v_max_id_clo_res 933 933 0 0.00 0 2323 12 0 0
v_max_id_ord_sup 5129 5129 0 0.00 0 3538 4 0 0
v_max_non_paid_invoice 1649 1649 0 0.00 0 387 2 0 0
v_max_non_paid_realizn 3548 3548 0 0.00 0 3852 4 0 0
v_min_id_clo_ord 5277 5277 0 0.00 0 4035 8 0 0
v_min_id_clo_res 933 933 0 0.00 0 7307 21 0 0
v_min_id_ord_sup 5129 5129 0 0.00 0 3617 6 0 0
v_min_non_paid_invoice 5636 5636 0 0.00 0 10260 23 0 0
v_min_non_paid_realizn 6779 6779 0 0.00 0 15613 32 0 0
v_our_firm 9734 9734 0 0.00 0 17 1 0 0
v_reserve_write_off 18880 18880 0 0.00 0 9826 7 0 0
x_lock_depdocs_on_canc_invoice 12194 12194 0 0.00 0 24402 122 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3425 367 0 0
x_make_qstorno_1000_1200 5277 5277 0 0.00 2 43259 1357 0 0
x_make_qstorno_1200_2000 5134 5134 0 0.00 3 89735 3534 0 0
x_make_qstorno_2100_3300 443570 443570 0 0.00 0 53753 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-09-2021 07:49:57 to 03-09-2021 07:50:01.

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:19:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:10 32388 8192000000 9733327984 10032148480 808090592 8.978 100 90 180 0 838126768 8068368 21967808
05:21:47 22897 8192000000 9768200624 10077036544 842293008 9.323 100 85 170 0 874768240 11859008 20616224
05:23:17 18640 8192000000 9820523568 10144718848 889349824 9.793 100 86 172 1 926789056 17854656 19584576
05:24:40 9700 8192000000 9882099488 10220056576 936851520 10.262 100 75 150 0 990523968 37212432 16460016
05:25:53 7901 8192000000 9947761024 10297798656 970940736 10.596 100 80 160 0 1059888112 72152352 16795024
05:27:04 6773 8192000000 9962508240 10320547840 1001614272 10.894 100 70 140 0 1069566816 53287008 14665536
05:28:15 7697 8192000000 10005533680 10380193792 1029976800 11.168 100 72 143 1 1113313984 69118048 14219136
05:29:24 2432 8192000000 10023687264 10392961024 1058250928 11.440 100 93 185 1 1139418128 62129936 19037264
05:30:29 1675 8192000000 10042913216 10416308224 1079785216 11.645 100 84 168 0 1158694752 62713424 16196112
05:31:33 4852 8192000000 10012757984 10410508288 1082060544 11.667 100 37 71 1 1147241744 57737200 7444000
05:32:38 6306 8192000000 9989417184 10381156352 1082610752 11.672 100 43 83 3 1111072720 18433968 10028000
05:33:47 8440 8192000000 9999111840 10401808384 1096442896 11.804 100 22 44 0 1120910672 19645760 4822016
05:34:56 18542 8192000000 9995396544 10398441472 1105789888 11.893 100 30 59 1 1133457616 21432496 6235232
05:36:15 21899 8192000000 10032767440 10422304768 1103014384 11.866 100 83 163 4 1137904336 16273968 18615984
05:37:37 9315 8192000000 10020072768 10428858368 1102845424 11.865 100 38 75 0 1139073136 27814880 8412832
05:38:47 8128 8192000000 10022771888 10436816896 1081665744 11.663 98 22 44 1 1118354784 31816528 4872512
05:39:57 5897 8192000000 9956671344 10348826624 1029729824 11.166 96 42 84 0 1059556128 20629280 9197024
05:41:09 6888 8192000000 9954525104 10341015552 999256688 10.871 96 39 77 2 1023587120 15709328 8621104
05:42:17 3428 8192000000 9958663392 10346979328 1043865120 11.302 100 43 83 1 1063974640 10214560 9894960
05:43:28 5579 8192000000 9966937424 10345611264 1018535952 11.058 98 45 88 3 1055786496 28029840 9220704
05:44:36 10506 8192000000 9940894112 10332463104 1028315328 11.152 100 40 80 0 1052776192 15335952 9124912
05:45:47 7993 8192000000 9969193808 10351366144 1041199328 11.276 99 49 99 3 1073484640 21053184 11232128
05:46:56 32765 8192000000 9964590752 10365845504 1058906112 11.446 100 19 38 0 1084198336 21526464 3765760
05:48:35 4252 8192000000 9990618832 10390982656 1069342560 11.546 100 54 102 3 1105273328 24870960 11059808
05:49:45 22563 8192000000 10030949392 10427117568 1090242480 11.745 100 55 110 1 1126511696 24314496 11954720
05:51:11 25130 8192000000 10071128112 10472341504 1107800112 11.912 100 54 108 0 1177903664 58841776 11261776
05:52:58 45908 8192000000 10110323760 10519924736 1132113968 12.141 100 40 80 0 1210605168 69564272 8926928
05:54:57 34835 8192000000 10135488096 10564292608 1137384544 12.191 100 42 84 0 1257769328 111461920 8922864
05:56:41 6641 8192000000 10205377136 10615672832 1150194416 12.311 100 65 128 1 1310013968 146148176 13671376
05:57:54 1827 8192000000 10214567408 10621296640 1154313520 12.350 100 78 153 0 1313285664 143275888 15696256
05:58:58 12523 8192000000 10225313232 10639077376 1163903696 12.440 100 70 140 2 1322882320 143583792 15394832
06:00:24 2240 8192000000 10262094656 10671611904 1163854384 12.439 100 86 173 0 1359955392 176091904 20009104
06:01:32 1581 8192000000 10243825696 10646413312 1147160048 12.283 100 83 167 6 1340160016 174686736 18313232
06:02:40 5242 8192000000 10286773728 10696847360 1161402496 12.416 100 68 136 2 1386961552 209957776 15601280
06:03:59 4867 8192000000 10307886256 10712731648 1143046688 12.244 99 79 156 0 1400106176 239272128 17787360
06:05:08 2716 8192000000 10295281712 10735673344 1154988464 12.356 100 71 142 0 1393839360 221944400 16906496
06:06:17 4767 8192000000 10315747248 10715672576 1147488288 12.286 100 82 161 3 1406347552 240504864 18354400
06:07:27 2526 8192000000 10356262288 10753642496 1151310448 12.322 100 83 166 1 1457852976 287551696 18990832
06:08:38 2525 8192000000 10377161440 10782724096 1150207488 12.311 100 82 163 0 1473223872 304200288 18816096
06:09:45 6895 8192000000 10367918656 10795573248 1153785952 12.345 100 47 94 0 1457025280 291400752 11838576
06:10:58 1628 8192000000 10386434256 10793975808 1129498176 12.117 98 80 160 2 1465268992 316840800 18930016
06:12:02 10928 8192000000 10334676720 10758688768 1138295856 12.199 99 37 73 2 1426287968 278313280 9678832
06:13:20 5842 8192000000 10410187136 10845421568 1150377504 12.313 100 51 101 0 1499736816 336396128 12963184
06:14:29 9677 8192000000 10343572176 10814971904 1132687024 12.147 99 39 78 0 1479058432 336113104 10258304
06:15:43 10245 8192000000 10369694160 10817146880 1130557376 12.127 100 36 72 1 1464678176 324682464 9438336
06:16:59 20025 8192000000 10339406624 10865860608 1150187088 12.311 100 29 58 0 1509732768 351878288 7667392
06:18:26 16388 8192000000 10262656960 10726236160 1115937600 11.989 99 20 40 1 1356126240 234876032 5312608
06:19:47 50265 8192000000 10154728800 10653007872 1121404336 12.040 99 12 24 0 1266583504 142348736 2830432
06:21:47 43525 8192000000 10139277568 10576920576 1140764080 12.223 100 23 47 1 1240602192 94355376 5482736
06:23:44 105469 8192000000 10105719872 10591842304 1103269056 11.869 97 11 22 2 1185237232 80296208 1671968
06:26:30 92492 8192000000 10043690944 10477641728 1088276176 11.726 96 37 73 2 1122667712 27077248 7314288
06:29:05 77412 8192000000 10063207328 10514448384 1132895712 12.149 100 20 41 0 1165407696 27861680 4650304
06:31:35 84798 8192000000 10110121408 10570862592 1108560288 11.919 98 27 54 0 1184862992 70540320 5762384
06:34:00 65970 8192000000 10121006256 10569850880 1127263744 12.096 99 19 38 0 1204891040 73238560 4388736
06:36:09 88610 8192000000 10116317488 10564743168 1117182512 12.000 98 13 26 0 1167564368 47926320 2455536
06:38:38 114832 8192000000 10152492256 10608807936 1127991504 12.102 99 26 52 1 1228910752 94652912 6266336
06:41:35 59230 8192000000 10159657360 10656481280 1133000624 12.150 99 27 54 1 1260088992 120868416 6219952
06:44:48 36719 8192000000 10079723600 10569146368 1143248064 12.246 100 38 71 4 1198586128 48041024 7297040
06:46:26 61789 8192000000 10088354976 10523185152 1086941312 11.714 95 14 28 0 1124169184 34552992 2674880
06:48:29 85268 8192000000 10140646256 10657361920 1106519376 11.899 97 16 32 0 1226208864 116455680 3233808
06:50:54 68985 8192000000 10090859744 10567122944 1122898272 12.054 98 18 37 0 1191864592 65284160 3682160
06:53:10 112771 8192000000 10066237488 10529746944 1078315664 11.631 95 12 24 1 1133830480 53259856 2254960
06:56:04 57639 8192000000 10110990336 10565586944 1119848848 12.026 98 25 49 2 1191875744 66943760 5083136
06:59:15 64003 8192000000 10127174720 10630123520 1115718000 11.987 99 27 54 1 1213290080 92235312 5336768
07:02:04 52127 8192000000 10054741728 10493734912 1106717952 11.901 98 46 89 2 1140358512 24857232 8783328
07:03:58 26271 8192000000 10102791904 10530127872 1123890048 12.064 99 55 110 2 1181198480 45441600 11866832
07:05:28 59072 8192000000 10080999904 10556755968 1119597456 12.023 98 17 34 0 1191929072 68483280 3848336
07:07:27 51385 8192000000 10059979104 10520141824 1132842288 12.148 99 6 12 0 1168233968 34131184 1260496
07:09:19 52860 8192000000 10096524656 10567237632 1146977952 12.281 100 13 26 0 1195950192 45458496 3513744
07:11:13 44056 8192000000 10087559712 10538684416 1112399456 11.955 97 16 32 2 1164884960 49429232 3056272
07:13:02 77766 8192000000 10042272032 10532999168 1094425440 11.785 96 9 18 0 1171431584 75227952 1778192
07:15:20 15764 8192000000 10065249536 10492248064 1117256272 12.001 98 46 89 3 1157162976 30806656 9100048
07:16:38 39056 8192000000 10138358320 10586583040 1100993600 11.847 96 25 50 2 1183653008 77446528 5212880
07:18:40 36416 8192000000 10164974624 10603048960 1126965984 12.093 98 26 52 2 1241791088 109221840 5603264
07:20:24 80371 8192000000 10084342112 10568228864 1129356944 12.115 98 16 32 0 1264450240 131791168 3302128
07:22:45 87677 8192000000 10080987808 10535432192 1116848496 11.997 97 9 18 0 1149491056 31534576 1107984
07:25:15 151504 8192000000 10076484688 10539876352 1074778032 11.598 93 24 48 1 1140501760 61106976 4616752
07:28:48 84313 8192000000 10096103264 10580013056 1114236480 11.973 97 12 24 0 1194837808 77800688 2800640
07:32:10 75204 8192000000 10094134848 10574409728 1146934288 12.281 100 18 36 0 1200989024 49701712 4353024
07:34:29 71556 8192000000 10112655392 10612736000 1142690096 12.241 99 37 75 1 1214793872 63921296 8182480
07:37:04 89473 8192000000 10085816352 10585034752 1109930928 11.932 96 7 14 0 1192115584 80795968 1388688
07:39:55 25965 8192000000 10104584112 10561298432 1141580736 12.230 99 23 46 1 1189347104 42825776 4940592
07:41:26 62647 8192000000 10122592576 10668507136 1133463232 12.154 98 33 66 1 1243192576 102558528 7170816
07:43:31 54406 8192000000 10107293120 10559709184 1100028016 11.838 96 16 32 0 1163823968 60550832 3245120
07:45:28 70407 8192000000 10071430672 10507059200 1075043344 11.600 95 28 56 0 1138313664 57229984 6040336
07:47:40 37049 8192000000 10082150016 10527039488 1118999296 12.018 99 26 52 0 1192153168 67710240 5443632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-09-2021 07:50:03 to 03-09-2021 07:50:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-09-2021 07:50:19 to 03-09-2021 07:50:21.

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          237230
MON$OLDEST_ACTIVE               237231
MON$OLDEST_SNAPSHOT             237231
MON$NEXT_TRANSACTION            237231
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                       2076000
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.09.21 07:50:22. 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-09-03 07:50:24 No severe PSQL-related problems occured
03.09.21 07:50:26. End of report.