Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2021 04:37
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 15917084 kB
MemAvailable 30683500 kB
Buffers 20 kB
Cached 15714056 kB
SwapCached 4672 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-10-05 04:37:08
DTS_END                         2021-10-05 04:37:08.7790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38642
TRN_ID                          234649
ATT_ID                          616
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: 8710.99 22910 1045319
customer order: creation 167.97 386 20156
customer order: refuse 13.93 343 1672
order to supplier: creation 44.48 1391 5338
order to supplier: removal 3.26 115733 391
invoice (draft): creation 44.76 3737 5371
invoice (draft): removal 48.43 1045 5812
invoice accept: apply 40.98 17602 4918
invoice accept: cancel 43.83 839 5260
customer reserve: creation 3753.22 174 450386
customer reserve: removal 4276.12 69 513134
realization accept: apply 79.50 187 9540
realization accept: cancel 68.47 114 8216
payment from customer: creation 58.27 125 6992
payment from customer: removal 5.68 77 682
payment to supplier: creation 47.66 122 5719
payment to supplier: removal 3.09 79 371
service: total inventory turnovers 3.95 3516 474
service: total monetary turnovers 6.41 521 769
service: aggregate perf. data 0.95 16671 114
service: refresh index statistics 0.03 295476 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-10-2021 04:38:15 to 05-10-2021 04:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8895
TEST_TIME 2 7801
TEST_TIME 3 7154
TEST_TIME 4 5806
TEST_TIME 5 6223
TEST_TIME 6 6823
TEST_TIME 7 7049
TEST_TIME 8 6799
TEST_TIME 9 7281
TEST_TIME 10 7277
TEST_TIME 11 7487
TEST_TIME 12 7611
TEST_TIME 13 8020
TEST_TIME 14 7921
TEST_TIME 15 8164
TEST_TIME 16 8213
TEST_TIME 17 8502
TEST_TIME 18 8622
TEST_TIME 19 9042
TEST_TIME 20 9046
TEST_TIME 21 9250
TEST_TIME 22 9312
TEST_TIME 23 9476
TEST_TIME 24 9674
TEST_TIME 25 10742
TEST_TIME 26 11278
TEST_TIME 27 10890
TEST_TIME 28 10628
TEST_TIME 29 10554
TEST_TIME 30 10502
TEST_TIME 31 10403
TEST_TIME 32 10376
TEST_TIME 33 10187
TEST_TIME 34 10001
TEST_TIME 35 10040
TEST_TIME 36 10011
TEST_TIME 37 9983
TEST_TIME 38 10085
TEST_TIME 39 9984
TEST_TIME 40 10002
TEST_TIME 41 9993
TEST_TIME 42 9856
TEST_TIME 43 9963
TEST_TIME 44 9976
TEST_TIME 45 10030
TEST_TIME 46 9907
TEST_TIME 47 9740
TEST_TIME 48 9554
TEST_TIME 49 9436
TEST_TIME 50 9341
TEST_TIME 51 9361
TEST_TIME 52 9315
TEST_TIME 53 9259
TEST_TIME 54 9148
TEST_TIME 55 9144
TEST_TIME 56 9012
TEST_TIME 57 8922
TEST_TIME 58 9020
TEST_TIME 59 9082
TEST_TIME 60 9050
TEST_TIME 61 9063
TEST_TIME 62 9070
TEST_TIME 63 9270
TEST_TIME 64 9261
TEST_TIME 65 9261
TEST_TIME 66 9313
TEST_TIME 67 9364
TEST_TIME 68 9390
TEST_TIME 69 9365
TEST_TIME 70 9333
TEST_TIME 71 9381
TEST_TIME 72 9295
TEST_TIME 73 9213
TEST_TIME 74 9153
TEST_TIME 75 9149
TEST_TIME 76 9109
TEST_TIME 77 9052
TEST_TIME 78 9073
TEST_TIME 79 9022
TEST_TIME 80 9052
TEST_TIME 81 9029
TEST_TIME 82 9031
TEST_TIME 83 9013
TEST_TIME 84 8984
TEST_TIME 85 9041
TEST_TIME 86 9061
TEST_TIME 87 9044
TEST_TIME 88 9057
TEST_TIME 89 9130
TEST_TIME 90 9114
TEST_TIME 91 9134
TEST_TIME 92 9141
TEST_TIME 93 9054
TEST_TIME 94 9004
TEST_TIME 95 8972
TEST_TIME 96 8914
TEST_TIME 97 8867
TEST_TIME 98 8836
TEST_TIME 99 8871
TEST_TIME 100 8866
TEST_TIME 101 8850
TEST_TIME 102 8858
TEST_TIME 103 8852
TEST_TIME 104 8843
TEST_TIME 105 8870
TEST_TIME 106 8890
TEST_TIME 107 8965
TEST_TIME 108 8977
TEST_TIME 109 8984
TEST_TIME 110 8951
TEST_TIME 111 8908
TEST_TIME 112 8872
TEST_TIME 113 8872
TEST_TIME 114 8817
TEST_TIME 115 8795
TEST_TIME 116 8796
TEST_TIME 117 8777
TEST_TIME 118 8777
TEST_TIME 119 8781
TEST_TIME 120 8718

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-10-2021 04:38:19 to 05-10-2021 04:38:22.

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 1462303 1462303 0 0.00 0 14451 6 0 0
doc_list_biud 1462301 1462301 0 0.00 0 48247 31 0 0
sp_add_invoice_to_stock 5047 4918 129 2.55 30 212240 17603 0 129
sp_cancel_adding_invoice 5260 5260 0 0.00 4 54923 840 0 0
sp_cancel_client_order 1672 1672 0 0.00 5 26336 343 0 0
sp_cancel_customer_reserve 513134 513134 0 0.00 1 48252 69 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 3168 78 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 2552 79 0 0
sp_cancel_supplier_invoice 5823 5812 11 0.18 1 25413 1045 0 11
sp_cancel_supplier_order 391 391 0 0.00 9 922963 115733 0 0
sp_cancel_write_off 8216 8216 0 0.00 1 15383 115 0 0
sp_client_order 20156 20156 0 0.00 4 24569 387 0 0
sp_customer_reserve 450386 450386 0 0.00 0 57664 175 0 0
sp_fill_shopping_cart 30858 30858 0 0.00 1 15914 45 0 0
sp_fill_shopping_cart_clo_res 450368 450368 0 0.00 0 18488 16 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 50829 440 0 0
sp_kill_cost_storno 14529 14529 0 0.00 0 8006 58 0 0
sp_kill_qstorno_ret_qs2qd 1040687 1040687 0 0.00 0 47938 38 0 0
sp_kill_qty_storno 548923 548923 0 0.00 0 48246 82 0 0
sp_lock_selected_doc 19977 19977 0 0.00 0 9234 4 0 0
sp_make_cost_storno 27150 27150 0 0.00 0 13226 51 0 0
sp_multiply_rows_for_pdistr 27149 27149 0 0.00 0 10941 31 0 0
sp_multiply_rows_for_qdistr 20156 20156 0 0.00 0 24507 203 0 0
sp_pay_from_customer 6992 6992 0 0.00 4 18088 126 0 0
sp_pay_to_supplier 5719 5719 0 0.00 5 11540 123 0 0
sp_payment_common 12710 12710 0 0.00 1 4311 18 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 26331 308 0 0
sp_qd_handle_on_invoice_upd_sts 10159 10159 0 0.00 0 34730 349 0 0
sp_qd_handle_on_reserve_upd_sts 9539 9539 0 0.00 0 33941 62 0 0
sp_reserve_write_off 9540 9540 0 0.00 7 34463 188 0 0
sp_supplier_invoice 5371 5371 0 0.00 7 105315 3737 0 0
sp_supplier_order 5338 5338 0 0.00 6 40048 1391 0 0
srv_aggregate_perf_data 114 114 0 0.00 2719 134023 16671 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1553 232502 50010 0 0
srv_find_qd_qs_mism 998743 998743 0 0.00 0 14450 5 0 0
srv_make_invnt_saldo 474 474 0 0.00 50 142783 3517 0 0
srv_make_money_saldo 769 769 0 0.00 1 13881 521 0 0
srv_recalc_idx_stat 4 4 0 0.00 187553 579648 295476 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4918 4918 0 0.00 0 67077 197 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 14259 104 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 4705 21 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 783 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 4481 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 3257 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 16056 97 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 18728 1493 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 11241 33 0 0
t$perf-norm:sp_client_order 20156 20156 0 0.00 0 13500 19 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 14588 25 0 0
t$perf-norm:sp_pay_from_customer 6992 6992 0 0.00 0 5246 11 0 0
t$perf-norm:sp_pay_to_supplier 5719 5719 0 0.00 0 17482 18 0 0
t$perf-norm:sp_reserve_write_off 9540 9540 0 0.00 0 31879 16 0 0
t$perf-norm:sp_supplier_invoice 5371 5371 0 0.00 0 17363 22 0 0
t$perf-norm:sp_supplier_order 5338 5338 0 0.00 0 19159 19 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 341 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 9229 33 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1778 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 44 15 0 0
v_add_invoice_to_stock 10052 9794 258 2.56 0 15830 21 0 258
v_all_customers 36540 36540 0 0.00 0 90 1 0 0
v_all_suppliers 29758 29758 0 0.00 0 90 1 0 0
v_all_wares 40312 40312 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 3651 17 0 0
v_cancel_client_order 3344 3344 0 0.00 0 3431 9 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2713 12 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 3462 7 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 1942 7 0 0
v_cancel_supplier_invoice 762 740 22 2.88 0 228 12 0 22
v_cancel_supplier_order 782 782 0 0.00 0 1737 13 0 0
v_cancel_write_off 2002 2002 0 0.00 0 3815 18 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 421 1 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 2445 15 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 5337 5 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 438 2 0 0
v_max_non_paid_realizn 3810 3810 0 0.00 0 350 2 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 4480 6 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 8978 28 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 7273 9 0 0
v_min_non_paid_invoice 5719 5719 0 0.00 0 4307 11 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 4002 14 0 0
v_our_firm 10134 10134 0 0.00 0 42 1 0 0
v_reserve_write_off 19078 19078 0 0.00 0 15836 10 0 0
x_lock_depdocs_on_canc_invoice 10702 10702 0 0.00 0 10932 115 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 6339 321 0 0
x_make_qstorno_1000_1200 5338 5338 0 0.00 2 40030 1317 0 0
x_make_qstorno_1200_2000 5371 5371 0 0.00 3 105244 3555 0 0
x_make_qstorno_2100_3300 450386 450386 0 0.00 0 57664 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2021 04:38:23 to 05-10-2021 04:38:27.

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
02:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 25068 8192000000 9706526864 10001326080 786729952 8.762 100 88 175 1 815989888 7939024 21320912
02:09:53 26854 8192000000 9771257328 10077663232 842542624 9.325 100 91 182 0 877942448 13759040 21640784
02:11:27 19223 8192000000 9823475040 10147864576 872742128 9.627 100 74 148 2 917900496 28067568 17090800
02:12:54 13682 8192000000 9887930000 10227503104 930725968 10.202 100 69 138 1 993310480 47804896 14779616
02:14:11 8509 8192000000 9932362128 10288807936 975559712 10.641 100 73 146 0 1051611136 60983776 15067648
02:15:23 3397 8192000000 9991283120 10348949504 1008350240 10.959 100 79 158 2 1108008400 83611200 16046960
02:16:28 3217 8192000000 10033013024 10397204480 1034533568 11.212 100 89 178 0 1137587744 84485056 18569120
02:17:33 1910 8192000000 10019573664 10388455424 1055250416 11.411 100 88 173 1 1131020864 57876192 17894256
02:18:37 2277 8192000000 10022155104 10408083456 1068584976 11.539 100 66 129 2 1142954816 60794256 13575584
02:19:41 4917 8192000000 9996391344 10396131328 1071545488 11.567 100 37 75 0 1126516720 46892752 8078480
02:20:46 4398 8192000000 10006937488 10398289920 1080342656 11.651 100 37 74 1 1127203632 38629648 8231328
02:21:51 40308 8192000000 9992071120 10387898368 1083725424 11.683 100 49 100 0 1100750176 6015808 11008944
02:23:32 6727 8192000000 10018061584 10428751872 1092056944 11.762 100 44 89 0 1125343296 23927920 9358432
02:24:41 7721 8192000000 10011156464 10416082944 1093451552 11.775 100 31 62 0 1121001616 20493376 7056688
02:25:53 7804 8192000000 10019677408 10416898048 1097644368 11.815 100 25 49 1 1122566096 19448768 5472960
02:27:01 48526 8192000000 9994675936 10404847616 1071976448 11.571 98 25 50 0 1101900800 24114640 5809712
02:28:56 9716 8192000000 9976884864 10373750784 1014136384 11.015 95 32 64 0 1033234320 12266752 6831184
02:30:06 8838 8192000000 9933639072 10333671424 1018252336 11.055 99 24 48 2 1041721744 17590400 5879008
02:31:18 13488 8192000000 9955616144 10352128000 1036362576 11.230 100 43 85 1 1062762016 16704704 9694736
02:32:58 6263 8192000000 9958550384 10340724736 1025214320 11.122 99 35 70 0 1046198576 13249936 7734320
02:34:07 6062 8192000000 9981422512 10372694016 1052671984 11.386 100 47 89 0 1079575520 15847024 11056512
02:35:17 7959 8192000000 9984901088 10384535552 1060267792 11.459 100 46 91 1 1093368896 22623056 10478048
02:36:31 41897 8192000000 10004750048 10409832448 1073418352 11.585 100 29 56 3 1100133408 20245344 6469712
02:38:29 42342 8192000000 10083953024 10487877632 1111432304 11.946 100 47 94 0 1175475072 54552048 9490720
02:40:17 12409 8192000000 10102938240 10507030528 1126771824 12.091 100 51 101 1 1206787152 68444224 11571104
02:41:37 46648 8192000000 10167109392 10586017792 1136710208 12.185 100 44 88 1 1279116528 132771296 9635024
02:43:30 19110 8192000000 10186817904 10613698560 1144948192 12.262 100 44 88 0 1313078832 157933680 10196960
02:45:01 19154 8192000000 10154958480 10574856192 1149362192 12.304 100 34 68 0 1266307696 109400544 7544960
02:46:23 4054 8192000000 10191111824 10620620800 1169150064 12.489 100 49 98 0 1295662336 115290576 11221696
02:47:35 2683 8192000000 10237111664 10641199104 1164439152 12.445 100 71 141 1 1339256352 158456976 16360224
02:48:44 8302 8192000000 10266237408 10681618432 1176896384 12.561 100 70 140 1 1365006736 172916304 15194048
02:49:56 11688 8192000000 10258916224 10677563392 1169304400 12.490 98 61 120 2 1351737424 168722448 13710576
02:51:13 1550 8192000000 10294622176 10695626752 1172382672 12.519 100 92 181 2 1397713168 204565792 20764704
02:52:18 11243 8192000000 10308558464 10732761088 1150545968 12.315 99 47 94 0 1405202800 243082848 11573984
02:53:33 7031 8192000000 10303602720 10700513280 1132825728 12.148 99 80 159 2 1389689344 238634432 18229184
02:54:46 1896 8192000000 10376558320 10787733504 1152599696 12.334 100 90 180 0 1467573728 293687216 21286816
02:55:53 3492 8192000000 10337525136 10745597952 1144655040 12.259 100 62 124 1 1432051216 273022944 14373232
02:57:00 12348 8192000000 10326269840 10779381760 1142023872 12.235 100 40 79 1 1443554896 291785152 9745872
02:58:18 9701 8192000000 10339038896 10764201984 1134341136 12.162 99 39 77 0 1440179072 296730432 9107504
02:59:30 13956 8192000000 10378138784 10821443584 1126486000 12.088 100 36 72 0 1470556576 334408544 9662032
03:01:04 11239 8192000000 10348672512 10822733824 1120638384 12.033 100 30 60 2 1470733536 341881280 8213872
03:02:24 11603 8192000000 10261195008 10711937024 1096473904 11.804 99 28 56 0 1367054208 262904432 7675872
03:03:37 11035 8192000000 10062486816 10472787968 1102120976 11.858 99 59 118 3 1148850752 32327888 14401888
03:05:06 89440 8192000000 10106095200 10570391552 1114927872 11.979 99 22 44 0 1212336720 93091728 4317120
03:07:36 54627 8192000000 10073009248 10555068416 1111666848 11.948 99 14 28 1 1201223888 86879232 2677808
03:09:33 49280 8192000000 10064660096 10498748416 1122381968 12.049 99 22 44 1 1158009520 31087648 4539904
03:11:25 86613 8192000000 10112072624 10581733376 1138980160 12.206 100 28 56 0 1231071504 85838848 6252496
03:14:07 157154 8192000000 10071404112 10505490432 1096182096 11.801 96 10 20 1 1136570528 38652288 1736144
03:18:23 54777 8192000000 10105754528 10523820032 1137493216 12.192 99 36 72 0 1176930576 32028608 7408752
03:20:26 35251 8192000000 10126208384 10615672832 1144973168 12.262 99 31 62 0 1218910720 67804400 6133152
03:22:20 232502 8192000000 10097756320 10570641408 1146072144 12.273 99 20 39 0 1188263264 37473600 4717520
03:27:54 88755 8192000000 10099501184 10612314112 1164823840 12.448 100 13 25 0 1213472528 46384272 2264416
03:30:23 57546 8192000000 10105827056 10577223680 1166132288 12.461 100 28 56 0 1213586768 40968560 6485920
03:32:26 78065 8192000000 10162130976 10648223744 1176077296 12.554 100 18 36 0 1273567968 93357232 4133440
03:34:50 70090 8192000000 10159360560 10682945536 1156127632 12.367 99 16 32 0 1250897632 90824160 3945840
03:37:24 41804 8192000000 10153506768 10597822464 1147599040 12.287 98 14 28 0 1219481680 69010544 2872096
03:39:09 60098 8192000000 10166632832 10610507776 1108463264 11.918 95 16 32 0 1209826688 97915696 3447728
03:41:10 41043 8192000000 10123322848 10586406912 1109320384 11.926 96 21 40 0 1161704768 48122560 4261824
03:43:08 69056 8192000000 10109096192 10584543232 1104381344 11.879 96 14 28 1 1199211088 91717776 3111968
03:45:25 27465 8192000000 10074180112 10506108928 1105846928 11.893 98 23 46 0 1143285056 32829536 4608592
03:46:56 18367 8192000000 10088056688 10513932288 1128994976 12.112 99 39 78 0 1162595312 26025024 7575312
03:48:17 69127 8192000000 10083313104 10571763712 1119558800 12.023 99 34 68 0 1211994208 85785632 6649776
03:50:26 75584 8192000000 10070399856 10507321344 1058508592 11.442 94 26 51 0 1102292592 38950000 4834000
03:52:57 63313 8192000000 10109610816 10574528512 1132950160 12.149 100 15 29 0 1232581728 96663600 2967968
03:55:01 59503 8192000000 10083596160 10529488896 1118787920 12.016 99 7 14 0 1190233488 70038672 1406896
03:57:09 61268 8192000000 10056911456 10533310464 1099096608 11.829 98 12 24 0 1154896656 53331184 2468864
03:59:15 57542 8192000000 10082042976 10523975680 1112599280 11.957 99 16 32 1 1162545360 46469552 3476528
04:01:13 28106 8192000000 10098284192 10539380736 1130920336 12.130 100 45 88 2 1197300208 54759680 11620192
04:03:12 89173 8192000000 10122071696 10564452352 1107958688 11.913 98 17 34 0 1159298208 48135104 3204416
04:05:42 104149 8192000000 10088766192 10532511744 1104980000 11.885 97 15 30 0 1163301952 55140976 3180976
04:08:26 72423 8192000000 10076304416 10550525952 1106922352 11.903 97 20 41 0 1168462368 57746240 3793776
04:10:39 80436 8192000000 10069139792 10508902400 1116730176 11.996 98 31 61 2 1143334144 19843536 6760432
04:13:01 58691 8192000000 10105793440 10555203584 1150718464 12.316 100 17 33 1 1209030096 54355568 3956064
04:15:02 68679 8192000000 10150989888 10604687360 1162589040 12.428 100 18 36 0 1247977680 81083936 4304704
04:17:11 79440 8192000000 10107076448 10581553152 1135936224 12.177 98 11 22 1 1179656048 41142752 2577072
04:19:44 59856 8192000000 10102809088 10567163904 1139637824 12.212 98 22 44 0 1196637376 52406848 4592704
04:21:47 96350 8192000000 10171998608 10628177920 1166014064 12.460 100 23 44 3 1255246400 84630304 4602032
04:24:24 90929 8192000000 10102737248 10600869888 1129156592 12.113 98 13 26 1 1207627376 75668224 2802560
04:27:04 17461 8192000000 10069253792 10506584064 1132587504 12.146 99 54 106 2 1162426784 19131344 10707936
04:28:28 109002 8192000000 10092834464 10533244928 1129701104 12.119 98 31 62 0 1182623040 46208224 6713712
04:31:17 45336 8192000000 10067285632 10519023616 1105183280 11.887 97 9 18 0 1145595472 38266512 2145680
04:33:16 40112 8192000000 10026652080 10484137984 1076483712 11.614 96 14 28 1 1108146192 28440832 3221648
04:35:11 32778 8192000000 10085904416 10543620096 1117291376 12.001 100 38 75 1 1169928144 45175216 7461552
04:36:51 25920 8192000000 9313638816 9659592704 385760960 4.497 34 7 14 0 416404848 29384560 1259328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2021 04:38:29 to 05-10-2021 04:38:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2021 04:38:45 to 05-10-2021 04:38:47.

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          231796
MON$OLDEST_ACTIVE               234931
MON$OLDEST_SNAPSHOT             234931
MON$NEXT_TRANSACTION            234931
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                       2037920
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:73: 
:74: mscs-test-161.budzdorov.ru    Tue Oct  5 04:37:19 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Tue Oct  5 04:37:19 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
--- end of diff output ---

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

05.10.21 04:38:48. 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-10-05 04:38:50 No severe PSQL-related problems occured
05.10.21 04:38:52. End of report.