Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2021 04: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 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 16607576 kB
MemAvailable 31356696 kB
Buffers 20 kB
Cached 14969472 kB
SwapCached 72 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-06-15 04:27:00
DTS_END                         2021-06-15 04:27:00.9670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58030
TRN_ID                          239431
ATT_ID                          647
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: 9040.41 22156 1084849
customer order: creation 177.94 371 21353
customer order: refuse 14.73 305 1767
order to supplier: creation 47.79 1329 5735
order to supplier: removal 3.38 107545 405
invoice (draft): creation 47.61 3588 5713
invoice (draft): removal 50.67 1008 6080
invoice accept: apply 43.01 17311 5161
invoice accept: cancel 45.30 837 5436
customer reserve: creation 3916.93 173 470032
customer reserve: removal 4390.85 64 526902
realization accept: apply 85.86 168 10303
realization accept: cancel 86.12 111 10334
payment from customer: creation 60.09 111 7211
payment from customer: removal 5.63 68 676
payment to supplier: creation 49.49 126 5939
payment to supplier: removal 3.67 65 440
service: total inventory turnovers 4.13 3023 495
service: total monetary turnovers 6.30 473 756
service: aggregate perf. data 0.89 12154 107
service: refresh index statistics 0.03 294287 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8931
TEST_TIME 2 7075
TEST_TIME 3 6080
TEST_TIME 4 5414
TEST_TIME 5 5624
TEST_TIME 6 5379
TEST_TIME 7 5448
TEST_TIME 8 5756
TEST_TIME 9 5895
TEST_TIME 10 6078
TEST_TIME 11 6162
TEST_TIME 12 6729
TEST_TIME 13 6646
TEST_TIME 14 6611
TEST_TIME 15 7120
TEST_TIME 16 7695
TEST_TIME 17 8141
TEST_TIME 18 8433
TEST_TIME 19 8678
TEST_TIME 20 8864
TEST_TIME 21 8875
TEST_TIME 22 9228
TEST_TIME 23 9431
TEST_TIME 24 9302
TEST_TIME 25 9757
TEST_TIME 26 11237
TEST_TIME 27 11284
TEST_TIME 28 11138
TEST_TIME 29 11295
TEST_TIME 30 11420
TEST_TIME 31 11271
TEST_TIME 32 11074
TEST_TIME 33 10741
TEST_TIME 34 10619
TEST_TIME 35 10564
TEST_TIME 36 10449
TEST_TIME 37 10341
TEST_TIME 38 10202
TEST_TIME 39 10108
TEST_TIME 40 10185
TEST_TIME 41 10031
TEST_TIME 42 9946
TEST_TIME 43 9789
TEST_TIME 44 9835
TEST_TIME 45 9691
TEST_TIME 46 9648
TEST_TIME 47 9649
TEST_TIME 48 9779
TEST_TIME 49 9793
TEST_TIME 50 9887
TEST_TIME 51 9846
TEST_TIME 52 9861
TEST_TIME 53 9816
TEST_TIME 54 9930
TEST_TIME 55 9893
TEST_TIME 56 10003
TEST_TIME 57 9882
TEST_TIME 58 9736
TEST_TIME 59 9701
TEST_TIME 60 9643
TEST_TIME 61 9569
TEST_TIME 62 9586
TEST_TIME 63 9535
TEST_TIME 64 9531
TEST_TIME 65 9569
TEST_TIME 66 9567
TEST_TIME 67 9582
TEST_TIME 68 9536
TEST_TIME 69 9504
TEST_TIME 70 9548
TEST_TIME 71 9560
TEST_TIME 72 9533
TEST_TIME 73 9541
TEST_TIME 74 9510
TEST_TIME 75 9597
TEST_TIME 76 9576
TEST_TIME 77 9496
TEST_TIME 78 9440
TEST_TIME 79 9356
TEST_TIME 80 9294
TEST_TIME 81 9248
TEST_TIME 82 9207
TEST_TIME 83 9237
TEST_TIME 84 9197
TEST_TIME 85 9225
TEST_TIME 86 9181
TEST_TIME 87 9187
TEST_TIME 88 9175
TEST_TIME 89 9168
TEST_TIME 90 9229
TEST_TIME 91 9289
TEST_TIME 92 9316
TEST_TIME 93 9367
TEST_TIME 94 9352
TEST_TIME 95 9261
TEST_TIME 96 9239
TEST_TIME 97 9233
TEST_TIME 98 9234
TEST_TIME 99 9232
TEST_TIME 100 9209
TEST_TIME 101 9185
TEST_TIME 102 9282
TEST_TIME 103 9261
TEST_TIME 104 9249
TEST_TIME 105 9280
TEST_TIME 106 9317
TEST_TIME 107 9301
TEST_TIME 108 9373
TEST_TIME 109 9338
TEST_TIME 110 9255
TEST_TIME 111 9205
TEST_TIME 112 9212
TEST_TIME 113 9152
TEST_TIME 114 9128
TEST_TIME 115 9123
TEST_TIME 116 9122
TEST_TIME 117 9134
TEST_TIME 118 9141
TEST_TIME 119 9118
TEST_TIME 120 9044

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-06-2021 04:28:03 to 15-06-2021 04:28:07.

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 1519743 1519743 0 0.00 0 18927 7 0 0
doc_list_biud 1519741 1519741 0 0.00 0 36060 29 0 0
sp_add_invoice_to_stock 5251 5161 90 1.71 28 187704 17312 0 90
sp_cancel_adding_invoice 5436 5436 0 0.00 6 99949 838 0 0
sp_cancel_client_order 1767 1767 0 0.00 5 20954 305 0 0
sp_cancel_customer_reserve 526902 526902 0 0.00 1 26694 64 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 3638 69 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 2337 65 0 0
sp_cancel_supplier_invoice 6083 6080 3 0.04 3 36063 1008 0 3
sp_cancel_supplier_order 405 405 0 0.00 8 1205407 107546 0 0
sp_cancel_write_off 10334 10334 0 0.00 1 11477 111 0 0
sp_client_order 21353 21353 0 0.00 4 51905 372 0 0
sp_customer_reserve 470032 470032 0 0.00 0 65089 173 0 0
sp_fill_shopping_cart 32795 32795 0 0.00 1 16796 41 0 0
sp_fill_shopping_cart_clo_res 470025 470025 0 0.00 0 16109 17 0 0
sp_get_clo_for_invoice 5153 5153 0 0.00 0 22982 389 0 0
sp_kill_cost_storno 16886 16886 0 0.00 0 11470 52 0 0
sp_kill_qstorno_ret_qs2qd 1070623 1070623 0 0.00 0 36059 36 0 0
sp_kill_qty_storno 566380 566380 0 0.00 0 36060 76 0 0
sp_lock_selected_doc 21197 21197 0 0.00 0 7452 6 0 0
sp_make_cost_storno 28606 28606 0 0.00 0 12835 56 0 0
sp_multiply_rows_for_pdistr 28606 28606 0 0.00 0 11515 30 0 0
sp_multiply_rows_for_qdistr 21353 21353 0 0.00 0 13982 200 0 0
sp_pay_from_customer 7211 7211 0 0.00 4 12278 111 0 0
sp_pay_to_supplier 5939 5939 0 0.00 5 12369 127 0 0
sp_payment_common 13149 13149 0 0.00 1 10880 21 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 20924 284 0 0
sp_qd_handle_on_invoice_upd_sts 10589 10589 0 0.00 0 30585 355 0 0
sp_qd_handle_on_reserve_upd_sts 10303 10303 0 0.00 0 10736 44 0 0
sp_reserve_write_off 10303 10303 0 0.00 7 17910 168 0 0
sp_supplier_invoice 5713 5713 0 0.00 6 127956 3589 0 0
sp_supplier_order 5735 5735 0 0.00 6 19515 1330 0 0
srv_aggregate_perf_data 107 107 0 0.00 2558 89265 12154 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1881 172826 43528 0 0
srv_find_qd_qs_mism 1035933 1035933 0 0.00 0 11487 6 0 0
srv_make_invnt_saldo 495 495 0 0.00 43 115161 3024 0 0
srv_make_money_saldo 756 756 0 0.00 0 10977 473 0 0
srv_recalc_idx_stat 4 4 0 0.00 137690 428802 294288 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5161 5161 0 0.00 0 9878 148 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 1899 76 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3773 9 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 2454 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 7715 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1653 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 765 10 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 18148 1557 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1883 9 0 0
t$perf-norm:sp_client_order 21353 21353 0 0.00 0 8798 16 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 3207 8 0 0
t$perf-norm:sp_pay_from_customer 7211 7211 0 0.00 0 3849 7 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 6326 11 0 0
t$perf-norm:sp_reserve_write_off 10303 10303 0 0.00 0 4948 11 0 0
t$perf-norm:sp_supplier_invoice 5713 5713 0 0.00 0 6600 16 0 0
t$perf-norm:sp_supplier_order 5735 5735 0 0.00 0 3420 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 126 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 214 6 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 1198 7 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 1830 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10486 10306 180 1.71 0 5110 16 0 180
v_all_customers 38670 38670 0 0.00 0 67 1 0 0
v_all_suppliers 31240 31240 0 0.00 0 70 1 0 0
v_all_wares 42706 42706 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3472 12 0 0
v_cancel_client_order 3534 3534 0 0.00 0 3067 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1967 6 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 853 4 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 892 5 0 0
v_cancel_supplier_invoice 820 814 6 0.73 0 5210 22 0 6
v_cancel_supplier_order 810 810 0 0.00 0 921 6 0 0
v_cancel_write_off 1950 1950 0 0.00 0 564 10 0 0
v_max_id_clo_ord 5735 5735 0 0.00 0 1194 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2147 11 0 0
v_max_id_ord_sup 5707 5707 0 0.00 0 10843 6 0 0
v_max_non_paid_invoice 1761 1761 0 0.00 0 1661 3 0 0
v_max_non_paid_realizn 3804 3804 0 0.00 0 4856 5 0 0
v_min_id_clo_ord 5735 5735 0 0.00 0 11252 9 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 6796 21 0 0
v_min_id_ord_sup 5707 5707 0 0.00 0 4045 5 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 7998 13 0 0
v_min_non_paid_realizn 7210 7210 0 0.00 0 7094 15 0 0
v_our_firm 10846 10846 0 0.00 0 16 1 0 0
v_reserve_write_off 20606 20606 0 0.00 0 5253 5 0 0
x_lock_depdocs_on_canc_invoice 11109 11109 0 0.00 0 13870 104 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2969 292 0 0
x_make_qstorno_1000_1200 5735 5735 0 0.00 2 19497 1254 0 0
x_make_qstorno_1200_2000 5713 5713 0 0.00 3 127943 3437 0 0
x_make_qstorno_2100_3300 470031 470031 0 0.00 0 58155 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2021 04:28:08 to 15-06-2021 04:28:12.

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
01:57:04 6 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
01:58:05 28769 8192000000 9724104160 10021826560 800753744 8.904 100 86 174 0 829244400 7760352 20730304
01:59:43 31438 8192000000 9770733440 10077257728 840627568 9.306 100 91 183 1 875799696 13833536 21338592
02:01:20 12978 8192000000 9810471504 10132463616 878033104 9.680 100 74 147 0 920059488 25182832 16843552
02:02:35 15607 8192000000 9896448256 10232561664 937633808 10.270 100 81 162 0 1002205536 47094032 17477696
02:03:53 8642 8192000000 9948944192 10300715008 977037664 10.655 100 80 160 0 1064629952 71287168 16305120
02:05:04 2546 8192000000 9978351760 10337038336 1004461984 10.922 100 67 135 0 1094642656 76385040 13795632
02:06:09 2340 8192000000 9995361568 10360700928 1035655504 11.223 100 83 167 2 1111025360 58569520 16800336
02:07:13 3127 8192000000 10024743104 10393591808 1061709040 11.473 100 83 162 0 1130519328 50885616 17924672
02:08:18 3799 8192000000 10051073008 10425430016 1069716080 11.549 100 72 146 0 1160908640 75778560 15414000
02:09:22 3283 8192000000 10037818704 10432528384 1075652224 11.606 100 50 100 0 1158860368 72677936 10530208
02:10:27 37061 8192000000 10005418080 10417770496 1081050432 11.657 100 44 88 2 1129047808 38388464 9608912
02:12:05 5250 8192000000 10000789040 10393767936 1087343920 11.717 100 32 62 0 1113163920 18827920 6992080
02:13:12 3838 8192000000 10019395488 10418790400 1095960272 11.799 100 45 90 0 1138915312 31604928 11350112
02:14:17 7560 8192000000 10005743808 10413412352 1097490976 11.814 100 33 64 2 1132301760 27523392 7287392
02:15:27 20128 8192000000 10031741008 10428903424 1100695296 11.844 100 53 107 2 1141021616 28641072 11685248
02:16:47 7682 8192000000 10012172544 10423005184 1106258864 11.897 100 36 70 1 1141201520 27235360 7707296
02:17:58 5926 8192000000 10001947760 10388819968 1058589776 11.443 97 53 104 0 1088832112 17782752 12459584
02:19:05 4086 8192000000 9962548944 10352852992 1054258768 11.402 100 55 109 2 1080910192 14347856 12303568
02:20:11 33879 8192000000 9950940576 10352508928 966901296 10.556 92 34 68 1 1014769248 40875424 6992528
02:21:45 7816 8192000000 9922417040 10315124736 997087840 10.850 97 43 86 1 1018942192 11205552 10648800
02:22:54 6069 8192000000 9964543200 10355159040 1044749728 11.310 100 42 80 1 1072309968 18607280 8952960
02:24:04 68052 8192000000 9964060480 10373287936 1053788368 11.397 100 27 54 1 1082688464 22889888 6010208
02:26:13 8512 8192000000 9980092816 10388664320 1068104960 11.534 100 42 84 1 1088253696 10664800 9483936
02:27:24 8443 8192000000 10041718432 10433040384 1101696848 11.854 100 75 149 3 1149400432 32100176 15603408
02:28:51 27332 8192000000 10075564080 10479575040 1119602320 12.023 100 42 84 1 1178795824 50737424 8456080
02:30:27 24930 8192000000 10096699600 10512932864 1134575184 12.164 100 42 83 1 1205344032 61090960 9677888
02:31:55 19832 8192000000 10144959920 10555256832 1140856848 12.224 100 46 92 0 1246068512 95278176 9933488
02:33:20 12999 8192000000 10158591200 10572443648 1152828592 12.336 100 42 84 0 1273082080 110343152 9910336
02:34:39 8667 8192000000 10210793296 10623291392 1165298736 12.453 100 54 107 2 1318477536 141564816 11613984
02:35:52 3981 8192000000 10261718240 10683723776 1181526096 12.604 100 66 130 2 1366269120 170015744 14727280
02:37:08 2022 8192000000 10295316960 10711957504 1180280112 12.593 100 75 151 2 1401273904 204319728 16674064
02:38:20 2441 8192000000 10328002496 10743447552 1184172080 12.629 100 74 148 0 1438149408 237275280 16702048
02:39:30 5186 8192000000 10339528256 10750775296 1181439344 12.604 100 76 152 0 1442233840 243590576 17203920
02:40:42 3257 8192000000 10359869904 10770849792 1173225488 12.527 100 71 141 0 1460071696 270649664 16196544
02:41:51 4048 8192000000 10392877776 10805837824 1175302768 12.546 100 65 130 0 1497050928 306030128 15718032
02:42:59 3893 8192000000 10396005136 10799648768 1155581168 12.362 100 86 173 0 1492629232 316368512 20679552
02:44:07 11842 8192000000 10408579360 10841022464 1172519440 12.520 100 57 111 0 1520644672 334985808 13139424
02:45:22 1875 8192000000 10381840816 10799968256 1154809824 12.355 100 79 157 2 1477437200 303848864 18778512
02:46:31 6660 8192000000 10355602112 10771165184 1140980336 12.225 100 57 113 0 1456202624 301514720 13707568
02:47:40 17106 8192000000 10370944608 10815070208 1122802528 12.053 99 38 75 4 1456784688 323964928 10017232
02:48:59 11679 8192000000 10365651792 10799456256 1118778352 12.015 99 33 66 1 1457283072 329284256 9220464
02:50:26 21486 8192000000 10311121520 10786201600 1106739616 11.902 98 29 55 1 1419896544 306197248 6959680
02:51:48 13535 8192000000 10361050912 10828292096 1106335728 11.898 99 25 48 1 1474164592 360808576 7020288
02:53:02 6957 8192000000 10287791072 10737774592 1100871440 11.846 99 23 46 1 1386370928 278542320 6957168
02:54:11 61403 8192000000 10065551184 10495942656 1098089632 11.820 98 22 44 0 1170843648 67864192 4889824
02:56:15 33876 8192000000 10133873296 10633162752 1128786672 12.110 100 16 32 1 1243684576 110770800 4127104
02:58:22 13680 8192000000 10058297776 10483556352 1129277856 12.115 100 58 114 1 1170458256 29005072 12175328
02:59:39 105401 8192000000 10035476880 10481586176 1092372016 11.765 97 44 87 0 1132690352 31287712 9030624
03:02:28 24661 8192000000 10052339456 10517381120 1126447392 12.088 100 7 14 0 1158990416 31370256 1172768
03:03:56 57415 8192000000 10090787104 10538704896 1137628800 12.193 100 27 54 0 1201024464 57709216 5686448
03:05:57 38918 8192000000 10132013856 10587435008 1142798768 12.242 100 27 55 0 1235271200 86627136 5845296
03:07:45 59966 8192000000 10096763104 10547408896 1142512736 12.239 100 23 46 2 1191870480 44053648 5304096
03:09:55 77813 8192000000 10118911472 10602467328 1149603904 12.306 100 16 32 0 1230325472 77166160 3555408
03:12:13 63623 8192000000 10079591136 10544869376 1101855312 11.855 96 18 35 1 1153537472 47850224 3831936
03:14:17 31606 8192000000 10116865536 10566025216 1150855040 12.318 100 24 48 2 1220829104 65053376 4920688
03:15:50 84363 8192000000 10105720592 10614571008 1152754176 12.335 100 9 18 0 1228997856 74190240 2053440
03:18:14 22213 8192000000 10109332832 10561056768 1140245584 12.218 99 35 68 2 1197082928 50189280 6648064
03:19:41 20581 8192000000 10105775040 10542424064 1150517392 12.314 100 32 63 0 1201023168 44438704 6067072
03:21:03 81692 8192000000 10106137936 10618228736 1109219232 11.925 96 11 22 0 1164026048 52495568 2311248
03:23:49 19788 8192000000 10104648592 10528485376 1141668896 12.231 100 26 52 1 1178061248 31356384 5035968
03:25:09 81875 8192000000 10079376912 10559823872 1142409392 12.238 100 26 52 0 1203706400 56016896 5280112
03:28:30 34700 8192000000 10079167184 10552463360 1148667904 12.297 100 5 11 0 1197227984 47672320 887760
03:30:08 72865 8192000000 10094127568 10570145792 1095920256 11.799 95 15 29 2 1142089632 42951616 3217760
03:32:21 43762 8192000000 10065631408 10539560960 1090978784 11.752 95 20 40 0 1154448528 58961920 4507824
03:34:08 47381 8192000000 10065307408 10527465472 1119566416 12.023 99 23 46 1 1170710400 46374976 4769008
03:35:55 49829 8192000000 10098183888 10545672192 1111353488 11.945 98 43 86 2 1168666608 48580720 8732400
03:37:47 26207 8192000000 10061953520 10488594432 1082514080 11.671 97 34 68 1 1131125520 41789024 6822416
03:39:14 41456 8192000000 10071173104 10496512000 1077156656 11.620 97 32 64 1 1124952384 41479504 6316224
03:40:58 12132 8192000000 10087899760 10504990720 1098062816 11.819 100 54 108 0 1157939248 49214912 10661520
03:42:12 89302 8192000000 10037115008 10552238080 1079427920 11.642 98 10 20 0 1196288400 114565600 2294880
03:44:55 68502 8192000000 10008442240 10454794240 1082248800 11.669 98 13 26 0 1107622736 23040560 2333376
03:47:07 63019 8192000000 10031641536 10482532352 1109266176 11.925 100 4 9 0 1132376576 22359696 750704
03:49:14 90006 8192000000 10037409632 10529366016 1106960528 11.904 99 15 30 3 1142195136 32224400 3010208
03:52:26 49993 8192000000 10045988672 10490445824 1118269680 12.011 100 15 30 0 1147954272 26656144 3028448
03:54:17 91058 8192000000 10119571344 10583904256 1125120880 12.075 99 23 46 0 1200127536 69323968 5682688
03:56:52 66799 8192000000 10140386816 10604220416 1122474736 12.050 98 26 52 1 1236176512 108169440 5532336
03:59:35 75428 8192000000 10100317808 10573037568 1128715728 12.109 98 13 26 1 1190296192 59204544 2375920
04:03:58 111548 8192000000 10090261616 10558537728 1129507968 12.117 98 11 23 0 1181677568 49699328 2470272
04:07:03 30079 8192000000 10128628320 10572402688 1152827600 12.336 100 31 62 2 1207324784 47383200 7113984
04:08:33 151184 8192000000 10074132464 10519887872 1085168608 11.697 94 13 26 0 1167360096 79402384 2789104
04:12:05 36419 8192000000 10094686144 10530545664 1083786352 11.684 96 37 74 2 1158617632 66883504 7947776
04:14:01 47082 8192000000 10127885584 10594394112 1112181904 11.953 98 20 40 0 1168323280 52475360 3666016
04:15:48 172826 8192000000 10085692336 10543316992 1091619504 11.758 96 21 42 0 1175444752 78332464 5492784
04:21:57 61379 8192000000 10101630208 10625609728 1128054448 12.103 99 11 22 0 1195887360 65368480 2464432
04:23:59 68099 8192000000 10045032624 10514284544 1092112000 11.763 97 17 33 1 1154665856 58922848 3631008
04:26:13 44076 8192000000 10063614288 10500657152 1084012240 11.686 96 21 42 0 1118424336 29888560 4523536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2021 04:28:14 to 15-06-2021 04:28:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-06-2021 04:28:30 to 15-06-2021 04:28:31.

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          237926
MON$OLDEST_ACTIVE               239617
MON$OLDEST_SNAPSHOT             239617
MON$NEXT_TRANSACTION            239617
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                       2103168
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             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2516 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2516 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2516 Firebird 4.0/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 ---
:105: 
:106: mscs-test-161.budzdorov.ru    Tue Jun 15 04:27:03 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Tue Jun 15 04:27:05 2021
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Tue Jun 15 04:27:05 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Tue Jun 15 04:27:07 2021
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Tue Jun 15 04:27:08 2021
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
--- end of diff output ---

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

15.06.21 04:28:32. 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-06-15 04:28:35 No severe PSQL-related problems occured
15.06.21 04:28:36. End of report.