builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0036 starttime: 1476278062.19 results: success (0) buildid: 20161012030211 builduid: a769541e4bb74131a09b21df7c55a423 revision: 500baee3ce9f1b701ace9d38489cbc112d076c72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.187359) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.187832) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.188104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.212065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.212466) ========= 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.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-10-12 06:14:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-10-12 06:14:22 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121920 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.356444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.356857) ========= 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.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.020815 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:22.408193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 06:14:22.408606) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 500baee3ce9f1b701ace9d38489cbc112d076c72 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 500baee3ce9f1b701ace9d38489cbc112d076c72 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-10-12 06:14:22,480 truncating revision to first 12 chars 2016-10-12 06:14:22,480 Setting DEBUG logging. 2016-10-12 06:14:22,481 attempt 1/10 2016-10-12 06:14:22,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/500baee3ce9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 06:14:23,245 unpacking tar archive at: mozilla-central-500baee3ce9f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.042660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 06:14:23.473318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:23.473634) ========= script_repo_revision: 500baee3ce9f1b701ace9d38489cbc112d076c72 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:23.474035) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:23.474356) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 06:14:23.493188) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-12 06:14:23.493569) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:14:23 INFO - MultiFileLogger online at 20161012 06:14:23 in /builds/slave/test 06:14:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:14:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:23 INFO - {'append_to_log': False, 06:14:23 INFO - 'base_work_dir': '/builds/slave/test', 06:14:23 INFO - 'blob_upload_branch': 'Firefox', 06:14:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:23 INFO - 'branch': 'Firefox', 06:14:23 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:23 INFO - 'config_files': ('talos/mac_config.py',), 06:14:23 INFO - 'default_actions': ('clobber', 06:14:23 INFO - 'read-buildbot-config', 06:14:23 INFO - 'download-and-extract', 06:14:23 INFO - 'populate-webroot', 06:14:23 INFO - 'create-virtualenv', 06:14:23 INFO - 'install', 06:14:23 INFO - 'run-tests'), 06:14:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:23 INFO - 'download_minidump_stackwalk': True, 06:14:23 INFO - 'download_symbols': 'ondemand', 06:14:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:23 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:23 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:23 INFO - 'installer_path': 'installer.exe', 06:14:23 INFO - 'log_level': 'info', 06:14:23 INFO - 'log_name': 'talos', 06:14:23 INFO - 'log_to_console': True, 06:14:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:14:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:14:23 INFO - 'opt_config_files': (), 06:14:23 INFO - 'pip_index': False, 06:14:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:23 INFO - 'cmd': ('bash', 06:14:23 INFO - '-c', 06:14:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:23 INFO - 'enabled': True, 06:14:23 INFO - 'halt_on_failure': False, 06:14:23 INFO - 'name': 'check_screen_resolution'},), 06:14:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:23 INFO - 'cmd': ('bash', 06:14:23 INFO - '-c', 06:14:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:23 INFO - 'enabled': True, 06:14:23 INFO - 'halt_on_failure': False, 06:14:23 INFO - 'name': 'check_screen_resolution'},), 06:14:23 INFO - 'run_cmd_checks_enabled': True, 06:14:23 INFO - 'sps_profile': False, 06:14:23 INFO - 'sps_profile_interval': 0, 06:14:23 INFO - 'suite': 'dromaeojs', 06:14:23 INFO - 'system_bits': '32', 06:14:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:23 INFO - 'title': 't-yosemite-r7-0036', 06:14:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:23 INFO - 'use_talos_json': True, 06:14:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:23 INFO - 'work_dir': 'build'} 06:14:23 INFO - [mozharness: 2016-10-12 13:14:23.706568Z] Running clobber step. 06:14:23 INFO - Running pre-action listener: _resource_record_pre_action 06:14:23 INFO - Running main action method: clobber 06:14:23 INFO - rmtree: /builds/slave/test/build 06:14:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:24 INFO - Running post-action listener: _resource_record_post_action 06:14:24 INFO - [mozharness: 2016-10-12 13:14:24.661146Z] Finished clobber step (success) 06:14:24 INFO - [mozharness: 2016-10-12 13:14:24.661283Z] Running read-buildbot-config step. 06:14:24 INFO - Running pre-action listener: _resource_record_pre_action 06:14:24 INFO - Running main action method: read_buildbot_config 06:14:24 INFO - Using buildbot properties: 06:14:24 INFO - { 06:14:24 INFO - "project": "", 06:14:24 INFO - "product": "firefox", 06:14:24 INFO - "script_repo_revision": "production", 06:14:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:14:24 INFO - "repository": "", 06:14:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:14:24 INFO - "buildid": "20161012030211", 06:14:24 INFO - "stage_platform": "macosx64", 06:14:24 INFO - "basedir": "/builds/slave/test", 06:14:24 INFO - "nightly_build": "True", 06:14:24 INFO - "buildnumber": 48, 06:14:24 INFO - "slavename": "t-yosemite-r7-0036", 06:14:24 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:14:24 INFO - "platform": "yosemite_r7", 06:14:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:14:24 INFO - "branch": "mozilla-central", 06:14:24 INFO - "repo_path": "mozilla-central", 06:14:24 INFO - "revision": "500baee3ce9f1b701ace9d38489cbc112d076c72", 06:14:24 INFO - "pgo_build": "False", 06:14:24 INFO - "builduid": "a769541e4bb74131a09b21df7c55a423", 06:14:24 INFO - "slavebuilddir": "test" 06:14:24 INFO - } 06:14:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:14:24 INFO - Running post-action listener: _resource_record_post_action 06:14:24 INFO - [mozharness: 2016-10-12 13:14:24.664560Z] Finished read-buildbot-config step (success) 06:14:24 INFO - [mozharness: 2016-10-12 13:14:24.664658Z] Running download-and-extract step. 06:14:24 INFO - Running pre-action listener: _resource_record_pre_action 06:14:24 INFO - Running main action method: download_and_extract 06:14:24 INFO - mkdir: /builds/slave/test/build/tests 06:14:24 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')]} 06:14:24 INFO - trying https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:14:24 INFO - Downloading https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/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 06:14:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/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 06:14:26 INFO - Downloaded 1199 bytes. 06:14:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:14:26 INFO - Using the following test package requirements: 06:14:26 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:14:26 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:14:26 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:14:26 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'jsshell-mac.zip'], 06:14:26 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:14:26 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:14:26 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:14:26 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:14:26 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:14:26 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:14:26 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:14:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:14:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:14:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:26 INFO - Fetch https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:14:26 INFO - Http code: 200 06:14:26 INFO - via: 1.1 0576b942ae9f4fc9c0b62b0736e9bfd6.cloudfront.net (CloudFront) 06:14:26 INFO - x-amz-cf-id: 3Yzor7Ur1IJeMlkmgqwTk5-Sh20eOktAxiH1alt8jlJ_TRr3iCGj-Q== 06:14:26 INFO - x-amz-version-id: l2U8Iwi_b6RiWqlnGJ8d1R_yWDwOrkBl 06:14:26 INFO - Expected file size: 16508680 06:14:26 INFO - Obtained file size: 16508680 06:14:33 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 06:14:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:14:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:33 INFO - Fetch https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:14:34 INFO - Http code: 200 06:14:34 INFO - via: 1.1 7db492e48a5d55351dcb787a8a14e113.cloudfront.net (CloudFront) 06:14:34 INFO - x-amz-cf-id: g9OwsPz1rKV2pSkmzXneez5p8ajgt6_6dvKGMI5HjzKaMHzxbbA0wQ== 06:14:34 INFO - x-amz-version-id: l2U8Iwi_b6RiWqlnGJ8d1R_yWDwOrkBl 06:14:34 INFO - Expected file size: 16508680 06:14:34 INFO - Obtained file size: 16508680 06:14:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:14:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:14:40 INFO - Fetch https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:14:41 INFO - Http code: 200 06:14:41 INFO - via: 1.1 aa89533ad2ec5e0edba466c9920bd000.cloudfront.net (CloudFront) 06:14:41 INFO - x-amz-cf-id: hTcj2aBfN5HRiCEwyw1W5szLQrB_eeuMLUUD1CzRxzsR6i7bMd2IfA== 06:14:41 INFO - x-amz-version-id: VxHcHZvJtfF5OFBE7Vd8IVqj6aUsC13O 06:14:41 INFO - Expected file size: 14409006 06:14:41 INFO - Obtained file size: 14409006 06:14:42 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')]} 06:14:42 INFO - trying https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:14:42 INFO - Downloading https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:14:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/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 06:14:45 INFO - Downloaded 111819546 bytes. 06:14:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:14:45 INFO - mkdir: /builds/slave/test/properties 06:14:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:45 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:45 INFO - Contents: 06:14:45 INFO - build_url:https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:14:45 INFO - Running post-action listener: _resource_record_post_action 06:14:45 INFO - Running post-action listener: set_extra_try_arguments 06:14:45 INFO - [mozharness: 2016-10-12 13:14:45.727288Z] Finished download-and-extract step (success) 06:14:45 INFO - [mozharness: 2016-10-12 13:14:45.727416Z] Running populate-webroot step. 06:14:45 INFO - Running pre-action listener: _resource_record_pre_action 06:14:45 INFO - Running main action method: populate_webroot 06:14:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:45 INFO - '--tppagecycles', 06:14:45 INFO - '7'], 06:14:45 INFO - 'tests': ['tsvgm']}}, 06:14:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['damp', 'tps']}, 06:14:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'tests': ['damp', 'tps']}, 06:14:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['dromaeo_dom']}, 06:14:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['basic_compositor_video']}, 06:14:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['a11yr', 06:14:45 INFO - 'ts_paint', 06:14:45 INFO - 'tpaint', 06:14:45 INFO - 'sessionrestore', 06:14:45 INFO - 'sessionrestore_no_auto_restore', 06:14:45 INFO - 'tabpaint']}, 06:14:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['tsvgx', 06:14:45 INFO - 'tsvgr_opacity', 06:14:45 INFO - 'tart', 06:14:45 INFO - 'tscrollx', 06:14:45 INFO - 'cart']}, 06:14:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:45 INFO - 'tsvgr_opacity', 06:14:45 INFO - 'tart', 06:14:45 INFO - 'tscrollx', 06:14:45 INFO - 'cart']}, 06:14:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'talos_options': ['--disable-e10s'], 06:14:45 INFO - 'tests': ['tp5o']}, 06:14:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'tests': ['tp5o']}, 06:14:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'talos_options': ['--disable-e10s', 06:14:45 INFO - '--xperf_path', 06:14:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:45 INFO - 'tests': ['tp5n']}, 06:14:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:45 INFO - 'talos_options': ['--xperf_path', 06:14:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:45 INFO - 'tests': ['tp5n']}}, 06:14:45 INFO - 'talos.zip': {'path': '', 06:14:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:45 INFO - Running post-action listener: _resource_record_post_action 06:14:45 INFO - [mozharness: 2016-10-12 13:14:45.745863Z] Finished populate-webroot step (success) 06:14:45 INFO - [mozharness: 2016-10-12 13:14:45.745991Z] Running create-virtualenv step. 06:14:45 INFO - Running pre-action listener: _resource_record_pre_action 06:14:45 INFO - Running main action method: create_virtualenv 06:14:45 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:45 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 06:14:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:45 INFO - Using real prefix '/tools/python27' 06:14:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:46 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:49 INFO - Installing pip.................done. 06:14:49 INFO - Return code: 0 06:14:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:49 INFO - Reading from file tmpfile_stdout 06:14:49 INFO - Output received: 06:14:49 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) 06:14:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:49 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')]} 06:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:49 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')]} 06:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:49 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:14:49 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 06:14:49 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 06:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:49 INFO - 'HOME': '/Users/cltbld', 06:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:49 INFO - 'LOGNAME': 'cltbld', 06:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:49 INFO - 'MOZ_NO_REMOTE': '1', 06:14:49 INFO - 'NO_EM_RESTART': '1', 06:14:49 INFO - 'PAGER': '/bin/cat', 06:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:49 INFO - 'PWD': '/builds/slave/test', 06:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:49 INFO - 'SHELL': '/bin/bash', 06:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:14:49 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:49 INFO - 'USER': 'cltbld', 06:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:49 INFO - 'XPC_FLAGS': '0x0', 06:14:49 INFO - 'XPC_SERVICE_NAME': '0', 06:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:49 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 06:14:49 INFO - Cleaning up... 06:14:49 INFO - Return code: 0 06:14:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:49 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')]} 06:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:49 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')]} 06:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:49 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:14:49 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 06:14:49 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 06:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:49 INFO - 'HOME': '/Users/cltbld', 06:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:49 INFO - 'LOGNAME': 'cltbld', 06:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:49 INFO - 'MOZ_NO_REMOTE': '1', 06:14:49 INFO - 'NO_EM_RESTART': '1', 06:14:49 INFO - 'PAGER': '/bin/cat', 06:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:49 INFO - 'PWD': '/builds/slave/test', 06:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:49 INFO - 'SHELL': '/bin/bash', 06:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:14:49 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:49 INFO - 'USER': 'cltbld', 06:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:49 INFO - 'XPC_FLAGS': '0x0', 06:14:49 INFO - 'XPC_SERVICE_NAME': '0', 06:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:50 INFO - Downloading/unpacking psutil>=3.1.1 06:14:50 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 06:14:50 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 06:14:50 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 06:14:50 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 06:14:50 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 06:14:50 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 06:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:53 INFO - Installing collected packages: psutil 06:14:53 INFO - Running setup.py install for psutil 06:14:53 INFO - building 'psutil._psutil_osx' extension 06:14:53 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 06:14:54 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 06:14:54 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 06:14:54 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 06:14:54 INFO - building 'psutil._psutil_posix' extension 06:14:54 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 06:14:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:14:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:14:54 INFO - ^ 06:14:54 INFO - 1 warning generated. 06:14:54 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 06:14:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:54 INFO - Successfully installed psutil 06:14:54 INFO - Cleaning up... 06:14:54 INFO - Return code: 0 06:14:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14:54 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')]} 06:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 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')]} 06:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:54 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:14:54 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 06:14:54 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 06:14:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:14:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:54 INFO - 'HOME': '/Users/cltbld', 06:14:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:54 INFO - 'LOGNAME': 'cltbld', 06:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:54 INFO - 'MOZ_NO_REMOTE': '1', 06:14:54 INFO - 'NO_EM_RESTART': '1', 06:14:54 INFO - 'PAGER': '/bin/cat', 06:14:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:54 INFO - 'PWD': '/builds/slave/test', 06:14:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:54 INFO - 'SHELL': '/bin/bash', 06:14:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:14:54 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:14:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:54 INFO - 'USER': 'cltbld', 06:14:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:54 INFO - 'XPC_FLAGS': '0x0', 06:14:54 INFO - 'XPC_SERVICE_NAME': '0', 06:14:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:14:54 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 06:14:54 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 06:14:54 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 06:14:54 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 06:14:54 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 06:14:54 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 06:14:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:14:57 INFO - Installing collected packages: mozsystemmonitor 06:14:57 INFO - Running setup.py install for mozsystemmonitor 06:14:57 INFO - Successfully installed mozsystemmonitor 06:14:57 INFO - Cleaning up... 06:14:57 INFO - Return code: 0 06:14:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:14:57 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')]} 06:14:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:57 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')]} 06:14:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:57 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:14:57 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 06:14:57 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 06:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:57 INFO - 'HOME': '/Users/cltbld', 06:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:57 INFO - 'LOGNAME': 'cltbld', 06:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:57 INFO - 'MOZ_NO_REMOTE': '1', 06:14:57 INFO - 'NO_EM_RESTART': '1', 06:14:57 INFO - 'PAGER': '/bin/cat', 06:14:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:57 INFO - 'PWD': '/builds/slave/test', 06:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:57 INFO - 'SHELL': '/bin/bash', 06:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:14:57 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:57 INFO - 'USER': 'cltbld', 06:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:57 INFO - 'XPC_FLAGS': '0x0', 06:14:57 INFO - 'XPC_SERVICE_NAME': '0', 06:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:57 INFO - Downloading/unpacking jsonschema==2.5.1 06:14:57 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 06:14:57 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 06:14:57 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 06:14:57 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 06:14:57 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 06:14:57 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 06:15:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:15:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:15:00 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 06:15:00 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 06:15:00 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 06:15:00 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 06:15:00 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 06:15:00 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 06:15:00 INFO - Downloading functools32-3.2.3-2.tar.gz 06:15:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:15:00 INFO - warning: no files found matching '*.txt' 06:15:00 INFO - no previously-included directories found matching 'build' 06:15:00 INFO - no previously-included directories found matching 'dist' 06:15:00 INFO - no previously-included directories found matching '.git*' 06:15:00 INFO - Installing collected packages: jsonschema, functools32 06:15:00 INFO - Running setup.py install for functools32 06:15:01 INFO - warning: no files found matching '*.txt' 06:15:01 INFO - no previously-included directories found matching 'build' 06:15:01 INFO - no previously-included directories found matching 'dist' 06:15:01 INFO - no previously-included directories found matching '.git*' 06:15:01 INFO - Successfully installed jsonschema functools32 06:15:01 INFO - Cleaning up... 06:15:01 INFO - Return code: 0 06:15:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:15:01 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')]} 06:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:01 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')]} 06:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:01 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:01 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 06:15:01 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 06:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:01 INFO - 'HOME': '/Users/cltbld', 06:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:01 INFO - 'LOGNAME': 'cltbld', 06:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:01 INFO - 'MOZ_NO_REMOTE': '1', 06:15:01 INFO - 'NO_EM_RESTART': '1', 06:15:01 INFO - 'PAGER': '/bin/cat', 06:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:01 INFO - 'PWD': '/builds/slave/test', 06:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:01 INFO - 'SHELL': '/bin/bash', 06:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:01 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:01 INFO - 'USER': 'cltbld', 06:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:01 INFO - 'XPC_FLAGS': '0x0', 06:15:01 INFO - 'XPC_SERVICE_NAME': '0', 06:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:15:01 INFO - Cleaning up... 06:15:01 INFO - Return code: 0 06:15:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:15:01 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')]} 06:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:01 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')]} 06:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:01 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:01 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 06:15:01 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 06:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:01 INFO - 'HOME': '/Users/cltbld', 06:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:01 INFO - 'LOGNAME': 'cltbld', 06:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:01 INFO - 'MOZ_NO_REMOTE': '1', 06:15:01 INFO - 'NO_EM_RESTART': '1', 06:15:01 INFO - 'PAGER': '/bin/cat', 06:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:01 INFO - 'PWD': '/builds/slave/test', 06:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:01 INFO - 'SHELL': '/bin/bash', 06:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:01 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:01 INFO - 'USER': 'cltbld', 06:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:01 INFO - 'XPC_FLAGS': '0x0', 06:15:01 INFO - 'XPC_SERVICE_NAME': '0', 06:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:01 INFO - Downloading/unpacking blobuploader==1.2.4 06:15:01 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 06:15:01 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 06:15:01 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 06:15:01 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 06:15:01 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 06:15:01 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 06:15:04 INFO - Downloading blobuploader-1.2.4.tar.gz 06:15:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:15:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:15:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:05 INFO - Downloading docopt-0.6.1.tar.gz 06:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:15:05 INFO - Installing collected packages: blobuploader, requests, docopt 06:15:05 INFO - Running setup.py install for blobuploader 06:15:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:15:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:15:05 INFO - Running setup.py install for requests 06:15:06 INFO - Running setup.py install for docopt 06:15:06 INFO - Successfully installed blobuploader requests docopt 06:15:06 INFO - Cleaning up... 06:15:06 INFO - Return code: 0 06:15:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:06 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')]} 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:06 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')]} 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:06 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:06 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 06:15:06 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 06:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:06 INFO - 'HOME': '/Users/cltbld', 06:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:06 INFO - 'LOGNAME': 'cltbld', 06:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:06 INFO - 'MOZ_NO_REMOTE': '1', 06:15:06 INFO - 'NO_EM_RESTART': '1', 06:15:06 INFO - 'PAGER': '/bin/cat', 06:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:06 INFO - 'PWD': '/builds/slave/test', 06:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:06 INFO - 'SHELL': '/bin/bash', 06:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:06 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:06 INFO - 'USER': 'cltbld', 06:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:06 INFO - 'XPC_FLAGS': '0x0', 06:15:06 INFO - 'XPC_SERVICE_NAME': '0', 06:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:06 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Lhspa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:06 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-RS6X5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:06 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Jzux71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PXmvjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-sxDxWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-fNX03J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OR88MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-l8EHWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-4A8Iii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-neGAJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-wIDNO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-_yXyU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:07 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-zGeWx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:08 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-EaLWEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:08 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-s_MVSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:08 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-p3VXfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:08 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-3vnUIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:15:08 INFO - Running setup.py install for manifestparser 06:15:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:15:08 INFO - Running setup.py install for mozcrash 06:15:08 INFO - Running setup.py install for mozdebug 06:15:08 INFO - Running setup.py install for mozdevice 06:15:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Running setup.py install for mozfile 06:15:09 INFO - Running setup.py install for mozhttpd 06:15:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Running setup.py install for mozinfo 06:15:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Running setup.py install for mozInstall 06:15:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Running setup.py install for mozleak 06:15:09 INFO - Running setup.py install for mozlog 06:15:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:15:09 INFO - Running setup.py install for moznetwork 06:15:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:15:10 INFO - Running setup.py install for mozprocess 06:15:10 INFO - Running setup.py install for mozprofile 06:15:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:15:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:15:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:15:10 INFO - Running setup.py install for mozrunner 06:15:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:15:10 INFO - Running setup.py install for mozscreenshot 06:15:10 INFO - Running setup.py install for moztest 06:15:10 INFO - Running setup.py install for mozversion 06:15:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:15:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:15:11 INFO - Cleaning up... 06:15:11 INFO - Return code: 0 06:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:11 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')]} 06:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:11 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')]} 06:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:11 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:11 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 06:15:11 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 06:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:11 INFO - 'HOME': '/Users/cltbld', 06:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:11 INFO - 'LOGNAME': 'cltbld', 06:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:11 INFO - 'MOZ_NO_REMOTE': '1', 06:15:11 INFO - 'NO_EM_RESTART': '1', 06:15:11 INFO - 'PAGER': '/bin/cat', 06:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:11 INFO - 'PWD': '/builds/slave/test', 06:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:11 INFO - 'SHELL': '/bin/bash', 06:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:11 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:11 INFO - 'USER': 'cltbld', 06:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:11 INFO - 'XPC_FLAGS': '0x0', 06:15:11 INFO - 'XPC_SERVICE_NAME': '0', 06:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-yfwZkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-oQHYix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-zXCYMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qsD91k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-yPrKfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-gGplLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:11 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)) 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OxOpdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-QbBOV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-9ZRdHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-0yeFIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-_bPigZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-havrbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-eSFGSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-YGfAl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-DLF2V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:12 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)) 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-HzwR4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:13 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)) 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-5BfWBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:13 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)) 06:15:13 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)) 06:15:13 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)) 06:15:13 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)) 06:15:13 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)) 06:15:13 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)) 06:15:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:13 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 06:15:13 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 06:15:13 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 06:15:13 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 06:15:13 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 06:15:13 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 06:15:15 INFO - Downloading blessings-1.6.tar.gz 06:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:15:15 INFO - Installing collected packages: blessings 06:15:15 INFO - Running setup.py install for blessings 06:15:15 INFO - Successfully installed blessings 06:15:15 INFO - Cleaning up... 06:15:15 INFO - Return code: 0 06:15:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:16 INFO - Reading from file tmpfile_stdout 06:15:16 INFO - Current package versions: 06:15:16 INFO - blessings == 1.6 06:15:16 INFO - blobuploader == 1.2.4 06:15:16 INFO - docopt == 0.6.1 06:15:16 INFO - functools32 == 3.2.3-2 06:15:16 INFO - jsonschema == 2.5.1 06:15:16 INFO - manifestparser == 1.1 06:15:16 INFO - mozInstall == 1.12 06:15:16 INFO - mozcrash == 0.17 06:15:16 INFO - mozdebug == 0.1 06:15:16 INFO - mozdevice == 0.48 06:15:16 INFO - mozfile == 1.2 06:15:16 INFO - mozhttpd == 0.7 06:15:16 INFO - mozinfo == 0.9 06:15:16 INFO - mozleak == 0.1 06:15:16 INFO - mozlog == 3.4 06:15:16 INFO - moznetwork == 0.27 06:15:16 INFO - mozprocess == 0.23 06:15:16 INFO - mozprofile == 0.28 06:15:16 INFO - mozrunner == 6.12 06:15:16 INFO - mozscreenshot == 0.1 06:15:16 INFO - mozsystemmonitor == 0.3 06:15:16 INFO - moztest == 0.7 06:15:16 INFO - mozversion == 1.4 06:15:16 INFO - psutil == 3.1.1 06:15:16 INFO - requests == 1.2.3 06:15:16 INFO - wsgiref == 0.1.2 06:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:16 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')]} 06:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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')]} 06:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:16 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 06:15:16 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 06:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:16 INFO - 'HOME': '/Users/cltbld', 06:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:16 INFO - 'LOGNAME': 'cltbld', 06:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:16 INFO - 'MOZ_NO_REMOTE': '1', 06:15:16 INFO - 'NO_EM_RESTART': '1', 06:15:16 INFO - 'PAGER': '/bin/cat', 06:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:16 INFO - 'PWD': '/builds/slave/test', 06:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:16 INFO - 'SHELL': '/bin/bash', 06:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:16 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:16 INFO - 'USER': 'cltbld', 06:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:16 INFO - 'XPC_FLAGS': '0x0', 06:15:16 INFO - 'XPC_SERVICE_NAME': '0', 06:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 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)) 06:15:16 INFO - Cleaning up... 06:15:16 INFO - Return code: 0 06:15:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:15:16 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')]} 06:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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')]} 06:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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 0x10d532490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5334b1f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, '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.VWim8ZsRwy/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.aykZEWwvrG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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 06:15:16 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 06:15:16 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 06:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:16 INFO - 'HOME': '/Users/cltbld', 06:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:16 INFO - 'LOGNAME': 'cltbld', 06:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:16 INFO - 'MOZ_NO_REMOTE': '1', 06:15:16 INFO - 'NO_EM_RESTART': '1', 06:15:16 INFO - 'PAGER': '/bin/cat', 06:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:16 INFO - 'PWD': '/builds/slave/test', 06:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:16 INFO - 'SHELL': '/bin/bash', 06:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:16 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:16 INFO - 'USER': 'cltbld', 06:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:16 INFO - 'XPC_FLAGS': '0x0', 06:15:16 INFO - 'XPC_SERVICE_NAME': '0', 06:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:15:16 INFO - Cleaning up... 06:15:16 INFO - Return code: 0 06:15:16 INFO - Running post-action listener: _resource_record_post_action 06:15:16 INFO - Running post-action listener: _start_resource_monitoring 06:15:16 INFO - Starting resource monitoring. 06:15:16 INFO - [mozharness: 2016-10-12 13:15:16.846436Z] Finished create-virtualenv step (success) 06:15:16 INFO - [mozharness: 2016-10-12 13:15:16.847444Z] Running install step. 06:15:16 INFO - Running pre-action listener: _resource_record_pre_action 06:15:16 INFO - Running main action method: install 06:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:17 INFO - Reading from file tmpfile_stdout 06:15:17 INFO - Detecting whether we're running mozinstall >=1.0... 06:15:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:15:17 INFO - Reading from file tmpfile_stdout 06:15:17 INFO - Output received: 06:15:17 INFO - Usage: mozinstall [options] installer 06:15:17 INFO - Options: 06:15:17 INFO - -h, --help show this help message and exit 06:15:17 INFO - -d DEST, --destination=DEST 06:15:17 INFO - Directory to install application into. [default: 06:15:17 INFO - "/builds/slave/test"] 06:15:17 INFO - --app=APP Application being installed. [default: firefox] 06:15:17 INFO - mkdir: /builds/slave/test/build/application 06:15:17 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'] 06:15:17 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 06:15:51 INFO - Reading from file tmpfile_stdout 06:15:51 INFO - Output received: 06:15:51 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:15:51 INFO - Running post-action listener: _resource_record_post_action 06:15:51 INFO - [mozharness: 2016-10-12 13:15:51.269458Z] Finished install step (success) 06:15:51 INFO - [mozharness: 2016-10-12 13:15:51.269588Z] Running run-tests step. 06:15:51 INFO - Running pre-action listener: _resource_record_pre_action 06:15:51 INFO - Running main action method: run_tests 06:15:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:51 INFO - 2016-10-12 06:15:51.284 screenresolution[2253:17781] starting screenresolution argv=screenresolution get 06:15:51 INFO - 2016-10-12 06:15:51.287 screenresolution[2253:17781] Display 0: 1600x1200x32@60 06:15:51 INFO - 2016-10-12 06:15:51.292 screenresolution[2254:17784] starting screenresolution argv=screenresolution list 06:15:51 INFO - Available Modes on Display 0 06:15:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:51 INFO - Intel Iris: 06:15:51 INFO - Chipset Model: Intel Iris 06:15:51 INFO - Type: GPU 06:15:51 INFO - Bus: Built-In 06:15:51 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:51 INFO - Vendor: Intel (0x8086) 06:15:51 INFO - Device ID: 0x0a2e 06:15:51 INFO - Revision ID: 0x0009 06:15:51 INFO - Displays: 06:15:51 INFO - Display: 06:15:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:51 INFO - Display Serial Number: 1600x1200 60 06:15:51 INFO - Main Display: Yes 06:15:51 INFO - Mirror: Off 06:15:51 INFO - Online: Yes 06:15:51 INFO - Rotation: Supported 06:15:51 INFO - Return code: 0 06:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:51 INFO - Python 2.7.3 06:15:51 INFO - Return code: 0 06:15:51 INFO - grabbing minidump binary from tooltool 06:15:51 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')]} 06:15:51 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 0x10d346d30>, '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 0x10d16cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d33eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:51 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 06:15:51 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 06:15:51 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 06:15:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:51 INFO - Return code: 0 06:15:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:15:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:15:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VWim8ZsRwy/Render', 06:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:51 INFO - 'HOME': '/Users/cltbld', 06:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:51 INFO - 'LOGNAME': 'cltbld', 06:15:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:51 INFO - 'MOZ_NO_REMOTE': '1', 06:15:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:51 INFO - 'NO_EM_RESTART': '1', 06:15:51 INFO - 'PAGER': '/bin/cat', 06:15:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:51 INFO - 'PWD': '/builds/slave/test', 06:15:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:51 INFO - 'SHELL': '/bin/bash', 06:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aykZEWwvrG/Listeners', 06:15:51 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 06:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:51 INFO - 'USER': 'cltbld', 06:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:51 INFO - 'XPC_FLAGS': '0x0', 06:15:51 INFO - 'XPC_SERVICE_NAME': '0', 06:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:15:51 INFO - mozversion application_buildid: 20161012030211 06:15:51 INFO - mozversion application_changeset: 500baee3ce9f1b701ace9d38489cbc112d076c72 06:15:51 INFO - mozversion application_display_name: Nightly 06:15:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:51 INFO - mozversion application_name: Firefox 06:15:51 INFO - mozversion application_remotingname: firefox 06:15:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:51 INFO - mozversion application_vendor: Mozilla 06:15:51 INFO - mozversion application_version: 52.0a1 06:15:51 INFO - mozversion platform_buildid: 20161012030211 06:15:51 INFO - mozversion platform_changeset: 500baee3ce9f1b701ace9d38489cbc112d076c72 06:15:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:51 INFO - mozversion platform_version: 52.0a1 06:15:51 INFO - using testdate: 1476278151 06:15:51 INFO - actual date: 1476278151 06:15:51 INFO - starting webserver on 'localhost:54630' 06:15:51 INFO - SUITE-START | Running 2 tests 06:15:51 INFO - TEST-START | dromaeo_css 06:15:51 INFO - operating with platform_type : mac_ 06:15:51 INFO - Initialising browser for dromaeo_css test... 06:15:51 INFO - TEST-INFO | started process 2264 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmppuEdt_/profile http://localhost:54630/getInfo.html) 06:15:54 INFO - PROCESS | 2264 | __metrics Screen width/height:1600/1200 06:15:54 INFO - PROCESS | 2264 | colorDepth:24 06:15:54 INFO - PROCESS | 2264 | Browser inner width/height: 1024/689 06:15:54 INFO - PROCESS | 2264 | __metrics 06:15:54 INFO - PROCESS | 2264 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:15:54 INFO - PROCESS | 2264 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:15:55 INFO - TEST-INFO | 2264: exit 0 06:15:55 INFO - Browser initialized. 06:15:55 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:55 INFO - TEST-INFO | started process 2266 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmppuEdt_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:07 INFO - PROCESS | 2266 | RSS: Main: 235180032 06:16:07 INFO - PROCESS | 2266 | 06:16:58 INFO - PROCESS | 2266 | 1476278218415 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] 06:19:11 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54630/tests/dromaeo/cssquery-ext.html) 06:19:12 INFO - PROCESS | 2266 | RSS: Main: 348549120 06:19:12 INFO - PROCESS | 2266 | 06:22:15 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-ext.html (next: http://localhost:54630/tests/dromaeo/cssquery-jquery.html) 06:22:16 INFO - PROCESS | 2266 | RSS: Main: 372064256 06:22:16 INFO - PROCESS | 2266 | 06:25:18 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54630/tests/dromaeo/cssquery-mootools.html) 06:25:19 INFO - PROCESS | 2266 | RSS: Main: 361742336 06:25:19 INFO - PROCESS | 2266 | 06:28:21 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54630/tests/dromaeo/cssquery-prototype.html) 06:28:22 INFO - PROCESS | 2266 | RSS: Main: 348893184 06:28:22 INFO - PROCESS | 2266 | 06:31:25 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54630/tests/dromaeo/cssquery-yui.html) 06:31:26 INFO - PROCESS | 2266 | RSS: Main: 328863744 06:31:26 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | Cycle 1(1): loaded http://localhost:54630/tests/dromaeo/cssquery-yui.html (next: http://localhost:54630/tests/dromaeo/cssquery-dojo.html) 06:34:29 INFO - PROCESS | 2266 | RSS: Main: 380563456 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | __start_tp_report 06:34:29 INFO - PROCESS | 2266 | _x_x_mozilla_page_load 06:34:29 INFO - PROCESS | 2266 | _x_x_mozilla_page_load_details 06:34:29 INFO - PROCESS | 2266 | |i|pagename|runs| 06:34:29 INFO - PROCESS | 2266 | |0;dojo.html;3258.4134855725974;3259.3644239373316;3274.83625818709;3284.6222684391273;3325.2019515316315;5507.201455788908;5556.16657501375;5594.272744543214;5623.381428042917;5654.123610840316;5582.106862901936;5591.580631452647;5627.043402621554;5649.406812284713;5691.601587888849;5114.309568208281;5150.072986862363;5197.740112994354;5228.398734145584;5245.370555533342;5105.132127538327;5236.136037553799;5244.921326180111;5248.973755131238;5256.553192978601;5224.7387630618505;5266.8419947401635;5276.7889284428575;5293.761780719858;5301.992621401941;5063.721495317756;5188.533032027117;5191.948080519184;5211.114110601206;5239.13554263546;5062.569681577071;5086.186210206386;5131.461196574381;5134.974325128388;5158.148905430599;5526.171074338841;5526.530244929186;5532.474414930575;5557.722113894289;5578.107502799573;6115.7859474918605;6132.908006379907;6139.846503837395;6146.29317628474;6173.012318029094;5990.550708696822;6051.939480605181;6123.740204357149;6135.447809697104;6229.906551401733;5650.773969041234;5679.687617181012;5680.857978550569;5694.572907031948;5737.799177028784;6261.311255761862;6261.96869015652;6272.937270627235;6279.277883043417;6296.5592408530965;5003;5819.127130930309;5880.117982302603;6055.182550355734;6068.575199735976;6069.2716873975405;6123.540734444934;6131.662758548234;6134.4172303631085;6169.413905678954;5970.13433052205;6072.514898452354;6085.421884920926;6127.601705889192;6135.846603834851;6124.693765311717;6145.692715364214;6153.107799369156;6153.1693221415435;6157.815265542041;4144.901601075718;4776.641751868624;4786.234202527578;4841.975790121027;4859.927101093486;4348.152110338454;4351.934720979188;4378.540253273361;4383.912321753611;4457.086297309044;5031.698098114125;5664.376918538957;5669.206311116447;5675.148727690879;5685.971570142123;6008.729607167688;6046.032634778502;6088.908666370008;6181.690915454299;6190.628562286277;5600.047991841397;5642.887142257132;5644.971775141098;5704.401037890962;5732.6847023413275;5263.078961181778;5320.680759154463;5333.306670132858;5333.493318134849;5335.1730481775385;6146.340488068107;6268.623882567061;6278.183836101278;6292.874142517124;6295.811125666238;6083.391660833881;6132.2947860996555;6136.447719705069;6139.553468857771;6142.560166306883;6219.689015549114;6241.001439769615;6269.937300626935;6273.404026617464;6275.6548389839;6033.758649653964;6092.086187071975;6092.45167934888;6108.51131909455;6114.847128821637;5805.216295800013;5879.647221166743;6053.3946605339115;6087.543434242361;6154.969225153845;6004.099385092271;6007.879842403215;6008.8798224036145;6026.096085587197;6067.029275315928;5002.449730529712;5962.1951036611235;5965.194698715792;5971.850703732442;5994.67029313392;4679.649026322972;5726.0838265877255;5761.769529218952;5782.421757824352;5833.125031245348;5891.086881533369;5980.730867111078;5990.311114221746;6027.156198132353;6037.818865434044;5670;5746.3966283541295;5815.04051831443;5829;5886.499647530008 06:34:29 INFO - PROCESS | 2266 | |1;ext.html;479.94240691117074;484.1068229117279;491.3416022529809;495.8075827634538;531.5747402078337;29330.826766929324;38633.42049869251;39563.40654890179;39815;41989.95025124373;40426;40665;40728;40739.59260407395;41019.58980410195;8188.672453101899;9313.674021409252;9498.477583732893;9510.66712665057;9534.523273836316;9396.436213827157;9476.526173691322;9484.762880927963;9484.762880927963;9486.573104210293;9442.716718498456;9473.905260947406;9497.192738617226;9510.47692376919;9515.476648784314;9400.012998635148;9475.47884866332;9485.857712134324;9486.667966621135;9494.335396522247;9371.812563748686;9445.102715242041;9447.433154010781;9471.66849160276;9477.90522094777;37790.811045944596;38055.61944380548;38068.61931380678;38155.42766858499;38167.618323816685;3033.63596368435;3301.603807543086;3339.415602269593;3353.4634458486526;3519.4524854019173;29747.702522974927;30072.398552029055;30235.395292094036;30260.54609180864;31033.06900792957;3204.359128174351;3208.5508028875975;3214.6624604416434;3321.219513414346;3334.1664583854035;432761;433532;433656;434398;439972.0006999819;32366.838165809022;33254.50118248228;33423.16442088967;34176;55087.898242034935;30027.39945201084;33383;33975.15062123417;34020.97937061892;34061.14847128842;11873.525058997717;14282.928585357007;14289.499867504588;14291.641464255892;14297;12128.69678258033;12210.145289829627;12237.95977341936;12242.44908979098;12381.62855114348;7480.17718051014;7537.321641052222;7590.3927685785;7605.771826845204;7606.391488681003;3182.3794360100023;3243;3243.5459035735016;3245.9837700811345;3267.068885367659;858.52780970466;950.2398081534744;994.3487016004528;998.6654470752379;1001.8046480936148;27967.02115425991;28556.571651425245;29364.706352936624;29548.704910217566;29754;908.9954550227338;957.4632714493277;1013.7871047080048;1026.3072426112344;1056.8837427882927;13955.860441395456;14744.189069601274;14765.33555989983;14880;14934;667137.6007358973;670779;671439;671875;675057;53210.73394633002;53632.46367536275;53636.65908352323;53745.46254537483;53866.73066634642;1218.7257866979858;1271.6312269441758;1276.431987765454;1363.8022486739565;1384.9238291894042;28468.572971405858;29331;30010.949616763308;30546.84726576353;31214.21964450816;52060.47939520556;52420;53342.46657533375;53400.198997015854;56791.71604141953;632303;638263;638421;638524;638792;33635.831820840736;33681.663183367855;40703.59296406998;41065.38401924033;41291;34930.65069349274;44694.10611787681;44985;45028;45240.54759452363;36893.07767305839;37120.628793711716;37341.4398784013;38122.42816357702;38385.616143839325;36295.63704362923;36616.45075323925;37380.25239495249;37546.62453375541;37620.435693464075;36953.26093478169;37228.25543489061;37259.81370093132;37306.62693373032;38980.220395591365;27647.267618871196;32301.030969070966;34028;34176.65823341834;34338.79814206491;22582.774172258723;24701.876490617433;24793.62809557822;25167;25564.616530751682 06:34:29 INFO - PROCESS | 2266 | |2;jquery.html;352.2883774774956;371.93677074897266;375.15589922673985;384.1663590009679;403.82231818000076;14650;14848.40606375745;19528.609427811436;20362;20803.895980520076;19814.00929953504;19835;20065.598688026268;20430.69353959692;20578.69131963018;5645.661260324373;6042.57701960863;6045.546584006195;6046.576739628228;6047.788327408542;5622.184783206432;5824.155497452867;5839;5867.207926929853;5969.373215812342;5824.30108386992;5825.766969321222;5826.941730582704;5829.504492118175;5831.562632802556;5014.598832093424;5572.749226284827;5778.653280803144;5815.5638327125425;5848.853778655568;5092.879566495365;5113.769880355393;5115.283860259567;5116.974415127937;5124.92312615311;24852.757362131822;24958.875205623855;25142;25172;25374.74625253742;2236.9888150559145;2428.453597940449;2590.430105376814;2599.908038623764;3022.0329494561747;14869.851301486955;15226.695466090616;15250.008749431252;15599;15691.764623530657;1796.6676164909534;1873.2694249242807;1884.981150188508;1886.368066697658;1922.6250881078195;75923.86114208291;92998.60502092406;95288;96049.5197524015;96891.88851393505;10214.591416343263;10476.371417714961;10626.299792033238;10755.89244107549;10842.566297348176;11844.348560829238;12258.325792081521;12449.315287659085;12489.562865299671;12951.740965180643;8223.917760822435;8391.748247552583;8433.198846109732;8550.657973680984;8595.527246001406;7120.9643951780945;8520.233179013918;8542.060373358925;8679.956600217085;8704.869426958601;5468.37113731918;6234.815385076821;6361.427471527492;6402.6158430494315;6418.229812422539;1609.251697960454;1814.1110855680622;1828.4880233534366;1843.1429385335882;1850.2044121027818;838.3335248477475;877.8024944387462;902.4630344944748;905.1310741687921;918.1461241045891;15903;16164.676706465805;16495.83504164967;19643.901780491007;20676.646944367087;886.7162507997378;913.881195444588;917.7797328641211;932.5616960028775;964.392432767352;9174.92639965619;10307.175425965905;11606;11618.302901825917;11823.935845773922;565317;565917;571794;579932;581110;63499.777507787;79041.20958790339;122444;126152;127897;965.666844938495;972.6741541583507;1018.1040684197859;1018.5772904244656;1024.36489376587;18209.362672306404;18727.438176854717;19024.953627550618;19026.7145992813;19393.8060619392;53785.731071344395;54797;54945.5603435346;54980.72509637426;55065.34803955888;81333;82122;82666.17333826826;83167.34661227209;83259;6899.48253880971;7656.846863062596;7686.654100565376;7827.360277471003;8092.959535202522;10460.63387781424;12654.240745555297;12927.482900683868;12996.350182490649;13075.476980920657;8631.784205394915;11077.667669969915;11279.605213817476;11333.093352531943;11361.091112710797;10786.73033174177;10969.23215374916;10983.725406864893;11283.717907052389;11390.715232119263;11178.608748693756;11514.251573647685;11653.30080195191;11752.764944700886;11761.764764704829;6281.685915704105;6927.307269273032;7320.304571065741;7451.850962980819;7454.478186527108;9230.584623691815;10975.396353200651;11313.773724525628;11333.093352531943;11509.424528773361 06:34:29 INFO - PROCESS | 2266 | |3;mootools.html;1353.9323033848304;1463.4000059975406;1509.4943194030002;1509.7207016701911;1604.6790641871628;7703.49927254728;8161.55111468869;8326.292265157468;8737.606807693654;8738.126187381258;8611.397202195849;8696.39125261232;8710.303175745941;8721.7819554511;8727.214550690453;2338.8362814602942;2377.6909001829736;2379.6668466414717;2382.1305223593436;2384.701912260967;2311.0640190722784;2348.2720356689483;2349.5066036132434;2350.5063936573342;2352.882355882208;2312.606856834342;2323.7908588227056;2336.532693461306;2341.4029422497238;2342.285602891126;2291.132726827647;2312.9653055204185;2318.40880575453;2319.072371051576;2332.6734257203834;2273.06804210273;2301.873396963166;2305.631099024165;2309.1802410144323;2311.5723591135525;8692.826143477158;8739.30085593151;8755.212030917248;8760.386772925898;8761.912380876174;5142.820001299974;5273.2617433559335;5334.333208348957;5350.678959262456;5352.678839269656;3508.3509550733197;3643.325984692814;3702.7222958277966;3702.796346200958;3720.720945929066;1052.6105341023795;1080.1574771678104;1090.4002798460895;1107.617871834216;1109.4896347680076;11527.481263343087;11585.710357241052;11627;11677.70805729855;11694.356810375426;4305.784710764449;4457.799399027052;4570.611498022638;4585.12882552314;4588.197065513522;9027.50348730826;9031.729048128567;9033.232175265044;9040.457572545669;9061.13919177677;5183.714895680774;5187.247849061865;5195.558377537876;5200.375954885438;5200.895982080413;4130.524989626171;4335.154644844288;4395.494518130456;4496.303073023687;4880.072786170616;3468.2196505785996;3733.8879833605033;3745.550533935945;3751.9062023449087;3774.377227757444;1125.2235957189514;1146.3866831245412;1155.3414553704476;1156.797560426922;1165.0737663557493;777.8522080804629;867.3754896474528;895.7178488776015;918.3846822628855;926.8656044873455;5725.885482290331;5820.35976042635;5941.979726892877;6133.478654314434;6138.846528836815;890.0965519997208;903.1690844423147;909.5315912305168;918.1323649151622;946.2193690205607;3527.470879368064;3697.4638677392168;3698.2973235085246;3760.379537376357;3866.7293289469467;10346.896531034743;10745.086667633183;10748;10752;10763.300385474919;10631.202659800545;10711;10721.624743134109;10727;10739.087177589836;7973.800654983672;8087.272145506815;8116.959415202886;8116.985376827896;8123.75628731139;4846.612271018381;5468.91796623059;5761.539076873756;5775.162601422854;5857.7949771757785;9606;9989.400635961865;9991.800163996533;10014.599416023277;10030.899691002996;9185.402948808307;9726.416415015121;10048.397096174253;10076.94961525217;10253.487325633838;6491.285958544462;6652.168478940132;6797.524173307821;6908.965455172692;7066.787996360117;8123.578373784446;8172.387070969582;8177;8208.302294304813;8238.340932725248;7370.852582948418;7396.815079623052;7407.407407407502;7463.290987356192;7472.439567032603;7047.753328633473;7239.782806515813;7314.96342518284;7328.157261914735;7371.852562948604;6798.55809372409;6919.550229235084;6923.965380173268;6933.890577507575;6935.167779866449;4086.9182616348103;4910.754462276944;4955;4994.375703037121;4997.375328083989;4756.429228492465;4834.7824347903725;4936.284238785428;4975.676581022222;5043.360907705084 06:34:29 INFO - PROCESS | 2266 | |4;prototype.html;262.8107762411064;299.4251038007027;301.98943036993694;314.60202843403084;319.4648962986996;4263.680223983202;4339.327404252342;4533.931991020133;5328.706921119347;5347.732613369326;4365.716228445157;4889.437714662815;5009.749512524378;5098.515641014107;5281.9207711884355;3254.535459043428;3849.6727778138893;3917.5103112110987;3937.448757173998;3938.8380427773877;4015.31739604268;4018.4173294872226;4018.8392464301537;4053.797310134496;4084.407760874686;3857.633524815138;3936.724429289951;3983.7012224083164;4013;4019.296623090962;3557.843700797251;4003.799810009517;4050.9392359114636;4055.634992850643;4056.2822908651274;3079.414911166871;3215.115843143131;3501.3039959231505;3510.7366947478786;3541.8229088545468;4608.193566125948;4892.779824907888;5152.896942061137;5152.92270615941;5210.114280572274;4201.7268877523165;4454.420925279725;4455.287154055368;4472.46330440346;4542.909141817145;5363.651362661414;5386.111291636915;5435.239066530688;5467.507924286793;5525.723713814293;3159.9052028439182;3749.512563366772;3753.1663593986636;3769.775832258953;3793.8103094845146;5460.788380705821;5822.650640961556;5848.619839710405;5868;5885.558583106242;3818.7517811342173;3849.480320156743;3870.0324918770307;3884.9223015539533;4025.3156963316305;3307.7353811695007;3929.685625149981;3942.664873485786;3946.2896678598127;3959.604039596017;3617.909552261162;4065.7763822989427;4546.613537849254;4591.770411479412;4638.976805115953;5536.4186760390385;5578.24693666358;5582.665040097526;5596.300462442195;5647.519960803378;1301.2387753164317;1326.4163767942075;1328.63150954517;1329.643763361366;1329.7008173161155;758.6206896551724;826.6734639817239;858.8282343531318;877.1009715042005;897.8866205904632;4757.119932812441;5020.920502092068;5159.845204643867;5164.845054648366;5179.902139633455;4661.627069834405;5184.507471790174;5394.002109609734;5585.776568937197;5633.915491267634;4589.8066502709535;4728.196206644879;4771.90246243366;4933.9506604933495;5127.89750203708;2601.297649634588;2647.7352264773754;2929.721676440735;2946.7495262902466;2951.793374463767;5539.781248125406;5576.570409198395;5787.247657804459;5809.099589563624;5819.563532735062;4604.746738929391;5688.09448834645;5704.944585251742;5707.831024210856;5716.828495145152;5148;5639.605227634026;5719.456651618089;5790;5849.7952571660635;5250.579953603779;5520.309961254843;5570.470805273492;5580;5736.6558006519745;4996.525330093636;5615.719214039364;5673.3759286477625;5676.517496012885;5705.286839145107;5958.433948774859;5974.581779275409;5978.595030167994;5986.55100867428;6028.698565071817;3406.5571475707998;3461.8265293219097;3768.660820526166;3800.9619903801713;3801.885943421702;5301.23132145829;5684.65892046462;5718.771249149988;5731.8853622928145;5750.7987220447085;5192.896142077062;5583.413741557078;5652.021792372637;5652.85867853307;5681.204631351697;4870.488598697086;4996.3504744383;5211.296474975829;5463;5604.887902242014;4545.749983750793;4590.609798167193;5091.490850914879;5390.218418329398;5451.236826844324;4739.810407583796;4995.050940321327;5084.805070808283;5421.945780542144;5445.564354851682;4406.427164468599;5062.721550314768;5171.612129090408;5318.840434786963;5338.572914166935 06:34:29 INFO - PROCESS | 2266 | |5;yui.html;705.379266245704;710.2613282186526;748.4498893313414;770.3259647808167;784.9207339907628;14644.70710585789;15924.52226433206;15926.681466370666;15929.203539823005;15976.760348594751;12093.576724814633;14233.857661423383;14351.784723229133;14357.425702971896;14374.856251437483;669.6718607882151;696.1646024770271;699.7306037175673;704.7639040921298;838.8741688746676;699.1889408286389;701.6281370873445;824.517657170553;857.1471385970937;858.1761508951414;923.3490389275545;928.7445952363121;938.9671361502361;940.3276657190141;944.598545618113;924.2605915267759;925.4586067150691;926.8100039491889;927.6660402255183;931.3201363005044;532.0980937311259;542.6391449685954;695.117201154536;698.0088274650009;700.2892064554482;14292.928535357256;14795.112293262438;14851.554453366416;15168.165750883696;15192.848071519255;14196.50312239077;14713.632159196106;14842.109473431628;14851.628709282246;14855.92572037133;11734.589289374917;11831.585894493737;11900.702482437922;11901.404929753564;11955.760884782343;3779.8677046303246;3791.146991926822;3899.6490315871706;3910.1397692507603;3989.501312335958;114392.89771028845;115036;116779;117458.82541174564;118724.21913671301;11315.547378104948;11345.035671967975;11355.091592672567;11368.658940231806;11660.825087623693;23774;23776.7622323778;23818.04727810903;23838.284851454486;23857.761422385902;7671.462997590227;7713.421493387906;7722.42081843864;8003.719869804529;8034.47775894565;4266.168097221012;4392.099619577979;4416.933745993812;4521.231390663529;4536.070105628338;3571.2500374921037;3687.1335236219466;3691.5201023866725;3693.3905905525826;3698.907527311785;659.1365311442026;665.8135721997849;666.3592687896976;671.966401679914;674.1068084787676;548.7174105335755;552.4144406928668;639.1850390751829;668.5921587831419;682.9726810927607;9124;9455.905440945502;9623.518824058909;9831.901680983241;10032.1472674822;663.3929954092048;673.6598018000878;681.1859827506181;695.7112798188796;698.8742026435189;4492;4539.614132798683;4688.2733176357715;4728.95962888164;4770.809167633325;87990;89352.5532372334;89549.65675514872;147859;157963;143994;144798.5520144806;147950.26024869806;148026;152247;118279;119075;121471;122164;122853.38573307077;7383.852322953619;7473.402127829652;8441.240288374076;8850;8924.776880578038;64602;65849.01226481702;67032.66483667551;68247.25571351385;68410.65794670995;92913.67715806888;104769.47615262179;106323;107159;107975;11987.280763154238;12050.216735912138;12117.69705757363;12327.69180770488;12638.43127059303;12811.487540498276;12979.28613926243;13107.606771796862;13260.602181934557;13325.33373331349;12089.818652720392;12200.633980980585;12442.315672638091;12467.750644986869;13182.472701091849;8820.62871387712;12398.566050188561;12629.368531573567;13811;14147.504837330644;13124.34378281063;13603.455861765418;13613.795793062915;13617.659558511117;13650;11185.66443006711;11243.550257989917;11552.884471155181;11602.88397116018;11899;9999.950000250243;11420.657380278604;11437.313761174357;11595.492585963771;11786.292822430682 06:34:29 INFO - PROCESS | 2266 | __end_tp_report 06:34:29 INFO - PROCESS | 2266 | __start_cc_report 06:34:29 INFO - PROCESS | 2266 | _x_x_mozilla_cycle_collect,1236 06:34:29 INFO - PROCESS | 2266 | __end_cc_report 06:34:29 INFO - PROCESS | 2266 | __startTimestamp1476279269835__endTimestamp 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | ------- Summary: start ------- 06:34:29 INFO - PROCESS | 2266 | Number of tests: 6 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#0] dojo.html Cycles:165 Average:5633.11 Median:5782.42 stddev:637.48 (11.0%) stddev-sans-first:611.61 06:34:29 INFO - PROCESS | 2266 | Values: 3258.4 3259.4 3274.8 3284.6 3325.2 5507.2 5556.2 5594.3 5623.4 5654.1 5582.1 5591.6 5627.0 5649.4 5691.6 5114.3 5150.1 5197.7 5228.4 5245.4 5105.1 5236.1 5244.9 5249.0 5256.6 5224.7 5266.8 5276.8 5293.8 5302.0 5063.7 5188.5 5191.9 5211.1 5239.1 5062.6 5086.2 5131.5 5135.0 5158.1 5526.2 5526.5 5532.5 5557.7 5578.1 6115.8 6132.9 6139.8 6146.3 6173.0 5990.6 6051.9 6123.7 6135.4 6229.9 5650.8 5679.7 5680.9 5694.6 5737.8 6261.3 6262.0 6272.9 6279.3 6296.6 5003.0 5819.1 5880.1 6055.2 6068.6 6069.3 6123.5 6131.7 6134.4 6169.4 5970.1 6072.5 6085.4 6127.6 6135.8 6124.7 6145.7 6153.1 6153.2 6157.8 4144.9 4776.6 4786.2 4842.0 4859.9 4348.2 4351.9 4378.5 4383.9 4457.1 5031.7 5664.4 5669.2 5675.1 5686.0 6008.7 6046.0 6088.9 6181.7 6190.6 5600.0 5642.9 5645.0 5704.4 5732.7 5263.1 5320.7 5333.3 5333.5 5335.2 6146.3 6268.6 6278.2 6292.9 6295.8 6083.4 6132.3 6136.4 6139.6 6142.6 6219.7 6241.0 6269.9 6273.4 6275.7 6033.8 6092.1 6092.5 6108.5 6114.8 5805.2 5879.6 6053.4 6087.5 6155.0 6004.1 6007.9 6008.9 6026.1 6067.0 5002.4 5962.2 5965.2 5971.9 5994.7 4679.6 5726.1 5761.8 5782.4 5833.1 5891.1 5980.7 5990.3 6027.2 6037.8 5670.0 5746.4 5815.0 5829.0 5886.5 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#1] ext.html Cycles:180 Average:69067.47 Median:38062.12 stddev:158548.26 (416.6%) stddev-sans-first:158909.40 06:34:29 INFO - PROCESS | 2266 | Values: 479.9 484.1 491.3 495.8 531.6 29330.8 38633.4 39563.4 39815.0 41990.0 40426.0 40665.0 40728.0 40739.6 41019.6 8188.7 9313.7 9498.5 9510.7 9534.5 9396.4 9476.5 9484.8 9484.8 9486.6 9442.7 9473.9 9497.2 9510.5 9515.5 9400.0 9475.5 9485.9 9486.7 9494.3 9371.8 9445.1 9447.4 9471.7 9477.9 37790.8 38055.6 38068.6 38155.4 38167.6 3033.6 3301.6 3339.4 3353.5 3519.5 29747.7 30072.4 30235.4 30260.5 31033.1 3204.4 3208.6 3214.7 3321.2 3334.2 432761.0 433532.0 433656.0 434398.0 439972.0 32366.8 33254.5 33423.2 34176.0 55087.9 30027.4 33383.0 33975.2 34021.0 34061.1 11873.5 14282.9 14289.5 14291.6 14297.0 12128.7 12210.1 12238.0 12242.4 12381.6 7480.2 7537.3 7590.4 7605.8 7606.4 3182.4 3243.0 3243.5 3246.0 3267.1 858.5 950.2 994.3 998.7 1001.8 27967.0 28556.6 29364.7 29548.7 29754.0 909.0 957.5 1013.8 1026.3 1056.9 13955.9 14744.2 14765.3 14880.0 14934.0 667137.6 670779.0 671439.0 671875.0 675057.0 53210.7 53632.5 53636.7 53745.5 53866.7 1218.7 1271.6 1276.4 1363.8 1384.9 28468.6 29331.0 30010.9 30546.8 31214.2 52060.5 52420.0 53342.5 53400.2 56791.7 632303.0 638263.0 638421.0 638524.0 638792.0 33635.8 33681.7 40703.6 41065.4 41291.0 34930.7 44694.1 44985.0 45028.0 45240.5 36893.1 37120.6 37341.4 38122.4 38385.6 36295.6 36616.5 37380.3 37546.6 37620.4 36953.3 37228.3 37259.8 37306.6 38980.2 27647.3 32301.0 34028.0 34176.7 34338.8 22582.8 24701.9 24793.6 25167.0 25564.6 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#2] jquery.html Cycles:180 Average:32959.07 Median:379.66 stddev:94922.06 (25001.8%) stddev-sans-first:95156.77 06:34:29 INFO - PROCESS | 2266 | Values: 352.3 371.9 375.2 384.2 403.8 14650.0 14848.4 19528.6 20362.0 20803.9 19814.0 19835.0 20065.6 20430.7 20578.7 5645.7 6042.6 6045.5 6046.6 6047.8 5622.2 5824.2 5839.0 5867.2 5969.4 5824.3 5825.8 5826.9 5829.5 5831.6 5014.6 5572.7 5778.7 5815.6 5848.9 5092.9 5113.8 5115.3 5117.0 5124.9 24852.8 24958.9 25142.0 25172.0 25374.7 2237.0 2428.5 2590.4 2599.9 3022.0 14869.9 15226.7 15250.0 15599.0 15691.8 1796.7 1873.3 1885.0 1886.4 1922.6 75923.9 92998.6 95288.0 96049.5 96891.9 10214.6 10476.4 10626.3 10755.9 10842.6 11844.3 12258.3 12449.3 12489.6 12951.7 8223.9 8391.7 8433.2 8550.7 8595.5 7121.0 8520.2 8542.1 8680.0 8704.9 5468.4 6234.8 6361.4 6402.6 6418.2 1609.3 1814.1 1828.5 1843.1 1850.2 838.3 877.8 902.5 905.1 918.1 15903.0 16164.7 16495.8 19643.9 20676.6 886.7 913.9 917.8 932.6 964.4 9174.9 10307.2 11606.0 11618.3 11823.9 565317.0 565917.0 571794.0 579932.0 581110.0 63499.8 79041.2 122444.0 126152.0 127897.0 965.7 972.7 1018.1 1018.6 1024.4 18209.4 18727.4 19025.0 19026.7 19393.8 53785.7 54797.0 54945.6 54980.7 55065.3 81333.0 82122.0 82666.2 83167.3 83259.0 6899.5 7656.8 7686.7 7827.4 8093.0 10460.6 12654.2 12927.5 12996.4 13075.5 8631.8 11077.7 11279.6 11333.1 11361.1 10786.7 10969.2 10983.7 11283.7 11390.7 11178.6 11514.3 11653.3 11752.8 11761.8 6281.7 6927.3 7320.3 7451.9 7454.5 9230.6 10975.4 11313.8 11333.1 11509.4 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#3] mootools.html Cycles:180 Average:5585.89 Median:4945.64 stddev:3154.60 (63.8%) stddev-sans-first:3147.42 06:34:29 INFO - PROCESS | 2266 | Values: 1353.9 1463.4 1509.5 1509.7 1604.7 7703.5 8161.6 8326.3 8737.6 8738.1 8611.4 8696.4 8710.3 8721.8 8727.2 2338.8 2377.7 2379.7 2382.1 2384.7 2311.1 2348.3 2349.5 2350.5 2352.9 2312.6 2323.8 2336.5 2341.4 2342.3 2291.1 2313.0 2318.4 2319.1 2332.7 2273.1 2301.9 2305.6 2309.2 2311.6 8692.8 8739.3 8755.2 8760.4 8761.9 5142.8 5273.3 5334.3 5350.7 5352.7 3508.4 3643.3 3702.7 3702.8 3720.7 1052.6 1080.2 1090.4 1107.6 1109.5 11527.5 11585.7 11627.0 11677.7 11694.4 4305.8 4457.8 4570.6 4585.1 4588.2 9027.5 9031.7 9033.2 9040.5 9061.1 5183.7 5187.2 5195.6 5200.4 5200.9 4130.5 4335.2 4395.5 4496.3 4880.1 3468.2 3733.9 3745.6 3751.9 3774.4 1125.2 1146.4 1155.3 1156.8 1165.1 777.9 867.4 895.7 918.4 926.9 5725.9 5820.4 5942.0 6133.5 6138.8 890.1 903.2 909.5 918.1 946.2 3527.5 3697.5 3698.3 3760.4 3866.7 10346.9 10745.1 10748.0 10752.0 10763.3 10631.2 10711.0 10721.6 10727.0 10739.1 7973.8 8087.3 8117.0 8117.0 8123.8 4846.6 5468.9 5761.5 5775.2 5857.8 9606.0 9989.4 9991.8 10014.6 10030.9 9185.4 9726.4 10048.4 10076.9 10253.5 6491.3 6652.2 6797.5 6909.0 7066.8 8123.6 8172.4 8177.0 8208.3 8238.3 7370.9 7396.8 7407.4 7463.3 7472.4 7047.8 7239.8 7315.0 7328.2 7371.9 6798.6 6919.6 6924.0 6933.9 6935.2 4086.9 4910.8 4955.0 4994.4 4997.4 4756.4 4834.8 4936.3 4975.7 5043.4 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#4] prototype.html Cycles:180 Average:4451.94 Median:4996.44 stddev:1377.65 (27.6%) stddev-sans-first:1345.16 06:34:29 INFO - PROCESS | 2266 | Values: 262.8 299.4 302.0 314.6 319.5 4263.7 4339.3 4533.9 5328.7 5347.7 4365.7 4889.4 5009.7 5098.5 5281.9 3254.5 3849.7 3917.5 3937.4 3938.8 4015.3 4018.4 4018.8 4053.8 4084.4 3857.6 3936.7 3983.7 4013.0 4019.3 3557.8 4003.8 4050.9 4055.6 4056.3 3079.4 3215.1 3501.3 3510.7 3541.8 4608.2 4892.8 5152.9 5152.9 5210.1 4201.7 4454.4 4455.3 4472.5 4542.9 5363.7 5386.1 5435.2 5467.5 5525.7 3159.9 3749.5 3753.2 3769.8 3793.8 5460.8 5822.7 5848.6 5868.0 5885.6 3818.8 3849.5 3870.0 3884.9 4025.3 3307.7 3929.7 3942.7 3946.3 3959.6 3617.9 4065.8 4546.6 4591.8 4639.0 5536.4 5578.2 5582.7 5596.3 5647.5 1301.2 1326.4 1328.6 1329.6 1329.7 758.6 826.7 858.8 877.1 897.9 4757.1 5020.9 5159.8 5164.8 5179.9 4661.6 5184.5 5394.0 5585.8 5633.9 4589.8 4728.2 4771.9 4934.0 5127.9 2601.3 2647.7 2929.7 2946.7 2951.8 5539.8 5576.6 5787.2 5809.1 5819.6 4604.7 5688.1 5704.9 5707.8 5716.8 5148.0 5639.6 5719.5 5790.0 5849.8 5250.6 5520.3 5570.5 5580.0 5736.7 4996.5 5615.7 5673.4 5676.5 5705.3 5958.4 5974.6 5978.6 5986.6 6028.7 3406.6 3461.8 3768.7 3801.0 3801.9 5301.2 5684.7 5718.8 5731.9 5750.8 5192.9 5583.4 5652.0 5652.9 5681.2 4870.5 4996.4 5211.3 5463.0 5604.9 4545.7 4590.6 5091.5 5390.2 5451.2 4739.8 4995.1 5084.8 5421.9 5445.6 4406.4 5062.7 5171.6 5318.8 5338.6 06:34:29 INFO - PROCESS | 2266 | 06:34:29 INFO - PROCESS | 2266 | [#5] yui.html Cycles:180 Average:25318.46 Median:3629.19 stddev:40697.42 (1121.4%) stddev-sans-first:40769.63 06:34:29 INFO - PROCESS | 2266 | Values: 705.4 710.3 748.4 770.3 784.9 14644.7 15924.5 15926.7 15929.2 15976.8 12093.6 14233.9 14351.8 14357.4 14374.9 669.7 696.2 699.7 704.8 838.9 699.2 701.6 824.5 857.1 858.2 923.3 928.7 939.0 940.3 944.6 924.3 925.5 926.8 927.7 931.3 532.1 542.6 695.1 698.0 700.3 14292.9 14795.1 14851.6 15168.2 15192.8 14196.5 14713.6 14842.1 14851.6 14855.9 11734.6 11831.6 11900.7 11901.4 11955.8 3779.9 3791.1 3899.6 3910.1 3989.5 114392.9 115036.0 116779.0 117458.8 118724.2 11315.5 11345.0 11355.1 11368.7 11660.8 23774.0 23776.8 23818.0 23838.3 23857.8 7671.5 7713.4 7722.4 8003.7 8034.5 4266.2 4392.1 4416.9 4521.2 4536.1 3571.3 3687.1 3691.5 3693.4 3698.9 659.1 665.8 666.4 672.0 674.1 548.7 552.4 639.2 668.6 683.0 9124.0 9455.9 9623.5 9831.9 10032.1 663.4 673.7 681.2 695.7 698.9 4492.0 4539.6 4688.3 4729.0 4770.8 87990.0 89352.6 89549.7 147859.0 157963.0 143994.0 144798.6 147950.3 148026.0 152247.0 118279.0 119075.0 121471.0 122164.0 122853.4 7383.9 7473.4 8441.2 8850.0 8924.8 64602.0 65849.0 67032.7 68247.3 68410.7 92913.7 104769.5 106323.0 107159.0 107975.0 11987.3 12050.2 12117.7 12327.7 12638.4 12811.5 12979.3 13107.6 13260.6 13325.3 12089.8 12200.6 12442.3 12467.8 13182.5 8820.6 12398.6 12629.4 13811.0 14147.5 13124.3 13603.5 13613.8 13617.7 13650.0 11185.7 11243.6 11552.9 11602.9 11899.0 10000.0 11420.7 11437.3 11595.5 11786.3 06:34:29 INFO - PROCESS | 2266 | -------- Summary: end -------- 06:34:29 INFO - PROCESS | 2266 | 06:34:30 INFO - TEST-INFO | 2266: exit 0 06:34:30 INFO - TEST-OK | dromaeo_css | took 1118634ms 06:34:30 INFO - TEST-START | kraken 06:34:30 INFO - operating with platform_type : mac_ 06:34:30 INFO - Initialising browser for kraken test... 06:34:30 INFO - TEST-INFO | started process 2270 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpm7hmnS/profile http://localhost:54630/getInfo.html) 06:34:32 INFO - PROCESS | 2270 | __metrics Screen width/height:1600/1200 06:34:32 INFO - PROCESS | 2270 | colorDepth:24 06:34:32 INFO - PROCESS | 2270 | Browser inner width/height: 1024/689 06:34:32 INFO - PROCESS | 2270 | __metrics 06:34:32 INFO - PROCESS | 2270 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:34:32 INFO - PROCESS | 2270 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:34:33 INFO - TEST-INFO | 2270: exit 0 06:34:33 INFO - Browser initialized. 06:34:33 INFO - Running cycle 1/1 for kraken test... 06:34:33 INFO - TEST-INFO | started process 2272 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpm7hmnS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:45 INFO - PROCESS | 2272 | RSS: Main: 235253760 06:34:45 INFO - PROCESS | 2272 | 06:34:48 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54630/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:49 INFO - PROCESS | 2272 | RSS: Main: 293756928 06:34:49 INFO - PROCESS | 2272 | 06:34:51 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54630/tests/kraken/driver.html?testName=audio-dft) 06:34:52 INFO - PROCESS | 2272 | RSS: Main: 293867520 06:34:52 INFO - PROCESS | 2272 | 06:34:56 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54630/tests/kraken/driver.html?testName=audio-fft) 06:34:56 INFO - PROCESS | 2272 | RSS: Main: 383725568 06:34:56 INFO - PROCESS | 2272 | 06:34:58 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54630/tests/kraken/driver.html?testName=audio-oscillator) 06:34:59 INFO - PROCESS | 2272 | RSS: Main: 287166464 06:34:59 INFO - PROCESS | 2272 | 06:35:01 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54630/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:35:02 INFO - PROCESS | 2272 | RSS: Main: 290504704 06:35:02 INFO - PROCESS | 2272 | 06:35:04 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54630/tests/kraken/driver.html?testName=imaging-darkroom) 06:35:05 INFO - PROCESS | 2272 | RSS: Main: 326844416 06:35:05 INFO - PROCESS | 2272 | 06:35:08 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54630/tests/kraken/driver.html?testName=imaging-desaturate) 06:35:08 INFO - PROCESS | 2272 | RSS: Main: 328544256 06:35:08 INFO - PROCESS | 2272 | 06:35:11 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54630/tests/kraken/driver.html?testName=json-parse-financial) 06:35:12 INFO - PROCESS | 2272 | RSS: Main: 328003584 06:35:12 INFO - PROCESS | 2272 | 06:35:13 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54630/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:35:14 INFO - PROCESS | 2272 | RSS: Main: 301023232 06:35:14 INFO - PROCESS | 2272 | 06:35:16 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:35:16 INFO - PROCESS | 2272 | RSS: Main: 304824320 06:35:16 INFO - PROCESS | 2272 | 06:35:18 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:19 INFO - PROCESS | 2272 | RSS: Main: 308158464 06:35:19 INFO - PROCESS | 2272 | 06:35:22 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:23 INFO - PROCESS | 2272 | RSS: Main: 325525504 06:35:23 INFO - PROCESS | 2272 | 06:35:26 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:26 INFO - PROCESS | 2272 | RSS: Main: 318738432 06:35:26 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | Cycle 1(1): loaded http://localhost:54630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54630/tests/kraken/driver.html?testName=ai-astar) 06:35:28 INFO - PROCESS | 2272 | RSS: Main: 363311104 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | __start_tp_report 06:35:28 INFO - PROCESS | 2272 | _x_x_mozilla_page_load 06:35:28 INFO - PROCESS | 2272 | _x_x_mozilla_page_load_details 06:35:28 INFO - PROCESS | 2272 | |i|pagename|runs| 06:35:28 INFO - PROCESS | 2272 | |0;ai-astar;113;114;114;113;113;114;113;114;112;114 06:35:28 INFO - PROCESS | 2272 | |1;audio-beat-detection;152;112;111;153;112;111;112;114;143;113 06:35:28 INFO - PROCESS | 2272 | |2;audio-dft;259;255;221;186;182;173;180;184;184;182 06:35:28 INFO - PROCESS | 2272 | |3;audio-fft;79;58;58;88;61;59;58;59;87;73 06:35:28 INFO - PROCESS | 2272 | |4;audio-oscillator;90;70;94;69;68;69;92;66;69;90 06:35:28 INFO - PROCESS | 2272 | |5;imaging-gaussian-blur;87;85;88;85;87;85;89;86;87;84 06:35:28 INFO - PROCESS | 2272 | |6;imaging-darkroom;92;94;93;93;93;93;92;93;94;93 06:35:28 INFO - PROCESS | 2272 | |7;imaging-desaturate;86;84;87;87;86;85;87;86;85;86 06:35:28 INFO - PROCESS | 2272 | |8;json-parse-financial;56;52;52;52;53;54;53;52;54;54 06:35:28 INFO - PROCESS | 2272 | |9;json-stringify-tinderbox;66;70;79;75;73;71;71;72;71;73 06:35:28 INFO - PROCESS | 2272 | |10;stanford-crypto-aes;86;87;87;86;87;86;83;89;86;89 06:35:28 INFO - PROCESS | 2272 | |11;stanford-crypto-ccm;146;145;146;136;152;149;160;160;176;144 06:35:28 INFO - PROCESS | 2272 | |12;stanford-crypto-pbkdf2;172;171;173;170;173;168;167;169;168;169 06:35:28 INFO - PROCESS | 2272 | |13;stanford-crypto-sha256-iterative;69;70;69;68;69;68;67;68;68;67 06:35:28 INFO - PROCESS | 2272 | __end_tp_report 06:35:28 INFO - PROCESS | 2272 | __start_cc_report 06:35:28 INFO - PROCESS | 2272 | _x_x_mozilla_cycle_collect,1975 06:35:28 INFO - PROCESS | 2272 | __end_cc_report 06:35:28 INFO - PROCESS | 2272 | __startTimestamp1476279328709__endTimestamp 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | ------- Summary: start ------- 06:35:28 INFO - PROCESS | 2272 | Number of tests: 14 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 06:35:28 INFO - PROCESS | 2272 | Values: 113.0 114.0 114.0 113.0 113.0 114.0 113.0 114.0 112.0 114.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:113.50 stddev:18.17 (16.0%) stddev-sans-first:16.03 06:35:28 INFO - PROCESS | 2272 | Values: 152.0 112.0 111.0 153.0 112.0 111.0 112.0 114.0 143.0 113.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#2] audio-dft Cycles:10 Average:200.60 Median:185.00 stddev:32.37 (17.5%) stddev-sans-first:26.55 06:35:28 INFO - PROCESS | 2272 | Values: 259.0 255.0 221.0 186.0 182.0 173.0 180.0 184.0 184.0 182.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#3] audio-fft Cycles:10 Average:68.00 Median:67.00 stddev:12.55 (18.7%) stddev-sans-first:12.67 06:35:28 INFO - PROCESS | 2272 | Values: 79.0 58.0 58.0 88.0 61.0 59.0 58.0 59.0 87.0 73.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.00 stddev:11.97 (15.0%) stddev-sans-first:11.84 06:35:28 INFO - PROCESS | 2272 | Values: 90.0 70.0 94.0 69.0 68.0 69.0 92.0 66.0 69.0 90.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 06:35:28 INFO - PROCESS | 2272 | Values: 87.0 85.0 88.0 85.0 87.0 85.0 89.0 86.0 87.0 84.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 06:35:28 INFO - PROCESS | 2272 | Values: 92.0 94.0 93.0 93.0 93.0 93.0 92.0 93.0 94.0 93.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 06:35:28 INFO - PROCESS | 2272 | Values: 86.0 84.0 87.0 87.0 86.0 85.0 87.0 86.0 85.0 86.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.32 (2.5%) stddev-sans-first:0.93 06:35:28 INFO - PROCESS | 2272 | Values: 56.0 52.0 52.0 52.0 53.0 54.0 53.0 52.0 54.0 54.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.50 stddev:3.38 (4.7%) stddev-sans-first:2.77 06:35:28 INFO - PROCESS | 2272 | Values: 66.0 70.0 79.0 75.0 73.0 71.0 71.0 72.0 71.0 73.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.71 (2.0%) stddev-sans-first:1.80 06:35:28 INFO - PROCESS | 2272 | Values: 86.0 87.0 87.0 86.0 87.0 86.0 83.0 89.0 86.0 89.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:150.50 stddev:11.31 (7.5%) stddev-sans-first:11.82 06:35:28 INFO - PROCESS | 2272 | Values: 146.0 145.0 146.0 136.0 152.0 149.0 160.0 160.0 176.0 144.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.50 stddev:2.16 (1.3%) stddev-sans-first:2.17 06:35:28 INFO - PROCESS | 2272 | Values: 172.0 171.0 173.0 170.0 173.0 168.0 167.0 169.0 168.0 169.0 06:35:28 INFO - PROCESS | 2272 | 06:35:28 INFO - PROCESS | 2272 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:0.95 (1.4%) stddev-sans-first:0.97 06:35:28 INFO - PROCESS | 2272 | Values: 69.0 70.0 69.0 68.0 69.0 68.0 67.0 68.0 68.0 67.0 06:35:28 INFO - PROCESS | 2272 | -------- Summary: end -------- 06:35:28 INFO - PROCESS | 2272 | 06:35:29 INFO - TEST-INFO | 2272: exit 0 06:35:29 INFO - TEST-OK | kraken | took 59104ms 06:35:29 INFO - SUITE-END | took 1177s 06:35:29 INFO - Completed test suite (00:19:37) 06:35:29 INFO - javascript benchmark 06:35:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.4134855725974, 3259.3644239373316, 3274.83625818709, 3284.6222684391273, 3325.2019515316315, 5507.201455788908, 5556.16657501375, 5594.272744543214, 5623.381428042917, 5654.123610840316, 5582.106862901936, 5591.580631452647, 5627.043402621554, 5649.406812284713, 5691.601587888849, 5114.309568208281, 5150.072986862363, 5197.740112994354, 5228.398734145584, 5245.370555533342, 5105.132127538327, 5236.136037553799, 5244.921326180111, 5248.973755131238, 5256.553192978601, 5224.7387630618505, 5266.8419947401635, 5276.7889284428575, 5293.761780719858, 5301.992621401941, 5063.721495317756, 5188.533032027117, 5191.948080519184, 5211.114110601206, 5239.13554263546, 5062.569681577071, 5086.186210206386, 5131.461196574381, 5134.974325128388, 5158.148905430599, 5526.171074338841, 5526.530244929186, 5532.474414930575, 5557.722113894289, 5578.107502799573, 6115.7859474918605, 6132.908006379907, 6139.846503837395, 6146.29317628474, 6173.012318029094, 5990.550708696822, 6051.939480605181, 6123.740204357149, 6135.447809697104, 6229.906551401733, 5650.773969041234, 5679.687617181012, 5680.857978550569, 5694.572907031948, 5737.799177028784, 6261.311255761862, 6261.96869015652, 6272.937270627235, 6279.277883043417, 6296.5592408530965, 5003.0, 5819.127130930309, 5880.117982302603, 6055.182550355734, 6068.575199735976, 6069.2716873975405, 6123.540734444934, 6131.662758548234, 6134.4172303631085, 6169.413905678954, 5970.13433052205, 6072.514898452354, 6085.421884920926, 6127.601705889192, 6135.846603834851, 6124.693765311717, 6145.692715364214, 6153.107799369156, 6153.1693221415435, 6157.815265542041, 4144.901601075718, 4776.641751868624, 4786.234202527578, 4841.975790121027, 4859.927101093486, 4348.152110338454, 4351.934720979188, 4378.540253273361, 4383.912321753611, 4457.086297309044, 5031.698098114125, 5664.376918538957, 5669.206311116447, 5675.148727690879, 5685.971570142123, 6008.729607167688, 6046.032634778502, 6088.908666370008, 6181.690915454299, 6190.628562286277, 5600.047991841397, 5642.887142257132, 5644.971775141098, 5704.401037890962, 5732.6847023413275, 5263.078961181778, 5320.680759154463, 5333.306670132858, 5333.493318134849, 5335.1730481775385, 6146.340488068107, 6268.623882567061, 6278.183836101278, 6292.874142517124, 6295.811125666238, 6083.391660833881, 6132.2947860996555, 6136.447719705069, 6139.553468857771, 6142.560166306883, 6219.689015549114, 6241.001439769615, 6269.937300626935, 6273.404026617464, 6275.6548389839, 6033.758649653964, 6092.086187071975, 6092.45167934888, 6108.51131909455, 6114.847128821637, 5805.216295800013, 5879.647221166743, 6053.3946605339115, 6087.543434242361, 6154.969225153845, 6004.099385092271, 6007.879842403215, 6008.8798224036145, 6026.096085587197, 6067.029275315928, 5002.449730529712, 5962.1951036611235, 5965.194698715792, 5971.850703732442, 5994.67029313392, 4679.649026322972, 5726.0838265877255, 5761.769529218952, 5782.421757824352, 5833.125031245348, 5891.086881533369, 5980.730867111078, 5990.311114221746, 6027.156198132353, 6037.818865434044, 5670.0, 5746.3966283541295, 5815.04051831443, 5829.0, 5886.499647530008], "value": 5592.522785757741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.94240691117074, 484.1068229117279, 491.3416022529809, 495.8075827634538, 531.5747402078337, 29330.826766929324, 38633.42049869251, 39563.40654890179, 39815.0, 41989.95025124373, 40426.0, 40665.0, 40728.0, 40739.59260407395, 41019.58980410195, 8188.672453101899, 9313.674021409252, 9498.477583732893, 9510.66712665057, 9534.523273836316, 9396.436213827157, 9476.526173691322, 9484.762880927963, 9484.762880927963, 9486.573104210293, 9442.716718498456, 9473.905260947406, 9497.192738617226, 9510.47692376919, 9515.476648784314, 9400.012998635148, 9475.47884866332, 9485.857712134324, 9486.667966621135, 9494.335396522247, 9371.812563748686, 9445.102715242041, 9447.433154010781, 9471.66849160276, 9477.90522094777, 37790.811045944596, 38055.61944380548, 38068.61931380678, 38155.42766858499, 38167.618323816685, 3033.63596368435, 3301.603807543086, 3339.415602269593, 3353.4634458486526, 3519.4524854019173, 29747.702522974927, 30072.398552029055, 30235.395292094036, 30260.54609180864, 31033.06900792957, 3204.359128174351, 3208.5508028875975, 3214.6624604416434, 3321.219513414346, 3334.1664583854035, 432761.0, 433532.0, 433656.0, 434398.0, 439972.0006999819, 32366.838165809022, 33254.50118248228, 33423.16442088967, 34176.0, 55087.898242034935, 30027.39945201084, 33383.0, 33975.15062123417, 34020.97937061892, 34061.14847128842, 11873.525058997717, 14282.928585357007, 14289.499867504588, 14291.641464255892, 14297.0, 12128.69678258033, 12210.145289829627, 12237.95977341936, 12242.44908979098, 12381.62855114348, 7480.17718051014, 7537.321641052222, 7590.3927685785, 7605.771826845204, 7606.391488681003, 3182.3794360100023, 3243.0, 3243.5459035735016, 3245.9837700811345, 3267.068885367659, 858.52780970466, 950.2398081534744, 994.3487016004528, 998.6654470752379, 1001.8046480936148, 27967.02115425991, 28556.571651425245, 29364.706352936624, 29548.704910217566, 29754.0, 908.9954550227338, 957.4632714493277, 1013.7871047080048, 1026.3072426112344, 1056.8837427882927, 13955.860441395456, 14744.189069601274, 14765.33555989983, 14880.0, 14934.0, 667137.6007358973, 670779.0, 671439.0, 671875.0, 675057.0, 53210.73394633002, 53632.46367536275, 53636.65908352323, 53745.46254537483, 53866.73066634642, 1218.7257866979858, 1271.6312269441758, 1276.431987765454, 1363.8022486739565, 1384.9238291894042, 28468.572971405858, 29331.0, 30010.949616763308, 30546.84726576353, 31214.21964450816, 52060.47939520556, 52420.0, 53342.46657533375, 53400.198997015854, 56791.71604141953, 632303.0, 638263.0, 638421.0, 638524.0, 638792.0, 33635.831820840736, 33681.663183367855, 40703.59296406998, 41065.38401924033, 41291.0, 34930.65069349274, 44694.10611787681, 44985.0, 45028.0, 45240.54759452363, 36893.07767305839, 37120.628793711716, 37341.4398784013, 38122.42816357702, 38385.616143839325, 36295.63704362923, 36616.45075323925, 37380.25239495249, 37546.62453375541, 37620.435693464075, 36953.26093478169, 37228.25543489061, 37259.81370093132, 37306.62693373032, 38980.220395591365, 27647.267618871196, 32301.030969070966, 34028.0, 34176.65823341834, 34338.79814206491, 22582.774172258723, 24701.876490617433, 24793.62809557822, 25167.0, 25564.616530751682], "value": 18154.41425213961, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.2883774774956, 371.93677074897266, 375.15589922673985, 384.1663590009679, 403.82231818000076, 14650.0, 14848.40606375745, 19528.609427811436, 20362.0, 20803.895980520076, 19814.00929953504, 19835.0, 20065.598688026268, 20430.69353959692, 20578.69131963018, 5645.661260324373, 6042.57701960863, 6045.546584006195, 6046.576739628228, 6047.788327408542, 5622.184783206432, 5824.155497452867, 5839.0, 5867.207926929853, 5969.373215812342, 5824.30108386992, 5825.766969321222, 5826.941730582704, 5829.504492118175, 5831.562632802556, 5014.598832093424, 5572.749226284827, 5778.653280803144, 5815.5638327125425, 5848.853778655568, 5092.879566495365, 5113.769880355393, 5115.283860259567, 5116.974415127937, 5124.92312615311, 24852.757362131822, 24958.875205623855, 25142.0, 25172.0, 25374.74625253742, 2236.9888150559145, 2428.453597940449, 2590.430105376814, 2599.908038623764, 3022.0329494561747, 14869.851301486955, 15226.695466090616, 15250.008749431252, 15599.0, 15691.764623530657, 1796.6676164909534, 1873.2694249242807, 1884.981150188508, 1886.368066697658, 1922.6250881078195, 75923.86114208291, 92998.60502092406, 95288.0, 96049.5197524015, 96891.88851393505, 10214.591416343263, 10476.371417714961, 10626.299792033238, 10755.89244107549, 10842.566297348176, 11844.348560829238, 12258.325792081521, 12449.315287659085, 12489.562865299671, 12951.740965180643, 8223.917760822435, 8391.748247552583, 8433.198846109732, 8550.657973680984, 8595.527246001406, 7120.9643951780945, 8520.233179013918, 8542.060373358925, 8679.956600217085, 8704.869426958601, 5468.37113731918, 6234.815385076821, 6361.427471527492, 6402.6158430494315, 6418.229812422539, 1609.251697960454, 1814.1110855680622, 1828.4880233534366, 1843.1429385335882, 1850.2044121027818, 838.3335248477475, 877.8024944387462, 902.4630344944748, 905.1310741687921, 918.1461241045891, 15903.0, 16164.676706465805, 16495.83504164967, 19643.901780491007, 20676.646944367087, 886.7162507997378, 913.881195444588, 917.7797328641211, 932.5616960028775, 964.392432767352, 9174.92639965619, 10307.175425965905, 11606.0, 11618.302901825917, 11823.935845773922, 565317.0, 565917.0, 571794.0, 579932.0, 581110.0, 63499.777507787, 79041.20958790339, 122444.0, 126152.0, 127897.0, 965.666844938495, 972.6741541583507, 1018.1040684197859, 1018.5772904244656, 1024.36489376587, 18209.362672306404, 18727.438176854717, 19024.953627550618, 19026.7145992813, 19393.8060619392, 53785.731071344395, 54797.0, 54945.5603435346, 54980.72509637426, 55065.34803955888, 81333.0, 82122.0, 82666.17333826826, 83167.34661227209, 83259.0, 6899.48253880971, 7656.846863062596, 7686.654100565376, 7827.360277471003, 8092.959535202522, 10460.63387781424, 12654.240745555297, 12927.482900683868, 12996.350182490649, 13075.476980920657, 8631.784205394915, 11077.667669969915, 11279.605213817476, 11333.093352531943, 11361.091112710797, 10786.73033174177, 10969.23215374916, 10983.725406864893, 11283.717907052389, 11390.715232119263, 11178.608748693756, 11514.251573647685, 11653.30080195191, 11752.764944700886, 11761.764764704829, 6281.685915704105, 6927.307269273032, 7320.304571065741, 7451.850962980819, 7454.478186527108, 9230.584623691815, 10975.396353200651, 11313.773724525628, 11333.093352531943, 11509.424528773361], "value": 9410.104197563827, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.9323033848304, 1463.4000059975406, 1509.4943194030002, 1509.7207016701911, 1604.6790641871628, 7703.49927254728, 8161.55111468869, 8326.292265157468, 8737.606807693654, 8738.126187381258, 8611.397202195849, 8696.39125261232, 8710.303175745941, 8721.7819554511, 8727.214550690453, 2338.8362814602942, 2377.6909001829736, 2379.6668466414717, 2382.1305223593436, 2384.701912260967, 2311.0640190722784, 2348.2720356689483, 2349.5066036132434, 2350.5063936573342, 2352.882355882208, 2312.606856834342, 2323.7908588227056, 2336.532693461306, 2341.4029422497238, 2342.285602891126, 2291.132726827647, 2312.9653055204185, 2318.40880575453, 2319.072371051576, 2332.6734257203834, 2273.06804210273, 2301.873396963166, 2305.631099024165, 2309.1802410144323, 2311.5723591135525, 8692.826143477158, 8739.30085593151, 8755.212030917248, 8760.386772925898, 8761.912380876174, 5142.820001299974, 5273.2617433559335, 5334.333208348957, 5350.678959262456, 5352.678839269656, 3508.3509550733197, 3643.325984692814, 3702.7222958277966, 3702.796346200958, 3720.720945929066, 1052.6105341023795, 1080.1574771678104, 1090.4002798460895, 1107.617871834216, 1109.4896347680076, 11527.481263343087, 11585.710357241052, 11627.0, 11677.70805729855, 11694.356810375426, 4305.784710764449, 4457.799399027052, 4570.611498022638, 4585.12882552314, 4588.197065513522, 9027.50348730826, 9031.729048128567, 9033.232175265044, 9040.457572545669, 9061.13919177677, 5183.714895680774, 5187.247849061865, 5195.558377537876, 5200.375954885438, 5200.895982080413, 4130.524989626171, 4335.154644844288, 4395.494518130456, 4496.303073023687, 4880.072786170616, 3468.2196505785996, 3733.8879833605033, 3745.550533935945, 3751.9062023449087, 3774.377227757444, 1125.2235957189514, 1146.3866831245412, 1155.3414553704476, 1156.797560426922, 1165.0737663557493, 777.8522080804629, 867.3754896474528, 895.7178488776015, 918.3846822628855, 926.8656044873455, 5725.885482290331, 5820.35976042635, 5941.979726892877, 6133.478654314434, 6138.846528836815, 890.0965519997208, 903.1690844423147, 909.5315912305168, 918.1323649151622, 946.2193690205607, 3527.470879368064, 3697.4638677392168, 3698.2973235085246, 3760.379537376357, 3866.7293289469467, 10346.896531034743, 10745.086667633183, 10748.0, 10752.0, 10763.300385474919, 10631.202659800545, 10711.0, 10721.624743134109, 10727.0, 10739.087177589836, 7973.800654983672, 8087.272145506815, 8116.959415202886, 8116.985376827896, 8123.75628731139, 4846.612271018381, 5468.91796623059, 5761.539076873756, 5775.162601422854, 5857.7949771757785, 9606.0, 9989.400635961865, 9991.800163996533, 10014.599416023277, 10030.899691002996, 9185.402948808307, 9726.416415015121, 10048.397096174253, 10076.94961525217, 10253.487325633838, 6491.285958544462, 6652.168478940132, 6797.524173307821, 6908.965455172692, 7066.787996360117, 8123.578373784446, 8172.387070969582, 8177.0, 8208.302294304813, 8238.340932725248, 7370.852582948418, 7396.815079623052, 7407.407407407502, 7463.290987356192, 7472.439567032603, 7047.753328633473, 7239.782806515813, 7314.96342518284, 7328.157261914735, 7371.852562948604, 6798.55809372409, 6919.550229235084, 6923.965380173268, 6933.890577507575, 6935.167779866449, 4086.9182616348103, 4910.754462276944, 4955.0, 4994.375703037121, 4997.375328083989, 4756.429228492465, 4834.7824347903725, 4936.284238785428, 4975.676581022222, 5043.360907705084], "value": 4477.632706053281, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [262.8107762411064, 299.4251038007027, 301.98943036993694, 314.60202843403084, 319.4648962986996, 4263.680223983202, 4339.327404252342, 4533.931991020133, 5328.706921119347, 5347.732613369326, 4365.716228445157, 4889.437714662815, 5009.749512524378, 5098.515641014107, 5281.9207711884355, 3254.535459043428, 3849.6727778138893, 3917.5103112110987, 3937.448757173998, 3938.8380427773877, 4015.31739604268, 4018.4173294872226, 4018.8392464301537, 4053.797310134496, 4084.407760874686, 3857.633524815138, 3936.724429289951, 3983.7012224083164, 4013.0, 4019.296623090962, 3557.843700797251, 4003.799810009517, 4050.9392359114636, 4055.634992850643, 4056.2822908651274, 3079.414911166871, 3215.115843143131, 3501.3039959231505, 3510.7366947478786, 3541.8229088545468, 4608.193566125948, 4892.779824907888, 5152.896942061137, 5152.92270615941, 5210.114280572274, 4201.7268877523165, 4454.420925279725, 4455.287154055368, 4472.46330440346, 4542.909141817145, 5363.651362661414, 5386.111291636915, 5435.239066530688, 5467.507924286793, 5525.723713814293, 3159.9052028439182, 3749.512563366772, 3753.1663593986636, 3769.775832258953, 3793.8103094845146, 5460.788380705821, 5822.650640961556, 5848.619839710405, 5868.0, 5885.558583106242, 3818.7517811342173, 3849.480320156743, 3870.0324918770307, 3884.9223015539533, 4025.3156963316305, 3307.7353811695007, 3929.685625149981, 3942.664873485786, 3946.2896678598127, 3959.604039596017, 3617.909552261162, 4065.7763822989427, 4546.613537849254, 4591.770411479412, 4638.976805115953, 5536.4186760390385, 5578.24693666358, 5582.665040097526, 5596.300462442195, 5647.519960803378, 1301.2387753164317, 1326.4163767942075, 1328.63150954517, 1329.643763361366, 1329.7008173161155, 758.6206896551724, 826.6734639817239, 858.8282343531318, 877.1009715042005, 897.8866205904632, 4757.119932812441, 5020.920502092068, 5159.845204643867, 5164.845054648366, 5179.902139633455, 4661.627069834405, 5184.507471790174, 5394.002109609734, 5585.776568937197, 5633.915491267634, 4589.8066502709535, 4728.196206644879, 4771.90246243366, 4933.9506604933495, 5127.89750203708, 2601.297649634588, 2647.7352264773754, 2929.721676440735, 2946.7495262902466, 2951.793374463767, 5539.781248125406, 5576.570409198395, 5787.247657804459, 5809.099589563624, 5819.563532735062, 4604.746738929391, 5688.09448834645, 5704.944585251742, 5707.831024210856, 5716.828495145152, 5148.0, 5639.605227634026, 5719.456651618089, 5790.0, 5849.7952571660635, 5250.579953603779, 5520.309961254843, 5570.470805273492, 5580.0, 5736.6558006519745, 4996.525330093636, 5615.719214039364, 5673.3759286477625, 5676.517496012885, 5705.286839145107, 5958.433948774859, 5974.581779275409, 5978.595030167994, 5986.55100867428, 6028.698565071817, 3406.5571475707998, 3461.8265293219097, 3768.660820526166, 3800.9619903801713, 3801.885943421702, 5301.23132145829, 5684.65892046462, 5718.771249149988, 5731.8853622928145, 5750.7987220447085, 5192.896142077062, 5583.413741557078, 5652.021792372637, 5652.85867853307, 5681.204631351697, 4870.488598697086, 4996.3504744383, 5211.296474975829, 5463.0, 5604.887902242014, 4545.749983750793, 4590.609798167193, 5091.490850914879, 5390.218418329398, 5451.236826844324, 4739.810407583796, 4995.050940321327, 5084.805070808283, 5421.945780542144, 5445.564354851682, 4406.427164468599, 5062.721550314768, 5171.612129090408, 5318.840434786963, 5338.572914166935], "value": 4011.3033459550143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.379266245704, 710.2613282186526, 748.4498893313414, 770.3259647808167, 784.9207339907628, 14644.70710585789, 15924.52226433206, 15926.681466370666, 15929.203539823005, 15976.760348594751, 12093.576724814633, 14233.857661423383, 14351.784723229133, 14357.425702971896, 14374.856251437483, 669.6718607882151, 696.1646024770271, 699.7306037175673, 704.7639040921298, 838.8741688746676, 699.1889408286389, 701.6281370873445, 824.517657170553, 857.1471385970937, 858.1761508951414, 923.3490389275545, 928.7445952363121, 938.9671361502361, 940.3276657190141, 944.598545618113, 924.2605915267759, 925.4586067150691, 926.8100039491889, 927.6660402255183, 931.3201363005044, 532.0980937311259, 542.6391449685954, 695.117201154536, 698.0088274650009, 700.2892064554482, 14292.928535357256, 14795.112293262438, 14851.554453366416, 15168.165750883696, 15192.848071519255, 14196.50312239077, 14713.632159196106, 14842.109473431628, 14851.628709282246, 14855.92572037133, 11734.589289374917, 11831.585894493737, 11900.702482437922, 11901.404929753564, 11955.760884782343, 3779.8677046303246, 3791.146991926822, 3899.6490315871706, 3910.1397692507603, 3989.501312335958, 114392.89771028845, 115036.0, 116779.0, 117458.82541174564, 118724.21913671301, 11315.547378104948, 11345.035671967975, 11355.091592672567, 11368.658940231806, 11660.825087623693, 23774.0, 23776.7622323778, 23818.04727810903, 23838.284851454486, 23857.761422385902, 7671.462997590227, 7713.421493387906, 7722.42081843864, 8003.719869804529, 8034.47775894565, 4266.168097221012, 4392.099619577979, 4416.933745993812, 4521.231390663529, 4536.070105628338, 3571.2500374921037, 3687.1335236219466, 3691.5201023866725, 3693.3905905525826, 3698.907527311785, 659.1365311442026, 665.8135721997849, 666.3592687896976, 671.966401679914, 674.1068084787676, 548.7174105335755, 552.4144406928668, 639.1850390751829, 668.5921587831419, 682.9726810927607, 9124.0, 9455.905440945502, 9623.518824058909, 9831.901680983241, 10032.1472674822, 663.3929954092048, 673.6598018000878, 681.1859827506181, 695.7112798188796, 698.8742026435189, 4492.0, 4539.614132798683, 4688.2733176357715, 4728.95962888164, 4770.809167633325, 87990.0, 89352.5532372334, 89549.65675514872, 147859.0, 157963.0, 143994.0, 144798.5520144806, 147950.26024869806, 148026.0, 152247.0, 118279.0, 119075.0, 121471.0, 122164.0, 122853.38573307077, 7383.852322953619, 7473.402127829652, 8441.240288374076, 8850.0, 8924.776880578038, 64602.0, 65849.01226481702, 67032.66483667551, 68247.25571351385, 68410.65794670995, 92913.67715806888, 104769.47615262179, 106323.0, 107159.0, 107975.0, 11987.280763154238, 12050.216735912138, 12117.69705757363, 12327.69180770488, 12638.43127059303, 12811.487540498276, 12979.28613926243, 13107.606771796862, 13260.602181934557, 13325.33373331349, 12089.818652720392, 12200.633980980585, 12442.315672638091, 12467.750644986869, 13182.472701091849, 8820.62871387712, 12398.566050188561, 12629.368531573567, 13811.0, 14147.504837330644, 13124.34378281063, 13603.455861765418, 13613.795793062915, 13617.659558511117, 13650.0, 11185.66443006711, 11243.550257989917, 11552.884471155181, 11602.88397116018, 11899.0, 9999.950000250243, 11420.657380278604, 11437.313761174357, 11595.492585963771, 11786.292822430682], "value": 7799.716912863756, "unit": "score"}], "name": "dromaeo_css", "value": 7152.210084989511, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 114.0, 112.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 112.0, 111.0, 153.0, 112.0, 111.0, 112.0, 114.0, 143.0, 113.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [259.0, 255.0, 221.0, 186.0, 182.0, 173.0, 180.0, 184.0, 184.0, 182.0], "value": 200.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 88.0, 61.0, 59.0, 58.0, 59.0, 87.0, 73.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 94.0, 69.0, 68.0, 69.0, 92.0, 66.0, 69.0, 90.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 85.0, 89.0, 86.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 94.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 87.0, 86.0, 85.0, 87.0, 86.0, 85.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 52.0, 52.0, 53.0, 54.0, 53.0, 52.0, 54.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 79.0, 75.0, 73.0, 71.0, 71.0, 72.0, 71.0, 73.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 86.0, 87.0, 86.0, 83.0, 89.0, 86.0, 89.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 136.0, 152.0, 149.0, 160.0, 160.0, 176.0, 144.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 173.0, 170.0, 173.0, 168.0, 167.0, 169.0, 168.0, 169.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 68.0, 69.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1449.8, "alertThreshold": 2.0}]} 06:35:29 INFO - Return code: 0 06:35:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:30 INFO - # TBPL SUCCESS # 06:35:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:35:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:35:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:35:30 INFO - 2016-10-12 06:35:30.044 screenresolution[2276:19773] starting screenresolution argv=screenresolution get 06:35:30 INFO - 2016-10-12 06:35:30.047 screenresolution[2276:19773] Display 0: 1600x1200x32@60 06:35:30 INFO - 2016-10-12 06:35:30.053 screenresolution[2277:19776] starting screenresolution argv=screenresolution list 06:35:30 INFO - Available Modes on Display 0 06:35:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:35:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:35:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:35:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:35:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:35:30 INFO - Intel Iris: 06:35:30 INFO - Chipset Model: Intel Iris 06:35:30 INFO - Type: GPU 06:35:30 INFO - Bus: Built-In 06:35:30 INFO - VRAM (Dynamic, Max): 1536 MB 06:35:30 INFO - Vendor: Intel (0x8086) 06:35:30 INFO - Device ID: 0x0a2e 06:35:30 INFO - Revision ID: 0x0009 06:35:30 INFO - Displays: 06:35:30 INFO - Display: 06:35:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:35:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:35:30 INFO - Display Serial Number: 1600x1200 60 06:35:30 INFO - Main Display: Yes 06:35:30 INFO - Mirror: Off 06:35:30 INFO - Online: Yes 06:35:30 INFO - Rotation: Supported 06:35:30 INFO - Return code: 0 06:35:30 INFO - Running post-action listener: _resource_record_post_action 06:35:30 INFO - [mozharness: 2016-10-12 13:35:30.288043Z] Finished run-tests step (success) 06:35:30 INFO - Running post-run listener: _resource_record_post_run 06:35:30 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 983040; Write bytes: 704641024; Read time: 10; Write time: 1943 06:35:30 INFO - TinderboxPrint: CPU usage
53.5% 06:35:30 INFO - TinderboxPrint: I/O read bytes / time
983,040 / 10 06:35:30 INFO - TinderboxPrint: I/O write bytes / time
704,641,024 / 1,943 06:35:30 INFO - TinderboxPrint: CPU idle
2,254.6 (46.5%) 06:35:30 INFO - TinderboxPrint: CPU system
971.9 (20.0%) 06:35:30 INFO - TinderboxPrint: CPU user
1,623.2 (33.5%) 06:35:30 INFO - TinderboxPrint: Swap in / out
407,142,400 / 0 06:35:30 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401406464; Write bytes: 396097024; Read time: 29645; Write time: 742 06:35:30 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 978944; Write bytes: 293609984; Read time: 10; Write time: 1151 06:35:30 INFO - Running post-run listener: _upload_blobber_files 06:35:30 INFO - Blob upload gear active. 06:35:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:30 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 06:35:30 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:35:30 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:35:31 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:32 INFO - (blobuploader) - INFO - Done attempting. 06:35:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:35:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:35:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:33 INFO - (blobuploader) - INFO - Done attempting. 06:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:35 INFO - (blobuploader) - INFO - Done attempting. 06:35:35 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:35 INFO - Return code: 0 06:35:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e927dc7de0d893830f8b5c1a65bc977eb97007a9050dc9ef1b99042c39a6978ce7c14da3606ab95a328267d964492fd7380498e83b9daaf94fa28ceebb5c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4c1195ec15750cd64d487ff21794ffab03b35706aa4186723389f6d3ce51eed6d4e46e5c772c490fa61dcff3c15f20446a42f3896edae86fb952d43065808af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ee248e0c3a0063b4254bb796840cdd6ef8e68113ff5359e68bb778d51f9bbb5c09af85529b47fcd265d4275be8151dc2b0c53a4fcd01b55155e28211efb4ec"} 06:35:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:35 INFO - Contents: 06:35:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e927dc7de0d893830f8b5c1a65bc977eb97007a9050dc9ef1b99042c39a6978ce7c14da3606ab95a328267d964492fd7380498e83b9daaf94fa28ceebb5c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4c1195ec15750cd64d487ff21794ffab03b35706aa4186723389f6d3ce51eed6d4e46e5c772c490fa61dcff3c15f20446a42f3896edae86fb952d43065808af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ee248e0c3a0063b4254bb796840cdd6ef8e68113ff5359e68bb778d51f9bbb5c09af85529b47fcd265d4275be8151dc2b0c53a4fcd01b55155e28211efb4ec"} 06:35:35 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:35 INFO - Copying logs to upload dir... 06:35:35 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.035185 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-12 06:35:35.566950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 06:35:35.570222) ========= 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.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e927dc7de0d893830f8b5c1a65bc977eb97007a9050dc9ef1b99042c39a6978ce7c14da3606ab95a328267d964492fd7380498e83b9daaf94fa28ceebb5c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4c1195ec15750cd64d487ff21794ffab03b35706aa4186723389f6d3ce51eed6d4e46e5c772c490fa61dcff3c15f20446a42f3896edae86fb952d43065808af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ee248e0c3a0063b4254bb796840cdd6ef8e68113ff5359e68bb778d51f9bbb5c09af85529b47fcd265d4275be8151dc2b0c53a4fcd01b55155e28211efb4ec"} build_url:https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011555 build_url: 'https://queue.taskcluster.net/v1/task/cmJPfEPzQs608nbMrikpeQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6e927dc7de0d893830f8b5c1a65bc977eb97007a9050dc9ef1b99042c39a6978ce7c14da3606ab95a328267d964492fd7380498e83b9daaf94fa28ceebb5c46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4c1195ec15750cd64d487ff21794ffab03b35706aa4186723389f6d3ce51eed6d4e46e5c772c490fa61dcff3c15f20446a42f3896edae86fb952d43065808af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90ee248e0c3a0063b4254bb796840cdd6ef8e68113ff5359e68bb778d51f9bbb5c09af85529b47fcd265d4275be8151dc2b0c53a4fcd01b55155e28211efb4ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 06:35:35.604699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:35:35.605286) ========= 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.VWim8ZsRwy/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.aykZEWwvrG/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005232 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:35:35.654709) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 06:35:35.655052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 06:35:35.655411) ========= ========= Total master_lag: 0.18 =========