Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2021 07:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16617524 kB
MemAvailable 31228368 kB
Buffers 0 kB
Cached 15092924 kB
SwapCached 4872 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-09 07:26:56
DTS_END                         2021-02-09 07:26:56.7010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53960
TRN_ID                          224826
ATT_ID                          655
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: 8970.00 19127 1076400
customer order: creation 161.18 453 19342
customer order: refuse 13.76 316 1651
order to supplier: creation 42.58 1364 5109
order to supplier: removal 3.48 113332 418
invoice (draft): creation 43.46 3413 5215
invoice (draft): removal 52.31 857 6277
invoice accept: apply 39.18 18763 4702
invoice accept: cancel 47.36 895 5683
customer reserve: creation 3641.05 183 436926
customer reserve: removal 4647.12 68 557654
realization accept: apply 77.77 160 9332
realization accept: cancel 80.43 124 9651
payment from customer: creation 55.20 105 6624
payment from customer: removal 5.79 78 695
payment to supplier: creation 46.00 112 5520
payment to supplier: removal 3.09 84 371
service: total inventory turnovers 3.94 2904 473
service: total monetary turnovers 5.37 525 644
service: aggregate perf. data 0.91 11194 109
service: refresh index statistics 0.03 227606 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-02-2021 07:30:21 to 09-02-2021 07:30:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5163
TEST_TIME 2 6533
TEST_TIME 3 5849
TEST_TIME 4 5658
TEST_TIME 5 6454
TEST_TIME 6 6593
TEST_TIME 7 6641
TEST_TIME 8 6364
TEST_TIME 9 6433
TEST_TIME 10 6209
TEST_TIME 11 6184
TEST_TIME 12 6175
TEST_TIME 13 6061
TEST_TIME 14 6497
TEST_TIME 15 6596
TEST_TIME 16 6637
TEST_TIME 17 6843
TEST_TIME 18 7007
TEST_TIME 19 8652
TEST_TIME 20 8478
TEST_TIME 21 8659
TEST_TIME 22 8855
TEST_TIME 23 9201
TEST_TIME 24 9915
TEST_TIME 25 10651
TEST_TIME 26 10658
TEST_TIME 27 10672
TEST_TIME 28 10463
TEST_TIME 29 10160
TEST_TIME 30 9879
TEST_TIME 31 9838
TEST_TIME 32 9972
TEST_TIME 33 9968
TEST_TIME 34 9804
TEST_TIME 35 9768
TEST_TIME 36 9582
TEST_TIME 37 9534
TEST_TIME 38 9542
TEST_TIME 39 9540
TEST_TIME 40 9567
TEST_TIME 41 9562
TEST_TIME 42 9660
TEST_TIME 43 9704
TEST_TIME 44 9691
TEST_TIME 45 9841
TEST_TIME 46 9834
TEST_TIME 47 9771
TEST_TIME 48 9657
TEST_TIME 49 9494
TEST_TIME 50 9502
TEST_TIME 51 9410
TEST_TIME 52 9372
TEST_TIME 53 9353
TEST_TIME 54 9298
TEST_TIME 55 9271
TEST_TIME 56 9185
TEST_TIME 57 9139
TEST_TIME 58 9196
TEST_TIME 59 9173
TEST_TIME 60 9341
TEST_TIME 61 9260
TEST_TIME 62 9415
TEST_TIME 63 9396
TEST_TIME 64 9391
TEST_TIME 65 9361
TEST_TIME 66 9406
TEST_TIME 67 9275
TEST_TIME 68 9256
TEST_TIME 69 9183
TEST_TIME 70 9143
TEST_TIME 71 9094
TEST_TIME 72 9044
TEST_TIME 73 9089
TEST_TIME 74 9039
TEST_TIME 75 9103
TEST_TIME 76 9059
TEST_TIME 77 9152
TEST_TIME 78 9167
TEST_TIME 79 9126
TEST_TIME 80 9150
TEST_TIME 81 9205
TEST_TIME 82 9307
TEST_TIME 83 9241
TEST_TIME 84 9152
TEST_TIME 85 9109
TEST_TIME 86 9072
TEST_TIME 87 9038
TEST_TIME 88 8982
TEST_TIME 89 8986
TEST_TIME 90 8944
TEST_TIME 91 8903
TEST_TIME 92 8902
TEST_TIME 93 8894
TEST_TIME 94 8922
TEST_TIME 95 8945
TEST_TIME 96 8943
TEST_TIME 97 9075
TEST_TIME 98 9068
TEST_TIME 99 9055
TEST_TIME 100 9067
TEST_TIME 101 9046
TEST_TIME 102 9027
TEST_TIME 103 8956
TEST_TIME 104 8914
TEST_TIME 105 8857
TEST_TIME 106 8803
TEST_TIME 107 8803
TEST_TIME 108 8773
TEST_TIME 109 8745
TEST_TIME 110 8769
TEST_TIME 111 8750
TEST_TIME 112 8732
TEST_TIME 113 8785
TEST_TIME 114 8805
TEST_TIME 115 8811
TEST_TIME 116 8849
TEST_TIME 117 8935
TEST_TIME 118 8917
TEST_TIME 119 8968
TEST_TIME 120 8973
TEST_TIME 121 8899

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2021 07:30:25 to 09-02-2021 07:30:28.

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 1481368 1481368 0 0.00 0 29117 6 0 0
doc_list_biud 1481367 1481367 0 0.00 0 48944 32 0 0
sp_add_invoice_to_stock 4785 4702 83 1.73 25 240347 18764 0 83
sp_cancel_adding_invoice 5683 5683 0 0.00 5 96710 895 0 0
sp_cancel_client_order 1651 1651 0 0.00 4 9784 316 0 0
sp_cancel_customer_reserve 557654 557654 0 0.00 1 48946 69 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 6919 78 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 2527 84 0 0
sp_cancel_supplier_invoice 6278 6277 1 0.01 2 19895 857 0 1
sp_cancel_supplier_order 418 418 0 0.00 8 1075439 113332 0 0
sp_cancel_write_off 9651 9651 0 0.00 1 9313 125 0 0
sp_client_order 19342 19342 0 0.00 4 55999 454 0 0
sp_customer_reserve 436926 436926 0 0.00 0 139720 184 0 0
sp_fill_shopping_cart 29656 29656 0 0.00 1 17487 45 0 0
sp_fill_shopping_cart_clo_res 436922 436922 0 0.00 0 26476 21 0 0
sp_get_clo_for_invoice 4696 4696 0 0.00 0 49173 426 0 0
sp_kill_cost_storno 16400 16400 0 0.00 0 9057 54 0 0
sp_kill_qstorno_ret_qs2qd 1131654 1131654 0 0.00 0 48939 37 0 0
sp_kill_qty_storno 595362 595362 0 0.00 0 48942 79 0 0
sp_lock_selected_doc 19476 19476 0 0.00 0 7180 5 0 0
sp_make_cost_storno 26172 26172 0 0.00 0 12345 49 0 0
sp_multiply_rows_for_pdistr 26172 26172 0 0.00 0 9034 29 0 0
sp_multiply_rows_for_qdistr 19342 19342 0 0.00 0 55984 275 0 0
sp_pay_from_customer 6624 6624 0 0.00 4 10025 105 0 0
sp_pay_to_supplier 5520 5520 0 0.00 5 10304 112 0 0
sp_payment_common 12144 12144 0 0.00 1 10240 23 0 0
sp_qd_handle_on_cancel_clo 1651 1651 0 0.00 0 9775 281 0 0
sp_qd_handle_on_invoice_upd_sts 10379 10379 0 0.00 0 21856 359 0 0
sp_qd_handle_on_reserve_upd_sts 9332 9332 0 0.00 0 18730 48 0 0
sp_reserve_write_off 9332 9332 0 0.00 7 18744 161 0 0
sp_supplier_invoice 5215 5215 0 0.00 6 112319 3413 0 0
sp_supplier_order 5109 5109 0 0.00 5 37444 1364 0 0
srv_aggregate_perf_data 109 109 0 0.00 2829 110432 11195 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1040 130256 48384 0 0
srv_find_qd_qs_mism 1031765 1031765 0 0.00 0 29116 5 0 0
srv_make_invnt_saldo 473 473 0 0.00 66 118525 2904 0 0
srv_make_money_saldo 644 644 0 0.00 1 18686 525 0 0
srv_recalc_idx_stat 4 4 0 0.00 89262 372435 227606 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4702 4702 0 0.00 0 17706 166 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 1894 74 0 0
t$perf-norm:sp_cancel_client_order 1651 1651 0 0.00 0 1027 10 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 532 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1803 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 781 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 704 10 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 21507 1179 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 1508 6 0 0
t$perf-norm:sp_client_order 19342 19342 0 0.00 0 6867 14 0 0
t$perf-norm:sp_customer_reserve 893 893 0 0.00 0 834 3 0 0
t$perf-norm:sp_pay_from_customer 6624 6624 0 0.00 0 3423 8 0 0
t$perf-norm:sp_pay_to_supplier 5520 5520 0 0.00 0 5812 7 0 0
t$perf-norm:sp_reserve_write_off 9332 9332 0 0.00 0 4646 8 0 0
t$perf-norm:sp_supplier_invoice 5215 5215 0 0.00 0 3409 13 0 0
t$perf-norm:sp_supplier_order 5109 5109 0 0.00 0 3611 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 219 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 109 3 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 1019 7 0 0
t$perf-norm:srv_make_money_saldo 644 644 0 0.00 0 1102 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9556 9390 166 1.73 0 2956 16 0 166
v_all_customers 34988 34988 0 0.00 0 76 1 0 0
v_all_suppliers 28560 28560 0 0.00 0 16 1 0 0
v_all_wares 38684 38684 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2388 14 0 0
v_cancel_client_order 3302 3302 0 0.00 0 5121 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 384 3 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 3276 6 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 2501 9 0 0
v_cancel_supplier_invoice 722 720 2 0.27 0 1248 15 0 2
v_cancel_supplier_order 836 836 0 0.00 0 7336 12 0 0
v_cancel_write_off 1826 1826 0 0.00 0 3642 19 0 0
v_max_id_clo_ord 5103 5103 0 0.00 0 1062 1 0 0
v_max_id_clo_res 893 893 0 0.00 0 4087 25 0 0
v_max_id_ord_sup 5211 5211 0 0.00 0 8063 11 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 197 2 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 2571 3 0 0
v_min_id_clo_ord 5103 5103 0 0.00 0 4568 7 0 0
v_min_id_clo_res 893 893 0 0.00 0 7310 23 0 0
v_min_id_ord_sup 5211 5211 0 0.00 0 6628 7 0 0
v_min_non_paid_invoice 5520 5520 0 0.00 0 10224 11 0 0
v_min_non_paid_realizn 6623 6623 0 0.00 0 9941 20 0 0
v_our_firm 9595 9595 0 0.00 0 17 1 0 0
v_reserve_write_off 18664 18664 0 0.00 0 4368 7 0 0
x_lock_depdocs_on_canc_invoice 11600 11600 0 0.00 0 24660 109 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 31087 418 0 0
x_make_qstorno_1000_1200 5109 5109 0 0.00 2 37406 1292 0 0
x_make_qstorno_1200_2000 5215 5215 0 0.00 2 112304 3235 0 0
x_make_qstorno_2100_3300 436926 436926 0 0.00 0 139719 160 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-02-2021 07:30:29 to 09-02-2021 07:30:34.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
04:57:00 8 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 30656 8192000000 9728900496 10026848256 801750384 8.914 100 85 172 0 830336768 7649376 20937008
04:59:34 11973 8192000000 9762005120 10069659648 838765888 9.287 100 84 168 2 870423360 11481552 20175920
05:00:49 19272 8192000000 9805758496 10122092544 869401056 9.594 100 82 164 0 906418752 18548320 18469376
05:02:12 13893 8192000000 9858741728 10192064512 921268048 10.109 100 83 166 0 968011200 29124688 17618464
05:03:29 7773 8192000000 9933909504 10275500032 953394864 10.424 100 84 168 1 1045710528 75028144 17287520
05:04:41 8127 8192000000 9970042496 10322362368 995903920 10.839 100 83 165 0 1079835072 67674624 16256528
05:05:51 2551 8192000000 10007378784 10364780544 1019985488 11.072 100 87 174 0 1119916912 82629360 17302064
05:06:56 2370 8192000000 10003252352 10367397888 1046878208 11.331 100 87 175 0 1117505584 52102896 18524480
05:08:01 1515 8192000000 10034428272 10403774464 1062834496 11.484 100 89 180 0 1140413104 59175248 18403360
05:09:03 17159 8192000000 10007365376 10387881984 1071717200 11.568 100 68 136 2 1122257744 36118304 14422240
05:10:22 7449 8192000000 9989993136 10379370496 1076746496 11.616 100 36 71 0 1106822480 23200784 6875200
05:11:30 13706 8192000000 10017937856 10427408384 1081871968 11.665 100 48 99 0 1129062384 36474416 10716000
05:13:24 5964 8192000000 9996749632 10398965760 1085701696 11.702 100 42 83 1 1113163152 17852512 9608944
05:14:34 3991 8192000000 10011364528 10412630016 1098514304 11.824 100 43 84 4 1123266304 16071984 8680016
05:15:40 10747 8192000000 10010758736 10403377152 1099099344 11.829 100 35 70 0 1116406304 9522816 7784144
05:16:55 1230 8192000000 10022462512 10411491328 1092022000 11.762 99 85 166 2 1135847744 25506880 18318864
05:18:01 6295 8192000000 10002633472 10404065280 1073801984 11.588 99 40 80 0 1110497440 28524288 8171168
05:19:09 8200 8192000000 9956259088 10343878656 1020440528 11.076 98 45 90 0 1044405968 14179600 9785840
05:20:21 44718 8192000000 9904430144 10280583168 915624096 10.053 89 42 83 0 935917264 11338096 8955072
05:22:09 7901 8192000000 9951019472 10345852928 1032402096 11.192 100 40 80 1 1062800672 22182080 8216496
05:23:23 3590 8192000000 9958165088 10340872192 1043540784 11.299 100 56 112 0 1068779520 12497040 12741696
05:24:30 7633 8192000000 9964643200 10365440000 1053786016 11.397 100 30 61 1 1084094592 24022976 6285600
05:25:41 55637 8192000000 9986123776 10388881408 1063816560 11.493 100 25 50 0 1098194192 28721008 5656624
05:27:48 4767 8192000000 10043406592 10434433024 1094489392 11.785 100 63 126 1 1157268496 49597232 13181872
05:28:58 27168 8192000000 10085532624 10500161536 1110452912 11.937 100 52 104 1 1182968992 61488192 11027888
05:30:48 9305 8192000000 10107522272 10509758464 1132064784 12.141 100 54 108 0 1212701408 69310240 11326384
05:32:02 15652 8192000000 10171935168 10587496448 1147797760 12.289 100 66 132 0 1288473808 126382704 14293344
05:33:20 8736 8192000000 10161180704 10569093120 1155997424 12.366 100 54 108 0 1263428352 95685232 11745696
05:34:33 5990 8192000000 10208329664 10622967808 1164549792 12.446 100 52 104 2 1316342896 140644288 11148816
05:35:41 9590 8192000000 10224520288 10639167488 1154135184 12.348 99 71 141 1 1324294096 154399344 15759568
05:36:56 6195 8192000000 10263346672 10672676864 1161339456 12.416 99 65 129 0 1361511312 185608688 14563168
05:38:08 4361 8192000000 10330303760 10739888128 1190904976 12.692 100 74 146 1 1424480848 217836512 15739360
05:39:16 1861 8192000000 10368152560 10774495232 1188645552 12.671 100 89 178 1 1466419600 257447328 20326720
05:40:26 4390 8192000000 10394384640 10809286656 1188374688 12.668 100 68 137 0 1498204288 293803344 16026256
05:41:35 2464 8192000000 10454140640 10864201728 1179575792 12.586 100 80 160 1 1560066976 362256080 18235104
05:42:44 3469 8192000000 10469474400 10879184896 1173792448 12.532 100 79 158 0 1571403920 378540192 19071280
05:43:50 4482 8192000000 10469484608 10885881856 1144779664 12.260 99 57 112 1 1562569040 403288496 14500880
05:45:03 1039 8192000000 10506035424 10937253888 1157902592 12.384 100 84 168 2 1603972112 424110864 21958656
05:46:06 12054 8192000000 10333168816 10785243136 1166648736 12.465 100 44 88 0 1433788800 256544112 10595952
05:47:19 20374 8192000000 10348339696 10797199360 1130792064 12.129 98 22 44 0 1434671376 297904352 5974960
05:48:42 26078 8192000000 10334074176 10776129536 1127311808 12.096 100 28 56 0 1465757456 331005280 7440368
05:50:09 36291 8192000000 10221676304 10687422464 1114526240 11.975 100 16 32 0 1306278400 187574720 4177440
05:51:47 95632 8192000000 10044835408 10504015872 1046098112 11.323 94 10 20 1 1095230592 46664320 2468160
05:54:26 90930 8192000000 10079826240 10518265856 1099387408 11.832 98 45 89 2 1139842320 31141024 9313888
05:56:57 82013 8192000000 10118012688 10554437632 1113891904 11.969 99 20 40 0 1199706432 81863152 3951376
05:59:29 29870 8192000000 10056871584 10530054144 1119173056 12.019 100 15 30 1 1181030528 58622976 3234496
06:01:36 114390 8192000000 10126624608 10576330752 1093652880 11.777 96 21 42 1 1162319904 63986128 4680896
06:05:15 60652 8192000000 10131207008 10579607552 1109144576 11.924 98 26 52 0 1234814784 119592512 6077696
06:07:17 38556 8192000000 10144682320 10578030592 1127793744 12.101 99 25 50 0 1237417024 104397088 5226192
06:09:04 55010 8192000000 10102215696 10536583168 1115723792 11.987 98 18 36 1 1189576032 69800800 4051440
06:11:13 77996 8192000000 10080160368 10543194112 1137174512 12.189 99 14 28 2 1218923360 78852384 2896464
06:13:33 23381 8192000000 10156215904 10592182272 1153602976 12.343 100 47 94 0 1223418400 60466144 9349280
06:14:57 72179 8192000000 10125103440 10615975936 1152195344 12.330 100 30 60 2 1229014592 70643024 6176224
06:17:46 32301 8192000000 10078792320 10572365824 1137667744 12.194 99 6 12 0 1185249952 46261728 1320480
06:19:18 67149 8192000000 10074454960 10553872384 1149291200 12.303 100 10 21 3 1205866096 54217696 2357200
06:21:34 45705 8192000000 10128626944 10558914560 1160904048 12.412 100 34 66 1 1214902288 46969744 7028496
06:23:20 68197 8192000000 10214374688 10662330368 1145397360 12.266 97 26 51 0 1268160624 116877264 5886000
06:25:37 84622 8192000000 10123459472 10640994304 1150684480 12.316 99 18 36 1 1266028768 111350080 3994208
06:28:04 73414 8192000000 10070612352 10507210752 1102666944 11.863 95 23 45 1 1166799216 59855728 4276544
06:30:18 46291 8192000000 10076316720 10508107776 1087797872 11.722 95 29 58 1 1134500032 41273728 5428432
06:32:07 35370 8192000000 10077393200 10526875648 1123876000 12.064 99 36 71 3 1164562464 32554976 8131488
06:33:43 75124 8192000000 10052162288 10524086272 1123235568 12.058 99 23 46 0 1186785200 58971632 4578000
06:35:59 90533 8192000000 10066796688 10537865216 1076231248 11.612 95 8 16 1 1125840736 47862000 1747488
06:38:29 39887 8192000000 10106795504 10590240768 1115132448 11.981 99 18 36 0 1197258704 78114080 4012176
06:40:24 69505 8192000000 10132395776 10605711360 1126502592 12.088 99 21 42 0 1221030000 89738976 4788432
06:42:43 53773 8192000000 10136154720 10577149952 1103866656 11.874 97 23 46 0 1193524944 84561776 5096512
06:45:13 60132 8192000000 10161772896 10619338752 1135472080 12.173 100 29 58 1 1212934896 71098784 6364032
06:47:21 83236 8192000000 10060426144 10558156800 1068468960 11.537 94 10 20 0 1169155232 98206704 2479568
06:49:44 130256 8192000000 10089254128 10559242240 1129901584 12.120 100 22 44 0 1205475168 70766864 4806720
06:53:11 42642 8192000000 10053063104 10558824448 1131796384 12.138 100 7 14 0 1170541792 37425040 1320368
06:56:05 65122 8192000000 10075127904 10516963328 1110484288 11.937 97 9 18 1 1154538544 42706048 1348208
06:58:10 62527 8192000000 10098302208 10540158976 1093175872 11.773 95 17 32 2 1155402976 58788192 3438912
07:00:20 72341 8192000000 10113566720 10569625600 1113686944 11.967 97 23 46 2 1206893824 88056912 5149968
07:02:38 69163 8192000000 10113871840 10636787712 1107970304 11.913 97 12 24 0 1210350880 99564960 2815616
07:04:48 77839 8192000000 10083910016 10554236928 1138122944 12.198 100 11 21 2 1167952912 27520624 2309344
07:07:06 22064 8192000000 10075626336 10508779520 1143517104 12.249 100 47 94 1 1169365040 15348320 10499616
07:08:35 15269 8192000000 10109315520 10549493760 1129781168 12.119 99 35 70 0 1188872560 52566880 6524512
07:09:53 77368 8192000000 10074102592 10529030144 1131556720 12.136 99 15 30 0 1174097072 39437712 3102640
07:12:13 74872 8192000000 10090966064 10580545536 1140550608 12.221 100 7 14 0 1201142768 59027200 1564960
07:14:28 70398 8192000000 10107281024 10561437696 1125541056 12.079 98 19 38 0 1176151952 46927712 3683184
07:16:38 61951 8192000000 10102114112 10560315392 1125853456 12.082 98 12 24 0 1174206960 46113664 2239840
07:18:41 52585 8192000000 10116592224 10608758784 1145589616 12.268 100 24 48 1 1215896608 65440656 4866336
07:20:47 47518 8192000000 10163862832 10627792896 1155868160 12.365 100 23 46 2 1266138784 104392384 5878240
07:23:06 75058 8192000000 10070593600 10528235520 1098785760 11.826 96 9 18 1 1147094528 46364224 1944544
07:25:31 112649 8192000000 9912339888 10385252352 975362768 10.639 86 10 19 2 1042506624 64947328 2196528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2021 07:30:51 to 09-02-2021 07:30:53.

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          224966
MON$OLDEST_ACTIVE               224967
MON$OLDEST_SNAPSHOT             224967
MON$NEXT_TRANSACTION            224967
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                       2091712
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:142: 
:143: mscs-test-161.budzdorov.ru    Tue Feb  9 07:27:43 2021
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:145: 
--- end of diff output ---

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

09.02.21 07:30:54. 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-02-09 07:30:57 No severe PSQL-related problems occured
09.02.21 07:30:58. End of report.