builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0354 starttime: 1468833260.17 results: success (0) buildid: 20160718000754 builduid: ec3b42f4c50140929997961397293eb7 revision: 0fbdcd21fad76a00328e67875c6f40dc219235f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.174773) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.175200) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.175490) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.202417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.202691) ========= 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.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-07-18 02:14:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.5M=0s 2016-07-18 02:14:20 (26.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.508450 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.733235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.733537) ========= 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.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.025311 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.781601) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:20.781915) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0fbdcd21fad76a00328e67875c6f40dc219235f4 --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 0fbdcd21fad76a00328e67875c6f40dc219235f4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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-07-18 02:14:20,865 truncating revision to first 12 chars 2016-07-18 02:14:20,865 Setting DEBUG logging. 2016-07-18 02:14:20,865 attempt 1/10 2016-07-18 02:14:20,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0fbdcd21fad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 02:14:21,506 unpacking tar archive at: mozilla-central-0fbdcd21fad7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.929622 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:21.732702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:21.732990) ========= script_repo_revision: 0fbdcd21fad76a00328e67875c6f40dc219235f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:21.733376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:21.733718) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 02:14:21.751904) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-07-18 02:14:21.752207) ========= /tools/buildbot/bin/python 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', '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:14:21 INFO - MultiFileLogger online at 20160718 02:14:21 in /builds/slave/test 02:14:21 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 02:14:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:14:21 INFO - {'append_to_log': False, 02:14:21 INFO - 'base_work_dir': '/builds/slave/test', 02:14:21 INFO - 'blob_upload_branch': 'Firefox', 02:14:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:14:21 INFO - 'branch': 'Firefox', 02:14:21 INFO - 'buildbot_json_path': 'buildprops.json', 02:14:21 INFO - 'config_files': ('talos/mac_config.py',), 02:14:21 INFO - 'default_actions': ('clobber', 02:14:21 INFO - 'read-buildbot-config', 02:14:21 INFO - 'download-and-extract', 02:14:21 INFO - 'populate-webroot', 02:14:21 INFO - 'create-virtualenv', 02:14:21 INFO - 'install', 02:14:21 INFO - 'run-tests'), 02:14:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:14:21 INFO - 'download_minidump_stackwalk': True, 02:14:21 INFO - 'download_symbols': 'ondemand', 02:14:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:14:21 INFO - 'tooltool.py': '/tools/tooltool.py', 02:14:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:14:21 INFO - '/tools/misc-python/virtualenv.py')}, 02:14:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:14:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:14:21 INFO - 'installer_path': 'installer.exe', 02:14:21 INFO - 'log_level': 'info', 02:14:21 INFO - 'log_name': 'talos', 02:14:21 INFO - 'log_to_console': True, 02:14:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:14:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:14:21 INFO - 'opt_config_files': (), 02:14:21 INFO - 'pip_index': False, 02:14:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:14:21 INFO - 'cmd': ('bash', 02:14:21 INFO - '-c', 02:14:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:14:21 INFO - 'enabled': True, 02:14:21 INFO - 'halt_on_failure': False, 02:14:21 INFO - 'name': 'check_screen_resolution'},), 02:14:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:14:21 INFO - 'cmd': ('bash', 02:14:21 INFO - '-c', 02:14:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:14:21 INFO - 'enabled': True, 02:14:21 INFO - 'halt_on_failure': False, 02:14:21 INFO - 'name': 'check_screen_resolution'},), 02:14:21 INFO - 'run_cmd_checks_enabled': True, 02:14:21 INFO - 'sps_profile': False, 02:14:21 INFO - 'sps_profile_interval': 0, 02:14:21 INFO - 'suite': 'dromaeojs', 02:14:21 INFO - 'system_bits': '32', 02:14:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:14:21 INFO - 'title': 't-yosemite-r7-0354', 02:14:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:14:21 INFO - 'use_talos_json': True, 02:14:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:14:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:14:21 INFO - 'work_dir': 'build'} 02:14:21 INFO - ##### 02:14:21 INFO - ##### Running clobber step. 02:14:21 INFO - ##### 02:14:21 INFO - Running pre-action listener: _resource_record_pre_action 02:14:21 INFO - Running main action method: clobber 02:14:21 INFO - rmtree: /builds/slave/test/build 02:14:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:14:23 INFO - Running post-action listener: _resource_record_post_action 02:14:23 INFO - ##### 02:14:23 INFO - ##### Finished clobber step (success) 02:14:23 INFO - ##### 02:14:23 INFO - ##### 02:14:23 INFO - ##### Running read-buildbot-config step. 02:14:23 INFO - ##### 02:14:23 INFO - Running pre-action listener: _resource_record_pre_action 02:14:23 INFO - Running main action method: read_buildbot_config 02:14:23 INFO - Using buildbot properties: 02:14:23 INFO - { 02:14:23 INFO - "project": "", 02:14:23 INFO - "product": "firefox", 02:14:23 INFO - "script_repo_revision": "production", 02:14:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:14:23 INFO - "repository": "", 02:14:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:14:23 INFO - "buildid": "20160718000754", 02:14:23 INFO - "pgo_build": "False", 02:14:23 INFO - "basedir": "/builds/slave/test", 02:14:23 INFO - "buildnumber": 20, 02:14:23 INFO - "slavename": "t-yosemite-r7-0354", 02:14:23 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:14:23 INFO - "platform": "yosemite_r7", 02:14:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:14:23 INFO - "branch": "mozilla-central", 02:14:23 INFO - "repo_path": "mozilla-central", 02:14:23 INFO - "revision": "0fbdcd21fad76a00328e67875c6f40dc219235f4", 02:14:23 INFO - "stage_platform": "macosx64", 02:14:23 INFO - "builduid": "ec3b42f4c50140929997961397293eb7", 02:14:23 INFO - "slavebuilddir": "test" 02:14:23 INFO - } 02:14:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:14:23 INFO - Running post-action listener: _resource_record_post_action 02:14:23 INFO - ##### 02:14:23 INFO - ##### Finished read-buildbot-config step (success) 02:14:23 INFO - ##### 02:14:23 INFO - ##### 02:14:23 INFO - ##### Running download-and-extract step. 02:14:23 INFO - ##### 02:14:23 INFO - Running pre-action listener: _resource_record_pre_action 02:14:23 INFO - Running main action method: download_and_extract 02:14:23 INFO - mkdir: /builds/slave/test/build/tests 02:14:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:14:23 INFO - https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:14:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:14:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:14:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:14:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 02:14:53 INFO - Downloaded 1199 bytes. 02:14:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:14:53 INFO - Using the following test package requirements: 02:14:53 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:14:53 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:14:53 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:14:53 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'jsshell-mac.zip'], 02:14:53 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:14:53 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:14:53 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:14:53 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:14:53 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:14:53 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:14:53 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:14:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:14:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:14:53 INFO - https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:14:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:14:55 INFO - Downloaded 18835899 bytes. 02:14:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:14:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:14:58 INFO - Return code: 0 02:14:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:14:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:14:58 INFO - https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:14:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:15:00 INFO - Downloaded 18835899 bytes. 02:15:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:15:03 INFO - Return code: 0 02:15:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:03 INFO - https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:15:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:15:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:15:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:15:04 INFO - Downloaded 14403317 bytes. 02:15:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:15:05 INFO - Return code: 0 02:15:05 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:05 INFO - https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:15:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:15:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:15:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 02:15:08 INFO - Downloaded 112769557 bytes. 02:15:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:15:08 INFO - mkdir: /builds/slave/test/properties 02:15:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:15:08 INFO - Writing to file /builds/slave/test/properties/build_url 02:15:08 INFO - Contents: 02:15:08 INFO - build_url:https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:15:09 INFO - Running post-action listener: _resource_record_post_action 02:15:09 INFO - Running post-action listener: set_extra_try_arguments 02:15:09 INFO - ##### 02:15:09 INFO - ##### Finished download-and-extract step (success) 02:15:09 INFO - ##### 02:15:09 INFO - ##### 02:15:09 INFO - ##### Running populate-webroot step. 02:15:09 INFO - ##### 02:15:09 INFO - Running pre-action listener: _resource_record_pre_action 02:15:09 INFO - Running main action method: populate_webroot 02:15:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:15:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:15:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:15:09 INFO - '--tppagecycles', 02:15:09 INFO - '7'], 02:15:09 INFO - 'tests': ['tsvgm']}}, 02:15:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:15:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:15:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:15:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:15:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['damp', 'tps']}, 02:15:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'tests': ['damp', 'tps']}, 02:15:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['dromaeo_dom']}, 02:15:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:15:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['basic_compositor_video']}, 02:15:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:15:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'other-e10s': {'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['a11yr', 02:15:09 INFO - 'ts_paint', 02:15:09 INFO - 'tpaint', 02:15:09 INFO - 'sessionrestore', 02:15:09 INFO - 'sessionrestore_no_auto_restore', 02:15:09 INFO - 'tabpaint']}, 02:15:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['tsvgx', 02:15:09 INFO - 'tsvgr_opacity', 02:15:09 INFO - 'tart', 02:15:09 INFO - 'tscrollx', 02:15:09 INFO - 'cart']}, 02:15:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:15:09 INFO - 'tsvgr_opacity', 02:15:09 INFO - 'tart', 02:15:09 INFO - 'tscrollx', 02:15:09 INFO - 'cart']}, 02:15:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'talos_options': ['--disable-e10s'], 02:15:09 INFO - 'tests': ['tp5o']}, 02:15:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'tests': ['tp5o']}, 02:15:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'talos_options': ['--disable-e10s', 02:15:09 INFO - '--xperf_path', 02:15:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:09 INFO - 'tests': ['tp5n']}, 02:15:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:09 INFO - 'talos_options': ['--xperf_path', 02:15:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:09 INFO - 'tests': ['tp5n']}}, 02:15:09 INFO - 'talos.zip': {'path': '', 02:15:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:15:09 INFO - Running post-action listener: _resource_record_post_action 02:15:09 INFO - ##### 02:15:09 INFO - ##### Finished populate-webroot step (success) 02:15:09 INFO - ##### 02:15:09 INFO - ##### 02:15:09 INFO - ##### Running create-virtualenv step. 02:15:09 INFO - ##### 02:15:09 INFO - Running pre-action listener: _resource_record_pre_action 02:15:09 INFO - Running main action method: create_virtualenv 02:15:09 INFO - Creating virtualenv /builds/slave/test/build/venv 02:15:09 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 02:15:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:15:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:15:10 INFO - Using real prefix '/tools/python27' 02:15:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:15:10 INFO - Installing distribute.............................................................................................................................................................................................done. 02:15:13 INFO - Installing pip.................done. 02:15:13 INFO - Return code: 0 02:15:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:15:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:13 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:13 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 02:15:13 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 02:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:13 INFO - 'HOME': '/Users/cltbld', 02:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:13 INFO - 'LOGNAME': 'cltbld', 02:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:13 INFO - 'MOZ_NO_REMOTE': '1', 02:15:13 INFO - 'NO_EM_RESTART': '1', 02:15:13 INFO - 'PAGER': '/bin/cat', 02:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:13 INFO - 'PWD': '/builds/slave/test', 02:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:13 INFO - 'SHELL': '/bin/bash', 02:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:13 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:13 INFO - 'USER': 'cltbld', 02:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:13 INFO - 'XPC_FLAGS': '0x0', 02:15:13 INFO - 'XPC_SERVICE_NAME': '0', 02:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:13 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 02:15:13 INFO - Cleaning up... 02:15:13 INFO - Return code: 0 02:15:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:15:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:13 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:13 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 02:15:13 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 02:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:13 INFO - 'HOME': '/Users/cltbld', 02:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:13 INFO - 'LOGNAME': 'cltbld', 02:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:13 INFO - 'MOZ_NO_REMOTE': '1', 02:15:13 INFO - 'NO_EM_RESTART': '1', 02:15:13 INFO - 'PAGER': '/bin/cat', 02:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:13 INFO - 'PWD': '/builds/slave/test', 02:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:13 INFO - 'SHELL': '/bin/bash', 02:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:13 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:13 INFO - 'USER': 'cltbld', 02:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:13 INFO - 'XPC_FLAGS': '0x0', 02:15:13 INFO - 'XPC_SERVICE_NAME': '0', 02:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:14 INFO - Downloading/unpacking psutil>=3.1.1 02:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:15:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:15:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:15:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:15:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:15:17 INFO - Installing collected packages: psutil 02:15:17 INFO - Running setup.py install for psutil 02:15:17 INFO - building 'psutil._psutil_osx' extension 02:15:17 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 02:15:17 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 02:15:17 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 02:15:17 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 02:15:18 INFO - building 'psutil._psutil_posix' extension 02:15:18 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 02:15:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:15:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:15:18 INFO - ^ 02:15:18 INFO - 1 warning generated. 02:15:18 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 02:15:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:15:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:15:18 INFO - Successfully installed psutil 02:15:18 INFO - Cleaning up... 02:15:18 INFO - Return code: 0 02:15:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:15:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:18 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:18 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 02:15:18 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 02:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:18 INFO - 'HOME': '/Users/cltbld', 02:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:18 INFO - 'LOGNAME': 'cltbld', 02:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:18 INFO - 'MOZ_NO_REMOTE': '1', 02:15:18 INFO - 'NO_EM_RESTART': '1', 02:15:18 INFO - 'PAGER': '/bin/cat', 02:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:18 INFO - 'PWD': '/builds/slave/test', 02:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:18 INFO - 'SHELL': '/bin/bash', 02:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:18 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:18 INFO - 'USER': 'cltbld', 02:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:18 INFO - 'XPC_FLAGS': '0x0', 02:15:18 INFO - 'XPC_SERVICE_NAME': '0', 02:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:15:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:15:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:15:21 INFO - Installing collected packages: mozsystemmonitor 02:15:21 INFO - Running setup.py install for mozsystemmonitor 02:15:21 INFO - Successfully installed mozsystemmonitor 02:15:21 INFO - Cleaning up... 02:15:21 INFO - Return code: 0 02:15:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:15:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:21 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:21 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 02:15:21 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 02:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:21 INFO - 'HOME': '/Users/cltbld', 02:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:21 INFO - 'LOGNAME': 'cltbld', 02:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:21 INFO - 'MOZ_NO_REMOTE': '1', 02:15:21 INFO - 'NO_EM_RESTART': '1', 02:15:21 INFO - 'PAGER': '/bin/cat', 02:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:21 INFO - 'PWD': '/builds/slave/test', 02:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:21 INFO - 'SHELL': '/bin/bash', 02:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:21 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:21 INFO - 'USER': 'cltbld', 02:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:21 INFO - 'XPC_FLAGS': '0x0', 02:15:21 INFO - 'XPC_SERVICE_NAME': '0', 02:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:21 INFO - Downloading/unpacking jsonschema==2.5.1 02:15:21 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 02:15:21 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 02:15:21 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 02:15:21 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 02:15:21 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 02:15:21 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 02:15:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:15:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:15:24 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 02:15:24 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 02:15:24 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 02:15:24 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 02:15:24 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 02:15:24 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 02:15:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:15:24 INFO - warning: no files found matching '*.txt' 02:15:24 INFO - no previously-included directories found matching 'build' 02:15:24 INFO - no previously-included directories found matching 'dist' 02:15:24 INFO - no previously-included directories found matching '.git*' 02:15:24 INFO - Installing collected packages: jsonschema, functools32 02:15:24 INFO - Running setup.py install for functools32 02:15:24 INFO - warning: no files found matching '*.txt' 02:15:24 INFO - no previously-included directories found matching 'build' 02:15:24 INFO - no previously-included directories found matching 'dist' 02:15:24 INFO - no previously-included directories found matching '.git*' 02:15:24 INFO - Successfully installed jsonschema functools32 02:15:24 INFO - Cleaning up... 02:15:24 INFO - Return code: 0 02:15:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:15: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:25 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:25 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 02:15:25 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 02:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:25 INFO - 'HOME': '/Users/cltbld', 02:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:25 INFO - 'LOGNAME': 'cltbld', 02:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:25 INFO - 'MOZ_NO_REMOTE': '1', 02:15:25 INFO - 'NO_EM_RESTART': '1', 02:15:25 INFO - 'PAGER': '/bin/cat', 02:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:25 INFO - 'PWD': '/builds/slave/test', 02:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:25 INFO - 'SHELL': '/bin/bash', 02:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:25 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:25 INFO - 'USER': 'cltbld', 02:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:25 INFO - 'XPC_FLAGS': '0x0', 02:15:25 INFO - 'XPC_SERVICE_NAME': '0', 02:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:25 INFO - Downloading/unpacking blobuploader==1.2.4 02:15:25 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 02:15:25 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 02:15:25 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 02:15:25 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 02:15:25 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 02:15:25 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 02:15:28 INFO - Downloading blobuploader-1.2.4.tar.gz 02:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:15:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:15:28 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 02:15:28 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 02:15:28 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 02:15:28 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 02:15:28 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 02:15:28 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 02:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:15:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:15:29 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 02:15:29 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 02:15:29 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 02:15:29 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 02:15:29 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 02:15:29 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 02:15:29 INFO - Downloading docopt-0.6.1.tar.gz 02:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:15:29 INFO - Installing collected packages: blobuploader, requests, docopt 02:15:29 INFO - Running setup.py install for blobuploader 02:15:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:15:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:15:29 INFO - Running setup.py install for requests 02:15:30 INFO - Running setup.py install for docopt 02:15:30 INFO - Successfully installed blobuploader requests docopt 02:15:30 INFO - Cleaning up... 02:15:30 INFO - Return code: 0 02:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:15:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:30 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:30 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 02:15:30 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 02:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:30 INFO - 'HOME': '/Users/cltbld', 02:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:30 INFO - 'LOGNAME': 'cltbld', 02:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:30 INFO - 'MOZ_NO_REMOTE': '1', 02:15:30 INFO - 'NO_EM_RESTART': '1', 02:15:30 INFO - 'PAGER': '/bin/cat', 02:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:30 INFO - 'PWD': '/builds/slave/test', 02:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:30 INFO - 'SHELL': '/bin/bash', 02:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:30 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:30 INFO - 'USER': 'cltbld', 02:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:30 INFO - 'XPC_FLAGS': '0x0', 02:15:30 INFO - 'XPC_SERVICE_NAME': '0', 02:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:15:30 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-4cUxoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-iu0YyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-DufB1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-1_y7RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ryiPJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-W0hfAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Xa9NZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-SiK9Uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-KscGlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Hi0ImP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:15:31 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-OdkOxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-v0PEAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-DxI1Q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-r38qCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-5A500F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-OZipOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:15:32 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-rNVXnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:15:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:15:32 INFO - Running setup.py install for manifestparser 02:15:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:15:32 INFO - Running setup.py install for mozcrash 02:15:33 INFO - Running setup.py install for mozdebug 02:15:33 INFO - Running setup.py install for mozdevice 02:15:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Running setup.py install for mozfile 02:15:33 INFO - Running setup.py install for mozhttpd 02:15:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Running setup.py install for mozinfo 02:15:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Running setup.py install for mozInstall 02:15:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:15:33 INFO - Running setup.py install for mozleak 02:15:34 INFO - Running setup.py install for mozlog 02:15:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Running setup.py install for moznetwork 02:15:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Running setup.py install for mozprocess 02:15:34 INFO - Running setup.py install for mozprofile 02:15:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Running setup.py install for mozrunner 02:15:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:15:34 INFO - Running setup.py install for mozscreenshot 02:15:34 INFO - Running setup.py install for moztest 02:15:35 INFO - Running setup.py install for mozversion 02:15:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:15:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:15:35 INFO - Cleaning up... 02:15:35 INFO - Return code: 0 02:15:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:15:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:35 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:35 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 02:15:35 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 02:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:35 INFO - 'HOME': '/Users/cltbld', 02:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:35 INFO - 'LOGNAME': 'cltbld', 02:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:35 INFO - 'MOZ_NO_REMOTE': '1', 02:15:35 INFO - 'NO_EM_RESTART': '1', 02:15:35 INFO - 'PAGER': '/bin/cat', 02:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:35 INFO - 'PWD': '/builds/slave/test', 02:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:35 INFO - 'SHELL': '/bin/bash', 02:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:35 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:35 INFO - 'USER': 'cltbld', 02:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:35 INFO - 'XPC_FLAGS': '0x0', 02:15:35 INFO - 'XPC_SERVICE_NAME': '0', 02:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:15:35 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-AEOfup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:15:35 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)) 02:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:15:35 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-O6H_t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:15:35 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)) 02:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:15:35 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-pZPbss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:15:35 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)) 02:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:15:35 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-uX0Eh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:15:35 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)) 02:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:15:35 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-6B1VMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-CkOHZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-EfX4Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-yrKr0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-Ufdmhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-LI7ACC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-9tIkZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-kYIO3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-1NaoHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:15:36 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)) 02:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:15:36 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-ToCPsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:15:37 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)) 02:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:15:37 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-lQx5f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:15:37 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)) 02:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:15:37 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-a8OLry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:15:37 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)) 02:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:15:37 INFO - Running setup.py (path:/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/pip-SVvSvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:15:37 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)) 02:15:37 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)) 02:15:37 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)) 02:15:37 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)) 02:15:37 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)) 02:15:37 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)) 02:15:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:15:37 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 02:15:37 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 02:15:37 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 02:15:37 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 02:15:37 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 02:15:37 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 02:15:39 INFO - Downloading blessings-1.6.tar.gz 02:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:15:39 INFO - Installing collected packages: blessings 02:15:39 INFO - Running setup.py install for blessings 02:15:40 INFO - Successfully installed blessings 02:15:40 INFO - Cleaning up... 02:15:40 INFO - Return code: 0 02:15:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:15:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:15:40 INFO - Reading from file tmpfile_stdout 02:15:40 INFO - Current package versions: 02:15:40 INFO - blessings == 1.6 02:15:40 INFO - blobuploader == 1.2.4 02:15:40 INFO - docopt == 0.6.1 02:15:40 INFO - functools32 == 3.2.3-2 02:15:40 INFO - jsonschema == 2.5.1 02:15:40 INFO - manifestparser == 1.1 02:15:40 INFO - mozInstall == 1.12 02:15:40 INFO - mozcrash == 0.17 02:15:40 INFO - mozdebug == 0.1 02:15:40 INFO - mozdevice == 0.48 02:15:40 INFO - mozfile == 1.2 02:15:40 INFO - mozhttpd == 0.7 02:15:40 INFO - mozinfo == 0.9 02:15:40 INFO - mozleak == 0.1 02:15:40 INFO - mozlog == 3.2 02:15:40 INFO - moznetwork == 0.27 02:15:40 INFO - mozprocess == 0.23 02:15:40 INFO - mozprofile == 0.28 02:15:40 INFO - mozrunner == 6.12 02:15:40 INFO - mozscreenshot == 0.1 02:15:40 INFO - mozsystemmonitor == 0.3 02:15:40 INFO - moztest == 0.7 02:15:40 INFO - mozversion == 1.4 02:15:40 INFO - psutil == 3.1.1 02:15:40 INFO - requests == 1.2.3 02:15:40 INFO - wsgiref == 0.1.2 02:15:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:15:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:40 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:40 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 02:15:40 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 02:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:40 INFO - 'HOME': '/Users/cltbld', 02:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:40 INFO - 'LOGNAME': 'cltbld', 02:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:40 INFO - 'MOZ_NO_REMOTE': '1', 02:15:40 INFO - 'NO_EM_RESTART': '1', 02:15:40 INFO - 'PAGER': '/bin/cat', 02:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:40 INFO - 'PWD': '/builds/slave/test', 02:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:40 INFO - 'SHELL': '/bin/bash', 02:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:40 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:40 INFO - 'USER': 'cltbld', 02:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:40 INFO - 'XPC_FLAGS': '0x0', 02:15:40 INFO - 'XPC_SERVICE_NAME': '0', 02:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 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)) 02:15:40 INFO - Cleaning up... 02:15:40 INFO - Return code: 0 02:15:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:15:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:40 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 0x105a5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105af2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa4fbcb5660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, '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.3I8OxCFIk1/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.p17aPuRSDz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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 02:15:40 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 02:15:40 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 02:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:15:40 INFO - 'HOME': '/Users/cltbld', 02:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:15:40 INFO - 'LOGNAME': 'cltbld', 02:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:40 INFO - 'MOZ_NO_REMOTE': '1', 02:15:40 INFO - 'NO_EM_RESTART': '1', 02:15:40 INFO - 'PAGER': '/bin/cat', 02:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:40 INFO - 'PWD': '/builds/slave/test', 02:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:15:40 INFO - 'SHELL': '/bin/bash', 02:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:15:40 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:15:40 INFO - 'USER': 'cltbld', 02:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:40 INFO - 'XPC_FLAGS': '0x0', 02:15:40 INFO - 'XPC_SERVICE_NAME': '0', 02:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:15:40 INFO - Cleaning up... 02:15:40 INFO - Return code: 0 02:15:40 INFO - Running post-action listener: _resource_record_post_action 02:15:40 INFO - Running post-action listener: _start_resource_monitoring 02:15:40 INFO - Starting resource monitoring. 02:15:40 INFO - ##### 02:15:40 INFO - ##### Finished create-virtualenv step (success) 02:15:40 INFO - ##### 02:15:40 INFO - ##### 02:15:40 INFO - ##### Running install step. 02:15:40 INFO - ##### 02:15:40 INFO - Running pre-action listener: _resource_record_pre_action 02:15:40 INFO - Running main action method: install 02:15:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:15:41 INFO - Reading from file tmpfile_stdout 02:15:41 INFO - Detecting whether we're running mozinstall >=1.0... 02:15:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:15:41 INFO - Reading from file tmpfile_stdout 02:15:41 INFO - Output received: 02:15:41 INFO - Usage: mozinstall [options] installer 02:15:41 INFO - Options: 02:15:41 INFO - -h, --help show this help message and exit 02:15:41 INFO - -d DEST, --destination=DEST 02:15:41 INFO - Directory to install application into. [default: 02:15:41 INFO - "/builds/slave/test"] 02:15:41 INFO - --app=APP Application being installed. [default: firefox] 02:15:41 INFO - mkdir: /builds/slave/test/build/application 02:15:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:16:16 INFO - Reading from file tmpfile_stdout 02:16:16 INFO - Output received: 02:16:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:16:16 INFO - Running post-action listener: _resource_record_post_action 02:16:16 INFO - ##### 02:16:16 INFO - ##### Finished install step (success) 02:16:16 INFO - ##### 02:16:16 INFO - ##### 02:16:16 INFO - ##### Running run-tests step. 02:16:16 INFO - ##### 02:16:16 INFO - Running pre-action listener: _resource_record_pre_action 02:16:16 INFO - Running main action method: run_tests 02:16:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:16:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:16:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:16:16 INFO - 2016-07-18 02:16:16.360 screenresolution[1644:8453] starting screenresolution argv=screenresolution get 02:16:16 INFO - 2016-07-18 02:16:16.363 screenresolution[1644:8453] Display 0: 1600x1200x32@60 02:16:16 INFO - 2016-07-18 02:16:16.368 screenresolution[1645:8455] starting screenresolution argv=screenresolution list 02:16:16 INFO - Available Modes on Display 0 02:16:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:16:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:16:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:16:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:16:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:16:16 INFO - Intel Iris: 02:16:16 INFO - Chipset Model: Intel Iris 02:16:16 INFO - Type: GPU 02:16:16 INFO - Bus: Built-In 02:16:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:16:16 INFO - Vendor: Intel (0x8086) 02:16:16 INFO - Device ID: 0x0a2e 02:16:16 INFO - Revision ID: 0x0009 02:16:16 INFO - Displays: 02:16:16 INFO - Display: 02:16:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:16:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:16:16 INFO - Display Serial Number: 1600x1200 60 02:16:16 INFO - Main Display: Yes 02:16:16 INFO - Mirror: Off 02:16:16 INFO - Online: Yes 02:16:16 INFO - Rotation: Supported 02:16:16 INFO - Return code: 0 02:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:16:16 INFO - Python 2.7.3 02:16:16 INFO - Return code: 0 02:16:16 INFO - grabbing minidump binary from tooltool 02:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:16 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 0x1059c1d30>, '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 0x105726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059b9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:16:16 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 02:16:16 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 02:16:16 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 02:16:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:16:16 INFO - Return code: 0 02:16:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:16:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:16:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:16:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:16:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:16:16 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 02:16:16 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0354 --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 02:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3I8OxCFIk1/Render', 02:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:16 INFO - 'HOME': '/Users/cltbld', 02:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:16 INFO - 'LOGNAME': 'cltbld', 02:16:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:16:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:16 INFO - 'MOZ_NO_REMOTE': '1', 02:16:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:16:16 INFO - 'NO_EM_RESTART': '1', 02:16:16 INFO - 'PAGER': '/bin/cat', 02:16:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:16 INFO - 'PWD': '/builds/slave/test', 02:16:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:16 INFO - 'SHELL': '/bin/bash', 02:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p17aPuRSDz/Listeners', 02:16:16 INFO - 'TMPDIR': '/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/', 02:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:16 INFO - 'USER': 'cltbld', 02:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:16 INFO - 'XPC_FLAGS': '0x0', 02:16:16 INFO - 'XPC_SERVICE_NAME': '0', 02:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:16 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0354', '--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 02:16:16 INFO - mozversion application_buildid: 20160718000754 02:16:16 INFO - mozversion application_changeset: 0fbdcd21fad76a00328e67875c6f40dc219235f4 02:16:16 INFO - mozversion application_display_name: Nightly 02:16:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:16:16 INFO - mozversion application_name: Firefox 02:16:16 INFO - mozversion application_remotingname: firefox 02:16:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:16:16 INFO - mozversion application_vendor: Mozilla 02:16:16 INFO - mozversion application_version: 50.0a1 02:16:16 INFO - mozversion platform_buildid: 20160718000754 02:16:16 INFO - mozversion platform_changeset: 0fbdcd21fad76a00328e67875c6f40dc219235f4 02:16:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:16:16 INFO - mozversion platform_version: 50.0a1 02:16:16 INFO - using testdate: 1468833376 02:16:16 INFO - actual date: 1468833376 02:16:16 INFO - starting webserver on 'localhost:49266' 02:16:16 INFO - SUITE-START | Running 2 tests 02:16:16 INFO - TEST-START | dromaeo_css 02:16:16 INFO - operating with platform_type : mac_ 02:16:16 INFO - Initialising browser for dromaeo_css test... 02:16:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp9x7ez4/profile http://localhost:49266/getInfo.html) 02:16:19 INFO - PROCESS | 1655 | JavaScript error: , line 0: uncaught exception: undefined 02:16:19 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 02:16:19 INFO - PROCESS | 1655 | colorDepth:24 02:16:19 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 02:16:19 INFO - PROCESS | 1655 | __metrics 02:16:19 INFO - PROCESS | 1655 | 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" 02:16:19 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:16:20 INFO - TEST-INFO | 1655: exit 0 02:16:20 INFO - Browser initialized. 02:16:20 INFO - Running cycle 1/1 for dromaeo_css test... 02:16:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmp9x7ez4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:16:32 INFO - PROCESS | 1658 | RSS: Main: 244928512 02:16:32 INFO - PROCESS | 1658 | 02:17:23 INFO - PROCESS | 1658 | 1468833443331 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] 02:19:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 02:19:35 INFO - PROCESS | 1658 | RSS: Main: 377606144 02:19:35 INFO - PROCESS | 1658 | 02:22:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 02:22:39 INFO - PROCESS | 1658 | RSS: Main: 381419520 02:22:39 INFO - PROCESS | 1658 | 02:25:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 02:25:42 INFO - PROCESS | 1658 | RSS: Main: 373411840 02:25:42 INFO - PROCESS | 1658 | 02:28:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 02:28:45 INFO - PROCESS | 1658 | RSS: Main: 371949568 02:28:45 INFO - PROCESS | 1658 | 02:31:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 02:31:49 INFO - PROCESS | 1658 | RSS: Main: 339898368 02:31:49 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 02:34:52 INFO - PROCESS | 1658 | RSS: Main: 427393024 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | __start_tp_report 02:34:52 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 02:34:52 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 02:34:52 INFO - PROCESS | 1658 | |i|pagename|runs| 02:34:52 INFO - PROCESS | 1658 | |0;dojo.html;3337.8998630041106;3344.9797811667445;3345.715614172795;3369.7135743461804;3425.5375524304213;5521.861953451165;5571.1086226203815;5655.4910058094765;5660.3773584905675;5690.772369105231;5612.635178713381;5646.18130371096;5729.856753581163;5744.224529688491;5796.304443466779;5109.514596113382;5122.206058061007;5166.509181627739;5178.14560597501;5296.920546191791;5270.235815806705;5282.366116066059;5290.656107353029;5294.92057619136;5342.439043900393;5111.795528178887;5139.743012849342;5175.327207463043;5191.039657663326;5217.5565076968505;5206.245094461282;5210.635255532115;5211.895762084737;5212.218167274939;5223.294855194538;5070.188769796851;5149.665271757362;5158.329417175781;5176.171812510018;5200.401953775327;5616.5787565932305;5658.632188907708;5663.405342439067;5672.801451949162;5674.347450043257;6182.443580077769;6225.19072520574;6245.219347581552;6250.156228909084;6252.5935814171935;6097.25246653012;6133.457080270857;6271.247450305947;6275.686215689243;6293.811185664392;5826.951148793216;5834.095715164156;5834.3290521589715;5840.007198776176;5849.88300233993;6309.242890853127;6352.2694890087305;6357.332480089616;6385.201849768779;6386.489080873519;5919.55603329752;5984.042553191469;6021.638701677914;6074.939250607437;6097.359777223416;6275.811725648238;6276.717547710364;6279.780207692709;6289.371062893765;6301.40136687014;6182.69086545671;6191.25704915404;6195.16365290689;6199;6205.069239614094;5941.287045554561;6063.090536419572;6081.726322315506;6128.442013630915;6137.193127861533;4907.9263811042865;4928.926066109011;4950.702957822542;4957.826476073392;4983.127952608279;4420.557944205618;4429.933550996738;4435;4471.06107717383;4479.529649386833;5570.693611851306;5622.718864056781;5638.633488823295;5668.376478587352;5675.31896172462;6222.284437289679;6282.685865706696;6291.213598300213;6308.873822523524;6309.211348581427;5682.119271512922;5713.74288157034;5721.39925307845;5725.570582206268;5771.567132465082;5282.524572788468;5348.91976620351;5366.785328586813;5370.650907690988;5409.459054094559;6279.999999999909;6323.1463752394675;6342.207224096988;6349.746010159542;6371.076193951851;6276.905846412399;6298.149749783721;6300.905486417616;6314.400131987454;6331.5251356147555;6190.50475961913;6328.303886572566;6354.745810167541;6368.331325210971;6370.872582548416;6109.556495504338;6111.174991376108;6122.154209354628;6186.381361863778;6189.752409903554;6102.084687296941;6269.811905642656;6277.529185311028;6290.21372328459;6303.810885673437;5865.384134665798;5920.822375328747;6054.152418661479;6093.116498107838;6094;5897.203877476486;5935.970320148372;5957.642541447527;5960.731767070579;5983.820485385445;5862.267216597926;5868.823935281948;5874.970625146847;5931.288245410578;5947;4933.235348520985;5827.825165245049;5999.970000149971;6004.669743163993;6029.849253768691;5755.740991655469;5808.6224395414165;5826.300843898759;5832.562557808266;5833.066709326487 02:34:52 INFO - PROCESS | 1658 | |1;ext.html;477.7491816796182;513.984879862272;519.5765451157309;529.4943329120689;552.1827695011383;31709.731610735573;42283.78858105709;43269.78365108174;43464.56535434645;43584.128317433635;44212;44594.33108503375;44725.776371118096;44807.32789008167;44824;8719.476831390137;9625.470599117045;9819.90180098197;9821.558029888673;9826.06652368024;8746.737597872074;11659.300441973466;11847.526098956032;11850.7629847403;12131.757364852698;11853.051755859553;11939;11944.581939632115;11947.880521194764;11952.64142075739;8703.086175951561;11587.130965177561;11808.114391420635;11808.52765889372;11897.167198296123;11732.94133529327;11794.174407791457;11819.113566482549;11834.644960651109;11851.822222666668;41318.79340603308;41767.58232417667;41839.37240941388;41851.7907410461;41902;3527;3538.85844566217;3661.4324779659196;3695.3902606069964;3849.4610754494392;31392.37215255682;31639.84180079108;32697.50953735672;32820.83589582061;32933;3251.154699778051;3261.8369081546066;3300.47192449207;3420.110771199506;3425.640307767673;445587;450775;450889;450983.4901650974;451466;53569.46430535645;61068.38931610716;61256;62035.379646203866;62160;65025;65329.67335163294;65621.67189164119;65650;65742.67128664421;16554.089525075997;16731;16743.497695069167;16746.581335466468;16777.245023973952;11740.002099821611;11936.820947685792;12801.679958000937;12947.287899165449;13167.407466664023;13062.085654004128;13750.312484375741;16546.917265413595;16566.420175293806;16713.07799603037;5427;6597.901031484532;6616.305287944792;6627.436667883284;6655.401013908771;899.5187574647532;953.8712273843081;1010.8989101089833;1023.3910823060268;1055.7413433708791;29460;29526.96655617043;30751.384972300428;30825;31724.524132138038;1002.7465667915106;1088.4394536813547;1097.983530247047;1145.0496088246734;1200.1059210887945;14851;15356;16123.435679751152;16134.919325403533;16165.59586010359;640864;656748;659352;659412;660006;54131.18803217955;56429;61811.07283390753;62033.68983155055;62106.37893621096;1275.324078238535;1408.5211028250444;1426.850180731029;1446.067286600158;1446.5515690136092;32001.359972800878;32049;32057.03828885137;32409.9210574995;33024.66975330216;51381.22928156006;51566.484335156165;52181;52202.21696674629;52270;544980;550145;550512;551492;553886;26844.79198436114;27339.726602734514;27433.725662743916;28464.85767571149;28609.99865004715;49254.75372623114;49347.50652493573;50016.74824143413;50928.23607645962;51142.48857511526;40710.389344159266;40890.79554602208;41578.168436631706;41911.378801888895;42196.789016054725;36230;40758.38862417125;40917.1816563673;41933.79033104815;41949;37781.81109094437;39433;40506.594934050285;40602.59397406106;40619.1876162481;33870.66129338675;34787.22348973296;34863.30273394467;35724.821375892956;35990;24375.75624243806;24794.25617231486;25835.354116147246;26097.869510653083;26769.464610708066 02:34:52 INFO - PROCESS | 1658 | |2;jquery.html;328.9868405263788;363.3006462559572;380.7277796375592;388.76285465865817;415.1261594343906;15356.46252381167;16216.675666486663;20410.795892041075;20796.064177112028;20809.479763005933;20198.596028079468;20209.898950505263;21047.68428473574;21085.578288434222;21157.471063223427;4944.629152813553;5310.575153987691;5488.91766623501;5495.862603434926;6127.877442451148;6075.908861367061;6084.939150608481;6108.69456527174;6120.173776540177;6124.938750612481;5614.157876318544;5947.6431414115295;5984.910226346608;6081;6106.969465152668;5824.475797178232;5946.286445626531;5970;5980.401959803985;5988.461038506533;5793.913091303634;5814.476697097281;5956.344802071769;5959.970200149015;5968.940310596882;16980;24860;25699.97200111993;25703.228903132935;25939.092131775487;2202.306273523835;2243.8204943604637;2444.2545023767743;2501.1746123779108;2903.9836057379957;14915.254237288093;14987.550373488813;15173.393064277416;15383.538493845203;15727.68544629101;1819.3905041811008;1843.5759775251634;1912.4740696308488;1944.3389247655864;2017.5157962088992;87992.56003720005;90085.34274586668;109376.90623093826;127019.3649031749;127210;9008.729738107868;9328.906710932939;11171.16216283782;11510.251833630955;11537.653870383901;14358.425662973574;14440.422383104768;14453.855461445251;14479.92760036193;14516.346764395628;8403.201695838885;8444.535550544779;8477.152284771597;8605.698800541952;8700.869486957701;8597.140285971478;8611.224989750952;8617.78455538604;8723.214910658071;8739.868901966349;4421.38100665907;4569.771511424482;4573.291139873325;4595.4025976622825;4648.930266046012;1885.4720678210124;1939.3212375668686;1939.6702560564738;1946.4744518979796;1946.688529835248;660.4650233311033;763.7823220382276;921.5714692667941;986.3884391677062;1046.1264843855313;21495.925203740062;21515.78484215169;21977.560448790933;22000.88999554992;22054.33836984893;952.5237381309346;953.8508875974615;976.3995142987154;1047.696168111239;1073.973150671224;11433.018077337478;11670.824937625943;11752.882471175179;11821.88178118225;11861.762764744657;582076;592215;599755;606158;606885;127535;132580.67419325683;132604;132818;133435;936.8501039833594;945.9858102128474;963.9084286992725;966.8598053282375;995.4922989275377;18827.152778124742;19303.710444343065;19304.903475482533;19308.517287067934;19360.322388716326;52915.73542132265;53170.93658126893;54530.72734636301;55191.448085518634;55220.72389638026;79611.20388796038;80058.59970700342;80264;80346.19653803621;80625.59687201526;8166.510009399455;8239.464434811958;8388.412811103164;8529.104444033288;8589.484630922165;11132.220744547803;11244.493997769956;13082.607521774362;13296.800547991594;13391.330433478091;9703.320767546204;10597;11060.723481913017;11471.827922581335;11475;11069.944650276697;11071.058959988492;11239.60661376848;11263.380514071478;11292.040176585082;10751.838722419327;11366.886331136582;11452.484638191134;11505.597304094317;11591.88408115908;8129.390295727726;8229;8292.917070829215;8455.408121431441;8512.659493620185;10569.207309451667;11503.482343294405;11536.711582210177;11537.538498459968;11636.243644163442 02:34:52 INFO - PROCESS | 1658 | |3;mootools.html;1360.8571100034503;1494.7832564278176;1556.245221067782;1694.1359906447708;1751.5708651380405;7725.420593455485;8495.277901378382;8734.519601421935;8735.694250701226;8855.601497932592;8463.492190468576;8724.476531408103;8738.825223495527;8739.956300218506;8775.95612021939;2343.2852979841186;2386.7971222446113;2388.0567175965484;2389.928302150938;2392.64110383443;2341.0401735288533;2378.916737914173;2382.9642555361684;2392.7487613800563;2408.5423769483828;2344.8475849069755;2370.679958205646;2382.868942208178;2383.892724827387;2387.0451819272257;2320.9187678431344;2342.929712108631;2352.764723527651;2360.4216966843146;2363.4564050268364;2316.455632926261;2343.6836027136287;2347.5774360615083;2354.882255887199;2355.9646605300936;8657.393982421234;8699.043105258417;8851.955740221321;8872.689455869078;8875.423097498706;5270.446603106695;5419.241306217133;5426.348838139409;5428.864278393033;5435.701036442994;3005.6242969628797;3534.204803919123;3540.6636369544854;3591.1381268495634;3690.8708195213308;1088.3546057188119;1090.2531765740493;1098.4837126550508;1101.2016288191078;1110.1396417776207;11100.389478578674;11157;11160.888391116147;11163.83254251187;11167.218294719292;4290.163418133497;4330.588594083557;4458.487273963471;4538.455385353713;4549.112922979987;8861.202491775643;8863;8874.068222836639;8874.911250887408;8877.467351958903;5069.949300507022;5089.770960306795;5099.107656160157;5102.897942041139;5110.977804439127;4410.977945110318;4588.311753237042;4733.431988161443;4811.422629284508;4934.703917764946;3687.483752274684;3711.2763011213096;3785.1483416231126;3807.2195199983935;3826.253880493277;1132.394169119517;1144.2676686920377;1153.5212886651946;1156.8843115688364;1159.5361855257963;866.445474896067;868.1101870582703;949.1457688080783;962.4465932088937;994.5673631970116;5822.534197264209;5998.730057147439;6080.361562036011;6133.417325354084;6185.134081228632;440.87655456472237;448.09261245977154;459.46931294355073;461.4785292619386;462.5859855429372;3988.6809055275507;4088.550259471456;4098.959010409917;4156.605122513356;4187;10588.735281618021;10675.73310667224;10716.35701857891;10772.138228941665;10788.730281743019;10737.302075365076;10746.94626526878;10750.999999999844;10759.78480430387;10763.892361076445;7723.575203363869;8103.87844182326;8139.877901831477;8282.17178282167;8299.045609754834;6105.511559075175;6123.724432400463;6222.968885155546;6257.092721555258;6269.5611307208055;10022.448765317977;10076.546555405172;10167.949160254151;10171.898281017391;10173.84739228927;8940.955295223483;9806.950965245129;10048.397096174253;10133.493325333557;10421.68734937953;7006.719731210695;7251.238619945041;7377.557346559223;7547.018887544584;7700.152983171959;8390.202930721573;8503.319734421355;8557.101504342203;8570.78573035679;8580.14198580137;7411.666475008746;7447.069116360455;7484.962575187307;7626.275503827128;7656.272654097852;7357.0803649543805;7451.441141914486;7465.813354666177;7586.051743532059;7603.125640551408;7053;7074.186468555976;7108.537945033556;7118.715251389886;7138.571685698875;4191.559886211904;5036.496350364934;5061.316722242597;5118.027574760784;5223.817166399158;4683.1804434223195;4748.715077095248;4752.833650822205;4792.760361981817;4880.194767863263 02:34:52 INFO - PROCESS | 1658 | |4;prototype.html;299.21456177533975;311.56069941382657;311.61982381494573;317.6601036890525;319.34693551686826;4696.17816882046;5261.238579085933;5394.460553944604;5394.514493695562;5480.205370221321;4418.513963464016;5363.490468405495;5385.246065550826;5415.967058447262;5435.858469721364;3888.2223555288915;3922.941155882664;4047.4131250968594;4107.815148318318;4119.361498967664;4021.4369988201674;4062.289099407592;4092.754434733911;4102.3026085565525;4105.486814148231;3525.665061819136;3695.7412981091334;4059.909146789391;4080.8775736727944;4091.468109145807;3868.5551161616354;3899.337112690849;4018.31688612935;4099.7745124018165;4111.629953304187;2765.7372549607753;3538.9646103538894;3551.5738111426535;3556.9110772230642;3568.1971556399862;4743.905121897543;5193.350831146106;5261.579073674096;5268.9736551317;5292.655977361459;4087.448194493735;4478.708883922534;4696.694714843524;4723.622110231173;4743.573078422924;5619.297587801525;5646.717664116778;5660.490555849952;5670.7731690732735;5675.460831221028;3957.6438120569;3962.9009274768073;3978.8408463661417;3984.8917844081375;3995.6603688686496;5103.642745007851;5687.8293651190115;5972.865155620505;5982.880342393128;6049.576529642927;3154.41643295991;3334.883279085269;3674.666586717454;3988.481497405319;4022.8189731462153;3242.5946756655417;3378.071030466592;4069.267531844237;4116.506019277646;4122.3404255319;4534.9319760203625;4717.834875779331;4718.575328220477;4725.503822098699;4733.290006499053;5672.4894759471845;5697.829065128053;5703.828885133453;5713.200151978726;5755.251817263813;1361.6391656211113;1382.9723405531834;1383.1148065238256;1383.4258782605107;1386.9597801648763;738.7635956493978;802.9598520073973;820.335528222142;852.799592095857;870.3733312015339;5080.822171223989;5101.138084599169;5303.522682958553;5313.44208858072;5320.643235974801;4747;5814.680192589448;5841.415858414124;5841.620294680833;5877.882442351214;4871.074495845778;4931.013797240566;5201.397390343695;5321.068812957717;5333.226682131147;2645.1535508637435;2958.2989780876082;3056.480398332289;3084.398542284233;3099.7365223955767;5309.592957866169;5764.596478246566;5854.882902341929;6104.328523862371;6109.725062372204;5788.421157884178;6035.374612043775;6036.245469316335;6056.303525094582;6078.1186727924205;6049.213602231682;6055.364186760326;6065.845760204581;6092.319379473018;6093.6039157454625;5183.3261675983385;5615.326160860723;5872;5874.706264686663;5875.7672084607575;4874.2201247800185;5265.947340526545;5775.422457754191;5791.1199496338395;5799.362070172362;5486.142322097378;5549.5837812165055;5984.760609575743;6025.794841031899;6079.483243924316;3598.478220658042;3993.2412841560013;4009.258287216874;4018.7588744675413;4025.315696331689;5185.792568297225;5771.797987070432;5868.735906884286;5880.8235752927485;5882.382349853204;5338.225957236256;5706.343770466284;5706.343770466449;5727.4845263926445;5740.330746203035;5158.6131040171385;5641.492265696107;5655.773769049192;5684.8010319638615;5700.686462244616;5230.686158830483;5265.157194981725;5540.1800433623;5582.609217354909;5585;4710.8351207707565;5088.760945184107;5358.839234822962;5674.858128546654;5692.601517893708;5036.194208926554;5052.5705315048635;5471.316085489314;5543.473370029841;5555.527780138734 02:34:52 INFO - PROCESS | 1658 | |5;yui.html;756.5385115079802;757.8938948547204;794.4954953604463;827.234807802783;863.5941107679389;15148.469803556864;16470.588235294123;16474.83525164748;16484.17579121045;16520.669586608277;14197.929010354934;15137.545873623809;15155.090694558334;15159.393624255044;15163.77254341183;705.115080573879;739.4749727693344;880.8590625499921;882.4705176893845;892.5046599137476;886.6940905387631;904.6562306323588;905.5291248550783;919.7010971434311;921.9308551858604;947.70147403568;957.4303289542746;957.5643082397527;958.6117622362954;960.0111884758675;745.2956955676888;911.4986757283467;936.1901954809109;952.4428209497418;953.0517135450236;707.9858402831966;708.6173466328177;708.8936659501064;709.4572651921285;709.6309918842173;15166.24168791556;15178.772318415233;15194.46819361328;15196.772048419172;15207.847921520754;13290;13465.528706495226;13550;13595.932020339835;13823.51617693386;12626.558070467581;12766.744665106646;12919.547815826401;12965;13410.731785364238;4641.187792136397;4687.88280292992;4811.78346974387;4853.466118726938;4863.562279394871;112078;114409;114437;115111.84888151094;118005.8199418012;10473.057424831803;11208.551657933755;11302.208845380745;11330.263532870336;11414.543418263176;17132;21693.806840623918;22128.44678883041;22138.225162119248;22139.225127120473;6982.546134501241;8236.876446853303;8312.67245171353;8492.363072769567;8557.657693692307;4465.776711164429;4545.2954792007295;4576.199165146153;4661.021185551072;4742.502037286035;3366.6701652846987;3639.763415377992;3656.433252845813;3779.8866034019024;3791.109089363997;681.9659017049227;688.2051230828401;690.8721886451023;705.5484489926348;707.6461769115442;624.7188765055743;627.1059062426867;686.9965650171717;704.3379223529865;708.3377042465312;9178.816423671622;9664.855027174597;9710.25296953443;9743.123118919331;9814.92035876053;573.7217449536971;655.2922843329193;688.6487891175536;694.4375056204491;720.2905138438633;4653.046125544255;4717.622590192776;4720.976395118071;4748.610302424087;4785.736784476887;149163;157064.21467892587;158524;158693;158835;71603;118747.40626296986;130722;131504.342478287;132239;114532;118113;119742;120110;121191;7426.925730742623;9130.086991301083;9182.219511341611;9188.310876684141;9282.953585232031;62826.37173628205;63214.68392658007;63356.683216583624;64653.35346646473;64864;97771;100053.99946000447;101248.49375753074;103583;104654;13168.473261069834;13394.9330253352;13774.65563360924;14019.299035048003;14152.22162781057;12128.92491594346;15430.768538471708;15740.134292614017;15855.286512107214;16403.3596640335;14511.564653060424;14556.126632402089;14578.562643120724;14579.34392952341;15004.399824006918;14182.858171418155;14398.712025759636;14478.058926169766;14991.550253492413;14998.400063997318;14088.970871262727;14256.857431425553;14364.640883977983;14563.85436145625;14702.926485367505;9258.814823703622;11425.371604561496;11451.370174640475;11540.653780386601;11582.768344633228;9676.177524910461;10985.395803230898;10999.945000274947;11758.118141139277;11851.466683999413 02:34:52 INFO - PROCESS | 1658 | __end_tp_report 02:34:52 INFO - PROCESS | 1658 | __start_cc_report 02:34:52 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1369 02:34:52 INFO - PROCESS | 1658 | __end_cc_report 02:34:52 INFO - PROCESS | 1658 | __startTimestamp1468834492812__endTimestamp 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | ------- Summary: start ------- 02:34:52 INFO - PROCESS | 1658 | Number of tests: 6 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5718.82 Median:5862.27 stddev:635.35 (10.8%) stddev-sans-first:609.23 02:34:52 INFO - PROCESS | 1658 | Values: 3337.9 3345.0 3345.7 3369.7 3425.5 5521.9 5571.1 5655.5 5660.4 5690.8 5612.6 5646.2 5729.9 5744.2 5796.3 5109.5 5122.2 5166.5 5178.1 5296.9 5270.2 5282.4 5290.7 5294.9 5342.4 5111.8 5139.7 5175.3 5191.0 5217.6 5206.2 5210.6 5211.9 5212.2 5223.3 5070.2 5149.7 5158.3 5176.2 5200.4 5616.6 5658.6 5663.4 5672.8 5674.3 6182.4 6225.2 6245.2 6250.2 6252.6 6097.3 6133.5 6271.2 6275.7 6293.8 5827.0 5834.1 5834.3 5840.0 5849.9 6309.2 6352.3 6357.3 6385.2 6386.5 5919.6 5984.0 6021.6 6074.9 6097.4 6275.8 6276.7 6279.8 6289.4 6301.4 6182.7 6191.3 6195.2 6199.0 6205.1 5941.3 6063.1 6081.7 6128.4 6137.2 4907.9 4928.9 4950.7 4957.8 4983.1 4420.6 4429.9 4435.0 4471.1 4479.5 5570.7 5622.7 5638.6 5668.4 5675.3 6222.3 6282.7 6291.2 6308.9 6309.2 5682.1 5713.7 5721.4 5725.6 5771.6 5282.5 5348.9 5366.8 5370.7 5409.5 6280.0 6323.1 6342.2 6349.7 6371.1 6276.9 6298.1 6300.9 6314.4 6331.5 6190.5 6328.3 6354.7 6368.3 6370.9 6109.6 6111.2 6122.2 6186.4 6189.8 6102.1 6269.8 6277.5 6290.2 6303.8 5865.4 5920.8 6054.2 6093.1 6094.0 5897.2 5936.0 5957.6 5960.7 5983.8 5862.3 5868.8 5875.0 5931.3 5947.0 4933.2 5827.8 6000.0 6004.7 6029.8 5755.7 5808.6 5826.3 5832.6 5833.1 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:69663.97 Median:3678.41 stddev:148944.18 (4049.1%) stddev-sans-first:149271.42 02:34:52 INFO - PROCESS | 1658 | Values: 477.7 514.0 519.6 529.5 552.2 31709.7 42283.8 43269.8 43464.6 43584.1 44212.0 44594.3 44725.8 44807.3 44824.0 8719.5 9625.5 9819.9 9821.6 9826.1 8746.7 11659.3 11847.5 11850.8 12131.8 11853.1 11939.0 11944.6 11947.9 11952.6 8703.1 11587.1 11808.1 11808.5 11897.2 11732.9 11794.2 11819.1 11834.6 11851.8 41318.8 41767.6 41839.4 41851.8 41902.0 3527.0 3538.9 3661.4 3695.4 3849.5 31392.4 31639.8 32697.5 32820.8 32933.0 3251.2 3261.8 3300.5 3420.1 3425.6 445587.0 450775.0 450889.0 450983.5 451466.0 53569.5 61068.4 61256.0 62035.4 62160.0 65025.0 65329.7 65621.7 65650.0 65742.7 16554.1 16731.0 16743.5 16746.6 16777.2 11740.0 11936.8 12801.7 12947.3 13167.4 13062.1 13750.3 16546.9 16566.4 16713.1 5427.0 6597.9 6616.3 6627.4 6655.4 899.5 953.9 1010.9 1023.4 1055.7 29460.0 29527.0 30751.4 30825.0 31724.5 1002.7 1088.4 1098.0 1145.0 1200.1 14851.0 15356.0 16123.4 16134.9 16165.6 640864.0 656748.0 659352.0 659412.0 660006.0 54131.2 56429.0 61811.1 62033.7 62106.4 1275.3 1408.5 1426.9 1446.1 1446.6 32001.4 32049.0 32057.0 32409.9 33024.7 51381.2 51566.5 52181.0 52202.2 52270.0 544980.0 550145.0 550512.0 551492.0 553886.0 26844.8 27339.7 27433.7 28464.9 28610.0 49254.8 49347.5 50016.7 50928.2 51142.5 40710.4 40890.8 41578.2 41911.4 42196.8 36230.0 40758.4 40917.2 41933.8 41949.0 37781.8 39433.0 40506.6 40602.6 40619.2 33870.7 34787.2 34863.3 35724.8 35990.0 24375.8 24794.3 25835.4 26097.9 26769.5 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35074.00 Median:25821.16 stddev:99655.02 (385.9%) stddev-sans-first:99900.43 02:34:52 INFO - PROCESS | 1658 | Values: 329.0 363.3 380.7 388.8 415.1 15356.5 16216.7 20410.8 20796.1 20809.5 20198.6 20209.9 21047.7 21085.6 21157.5 4944.6 5310.6 5488.9 5495.9 6127.9 6075.9 6084.9 6108.7 6120.2 6124.9 5614.2 5947.6 5984.9 6081.0 6107.0 5824.5 5946.3 5970.0 5980.4 5988.5 5793.9 5814.5 5956.3 5960.0 5968.9 16980.0 24860.0 25700.0 25703.2 25939.1 2202.3 2243.8 2444.3 2501.2 2904.0 14915.3 14987.6 15173.4 15383.5 15727.7 1819.4 1843.6 1912.5 1944.3 2017.5 87992.6 90085.3 109376.9 127019.4 127210.0 9008.7 9328.9 11171.2 11510.3 11537.7 14358.4 14440.4 14453.9 14479.9 14516.3 8403.2 8444.5 8477.2 8605.7 8700.9 8597.1 8611.2 8617.8 8723.2 8739.9 4421.4 4569.8 4573.3 4595.4 4648.9 1885.5 1939.3 1939.7 1946.5 1946.7 660.5 763.8 921.6 986.4 1046.1 21495.9 21515.8 21977.6 22000.9 22054.3 952.5 953.9 976.4 1047.7 1074.0 11433.0 11670.8 11752.9 11821.9 11861.8 582076.0 592215.0 599755.0 606158.0 606885.0 127535.0 132580.7 132604.0 132818.0 133435.0 936.9 946.0 963.9 966.9 995.5 18827.2 19303.7 19304.9 19308.5 19360.3 52915.7 53170.9 54530.7 55191.4 55220.7 79611.2 80058.6 80264.0 80346.2 80625.6 8166.5 8239.5 8388.4 8529.1 8589.5 11132.2 11244.5 13082.6 13296.8 13391.3 9703.3 10597.0 11060.7 11471.8 11475.0 11069.9 11071.1 11239.6 11263.4 11292.0 10751.8 11366.9 11452.5 11505.6 11591.9 8129.4 8229.0 8292.9 8455.4 8512.7 10569.2 11503.5 11536.7 11537.5 11636.2 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5653.07 Median:2572.88 stddev:3173.45 (123.3%) stddev-sans-first:3165.96 02:34:52 INFO - PROCESS | 1658 | Values: 1360.9 1494.8 1556.2 1694.1 1751.6 7725.4 8495.3 8734.5 8735.7 8855.6 8463.5 8724.5 8738.8 8740.0 8776.0 2343.3 2386.8 2388.1 2389.9 2392.6 2341.0 2378.9 2383.0 2392.7 2408.5 2344.8 2370.7 2382.9 2383.9 2387.0 2320.9 2342.9 2352.8 2360.4 2363.5 2316.5 2343.7 2347.6 2354.9 2356.0 8657.4 8699.0 8852.0 8872.7 8875.4 5270.4 5419.2 5426.3 5428.9 5435.7 3005.6 3534.2 3540.7 3591.1 3690.9 1088.4 1090.3 1098.5 1101.2 1110.1 11100.4 11157.0 11160.9 11163.8 11167.2 4290.2 4330.6 4458.5 4538.5 4549.1 8861.2 8863.0 8874.1 8874.9 8877.5 5069.9 5089.8 5099.1 5102.9 5111.0 4411.0 4588.3 4733.4 4811.4 4934.7 3687.5 3711.3 3785.1 3807.2 3826.3 1132.4 1144.3 1153.5 1156.9 1159.5 866.4 868.1 949.1 962.4 994.6 5822.5 5998.7 6080.4 6133.4 6185.1 440.9 448.1 459.5 461.5 462.6 3988.7 4088.6 4099.0 4156.6 4187.0 10588.7 10675.7 10716.4 10772.1 10788.7 10737.3 10746.9 10751.0 10759.8 10763.9 7723.6 8103.9 8139.9 8282.2 8299.0 6105.5 6123.7 6223.0 6257.1 6269.6 10022.4 10076.5 10167.9 10171.9 10173.8 8941.0 9807.0 10048.4 10133.5 10421.7 7006.7 7251.2 7377.6 7547.0 7700.2 8390.2 8503.3 8557.1 8570.8 8580.1 7411.7 7447.1 7485.0 7626.3 7656.3 7357.1 7451.4 7465.8 7586.1 7603.1 7053.0 7074.2 7108.5 7118.7 7138.6 4191.6 5036.5 5061.3 5118.0 5223.8 4683.2 4748.7 4752.8 4792.8 4880.2 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4612.12 Median:5261.41 stddev:1434.53 (27.3%) stddev-sans-first:1401.56 02:34:52 INFO - PROCESS | 1658 | Values: 299.2 311.6 311.6 317.7 319.3 4696.2 5261.2 5394.5 5394.5 5480.2 4418.5 5363.5 5385.2 5416.0 5435.9 3888.2 3922.9 4047.4 4107.8 4119.4 4021.4 4062.3 4092.8 4102.3 4105.5 3525.7 3695.7 4059.9 4080.9 4091.5 3868.6 3899.3 4018.3 4099.8 4111.6 2765.7 3539.0 3551.6 3556.9 3568.2 4743.9 5193.4 5261.6 5269.0 5292.7 4087.4 4478.7 4696.7 4723.6 4743.6 5619.3 5646.7 5660.5 5670.8 5675.5 3957.6 3962.9 3978.8 3984.9 3995.7 5103.6 5687.8 5972.9 5982.9 6049.6 3154.4 3334.9 3674.7 3988.5 4022.8 3242.6 3378.1 4069.3 4116.5 4122.3 4534.9 4717.8 4718.6 4725.5 4733.3 5672.5 5697.8 5703.8 5713.2 5755.3 1361.6 1383.0 1383.1 1383.4 1387.0 738.8 803.0 820.3 852.8 870.4 5080.8 5101.1 5303.5 5313.4 5320.6 4747.0 5814.7 5841.4 5841.6 5877.9 4871.1 4931.0 5201.4 5321.1 5333.2 2645.2 2958.3 3056.5 3084.4 3099.7 5309.6 5764.6 5854.9 6104.3 6109.7 5788.4 6035.4 6036.2 6056.3 6078.1 6049.2 6055.4 6065.8 6092.3 6093.6 5183.3 5615.3 5872.0 5874.7 5875.8 4874.2 5265.9 5775.4 5791.1 5799.4 5486.1 5549.6 5984.8 6025.8 6079.5 3598.5 3993.2 4009.3 4018.8 4025.3 5185.8 5771.8 5868.7 5880.8 5882.4 5338.2 5706.3 5706.3 5727.5 5740.3 5158.6 5641.5 5655.8 5684.8 5700.7 5230.7 5265.2 5540.2 5582.6 5585.0 4710.8 5088.8 5358.8 5674.9 5692.6 5036.2 5052.6 5471.3 5543.5 5555.5 02:34:52 INFO - PROCESS | 1658 | 02:34:52 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:25646.50 Median:12752.95 stddev:40922.89 (320.9%) stddev-sans-first:40995.02 02:34:52 INFO - PROCESS | 1658 | Values: 756.5 757.9 794.5 827.2 863.6 15148.5 16470.6 16474.8 16484.2 16520.7 14197.9 15137.5 15155.1 15159.4 15163.8 705.1 739.5 880.9 882.5 892.5 886.7 904.7 905.5 919.7 921.9 947.7 957.4 957.6 958.6 960.0 745.3 911.5 936.2 952.4 953.1 708.0 708.6 708.9 709.5 709.6 15166.2 15178.8 15194.5 15196.8 15207.8 13290.0 13465.5 13550.0 13595.9 13823.5 12626.6 12766.7 12919.5 12965.0 13410.7 4641.2 4687.9 4811.8 4853.5 4863.6 112078.0 114409.0 114437.0 115111.8 118005.8 10473.1 11208.6 11302.2 11330.3 11414.5 17132.0 21693.8 22128.4 22138.2 22139.2 6982.5 8236.9 8312.7 8492.4 8557.7 4465.8 4545.3 4576.2 4661.0 4742.5 3366.7 3639.8 3656.4 3779.9 3791.1 682.0 688.2 690.9 705.5 707.6 624.7 627.1 687.0 704.3 708.3 9178.8 9664.9 9710.3 9743.1 9814.9 573.7 655.3 688.6 694.4 720.3 4653.0 4717.6 4721.0 4748.6 4785.7 149163.0 157064.2 158524.0 158693.0 158835.0 71603.0 118747.4 130722.0 131504.3 132239.0 114532.0 118113.0 119742.0 120110.0 121191.0 7426.9 9130.1 9182.2 9188.3 9283.0 62826.4 63214.7 63356.7 64653.4 64864.0 97771.0 100054.0 101248.5 103583.0 104654.0 13168.5 13394.9 13774.7 14019.3 14152.2 12128.9 15430.8 15740.1 15855.3 16403.4 14511.6 14556.1 14578.6 14579.3 15004.4 14182.9 14398.7 14478.1 14991.6 14998.4 14089.0 14256.9 14364.6 14563.9 14702.9 9258.8 11425.4 11451.4 11540.7 11582.8 9676.2 10985.4 10999.9 11758.1 11851.5 02:34:52 INFO - PROCESS | 1658 | -------- Summary: end -------- 02:34:52 INFO - PROCESS | 1658 | 02:34:53 INFO - TEST-INFO | 1658: exit 0 02:34:53 INFO - TEST-OK | dromaeo_css | took 1116488ms 02:34:53 INFO - TEST-START | kraken 02:34:53 INFO - operating with platform_type : mac_ 02:34:53 INFO - Initialising browser for kraken test... 02:34:53 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpfTlFk8/profile http://localhost:49266/getInfo.html) 02:34:55 INFO - PROCESS | 1665 | JavaScript error: , line 0: uncaught exception: undefined 02:34:55 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 02:34:55 INFO - PROCESS | 1665 | colorDepth:24 02:34:55 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 02:34:55 INFO - PROCESS | 1665 | __metrics 02:34:55 INFO - PROCESS | 1665 | 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" 02:34:55 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:34:56 INFO - TEST-INFO | 1665: exit 0 02:34:56 INFO - Browser initialized. 02:34:56 INFO - Running cycle 1/1 for kraken test... 02:34:56 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/T/tmpfTlFk8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:08 INFO - PROCESS | 1666 | RSS: Main: 244883456 02:35:08 INFO - PROCESS | 1666 | 02:35:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 02:35:12 INFO - PROCESS | 1666 | RSS: Main: 294313984 02:35:12 INFO - PROCESS | 1666 | 02:35:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 02:35:15 INFO - PROCESS | 1666 | RSS: Main: 301178880 02:35:15 INFO - PROCESS | 1666 | 02:35:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 02:35:19 INFO - PROCESS | 1666 | RSS: Main: 389447680 02:35:19 INFO - PROCESS | 1666 | 02:35:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 02:35:21 INFO - PROCESS | 1666 | RSS: Main: 291332096 02:35:21 INFO - PROCESS | 1666 | 02:35:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:35:24 INFO - PROCESS | 1666 | RSS: Main: 295325696 02:35:24 INFO - PROCESS | 1666 | 02:35:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 02:35:28 INFO - PROCESS | 1666 | RSS: Main: 331796480 02:35:28 INFO - PROCESS | 1666 | 02:35:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 02:35:31 INFO - PROCESS | 1666 | RSS: Main: 335671296 02:35:31 INFO - PROCESS | 1666 | 02:35:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 02:35:34 INFO - PROCESS | 1666 | RSS: Main: 333524992 02:35:34 INFO - PROCESS | 1666 | 02:35:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:35:37 INFO - PROCESS | 1666 | RSS: Main: 311861248 02:35:37 INFO - PROCESS | 1666 | 02:35:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:35:39 INFO - PROCESS | 1666 | RSS: Main: 319524864 02:35:39 INFO - PROCESS | 1666 | 02:35:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:35:42 INFO - PROCESS | 1666 | RSS: Main: 316424192 02:35:42 INFO - PROCESS | 1666 | 02:35:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:35:45 INFO - PROCESS | 1666 | RSS: Main: 325177344 02:35:45 INFO - PROCESS | 1666 | 02:35:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:35:49 INFO - PROCESS | 1666 | RSS: Main: 326778880 02:35:49 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 02:35:51 INFO - PROCESS | 1666 | RSS: Main: 370069504 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | __start_tp_report 02:35:51 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 02:35:51 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 02:35:51 INFO - PROCESS | 1666 | |i|pagename|runs| 02:35:51 INFO - PROCESS | 1666 | |0;ai-astar;102;114;114;114;113;114;115;114;113;113 02:35:51 INFO - PROCESS | 1666 | |1;audio-beat-detection;134;127;136;189;103;105;127;108;140;105 02:35:51 INFO - PROCESS | 1666 | |2;audio-dft;185;183;184;184;184;181;182;184;186;184 02:35:51 INFO - PROCESS | 1666 | |3;audio-fft;79;58;58;107;59;57;61;65;84;57 02:35:51 INFO - PROCESS | 1666 | |4;audio-oscillator;84;68;91;71;72;68;97;70;67;96 02:35:51 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;90;88;91;88;89;88;74;113;135;87 02:35:51 INFO - PROCESS | 1666 | |6;imaging-darkroom;95;95;94;95;97;95;95;96;94;94 02:35:51 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;87;86;85;85;84;84;86;86;85 02:35:51 INFO - PROCESS | 1666 | |8;json-parse-financial;55;55;52;53;52;53;54;52;51;52 02:35:51 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;67;80;81;76;74;73;71;71;73 02:35:51 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;84;85;82;81;83;82;83;83;84;85 02:35:51 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;142;145;148;145;134;133;138;134;179;137 02:35:51 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;167;173;167;167;169;169;168;166;171;165 02:35:51 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;68;66;67;67;67;66;66;66;65 02:35:51 INFO - PROCESS | 1666 | __end_tp_report 02:35:51 INFO - PROCESS | 1666 | __start_cc_report 02:35:51 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2007 02:35:51 INFO - PROCESS | 1666 | __end_cc_report 02:35:51 INFO - PROCESS | 1666 | __startTimestamp1468834551282__endTimestamp 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | ------- Summary: start ------- 02:35:51 INFO - PROCESS | 1666 | Number of tests: 14 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.78 (3.3%) stddev-sans-first:0.67 02:35:51 INFO - PROCESS | 1666 | Values: 102.0 114.0 114.0 114.0 113.0 114.0 115.0 114.0 113.0 113.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:130.50 stddev:25.92 (19.9%) stddev-sans-first:27.38 02:35:51 INFO - PROCESS | 1666 | Values: 134.0 127.0 136.0 189.0 103.0 105.0 127.0 108.0 140.0 105.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.42 (0.8%) stddev-sans-first:1.42 02:35:51 INFO - PROCESS | 1666 | Values: 185.0 183.0 184.0 184.0 184.0 181.0 182.0 184.0 186.0 184.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:68.50 Median:60.00 stddev:16.59 (27.6%) stddev-sans-first:17.15 02:35:51 INFO - PROCESS | 1666 | Values: 79.0 58.0 58.0 107.0 59.0 57.0 61.0 65.0 84.0 57.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:78.00 stddev:12.29 (15.8%) stddev-sans-first:12.86 02:35:51 INFO - PROCESS | 1666 | Values: 84.0 68.0 91.0 71.0 72.0 68.0 97.0 70.0 67.0 96.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:88.00 stddev:17.15 (19.5%) stddev-sans-first:18.12 02:35:51 INFO - PROCESS | 1666 | Values: 90.0 88.0 91.0 88.0 89.0 88.0 74.0 113.0 135.0 87.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 02:35:51 INFO - PROCESS | 1666 | Values: 95.0 95.0 94.0 95.0 97.0 95.0 95.0 96.0 94.0 94.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.03 (1.2%) stddev-sans-first:1.00 02:35:51 INFO - PROCESS | 1666 | Values: 84.0 87.0 86.0 85.0 85.0 84.0 84.0 86.0 86.0 85.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.37 (2.6%) stddev-sans-first:1.22 02:35:51 INFO - PROCESS | 1666 | Values: 55.0 55.0 52.0 53.0 52.0 53.0 54.0 52.0 51.0 52.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:73.50 stddev:4.89 (6.7%) stddev-sans-first:4.44 02:35:51 INFO - PROCESS | 1666 | Values: 66.0 67.0 80.0 81.0 76.0 74.0 73.0 71.0 71.0 73.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:1.32 (1.6%) stddev-sans-first:1.36 02:35:51 INFO - PROCESS | 1666 | Values: 84.0 85.0 82.0 81.0 83.0 82.0 83.0 83.0 84.0 85.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:143.50 stddev:13.54 (9.4%) stddev-sans-first:14.35 02:35:51 INFO - PROCESS | 1666 | Values: 142.0 145.0 148.0 145.0 134.0 133.0 138.0 134.0 179.0 137.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:168.50 stddev:2.39 (1.4%) stddev-sans-first:2.50 02:35:51 INFO - PROCESS | 1666 | Values: 167.0 173.0 167.0 167.0 169.0 169.0 168.0 166.0 171.0 165.0 02:35:51 INFO - PROCESS | 1666 | 02:35:51 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 02:35:51 INFO - PROCESS | 1666 | Values: 67.0 68.0 66.0 67.0 67.0 67.0 66.0 66.0 66.0 65.0 02:35:51 INFO - PROCESS | 1666 | -------- Summary: end -------- 02:35:51 INFO - PROCESS | 1666 | 02:35:52 INFO - TEST-INFO | 1666: exit 0 02:35:52 INFO - TEST-OK | kraken | took 58698ms 02:35:52 INFO - SUITE-END | took 1175s 02:35:52 INFO - Completed test suite (00:19:35) 02:35:52 INFO - javascript benchmark 02:35:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.8998630041106, 3344.9797811667445, 3345.715614172795, 3369.7135743461804, 3425.5375524304213, 5521.861953451165, 5571.1086226203815, 5655.4910058094765, 5660.3773584905675, 5690.772369105231, 5612.635178713381, 5646.18130371096, 5729.856753581163, 5744.224529688491, 5796.304443466779, 5109.514596113382, 5122.206058061007, 5166.509181627739, 5178.14560597501, 5296.920546191791, 5270.235815806705, 5282.366116066059, 5290.656107353029, 5294.92057619136, 5342.439043900393, 5111.795528178887, 5139.743012849342, 5175.327207463043, 5191.039657663326, 5217.5565076968505, 5206.245094461282, 5210.635255532115, 5211.895762084737, 5212.218167274939, 5223.294855194538, 5070.188769796851, 5149.665271757362, 5158.329417175781, 5176.171812510018, 5200.401953775327, 5616.5787565932305, 5658.632188907708, 5663.405342439067, 5672.801451949162, 5674.347450043257, 6182.443580077769, 6225.19072520574, 6245.219347581552, 6250.156228909084, 6252.5935814171935, 6097.25246653012, 6133.457080270857, 6271.247450305947, 6275.686215689243, 6293.811185664392, 5826.951148793216, 5834.095715164156, 5834.3290521589715, 5840.007198776176, 5849.88300233993, 6309.242890853127, 6352.2694890087305, 6357.332480089616, 6385.201849768779, 6386.489080873519, 5919.55603329752, 5984.042553191469, 6021.638701677914, 6074.939250607437, 6097.359777223416, 6275.811725648238, 6276.717547710364, 6279.780207692709, 6289.371062893765, 6301.40136687014, 6182.69086545671, 6191.25704915404, 6195.16365290689, 6199.0, 6205.069239614094, 5941.287045554561, 6063.090536419572, 6081.726322315506, 6128.442013630915, 6137.193127861533, 4907.9263811042865, 4928.926066109011, 4950.702957822542, 4957.826476073392, 4983.127952608279, 4420.557944205618, 4429.933550996738, 4435.0, 4471.06107717383, 4479.529649386833, 5570.693611851306, 5622.718864056781, 5638.633488823295, 5668.376478587352, 5675.31896172462, 6222.284437289679, 6282.685865706696, 6291.213598300213, 6308.873822523524, 6309.211348581427, 5682.119271512922, 5713.74288157034, 5721.39925307845, 5725.570582206268, 5771.567132465082, 5282.524572788468, 5348.91976620351, 5366.785328586813, 5370.650907690988, 5409.459054094559, 6279.999999999909, 6323.1463752394675, 6342.207224096988, 6349.746010159542, 6371.076193951851, 6276.905846412399, 6298.149749783721, 6300.905486417616, 6314.400131987454, 6331.5251356147555, 6190.50475961913, 6328.303886572566, 6354.745810167541, 6368.331325210971, 6370.872582548416, 6109.556495504338, 6111.174991376108, 6122.154209354628, 6186.381361863778, 6189.752409903554, 6102.084687296941, 6269.811905642656, 6277.529185311028, 6290.21372328459, 6303.810885673437, 5865.384134665798, 5920.822375328747, 6054.152418661479, 6093.116498107838, 6094.0, 5897.203877476486, 5935.970320148372, 5957.642541447527, 5960.731767070579, 5983.820485385445, 5862.267216597926, 5868.823935281948, 5874.970625146847, 5931.288245410578, 5947.0, 4933.235348520985, 5827.825165245049, 5999.970000149971, 6004.669743163993, 6029.849253768691, 5755.740991655469, 5808.6224395414165, 5826.300843898759, 5832.562557808266, 5833.066709326487], "value": 5678.026839210583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.7491816796182, 513.984879862272, 519.5765451157309, 529.4943329120689, 552.1827695011383, 31709.731610735573, 42283.78858105709, 43269.78365108174, 43464.56535434645, 43584.128317433635, 44212.0, 44594.33108503375, 44725.776371118096, 44807.32789008167, 44824.0, 8719.476831390137, 9625.470599117045, 9819.90180098197, 9821.558029888673, 9826.06652368024, 8746.737597872074, 11659.300441973466, 11847.526098956032, 11850.7629847403, 12131.757364852698, 11853.051755859553, 11939.0, 11944.581939632115, 11947.880521194764, 11952.64142075739, 8703.086175951561, 11587.130965177561, 11808.114391420635, 11808.52765889372, 11897.167198296123, 11732.94133529327, 11794.174407791457, 11819.113566482549, 11834.644960651109, 11851.822222666668, 41318.79340603308, 41767.58232417667, 41839.37240941388, 41851.7907410461, 41902.0, 3527.0, 3538.85844566217, 3661.4324779659196, 3695.3902606069964, 3849.4610754494392, 31392.37215255682, 31639.84180079108, 32697.50953735672, 32820.83589582061, 32933.0, 3251.154699778051, 3261.8369081546066, 3300.47192449207, 3420.110771199506, 3425.640307767673, 445587.0, 450775.0, 450889.0, 450983.4901650974, 451466.0, 53569.46430535645, 61068.38931610716, 61256.0, 62035.379646203866, 62160.0, 65025.0, 65329.67335163294, 65621.67189164119, 65650.0, 65742.67128664421, 16554.089525075997, 16731.0, 16743.497695069167, 16746.581335466468, 16777.245023973952, 11740.002099821611, 11936.820947685792, 12801.679958000937, 12947.287899165449, 13167.407466664023, 13062.085654004128, 13750.312484375741, 16546.917265413595, 16566.420175293806, 16713.07799603037, 5427.0, 6597.901031484532, 6616.305287944792, 6627.436667883284, 6655.401013908771, 899.5187574647532, 953.8712273843081, 1010.8989101089833, 1023.3910823060268, 1055.7413433708791, 29460.0, 29526.96655617043, 30751.384972300428, 30825.0, 31724.524132138038, 1002.7465667915106, 1088.4394536813547, 1097.983530247047, 1145.0496088246734, 1200.1059210887945, 14851.0, 15356.0, 16123.435679751152, 16134.919325403533, 16165.59586010359, 640864.0, 656748.0, 659352.0, 659412.0, 660006.0, 54131.18803217955, 56429.0, 61811.07283390753, 62033.68983155055, 62106.37893621096, 1275.324078238535, 1408.5211028250444, 1426.850180731029, 1446.067286600158, 1446.5515690136092, 32001.359972800878, 32049.0, 32057.03828885137, 32409.9210574995, 33024.66975330216, 51381.22928156006, 51566.484335156165, 52181.0, 52202.21696674629, 52270.0, 544980.0, 550145.0, 550512.0, 551492.0, 553886.0, 26844.79198436114, 27339.726602734514, 27433.725662743916, 28464.85767571149, 28609.99865004715, 49254.75372623114, 49347.50652493573, 50016.74824143413, 50928.23607645962, 51142.48857511526, 40710.389344159266, 40890.79554602208, 41578.168436631706, 41911.378801888895, 42196.789016054725, 36230.0, 40758.38862417125, 40917.1816563673, 41933.79033104815, 41949.0, 37781.81109094437, 39433.0, 40506.594934050285, 40602.59397406106, 40619.1876162481, 33870.66129338675, 34787.22348973296, 34863.30273394467, 35724.821375892956, 35990.0, 24375.75624243806, 24794.25617231486, 25835.354116147246, 26097.869510653083, 26769.464610708066], "value": 20626.27891692679, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.9868405263788, 363.3006462559572, 380.7277796375592, 388.76285465865817, 415.1261594343906, 15356.46252381167, 16216.675666486663, 20410.795892041075, 20796.064177112028, 20809.479763005933, 20198.596028079468, 20209.898950505263, 21047.68428473574, 21085.578288434222, 21157.471063223427, 4944.629152813553, 5310.575153987691, 5488.91766623501, 5495.862603434926, 6127.877442451148, 6075.908861367061, 6084.939150608481, 6108.69456527174, 6120.173776540177, 6124.938750612481, 5614.157876318544, 5947.6431414115295, 5984.910226346608, 6081.0, 6106.969465152668, 5824.475797178232, 5946.286445626531, 5970.0, 5980.401959803985, 5988.461038506533, 5793.913091303634, 5814.476697097281, 5956.344802071769, 5959.970200149015, 5968.940310596882, 16980.0, 24860.0, 25699.97200111993, 25703.228903132935, 25939.092131775487, 2202.306273523835, 2243.8204943604637, 2444.2545023767743, 2501.1746123779108, 2903.9836057379957, 14915.254237288093, 14987.550373488813, 15173.393064277416, 15383.538493845203, 15727.68544629101, 1819.3905041811008, 1843.5759775251634, 1912.4740696308488, 1944.3389247655864, 2017.5157962088992, 87992.56003720005, 90085.34274586668, 109376.90623093826, 127019.3649031749, 127210.0, 9008.729738107868, 9328.906710932939, 11171.16216283782, 11510.251833630955, 11537.653870383901, 14358.425662973574, 14440.422383104768, 14453.855461445251, 14479.92760036193, 14516.346764395628, 8403.201695838885, 8444.535550544779, 8477.152284771597, 8605.698800541952, 8700.869486957701, 8597.140285971478, 8611.224989750952, 8617.78455538604, 8723.214910658071, 8739.868901966349, 4421.38100665907, 4569.771511424482, 4573.291139873325, 4595.4025976622825, 4648.930266046012, 1885.4720678210124, 1939.3212375668686, 1939.6702560564738, 1946.4744518979796, 1946.688529835248, 660.4650233311033, 763.7823220382276, 921.5714692667941, 986.3884391677062, 1046.1264843855313, 21495.925203740062, 21515.78484215169, 21977.560448790933, 22000.88999554992, 22054.33836984893, 952.5237381309346, 953.8508875974615, 976.3995142987154, 1047.696168111239, 1073.973150671224, 11433.018077337478, 11670.824937625943, 11752.882471175179, 11821.88178118225, 11861.762764744657, 582076.0, 592215.0, 599755.0, 606158.0, 606885.0, 127535.0, 132580.67419325683, 132604.0, 132818.0, 133435.0, 936.8501039833594, 945.9858102128474, 963.9084286992725, 966.8598053282375, 995.4922989275377, 18827.152778124742, 19303.710444343065, 19304.903475482533, 19308.517287067934, 19360.322388716326, 52915.73542132265, 53170.93658126893, 54530.72734636301, 55191.448085518634, 55220.72389638026, 79611.20388796038, 80058.59970700342, 80264.0, 80346.19653803621, 80625.59687201526, 8166.510009399455, 8239.464434811958, 8388.412811103164, 8529.104444033288, 8589.484630922165, 11132.220744547803, 11244.493997769956, 13082.607521774362, 13296.800547991594, 13391.330433478091, 9703.320767546204, 10597.0, 11060.723481913017, 11471.827922581335, 11475.0, 11069.944650276697, 11071.058959988492, 11239.60661376848, 11263.380514071478, 11292.040176585082, 10751.838722419327, 11366.886331136582, 11452.484638191134, 11505.597304094317, 11591.88408115908, 8129.390295727726, 8229.0, 8292.917070829215, 8455.408121431441, 8512.659493620185, 10569.207309451667, 11503.482343294405, 11536.711582210177, 11537.538498459968, 11636.243644163442], "value": 9718.324427333579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.8571100034503, 1494.7832564278176, 1556.245221067782, 1694.1359906447708, 1751.5708651380405, 7725.420593455485, 8495.277901378382, 8734.519601421935, 8735.694250701226, 8855.601497932592, 8463.492190468576, 8724.476531408103, 8738.825223495527, 8739.956300218506, 8775.95612021939, 2343.2852979841186, 2386.7971222446113, 2388.0567175965484, 2389.928302150938, 2392.64110383443, 2341.0401735288533, 2378.916737914173, 2382.9642555361684, 2392.7487613800563, 2408.5423769483828, 2344.8475849069755, 2370.679958205646, 2382.868942208178, 2383.892724827387, 2387.0451819272257, 2320.9187678431344, 2342.929712108631, 2352.764723527651, 2360.4216966843146, 2363.4564050268364, 2316.455632926261, 2343.6836027136287, 2347.5774360615083, 2354.882255887199, 2355.9646605300936, 8657.393982421234, 8699.043105258417, 8851.955740221321, 8872.689455869078, 8875.423097498706, 5270.446603106695, 5419.241306217133, 5426.348838139409, 5428.864278393033, 5435.701036442994, 3005.6242969628797, 3534.204803919123, 3540.6636369544854, 3591.1381268495634, 3690.8708195213308, 1088.3546057188119, 1090.2531765740493, 1098.4837126550508, 1101.2016288191078, 1110.1396417776207, 11100.389478578674, 11157.0, 11160.888391116147, 11163.83254251187, 11167.218294719292, 4290.163418133497, 4330.588594083557, 4458.487273963471, 4538.455385353713, 4549.112922979987, 8861.202491775643, 8863.0, 8874.068222836639, 8874.911250887408, 8877.467351958903, 5069.949300507022, 5089.770960306795, 5099.107656160157, 5102.897942041139, 5110.977804439127, 4410.977945110318, 4588.311753237042, 4733.431988161443, 4811.422629284508, 4934.703917764946, 3687.483752274684, 3711.2763011213096, 3785.1483416231126, 3807.2195199983935, 3826.253880493277, 1132.394169119517, 1144.2676686920377, 1153.5212886651946, 1156.8843115688364, 1159.5361855257963, 866.445474896067, 868.1101870582703, 949.1457688080783, 962.4465932088937, 994.5673631970116, 5822.534197264209, 5998.730057147439, 6080.361562036011, 6133.417325354084, 6185.134081228632, 440.87655456472237, 448.09261245977154, 459.46931294355073, 461.4785292619386, 462.5859855429372, 3988.6809055275507, 4088.550259471456, 4098.959010409917, 4156.605122513356, 4187.0, 10588.735281618021, 10675.73310667224, 10716.35701857891, 10772.138228941665, 10788.730281743019, 10737.302075365076, 10746.94626526878, 10750.999999999844, 10759.78480430387, 10763.892361076445, 7723.575203363869, 8103.87844182326, 8139.877901831477, 8282.17178282167, 8299.045609754834, 6105.511559075175, 6123.724432400463, 6222.968885155546, 6257.092721555258, 6269.5611307208055, 10022.448765317977, 10076.546555405172, 10167.949160254151, 10171.898281017391, 10173.84739228927, 8940.955295223483, 9806.950965245129, 10048.397096174253, 10133.493325333557, 10421.68734937953, 7006.719731210695, 7251.238619945041, 7377.557346559223, 7547.018887544584, 7700.152983171959, 8390.202930721573, 8503.319734421355, 8557.101504342203, 8570.78573035679, 8580.14198580137, 7411.666475008746, 7447.069116360455, 7484.962575187307, 7626.275503827128, 7656.272654097852, 7357.0803649543805, 7451.441141914486, 7465.813354666177, 7586.051743532059, 7603.125640551408, 7053.0, 7074.186468555976, 7108.537945033556, 7118.715251389886, 7138.571685698875, 4191.559886211904, 5036.496350364934, 5061.316722242597, 5118.027574760784, 5223.817166399158, 4683.1804434223195, 4748.715077095248, 4752.833650822205, 4792.760361981817, 4880.194767863263], "value": 4467.886994401588, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.21456177533975, 311.56069941382657, 311.61982381494573, 317.6601036890525, 319.34693551686826, 4696.17816882046, 5261.238579085933, 5394.460553944604, 5394.514493695562, 5480.205370221321, 4418.513963464016, 5363.490468405495, 5385.246065550826, 5415.967058447262, 5435.858469721364, 3888.2223555288915, 3922.941155882664, 4047.4131250968594, 4107.815148318318, 4119.361498967664, 4021.4369988201674, 4062.289099407592, 4092.754434733911, 4102.3026085565525, 4105.486814148231, 3525.665061819136, 3695.7412981091334, 4059.909146789391, 4080.8775736727944, 4091.468109145807, 3868.5551161616354, 3899.337112690849, 4018.31688612935, 4099.7745124018165, 4111.629953304187, 2765.7372549607753, 3538.9646103538894, 3551.5738111426535, 3556.9110772230642, 3568.1971556399862, 4743.905121897543, 5193.350831146106, 5261.579073674096, 5268.9736551317, 5292.655977361459, 4087.448194493735, 4478.708883922534, 4696.694714843524, 4723.622110231173, 4743.573078422924, 5619.297587801525, 5646.717664116778, 5660.490555849952, 5670.7731690732735, 5675.460831221028, 3957.6438120569, 3962.9009274768073, 3978.8408463661417, 3984.8917844081375, 3995.6603688686496, 5103.642745007851, 5687.8293651190115, 5972.865155620505, 5982.880342393128, 6049.576529642927, 3154.41643295991, 3334.883279085269, 3674.666586717454, 3988.481497405319, 4022.8189731462153, 3242.5946756655417, 3378.071030466592, 4069.267531844237, 4116.506019277646, 4122.3404255319, 4534.9319760203625, 4717.834875779331, 4718.575328220477, 4725.503822098699, 4733.290006499053, 5672.4894759471845, 5697.829065128053, 5703.828885133453, 5713.200151978726, 5755.251817263813, 1361.6391656211113, 1382.9723405531834, 1383.1148065238256, 1383.4258782605107, 1386.9597801648763, 738.7635956493978, 802.9598520073973, 820.335528222142, 852.799592095857, 870.3733312015339, 5080.822171223989, 5101.138084599169, 5303.522682958553, 5313.44208858072, 5320.643235974801, 4747.0, 5814.680192589448, 5841.415858414124, 5841.620294680833, 5877.882442351214, 4871.074495845778, 4931.013797240566, 5201.397390343695, 5321.068812957717, 5333.226682131147, 2645.1535508637435, 2958.2989780876082, 3056.480398332289, 3084.398542284233, 3099.7365223955767, 5309.592957866169, 5764.596478246566, 5854.882902341929, 6104.328523862371, 6109.725062372204, 5788.421157884178, 6035.374612043775, 6036.245469316335, 6056.303525094582, 6078.1186727924205, 6049.213602231682, 6055.364186760326, 6065.845760204581, 6092.319379473018, 6093.6039157454625, 5183.3261675983385, 5615.326160860723, 5872.0, 5874.706264686663, 5875.7672084607575, 4874.2201247800185, 5265.947340526545, 5775.422457754191, 5791.1199496338395, 5799.362070172362, 5486.142322097378, 5549.5837812165055, 5984.760609575743, 6025.794841031899, 6079.483243924316, 3598.478220658042, 3993.2412841560013, 4009.258287216874, 4018.7588744675413, 4025.315696331689, 5185.792568297225, 5771.797987070432, 5868.735906884286, 5880.8235752927485, 5882.382349853204, 5338.225957236256, 5706.343770466284, 5706.343770466449, 5727.4845263926445, 5740.330746203035, 5158.6131040171385, 5641.492265696107, 5655.773769049192, 5684.8010319638615, 5700.686462244616, 5230.686158830483, 5265.157194981725, 5540.1800433623, 5582.609217354909, 5585.0, 4710.8351207707565, 5088.760945184107, 5358.839234822962, 5674.858128546654, 5692.601517893708, 5036.194208926554, 5052.5705315048635, 5471.316085489314, 5543.473370029841, 5555.527780138734], "value": 4148.205613434286, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.5385115079802, 757.8938948547204, 794.4954953604463, 827.234807802783, 863.5941107679389, 15148.469803556864, 16470.588235294123, 16474.83525164748, 16484.17579121045, 16520.669586608277, 14197.929010354934, 15137.545873623809, 15155.090694558334, 15159.393624255044, 15163.77254341183, 705.115080573879, 739.4749727693344, 880.8590625499921, 882.4705176893845, 892.5046599137476, 886.6940905387631, 904.6562306323588, 905.5291248550783, 919.7010971434311, 921.9308551858604, 947.70147403568, 957.4303289542746, 957.5643082397527, 958.6117622362954, 960.0111884758675, 745.2956955676888, 911.4986757283467, 936.1901954809109, 952.4428209497418, 953.0517135450236, 707.9858402831966, 708.6173466328177, 708.8936659501064, 709.4572651921285, 709.6309918842173, 15166.24168791556, 15178.772318415233, 15194.46819361328, 15196.772048419172, 15207.847921520754, 13290.0, 13465.528706495226, 13550.0, 13595.932020339835, 13823.51617693386, 12626.558070467581, 12766.744665106646, 12919.547815826401, 12965.0, 13410.731785364238, 4641.187792136397, 4687.88280292992, 4811.78346974387, 4853.466118726938, 4863.562279394871, 112078.0, 114409.0, 114437.0, 115111.84888151094, 118005.8199418012, 10473.057424831803, 11208.551657933755, 11302.208845380745, 11330.263532870336, 11414.543418263176, 17132.0, 21693.806840623918, 22128.44678883041, 22138.225162119248, 22139.225127120473, 6982.546134501241, 8236.876446853303, 8312.67245171353, 8492.363072769567, 8557.657693692307, 4465.776711164429, 4545.2954792007295, 4576.199165146153, 4661.021185551072, 4742.502037286035, 3366.6701652846987, 3639.763415377992, 3656.433252845813, 3779.8866034019024, 3791.109089363997, 681.9659017049227, 688.2051230828401, 690.8721886451023, 705.5484489926348, 707.6461769115442, 624.7188765055743, 627.1059062426867, 686.9965650171717, 704.3379223529865, 708.3377042465312, 9178.816423671622, 9664.855027174597, 9710.25296953443, 9743.123118919331, 9814.92035876053, 573.7217449536971, 655.2922843329193, 688.6487891175536, 694.4375056204491, 720.2905138438633, 4653.046125544255, 4717.622590192776, 4720.976395118071, 4748.610302424087, 4785.736784476887, 149163.0, 157064.21467892587, 158524.0, 158693.0, 158835.0, 71603.0, 118747.40626296986, 130722.0, 131504.342478287, 132239.0, 114532.0, 118113.0, 119742.0, 120110.0, 121191.0, 7426.925730742623, 9130.086991301083, 9182.219511341611, 9188.310876684141, 9282.953585232031, 62826.37173628205, 63214.68392658007, 63356.683216583624, 64653.35346646473, 64864.0, 97771.0, 100053.99946000447, 101248.49375753074, 103583.0, 104654.0, 13168.473261069834, 13394.9330253352, 13774.65563360924, 14019.299035048003, 14152.22162781057, 12128.92491594346, 15430.768538471708, 15740.134292614017, 15855.286512107214, 16403.3596640335, 14511.564653060424, 14556.126632402089, 14578.562643120724, 14579.34392952341, 15004.399824006918, 14182.858171418155, 14398.712025759636, 14478.058926169766, 14991.550253492413, 14998.400063997318, 14088.970871262727, 14256.857431425553, 14364.640883977983, 14563.85436145625, 14702.926485367505, 9258.814823703622, 11425.371604561496, 11451.370174640475, 11540.653780386601, 11582.768344633228, 9676.177524910461, 10985.395803230898, 10999.945000274947, 11758.118141139277, 11851.466683999413], "value": 8124.729768969299, "unit": "score"}], "name": "dromaeo_css", "value": 7453.121047475109, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0, 113.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 127.0, 136.0, 189.0, 103.0, 105.0, 127.0, 108.0, 140.0, 105.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 184.0, 184.0, 181.0, 182.0, 184.0, 186.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 107.0, 59.0, 57.0, 61.0, 65.0, 84.0, 57.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 91.0, 71.0, 72.0, 68.0, 97.0, 70.0, 67.0, 96.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 88.0, 89.0, 88.0, 74.0, 113.0, 135.0, 87.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 97.0, 95.0, 95.0, 96.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 85.0, 85.0, 84.0, 84.0, 86.0, 86.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 52.0, 53.0, 52.0, 53.0, 54.0, 52.0, 51.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 80.0, 81.0, 76.0, 74.0, 73.0, 71.0, 71.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 81.0, 83.0, 82.0, 83.0, 83.0, 84.0, 85.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 148.0, 145.0, 134.0, 133.0, 138.0, 134.0, 179.0, 137.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 167.0, 167.0, 169.0, 169.0, 168.0, 166.0, 171.0, 165.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1432.6000000000001, "alertThreshold": 2.0}]} 02:35:52 INFO - Return code: 0 02:35:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:35:52 INFO - # TBPL SUCCESS # 02:35:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:35:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:35:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:35:52 INFO - 2016-07-18 02:35:52.597 screenresolution[1670:10633] starting screenresolution argv=screenresolution get 02:35:52 INFO - 2016-07-18 02:35:52.599 screenresolution[1670:10633] Display 0: 1600x1200x32@60 02:35:52 INFO - 2016-07-18 02:35:52.605 screenresolution[1671:10636] starting screenresolution argv=screenresolution list 02:35:52 INFO - Available Modes on Display 0 02:35:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:35:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:35:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:35:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:35:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:35:52 INFO - Intel Iris: 02:35:52 INFO - Chipset Model: Intel Iris 02:35:52 INFO - Type: GPU 02:35:52 INFO - Bus: Built-In 02:35:52 INFO - VRAM (Dynamic, Max): 1536 MB 02:35:52 INFO - Vendor: Intel (0x8086) 02:35:52 INFO - Device ID: 0x0a2e 02:35:52 INFO - Revision ID: 0x0009 02:35:52 INFO - Displays: 02:35:52 INFO - Display: 02:35:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:35:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:35:52 INFO - Display Serial Number: 1600x1200 60 02:35:52 INFO - Main Display: Yes 02:35:52 INFO - Mirror: Off 02:35:52 INFO - Online: Yes 02:35:52 INFO - Rotation: Supported 02:35:52 INFO - Return code: 0 02:35:52 INFO - Running post-action listener: _resource_record_post_action 02:35:52 INFO - ##### 02:35:52 INFO - ##### Finished run-tests step (success) 02:35:52 INFO - ##### 02:35:52 INFO - Running post-run listener: _resource_record_post_run 02:35:52 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31636992; Write bytes: 707114496; Read time: 196; Write time: 1978 02:35:52 INFO - TinderboxPrint: CPU usage
53.4% 02:35:52 INFO - TinderboxPrint: I/O read bytes / time
31,636,992 / 196 02:35:52 INFO - TinderboxPrint: I/O write bytes / time
707,114,496 / 1,978 02:35:52 INFO - TinderboxPrint: CPU idle
2,256.1 (46.6%) 02:35:52 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 02:35:52 INFO - TinderboxPrint: CPU user
1,623.7 (33.5%) 02:35:52 INFO - TinderboxPrint: Swap in / out
435,249,152 / 0 02:35:52 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 403079680; Write bytes: 398131712; Read time: 30981; Write time: 840 02:35:52 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29572608; Write bytes: 295281664; Read time: 178; Write time: 1100 02:35:53 INFO - Running post-run listener: _upload_blobber_files 02:35:53 INFO - Blob upload gear active. 02:35:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:35:53 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 02:35:53 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'] 02:35:53 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 02:35:53 INFO - (blobuploader) - INFO - Open directory for files ... 02:35:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:35:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:35:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:54 INFO - (blobuploader) - INFO - Done attempting. 02:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:35:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:55 INFO - (blobuploader) - INFO - Done attempting. 02:35:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:35:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:35:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:35:58 INFO - (blobuploader) - INFO - Done attempting. 02:35:58 INFO - (blobuploader) - INFO - Iteration through files over. 02:35:58 INFO - Return code: 0 02:35:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:35:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:35:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf2d0698d842af30e9fab378090f29077606b5cda6696dc3e1a40102b94e789ac2e38e326d4a7ea30c153c70ae37335601face890efc10b003a8402f0732f67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728138df00a52082dc4348d8492c437148df1581a0d2e2365cd206f6dce8741be31202e997bd1d9e3063d2de14e081dd30c08847b0463141cb89243a97679961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2ae0494b3f87159bb6e1b88046e25ebe0a29719600f96fd57be03263b9f5acea39e8e252d08f465c0a6d34786086891395d3e5217017307a5d41b41b6c1ad5"} 02:35:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:35:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:35:58 INFO - Contents: 02:35:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf2d0698d842af30e9fab378090f29077606b5cda6696dc3e1a40102b94e789ac2e38e326d4a7ea30c153c70ae37335601face890efc10b003a8402f0732f67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728138df00a52082dc4348d8492c437148df1581a0d2e2365cd206f6dce8741be31202e997bd1d9e3063d2de14e081dd30c08847b0463141cb89243a97679961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2ae0494b3f87159bb6e1b88046e25ebe0a29719600f96fd57be03263b9f5acea39e8e252d08f465c0a6d34786086891395d3e5217017307a5d41b41b6c1ad5"} 02:35:58 INFO - Running post-run listener: copy_logs_to_upload_dir 02:35:58 INFO - Copying logs to upload dir... 02:35:58 INFO - mkdir: /builds/slave/test/build/upload/logs 02:35:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.369500 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-07-18 02:35:58.158515) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 02:35:58.162431) ========= 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.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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/bcf2d0698d842af30e9fab378090f29077606b5cda6696dc3e1a40102b94e789ac2e38e326d4a7ea30c153c70ae37335601face890efc10b003a8402f0732f67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728138df00a52082dc4348d8492c437148df1581a0d2e2365cd206f6dce8741be31202e997bd1d9e3063d2de14e081dd30c08847b0463141cb89243a97679961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2ae0494b3f87159bb6e1b88046e25ebe0a29719600f96fd57be03263b9f5acea39e8e252d08f465c0a6d34786086891395d3e5217017307a5d41b41b6c1ad5"} build_url:https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010723 build_url: 'https://queue.taskcluster.net/v1/task/b7weYYveTQ25TYjtnmpKoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcf2d0698d842af30e9fab378090f29077606b5cda6696dc3e1a40102b94e789ac2e38e326d4a7ea30c153c70ae37335601face890efc10b003a8402f0732f67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/728138df00a52082dc4348d8492c437148df1581a0d2e2365cd206f6dce8741be31202e997bd1d9e3063d2de14e081dd30c08847b0463141cb89243a97679961", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc2ae0494b3f87159bb6e1b88046e25ebe0a29719600f96fd57be03263b9f5acea39e8e252d08f465c0a6d34786086891395d3e5217017307a5d41b41b6c1ad5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 02:35:58.193761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:35:58.194074) ========= 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.3I8OxCFIk1/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.p17aPuRSDz/Listeners TMPDIR=/var/folders/dd/py29nth97xxbf83ghv9hvnzr00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:35:58.242556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 02:35:58.242845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 02:35:58.243150) ========= ========= Total master_lag: 0.17 =========