builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0228 starttime: 1475285076.3 results: success (0) buildid: 20160930151154 builduid: 1c5206df3e7c466b91d40fbd3825d56e revision: d69d58af9bea7a2781304d3ee3ba2122b2c07abf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.299940) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.300359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.300655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.322695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.322967) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-30 18:24:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.31M=0.001s 2016-09-30 18:24:36 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.454802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.458129) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021299 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:36.506245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 18:24:36.506565) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d69d58af9bea7a2781304d3ee3ba2122b2c07abf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d69d58af9bea7a2781304d3ee3ba2122b2c07abf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-30 18:24:36,585 truncating revision to first 12 chars 2016-09-30 18:24:36,585 Setting DEBUG logging. 2016-09-30 18:24:36,585 attempt 1/10 2016-09-30 18:24:36,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d69d58af9bea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 18:24:38,269 unpacking tar archive at: fx-team-d69d58af9bea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.966530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 18:24:38.497667) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:38.501009) ========= script_repo_revision: d69d58af9bea7a2781304d3ee3ba2122b2c07abf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:38.501392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:38.501654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 18:24:38.522184) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-30 18:24:38.522451) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:24:38 INFO - MultiFileLogger online at 20160930 18:24:38 in /builds/slave/test 18:24:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:24:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:24:38 INFO - {'append_to_log': False, 18:24:38 INFO - 'base_work_dir': '/builds/slave/test', 18:24:38 INFO - 'blob_upload_branch': 'Fx-Team', 18:24:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:24:38 INFO - 'branch': 'Fx-Team', 18:24:38 INFO - 'buildbot_json_path': 'buildprops.json', 18:24:38 INFO - 'config_files': ('talos/mac_config.py',), 18:24:38 INFO - 'default_actions': ('clobber', 18:24:38 INFO - 'read-buildbot-config', 18:24:38 INFO - 'download-and-extract', 18:24:38 INFO - 'populate-webroot', 18:24:38 INFO - 'create-virtualenv', 18:24:38 INFO - 'install', 18:24:38 INFO - 'run-tests'), 18:24:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:24:38 INFO - 'download_minidump_stackwalk': True, 18:24:38 INFO - 'download_symbols': 'ondemand', 18:24:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:24:38 INFO - 'tooltool.py': '/tools/tooltool.py', 18:24:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:24:38 INFO - '/tools/misc-python/virtualenv.py')}, 18:24:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:24:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:24:38 INFO - 'installer_path': 'installer.exe', 18:24:38 INFO - 'log_level': 'info', 18:24:38 INFO - 'log_name': 'talos', 18:24:38 INFO - 'log_to_console': True, 18:24:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:24:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:24:38 INFO - 'opt_config_files': (), 18:24:38 INFO - 'pip_index': False, 18:24:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:24:38 INFO - 'cmd': ('bash', 18:24:38 INFO - '-c', 18:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:24:38 INFO - 'enabled': True, 18:24:38 INFO - 'halt_on_failure': False, 18:24:38 INFO - 'name': 'check_screen_resolution'},), 18:24:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:24:38 INFO - 'cmd': ('bash', 18:24:38 INFO - '-c', 18:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:24:38 INFO - 'enabled': True, 18:24:38 INFO - 'halt_on_failure': False, 18:24:38 INFO - 'name': 'check_screen_resolution'},), 18:24:38 INFO - 'run_cmd_checks_enabled': True, 18:24:38 INFO - 'sps_profile': False, 18:24:38 INFO - 'sps_profile_interval': 0, 18:24:38 INFO - 'suite': 'dromaeojs-e10s', 18:24:38 INFO - 'system_bits': '32', 18:24:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:24:38 INFO - 'title': 't-yosemite-r7-0228', 18:24:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:24:38 INFO - 'use_talos_json': True, 18:24:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:24:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:24:38 INFO - 'work_dir': 'build'} 18:24:38 INFO - [mozharness: 2016-10-01 01:24:38.723647Z] Running clobber step. 18:24:38 INFO - Running pre-action listener: _resource_record_pre_action 18:24:38 INFO - Running main action method: clobber 18:24:38 INFO - rmtree: /builds/slave/test/build 18:24:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:24:38 INFO - Running post-action listener: _resource_record_post_action 18:24:38 INFO - [mozharness: 2016-10-01 01:24:38.961558Z] Finished clobber step (success) 18:24:38 INFO - [mozharness: 2016-10-01 01:24:38.961680Z] Running read-buildbot-config step. 18:24:38 INFO - Running pre-action listener: _resource_record_pre_action 18:24:38 INFO - Running main action method: read_buildbot_config 18:24:38 INFO - Using buildbot properties: 18:24:38 INFO - { 18:24:38 INFO - "project": "", 18:24:38 INFO - "product": "firefox", 18:24:38 INFO - "script_repo_revision": "production", 18:24:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:24:38 INFO - "repository": "", 18:24:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:24:38 INFO - "buildid": "20160930151154", 18:24:38 INFO - "pgo_build": "False", 18:24:38 INFO - "basedir": "/builds/slave/test", 18:24:38 INFO - "buildnumber": 27, 18:24:38 INFO - "slavename": "t-yosemite-r7-0228", 18:24:38 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:24:38 INFO - "platform": "yosemite_r7", 18:24:38 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 18:24:38 INFO - "branch": "fx-team", 18:24:38 INFO - "repo_path": "integration/fx-team", 18:24:38 INFO - "revision": "d69d58af9bea7a2781304d3ee3ba2122b2c07abf", 18:24:38 INFO - "stage_platform": "macosx64", 18:24:38 INFO - "builduid": "1c5206df3e7c466b91d40fbd3825d56e", 18:24:38 INFO - "slavebuilddir": "test" 18:24:38 INFO - } 18:24:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 18:24:38 INFO - Running post-action listener: _resource_record_post_action 18:24:38 INFO - [mozharness: 2016-10-01 01:24:38.964878Z] Finished read-buildbot-config step (success) 18:24:38 INFO - [mozharness: 2016-10-01 01:24:38.965029Z] Running download-and-extract step. 18:24:38 INFO - Running pre-action listener: _resource_record_pre_action 18:24:38 INFO - Running main action method: download_and_extract 18:24:38 INFO - mkdir: /builds/slave/test/build/tests 18:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:38 INFO - trying https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 18:24:38 INFO - Downloading https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 18:24:40 INFO - Downloaded 1199 bytes. 18:24:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:24:40 INFO - Using the following test package requirements: 18:24:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:24:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 18:24:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 18:24:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'jsshell-mac.zip'], 18:24:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 18:24:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:24:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 18:24:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 18:24:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 18:24:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:24:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 18:24:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:24:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:24:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:24:40 INFO - Fetch https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:24:40 INFO - Http code: 200 18:24:40 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 18:24:40 INFO - x-amz-cf-id: l_MtWOZSwm6u2N6wB1kGslD4kEbhh06VkWPfWINIgFDxufAhOpF9gA== 18:24:40 INFO - x-amz-version-id: lVQuXOPQuzP2PuIpP9rwGJH7Bk3JRxs5 18:24:41 INFO - Expected file size: 16474124 18:24:41 INFO - Obtained file size: 16474124 18:24:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:24:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:24:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:24:47 INFO - Fetch https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:24:48 INFO - Http code: 200 18:24:48 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 18:24:48 INFO - x-amz-cf-id: 045TOnGxCVFM35-aCZYI2KKIBKP0sfjBoEzq7MeGw1fYwdDRf0G_3A== 18:24:48 INFO - x-amz-version-id: lVQuXOPQuzP2PuIpP9rwGJH7Bk3JRxs5 18:24:48 INFO - Expected file size: 16474124 18:24:48 INFO - Obtained file size: 16474124 18:24:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 18:24:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:24:55 INFO - Fetch https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 18:24:56 INFO - Http code: 200 18:24:56 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 18:24:56 INFO - x-amz-cf-id: _liaEkWRjPd7yP3VE6m1gd8CvnZTRQbiemYeiMREZpjwp2IAHBHfjA== 18:24:56 INFO - x-amz-version-id: Nvx1IvxnlqIcWiYQ7Wa7ymwzd0bGmL86 18:24:56 INFO - Expected file size: 14408522 18:24:56 INFO - Obtained file size: 14408522 18:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:56 INFO - trying https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:24:56 INFO - Downloading https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 18:24:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 18:24:59 INFO - Downloaded 111785308 bytes. 18:24:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:24:59 INFO - mkdir: /builds/slave/test/properties 18:24:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:24:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:24:59 INFO - Contents: 18:24:59 INFO - build_url:https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:25:00 INFO - Running post-action listener: _resource_record_post_action 18:25:00 INFO - Running post-action listener: set_extra_try_arguments 18:25:00 INFO - [mozharness: 2016-10-01 01:25:00.278976Z] Finished download-and-extract step (success) 18:25:00 INFO - [mozharness: 2016-10-01 01:25:00.279098Z] Running populate-webroot step. 18:25:00 INFO - Running pre-action listener: _resource_record_pre_action 18:25:00 INFO - Running main action method: populate_webroot 18:25:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:25:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:25:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:25:00 INFO - '--tppagecycles', 18:25:00 INFO - '7'], 18:25:00 INFO - 'tests': ['tsvgm']}}, 18:25:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:25:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:25:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['damp', 'tps']}, 18:25:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'tests': ['damp', 'tps']}, 18:25:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['dromaeo_dom']}, 18:25:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['basic_compositor_video']}, 18:25:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:25:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['a11yr', 18:25:00 INFO - 'ts_paint', 18:25:00 INFO - 'tpaint', 18:25:00 INFO - 'sessionrestore', 18:25:00 INFO - 'sessionrestore_no_auto_restore', 18:25:00 INFO - 'tabpaint']}, 18:25:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['tsvgx', 18:25:00 INFO - 'tsvgr_opacity', 18:25:00 INFO - 'tart', 18:25:00 INFO - 'tscrollx', 18:25:00 INFO - 'cart']}, 18:25:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:00 INFO - 'tsvgr_opacity', 18:25:00 INFO - 'tart', 18:25:00 INFO - 'tscrollx', 18:25:00 INFO - 'cart']}, 18:25:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'talos_options': ['--disable-e10s'], 18:25:00 INFO - 'tests': ['tp5o']}, 18:25:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'tests': ['tp5o']}, 18:25:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'talos_options': ['--disable-e10s', 18:25:00 INFO - '--xperf_path', 18:25:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:00 INFO - 'tests': ['tp5n']}, 18:25:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:25:00 INFO - 'talos_options': ['--xperf_path', 18:25:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:00 INFO - 'tests': ['tp5n']}}, 18:25:00 INFO - 'talos.zip': {'path': '', 18:25:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:25:00 INFO - Running post-action listener: _resource_record_post_action 18:25:00 INFO - [mozharness: 2016-10-01 01:25:00.298044Z] Finished populate-webroot step (success) 18:25:00 INFO - [mozharness: 2016-10-01 01:25:00.298176Z] Running create-virtualenv step. 18:25:00 INFO - Running pre-action listener: _resource_record_pre_action 18:25:00 INFO - Running main action method: create_virtualenv 18:25:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:25:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:25:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:00 INFO - Using real prefix '/tools/python27' 18:25:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:03 INFO - Installing pip.................done. 18:25:03 INFO - Return code: 0 18:25:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:04 INFO - Reading from file tmpfile_stdout 18:25:04 INFO - Output received: 18:25:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:25:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:25:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:04 INFO - 'HOME': '/Users/cltbld', 18:25:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:04 INFO - 'LOGNAME': 'cltbld', 18:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:04 INFO - 'MOZ_NO_REMOTE': '1', 18:25:04 INFO - 'NO_EM_RESTART': '1', 18:25:04 INFO - 'PAGER': '/bin/cat', 18:25:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:04 INFO - 'PWD': '/builds/slave/test', 18:25:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:04 INFO - 'SHELL': '/bin/bash', 18:25:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:04 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:04 INFO - 'USER': 'cltbld', 18:25:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:04 INFO - 'XPC_FLAGS': '0x0', 18:25:04 INFO - 'XPC_SERVICE_NAME': '0', 18:25:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:25:04 INFO - Cleaning up... 18:25:04 INFO - Return code: 0 18:25:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:25:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:04 INFO - 'HOME': '/Users/cltbld', 18:25:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:04 INFO - 'LOGNAME': 'cltbld', 18:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:04 INFO - 'MOZ_NO_REMOTE': '1', 18:25:04 INFO - 'NO_EM_RESTART': '1', 18:25:04 INFO - 'PAGER': '/bin/cat', 18:25:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:04 INFO - 'PWD': '/builds/slave/test', 18:25:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:04 INFO - 'SHELL': '/bin/bash', 18:25:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:04 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:04 INFO - 'USER': 'cltbld', 18:25:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:04 INFO - 'XPC_FLAGS': '0x0', 18:25:04 INFO - 'XPC_SERVICE_NAME': '0', 18:25:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:04 INFO - Downloading/unpacking psutil>=3.1.1 18:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:08 INFO - Installing collected packages: psutil 18:25:08 INFO - Running setup.py install for psutil 18:25:08 INFO - building 'psutil._psutil_osx' extension 18:25:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:25:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:25:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:25:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:25:08 INFO - building 'psutil._psutil_posix' extension 18:25:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:25:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:25:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:25:08 INFO - ^ 18:25:08 INFO - 1 warning generated. 18:25:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:25:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:09 INFO - Successfully installed psutil 18:25:09 INFO - Cleaning up... 18:25:09 INFO - Return code: 0 18:25:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:25:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:25:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:09 INFO - 'HOME': '/Users/cltbld', 18:25:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:09 INFO - 'LOGNAME': 'cltbld', 18:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:09 INFO - 'MOZ_NO_REMOTE': '1', 18:25:09 INFO - 'NO_EM_RESTART': '1', 18:25:09 INFO - 'PAGER': '/bin/cat', 18:25:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:09 INFO - 'PWD': '/builds/slave/test', 18:25:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:09 INFO - 'SHELL': '/bin/bash', 18:25:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:09 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:09 INFO - 'USER': 'cltbld', 18:25:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:09 INFO - 'XPC_FLAGS': '0x0', 18:25:09 INFO - 'XPC_SERVICE_NAME': '0', 18:25:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:11 INFO - Installing collected packages: mozsystemmonitor 18:25:11 INFO - Running setup.py install for mozsystemmonitor 18:25:12 INFO - Successfully installed mozsystemmonitor 18:25:12 INFO - Cleaning up... 18:25:12 INFO - Return code: 0 18:25:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:12 INFO - 'HOME': '/Users/cltbld', 18:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:12 INFO - 'LOGNAME': 'cltbld', 18:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:12 INFO - 'MOZ_NO_REMOTE': '1', 18:25:12 INFO - 'NO_EM_RESTART': '1', 18:25:12 INFO - 'PAGER': '/bin/cat', 18:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:12 INFO - 'PWD': '/builds/slave/test', 18:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:12 INFO - 'SHELL': '/bin/bash', 18:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:12 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:12 INFO - 'USER': 'cltbld', 18:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:12 INFO - 'XPC_FLAGS': '0x0', 18:25:12 INFO - 'XPC_SERVICE_NAME': '0', 18:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:12 INFO - Downloading/unpacking jsonschema==2.5.1 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:25:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:15 INFO - Downloading functools32-3.2.3-2.tar.gz 18:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:25:15 INFO - warning: no files found matching '*.txt' 18:25:15 INFO - no previously-included directories found matching 'build' 18:25:15 INFO - no previously-included directories found matching 'dist' 18:25:15 INFO - no previously-included directories found matching '.git*' 18:25:15 INFO - Installing collected packages: jsonschema, functools32 18:25:15 INFO - Running setup.py install for functools32 18:25:15 INFO - warning: no files found matching '*.txt' 18:25:15 INFO - no previously-included directories found matching 'build' 18:25:15 INFO - no previously-included directories found matching 'dist' 18:25:15 INFO - no previously-included directories found matching '.git*' 18:25:15 INFO - Successfully installed jsonschema functools32 18:25:15 INFO - Cleaning up... 18:25:15 INFO - Return code: 0 18:25:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:15 INFO - 'HOME': '/Users/cltbld', 18:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:15 INFO - 'LOGNAME': 'cltbld', 18:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:15 INFO - 'MOZ_NO_REMOTE': '1', 18:25:15 INFO - 'NO_EM_RESTART': '1', 18:25:15 INFO - 'PAGER': '/bin/cat', 18:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:15 INFO - 'PWD': '/builds/slave/test', 18:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:15 INFO - 'SHELL': '/bin/bash', 18:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:15 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:15 INFO - 'USER': 'cltbld', 18:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:15 INFO - 'XPC_FLAGS': '0x0', 18:25:15 INFO - 'XPC_SERVICE_NAME': '0', 18:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:25:15 INFO - Cleaning up... 18:25:15 INFO - Return code: 0 18:25:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:25:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:15 INFO - 'HOME': '/Users/cltbld', 18:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:15 INFO - 'LOGNAME': 'cltbld', 18:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:15 INFO - 'MOZ_NO_REMOTE': '1', 18:25:15 INFO - 'NO_EM_RESTART': '1', 18:25:15 INFO - 'PAGER': '/bin/cat', 18:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:15 INFO - 'PWD': '/builds/slave/test', 18:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:15 INFO - 'SHELL': '/bin/bash', 18:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:15 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:15 INFO - 'USER': 'cltbld', 18:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:15 INFO - 'XPC_FLAGS': '0x0', 18:25:15 INFO - 'XPC_SERVICE_NAME': '0', 18:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:16 INFO - Downloading/unpacking blobuploader==1.2.4 18:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:18 INFO - Downloading blobuploader-1.2.4.tar.gz 18:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:25:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:25:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:19 INFO - Downloading docopt-0.6.1.tar.gz 18:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:25:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:25:19 INFO - Running setup.py install for blobuploader 18:25:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:25:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:25:20 INFO - Running setup.py install for requests 18:25:20 INFO - Running setup.py install for docopt 18:25:20 INFO - Successfully installed blobuploader requests docopt 18:25:20 INFO - Cleaning up... 18:25:20 INFO - Return code: 0 18:25:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:20 INFO - 'HOME': '/Users/cltbld', 18:25:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:20 INFO - 'LOGNAME': 'cltbld', 18:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:20 INFO - 'MOZ_NO_REMOTE': '1', 18:25:20 INFO - 'NO_EM_RESTART': '1', 18:25:20 INFO - 'PAGER': '/bin/cat', 18:25:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:20 INFO - 'PWD': '/builds/slave/test', 18:25:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:20 INFO - 'SHELL': '/bin/bash', 18:25:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:20 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:20 INFO - 'USER': 'cltbld', 18:25:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:20 INFO - 'XPC_FLAGS': '0x0', 18:25:20 INFO - 'XPC_SERVICE_NAME': '0', 18:25:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-oboi2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-mfyAKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RVfeNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VwEEef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QcBzk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-bBB0ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-YXPhY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aEznH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:21 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VwdhWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zyt9kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-qfzTyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-TgFC58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-QIGJ_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-GdkTd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-wqXt6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-HzQSfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:22 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-dSik0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:25:22 INFO - Running setup.py install for manifestparser 18:25:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:25:23 INFO - Running setup.py install for mozcrash 18:25:23 INFO - Running setup.py install for mozdebug 18:25:23 INFO - Running setup.py install for mozdevice 18:25:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:25:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:25:23 INFO - Running setup.py install for mozfile 18:25:23 INFO - Running setup.py install for mozhttpd 18:25:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:25:23 INFO - Running setup.py install for mozinfo 18:25:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:25:23 INFO - Running setup.py install for mozInstall 18:25:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Running setup.py install for mozleak 18:25:24 INFO - Running setup.py install for mozlog 18:25:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Running setup.py install for moznetwork 18:25:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Running setup.py install for mozprocess 18:25:24 INFO - Running setup.py install for mozprofile 18:25:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:25:24 INFO - Running setup.py install for mozrunner 18:25:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:25:25 INFO - Running setup.py install for mozscreenshot 18:25:25 INFO - Running setup.py install for moztest 18:25:25 INFO - Running setup.py install for mozversion 18:25:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:25:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:25:25 INFO - Cleaning up... 18:25:25 INFO - Return code: 0 18:25:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:25 INFO - 'HOME': '/Users/cltbld', 18:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:25 INFO - 'LOGNAME': 'cltbld', 18:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:25 INFO - 'MOZ_NO_REMOTE': '1', 18:25:25 INFO - 'NO_EM_RESTART': '1', 18:25:25 INFO - 'PAGER': '/bin/cat', 18:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:25 INFO - 'PWD': '/builds/slave/test', 18:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:25 INFO - 'SHELL': '/bin/bash', 18:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:25 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:25 INFO - 'USER': 'cltbld', 18:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:25 INFO - 'XPC_FLAGS': '0x0', 18:25:25 INFO - 'XPC_SERVICE_NAME': '0', 18:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:25:25 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-VFjVFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:25:25 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-kfPbV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:25:25 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-943SY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-H2z4xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Os1Tz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Uhc1t7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-3FBafB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-mFOHcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-bJNtVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-CWnNsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Ceu39v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:25:26 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SJmqZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:25:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Ul6oYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:25:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-skMbV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-FNpd2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:25:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-DkuHie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:25:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:25:27 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-aH4hd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:25:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:30 INFO - Downloading blessings-1.6.tar.gz 18:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:25:31 INFO - Installing collected packages: blessings 18:25:31 INFO - Running setup.py install for blessings 18:25:31 INFO - Successfully installed blessings 18:25:31 INFO - Cleaning up... 18:25:31 INFO - Return code: 0 18:25:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:31 INFO - Reading from file tmpfile_stdout 18:25:31 INFO - Current package versions: 18:25:31 INFO - blessings == 1.6 18:25:31 INFO - blobuploader == 1.2.4 18:25:31 INFO - docopt == 0.6.1 18:25:31 INFO - functools32 == 3.2.3-2 18:25:31 INFO - jsonschema == 2.5.1 18:25:31 INFO - manifestparser == 1.1 18:25:31 INFO - mozInstall == 1.12 18:25:31 INFO - mozcrash == 0.17 18:25:31 INFO - mozdebug == 0.1 18:25:31 INFO - mozdevice == 0.48 18:25:31 INFO - mozfile == 1.2 18:25:31 INFO - mozhttpd == 0.7 18:25:31 INFO - mozinfo == 0.9 18:25:31 INFO - mozleak == 0.1 18:25:31 INFO - mozlog == 3.4 18:25:31 INFO - moznetwork == 0.27 18:25:31 INFO - mozprocess == 0.23 18:25:31 INFO - mozprofile == 0.28 18:25:31 INFO - mozrunner == 6.12 18:25:31 INFO - mozscreenshot == 0.1 18:25:31 INFO - mozsystemmonitor == 0.3 18:25:31 INFO - moztest == 0.7 18:25:31 INFO - mozversion == 1.4 18:25:31 INFO - psutil == 3.1.1 18:25:31 INFO - requests == 1.2.3 18:25:31 INFO - wsgiref == 0.1.2 18:25:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:25:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:32 INFO - 'HOME': '/Users/cltbld', 18:25:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:32 INFO - 'LOGNAME': 'cltbld', 18:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:32 INFO - 'MOZ_NO_REMOTE': '1', 18:25:32 INFO - 'NO_EM_RESTART': '1', 18:25:32 INFO - 'PAGER': '/bin/cat', 18:25:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:32 INFO - 'PWD': '/builds/slave/test', 18:25:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:32 INFO - 'SHELL': '/bin/bash', 18:25:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:32 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:32 INFO - 'USER': 'cltbld', 18:25:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:32 INFO - 'XPC_FLAGS': '0x0', 18:25:32 INFO - 'XPC_SERVICE_NAME': '0', 18:25:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:25:33 INFO - Cleaning up... 18:25:33 INFO - Return code: 0 18:25:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:25:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101054490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2da4e4170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:25:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:25:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:25:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:33 INFO - 'HOME': '/Users/cltbld', 18:25:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:33 INFO - 'LOGNAME': 'cltbld', 18:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:33 INFO - 'MOZ_NO_REMOTE': '1', 18:25:33 INFO - 'NO_EM_RESTART': '1', 18:25:33 INFO - 'PAGER': '/bin/cat', 18:25:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:33 INFO - 'PWD': '/builds/slave/test', 18:25:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:33 INFO - 'SHELL': '/bin/bash', 18:25:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:25:33 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:25:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:33 INFO - 'USER': 'cltbld', 18:25:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:33 INFO - 'XPC_FLAGS': '0x0', 18:25:33 INFO - 'XPC_SERVICE_NAME': '0', 18:25:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:25:33 INFO - Cleaning up... 18:25:33 INFO - Return code: 0 18:25:33 INFO - Running post-action listener: _resource_record_post_action 18:25:33 INFO - Running post-action listener: _start_resource_monitoring 18:25:33 INFO - Starting resource monitoring. 18:25:33 INFO - [mozharness: 2016-10-01 01:25:33.788316Z] Finished create-virtualenv step (success) 18:25:33 INFO - [mozharness: 2016-10-01 01:25:33.789345Z] Running install step. 18:25:33 INFO - Running pre-action listener: _resource_record_pre_action 18:25:33 INFO - Running main action method: install 18:25:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:25:34 INFO - Reading from file tmpfile_stdout 18:25:34 INFO - Detecting whether we're running mozinstall >=1.0... 18:25:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:25:34 INFO - Reading from file tmpfile_stdout 18:25:34 INFO - Output received: 18:25:34 INFO - Usage: mozinstall [options] installer 18:25:34 INFO - Options: 18:25:34 INFO - -h, --help show this help message and exit 18:25:34 INFO - -d DEST, --destination=DEST 18:25:34 INFO - Directory to install application into. [default: 18:25:34 INFO - "/builds/slave/test"] 18:25:34 INFO - --app=APP Application being installed. [default: firefox] 18:25:34 INFO - mkdir: /builds/slave/test/build/application 18:25:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:26:08 INFO - Reading from file tmpfile_stdout 18:26:08 INFO - Output received: 18:26:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:26:08 INFO - Running post-action listener: _resource_record_post_action 18:26:08 INFO - [mozharness: 2016-10-01 01:26:08.386633Z] Finished install step (success) 18:26:08 INFO - [mozharness: 2016-10-01 01:26:08.386773Z] Running run-tests step. 18:26:08 INFO - Running pre-action listener: _resource_record_pre_action 18:26:08 INFO - Running main action method: run_tests 18:26:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:26:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:26:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:26:08 INFO - 2016-09-30 18:26:08.401 screenresolution[2379:34306] starting screenresolution argv=screenresolution get 18:26:08 INFO - 2016-09-30 18:26:08.404 screenresolution[2379:34306] Display 0: 1600x1200x32@60 18:26:08 INFO - 2016-09-30 18:26:08.410 screenresolution[2380:34309] starting screenresolution argv=screenresolution list 18:26:08 INFO - Available Modes on Display 0 18:26:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:26:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:26:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:26:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:26:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:26:08 INFO - Intel Iris: 18:26:08 INFO - Chipset Model: Intel Iris 18:26:08 INFO - Type: GPU 18:26:08 INFO - Bus: Built-In 18:26:08 INFO - VRAM (Dynamic, Max): 1536 MB 18:26:08 INFO - Vendor: Intel (0x8086) 18:26:08 INFO - Device ID: 0x0a2e 18:26:08 INFO - Revision ID: 0x0009 18:26:08 INFO - Displays: 18:26:08 INFO - Display: 18:26:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:26:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:26:08 INFO - Display Serial Number: 1600x1200 60 18:26:08 INFO - Main Display: Yes 18:26:08 INFO - Mirror: Off 18:26:08 INFO - Online: Yes 18:26:08 INFO - Rotation: Supported 18:26:08 INFO - Return code: 0 18:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:26:08 INFO - Python 2.7.3 18:26:08 INFO - Return code: 0 18:26:08 INFO - grabbing minidump binary from tooltool 18:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:08 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e68d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e60b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:26:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:26:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:26:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:26:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:26:08 INFO - Return code: 0 18:26:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:26:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:26:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:26:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:26:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SIWKgF4j5S/Render', 18:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:08 INFO - 'HOME': '/Users/cltbld', 18:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:08 INFO - 'LOGNAME': 'cltbld', 18:26:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:26:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:08 INFO - 'MOZ_NO_REMOTE': '1', 18:26:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:26:08 INFO - 'NO_EM_RESTART': '1', 18:26:08 INFO - 'PAGER': '/bin/cat', 18:26:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:08 INFO - 'PWD': '/builds/slave/test', 18:26:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:08 INFO - 'SHELL': '/bin/bash', 18:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zreFG6e40c/Listeners', 18:26:08 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 18:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:08 INFO - 'USER': 'cltbld', 18:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:08 INFO - 'XPC_FLAGS': '0x0', 18:26:08 INFO - 'XPC_SERVICE_NAME': '0', 18:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:26:08 INFO - mozversion application_buildid: 20160930151154 18:26:08 INFO - mozversion application_changeset: d69d58af9bea7a2781304d3ee3ba2122b2c07abf 18:26:08 INFO - mozversion application_display_name: Nightly 18:26:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:26:08 INFO - mozversion application_name: Firefox 18:26:08 INFO - mozversion application_remotingname: firefox 18:26:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:26:08 INFO - mozversion application_vendor: Mozilla 18:26:08 INFO - mozversion application_version: 52.0a1 18:26:08 INFO - mozversion platform_buildid: 20160930151154 18:26:08 INFO - mozversion platform_changeset: d69d58af9bea7a2781304d3ee3ba2122b2c07abf 18:26:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:26:08 INFO - mozversion platform_version: 52.0a1 18:26:08 INFO - using testdate: 1475285168 18:26:08 INFO - actual date: 1475285168 18:26:08 INFO - starting webserver on 'localhost:52682' 18:26:08 INFO - SUITE-START | Running 2 tests 18:26:08 INFO - TEST-START | dromaeo_css 18:26:08 INFO - operating with platform_type : mac_ 18:26:08 INFO - Initialising browser for dromaeo_css test... 18:26:09 INFO - TEST-INFO | started process 2390 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp_yHdCR/profile http://localhost:52682/getInfo.html) 18:26:11 INFO - PROCESS | 2390 | 2016-09-30 18:26:11.736 plugin-container[2392:34469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 18:26:11 INFO - PROCESS | 2390 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:11 INFO - PROCESS | 2390 | 2016-09-30 18:26:11.739 plugin-container[2392:34469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 18:26:11 INFO - PROCESS | 2390 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:11 INFO - PROCESS | 2390 | 2016-09-30 18:26:11.739 plugin-container[2392:34469] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:11 INFO - PROCESS | 2390 | __metrics Screen width/height:1600/1200 18:26:11 INFO - PROCESS | 2390 | colorDepth:24 18:26:11 INFO - PROCESS | 2390 | Browser inner width/height: 1024/689 18:26:11 INFO - PROCESS | 2390 | __metrics 18:26:11 ERROR - PROCESS | 2390 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 18:26:12 INFO - TEST-INFO | 2390: exit 0 18:26:12 INFO - Browser initialized. 18:26:12 INFO - Running cycle 1/1 for dromaeo_css test... 18:26:12 INFO - TEST-INFO | started process 2393 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp_yHdCR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:15 INFO - PROCESS | 2393 | 2016-09-30 18:26:15.135 plugin-container[2394:34588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d23, name = 'com.apple.tsm.portname' 18:26:15 INFO - PROCESS | 2393 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:15 INFO - PROCESS | 2393 | 2016-09-30 18:26:15.138 plugin-container[2394:34588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 18:26:15 INFO - PROCESS | 2393 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:15 INFO - PROCESS | 2393 | 2016-09-30 18:26:15.138 plugin-container[2394:34588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:24 INFO - PROCESS | 2393 | RSS: Main: 240107520 18:26:24 INFO - PROCESS | 2393 | 18:27:14 INFO - PROCESS | 2393 | 1475285234347 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:29:26 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52682/tests/dromaeo/cssquery-ext.html) 18:29:27 INFO - PROCESS | 2393 | RSS: Main: 255541248 18:29:27 INFO - PROCESS | 2393 | 18:32:29 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-ext.html (next: http://localhost:52682/tests/dromaeo/cssquery-jquery.html) 18:32:30 INFO - PROCESS | 2393 | RSS: Main: 266727424 18:32:30 INFO - PROCESS | 2393 | 18:35:32 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52682/tests/dromaeo/cssquery-mootools.html) 18:35:32 INFO - PROCESS | 2393 | RSS: Main: 270331904 18:35:32 INFO - PROCESS | 2393 | 18:38:34 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52682/tests/dromaeo/cssquery-prototype.html) 18:38:35 INFO - PROCESS | 2393 | RSS: Main: 269987840 18:38:35 INFO - PROCESS | 2393 | 18:41:38 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52682/tests/dromaeo/cssquery-yui.html) 18:41:38 INFO - PROCESS | 2393 | RSS: Main: 275423232 18:41:38 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-yui.html (next: http://localhost:52682/tests/dromaeo/cssquery-dojo.html) 18:44:41 INFO - PROCESS | 2393 | RSS: Main: 278118400 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | __start_tp_report 18:44:41 INFO - PROCESS | 2393 | _x_x_mozilla_page_load 18:44:41 INFO - PROCESS | 2393 | _x_x_mozilla_page_load_details 18:44:41 INFO - PROCESS | 2393 | |i|pagename|runs| 18:44:41 INFO - PROCESS | 2393 | |0;dojo.html;2759.337758937853;2794.5249307617705;3339.8831040913574;3381.932361352772;3390.288039511702;4635.443746750386;5603.551715862726;5626.606137570372;5634.95753285642;5708.628939118955;5558.221848941152;5653.575981801361;5673.574481913853;5712.99999999999;5749.942500574993;4248.575142485742;5080.237964305356;5186.636935414523;5191.273221748958;5217.843464696065;4684.08220132358;5193.45468725784;5203.687778733288;5290.814821481249;5351.678899266037;5218.895622087575;5234.7120908350025;5246.947530524684;5286.841394758163;5293.285406470135;5084.745762711849;5134.486551344873;5147.202183661501;5173.224016397533;5248.921266181011;4632.976835115836;5151.6908985461;5152.845414637567;5192.299039629639;5242.318498595158;5545.694986775726;5634.549236061146;5686.943130568724;5715.571332150105;5719.25649665545;6044.637321760709;6113.35468597686;6146.631202127842;6252.781152659635;6269.279032911228;6255.562110652256;6297.842553936142;6319.905201421982;6338.556301058928;6340;5754.338251101093;5759.136129580555;5765.452282033242;5772;5834.854128646775;6119.715820550745;6236.781712640036;6307;6314.463270622048;6318.462930650946;6013.849653758691;6062.030075187949;6099.176611157525;6121.117656725397;6147.108669242934;6230.813075607739;6255.030470277115;6261.655608941461;6271.905921411182;6281.7173227204885;6062.060380641008;6068.817935461943;6111.682900513672;6185.443310102112;6189.257289125334;6147.415995480422;6249.156363890907;6261.373862613702;6263.906041409383;6299.905501417483;4900.166971614834;4916.164252077156;4938.851834444972;4943.90112197754;4948.282499037619;4315.741055536678;4331.371951067141;4439.223135951195;4451.198784218872;4459.9331010034875;5749.108888122348;5791.102379131242;5820.738066787005;5880.676562789002;5925;6227;6272.780452684134;6285.96857015712;6304.527160462983;6309.495240380758;5684.63049901755;5893.970530147408;5894.970525147432;5925.005591034483;5977.252843394575;5359.3032905722;5401.3248343957;5404.081306177959;5428.077226871442;5508.173773933861;5058.3929928408825;6062.878742425127;6323;6326.778562750282;6381.553291269566;5827.3007239131575;6184.917639413175;6254.937450625435;6297.811065668037;6304;6187.257529096537;6238.625682459067;6244.562880598496;6258.155149054819;6258.968705156445;5182.481751824938;6190.752369905334;6202.193714817045;6239.812805615838;6261.154744109487;6094.56777657259;6272.686365681789;6290.591111577733;6293;6329.240491141092;5889.234399528034;6034.879302414015;6052.878942421215;6090.543209259412;6156;6101.633901965896;6125.601746185103;6147.385261473817;6171.876562468815;6211.409916057967;5659.462351076641;5827.067669172912;5997.820065398045;6013.338532761481;6022.187004754477;6012.969935150296;6058.697065146813;6090.725917333819;6101.145839582373;6172.475339596184;5292.497212764782;5737.311522617146;5927.051671732502;5949.613275137275;6011.639301641915 18:44:41 INFO - PROCESS | 2393 | |1;ext.html;457.88504990348525;484.6292586171579;495.5094456488078;523.4190049045559;533.7811497286112;38237.61762382375;39774.60225397745;39857.202855942865;42381.36427953583;42406.575934240645;41148.794256028756;41731.95670108241;41771;41787.373189402104;41793.58206417935;9697.078777516143;9903.752406189868;9909.653162139326;9930.155936745385;9931.751706207366;9743.22054235663;9813.852792208123;9834;9846.113849753521;9849.901500985006;9873.506324683773;9884.110430061293;9887.505624718771;9894.901050989505;9921.35511191774;9802.41185528867;9845.655402060966;9848.162906152986;9854.802903941953;9870.2103831694;9716.514174291256;9806.215502759833;9807.656732014417;9827.852582211273;9835;39274.21451570953;39412.802935985426;39757.608483702934;39758.40362394566;39801;2763.6130941668184;3364.4112280351087;3468.670476304747;3546.202104526487;3697.2235830475634;29939.550906736415;30028.8498557508;30849.84575077133;30952.690473095205;31098.689013109804;2800.085849334405;3187.920301992469;3204.455242608762;3213.775035747499;3260.7065364117107;492264;499413;499460;499539;501294;32414.513782293285;33897.66102338945;55969;56422;56637.15044274339;34302.313953721285;34323.97028089161;34354;34544.30911381758;34614.307713845585;14243;14352;14377;14377.6405589859;14402.85597144015;11955.103367247491;12068.97413719826;12103.63689089311;12144.332061736513;12165.33090680021;7614.657340419583;7625.1231108422135;7643.885341719879;7720.613969301512;7748.418868584766;3231.74146068314;3283.326917981808;3288.1122097033667;3294.769366144395;3322.3853587085982;937.3204426790659;966.6085235479642;980.7940332530248;1004.8995100489892;1077.5097330714507;29517.704822951924;29534.852325738662;29636.851815741215;30187.396252074836;30293.69706302953;987.3138168972639;1027.419507978005;1058.809414305417;1120.1990576737671;1136.3977092141179;14678.926605366905;15199.924000380148;15269.923650381677;15428.305726242346;15455.826321638186;693713;705158;708462.4576877083;708915;709520;55226;55754;56142.719286403306;56456.717716411156;56493.717531412905;1341.9463221471226;1346.4816045822233;1392.436063394327;1397.1337770582102;1402.9298535073206;24890.751092488845;28577.14268571946;28932.20364221207;29147.85426072856;29315.267118322212;46223;46620.53379466162;46626;46896.7655161722;47128.52871471241;596811;603019;605591.9720401369;605724;606731;25687.357816054748;25740.683338499624;26045.91632669344;26088.478230434906;26094.86952565225;45427.77286113681;46317.84205394892;46588.83527911829;46734.53265467302;46864.7656761714;37480.437793432575;37528.62471375251;37691.62308376881;38890.80554597209;39077.21845563129;37663.246735065695;38112.81153480811;38694;38703.41944870885;39079;34605.30789384249;36522;37696.86909392723;38016.049598760255;40103.79948100241;32528;33881.19089036819;33887;34136;34236.657633424344;19513.317033903746;20267;20892.895535522224;21106.577868442237;21335.573288533833 18:44:41 INFO - PROCESS | 2393 | |2;jquery.html;362.45088690633685;365.37156091522587;369.1859449912942;379.7835233916665;394.378853306043;16254.593635159126;19381.709274360877;19978.90010549945;20787.688184677223;20972.265970691027;19293;19477.220911163535;19951.900240498777;19982.900085499554;20339.49151271219;4746.454157771848;5234.895302093956;5417.133258678612;5422.864428389302;6019.247594050744;5892.646441213519;6027.60820546663;6031;6031.969840150793;6035.969820150915;5692.203091567184;5884.617114977977;5921.259842519685;5947.672877991708;5979.431953964367;5867.647941123546;5888.528917686596;5958.433948774881;5965.582409231356;5968.97015514924;5024.447310795809;5195;5232.92150617741;5255.02781985334;5255.237990491358;12689.428975696115;13060.542880999119;13068.607941761762;13186.208827470382;13199.538016169388;2113.292047164202;2239.193890199527;2252.4819291562867;2275.203678712454;2337.474068334628;9757.170640495566;9829.213662907021;10111.49442527878;10156;10249.795004099951;1837.889726616394;1878.6994080946984;1909.598984213317;1935.8451323894055;1945.8540609454205;114961;118884;119026.4048679751;119851;120875;11259.128922124806;11371.601993930173;11582.88417115818;11733.471993760131;11766.117541184445;14471.276436178148;14481.275936203148;14549.19979401122;14564.49024284145;14599.78100328496;8279.54462504556;8432.704855330034;8443.451175673561;8452.788680283042;8583.828323433496;7067.399271061914;7396.704131834774;7446.851062978819;8078.586247406681;8220.136885626924;4075.409065685459;4132.049628585382;4425.889352766207;4506.549345065533;4607.539246075366;1553.9766903496457;1878.5679293762512;1929.0451226642901;1971.1228503315888;2006.1273346094204;797.968081276754;880.8942926848819;935.3779736475303;964.5466630683568;1012.5198728139964;18956.715649265272;21641.56716865654;21663.566728665337;21678.783212167677;21754.238601648867;732.045438678729;898.5462341517492;911.0798093925183;968.8837339519162;980.6469670918464;10718.334228267966;11375.431228438545;11504.712382190512;11527.654170374735;11574.363410012531;574845;575058;575168;582598;584123;75910;128669;131766.68233317544;131817;140303;833.6373677450329;949.6581230756922;997.4065431068502;998.7752755630071;1021.9744506387251;16295.348186072424;18822.341218057303;19217.61564768669;20234.19063237513;20322.288719894732;39955;51783.74108129435;56751;56864.14703779364;57038.714806425705;80208.59895700484;81784.59107704423;82031.07813531207;82792.17207827844;84246.15753842382;6789;7902.328302094386;8119.30985866208;8214.58927053633;8280.627371768165;10820.891791082304;11077.944610276896;11092.668879734483;11809.704757381134;12319.815202771786;10928.836067458835;11067.557297708001;11143.33140011602;11298.943505282421;11336.943315283701;10961.945190273998;10986.890131098588;11091.279066860629;11177.161712871404;11495.425228738362;11025.83461248098;11219.439028048728;11368.658940231806;11501;11746.001589864958;8055.704124324539;8058.798530036796;8060.476069055492;8291.62687679068;8291.709790157556;8102.392320576098;10741.409222492837;10811.891881081403;11203.383813890314;11465 18:44:41 INFO - PROCESS | 2393 | |3;mootools.html;1527.8701310388617;1564.3507944203157;1794.9012804295767;1847.7967423583411;1896.5732710140212;8038.557879316635;8311.584420778967;8519.233269005788;9085.500297483635;9098.727038188848;8494.193051660082;8809.95595022024;8838.204561589455;8932.240759535447;8965;1972.6942323939813;1982.672858978267;2370.0401337458356;2410.7348191698898;2411.011485291031;2361.055577768898;2385.8210634202505;2411.2766170148975;2413.746556611557;2415.7342692303832;2098.842586805988;2135.1032566322106;2284.6458798886115;2372.7983121434613;2398.268528098929;2368.7512811154925;2380.5119950410126;2384.332386931662;2397.4006498375406;2414.275717284825;2064.967516241879;2294.8737819419925;2371.620540713495;2372.181597348912;2387.7492863249454;8803.559822008874;8940.597673104727;8980.326475514297;8980.55097245135;9003.054679258648;5297.073012222845;5452.91820622691;5459.3994660587305;5472.26124473195;5475.151351540478;3363.360961417323;3617.457381392786;3727.813609319523;3733.5146430964073;3748.081719978623;1017.6082208349765;1026.7730062575226;1052.841873938669;1085.8914108589156;1098.9450527473593;11583.536658533649;11645;11656.242344247597;11668.35824029678;11673;4606.838760643362;4728.219843725816;4841.782119804547;4853.927191092136;4876.4879687633;10038.297319187734;10118.443485608363;10123.64567240143;10139.797204056025;10161.898381016243;5371.785128594813;5412.91880621791;5445.401005889349;5449.64577302474;5455.072637651609;4429.712068715523;4661.743604101807;4899.902001959941;4969.130402179604;4975.328330675385;3274.492453669685;3964.187341595024;4049.635532802062;4075.5313138988804;4113.403556484293;1291.7351942851692;1311.4098655605017;1315.5855905389963;1323.503686117706;1331.6404570766033;856.5888373580716;858.9355798315152;907.6414816147585;912.5528491039342;927.5501381829807;5779.566532510079;5807.767689292381;6008.939910600925;6036.577439579188;6174.351693072163;928.7910220200536;949.3307218411004;972.5429048347265;973.6932866147141;986.2307400227834;3502.772319799205;3959.940600890989;3966.6628336591134;4046.716729828943;4092.7748973806724;9847.409155450696;10833.783324333626;10855.7975397258;10860.13118950482;10863;10650.946745266225;10687.305325153995;10739.35563866155;10775.976282253174;10831.620893268855;8236.341092712568;8353.874691879626;8364.414490985695;8365.121662225378;8386.371022173362;5647.604667673223;5716.714164291686;5863.618864773776;5964.761409543743;5977.133315669116;10164.593416263267;10188.337758045704;10196;10264.743381415226;10303.639372621923;9820.803583928138;10222.386656800616;10276;10402.323848949794;10440.216983726403;5725.8568535786935;7044.295570442915;7255.637218138966;7646.8088297793;7780.533168009937;8352.916470835215;8629.964404271528;8631.395802293779;8662.610182541675;8670.82658346842;6165.85931602637;6872.931270687429;7031.648417578998;7452.552846829208;7693.384529237535;7393.112826460859;7467;7482.625868706652;7491;7546.396288297033;6309.779157729457;6967.372936435745;7055.2944705529035;7070.893936590853;7101.680424380812;4974.228994505857;5045.217991211368;5052.418971818288;5107.9489205107475;5138.023775482647;4386.802593883347;4946.282788995647;4963.925541116814;5182.766775495187;5209.270702101785 18:44:41 INFO - PROCESS | 2393 | |4;prototype.html;279.6266983576925;288.39437181917975;292.6079054067549;312.7123046796948;317.2212219002349;4195.727277726945;5343.732813359326;5347.385050719168;5490.231367608533;5497.39528651848;5130.640855140142;5317.1021793122745;5364.839054828351;5375.892482150364;5390.5418039466595;4108.459327752101;4136.193442278757;4158.480189976253;4158.939470435035;4168.895777605554;3087.7684173686953;4053.412255222991;4138.172365526892;4150.439690641755;4163.770992595406;3690.7231957603153;4043.6158564936286;4050.534188568323;4063.573324800898;4064.1304999700174;3537.4340105582983;3907.2185562887535;3908.863189788344;3930.6658933990643;4098.098418347973;3359.9496007559906;3489.731674411653;3523.154442933703;3530.717542596586;3549.4143466328023;4437.400950871655;5166.612504062173;5187.351581052369;5222.817201397933;5248.422673505911;3833.731638785286;4519.4802597701355;4686.718796872198;4713.717176969359;4734.668573199878;5478.506934375885;5507.917381239285;5738.766165274486;5746.62081100537;5811.941880581225;3252.5363586385984;3435.4846772984;3495.4407294832704;3913.4521167036637;3916.15802602439;5364.544013758835;5433.646812957185;5980.671063091529;6026.306974697922;6027.216461859974;3759.586445490994;3899.6100389960775;3936.110666879914;3950.3481925481974;3963.3460479020637;3993.5407428145554;3997.380406037069;4006;4006.0385507477995;4019.8593049243136;4290.356446533045;4649.930251046237;4651.37206477128;4668.789904454307;4676.796394469799;4607.585317321457;5026.019926114445;5027.949720502748;5603.971980140073;5771.249737534177;1191.7676053169723;1346.6094832498466;1350.3653282956996;1366.3100134432248;1376.181172202538;735.5843948169374;748.4798065344534;792.2513225002433;830.9916900831035;838.7190291252376;3914.8629797956933;4277.786110694515;4313.482382114166;4387.056782791715;4530.478994915561;4396.384151429894;4822.614190864722;4988.476209997898;5039.370078740158;5052.621053421009;3888.650021498079;4192.937105943413;4342.002678767739;4377.474703035592;4550.8610952852105;2214.7563767985507;2252.4368907773055;2402.0423354965646;2437.512497500507;2641.947161056768;4598.2412901871485;4760.404949381327;4839.9758001210475;5219.19102539107;5220.086484865209;4464.99537604043;4645.25929701471;5013.573846223112;5046.4953504649975;5053.317802096743;4200.051951165894;4698.154332220233;4707.388039554836;5044.772985215747;5159.432462429129;4021.8994525137105;4941.888075183198;4958.851234462972;4973.825916093064;4989.376327959005;4025.9798701006307;4560.88597785058;4708.599769019672;4909.410870695539;4974.328465657235;4113.691473139586;4507.797149128362;4647.3726046984575;5114.820981265638;5134.820281290137;2931.2525306046823;3335.232896433882;3438.5529881115294;3629.4555816627717;3768.6419790119894;4338.58783415575;4929.531694489017;5079.644424890223;5214.539928819937;5701.035461560048;4941.308216849572;5621.988042152452;5645.435456454322;5674.262345895172;5697.4587414195585;5098.490150984871;5204.635675502678;5280.60395470349;5642.887142257214;5689.573282003949;4334.28484300087;5093.668911520739;5472.251507717227;5515.641483303572;5519.613627046068;4984;5607.719614019365;5654.802081927277;5660.7169641518585;5688.431156884279;5265.2891859599995;5372.382176049804;5639.724755066083;5692.556003617751;5700.973824711426 18:44:41 INFO - PROCESS | 2393 | |5;yui.html;679.2392520377176;706.2090458686272;765.3073968058909;782.4835608498389;813.979650508737;15077.849221507782;16066;16184.433544825948;16193.91903040486;16234.863559550837;14558.636034099152;14657.560273191795;14669.339879705412;14679.779803302932;14708.48520301787;660.8678264347126;692.5636848785258;820.831729495452;833.9207775261341;854.8418542569614;847.1697736218479;848.4612271207797;849.1550906847696;859.5916939453741;861.3496809908511;881.8633111867671;896.2605850173633;898.2813749000806;900.4822227219342;901.3960646366917;867.2368315882015;870.0690261420283;889.7508697564692;894.8344556257114;898.2454738020061;658.5818005566451;659.650385295794;668.6021817018866;668.6890595872942;670.8825955457825;15264.313105910149;15525.301361438762;15600.765988510182;15614.687706245924;15646.295916683777;14604.853951460456;14629.6342591435;14677.633059173499;14720.852791472056;14727.779083313759;11851.348175850324;11995.94002029993;12218.144729868913;12409.007279417625;12510;3777.395616701342;3787.8106094695154;3969.622885825842;4007.6593489553425;4031.475908131924;110721.44639276832;110910;111066;111535.44232278867;116358.99999999916;11192;11235.213535052631;11320.716982075513;11366.261193022427;11430.028447582048;23463.53072938532;23467.53064938726;23472.295831125095;23495.530089398115;23571.410714731926;7445.10658720957;7702.730404435818;8067.556284404297;8312.002559692755;8436.493810371398;4280.464941882265;4490.438695163105;4491.393661855673;4515.616172625298;4516.299973504112;3136.9372612548077;3444.4488881778793;3476.791392516457;3547.0954906498678;3576.3204991051616;668.3725129310145;669.0399277037467;669.9732010719516;672.4821887146869;677.1231255524108;505.44906052402325;606.1846816032429;607.2894713185583;633.4932054356497;642.1459458919626;6578.440832529192;8524.445911015764;8963.820723585492;9038.276937844958;9105.271578273721;534.2733881920662;540.41635034163;596.9850753731191;616.5868867858455;621.142822904389;4276.123394704078;4313.9137217255475;4365.301551751704;4451.9554804451545;4474.753888536096;97515;110644.89355106346;147108;158688;167842;108503;120241.39879300547;122426;127438;128754.84980600858;106430.93569064364;115646.42176789063;117652.41173794077;117827;119848;8225.683890577478;8506;8526.573671316286;8563.657453702031;8645.740627781177;60205;60899;61672;64402;65075;78737;94954.05045949729;97439.53840692254;97763.02236977823;102347.97652023385;10786.406747628957;10949.616763413242;11019.779604407706;11087.22389432732;11320.320780753182;12034.63896083119;12105.939470302592;12181.878181218075;12192.963598094262;12655.303958282382;10990;11027.669169924915;11218.607348742755;11670.299782013106;11690.590829320932;11505.194636375374;11622.94188529052;11683.240589361665;11989.460474278852;12070.45682944252;11161.776764464828;11382.715432114263;11438.256513326607;11448.713782155513;11695.064394848561;8436.277180642826;9124.452532847785;9503.619855205714;9591.664291749756;9670.951645241728;8501.410104004535;8894.377393582387;9674.274429417681;9733.270004749531;9797.559109840216 18:44:41 INFO - PROCESS | 2393 | __end_tp_report 18:44:41 INFO - PROCESS | 2393 | __start_cc_report 18:44:41 INFO - PROCESS | 2393 | _x_x_mozilla_cycle_collect,368 18:44:41 INFO - PROCESS | 2393 | __end_cc_report 18:44:41 INFO - PROCESS | 2393 | __startTimestamp1475286281704__endTimestamp 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | ------- Summary: start ------- 18:44:41 INFO - PROCESS | 2393 | Number of tests: 6 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#0] dojo.html Cycles:165 Average:5702.23 Median:5927.05 stddev:690.10 (11.6%) stddev-sans-first:652.46 18:44:41 INFO - PROCESS | 2393 | Values: 2759.3 2794.5 3339.9 3381.9 3390.3 4635.4 5603.6 5626.6 5635.0 5708.6 5558.2 5653.6 5673.6 5713.0 5749.9 4248.6 5080.2 5186.6 5191.3 5217.8 4684.1 5193.5 5203.7 5290.8 5351.7 5218.9 5234.7 5246.9 5286.8 5293.3 5084.7 5134.5 5147.2 5173.2 5248.9 4633.0 5151.7 5152.8 5192.3 5242.3 5545.7 5634.5 5686.9 5715.6 5719.3 6044.6 6113.4 6146.6 6252.8 6269.3 6255.6 6297.8 6319.9 6338.6 6340.0 5754.3 5759.1 5765.5 5772.0 5834.9 6119.7 6236.8 6307.0 6314.5 6318.5 6013.8 6062.0 6099.2 6121.1 6147.1 6230.8 6255.0 6261.7 6271.9 6281.7 6062.1 6068.8 6111.7 6185.4 6189.3 6147.4 6249.2 6261.4 6263.9 6299.9 4900.2 4916.2 4938.9 4943.9 4948.3 4315.7 4331.4 4439.2 4451.2 4459.9 5749.1 5791.1 5820.7 5880.7 5925.0 6227.0 6272.8 6286.0 6304.5 6309.5 5684.6 5894.0 5895.0 5925.0 5977.3 5359.3 5401.3 5404.1 5428.1 5508.2 5058.4 6062.9 6323.0 6326.8 6381.6 5827.3 6184.9 6254.9 6297.8 6304.0 6187.3 6238.6 6244.6 6258.2 6259.0 5182.5 6190.8 6202.2 6239.8 6261.2 6094.6 6272.7 6290.6 6293.0 6329.2 5889.2 6034.9 6052.9 6090.5 6156.0 6101.6 6125.6 6147.4 6171.9 6211.4 5659.5 5827.1 5997.8 6013.3 6022.2 6013.0 6058.7 6090.7 6101.1 6172.5 5292.5 5737.3 5927.1 5949.6 6011.6 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#1] ext.html Cycles:180 Average:70961.18 Median:38984.01 stddev:163330.11 (419.0%) stddev-sans-first:163702.51 18:44:41 INFO - PROCESS | 2393 | Values: 457.9 484.6 495.5 523.4 533.8 38237.6 39774.6 39857.2 42381.4 42406.6 41148.8 41732.0 41771.0 41787.4 41793.6 9697.1 9903.8 9909.7 9930.2 9931.8 9743.2 9813.9 9834.0 9846.1 9849.9 9873.5 9884.1 9887.5 9894.9 9921.4 9802.4 9845.7 9848.2 9854.8 9870.2 9716.5 9806.2 9807.7 9827.9 9835.0 39274.2 39412.8 39757.6 39758.4 39801.0 2763.6 3364.4 3468.7 3546.2 3697.2 29939.6 30028.8 30849.8 30952.7 31098.7 2800.1 3187.9 3204.5 3213.8 3260.7 492264.0 499413.0 499460.0 499539.0 501294.0 32414.5 33897.7 55969.0 56422.0 56637.2 34302.3 34324.0 34354.0 34544.3 34614.3 14243.0 14352.0 14377.0 14377.6 14402.9 11955.1 12069.0 12103.6 12144.3 12165.3 7614.7 7625.1 7643.9 7720.6 7748.4 3231.7 3283.3 3288.1 3294.8 3322.4 937.3 966.6 980.8 1004.9 1077.5 29517.7 29534.9 29636.9 30187.4 30293.7 987.3 1027.4 1058.8 1120.2 1136.4 14678.9 15199.9 15269.9 15428.3 15455.8 693713.0 705158.0 708462.5 708915.0 709520.0 55226.0 55754.0 56142.7 56456.7 56493.7 1341.9 1346.5 1392.4 1397.1 1402.9 24890.8 28577.1 28932.2 29147.9 29315.3 46223.0 46620.5 46626.0 46896.8 47128.5 596811.0 603019.0 605592.0 605724.0 606731.0 25687.4 25740.7 26045.9 26088.5 26094.9 45427.8 46317.8 46588.8 46734.5 46864.8 37480.4 37528.6 37691.6 38890.8 39077.2 37663.2 38112.8 38694.0 38703.4 39079.0 34605.3 36522.0 37696.9 38016.0 40103.8 32528.0 33881.2 33887.0 34136.0 34236.7 19513.3 20267.0 20892.9 21106.6 21335.6 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#2] jquery.html Cycles:180 Average:33969.05 Median:2263.84 stddev:96789.55 (4275.5%) stddev-sans-first:97028.17 18:44:41 INFO - PROCESS | 2393 | Values: 362.5 365.4 369.2 379.8 394.4 16254.6 19381.7 19978.9 20787.7 20972.3 19293.0 19477.2 19951.9 19982.9 20339.5 4746.5 5234.9 5417.1 5422.9 6019.2 5892.6 6027.6 6031.0 6032.0 6036.0 5692.2 5884.6 5921.3 5947.7 5979.4 5867.6 5888.5 5958.4 5965.6 5969.0 5024.4 5195.0 5232.9 5255.0 5255.2 12689.4 13060.5 13068.6 13186.2 13199.5 2113.3 2239.2 2252.5 2275.2 2337.5 9757.2 9829.2 10111.5 10156.0 10249.8 1837.9 1878.7 1909.6 1935.8 1945.9 114961.0 118884.0 119026.4 119851.0 120875.0 11259.1 11371.6 11582.9 11733.5 11766.1 14471.3 14481.3 14549.2 14564.5 14599.8 8279.5 8432.7 8443.5 8452.8 8583.8 7067.4 7396.7 7446.9 8078.6 8220.1 4075.4 4132.0 4425.9 4506.5 4607.5 1554.0 1878.6 1929.0 1971.1 2006.1 798.0 880.9 935.4 964.5 1012.5 18956.7 21641.6 21663.6 21678.8 21754.2 732.0 898.5 911.1 968.9 980.6 10718.3 11375.4 11504.7 11527.7 11574.4 574845.0 575058.0 575168.0 582598.0 584123.0 75910.0 128669.0 131766.7 131817.0 140303.0 833.6 949.7 997.4 998.8 1022.0 16295.3 18822.3 19217.6 20234.2 20322.3 39955.0 51783.7 56751.0 56864.1 57038.7 80208.6 81784.6 82031.1 82792.2 84246.2 6789.0 7902.3 8119.3 8214.6 8280.6 10820.9 11077.9 11092.7 11809.7 12319.8 10928.8 11067.6 11143.3 11298.9 11336.9 10961.9 10986.9 11091.3 11177.2 11495.4 11025.8 11219.4 11368.7 11501.0 11746.0 8055.7 8058.8 8060.5 8291.6 8291.7 8102.4 10741.4 10811.9 11203.4 11465.0 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#3] mootools.html Cycles:180 Average:5739.00 Median:4888.19 stddev:3227.28 (66.0%) stddev-sans-first:3220.82 18:44:41 INFO - PROCESS | 2393 | Values: 1527.9 1564.4 1794.9 1847.8 1896.6 8038.6 8311.6 8519.2 9085.5 9098.7 8494.2 8810.0 8838.2 8932.2 8965.0 1972.7 1982.7 2370.0 2410.7 2411.0 2361.1 2385.8 2411.3 2413.7 2415.7 2098.8 2135.1 2284.6 2372.8 2398.3 2368.8 2380.5 2384.3 2397.4 2414.3 2065.0 2294.9 2371.6 2372.2 2387.7 8803.6 8940.6 8980.3 8980.6 9003.1 5297.1 5452.9 5459.4 5472.3 5475.2 3363.4 3617.5 3727.8 3733.5 3748.1 1017.6 1026.8 1052.8 1085.9 1098.9 11583.5 11645.0 11656.2 11668.4 11673.0 4606.8 4728.2 4841.8 4853.9 4876.5 10038.3 10118.4 10123.6 10139.8 10161.9 5371.8 5412.9 5445.4 5449.6 5455.1 4429.7 4661.7 4899.9 4969.1 4975.3 3274.5 3964.2 4049.6 4075.5 4113.4 1291.7 1311.4 1315.6 1323.5 1331.6 856.6 858.9 907.6 912.6 927.6 5779.6 5807.8 6008.9 6036.6 6174.4 928.8 949.3 972.5 973.7 986.2 3502.8 3959.9 3966.7 4046.7 4092.8 9847.4 10833.8 10855.8 10860.1 10863.0 10650.9 10687.3 10739.4 10776.0 10831.6 8236.3 8353.9 8364.4 8365.1 8386.4 5647.6 5716.7 5863.6 5964.8 5977.1 10164.6 10188.3 10196.0 10264.7 10303.6 9820.8 10222.4 10276.0 10402.3 10440.2 5725.9 7044.3 7255.6 7646.8 7780.5 8352.9 8630.0 8631.4 8662.6 8670.8 6165.9 6872.9 7031.6 7452.6 7693.4 7393.1 7467.0 7482.6 7491.0 7546.4 6309.8 6967.4 7055.3 7070.9 7101.7 4974.2 5045.2 5052.4 5107.9 5138.0 4386.8 4946.3 4963.9 5182.8 5209.3 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#4] prototype.html Cycles:180 Average:4296.24 Median:4660.08 stddev:1317.25 (28.3%) stddev-sans-first:1285.98 18:44:41 INFO - PROCESS | 2393 | Values: 279.6 288.4 292.6 312.7 317.2 4195.7 5343.7 5347.4 5490.2 5497.4 5130.6 5317.1 5364.8 5375.9 5390.5 4108.5 4136.2 4158.5 4158.9 4168.9 3087.8 4053.4 4138.2 4150.4 4163.8 3690.7 4043.6 4050.5 4063.6 4064.1 3537.4 3907.2 3908.9 3930.7 4098.1 3359.9 3489.7 3523.2 3530.7 3549.4 4437.4 5166.6 5187.4 5222.8 5248.4 3833.7 4519.5 4686.7 4713.7 4734.7 5478.5 5507.9 5738.8 5746.6 5811.9 3252.5 3435.5 3495.4 3913.5 3916.2 5364.5 5433.6 5980.7 6026.3 6027.2 3759.6 3899.6 3936.1 3950.3 3963.3 3993.5 3997.4 4006.0 4006.0 4019.9 4290.4 4649.9 4651.4 4668.8 4676.8 4607.6 5026.0 5027.9 5604.0 5771.2 1191.8 1346.6 1350.4 1366.3 1376.2 735.6 748.5 792.3 831.0 838.7 3914.9 4277.8 4313.5 4387.1 4530.5 4396.4 4822.6 4988.5 5039.4 5052.6 3888.7 4192.9 4342.0 4377.5 4550.9 2214.8 2252.4 2402.0 2437.5 2641.9 4598.2 4760.4 4840.0 5219.2 5220.1 4465.0 4645.3 5013.6 5046.5 5053.3 4200.1 4698.2 4707.4 5044.8 5159.4 4021.9 4941.9 4958.9 4973.8 4989.4 4026.0 4560.9 4708.6 4909.4 4974.3 4113.7 4507.8 4647.4 5114.8 5134.8 2931.3 3335.2 3438.6 3629.5 3768.6 4338.6 4929.5 5079.6 5214.5 5701.0 4941.3 5622.0 5645.4 5674.3 5697.5 5098.5 5204.6 5280.6 5642.9 5689.6 4334.3 5093.7 5472.3 5515.6 5519.6 4984.0 5607.7 5654.8 5660.7 5688.4 5265.3 5372.4 5639.7 5692.6 5701.0 18:44:41 INFO - PROCESS | 2393 | 18:44:41 INFO - PROCESS | 2393 | [#5] yui.html Cycles:180 Average:24280.48 Median:4278.29 stddev:39051.23 (912.8%) stddev-sans-first:39120.57 18:44:41 INFO - PROCESS | 2393 | Values: 679.2 706.2 765.3 782.5 814.0 15077.8 16066.0 16184.4 16193.9 16234.9 14558.6 14657.6 14669.3 14679.8 14708.5 660.9 692.6 820.8 833.9 854.8 847.2 848.5 849.2 859.6 861.3 881.9 896.3 898.3 900.5 901.4 867.2 870.1 889.8 894.8 898.2 658.6 659.7 668.6 668.7 670.9 15264.3 15525.3 15600.8 15614.7 15646.3 14604.9 14629.6 14677.6 14720.9 14727.8 11851.3 11995.9 12218.1 12409.0 12510.0 3777.4 3787.8 3969.6 4007.7 4031.5 110721.4 110910.0 111066.0 111535.4 116359.0 11192.0 11235.2 11320.7 11366.3 11430.0 23463.5 23467.5 23472.3 23495.5 23571.4 7445.1 7702.7 8067.6 8312.0 8436.5 4280.5 4490.4 4491.4 4515.6 4516.3 3136.9 3444.4 3476.8 3547.1 3576.3 668.4 669.0 670.0 672.5 677.1 505.4 606.2 607.3 633.5 642.1 6578.4 8524.4 8963.8 9038.3 9105.3 534.3 540.4 597.0 616.6 621.1 4276.1 4313.9 4365.3 4452.0 4474.8 97515.0 110644.9 147108.0 158688.0 167842.0 108503.0 120241.4 122426.0 127438.0 128754.8 106430.9 115646.4 117652.4 117827.0 119848.0 8225.7 8506.0 8526.6 8563.7 8645.7 60205.0 60899.0 61672.0 64402.0 65075.0 78737.0 94954.1 97439.5 97763.0 102348.0 10786.4 10949.6 11019.8 11087.2 11320.3 12034.6 12105.9 12181.9 12193.0 12655.3 10990.0 11027.7 11218.6 11670.3 11690.6 11505.2 11622.9 11683.2 11989.5 12070.5 11161.8 11382.7 11438.3 11448.7 11695.1 8436.3 9124.5 9503.6 9591.7 9671.0 8501.4 8894.4 9674.3 9733.3 9797.6 18:44:41 INFO - PROCESS | 2393 | -------- Summary: end -------- 18:44:41 INFO - PROCESS | 2393 | 18:44:42 INFO - TEST-INFO | 2393: exit 0 18:44:42 INFO - TEST-OK | dromaeo_css | took 1113343ms 18:44:42 INFO - TEST-START | kraken 18:44:42 INFO - operating with platform_type : mac_ 18:44:42 INFO - Initialising browser for kraken test... 18:44:42 INFO - TEST-INFO | started process 2399 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpUW_n3i/profile http://localhost:52682/getInfo.html) 18:44:44 INFO - PROCESS | 2399 | 2016-09-30 18:44:44.883 plugin-container[2400:36450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.tsm.portname' 18:44:44 INFO - PROCESS | 2399 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:44 INFO - PROCESS | 2399 | 2016-09-30 18:44:44.886 plugin-container[2400:36450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 18:44:44 INFO - PROCESS | 2399 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:44 INFO - PROCESS | 2399 | 2016-09-30 18:44:44.886 plugin-container[2400:36450] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:44:44 INFO - PROCESS | 2399 | __metrics Screen width/height:1600/1200 18:44:44 INFO - PROCESS | 2399 | colorDepth:24 18:44:44 INFO - PROCESS | 2399 | Browser inner width/height: 1024/689 18:44:44 INFO - PROCESS | 2399 | __metrics 18:44:45 ERROR - PROCESS | 2399 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 18:44:45 INFO - TEST-INFO | 2399: exit 0 18:44:45 INFO - Browser initialized. 18:44:45 INFO - Running cycle 1/1 for kraken test... 18:44:45 INFO - TEST-INFO | started process 2401 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpUW_n3i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:44:47 INFO - PROCESS | 2401 | 2016-09-30 18:44:47.957 plugin-container[2402:36562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882b, name = 'com.apple.tsm.portname' 18:44:47 INFO - PROCESS | 2401 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:47 INFO - PROCESS | 2401 | 2016-09-30 18:44:47.964 plugin-container[2402:36562] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 18:44:47 INFO - PROCESS | 2401 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:47 INFO - PROCESS | 2401 | 2016-09-30 18:44:47.964 plugin-container[2402:36562] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:44:57 INFO - PROCESS | 2401 | RSS: Main: 239861760 18:44:57 INFO - PROCESS | 2401 | 18:45:01 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-beat-detection) 18:45:01 INFO - PROCESS | 2401 | RSS: Main: 253825024 18:45:01 INFO - PROCESS | 2401 | 18:45:04 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-dft) 18:45:05 INFO - PROCESS | 2401 | RSS: Main: 262176768 18:45:05 INFO - PROCESS | 2401 | 18:45:08 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-fft) 18:45:09 INFO - PROCESS | 2401 | RSS: Main: 262557696 18:45:09 INFO - PROCESS | 2401 | 18:45:11 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-oscillator) 18:45:12 INFO - PROCESS | 2401 | RSS: Main: 263335936 18:45:12 INFO - PROCESS | 2401 | 18:45:14 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:45:14 INFO - PROCESS | 2401 | RSS: Main: 263688192 18:45:14 INFO - PROCESS | 2401 | 18:45:17 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:18 INFO - PROCESS | 2401 | RSS: Main: 265293824 18:45:18 INFO - PROCESS | 2401 | 18:45:21 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:21 INFO - PROCESS | 2401 | RSS: Main: 265875456 18:45:21 INFO - PROCESS | 2401 | 18:45:24 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52682/tests/kraken/driver.html?testName=json-parse-financial) 18:45:25 INFO - PROCESS | 2401 | RSS: Main: 265621504 18:45:25 INFO - PROCESS | 2401 | 18:45:27 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:27 INFO - PROCESS | 2401 | RSS: Main: 265494528 18:45:27 INFO - PROCESS | 2401 | 18:45:29 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:30 INFO - PROCESS | 2401 | RSS: Main: 265609216 18:45:30 INFO - PROCESS | 2401 | 18:45:32 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:45:33 INFO - PROCESS | 2401 | RSS: Main: 265625600 18:45:33 INFO - PROCESS | 2401 | 18:45:36 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:45:36 INFO - PROCESS | 2401 | RSS: Main: 265924608 18:45:36 INFO - PROCESS | 2401 | 18:45:39 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:45:40 INFO - PROCESS | 2401 | RSS: Main: 265793536 18:45:40 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52682/tests/kraken/driver.html?testName=ai-astar) 18:45:42 INFO - PROCESS | 2401 | RSS: Main: 267407360 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | __start_tp_report 18:45:42 INFO - PROCESS | 2401 | _x_x_mozilla_page_load 18:45:42 INFO - PROCESS | 2401 | _x_x_mozilla_page_load_details 18:45:42 INFO - PROCESS | 2401 | |i|pagename|runs| 18:45:42 INFO - PROCESS | 2401 | |0;ai-astar;129;174;108;116;155;176;174;125;124;103 18:45:42 INFO - PROCESS | 2401 | |1;audio-beat-detection;161;138;139;189;105;137;145;148;149;115 18:45:42 INFO - PROCESS | 2401 | |2;audio-dft;206;227;262;283;234;224;250;202;195;205 18:45:42 INFO - PROCESS | 2401 | |3;audio-fft;81;85;52;97;52;56;62;84;104;58 18:45:42 INFO - PROCESS | 2401 | |4;audio-oscillator;85;71;86;73;69;68;87;69;61;108 18:45:42 INFO - PROCESS | 2401 | |5;imaging-gaussian-blur;116;87;95;88;90;87;91;88;89;87 18:45:42 INFO - PROCESS | 2401 | |6;imaging-darkroom;100;100;100;133;176;99;101;100;128;147 18:45:42 INFO - PROCESS | 2401 | |7;imaging-desaturate;90;90;147;150;91;89;101;93;91;92 18:45:42 INFO - PROCESS | 2401 | |8;json-parse-financial;56;58;56;57;57;59;58;57;58;59 18:45:42 INFO - PROCESS | 2401 | |9;json-stringify-tinderbox;68;70;72;76;69;82;103;68;73;71 18:45:42 INFO - PROCESS | 2401 | |10;stanford-crypto-aes;92;91;89;89;88;100;85;80;80;81 18:45:42 INFO - PROCESS | 2401 | |11;stanford-crypto-ccm;147;188;157;151;168;163;143;156;179;154 18:45:42 INFO - PROCESS | 2401 | |12;stanford-crypto-pbkdf2;171;171;174;175;179;181;177;175;173;176 18:45:42 INFO - PROCESS | 2401 | |13;stanford-crypto-sha256-iterative;75;77;88;66;75;91;90;92;92;91 18:45:42 INFO - PROCESS | 2401 | __end_tp_report 18:45:42 INFO - PROCESS | 2401 | __start_cc_report 18:45:42 INFO - PROCESS | 2401 | _x_x_mozilla_cycle_collect,937 18:45:42 INFO - PROCESS | 2401 | __end_cc_report 18:45:42 INFO - PROCESS | 2401 | __startTimestamp1475286342591__endTimestamp 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | ------- Summary: start ------- 18:45:42 INFO - PROCESS | 2401 | Number of tests: 14 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#0] ai-astar Cycles:10 Average:138.40 Median:142.00 stddev:28.63 (20.2%) stddev-sans-first:30.17 18:45:42 INFO - PROCESS | 2401 | Values: 129.0 174.0 108.0 116.0 155.0 176.0 174.0 125.0 124.0 103.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:146.50 stddev:23.11 (15.8%) stddev-sans-first:23.54 18:45:42 INFO - PROCESS | 2401 | Values: 161.0 138.0 139.0 189.0 105.0 137.0 145.0 148.0 149.0 115.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#2] audio-dft Cycles:10 Average:228.80 Median:230.50 stddev:28.85 (12.5%) stddev-sans-first:29.39 18:45:42 INFO - PROCESS | 2401 | Values: 206.0 227.0 262.0 283.0 234.0 224.0 250.0 202.0 195.0 205.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#3] audio-fft Cycles:10 Average:73.10 Median:71.50 stddev:19.39 (27.1%) stddev-sans-first:20.35 18:45:42 INFO - PROCESS | 2401 | Values: 81.0 85.0 52.0 97.0 52.0 56.0 62.0 84.0 104.0 58.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:72.00 stddev:13.82 (19.2%) stddev-sans-first:14.40 18:45:42 INFO - PROCESS | 2401 | Values: 85.0 71.0 86.0 73.0 69.0 68.0 87.0 69.0 61.0 108.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:88.50 stddev:8.85 (10.0%) stddev-sans-first:2.62 18:45:42 INFO - PROCESS | 2401 | Values: 116.0 87.0 95.0 88.0 90.0 87.0 91.0 88.0 89.0 87.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#6] imaging-darkroom Cycles:10 Average:118.40 Median:130.50 stddev:26.82 (20.6%) stddev-sans-first:27.61 18:45:42 INFO - PROCESS | 2401 | Values: 100.0 100.0 100.0 133.0 176.0 99.0 101.0 100.0 128.0 147.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#7] imaging-desaturate Cycles:10 Average:103.40 Median:90.50 stddev:24.01 (26.5%) stddev-sans-first:24.98 18:45:42 INFO - PROCESS | 2401 | Values: 90.0 90.0 147.0 150.0 91.0 89.0 101.0 93.0 91.0 92.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.08 (1.9%) stddev-sans-first:1.00 18:45:42 INFO - PROCESS | 2401 | Values: 56.0 58.0 56.0 57.0 57.0 59.0 58.0 57.0 58.0 59.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:71.50 stddev:10.65 (14.9%) stddev-sans-first:10.98 18:45:42 INFO - PROCESS | 2401 | Values: 68.0 70.0 72.0 76.0 69.0 82.0 103.0 68.0 73.0 71.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:88.50 stddev:6.28 (7.1%) stddev-sans-first:6.44 18:45:42 INFO - PROCESS | 2401 | Values: 92.0 91.0 89.0 89.0 88.0 100.0 85.0 80.0 80.0 81.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#11] stanford-crypto-ccm Cycles:10 Average:160.60 Median:160.00 stddev:14.20 (8.9%) stddev-sans-first:14.18 18:45:42 INFO - PROCESS | 2401 | Values: 147.0 188.0 157.0 151.0 168.0 163.0 143.0 156.0 179.0 154.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.50 stddev:3.22 (1.8%) stddev-sans-first:3.04 18:45:42 INFO - PROCESS | 2401 | Values: 171.0 171.0 174.0 175.0 179.0 181.0 177.0 175.0 173.0 176.0 18:45:42 INFO - PROCESS | 2401 | 18:45:42 INFO - PROCESS | 2401 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.70 Median:90.50 stddev:9.50 (10.5%) stddev-sans-first:9.54 18:45:42 INFO - PROCESS | 2401 | Values: 75.0 77.0 88.0 66.0 75.0 91.0 90.0 92.0 92.0 91.0 18:45:42 INFO - PROCESS | 2401 | -------- Summary: end -------- 18:45:42 INFO - PROCESS | 2401 | 18:45:43 INFO - TEST-INFO | 2401: exit 0 18:45:43 INFO - TEST-OK | kraken | took 61135ms 18:45:43 INFO - SUITE-END | took 1174s 18:45:43 INFO - Completed test suite (00:19:34) 18:45:43 INFO - javascript benchmark 18:45:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7111.1085934837065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2759.337758937853, 2794.5249307617705, 3339.8831040913574, 3381.932361352772, 3390.288039511702, 4635.443746750386, 5603.551715862726, 5626.606137570372, 5634.95753285642, 5708.628939118955, 5558.221848941152, 5653.575981801361, 5673.574481913853, 5712.99999999999, 5749.942500574993, 4248.575142485742, 5080.237964305356, 5186.636935414523, 5191.273221748958, 5217.843464696065, 4684.08220132358, 5193.45468725784, 5203.687778733288, 5290.814821481249, 5351.678899266037, 5218.895622087575, 5234.7120908350025, 5246.947530524684, 5286.841394758163, 5293.285406470135, 5084.745762711849, 5134.486551344873, 5147.202183661501, 5173.224016397533, 5248.921266181011, 4632.976835115836, 5151.6908985461, 5152.845414637567, 5192.299039629639, 5242.318498595158, 5545.694986775726, 5634.549236061146, 5686.943130568724, 5715.571332150105, 5719.25649665545, 6044.637321760709, 6113.35468597686, 6146.631202127842, 6252.781152659635, 6269.279032911228, 6255.562110652256, 6297.842553936142, 6319.905201421982, 6338.556301058928, 6340.0, 5754.338251101093, 5759.136129580555, 5765.452282033242, 5772.0, 5834.854128646775, 6119.715820550745, 6236.781712640036, 6307.0, 6314.463270622048, 6318.462930650946, 6013.849653758691, 6062.030075187949, 6099.176611157525, 6121.117656725397, 6147.108669242934, 6230.813075607739, 6255.030470277115, 6261.655608941461, 6271.905921411182, 6281.7173227204885, 6062.060380641008, 6068.817935461943, 6111.682900513672, 6185.443310102112, 6189.257289125334, 6147.415995480422, 6249.156363890907, 6261.373862613702, 6263.906041409383, 6299.905501417483, 4900.166971614834, 4916.164252077156, 4938.851834444972, 4943.90112197754, 4948.282499037619, 4315.741055536678, 4331.371951067141, 4439.223135951195, 4451.198784218872, 4459.9331010034875, 5749.108888122348, 5791.102379131242, 5820.738066787005, 5880.676562789002, 5925.0, 6227.0, 6272.780452684134, 6285.96857015712, 6304.527160462983, 6309.495240380758, 5684.63049901755, 5893.970530147408, 5894.970525147432, 5925.005591034483, 5977.252843394575, 5359.3032905722, 5401.3248343957, 5404.081306177959, 5428.077226871442, 5508.173773933861, 5058.3929928408825, 6062.878742425127, 6323.0, 6326.778562750282, 6381.553291269566, 5827.3007239131575, 6184.917639413175, 6254.937450625435, 6297.811065668037, 6304.0, 6187.257529096537, 6238.625682459067, 6244.562880598496, 6258.155149054819, 6258.968705156445, 5182.481751824938, 6190.752369905334, 6202.193714817045, 6239.812805615838, 6261.154744109487, 6094.56777657259, 6272.686365681789, 6290.591111577733, 6293.0, 6329.240491141092, 5889.234399528034, 6034.879302414015, 6052.878942421215, 6090.543209259412, 6156.0, 6101.633901965896, 6125.601746185103, 6147.385261473817, 6171.876562468815, 6211.409916057967, 5659.462351076641, 5827.067669172912, 5997.820065398045, 6013.338532761481, 6022.187004754477, 6012.969935150296, 6058.697065146813, 6090.725917333819, 6101.145839582373, 6172.475339596184, 5292.497212764782, 5737.311522617146, 5927.051671732502, 5949.613275137275, 6011.639301641915], "value": 5654.989465762287, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.88504990348525, 484.6292586171579, 495.5094456488078, 523.4190049045559, 533.7811497286112, 38237.61762382375, 39774.60225397745, 39857.202855942865, 42381.36427953583, 42406.575934240645, 41148.794256028756, 41731.95670108241, 41771.0, 41787.373189402104, 41793.58206417935, 9697.078777516143, 9903.752406189868, 9909.653162139326, 9930.155936745385, 9931.751706207366, 9743.22054235663, 9813.852792208123, 9834.0, 9846.113849753521, 9849.901500985006, 9873.506324683773, 9884.110430061293, 9887.505624718771, 9894.901050989505, 9921.35511191774, 9802.41185528867, 9845.655402060966, 9848.162906152986, 9854.802903941953, 9870.2103831694, 9716.514174291256, 9806.215502759833, 9807.656732014417, 9827.852582211273, 9835.0, 39274.21451570953, 39412.802935985426, 39757.608483702934, 39758.40362394566, 39801.0, 2763.6130941668184, 3364.4112280351087, 3468.670476304747, 3546.202104526487, 3697.2235830475634, 29939.550906736415, 30028.8498557508, 30849.84575077133, 30952.690473095205, 31098.689013109804, 2800.085849334405, 3187.920301992469, 3204.455242608762, 3213.775035747499, 3260.7065364117107, 492264.0, 499413.0, 499460.0, 499539.0, 501294.0, 32414.513782293285, 33897.66102338945, 55969.0, 56422.0, 56637.15044274339, 34302.313953721285, 34323.97028089161, 34354.0, 34544.30911381758, 34614.307713845585, 14243.0, 14352.0, 14377.0, 14377.6405589859, 14402.85597144015, 11955.103367247491, 12068.97413719826, 12103.63689089311, 12144.332061736513, 12165.33090680021, 7614.657340419583, 7625.1231108422135, 7643.885341719879, 7720.613969301512, 7748.418868584766, 3231.74146068314, 3283.326917981808, 3288.1122097033667, 3294.769366144395, 3322.3853587085982, 937.3204426790659, 966.6085235479642, 980.7940332530248, 1004.8995100489892, 1077.5097330714507, 29517.704822951924, 29534.852325738662, 29636.851815741215, 30187.396252074836, 30293.69706302953, 987.3138168972639, 1027.419507978005, 1058.809414305417, 1120.1990576737671, 1136.3977092141179, 14678.926605366905, 15199.924000380148, 15269.923650381677, 15428.305726242346, 15455.826321638186, 693713.0, 705158.0, 708462.4576877083, 708915.0, 709520.0, 55226.0, 55754.0, 56142.719286403306, 56456.717716411156, 56493.717531412905, 1341.9463221471226, 1346.4816045822233, 1392.436063394327, 1397.1337770582102, 1402.9298535073206, 24890.751092488845, 28577.14268571946, 28932.20364221207, 29147.85426072856, 29315.267118322212, 46223.0, 46620.53379466162, 46626.0, 46896.7655161722, 47128.52871471241, 596811.0, 603019.0, 605591.9720401369, 605724.0, 606731.0, 25687.357816054748, 25740.683338499624, 26045.91632669344, 26088.478230434906, 26094.86952565225, 45427.77286113681, 46317.84205394892, 46588.83527911829, 46734.53265467302, 46864.7656761714, 37480.437793432575, 37528.62471375251, 37691.62308376881, 38890.80554597209, 39077.21845563129, 37663.246735065695, 38112.81153480811, 38694.0, 38703.41944870885, 39079.0, 34605.30789384249, 36522.0, 37696.86909392723, 38016.049598760255, 40103.79948100241, 32528.0, 33881.19089036819, 33887.0, 34136.0, 34236.657633424344, 19513.317033903746, 20267.0, 20892.895535522224, 21106.577868442237, 21335.573288533833], "value": 18391.588283015502, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.45088690633685, 365.37156091522587, 369.1859449912942, 379.7835233916665, 394.378853306043, 16254.593635159126, 19381.709274360877, 19978.90010549945, 20787.688184677223, 20972.265970691027, 19293.0, 19477.220911163535, 19951.900240498777, 19982.900085499554, 20339.49151271219, 4746.454157771848, 5234.895302093956, 5417.133258678612, 5422.864428389302, 6019.247594050744, 5892.646441213519, 6027.60820546663, 6031.0, 6031.969840150793, 6035.969820150915, 5692.203091567184, 5884.617114977977, 5921.259842519685, 5947.672877991708, 5979.431953964367, 5867.647941123546, 5888.528917686596, 5958.433948774881, 5965.582409231356, 5968.97015514924, 5024.447310795809, 5195.0, 5232.92150617741, 5255.02781985334, 5255.237990491358, 12689.428975696115, 13060.542880999119, 13068.607941761762, 13186.208827470382, 13199.538016169388, 2113.292047164202, 2239.193890199527, 2252.4819291562867, 2275.203678712454, 2337.474068334628, 9757.170640495566, 9829.213662907021, 10111.49442527878, 10156.0, 10249.795004099951, 1837.889726616394, 1878.6994080946984, 1909.598984213317, 1935.8451323894055, 1945.8540609454205, 114961.0, 118884.0, 119026.4048679751, 119851.0, 120875.0, 11259.128922124806, 11371.601993930173, 11582.88417115818, 11733.471993760131, 11766.117541184445, 14471.276436178148, 14481.275936203148, 14549.19979401122, 14564.49024284145, 14599.78100328496, 8279.54462504556, 8432.704855330034, 8443.451175673561, 8452.788680283042, 8583.828323433496, 7067.399271061914, 7396.704131834774, 7446.851062978819, 8078.586247406681, 8220.136885626924, 4075.409065685459, 4132.049628585382, 4425.889352766207, 4506.549345065533, 4607.539246075366, 1553.9766903496457, 1878.5679293762512, 1929.0451226642901, 1971.1228503315888, 2006.1273346094204, 797.968081276754, 880.8942926848819, 935.3779736475303, 964.5466630683568, 1012.5198728139964, 18956.715649265272, 21641.56716865654, 21663.566728665337, 21678.783212167677, 21754.238601648867, 732.045438678729, 898.5462341517492, 911.0798093925183, 968.8837339519162, 980.6469670918464, 10718.334228267966, 11375.431228438545, 11504.712382190512, 11527.654170374735, 11574.363410012531, 574845.0, 575058.0, 575168.0, 582598.0, 584123.0, 75910.0, 128669.0, 131766.68233317544, 131817.0, 140303.0, 833.6373677450329, 949.6581230756922, 997.4065431068502, 998.7752755630071, 1021.9744506387251, 16295.348186072424, 18822.341218057303, 19217.61564768669, 20234.19063237513, 20322.288719894732, 39955.0, 51783.74108129435, 56751.0, 56864.14703779364, 57038.714806425705, 80208.59895700484, 81784.59107704423, 82031.07813531207, 82792.17207827844, 84246.15753842382, 6789.0, 7902.328302094386, 8119.30985866208, 8214.58927053633, 8280.627371768165, 10820.891791082304, 11077.944610276896, 11092.668879734483, 11809.704757381134, 12319.815202771786, 10928.836067458835, 11067.557297708001, 11143.33140011602, 11298.943505282421, 11336.943315283701, 10961.945190273998, 10986.890131098588, 11091.279066860629, 11177.161712871404, 11495.425228738362, 11025.83461248098, 11219.439028048728, 11368.658940231806, 11501.0, 11746.001589864958, 8055.704124324539, 8058.798530036796, 8060.476069055492, 8291.62687679068, 8291.709790157556, 8102.392320576098, 10741.409222492837, 10811.891881081403, 11203.383813890314, 11465.0], "value": 9241.65197412867, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1527.8701310388617, 1564.3507944203157, 1794.9012804295767, 1847.7967423583411, 1896.5732710140212, 8038.557879316635, 8311.584420778967, 8519.233269005788, 9085.500297483635, 9098.727038188848, 8494.193051660082, 8809.95595022024, 8838.204561589455, 8932.240759535447, 8965.0, 1972.6942323939813, 1982.672858978267, 2370.0401337458356, 2410.7348191698898, 2411.011485291031, 2361.055577768898, 2385.8210634202505, 2411.2766170148975, 2413.746556611557, 2415.7342692303832, 2098.842586805988, 2135.1032566322106, 2284.6458798886115, 2372.7983121434613, 2398.268528098929, 2368.7512811154925, 2380.5119950410126, 2384.332386931662, 2397.4006498375406, 2414.275717284825, 2064.967516241879, 2294.8737819419925, 2371.620540713495, 2372.181597348912, 2387.7492863249454, 8803.559822008874, 8940.597673104727, 8980.326475514297, 8980.55097245135, 9003.054679258648, 5297.073012222845, 5452.91820622691, 5459.3994660587305, 5472.26124473195, 5475.151351540478, 3363.360961417323, 3617.457381392786, 3727.813609319523, 3733.5146430964073, 3748.081719978623, 1017.6082208349765, 1026.7730062575226, 1052.841873938669, 1085.8914108589156, 1098.9450527473593, 11583.536658533649, 11645.0, 11656.242344247597, 11668.35824029678, 11673.0, 4606.838760643362, 4728.219843725816, 4841.782119804547, 4853.927191092136, 4876.4879687633, 10038.297319187734, 10118.443485608363, 10123.64567240143, 10139.797204056025, 10161.898381016243, 5371.785128594813, 5412.91880621791, 5445.401005889349, 5449.64577302474, 5455.072637651609, 4429.712068715523, 4661.743604101807, 4899.902001959941, 4969.130402179604, 4975.328330675385, 3274.492453669685, 3964.187341595024, 4049.635532802062, 4075.5313138988804, 4113.403556484293, 1291.7351942851692, 1311.4098655605017, 1315.5855905389963, 1323.503686117706, 1331.6404570766033, 856.5888373580716, 858.9355798315152, 907.6414816147585, 912.5528491039342, 927.5501381829807, 5779.566532510079, 5807.767689292381, 6008.939910600925, 6036.577439579188, 6174.351693072163, 928.7910220200536, 949.3307218411004, 972.5429048347265, 973.6932866147141, 986.2307400227834, 3502.772319799205, 3959.940600890989, 3966.6628336591134, 4046.716729828943, 4092.7748973806724, 9847.409155450696, 10833.783324333626, 10855.7975397258, 10860.13118950482, 10863.0, 10650.946745266225, 10687.305325153995, 10739.35563866155, 10775.976282253174, 10831.620893268855, 8236.341092712568, 8353.874691879626, 8364.414490985695, 8365.121662225378, 8386.371022173362, 5647.604667673223, 5716.714164291686, 5863.618864773776, 5964.761409543743, 5977.133315669116, 10164.593416263267, 10188.337758045704, 10196.0, 10264.743381415226, 10303.639372621923, 9820.803583928138, 10222.386656800616, 10276.0, 10402.323848949794, 10440.216983726403, 5725.8568535786935, 7044.295570442915, 7255.637218138966, 7646.8088297793, 7780.533168009937, 8352.916470835215, 8629.964404271528, 8631.395802293779, 8662.610182541675, 8670.82658346842, 6165.85931602637, 6872.931270687429, 7031.648417578998, 7452.552846829208, 7693.384529237535, 7393.112826460859, 7467.0, 7482.625868706652, 7491.0, 7546.396288297033, 6309.779157729457, 6967.372936435745, 7055.2944705529035, 7070.893936590853, 7101.680424380812, 4974.228994505857, 5045.217991211368, 5052.418971818288, 5107.9489205107475, 5138.023775482647, 4386.802593883347, 4946.282788995647, 4963.925541116814, 5182.766775495187, 5209.270702101785], "value": 4610.609287560419, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.6266983576925, 288.39437181917975, 292.6079054067549, 312.7123046796948, 317.2212219002349, 4195.727277726945, 5343.732813359326, 5347.385050719168, 5490.231367608533, 5497.39528651848, 5130.640855140142, 5317.1021793122745, 5364.839054828351, 5375.892482150364, 5390.5418039466595, 4108.459327752101, 4136.193442278757, 4158.480189976253, 4158.939470435035, 4168.895777605554, 3087.7684173686953, 4053.412255222991, 4138.172365526892, 4150.439690641755, 4163.770992595406, 3690.7231957603153, 4043.6158564936286, 4050.534188568323, 4063.573324800898, 4064.1304999700174, 3537.4340105582983, 3907.2185562887535, 3908.863189788344, 3930.6658933990643, 4098.098418347973, 3359.9496007559906, 3489.731674411653, 3523.154442933703, 3530.717542596586, 3549.4143466328023, 4437.400950871655, 5166.612504062173, 5187.351581052369, 5222.817201397933, 5248.422673505911, 3833.731638785286, 4519.4802597701355, 4686.718796872198, 4713.717176969359, 4734.668573199878, 5478.506934375885, 5507.917381239285, 5738.766165274486, 5746.62081100537, 5811.941880581225, 3252.5363586385984, 3435.4846772984, 3495.4407294832704, 3913.4521167036637, 3916.15802602439, 5364.544013758835, 5433.646812957185, 5980.671063091529, 6026.306974697922, 6027.216461859974, 3759.586445490994, 3899.6100389960775, 3936.110666879914, 3950.3481925481974, 3963.3460479020637, 3993.5407428145554, 3997.380406037069, 4006.0, 4006.0385507477995, 4019.8593049243136, 4290.356446533045, 4649.930251046237, 4651.37206477128, 4668.789904454307, 4676.796394469799, 4607.585317321457, 5026.019926114445, 5027.949720502748, 5603.971980140073, 5771.249737534177, 1191.7676053169723, 1346.6094832498466, 1350.3653282956996, 1366.3100134432248, 1376.181172202538, 735.5843948169374, 748.4798065344534, 792.2513225002433, 830.9916900831035, 838.7190291252376, 3914.8629797956933, 4277.786110694515, 4313.482382114166, 4387.056782791715, 4530.478994915561, 4396.384151429894, 4822.614190864722, 4988.476209997898, 5039.370078740158, 5052.621053421009, 3888.650021498079, 4192.937105943413, 4342.002678767739, 4377.474703035592, 4550.8610952852105, 2214.7563767985507, 2252.4368907773055, 2402.0423354965646, 2437.512497500507, 2641.947161056768, 4598.2412901871485, 4760.404949381327, 4839.9758001210475, 5219.19102539107, 5220.086484865209, 4464.99537604043, 4645.25929701471, 5013.573846223112, 5046.4953504649975, 5053.317802096743, 4200.051951165894, 4698.154332220233, 4707.388039554836, 5044.772985215747, 5159.432462429129, 4021.8994525137105, 4941.888075183198, 4958.851234462972, 4973.825916093064, 4989.376327959005, 4025.9798701006307, 4560.88597785058, 4708.599769019672, 4909.410870695539, 4974.328465657235, 4113.691473139586, 4507.797149128362, 4647.3726046984575, 5114.820981265638, 5134.820281290137, 2931.2525306046823, 3335.232896433882, 3438.5529881115294, 3629.4555816627717, 3768.6419790119894, 4338.58783415575, 4929.531694489017, 5079.644424890223, 5214.539928819937, 5701.035461560048, 4941.308216849572, 5621.988042152452, 5645.435456454322, 5674.262345895172, 5697.4587414195585, 5098.490150984871, 5204.635675502678, 5280.60395470349, 5642.887142257214, 5689.573282003949, 4334.28484300087, 5093.668911520739, 5472.251507717227, 5515.641483303572, 5519.613627046068, 4984.0, 5607.719614019365, 5654.802081927277, 5660.7169641518585, 5688.431156884279, 5265.2891859599995, 5372.382176049804, 5639.724755066083, 5692.556003617751, 5700.973824711426], "value": 3877.2871340730294, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.2392520377176, 706.2090458686272, 765.3073968058909, 782.4835608498389, 813.979650508737, 15077.849221507782, 16066.0, 16184.433544825948, 16193.91903040486, 16234.863559550837, 14558.636034099152, 14657.560273191795, 14669.339879705412, 14679.779803302932, 14708.48520301787, 660.8678264347126, 692.5636848785258, 820.831729495452, 833.9207775261341, 854.8418542569614, 847.1697736218479, 848.4612271207797, 849.1550906847696, 859.5916939453741, 861.3496809908511, 881.8633111867671, 896.2605850173633, 898.2813749000806, 900.4822227219342, 901.3960646366917, 867.2368315882015, 870.0690261420283, 889.7508697564692, 894.8344556257114, 898.2454738020061, 658.5818005566451, 659.650385295794, 668.6021817018866, 668.6890595872942, 670.8825955457825, 15264.313105910149, 15525.301361438762, 15600.765988510182, 15614.687706245924, 15646.295916683777, 14604.853951460456, 14629.6342591435, 14677.633059173499, 14720.852791472056, 14727.779083313759, 11851.348175850324, 11995.94002029993, 12218.144729868913, 12409.007279417625, 12510.0, 3777.395616701342, 3787.8106094695154, 3969.622885825842, 4007.6593489553425, 4031.475908131924, 110721.44639276832, 110910.0, 111066.0, 111535.44232278867, 116358.99999999916, 11192.0, 11235.213535052631, 11320.716982075513, 11366.261193022427, 11430.028447582048, 23463.53072938532, 23467.53064938726, 23472.295831125095, 23495.530089398115, 23571.410714731926, 7445.10658720957, 7702.730404435818, 8067.556284404297, 8312.002559692755, 8436.493810371398, 4280.464941882265, 4490.438695163105, 4491.393661855673, 4515.616172625298, 4516.299973504112, 3136.9372612548077, 3444.4488881778793, 3476.791392516457, 3547.0954906498678, 3576.3204991051616, 668.3725129310145, 669.0399277037467, 669.9732010719516, 672.4821887146869, 677.1231255524108, 505.44906052402325, 606.1846816032429, 607.2894713185583, 633.4932054356497, 642.1459458919626, 6578.440832529192, 8524.445911015764, 8963.820723585492, 9038.276937844958, 9105.271578273721, 534.2733881920662, 540.41635034163, 596.9850753731191, 616.5868867858455, 621.142822904389, 4276.123394704078, 4313.9137217255475, 4365.301551751704, 4451.9554804451545, 4474.753888536096, 97515.0, 110644.89355106346, 147108.0, 158688.0, 167842.0, 108503.0, 120241.39879300547, 122426.0, 127438.0, 128754.84980600858, 106430.93569064364, 115646.42176789063, 117652.41173794077, 117827.0, 119848.0, 8225.683890577478, 8506.0, 8526.573671316286, 8563.657453702031, 8645.740627781177, 60205.0, 60899.0, 61672.0, 64402.0, 65075.0, 78737.0, 94954.05045949729, 97439.53840692254, 97763.02236977823, 102347.97652023385, 10786.406747628957, 10949.616763413242, 11019.779604407706, 11087.22389432732, 11320.320780753182, 12034.63896083119, 12105.939470302592, 12181.878181218075, 12192.963598094262, 12655.303958282382, 10990.0, 11027.669169924915, 11218.607348742755, 11670.299782013106, 11690.590829320932, 11505.194636375374, 11622.94188529052, 11683.240589361665, 11989.460474278852, 12070.45682944252, 11161.776764464828, 11382.715432114263, 11438.256513326607, 11448.713782155513, 11695.064394848561, 8436.277180642826, 9124.452532847785, 9503.619855205714, 9591.664291749756, 9670.951645241728, 8501.410104004535, 8894.377393582387, 9674.274429417681, 9733.270004749531, 9797.559109840216], "value": 7524.749681664991, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 174.0, 108.0, 116.0, 155.0, 176.0, 174.0, 125.0, 124.0, 103.0], "value": 138.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 138.0, 139.0, 189.0, 105.0, 137.0, 145.0, 148.0, 149.0, 115.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 227.0, 262.0, 283.0, 234.0, 224.0, 250.0, 202.0, 195.0, 205.0], "value": 228.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 52.0, 97.0, 52.0, 56.0, 62.0, 84.0, 104.0, 58.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 86.0, 73.0, 69.0, 68.0, 87.0, 69.0, 61.0, 108.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 87.0, 95.0, 88.0, 90.0, 87.0, 91.0, 88.0, 89.0, 87.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 100.0, 133.0, 176.0, 99.0, 101.0, 100.0, 128.0, 147.0], "value": 118.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 147.0, 150.0, 91.0, 89.0, 101.0, 93.0, 91.0, 92.0], "value": 103.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 57.0, 57.0, 59.0, 58.0, 57.0, 58.0, 59.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 72.0, 76.0, 69.0, 82.0, 103.0, 68.0, 73.0, 71.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 89.0, 89.0, 88.0, 100.0, 85.0, 80.0, 80.0, 81.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 188.0, 157.0, 151.0, 168.0, 163.0, 143.0, 156.0, 179.0, 154.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 174.0, 175.0, 179.0, 181.0, 177.0, 175.0, 173.0, 176.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 88.0, 66.0, 75.0, 91.0, 90.0, 92.0, 92.0, 91.0], "value": 83.7, "unit": "score"}]}]} 18:45:43 INFO - Return code: 0 18:45:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:45:43 ERROR - # TBPL SUCCESS # 18:45:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:45:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:45:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:45:43 INFO - 2016-09-30 18:45:43.875 screenresolution[2406:36946] starting screenresolution argv=screenresolution get 18:45:43 INFO - 2016-09-30 18:45:43.877 screenresolution[2406:36946] Display 0: 1600x1200x32@60 18:45:43 INFO - 2016-09-30 18:45:43.884 screenresolution[2407:36949] starting screenresolution argv=screenresolution list 18:45:43 INFO - Available Modes on Display 0 18:45:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:45:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:45:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:45:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:45:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:45:44 INFO - Intel Iris: 18:45:44 INFO - Chipset Model: Intel Iris 18:45:44 INFO - Type: GPU 18:45:44 INFO - Bus: Built-In 18:45:44 INFO - VRAM (Dynamic, Max): 1536 MB 18:45:44 INFO - Vendor: Intel (0x8086) 18:45:44 INFO - Device ID: 0x0a2e 18:45:44 INFO - Revision ID: 0x0009 18:45:44 INFO - Displays: 18:45:44 INFO - Display: 18:45:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:45:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:45:44 INFO - Display Serial Number: 1600x1200 60 18:45:44 INFO - Main Display: Yes 18:45:44 INFO - Mirror: Off 18:45:44 INFO - Online: Yes 18:45:44 INFO - Rotation: Supported 18:45:44 INFO - Return code: 0 18:45:44 INFO - Running post-action listener: _resource_record_post_action 18:45:44 INFO - [mozharness: 2016-10-01 01:45:44.121019Z] Finished run-tests step (success) 18:45:44 INFO - Running post-run listener: _resource_record_post_run 18:45:44 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 1103360; Write bytes: 708613120; Read time: 11; Write time: 2033 18:45:44 INFO - TinderboxPrint: CPU usage
54.6% 18:45:44 INFO - TinderboxPrint: I/O read bytes / time
1,103,360 / 11 18:45:44 INFO - TinderboxPrint: I/O write bytes / time
708,613,120 / 2,033 18:45:44 INFO - TinderboxPrint: CPU idle
2,194.1 (45.4%) 18:45:44 INFO - TinderboxPrint: CPU system
980.9 (20.3%) 18:45:44 INFO - TinderboxPrint: CPU user
1,662.7 (34.4%) 18:45:44 INFO - TinderboxPrint: Swap in / out
408,309,760 / 0 18:45:44 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395596288; Write bytes: 390027264; Read time: 29260; Write time: 814 18:45:44 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 1103360; Write bytes: 296105472; Read time: 11; Write time: 1157 18:45:44 INFO - Running post-run listener: _upload_blobber_files 18:45:44 INFO - Blob upload gear active. 18:45:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:45:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:45:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:45:45 INFO - (blobuploader) - INFO - Open directory for files ... 18:45:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:45:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:45:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:46 INFO - (blobuploader) - INFO - Done attempting. 18:45:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:45:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:45:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:47 INFO - (blobuploader) - INFO - Done attempting. 18:45:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:45:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:45:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:45:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:45:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:45:50 INFO - (blobuploader) - INFO - Done attempting. 18:45:50 INFO - (blobuploader) - INFO - Iteration through files over. 18:45:50 INFO - Return code: 0 18:45:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:45:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:45:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6fd2e93adf1f61d07ca68c954d13d40e8ca90320c45903da01fd5e7b6751dc93c7746671d6af1ac30748cdd85f32178005843101cb717cdca90f5d79c0e4bce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8bb5f1a37962d87e58f7cd9b30e47ff040236433d4895343f546231a46e9cc9288c9386fb816781de2da9cb499fa0812a36f9d6a2a03adc8ae409d377a854b81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a0a8036bf1be5d2c552eb81393fca58c54f86cdaaa927b4bf4c663d0f1e480dda7a0585c466d078d1f4e30e831d33f20acb760aba9a4cc8e965a553eb7c09dd"} 18:45:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:45:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:45:50 INFO - Contents: 18:45:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6fd2e93adf1f61d07ca68c954d13d40e8ca90320c45903da01fd5e7b6751dc93c7746671d6af1ac30748cdd85f32178005843101cb717cdca90f5d79c0e4bce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8bb5f1a37962d87e58f7cd9b30e47ff040236433d4895343f546231a46e9cc9288c9386fb816781de2da9cb499fa0812a36f9d6a2a03adc8ae409d377a854b81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a0a8036bf1be5d2c552eb81393fca58c54f86cdaaa927b4bf4c663d0f1e480dda7a0585c466d078d1f4e30e831d33f20acb760aba9a4cc8e965a553eb7c09dd"} 18:45:50 INFO - Running post-run listener: copy_logs_to_upload_dir 18:45:50 INFO - Copying logs to upload dir... 18:45:50 INFO - mkdir: /builds/slave/test/build/upload/logs 18:45:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.380248 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-30 18:45:50.956453) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 18:45:50.961325) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6fd2e93adf1f61d07ca68c954d13d40e8ca90320c45903da01fd5e7b6751dc93c7746671d6af1ac30748cdd85f32178005843101cb717cdca90f5d79c0e4bce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8bb5f1a37962d87e58f7cd9b30e47ff040236433d4895343f546231a46e9cc9288c9386fb816781de2da9cb499fa0812a36f9d6a2a03adc8ae409d377a854b81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a0a8036bf1be5d2c552eb81393fca58c54f86cdaaa927b4bf4c663d0f1e480dda7a0585c466d078d1f4e30e831d33f20acb760aba9a4cc8e965a553eb7c09dd"} build_url:https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/avWJ5VCMS8ezCVSXZA03uA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6fd2e93adf1f61d07ca68c954d13d40e8ca90320c45903da01fd5e7b6751dc93c7746671d6af1ac30748cdd85f32178005843101cb717cdca90f5d79c0e4bce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8bb5f1a37962d87e58f7cd9b30e47ff040236433d4895343f546231a46e9cc9288c9386fb816781de2da9cb499fa0812a36f9d6a2a03adc8ae409d377a854b81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a0a8036bf1be5d2c552eb81393fca58c54f86cdaaa927b4bf4c663d0f1e480dda7a0585c466d078d1f4e30e831d33f20acb760aba9a4cc8e965a553eb7c09dd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 18:45:50.993831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:45:50.997174) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SIWKgF4j5S/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.zreFG6e40c/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005202 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 18:45:51.045692) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 18:45:51.046008) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 18:45:51.046324) ========= ========= Total master_lag: 0.19 =========