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

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.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-04-05 13:26:54
DTS_END                         2021-04-05 13:26:54.7600
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/44300
TRN_ID                          233868
ATT_ID                          610
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: 9283.12 19850 1113974
customer order: creation 175.20 387 21024
customer order: refuse 14.63 315 1756
order to supplier: creation 46.29 1383 5555
order to supplier: removal 3.43 130027 412
invoice (draft): creation 45.10 3141 5412
invoice (draft): removal 52.85 1053 6342
invoice accept: apply 41.28 17612 4953
invoice accept: cancel 48.39 924 5807
customer reserve: creation 3840.34 173 460841
customer reserve: removal 4711.17 75 565340
realization accept: apply 84.20 175 10104
realization accept: cancel 91.66 114 10999
payment from customer: creation 58.98 99 7078
payment from customer: removal 5.76 75 691
payment to supplier: creation 49.43 110 5931
payment to supplier: removal 3.57 71 428
service: total inventory turnovers 3.89 3655 467
service: total monetary turnovers 6.04 619 725
service: aggregate perf. data 0.88 13557 106
service: refresh index statistics 0.03 223430 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-04-2021 13:28:04 to 05-04-2021 13:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5980
TEST_TIME 2 6630
TEST_TIME 3 6941
TEST_TIME 4 7247
TEST_TIME 5 6805
TEST_TIME 6 6448
TEST_TIME 7 6741
TEST_TIME 8 6687
TEST_TIME 9 6343
TEST_TIME 10 6441
TEST_TIME 11 6607
TEST_TIME 12 6576
TEST_TIME 13 6494
TEST_TIME 14 6638
TEST_TIME 15 6826
TEST_TIME 16 6763
TEST_TIME 17 6730
TEST_TIME 18 6971
TEST_TIME 19 7212
TEST_TIME 20 7204
TEST_TIME 21 7817
TEST_TIME 22 7746
TEST_TIME 23 7782
TEST_TIME 24 8051
TEST_TIME 25 7960
TEST_TIME 26 8183
TEST_TIME 27 8186
TEST_TIME 28 9142
TEST_TIME 29 11057
TEST_TIME 30 11260
TEST_TIME 31 11198
TEST_TIME 32 11142
TEST_TIME 33 11146
TEST_TIME 34 10988
TEST_TIME 35 10861
TEST_TIME 36 10667
TEST_TIME 37 10682
TEST_TIME 38 10828
TEST_TIME 39 10723
TEST_TIME 40 10683
TEST_TIME 41 10757
TEST_TIME 42 10672
TEST_TIME 43 10721
TEST_TIME 44 10620
TEST_TIME 45 10389
TEST_TIME 46 10199
TEST_TIME 47 10195
TEST_TIME 48 10138
TEST_TIME 49 10214
TEST_TIME 50 10095
TEST_TIME 51 10018
TEST_TIME 52 9922
TEST_TIME 53 9837
TEST_TIME 54 9748
TEST_TIME 55 9696
TEST_TIME 56 9790
TEST_TIME 57 9906
TEST_TIME 58 9827
TEST_TIME 59 9848
TEST_TIME 60 9839
TEST_TIME 61 9946
TEST_TIME 62 9920
TEST_TIME 63 9922
TEST_TIME 64 9879
TEST_TIME 65 9999
TEST_TIME 66 9977
TEST_TIME 67 10022
TEST_TIME 68 10015
TEST_TIME 69 10070
TEST_TIME 70 10105
TEST_TIME 71 10132
TEST_TIME 72 10031
TEST_TIME 73 9916
TEST_TIME 74 9854
TEST_TIME 75 9828
TEST_TIME 76 9772
TEST_TIME 77 9765
TEST_TIME 78 9690
TEST_TIME 79 9676
TEST_TIME 80 9615
TEST_TIME 81 9571
TEST_TIME 82 9586
TEST_TIME 83 9522
TEST_TIME 84 9587
TEST_TIME 85 9576
TEST_TIME 86 9597
TEST_TIME 87 9608
TEST_TIME 88 9562
TEST_TIME 89 9579
TEST_TIME 90 9688
TEST_TIME 91 9714
TEST_TIME 92 9691
TEST_TIME 93 9713
TEST_TIME 94 9637
TEST_TIME 95 9590
TEST_TIME 96 9532
TEST_TIME 97 9492
TEST_TIME 98 9439
TEST_TIME 99 9490
TEST_TIME 100 9470
TEST_TIME 101 9444
TEST_TIME 102 9465
TEST_TIME 103 9472
TEST_TIME 104 9423
TEST_TIME 105 9415
TEST_TIME 106 9435
TEST_TIME 107 9554
TEST_TIME 108 9537
TEST_TIME 109 9581
TEST_TIME 110 9604
TEST_TIME 111 9556
TEST_TIME 112 9493
TEST_TIME 113 9459
TEST_TIME 114 9419
TEST_TIME 115 9358
TEST_TIME 116 9308
TEST_TIME 117 9272
TEST_TIME 118 9321
TEST_TIME 119 9303
TEST_TIME 120 9284

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-04-2021 13:28:08 to 05-04-2021 13:28:11.

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 1539944 1539944 0 0.00 0 16870 7 0 0
doc_list_biud 1539940 1539940 0 0.00 0 61479 34 0 0
sp_add_invoice_to_stock 5049 4953 96 1.90 33 201259 17613 0 96
sp_cancel_adding_invoice 5807 5807 0 0.00 5 86348 925 0 0
sp_cancel_client_order 1756 1756 0 0.00 4 11634 316 0 0
sp_cancel_customer_reserve 565340 565340 0 0.00 1 61480 75 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 5292 76 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 5329 72 0 0
sp_cancel_supplier_invoice 6351 6342 9 0.14 2 26463 1054 0 9
sp_cancel_supplier_order 412 412 0 0.00 6 1225557 130027 0 0
sp_cancel_write_off 10999 10999 0 0.00 1 19577 115 0 0
sp_client_order 21024 21024 0 0.00 4 49347 387 0 0
sp_customer_reserve 460841 460841 0 0.00 0 63480 173 0 0
sp_fill_shopping_cart 31974 31974 0 0.00 1 17613 37 0 0
sp_fill_shopping_cart_clo_res 460835 460835 0 0.00 0 23415 17 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 40930 354 0 0
sp_kill_cost_storno 17925 17925 0 0.00 0 8792 55 0 0
sp_kill_qstorno_ret_qs2qd 1148433 1148433 0 0.00 0 61477 41 0 0
sp_kill_qty_storno 605706 605706 0 0.00 0 61479 86 0 0
sp_lock_selected_doc 20843 20843 0 0.00 0 6230 4 0 0
sp_make_cost_storno 28060 28060 0 0.00 0 16867 56 0 0
sp_multiply_rows_for_pdistr 28059 28059 0 0.00 0 6777 31 0 0
sp_multiply_rows_for_qdistr 21024 21024 0 0.00 0 49340 199 0 0
sp_pay_from_customer 7078 7078 0 0.00 4 12916 99 0 0
sp_pay_to_supplier 5931 5931 0 0.00 5 10676 110 0 0
sp_payment_common 13009 13009 0 0.00 1 6600 18 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 11626 294 0 0
sp_qd_handle_on_invoice_upd_sts 10753 10753 0 0.00 0 33229 372 0 0
sp_qd_handle_on_reserve_upd_sts 10104 10104 0 0.00 0 9313 50 0 0
sp_reserve_write_off 10104 10104 0 0.00 6 16878 176 0 0
sp_supplier_invoice 5412 5412 0 0.00 8 107979 3141 0 0
sp_supplier_order 5555 5555 0 0.00 6 68031 1383 0 0
srv_aggregate_perf_data 106 106 0 0.00 2616 112199 13557 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1805 182359 50529 0 0
srv_find_qd_qs_mism 1065769 1065769 0 0.00 0 16275 6 0 0
srv_make_invnt_saldo 467 467 0 0.00 44 83867 3655 0 0
srv_make_money_saldo 725 725 0 0.00 1 17405 619 0 0
srv_recalc_idx_stat 3 3 0 0.00 160462 255782 223431 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 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 4953 4953 0 0.00 0 16825 194 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 10712 141 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 8775 13 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 5644 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2071 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 4422 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2045 20 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 18137 1598 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 7530 14 0 0
t$perf-norm:sp_client_order 21024 21024 0 0.00 0 6096 18 0 0
t$perf-norm:sp_customer_reserve 1076 1076 0 0.00 0 3325 12 0 0
t$perf-norm:sp_pay_from_customer 7078 7078 0 0.00 0 6850 12 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 7035 14 0 0
t$perf-norm:sp_reserve_write_off 10104 10104 0 0.00 0 5949 10 0 0
t$perf-norm:sp_supplier_invoice 5412 5412 0 0.00 0 10543 21 0 0
t$perf-norm:sp_supplier_order 5555 5555 0 0.00 0 5711 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 319 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 166 3 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 3134 14 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 6750 16 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10070 9878 192 1.90 0 5455 16 0 192
v_all_customers 38012 38012 0 0.00 0 14 1 0 0
v_all_suppliers 30848 30848 0 0.00 0 186 1 0 0
v_all_wares 42046 42046 0 0.00 0 114 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1915 10 0 0
v_cancel_client_order 3512 3512 0 0.00 0 1168 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1064 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 617 3 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 602 4 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 6233 20 0 18
v_cancel_supplier_order 824 824 0 0.00 0 198 3 0 0
v_cancel_write_off 1944 1944 0 0.00 0 1443 14 0 0
v_max_id_clo_ord 5551 5551 0 0.00 0 1572 2 0 0
v_max_id_clo_res 1076 1076 0 0.00 0 4204 19 0 0
v_max_id_ord_sup 5400 5400 0 0.00 0 5890 5 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 2355 3 0 0
v_max_non_paid_realizn 3785 3785 0 0.00 0 2937 4 0 0
v_min_id_clo_ord 5551 5551 0 0.00 0 1956 5 0 0
v_min_id_clo_res 1076 1076 0 0.00 0 7203 17 0 0
v_min_id_ord_sup 5400 5400 0 0.00 0 4288 6 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 3942 10 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 4202 13 0 0
v_our_firm 10620 10620 0 0.00 0 16 1 0 0
v_reserve_write_off 20208 20208 0 0.00 0 4989 5 0 0
x_lock_depdocs_on_canc_invoice 11745 11745 0 0.00 0 32627 113 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 17801 390 0 0
x_make_qstorno_1000_1200 5555 5555 0 0.00 2 67843 1313 0 0
x_make_qstorno_1200_2000 5412 5412 0 0.00 3 107973 3003 0 0
x_make_qstorno_2100_3300 460841 460841 0 0.00 0 63478 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-04-2021 13:28:12 to 05-04-2021 13:28:16.

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
10:56:58 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:00 23491 8192000000 9711317360 10008080384 796824016 8.864 100 85 170 0 825509344 7625904 21059424
10:59:36 19046 8192000000 9762390048 10068336640 837065552 9.270 100 91 182 1 871360288 12128976 22165760
11:01:03 22463 8192000000 9801842592 10122670080 864700272 9.547 100 86 172 0 905208032 20564400 19943360
11:02:27 14825 8192000000 9860803648 10191679488 907019968 9.968 100 82 164 0 967637760 42878064 17739728
11:03:47 7827 8192000000 9926228128 10268651520 963376736 10.522 100 86 171 0 1035153760 53834944 17942080
11:04:56 6866 8192000000 9976910944 10329616384 996601664 10.846 100 86 170 2 1083897408 70198560 17097184
11:06:05 3302 8192000000 10000191280 10364542976 1022027264 11.092 100 75 150 0 1111796960 74931936 14837760
11:07:11 2462 8192000000 10039927216 10404179968 1048662048 11.348 100 90 181 0 1147425248 80574416 18188784
11:08:14 2528 8192000000 10041731248 10414829568 1067171872 11.525 100 84 168 0 1150908272 66099168 17637232
11:09:18 51038 8192000000 10010033328 10395648000 1082865264 11.675 100 42 83 0 1119135792 27400128 8870400
11:11:09 5977 8192000000 10011767248 10413608960 1088078272 11.724 100 33 66 1 1119568608 23819616 7670720
11:12:16 7635 8192000000 10010509120 10416795648 1095186432 11.792 100 27 54 1 1121371008 20644960 5539616
11:13:27 5623 8192000000 10010846720 10420531200 1102168464 11.858 100 17 35 0 1130961136 25244784 3547888
11:14:36 66703 8192000000 10021196496 10432851968 1107555104 11.909 100 30 60 1 1132094368 18290448 6248816
11:16:42 5183 8192000000 10038404688 10445910016 1104123040 11.877 100 43 86 2 1156949568 43441312 9385216
11:17:48 7392 8192000000 10027630592 10432430080 1058722144 11.444 97 48 95 1 1092707664 24528576 9456944
11:19:00 7026 8192000000 9965855888 10353651712 1012402256 10.999 95 41 82 1 1050053760 28724064 8927440
11:20:08 35707 8192000000 9903035296 10274619392 956845984 10.458 93 45 90 1 980520928 13663248 10011696
11:21:51 6323 8192000000 9934317984 10318188544 969232768 10.579 95 52 103 1 1002709040 21869456 11606816
11:23:00 9928 8192000000 9956858064 10348871680 1032778480 11.195 100 34 68 3 1067552288 27307008 7466800
11:24:10 7555 8192000000 9966778832 10357108736 1050126464 11.362 100 34 68 0 1083629680 25365168 8138048
11:25:19 38359 8192000000 9955914192 10364448768 1048387600 11.345 99 25 48 1 1071538704 17807328 5343776
11:27:00 3456 8192000000 10015174400 10401538048 1086762544 11.712 100 63 125 0 1116860784 17071152 13027088
11:28:09 12566 8192000000 10078582752 10475147264 1103394960 11.870 100 61 122 1 1190580304 74935136 12250208
11:29:27 13835 8192000000 10055185888 10461085696 1115836416 11.988 100 45 88 1 1174683952 49776256 9071280
11:30:45 4808 8192000000 10104132880 10514251776 1127548352 12.098 100 63 126 1 1214567408 73309760 13709296
11:31:53 17384 8192000000 10118690864 10527145984 1139565360 12.211 100 53 104 3 1219224528 67893008 11766160
11:33:16 27235 8192000000 10172145344 10599575552 1152341392 12.331 100 56 111 1 1270835136 106623744 11870000
11:34:51 15785 8192000000 10173451856 10592911360 1163893904 12.440 100 60 120 0 1290448816 113668592 12886320
11:36:09 5640 8192000000 10192823568 10610106368 1172409632 12.519 100 50 100 1 1301472992 118059584 11003776
11:37:25 19079 8192000000 10224953136 10646745088 1188577648 12.670 100 47 94 0 1329789520 130432128 10779744
11:38:49 5535 8192000000 10271504048 10687721472 1204126512 12.815 100 58 116 0 1372002304 155127936 12747856
11:39:57 4281 8192000000 10307056272 10719444992 1193199216 12.713 100 64 127 0 1413112368 204091984 15821168
11:41:09 3464 8192000000 10340041024 10758004736 1194814304 12.728 100 74 145 0 1443709088 232056048 16838736
11:42:16 3758 8192000000 10389131904 10796273664 1209114768 12.861 100 86 169 1 1488731728 260119040 19497920
11:43:24 5436 8192000000 10441948928 10858225664 1203989744 12.813 100 76 151 0 1544383216 322666880 17726592
11:44:35 1914 8192000000 10522436624 10936836096 1220135808 12.963 100 87 177 1 1620079472 380023056 19920608
11:45:40 3634 8192000000 10499058992 10919280640 1197138128 12.750 100 65 129 2 1601991136 388697920 16155088
11:46:56 1805 8192000000 10559780016 10970521600 1190367472 12.687 99 81 160 3 1653316544 443825120 19123952
11:48:01 4190 8192000000 10510475392 10951602176 1186661088 12.652 99 60 120 0 1600206272 397616352 15928832
11:49:08 16056 8192000000 10595090032 11025821696 1201699120 12.792 100 48 97 0 1686033424 471699856 12634448
11:50:37 18725 8192000000 10636019376 11079442432 1194450544 12.725 100 44 88 0 1723443744 517359120 11634080
11:52:09 15848 8192000000 10598179040 11071201280 1154444048 12.351 99 43 84 1 1698484464 532881296 11159120
11:53:36 12985 8192000000 10689034352 11138412544 1124321920 12.068 100 31 62 1 1788892304 654645088 9925296
11:55:16 13470 8192000000 10376125104 10924806144 1131308240 12.134 100 27 54 0 1504113168 365873520 6931408
11:56:33 43623 8192000000 10057148560 10510237696 1104285568 11.878 98 24 48 0 1143790080 33886384 5618128
11:58:21 127141 8192000000 10038873760 10488496128 1039219424 11.257 92 13 26 0 1088756288 46645008 2891856
12:01:34 28616 8192000000 10064690752 10493452288 1093140848 11.773 97 37 72 0 1129031312 28336992 7553472
12:03:06 94713 8192000000 10062618096 10554556416 1102826144 11.864 99 18 36 1 1172290448 65747648 3716656
12:05:43 34690 8192000000 10080743712 10505416704 1104936992 11.884 99 19 38 0 1159936784 51155936 3843856
12:07:19 102487 8192000000 10084848224 10516209664 1105623856 11.891 98 21 42 1 1153663616 44041584 3998176
12:10:02 182359 8192000000 10116408784 10558525440 1109958368 11.932 98 20 40 0 1160989696 47100064 3931264
12:14:22 45390 8192000000 10104630784 10553626624 1136562640 12.183 100 19 38 2 1189413920 48355328 4495952
12:16:21 29699 8192000000 10076288624 10520174592 1123014704 12.055 99 24 47 2 1149193008 21666512 4511792
12:17:55 88137 8192000000 10133210816 10578866176 1137279808 12.190 99 17 35 0 1208626816 67097328 4249680
12:20:23 77656 8192000000 10166832704 10641149952 1114348336 11.974 97 24 47 2 1236131840 116434336 5349168
12:22:43 61255 8192000000 10104513616 10654224384 1134736784 12.166 100 10 21 0 1245312528 108259632 2316112
12:24:44 67338 8192000000 10069959504 10517176320 1122397456 12.050 99 19 38 0 1171107264 44626080 4083728
12:26:56 77449 8192000000 10076588960 10537025536 1104288464 11.878 96 16 32 0 1165502528 58025472 3188592
12:29:14 50759 8192000000 10090420704 10553544704 1121898448 12.045 98 17 34 1 1170858272 45582768 3377056
12:31:32 71995 8192000000 10092480224 10558189568 1106310640 11.897 97 14 26 1 1164334672 55148336 2875696
12:33:50 59685 8192000000 10130886096 10614276096 1138285632 12.199 100 30 60 2 1245198560 100327904 6585024
12:35:50 32203 8192000000 10079135872 10553417728 1110116448 11.934 97 10 20 1 1153180128 41016976 2046704
12:37:54 169340 8192000000 10099656736 10538229760 1112615488 11.957 97 21 40 1 1168784176 51831104 4337584
12:41:44 44269 8192000000 10072616128 10520387584 1093506016 11.776 96 18 36 1 1142353360 44505504 4341840
12:43:32 39392 8192000000 10044679744 10494996480 1073133424 11.582 97 7 14 1 1103073552 28301184 1638944
12:45:16 79231 8192000000 10037933296 10506506240 1095570192 11.796 98 16 32 0 1135870592 35996080 4304320
12:47:52 111881 8192000000 10083194720 10525315072 1073283056 11.583 96 7 14 0 1108273616 33788432 1202128
12:50:46 39424 8192000000 10082449408 10540318720 1097451488 11.813 98 23 46 0 1166817264 64208544 5157232
12:52:26 49137 8192000000 10088742832 10526199808 1104357360 11.879 99 25 50 1 1171783760 62468592 4957808
12:54:16 35306 8192000000 10116101264 10543534080 1118039584 12.008 99 51 98 2 1205273568 76678560 10555424
12:55:57 44313 8192000000 10075049168 10527440896 1110496544 11.937 99 37 74 2 1163420608 45980208 6943856
12:57:43 42641 8192000000 10098315952 10544472064 1095390016 11.794 97 39 78 2 1172705840 69800336 7515488
12:59:28 108652 8192000000 10084063952 10541314048 1133884864 12.158 100 48 96 0 1207047136 63776592 9385680
13:02:27 56940 8192000000 10046986480 10517131264 1133110752 12.151 100 10 20 0 1173746160 37828608 2806800
13:04:33 94345 8192000000 10093335792 10608025600 1132733456 12.147 99 16 32 2 1189077648 52724432 3619760
13:07:25 73234 8192000000 10114753504 10568982528 1137393952 12.191 100 25 50 1 1211039968 68035136 5610880
13:09:38 81110 8192000000 10140411664 10581757952 1140032064 12.216 99 31 62 2 1209532176 62950320 6549792
13:12:03 112519 8192000000 10096846848 10634944512 1119207952 12.020 98 13 26 0 1248867472 126631056 3028464
13:14:57 106328 8192000000 10077263328 10558476288 1099183568 11.830 96 12 24 0 1165411008 63578560 2648880
13:17:44 106808 8192000000 10041726560 10495483904 1119255872 12.020 99 17 34 1 1150313888 27444160 3613856
13:20:36 61030 8192000000 10069875520 10522980352 1125272208 12.077 100 6 12 1 1143001888 16550176 1179504
13:23:06 92335 8192000000 10083935552 10601848832 1119089120 12.018 98 13 26 1 1210682848 88757936 2835792
13:25:42 78386 8192000000 9095005680 9366085632 243897136 2.891 22 8 16 0 272500000 27108448 1494416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-04-2021 13:28:18 to 05-04-2021 13:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 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 05-04-2021 13:28:33 to 05-04-2021 13:28:35.

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          232449
MON$OLDEST_ACTIVE               234144
MON$OLDEST_SNAPSHOT             234144
MON$NEXT_TRANSACTION            234144
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                       2125120
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2404 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.2404 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 ---
:68: 
:69: mscs-test-161.budzdorov.ru    Mon Apr  5 13:27:03 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
--- end of diff output ---

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

05.04.21 13:28:36. 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-04-05 13:28:39 No severe PSQL-related problems occured
05.04.21 13:28:40. End of report.