Drupal Quality Assurance

[11:33:01] Log initialized (/var/lib/drupaltestbot/sites/default/files/review.test_1181053.pifr_simpletest.commerce_checkout.pages-403-instead-of-404-2605012-2.patch.1446229981.log)
[11:33:01] Review timestamp: (Fri, 10/30/2015 - 11:33)
[11:33:01] Review host: (ec2-54-69-10-31)
[11:33:01] Operations:Array
(
    [0] => setup
    [1] => fetch
    [2] => checkout
    [3] => check
    [4] => apply
    [5] => syntax
    [6] => install
    [7] => review
)

[11:33:01] Result Codes: Array
(
    [fail] => 9
    [pass] => 10
)

[11:33:01] Environment variables and review information initialized.
[11:33:01] core_url = [git://git.drupal.org/project/drupal.git];  Test repository = [git://git.drupal.org/project/commerce.git]
[11:33:01] Project directory is [commerce]
[11:33:01] Review started.
[11:33:01] Test: Array
(
    [test_id] => 1181053
    [review] => Array
        (
            [plugin] => pifr_simpletest
            [argument] => Array
                (
                    [test.title] => 
                    [test.directory.review] => Array
                        (
                            [0] => sites/default/modules/commerce
                        )

                    [test.directory.apply] => 
                    [test.directory.dependency] => 
                    [test.files] => Array
                        (
                        )

                    [test.extensions] => Array
                        (
                            [0] => php
                            [1] => inc
                            [2] => install
                            [3] => module
                            [4] => test
                        )

                    [drupal.core.version] => 7
                    [drupal.core.url] => git://git.drupal.org/project/drupal.git
                    [drupal.modules] => Array
                        (
                        )

                    [drupal.user] => admin
                    [drupal.pass] => 
                    [drupal.variables] => Array
                        (
                        )

                    [simpletest.tests] => Array
                        (
                        )

                    [simpletest.db] => 
                    [test.php.version] => 5.3
                )

        )

    [vcs] => Array
        (
            [main] => Array
                (
                    [repository] => Array
                        (
                            [type] => git
                            [url] => git://git.drupal.org/project/drupal.git
                        )

                    [vcs_identifier] => 7.x
                )

            [dependencies] => Array
                (
                    [1] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/entity.git
                                )

                            [vcs_identifier] => 7.x-1.6
                        )

                    [2] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/rules.git
                                )

                            [vcs_identifier] => 7.x-2.9
                        )

                    [3] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/addressfield.git
                                )

                            [vcs_identifier] => 7.x-1.2
                        )

                    [4] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/ctools.git
                                )

                            [vcs_identifier] => 7.x-1.7
                        )

                    [5] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/views.git
                                )

                            [vcs_identifier] => 7.x-3.11
                        )

                    [6] => Array
                        (
                            [repository] => Array
                                (
                                    [type] => git
                                    [url] => git://git.drupal.org/project/commerce.git
                                )

                            [vcs_identifier] => 7.x-1.11
                        )

                )

        )

    [files] => Array
        (
            [0] => https://www.drupal.org/files/issues/commerce_checkout.pages-403-instead-of-404-2605012-2.patch
        )

)

[11:33:01] Operations: Array
(
    [0] => setup
    [1] => fetch
    [2] => checkout
    [3] => check
    [4] => apply
    [5] => syntax
    [6] => install
    [7] => review
)

[11:33:01] Invoking operation [setup]...
[11:33:01] Command [chmod -R u+rw sites/default/files/checkout 2>&1] succeeded.
[11:33:01] Command [rm -rf sites/default/files/checkout 2>&1] succeeded.
[11:33:01] Command [rm -rf /tmp/po* 2>&1] succeeded.
[11:33:01] Command [rm -rf sites/default/files/review 2>&1] succeeded.
[11:33:01] Database backend [mysql] loaded.
[11:33:01] Invoking operation [fetch]...
[11:33:01] Fetched [commerce_checkout.pages-403-instead-of-404-2605012-2.patch].
[11:33:01] Invoking operation [checkout]...
[11:33:01] Version control system backend [git] loaded.
[11:33:03] Command [git clone 'git://git.drupal.org/project/drupal.git' 'checkout' --reference /var/cache/git/reference 2>&1] succeeded.
[11:33:04] Command [git --work-tree='checkout' --git-dir='checkout/.git' checkout '7.x' 2>&1] succeeded.
[11:33:04] Command [git --work-tree='checkout' --git-dir='checkout/.git' status 2>&1] succeeded.
[11:33:04] Command [git --work-tree='checkout' --git-dir='checkout/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files]
  Completion status: [0]
  Output: [405e861 Issue #1862250 by amanire, Ivan Zugec, Novitsh, joates, sdstyles, yoroy, joshi.rohit100, acabouet, rpayanm, klonos, dahousecat, Daniel_Rose, HeimdallJHM, cluther, vineet.osscube, joachim, Stalski, Bojhan, chris_h: The "Trim length" format description is confusing/not clear to Drupal newcomers].
[11:33:04] Main branch [7.x] checkout [complete].
[11:33:18] Command [git clone 'git://git.drupal.org/project/entity.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity' --reference /var/cache/git/reference 2>&1] succeeded.
[11:33:18] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity/.git' checkout '7.x-1.6' 2>&1] succeeded.
[11:33:18] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity/.git' status 2>&1] succeeded.
[11:33:18] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/entity/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [724e719 Patch by klausi: Sanitize field labels before passing them to the Token API.].
[11:33:18] Dependency branch [7.x-1.6] checkout [complete].
[11:33:34] Command [git clone 'git://git.drupal.org/project/rules.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules' --reference /var/cache/git/reference 2>&1] succeeded.
[11:33:34] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules/.git' checkout '7.x-2.9' 2>&1] succeeded.
[11:33:34] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules/.git' status 2>&1] succeeded.
[11:33:34] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/rules/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [db14bda Issue #2324587 by klausi, das-peter, fago: Rules might be triggered too early in the bootstrap].
[11:33:34] Dependency branch [7.x-2.9] checkout [complete].
[11:33:48] Command [git clone 'git://git.drupal.org/project/addressfield.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield' --reference /var/cache/git/reference 2>&1] succeeded.
[11:33:48] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield/.git' checkout '7.x-1.2' 2>&1] succeeded.
[11:33:48] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield/.git' status 2>&1] succeeded.
[11:33:48] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/addressfield/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [c8a4d1f Issue #2507665 by joelpittet: addressfield_get_administrative_areas calls t() nearly 1000 times in one call].
[11:33:48] Dependency branch [7.x-1.2] checkout [complete].
[11:34:04] Command [git clone 'git://git.drupal.org/project/ctools.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools' --reference /var/cache/git/reference 2>&1] succeeded.
[11:34:04] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools/.git' checkout '7.x-1.7' 2>&1] succeeded.
[11:34:04] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools/.git' status 2>&1] succeeded.
[11:34:04] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/ctools/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [7b975e4 ctools 1.7].
[11:34:04] Dependency branch [7.x-1.7] checkout [complete].
[11:34:12] Command [git clone 'git://git.drupal.org/project/views.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views' --reference /var/cache/git/reference 2>&1] succeeded.
[11:34:12] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views/.git' checkout '7.x-3.11' 2>&1] succeeded.
[11:34:12] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views/.git' status 2>&1] succeeded.
[11:34:12] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/views/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [cef693b Issue by das-peter: Ensure that we do not end up with broken views data cache entries.].
[11:34:12] Dependency branch [7.x-3.11] checkout [complete].
[11:34:27] Command [git clone 'git://git.drupal.org/project/commerce.git' '/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce' --reference /var/cache/git/reference 2>&1] succeeded.
[11:34:27] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce/.git' checkout '7.x-1.11' 2>&1] succeeded.
[11:34:27] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce/.git' status 2>&1] succeeded.
[11:34:27] Command [git --work-tree='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce' --git-dir='/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules/commerce/.git' log --oneline -1 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout/sites/default/modules]
  Completion status: [0]
  Output: [40a7e55 Issue #2332333 by torgosPizza: Remove the default product entity from the form state just prior to caching].
[11:34:27] Dependency branch [7.x-1.11] checkout [complete].
[11:34:27] Invoking operation [check]...
[11:34:27] Invoking operation [apply]...
[11:34:27] Command [git apply --check -p1 /var/lib/drupaltestbot/sites/default/files/review/commerce_checkout.pages-403-instead-of-404-2605012-2.patch 2>&1] succeeded.
[11:34:27] Command [git apply -v -p1 /var/lib/drupaltestbot/sites/default/files/review/commerce_checkout.pages-403-instead-of-404-2605012-2.patch 2>&1] succeeded.
[11:34:27] Applied [commerce_checkout.pages-403-instead-of-404-2605012-2.patch] to:
 > modules/checkout/includes/commerce_checkout.pages.inc
[11:34:27] Invoking operation [syntax]...
[11:34:27] Command [php -l -f './sites/default/modules/commerce/modules/checkout/includes/commerce_checkout.pages.inc' 2>&1] succeeded.
[11:34:27] Checked [1 of 1] relevant file(s) for syntax.
[11:34:27] Invoking operation [install]...
[11:34:27] drush si command=cd sites/default/files/checkout && drush si -y --db-url=mysql://drupaltestbot-my:kYQMxYXyYtmr@localhost/drupaltestbotmysql --clean-url=0 --account-name=admin --account-pass=drupal --account-mail=admin@example.com
[11:34:31] Command [cd sites/default/files/checkout && drush si -y --db-url=mysql://drupaltestbot-my:kYQMxYXyYtmr@localhost/drupaltestbotmysql --clean-url=0 --account-name=admin --account-pass=drupal --account-mail=admin@example.com 2>&1] succeeded
  Duration: 4 seconds
  Directory: [/var/lib/drupaltestbot]
  Completion status: [0]
  Output: [You are about to create a /var/lib/drupaltestbot/sites/default/files/checkout/sites/default/settings.php file and DROP all tables in your 'drupaltestbotmysql' database. Do you want to continue? (y/n): y
Starting Drupal installation. This takes a while. Consider using the        [ok]
--notify global option.
Installation complete.  User name: admin  User password: drupal             [ok]].
[11:34:31] Command [cd /var/lib/drupaltestbot 2>&1] succeeded.
[11:34:32] Command [cd sites/default/files/checkout && drush vset simpletest_clear_results '0' 2>&1] succeeded
  Duration: 1 seconds
  Directory: [/var/lib/drupaltestbot]
  Completion status: [0]
  Output: [simpletest_clear_results was set to "0".                               [success]].
[11:34:32] Command [cd /var/lib/drupaltestbot 2>&1] succeeded.
[11:34:32] Command [cd sites/default/files/checkout && drush vset simpletest_verbose '0' 2>&1] succeeded
  Duration: 0 seconds
  Directory: [/var/lib/drupaltestbot]
  Completion status: [0]
  Output: [simpletest_verbose was set to "0".                                     [success]].
[11:34:32] Command [cd /var/lib/drupaltestbot 2>&1] succeeded.
[11:34:32] Invoking operation [review]...
[11:34:33] Command [cd sites/default/files/checkout && drush en -y simpletest 2>&1] succeeded
  Duration: 1 seconds
  Directory: [/var/lib/drupaltestbot]
  Completion status: [0]
  Output: [The following extensions will be enabled: simpletest
Do you really want to continue? (y/n): y
simpletest was enabled successfully.                                        [ok]
simpletest defines the following permissions: administer unit tests].
[11:34:33] Command [cd /var/lib/drupaltestbot 2>&1] succeeded.
[11:34:33] Scanning sites/default/files/checkout/sites/default/modules/commerce for .test files.
[11:34:33] Scanning sites/default/files/checkout/sites/default/modules/commerce for /Tests/*.php files.
[11:34:33] Cleaning run-tests.sh environment.
[11:34:33] Command [/usr/bin/php ./scripts/run-tests.sh --concurrency 8 --php /usr/bin/php --url http://ec2-54-69-10-31.us-west-2.compute.amazonaws.com/checkout --file sites/default/modules/commerce/modules/cart/tests/commerce_cart.test,sites/default/modules/commerce/modules/checkout/tests/commerce_checkout.test,sites/default/modules/commerce/modules/customer/tests/commerce_customer_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order.test,sites/default/modules/commerce/modules/order/tests/commerce_order.rules.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment_ui.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment.rules.test,sites/default/modules/commerce/modules/product/tests/commerce_product_ui.test,sites/default/modules/commerce/modules/product/tests/commerce_product.test,sites/default/modules/commerce/modules/product_reference/tests/commerce_product_reference.test,sites/default/modules/commerce/modules/tax/tests/commerce_tax_ui.test,sites/default/modules/commerce/tests/commerce_base.test --clean 2>&1] succeeded.
[11:34:33] Initiating run-tests.sh.
[11:40:54] Command [/usr/bin/php ./scripts/run-tests.sh --concurrency 8 --php /usr/bin/php --url http://ec2-54-69-10-31.us-west-2.compute.amazonaws.com/checkout --file sites/default/modules/commerce/modules/cart/tests/commerce_cart.test,sites/default/modules/commerce/modules/checkout/tests/commerce_checkout.test,sites/default/modules/commerce/modules/customer/tests/commerce_customer_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order.test,sites/default/modules/commerce/modules/order/tests/commerce_order.rules.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment_ui.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment.rules.test,sites/default/modules/commerce/modules/product/tests/commerce_product_ui.test,sites/default/modules/commerce/modules/product/tests/commerce_product.test,sites/default/modules/commerce/modules/product_reference/tests/commerce_product_reference.test,sites/default/modules/commerce/modules/tax/tests/commerce_tax_ui.test,sites/default/modules/commerce/tests/commerce_base.test 2>&1] succeeded
  Duration: 381 seconds
  Directory: [/var/lib/drupaltestbot/sites/default/files/checkout]
  Completion status: [0]
  Output: [Drupal test run
---------------

Tests to be run:
 - Commerce base (CommerceBaseTesterTestCase)
 - Shopping cart anonymous to authenticated (CommerceCartTestCaseAnonymousToAuthenticated)
 - Shopping cart attributes (CommerceCartTestCaseAttributes)
 - Shopping cart multiple (CommerceCartTestCaseMultiProducts)
 - Shopping cart (CommerceCartTestCaseSimpleProduct)
 - Checkout process (CommerceCheckoutTestProcess)
 - Customer user interface (CommerceCustomerUITest)
 - Order CRUD (CommerceOrderCRUDTestCase)
 - Order Rules (CommerceOrderRulesTest)
 - Order administration (CommerceOrderUIAdminTest)
 - Payment Offsite (CommercePaymentOffsiteTest)
 - Payment Rules (CommercePaymentRulesTest)
 - Payment user interface (CommercePaymentUITest)
 - Product CRUD (CommerceProductCRUDTestCase)
 - Product reference (CommerceProductReferenceAdminTest)
 - Product administration (CommerceProductUIAdminTest)
 - Commerce sandbox (CommerceSandboxTestCase)
 - Tax administration (CommerceTaxUIAdminTest)

Test run started:
 Friday, October 30, 2015 - 18:34

Test summary
------------

Shopping cart anonymous to authenticated 34 passes, 0 fails, and 0 exceptions
Order CRUD 39 passes, 0 fails, and 0 exceptions
Order Rules 31 passes, 0 fails, and 0 exceptions
Shopping cart attributes 50 passes, 0 fails, and 0 exceptions
Payment Rules 12 passes, 0 fails, and 0 exceptions
Payment Offsite 33 passes, 5 fails, and 9 exceptions
Shopping cart multiple 94 passes, 0 fails, and 0 exceptions
Shopping cart 144 passes, 0 fails, and 0 exceptions
Product CRUD 71 passes, 0 fails, and 0 exceptions
Payment user interface 188 passes, 0 fails, and 0 exceptions
Commerce sandbox 20 passes, 1 fail, and 7 exceptions
Checkout process 349 passes, 0 fails, and 0 exceptions
Commerce base 216 passes, 0 fails, and 0 exceptions
Order administration 515 passes, 0 fails, and 0 exceptions
Customer user interface 410 passes, 10 fails, and 42 exceptions
Product reference 293 passes, 0 fails, and 0 exceptions
Product administration 476 passes, 0 fails, and 0 exceptions
Tax administration 598 passes, 0 fails, and 0 exceptions

Test run duration: 6 min 20 sec].
[11:40:54] Command [/usr/bin/php ./scripts/run-tests.sh --php /usr/bin/php --url http://ec2-54-69-10-31.us-west-2.compute.amazonaws.com/checkout --file sites/default/modules/commerce/modules/cart/tests/commerce_cart.test,sites/default/modules/commerce/modules/checkout/tests/commerce_checkout.test,sites/default/modules/commerce/modules/customer/tests/commerce_customer_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order_ui.test,sites/default/modules/commerce/modules/order/tests/commerce_order.test,sites/default/modules/commerce/modules/order/tests/commerce_order.rules.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment_ui.test,sites/default/modules/commerce/modules/payment/tests/commerce_payment.rules.test,sites/default/modules/commerce/modules/product/tests/commerce_product_ui.test,sites/default/modules/commerce/modules/product/tests/commerce_product.test,sites/default/modules/commerce/modules/product_reference/tests/commerce_product_reference.test,sites/default/modules/commerce/modules/tax/tests/commerce_tax_ui.test,sites/default/modules/commerce/tests/commerce_base.test --list 2>&1] succeeded.
[11:40:54] Review complete. test_id=1181053 result code=9 details=Array
(
    [@pass] => 3573
    [@fail] => 16
    [@exception] => 58
    [@debug] => 0
)

[11:40:54] Static variables reset.