builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1457920641.81 results: success (0) buildid: 20160313164627 builduid: a44850837bfc4c7991f0a36f6ae4c580 revision: d05a61ed142b13dad72593a3a3ce860f301df669 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:21.806740) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:21.807178) ========= ========= Started set props: basedir (results: 0, elapsed: 2 secs) (at 2016-03-13 18:57:21.807495) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005865 basedir: '/builds/slave/test' ========= master_lag: 2.86 ========= ========= Finished set props: basedir (results: 0, elapsed: 2 secs) (at 2016-03-13 18:57:24.675956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-03-13 18:57:24.679254) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-03-13 18:57:29.189249) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:29.189650) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005747 ========= master_lag: 0.27 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:29.461522) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:29.461815) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:29.462173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:29.462447) ========= 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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-03-13 18:57:29-- 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% 19.9M=0.001s 2016-03-13 18:57:30 (19.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.530619 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:30.096254) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-03-13 18:57:30.096568) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.020040 ========= master_lag: 3.25 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-03-13 18:57:33.371409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-13 18:57:33.371818) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d05a61ed142b13dad72593a3a3ce860f301df669 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d05a61ed142b13dad72593a3a3ce860f301df669 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-03-13 18:57:33,465 truncating revision to first 12 chars 2016-03-13 18:57:33,465 Setting DEBUG logging. 2016-03-13 18:57:33,466 attempt 1/10 2016-03-13 18:57:33,466 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d05a61ed142b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-13 18:57:34,184 unpacking tar archive at: mozilla-aurora-d05a61ed142b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.962062 ========= master_lag: 1.51 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-13 18:57:35.843674) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:35.844055) ========= echo d05a61ed142b13dad72593a3a3ce860f301df669 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd05a61ed142b13dad72593a3a3ce860f301df669'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 d05a61ed142b13dad72593a3a3ce860f301df669 program finished with exit code 0 elapsedTime=0.005340 script_repo_revision: 'd05a61ed142b13dad72593a3a3ce860f301df669' ========= master_lag: 0.08 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-13 18:57:35.931526) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-03-13 18:57:35.931838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 4 secs) (at 2016-03-13 18:57:40.261467) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-03-13 18:57:40.261888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:57:40 INFO - MultiFileLogger online at 20160313 18:57:40 in /builds/slave/test 18:57:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:57:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:57:40 INFO - {'append_to_log': False, 18:57:40 INFO - 'base_work_dir': '/builds/slave/test', 18:57:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:57:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:57:40 INFO - 'branch': 'Mozilla-Aurora', 18:57:40 INFO - 'buildbot_json_path': 'buildprops.json', 18:57:40 INFO - 'config_files': ('talos/mac_config.py',), 18:57:40 INFO - 'default_actions': ('clobber', 18:57:40 INFO - 'read-buildbot-config', 18:57:40 INFO - 'download-and-extract', 18:57:40 INFO - 'populate-webroot', 18:57:40 INFO - 'create-virtualenv', 18:57:40 INFO - 'install', 18:57:40 INFO - 'run-tests'), 18:57:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:57:40 INFO - 'download_minidump_stackwalk': True, 18:57:40 INFO - 'download_symbols': 'ondemand', 18:57:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:57:40 INFO - 'tooltool.py': '/tools/tooltool.py', 18:57:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:57:40 INFO - '/tools/misc-python/virtualenv.py')}, 18:57:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:57:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:57:40 INFO - 'installer_path': 'installer.exe', 18:57:40 INFO - 'log_level': 'info', 18:57:40 INFO - 'log_name': 'talos', 18:57:40 INFO - 'log_to_console': True, 18:57:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:57:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:57:40 INFO - 'opt_config_files': (), 18:57:40 INFO - 'pip_index': False, 18:57:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:57:40 INFO - 'cmd': ('bash', 18:57:40 INFO - '-c', 18:57:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:57:40 INFO - 'enabled': True, 18:57:40 INFO - 'halt_on_failure': False, 18:57:40 INFO - 'name': 'check_screen_resolution'},), 18:57:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:57:40 INFO - 'cmd': ('bash', 18:57:40 INFO - '-c', 18:57:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:57:40 INFO - 'enabled': True, 18:57:40 INFO - 'halt_on_failure': False, 18:57:40 INFO - 'name': 'check_screen_resolution'},), 18:57:40 INFO - 'run_cmd_checks_enabled': True, 18:57:40 INFO - 'sps_profile': False, 18:57:40 INFO - 'sps_profile_interval': 0, 18:57:40 INFO - 'suite': 'dromaeojs', 18:57:40 INFO - 'system_bits': '32', 18:57:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:57:40 INFO - 'title': 't-yosemite-r7-0173', 18:57:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:57:40 INFO - 'use_talos_json': True, 18:57:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:57:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:57:40 INFO - 'work_dir': 'build'} 18:57:40 INFO - ##### 18:57:40 INFO - ##### Running clobber step. 18:57:40 INFO - ##### 18:57:40 INFO - Running pre-action listener: _resource_record_pre_action 18:57:40 INFO - Running main action method: clobber 18:57:40 INFO - rmtree: /builds/slave/test/build 18:57:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:57:41 INFO - Running post-action listener: _resource_record_post_action 18:57:41 INFO - ##### 18:57:41 INFO - ##### Running read-buildbot-config step. 18:57:41 INFO - ##### 18:57:41 INFO - Running pre-action listener: _resource_record_pre_action 18:57:41 INFO - Running main action method: read_buildbot_config 18:57:41 INFO - Using buildbot properties: 18:57:41 INFO - { 18:57:41 INFO - "project": "", 18:57:41 INFO - "product": "firefox", 18:57:41 INFO - "script_repo_revision": "production", 18:57:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:57:41 INFO - "repository": "", 18:57:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:57:41 INFO - "buildid": "20160313164627", 18:57:41 INFO - "pgo_build": "False", 18:57:41 INFO - "basedir": "/builds/slave/test", 18:57:41 INFO - "buildnumber": 124, 18:57:41 INFO - "slavename": "t-yosemite-r7-0173", 18:57:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:57:41 INFO - "platform": "yosemite_r7", 18:57:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:57:41 INFO - "branch": "mozilla-aurora", 18:57:41 INFO - "repo_path": "releases/mozilla-aurora", 18:57:41 INFO - "revision": "d05a61ed142b13dad72593a3a3ce860f301df669", 18:57:41 INFO - "stage_platform": "macosx64", 18:57:41 INFO - "builduid": "a44850837bfc4c7991f0a36f6ae4c580", 18:57:41 INFO - "slavebuilddir": "test" 18:57:41 INFO - } 18:57:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 18:57:41 INFO - Running post-action listener: _resource_record_post_action 18:57:41 INFO - ##### 18:57:41 INFO - ##### Running download-and-extract step. 18:57:41 INFO - ##### 18:57:41 INFO - Running pre-action listener: _resource_record_pre_action 18:57:41 INFO - Running main action method: download_and_extract 18:57:41 INFO - mkdir: /builds/slave/test/build/tests 18:57:41 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')]} 18:57:41 INFO - https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:57:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 18:57:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 18:57:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 18:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 18:57:43 INFO - Downloaded 1277 bytes. 18:57:43 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 18:57:43 INFO - Using the following test package requirements: 18:57:43 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 18:57:43 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 18:57:43 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 18:57:43 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'jsshell-mac.zip'], 18:57:43 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 18:57:43 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 18:57:43 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 18:57:43 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 18:57:43 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 18:57:43 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 18:57:43 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 18:57:43 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 18:57:43 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:57:43 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')]} 18:57:43 INFO - https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:57:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:57:44 INFO - Downloaded 17865031 bytes. 18:57:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:57:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:57:47 INFO - Return code: 0 18:57:47 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:57:47 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')]} 18:57:47 INFO - https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:57:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 18:57:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:57:48 INFO - Downloaded 17865031 bytes. 18:57:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:57:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:57:52 INFO - Return code: 0 18:57:52 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')]} 18:57:52 INFO - https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:57:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 18:57:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 18:57:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 18:57:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:57:53 INFO - Downloaded 10845161 bytes. 18:57:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:57:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:57:53 INFO - Return code: 0 18:57: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')]} 18:57:53 INFO - https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:57:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 18:57:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 18:57:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 18:57:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 18:57:56 INFO - Downloaded 115006803 bytes. 18:57:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 18:57:56 INFO - mkdir: /builds/slave/test/properties 18:57:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:57:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:57:56 INFO - Contents: 18:57:56 INFO - build_url:https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 18:57:57 INFO - Running post-action listener: _resource_record_post_action 18:57:57 INFO - Running post-action listener: set_extra_try_arguments 18:57:57 INFO - ##### 18:57:57 INFO - ##### Running populate-webroot step. 18:57:57 INFO - ##### 18:57:57 INFO - Running pre-action listener: _resource_record_pre_action 18:57:57 INFO - Running main action method: populate_webroot 18:57:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:57:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:57:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:57:57 INFO - '--tppagecycles', 18:57:57 INFO - '7'], 18:57:57 INFO - 'tests': ['tsvgm']}}, 18:57:57 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:57:57 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:57:57 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 18:57:57 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:57:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:57:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:57:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'tests': ['damp', 'tps']}, 18:57:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['damp', 'tps']}, 18:57:57 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:57:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:57:57 INFO - 'other': {'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'other_l64': {'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'other_nol64': {'tests': ['a11yr', 18:57:57 INFO - 'ts_paint', 18:57:57 INFO - 'tpaint', 18:57:57 INFO - 'sessionrestore', 18:57:57 INFO - 'sessionrestore_no_auto_restore', 18:57:57 INFO - 'tabpaint']}, 18:57:57 INFO - 'svgr': {'tests': ['tsvgx', 18:57:57 INFO - 'tsvgr_opacity', 18:57:57 INFO - 'tart', 18:57:57 INFO - 'tscrollx', 18:57:57 INFO - 'cart']}, 18:57:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['tsvgx', 18:57:57 INFO - 'tsvgr_opacity', 18:57:57 INFO - 'tart', 18:57:57 INFO - 'tscrollx', 18:57:57 INFO - 'cart']}, 18:57:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'tests': ['tp5o']}, 18:57:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'talos_options': ['--e10s'], 18:57:57 INFO - 'tests': ['tp5o']}, 18:57:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'talos_options': ['--xperf_path', 18:57:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:57:57 INFO - 'tests': ['tp5n']}, 18:57:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:57 INFO - 'talos_options': ['--e10s', 18:57:57 INFO - '--xperf_path', 18:57:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:57:57 INFO - 'tests': ['tp5n']}}, 18:57:57 INFO - 'talos.zip': {'path': '', 18:57:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:57:57 INFO - Running post-action listener: _resource_record_post_action 18:57:57 INFO - ##### 18:57:57 INFO - ##### Running create-virtualenv step. 18:57:57 INFO - ##### 18:57:57 INFO - Running pre-action listener: _resource_record_pre_action 18:57:57 INFO - Running main action method: create_virtualenv 18:57:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:57:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:57:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:57:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:57:57 INFO - Using real prefix '/tools/python27' 18:57:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:57:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:58:00 INFO - Installing pip.................done. 18:58:00 INFO - Return code: 0 18:58:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:58:00 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')]} 18:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:00 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')]} 18:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:00 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:58:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:00 INFO - 'HOME': '/Users/cltbld', 18:58:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:00 INFO - 'LOGNAME': 'cltbld', 18:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:00 INFO - 'MOZ_NO_REMOTE': '1', 18:58:00 INFO - 'NO_EM_RESTART': '1', 18:58:00 INFO - 'PAGER': '/bin/cat', 18:58:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:00 INFO - 'PWD': '/builds/slave/test', 18:58:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:00 INFO - 'SHELL': '/bin/bash', 18:58:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:00 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:00 INFO - 'USER': 'cltbld', 18:58:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:00 INFO - 'XPC_FLAGS': '0x0', 18:58:00 INFO - 'XPC_SERVICE_NAME': '0', 18:58:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:58:01 INFO - Cleaning up... 18:58:01 INFO - Return code: 0 18:58:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:58:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:01 INFO - 'HOME': '/Users/cltbld', 18:58:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:01 INFO - 'LOGNAME': 'cltbld', 18:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:01 INFO - 'MOZ_NO_REMOTE': '1', 18:58:01 INFO - 'NO_EM_RESTART': '1', 18:58:01 INFO - 'PAGER': '/bin/cat', 18:58:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:01 INFO - 'PWD': '/builds/slave/test', 18:58:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:01 INFO - 'SHELL': '/bin/bash', 18:58:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:01 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:01 INFO - 'USER': 'cltbld', 18:58:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:01 INFO - 'XPC_FLAGS': '0x0', 18:58:01 INFO - 'XPC_SERVICE_NAME': '0', 18:58:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:01 INFO - Downloading/unpacking psutil>=0.7.1 18:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:58:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:58:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:58:04 INFO - Installing collected packages: psutil 18:58:04 INFO - Running setup.py install for psutil 18:58:04 INFO - building 'psutil._psutil_osx' extension 18:58:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:58:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:58:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:58:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:58:04 INFO - building 'psutil._psutil_posix' extension 18:58:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:58:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:58:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:58:04 INFO - ^ 18:58:04 INFO - 1 warning generated. 18:58:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:58:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:58:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:58:05 INFO - Successfully installed psutil 18:58:05 INFO - Cleaning up... 18:58:05 INFO - Return code: 0 18:58:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:58: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')]} 18:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58: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')]} 18:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:05 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:05 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.0.0'] in /builds/slave/test/build 18:58:05 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.0.0 18:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:05 INFO - 'HOME': '/Users/cltbld', 18:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:05 INFO - 'LOGNAME': 'cltbld', 18:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:05 INFO - 'MOZ_NO_REMOTE': '1', 18:58:05 INFO - 'NO_EM_RESTART': '1', 18:58:05 INFO - 'PAGER': '/bin/cat', 18:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:05 INFO - 'PWD': '/builds/slave/test', 18:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:05 INFO - 'SHELL': '/bin/bash', 18:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:05 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:05 INFO - 'USER': 'cltbld', 18:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:05 INFO - 'XPC_FLAGS': '0x0', 18:58:05 INFO - 'XPC_SERVICE_NAME': '0', 18:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:58:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:58:07 INFO - Installing collected packages: mozsystemmonitor 18:58:07 INFO - Running setup.py install for mozsystemmonitor 18:58:07 INFO - Successfully installed mozsystemmonitor 18:58:07 INFO - Cleaning up... 18:58:08 INFO - Return code: 0 18:58:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:08 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:58:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:08 INFO - 'HOME': '/Users/cltbld', 18:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:08 INFO - 'LOGNAME': 'cltbld', 18:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:08 INFO - 'MOZ_NO_REMOTE': '1', 18:58:08 INFO - 'NO_EM_RESTART': '1', 18:58:08 INFO - 'PAGER': '/bin/cat', 18:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:08 INFO - 'PWD': '/builds/slave/test', 18:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:08 INFO - 'SHELL': '/bin/bash', 18:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:08 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:08 INFO - 'USER': 'cltbld', 18:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:08 INFO - 'XPC_FLAGS': '0x0', 18:58:08 INFO - 'XPC_SERVICE_NAME': '0', 18:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:58:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:58:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:11 INFO - Downloading docopt-0.6.1.tar.gz 18:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:58:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:58:11 INFO - Running setup.py install for blobuploader 18:58:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:58:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:58:11 INFO - Running setup.py install for requests 18:58:12 INFO - Running setup.py install for docopt 18:58:12 INFO - Successfully installed blobuploader requests docopt 18:58:12 INFO - Cleaning up... 18:58:12 INFO - Return code: 0 18:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:12 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:12 INFO - 'HOME': '/Users/cltbld', 18:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:12 INFO - 'LOGNAME': 'cltbld', 18:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:12 INFO - 'MOZ_NO_REMOTE': '1', 18:58:12 INFO - 'NO_EM_RESTART': '1', 18:58:12 INFO - 'PAGER': '/bin/cat', 18:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:12 INFO - 'PWD': '/builds/slave/test', 18:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:12 INFO - 'SHELL': '/bin/bash', 18:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:12 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:12 INFO - 'USER': 'cltbld', 18:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:12 INFO - 'XPC_FLAGS': '0x0', 18:58:12 INFO - 'XPC_SERVICE_NAME': '0', 18:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:58:12 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XzDqie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-sHQGBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-r9zaw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-OW2e3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-dYulZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-nydfcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-L0SfXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-j5xXnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QhtgE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:58:13 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-m8u9u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-fp_v57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8H9NuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oHJbV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5qL54c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-dUBWRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-HdRiJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:58:14 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-97GQCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:58:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:58:14 INFO - Running setup.py install for manifestparser 18:58:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:58:14 INFO - Running setup.py install for mozcrash 18:58:15 INFO - Running setup.py install for mozdebug 18:58:15 INFO - Running setup.py install for mozdevice 18:58:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Running setup.py install for mozfile 18:58:15 INFO - Running setup.py install for mozhttpd 18:58:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Running setup.py install for mozinfo 18:58:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Running setup.py install for mozInstall 18:58:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:58:15 INFO - Running setup.py install for mozleak 18:58:16 INFO - Running setup.py install for mozlog 18:58:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Running setup.py install for moznetwork 18:58:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Running setup.py install for mozprocess 18:58:16 INFO - Running setup.py install for mozprofile 18:58:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Running setup.py install for mozrunner 18:58:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:58:16 INFO - Running setup.py install for mozscreenshot 18:58:17 INFO - Running setup.py install for moztest 18:58:17 INFO - Running setup.py install for mozversion 18:58:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:58:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:58:17 INFO - Cleaning up... 18:58:17 INFO - Return code: 0 18:58:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:58:17 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')]} 18:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:17 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')]} 18:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:17 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:17 INFO - 'HOME': '/Users/cltbld', 18:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:17 INFO - 'LOGNAME': 'cltbld', 18:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:17 INFO - 'MOZ_NO_REMOTE': '1', 18:58:17 INFO - 'NO_EM_RESTART': '1', 18:58:17 INFO - 'PAGER': '/bin/cat', 18:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:17 INFO - 'PWD': '/builds/slave/test', 18:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:17 INFO - 'SHELL': '/bin/bash', 18:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:17 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:17 INFO - 'USER': 'cltbld', 18:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:17 INFO - 'XPC_FLAGS': '0x0', 18:58:17 INFO - 'XPC_SERVICE_NAME': '0', 18:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:58:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-vQlqz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:58:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-v1IMoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:58:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ne4sya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:58:17 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-j4tiOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-wRh8J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-YEf3EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9ADpUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-tHRU67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-lI8PBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Xubugf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-E_65eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-q1fFoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:58:18 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ndrB2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:58:19 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BU3tXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:58:19 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5NecNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:58:19 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zD5C5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:58:19 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-mm6y22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:21 INFO - Downloading blessings-1.6.tar.gz 18:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:58:21 INFO - Installing collected packages: blessings 18:58:21 INFO - Running setup.py install for blessings 18:58:22 INFO - Successfully installed blessings 18:58:22 INFO - Cleaning up... 18:58:22 INFO - Return code: 0 18:58:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:58:22 INFO - Reading from file tmpfile_stdout 18:58:22 INFO - Current package versions: 18:58:22 INFO - blessings == 1.6 18:58:22 INFO - blobuploader == 1.2.4 18:58:22 INFO - docopt == 0.6.1 18:58:22 INFO - manifestparser == 1.1 18:58:22 INFO - mozInstall == 1.12 18:58:22 INFO - mozcrash == 0.17 18:58:22 INFO - mozdebug == 0.1 18:58:22 INFO - mozdevice == 0.48 18:58:22 INFO - mozfile == 1.2 18:58:22 INFO - mozhttpd == 0.7 18:58:22 INFO - mozinfo == 0.9 18:58:22 INFO - mozleak == 0.1 18:58:22 INFO - mozlog == 3.1 18:58:22 INFO - moznetwork == 0.27 18:58:22 INFO - mozprocess == 0.22 18:58:22 INFO - mozprofile == 0.28 18:58:22 INFO - mozrunner == 6.11 18:58:22 INFO - mozscreenshot == 0.1 18:58:22 INFO - mozsystemmonitor == 0.0 18:58:22 INFO - moztest == 0.7 18:58:22 INFO - mozversion == 1.4 18:58:22 INFO - psutil == 3.1.1 18:58:22 INFO - requests == 1.2.3 18:58:22 INFO - wsgiref == 0.1.2 18:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:58:22 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')]} 18:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:22 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')]} 18:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:22 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:22 INFO - 'HOME': '/Users/cltbld', 18:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:22 INFO - 'LOGNAME': 'cltbld', 18:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:22 INFO - 'MOZ_NO_REMOTE': '1', 18:58:22 INFO - 'NO_EM_RESTART': '1', 18:58:22 INFO - 'PAGER': '/bin/cat', 18:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:22 INFO - 'PWD': '/builds/slave/test', 18:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:22 INFO - 'SHELL': '/bin/bash', 18:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:22 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:22 INFO - 'USER': 'cltbld', 18:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:22 INFO - 'XPC_FLAGS': '0x0', 18:58:22 INFO - 'XPC_SERVICE_NAME': '0', 18:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:58:22 INFO - Cleaning up... 18:58:22 INFO - Return code: 0 18:58:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:58:22 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')]} 18:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:22 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')]} 18:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:22 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 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:22 INFO - 'HOME': '/Users/cltbld', 18:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:22 INFO - 'LOGNAME': 'cltbld', 18:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:22 INFO - 'MOZ_NO_REMOTE': '1', 18:58:22 INFO - 'NO_EM_RESTART': '1', 18:58:22 INFO - 'PAGER': '/bin/cat', 18:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:22 INFO - 'PWD': '/builds/slave/test', 18:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:22 INFO - 'SHELL': '/bin/bash', 18:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:22 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:22 INFO - 'USER': 'cltbld', 18:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:22 INFO - 'XPC_FLAGS': '0x0', 18:58:22 INFO - 'XPC_SERVICE_NAME': '0', 18:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:22 INFO - Downloading/unpacking jsonschema 18:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:58:25 INFO - Downloading/unpacking functools32 (from jsonschema) 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:58:25 INFO - warning: no files found matching '*.txt' 18:58:25 INFO - no previously-included directories found matching 'build' 18:58:25 INFO - no previously-included directories found matching 'dist' 18:58:25 INFO - no previously-included directories found matching '.git*' 18:58:25 INFO - Installing collected packages: jsonschema, functools32 18:58:25 INFO - Running setup.py install for functools32 18:58:25 INFO - warning: no files found matching '*.txt' 18:58:25 INFO - no previously-included directories found matching 'build' 18:58:25 INFO - no previously-included directories found matching 'dist' 18:58:25 INFO - no previously-included directories found matching '.git*' 18:58:25 INFO - Successfully installed jsonschema functools32 18:58:25 INFO - Cleaning up... 18:58:26 INFO - Return code: 0 18:58:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:58:26 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')]} 18:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:26 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')]} 18:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:26 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11019ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101a34c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8935715c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, '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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:58:26 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', 'flake8'] in /builds/slave/test/build 18:58:26 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 flake8 18:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:58:26 INFO - 'HOME': '/Users/cltbld', 18:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:58:26 INFO - 'LOGNAME': 'cltbld', 18:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:26 INFO - 'MOZ_NO_REMOTE': '1', 18:58:26 INFO - 'NO_EM_RESTART': '1', 18:58:26 INFO - 'PAGER': '/bin/cat', 18:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:58:26 INFO - 'PWD': '/builds/slave/test', 18:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:58:26 INFO - 'SHELL': '/bin/bash', 18:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:58:26 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:58:26 INFO - 'USER': 'cltbld', 18:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:26 INFO - 'XPC_FLAGS': '0x0', 18:58:26 INFO - 'XPC_SERVICE_NAME': '0', 18:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:26 INFO - Downloading/unpacking flake8 18:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:58:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:58:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58: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 18:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:58:29 INFO - no previously-included directories found matching 'docs/_build' 18:58:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:58:29 INFO - Running setup.py install for pep8 18:58:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:58:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:58:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:58:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:58:30 INFO - no previously-included directories found matching 'docs/_build' 18:58:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:58:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:58:30 INFO - Cleaning up... 18:58:30 INFO - Return code: 0 18:58:30 INFO - Running post-action listener: _resource_record_post_action 18:58:30 INFO - Running post-action listener: _start_resource_monitoring 18:58:30 INFO - Starting resource monitoring. 18:58:30 INFO - ##### 18:58:30 INFO - ##### Running install step. 18:58:30 INFO - ##### 18:58:30 INFO - Running pre-action listener: _resource_record_pre_action 18:58:30 INFO - Running main action method: install 18:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:58:30 INFO - Reading from file tmpfile_stdout 18:58:30 INFO - Detecting whether we're running mozinstall >=1.0... 18:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:58:30 INFO - Reading from file tmpfile_stdout 18:58:30 INFO - Output received: 18:58:30 INFO - Usage: mozinstall [options] installer 18:58:30 INFO - Options: 18:58:30 INFO - -h, --help show this help message and exit 18:58:30 INFO - -d DEST, --destination=DEST 18:58:30 INFO - Directory to install application into. [default: 18:58:30 INFO - "/builds/slave/test"] 18:58:30 INFO - --app=APP Application being installed. [default: firefox] 18:58:30 INFO - mkdir: /builds/slave/test/build/application 18:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:59:02 INFO - Reading from file tmpfile_stdout 18:59:02 INFO - Output received: 18:59:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:59:02 INFO - Running post-action listener: _resource_record_post_action 18:59:02 INFO - ##### 18:59:02 INFO - ##### Running run-tests step. 18:59:02 INFO - ##### 18:59:02 INFO - Running pre-action listener: _resource_record_pre_action 18:59:02 INFO - Running main action method: run_tests 18:59:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:59:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:59:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:59:02 INFO - 2016-03-13 18:59:02.269 screenresolution[2297:19406] starting screenresolution argv=screenresolution get 18:59:02 INFO - 2016-03-13 18:59:02.272 screenresolution[2297:19406] Display 0: 1600x1200x32@60 18:59:02 INFO - 2016-03-13 18:59:02.278 screenresolution[2298:19408] starting screenresolution argv=screenresolution list 18:59:02 INFO - Available Modes on Display 0 18:59:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:59:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:59:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:59:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:59:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:59:02 INFO - Intel Iris: 18:59:02 INFO - Chipset Model: Intel Iris 18:59:02 INFO - Type: GPU 18:59:02 INFO - Bus: Built-In 18:59:02 INFO - VRAM (Dynamic, Max): 1536 MB 18:59:02 INFO - Vendor: Intel (0x8086) 18:59:02 INFO - Device ID: 0x0a2e 18:59:02 INFO - Revision ID: 0x0009 18:59:02 INFO - Displays: 18:59:02 INFO - Display: 18:59:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:59:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:59:02 INFO - Display Serial Number: 1600x1200 60 18:59:02 INFO - Main Display: Yes 18:59:02 INFO - Mirror: Off 18:59:02 INFO - Online: Yes 18:59:02 INFO - Rotation: Supported 18:59:02 INFO - Return code: 0 18:59:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:59:02 INFO - Python 2.7.3 18:59:02 INFO - Return code: 0 18:59:02 INFO - grabbing minidump binary from tooltool 18:59:02 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')]} 18:59:02 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110040d30>, '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 0x10fea4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110037b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:59:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:59:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:59:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:59:02 INFO - Return code: 0 18:59:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:59:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:59:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:59:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:59:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:59:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:59:03 INFO - Return code: 0 18:59:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 18:59:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 18:59:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n0xIVjTjE9/Render', 18:59:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:59:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:59:03 INFO - 'HOME': '/Users/cltbld', 18:59:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:59:03 INFO - 'LOGNAME': 'cltbld', 18:59:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:59:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:03 INFO - 'MOZ_NO_REMOTE': '1', 18:59:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:59:03 INFO - 'NO_EM_RESTART': '1', 18:59:03 INFO - 'PAGER': '/bin/cat', 18:59:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:03 INFO - 'PWD': '/builds/slave/test', 18:59:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:59:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:59:03 INFO - 'SHELL': '/bin/bash', 18:59:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.isIZyp8SaZ/Listeners', 18:59:03 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:59:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:59:03 INFO - 'USER': 'cltbld', 18:59:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:59:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:03 INFO - 'XPC_FLAGS': '0x0', 18:59:03 INFO - 'XPC_SERVICE_NAME': '0', 18:59:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:59:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 18:59:04 INFO - mozversion application_buildid: 20160313164627 18:59:04 INFO - mozversion application_changeset: d05a61ed142b13dad72593a3a3ce860f301df669 18:59:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:59:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:59:04 INFO - mozversion application_name: Firefox 18:59:04 INFO - mozversion application_remotingname: firefox-dev 18:59:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:59:04 INFO - mozversion application_vendor: Mozilla 18:59:04 INFO - mozversion application_version: 47.0a2 18:59:04 INFO - mozversion platform_buildid: 20160313164627 18:59:04 INFO - mozversion platform_changeset: d05a61ed142b13dad72593a3a3ce860f301df669 18:59:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:59:04 INFO - mozversion platform_version: 47.0a2 18:59:04 INFO - using testdate: 1457920744 18:59:04 INFO - actual date: 1457920744 18:59:04 INFO - starting webserver on 'localhost:50174' 18:59:04 INFO - SUITE-START | Running 2 tests 18:59:04 INFO - TEST-START | dromaeo_css 18:59:04 INFO - operating with platform_type : mac_ 18:59:04 INFO - Initialising browser for dromaeo_css test... 18:59:04 INFO - TEST-INFO | started process 2316 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp9Xklgu/profile http://localhost:50174/getInfo.html) 18:59:06 INFO - PROCESS | 2316 | __metrics Screen width/height:1600/1200 18:59:06 INFO - PROCESS | 2316 | colorDepth:24 18:59:06 INFO - PROCESS | 2316 | Browser inner width/height: 1024/689 18:59:06 INFO - PROCESS | 2316 | __metrics 18:59:06 INFO - PROCESS | 2316 | 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" 18:59:06 INFO - PROCESS | 2316 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:59:06 INFO - TEST-INFO | 2316: exit 0 18:59:06 INFO - Browser initialized. 18:59:06 INFO - Running cycle 1/1 for dromaeo_css test... 18:59:06 INFO - TEST-INFO | started process 2318 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp9Xklgu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:59:18 INFO - PROCESS | 2318 | RSS: Main: 210829312 18:59:18 INFO - PROCESS | 2318 | 19:00:09 INFO - PROCESS | 2318 | 1457920809899 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] 19:02:20 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50174/tests/dromaeo/cssquery-ext.html) 19:02:21 INFO - PROCESS | 2318 | RSS: Main: 321163264 19:02:21 INFO - PROCESS | 2318 | 19:05:23 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-ext.html (next: http://localhost:50174/tests/dromaeo/cssquery-jquery.html) 19:05:24 INFO - PROCESS | 2318 | RSS: Main: 342310912 19:05:24 INFO - PROCESS | 2318 | 19:08:26 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50174/tests/dromaeo/cssquery-mootools.html) 19:08:26 INFO - PROCESS | 2318 | RSS: Main: 347111424 19:08:26 INFO - PROCESS | 2318 | 19:11:28 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50174/tests/dromaeo/cssquery-prototype.html) 19:11:29 INFO - PROCESS | 2318 | RSS: Main: 302432256 19:11:29 INFO - PROCESS | 2318 | 19:14:32 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50174/tests/dromaeo/cssquery-yui.html) 19:14:33 INFO - PROCESS | 2318 | RSS: Main: 306409472 19:14:33 INFO - PROCESS | 2318 | 19:17:35 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:50174/tests/dromaeo/cssquery-yui.html (next: http://localhost:50174/tests/dromaeo/cssquery-dojo.html) 19:17:36 INFO - PROCESS | 2318 | RSS: Main: 349003776 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | __start_tp_report 19:17:36 INFO - PROCESS | 2318 | _x_x_mozilla_page_load 19:17:36 INFO - PROCESS | 2318 | _x_x_mozilla_page_load_details 19:17:36 INFO - PROCESS | 2318 | |i|pagename|runs| 19:17:36 INFO - PROCESS | 2318 | |0;dojo.html;2719.2657982344767;3487.3548393547194;3511.17487390463;3529.0647978285742;3562.25192709531;5709.942900570993;5712.714364281785;5748.137779333102;5753.338366087901;5766.682832444214;5713;5776.797812076578;5779.595428320019;5783.942160578393;5790.334111577159;5094.490550944913;5172.586193104561;5188.37739471263;5274.55166310864;5293.311869456985;5262.710550919698;5263.394709608387;5273.235380869772;5289.550388216987;5304.3634763828395;5209.426963034063;5260.342457192851;5261.079311120558;5276.103062479368;5298.708571028592;5279.445658205891;5347.545458636001;5350.598705097094;5363.436839131874;5372.91940620891;5057.418396884369;5239.842804715865;5244.292020577212;5255.369355677305;5270.631055826094;5530.142827861688;5532.834014979517;5567.47109024642;5596.832095037155;5607.271054762855;6083.513318934474;6238.625682459022;6273.1844860167885;6276.435120839101;6296.496280297611;6188.040853667689;6196.225471816023;6234.750609975641;6248.468880145193;6259.217597800275;5845.7369418376275;5846.649201047908;5898.616589921728;5911.645301281937;5921;5482.616216864861;5520.895820835849;5990.28116625999;6388.840278993062;6413.518986076063;6117.248930028882;6215.098810672427;6225.315215326311;6230.626162430178;6256.843578910563;6242.157308763259;6243.843903902439;6248.843778905563;6257.155284036597;6259.499240060784;6044.395560443921;6071.848203794852;6097.75608975636;6173.444390004921;6179.258488981351;5242.9213561796605;5969.970150149222;6156.415140561639;6242.687865606792;6280.340564240781;4921.212605983025;4922.360093187863;4923.286123512143;4924.064427758714;4963;4354;4440.777961101932;4440.888977775581;4455.443069616298;4506.098780243899;5743.569232307594;5813.011787996051;5829.446202610745;5836;5870.471657550841;6156.29202641693;6168.691565421711;6178.165947597017;6178.567500274938;6178.721957511832;4697.224957881911;5714.628549144292;5724.971375143181;5740.225069615549;5760.251167348301;5340;5410.026195284808;5415.187721841677;5432.375276843213;5478.150886612581;6455.225372955276;6455.709493072917;6474.449671777952;6481.611103333815;6485.77299794505;6119.380693981147;6170.629762214281;6218.906716399167;6222.160008398808;6235.844103897439;5399.973000134974;6494.6103233806125;6529.934700652932;6542.313057128933;6550.410463058348;6157.26112866441;6180.536459765625;6190.2571691395515;6197.566170368031;6205.069239614094;6138.815835524942;6144.2319710036245;6145.6927153641245;6173.81478555644;6226.159468471698;6076.859660101179;6149.815505534841;6157.1072194532435;6163.229596300463;6168.1056246844855;6091.42131497507;6144.262688477412;6147.84630384244;6167.84580385494;6244.812655620339;4746.003339298786;5078.314427552233;5889.469947704556;6036.63780173191;6113.816585502442;6110.511159107172;6125.234345706786;6129.724162412613;6136.018237082224;6139.4781443577795;5960.105984102419;5999.580029398075;6012.579119461596;6016.42843929826;6018.578699491169 19:17:36 INFO - PROCESS | 2318 | |1;ext.html;498.88250319284765;589.5872888977717;594.9202198010613;663.7743167323109;666.9866602667947;32798;33373.66626333739;41134.58865411345;41502.37746433798;41548.376774348406;43815.56184438155;44188.77905610467;44367;44392.55607443924;44434.777826110825;9739.951300243489;9891.208703303755;9902.158316543102;9902.306838521306;9905.108540231378;9683.515824208796;9817.46003969785;9829.262805289596;9836.606535738561;9837.262205334593;9920.305578609501;9971.451570163637;9990.400575965428;10002.499875006257;10003.049710277512;9888.40669559825;9969.950150249275;9980.101790838788;9989.75025624358;9991.450470224136;9662.710118696381;9773.902260977371;9795.804083918281;9817.116459518678;9829.213662907021;43011.35482967758;43308;43316;43415.782921085505;43429;3243.8702451901895;3405.165734395064;3762.3415902217275;3858.4405261237243;3913.0413048803225;29662.258443539085;29883.701162988527;29911.70088299133;30003.94986175495;30014.699853001628;2761.5465056920875;2773.736495032969;2826.321682796114;2860.099068793344;2866.3980564081567;487252;487790;488744.99999999645;488824;489486.5525672349;71630;71717.6414117926;72038.9194162088;72076.63961680158;72174.63912680403;76121.61939190268;76889;80082.19917800864;81117.59441202757;81605;18823.152958116916;18844.34044808425;19030.90484547596;19051.1426985786;19067.80932190688;14348.928255358865;14422.783658245135;14441.06133101345;14442.927785361217;14472.493462728755;18888.527786805163;23087;23175.7682423177;23461.296161114853;23494.88252558726;9505.144536991704;9726.951365243129;9736.853947190797;9742.902570974342;9764.267679923893;980.4705459052175;1028.2545154762738;1105.6240878101323;1145.374011344369;1202.0143482344395;30411.695883041328;31976;33537.496937545955;34212.82893585516;35635.643643563235;1020.2399212586671;1098.0008192544747;1115.8995690387903;1137.971550711239;1152.8213126965334;15474.381024759108;15900.602484937968;16081.758773618405;16103.436379726887;16257.722243551652;654157;671185;674332;675420;675889;64505.70988580202;65037.67481162564;65045.674771626786;65122.37194070186;65208.673956629915;1359.5377571625695;1531.5175719648275;1569.089927841849;1587.5396135120686;1613.0886049382295;31145;31776.523352149274;32123.238595653063;32163.839180804884;33267.50098748569;52820;53428;53556;53615;53630.73184634052;364266;365849;367285;369979;370289;36385.90842274736;47127.76436117797;47571.524284756706;49459;49475;47883;48016;48327;49100.75449622729;49919.75040124776;30661;31125.53311700281;38238.42642360422;39871;40668.59331406648;38841.2231755369;39132.80433597814;39506.46909432739;39622.80188599038;39877.401838971855;38919.805400972815;39402.21195576015;39742.60257397389;39863.40204896987;40377;33975.32049359049;33976.3204735899;34681.9595412138;34852.825735871156;35000.47499287462;22182.66725999079;26336.8683156583;26359.472810544066;26485.470290594465;26608.60087098734 19:17:36 INFO - PROCESS | 2318 | |2;jquery.html;382.3633649972795;387.8080350226637;388.0550858659165;396.58358723340484;396.84919730502406;16682.83317166828;16832.495025149234;19367.22531098756;21154.89422552885;21304.57390852182;20394.490137746525;20945.895270523662;20951.476213094644;21133.788662113373;21477.818719970397;4898.853034408973;5196.610254230929;5773.711314434283;5815.418458154193;5826.766929322822;4295.570442955695;5696.800611978581;5794.942050579482;6096.664683442409;6386.87226255477;6302;6316.810495685137;6319.968400158015;6322.525810564203;6333.55665103443;6120.69396530174;6291.213598300213;6373.820843143987;6377.23473183221;6384.808455746358;5534.4742249486235;5552.333719953632;5560.388357280696;5565.1095824667855;5572.275604171432;25536.872315638302;26298.342541436425;26754;26765.732342676714;26767.866160669266;1866.5893503429224;2250.7861753133425;2304.7580004099495;2884.927876803076;2899.4780939430893;7303.634818259119;10130.493475326279;10180.490975451272;10275.845862312071;10428.947855260674;1744;1754.9122543872884;1767.6199617082389;1770.7786526684165;1783.7235228539598;102341.4882925588;106559;108353;109890.45054774675;113058.30412543737;11589.884101159048;11591.304521728724;11604;11625.069994400428;11771.764564708828;12391.93804030992;12730.490780368867;13279.66800829987;14288.714225715428;14334.211618360881;7227.819304517429;8693.174148455886;8958.552072396355;9077.09229077087;9101.590428430736;9256.352055356196;9262.675806346746;9274.21169200612;9303.534823258946;9333.579988900516;4550.340200670884;4623.214053610895;4626.249801618775;4672.2057250267535;4675.18184317743;1920.3470819921295;1976.0811222781476;1976.723258743777;1980.2969945669367;1980.920763169486;815.0016230118176;983.9914088059653;1048.926575139733;1104.1939384249542;1113.0539041814554;16666.08718975222;20604.587908241752;21215.78784212169;21245.362639120853;21331.253406130712;1000.8748906386702;1006.4917216805467;1010.2625083688948;1048.109107258824;1081.734974931147;9199.636606866416;11041.944790275997;11194.944025279985;11415.60045398407;11443.485043173077;507698;683002;686996;695069;698951;119993;123614;124612;125909.11136332962;126896;1056.815057364958;1097.7694684116266;1120.2046546951756;1123.9887601123885;1126;18212.18045187943;18519.814801851808;18682.813171868107;19596;19651.70522442193;50746;53421.7328913353;53901.19148212702;55986.440135598124;57702;75215.62392188005;76693;77555;77961.61019195095;82530;7841.255080767318;8208.179182081743;8360.66557337724;8419.494830310201;8420.87368689458;9482.668106616235;12773.680657983625;12924.095313328353;13680;13708.93145534266;9297.953510232406;11135.72160695989;11825.231359961575;11901.464434100659;11956.641300760992;8918.286537077149;11263.66209013731;11853.76292474163;11867.821982670093;11875.287482751062;11877.58428455;11947.103967202669;12095.75808483843;12382.81425778632;12420;8271.421000529905;8359.874601881098;8425.62084706202;8464.873026904725;8630.439021463584;9029.322800790098;11127.666170014912;11595.884041159818;11666.591669291873;11837.993770529602 19:17:36 INFO - PROCESS | 2318 | |3;mootools.html;1492.850714928508;1624.1148574027154;1644.2765183319339;1963.8036196380356;2055.2703790154487;8441.155884411555;9100.226480749136;9285.907140928588;9301.581428835701;9344.766380840474;9098.86351704725;9423.57593908274;9426.05739426057;9443.858342124873;9454.054594540543;2578.7034491033583;2615.4769046190745;2616.0582190411437;2617.8560179190135;2618.0836707152534;2498.263012411343;2558.654581631484;2563.679540057493;2566.8203225774205;2576.2528866628654;2553.872306384683;2562.449073449208;2622.881970311331;2626.8686565671737;2627.106783693544;2559.897604095834;2578.5229732499456;2587.4178309880313;2609.3737502999334;2612.58198688209;2540.847549147057;2574.768270855613;2582.3931376126593;2615.751503607173;2618.8166828322023;9205.677801276923;9365.48489833059;9403.200727938134;9503.144716975436;9521;4727.57451829337;5911.615744976606;5914.97042514789;5922.230110085704;5993.430624090704;3665.7433979621437;3710.4063349864164;3779.055236190952;3838.8272527736317;3882.9029274268087;1043.8173319669104;1044.571725592511;1052.7105046112304;1054.46222426563;1104.320842681753;12428.875711242863;12613.180143290656;12630.24218546881;12637;12651.0511711622;4882.829100981448;4898.363212782316;5033.723145226974;5035.244713293036;5042.697438153723;9927.007299270159;9940.403575785476;9942.204623630092;9953.850692239623;9953.900460995443;5661.122526008476;5670.688112153871;5676.744546495335;5684.37471878093;5684.8294551163535;4669.0195059037305;4812.687175333592;5103.464136265713;5238.214267859776;5239.73801309933;3993.900152496211;4014.116894283253;4056.8985775355263;4072.3687828386646;4087.3868919661695;1233.4572787973264;1244.3031902134833;1245.9563915262922;1250.2373552133192;1256.466001949168;979.7599588100961;1026.8511065895552;1063.0273299930477;1268.3594784633701;1430.8354539228121;8566.52884091381;8576.142385761374;8691.652333906699;8695.695650652196;8697.391182617283;659.4856012310405;665.9467242620578;674.7031306225246;675.3516624040976;815.1644564321617;5214.530692237717;6611.438027767597;6632.2704502504685;6638.4689224861895;6644.60132392058;10962.56149754001;11196.936291052338;11240.381779002233;11248.931351521593;11349.659510214708;10771.246012779187;11116.38859862699;11168.944155279172;11198.38408887519;11205.271657342246;8633.352498562634;8763.036066032731;8807.163319484638;8826.5586720665;8840.469571825712;8633.56832158377;8646.351523635627;8688.652453902027;8756;8826.867596986178;9972.750681232737;10211.23415743837;10253.07722304996;10365.689029329133;10378.221633377678;10355.171586272929;10683;10848.02367786903;10861.185411094042;10897;7734.651940662796;8095.595220239083;8106.351491880753;8114;8132.552709600792;8977.10228977097;9018.413803102778;9065.728028159167;9073.818523629623;9077.228435583049;7942.245486678757;8055.7583272501915;8125.837483250184;8188.713395031145;8237.341012718849;7923.524588524707;7997.960010199912;8047.476914000571;8125.8374832504205;8157.836843262983;8067.757967260992;8158.673653054048;8193.67225310981;8219.589020549069;8243.917560824315;7361.337479626859;7382.335589796944;7387.076615423072;7523.849523009619;7524.548527088392;6314.210723659543;7544.509606875536;7564.810879728051;7565.167831538416;7610.9238907610215 19:17:36 INFO - PROCESS | 2318 | |4;prototype.html;338.4212995777221;344.1739824421388;347.3625896479959;349.500214692989;352.5012107867369;5722.6280291781;6246.719422518384;6657.434118099955;6698.430633396167;6712.06031155638;6617.470602351812;6643.833904152399;6649.235337936138;6653.401193892549;6655.667216639161;4716.575508204261;4811.206150985101;4906.828261010865;4937.728424936627;4941.60467162627;4008.318585840414;4888.095702295069;4898.167311557026;4925.3597032385915;4935.210366341386;3956.821943012557;3981.3231750602467;4944.2089265717495;4961.5286547777905;4963.528464795839;4683.742394168319;4775.283707443859;4925.482824303468;4951.876203094916;4963.553280204799;4259.275923093051;4259.680523960685;4267;4273.850415235483;4288.442502474658;6408.93591064088;6416.294207637156;6423.229212494531;6429.35706429358;6455.289918109004;5874.471297583238;5876.55925805562;5885.882282354329;5887.087501437284;5889.882202355929;6714.362135597159;7072.681729322193;7090.759117154477;7092.893606595905;7093.964530177316;4568.771561421949;4582.747948862811;4616.722996620214;4619.937414394674;4625.838095666635;7416.555006699616;7493.475456718032;7495.587742674151;7503.587302698349;7539.962300188518;4709.576138147515;4721.504242054603;4728.078024785203;4741.336212930192;4743.075100355398;4782.402199725035;4795.856124316276;4799.856004319876;4804.06320767447;4818.445878723992;5514.448555144453;5710.229119068956;5714.79998200061;5716.085426331767;5733.741981610754;7112.537685050455;7115.181754098345;7123.786286411416;7147.89278160828;7174.282571742889;1627.4385337058695;1663.3346661335563;1666.4584010196734;1667.4247384652276;1667.90826504541;1014.660088870222;1056.8309070548828;1066.2749330455365;1089.7330154112294;1092.8852470490642;6570.244421891543;6671.299513551007;6851.109355783717;6854.246032936373;6855.280195579492;7285.599292038989;7360.411167106618;7363.705451781976;7365.305979624716;7387.556746595114;6633.436157926534;6644.235912869985;6647.102641143384;6652.8669426611195;6657.46740260778;3751.4185301278344;3801.163743976321;3840.1551658635058;3842.0971071798103;3849.576546579874;6230.408111229426;7684.154742978268;7733.922660773433;7754.263344982218;7764.262395072572;7598.088229412506;7604.12552556452;7606.847863042819;7612.505187162817;7642.121156067013;7694.576798276036;7710.036245469317;7729.5748733819055;7732.381409487115;7733.883991740128;5970.850728731816;7157.329500653726;7339.339459448675;7345.3021962913435;7393.297636724503;7278.235785242473;7329.890051649336;7334.926650733638;7336.193018768038;7357.337839594462;7776.961115194388;7796.376289896682;7799.298063174341;7823.530588164728;7838.137804841349;4658.67389282747;4694.671373003857;4698.130845793403;4708.3643708100335;4721.834735784231;5916.763329466773;7141.928580714126;7355.264473552602;7395.297446742551;7405.481616286808;7127.5723456590695;7180.820479487846;7194.676239569128;7196.928030719626;7198.424126070006;7152.856942861218;7157.24848890859;7160.677769500281;7163.677634506355;7166.068411106523;6960.651967401711;6976.860462790614;6980.546264492792;6980.755673551402;6998.475114366544;6889.586624802328;7133.5719856808755;7147.571145731273;7162.570245785269;7183.173934997542;6659.500537459807;6840.076589660531;6854.828629284308;6965.825854353681;6978.953157026283 19:17:36 INFO - PROCESS | 2318 | |5;yui.html;731.8865575835745;757.8522188173305;761.3832795435695;773.9032620922385;792.4571668407145;15225;15558.144302063381;15566.066036037832;15570.221488925565;15579.065256084643;14349.354279057441;14461.78307325391;14464.204468754215;14475.782863257033;14491;786.8032991752048;788.2984144111745;971.4996776660025;991.5092029445435;992;969.3505351414569;974.741693451236;979.0356498848629;980.127686359141;981.4651015196699;841.5203334099566;1042.3849928542159;1049.5329578337644;1052.389614023868;1053.2785042246048;1019.9949000254989;1033.5142483032962;1036.0209601926183;1036.19694736579;1038.771470276538;756.2475337039681;768.8154842837735;770.5107256891858;771.5640663025373;772.7952092695384;12615.684607884785;15316.770248446282;15395.538133855891;15415.460458883885;15429.151396673178;12797.936010319889;12878;13166.341682915816;13371.799423008662;13591.524296649668;10267.332623379456;11229.989300962952;11352.48913798881;11558.075353971662;11692.240004399771;3461.273132642148;3513.2270900401872;3535.7171426285836;3558.217192217708;3583.3191693578137;86988;87224.1277587216;87935;88729.55635221847;88791.66812497818;9396.684464175021;9462.195713364303;9522.523873806282;9700.175485083846;9728.659496917573;21413.250536231157;21609.243676471247;21653.78346216549;21716;22188.667169992463;7633.885491717629;7644.847103058019;7675.769726908202;7735.265149810759;7765.844683106419;4159.771212583337;4171.83312667496;4183;4237.237297286518;4398.252297109499;3632.418812989909;3778.7732736035928;3854.248421557825;3859.996400935777;3861.498005259298;662.8873091574444;663.5090033375365;667.853072324088;678.6437120511771;699.6781480518916;492.4484577273405;550.3533348315793;551.4457969740386;588.8792797476507;616.7881402151835;7221.89167162493;8336.66653333872;8401.747947561584;8490.235878770816;8519.829603408021;534.767376191358;570.925779648643;612.0788213738296;612.1705089603612;624.1386886097157;3486.599041110254;3948.7038472114705;4010.7794071326357;4094.9181016379507;4097.631213190827;82356;95056;95655.52172239234;97157.02842971621;99396;74055;76632;77322;81493.77759333614;85226.72159917606;77432.22567774363;79319;79993.4001319982;80350.59824700838;80381.59809200917;8675.828698276406;8699.08659590759;8729.65081396737;8787.209151176425;8823.823523529622;51754.482455176476;53483.930321392574;54335.45664543462;54808;55232.72383638056;72464;76841.70003849626;76967.230327696;80329;80884;11743.882561174622;11842.585509507471;11912.10659200546;12195.268283902995;12323.50705971751;12118.878811212127;12203.450844711831;12266.080043996915;12463.940565052073;13006.349682516025;9880.40717556949;11752.177347585928;11859.34773587461;12224.75550489003;12402.937985310016;10074;11279.7744045117;11967.61423541342;12031.338276394883;12218.572349967708;11406.14453915943;11439.199256051996;11746.94126529396;11949.522019119138;12092.818607720716;9214.723558293263;9492.715218543455;9524.52034073716;9606.231501480004;9830.115289623967;9596.616135354509;9853.349187475114;10108.444035578113;10157.238207134347;10238.385696858213 19:17:36 INFO - PROCESS | 2318 | __end_tp_report 19:17:36 INFO - PROCESS | 2318 | __start_cc_report 19:17:36 INFO - PROCESS | 2318 | _x_x_mozilla_cycle_collect,988 19:17:36 INFO - PROCESS | 2318 | __end_cc_report 19:17:36 INFO - PROCESS | 2318 | __startTimestamp1457921856098__endTimestamp 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | ------- Summary: start ------- 19:17:36 INFO - PROCESS | 2318 | Number of tests: 6 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#0] dojo.html Cycles:165 Average:5733.88 Median:5960.11 stddev:651.59 (10.9%) stddev-sans-first:609.17 19:17:36 INFO - PROCESS | 2318 | Values: 2719.3 3487.4 3511.2 3529.1 3562.3 5709.9 5712.7 5748.1 5753.3 5766.7 5713.0 5776.8 5779.6 5783.9 5790.3 5094.5 5172.6 5188.4 5274.6 5293.3 5262.7 5263.4 5273.2 5289.6 5304.4 5209.4 5260.3 5261.1 5276.1 5298.7 5279.4 5347.5 5350.6 5363.4 5372.9 5057.4 5239.8 5244.3 5255.4 5270.6 5530.1 5532.8 5567.5 5596.8 5607.3 6083.5 6238.6 6273.2 6276.4 6296.5 6188.0 6196.2 6234.8 6248.5 6259.2 5845.7 5846.6 5898.6 5911.6 5921.0 5482.6 5520.9 5990.3 6388.8 6413.5 6117.2 6215.1 6225.3 6230.6 6256.8 6242.2 6243.8 6248.8 6257.2 6259.5 6044.4 6071.8 6097.8 6173.4 6179.3 5242.9 5970.0 6156.4 6242.7 6280.3 4921.2 4922.4 4923.3 4924.1 4963.0 4354.0 4440.8 4440.9 4455.4 4506.1 5743.6 5813.0 5829.4 5836.0 5870.5 6156.3 6168.7 6178.2 6178.6 6178.7 4697.2 5714.6 5725.0 5740.2 5760.3 5340.0 5410.0 5415.2 5432.4 5478.2 6455.2 6455.7 6474.4 6481.6 6485.8 6119.4 6170.6 6218.9 6222.2 6235.8 5400.0 6494.6 6529.9 6542.3 6550.4 6157.3 6180.5 6190.3 6197.6 6205.1 6138.8 6144.2 6145.7 6173.8 6226.2 6076.9 6149.8 6157.1 6163.2 6168.1 6091.4 6144.3 6147.8 6167.8 6244.8 4746.0 5078.3 5889.5 6036.6 6113.8 6110.5 6125.2 6129.7 6136.0 6139.5 5960.1 5999.6 6012.6 6016.4 6018.6 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#1] ext.html Cycles:180 Average:67442.89 Median:39874.20 stddev:139703.44 (350.4%) stddev-sans-first:140004.93 19:17:36 INFO - PROCESS | 2318 | Values: 498.9 589.6 594.9 663.8 667.0 32798.0 33373.7 41134.6 41502.4 41548.4 43815.6 44188.8 44367.0 44392.6 44434.8 9740.0 9891.2 9902.2 9902.3 9905.1 9683.5 9817.5 9829.3 9836.6 9837.3 9920.3 9971.5 9990.4 10002.5 10003.0 9888.4 9970.0 9980.1 9989.8 9991.5 9662.7 9773.9 9795.8 9817.1 9829.2 43011.4 43308.0 43316.0 43415.8 43429.0 3243.9 3405.2 3762.3 3858.4 3913.0 29662.3 29883.7 29911.7 30003.9 30014.7 2761.5 2773.7 2826.3 2860.1 2866.4 487252.0 487790.0 488745.0 488824.0 489486.6 71630.0 71717.6 72038.9 72076.6 72174.6 76121.6 76889.0 80082.2 81117.6 81605.0 18823.2 18844.3 19030.9 19051.1 19067.8 14348.9 14422.8 14441.1 14442.9 14472.5 18888.5 23087.0 23175.8 23461.3 23494.9 9505.1 9727.0 9736.9 9742.9 9764.3 980.5 1028.3 1105.6 1145.4 1202.0 30411.7 31976.0 33537.5 34212.8 35635.6 1020.2 1098.0 1115.9 1138.0 1152.8 15474.4 15900.6 16081.8 16103.4 16257.7 654157.0 671185.0 674332.0 675420.0 675889.0 64505.7 65037.7 65045.7 65122.4 65208.7 1359.5 1531.5 1569.1 1587.5 1613.1 31145.0 31776.5 32123.2 32163.8 33267.5 52820.0 53428.0 53556.0 53615.0 53630.7 364266.0 365849.0 367285.0 369979.0 370289.0 36385.9 47127.8 47571.5 49459.0 49475.0 47883.0 48016.0 48327.0 49100.8 49919.8 30661.0 31125.5 38238.4 39871.0 40668.6 38841.2 39132.8 39506.5 39622.8 39877.4 38919.8 39402.2 39742.6 39863.4 40377.0 33975.3 33976.3 34682.0 34852.8 35000.5 22182.7 26336.9 26359.5 26485.5 26608.6 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#2] jquery.html Cycles:180 Average:36290.64 Median:21317.91 stddev:109126.46 (511.9%) stddev-sans-first:109399.28 19:17:36 INFO - PROCESS | 2318 | Values: 382.4 387.8 388.1 396.6 396.8 16682.8 16832.5 19367.2 21154.9 21304.6 20394.5 20945.9 20951.5 21133.8 21477.8 4898.9 5196.6 5773.7 5815.4 5826.8 4295.6 5696.8 5794.9 6096.7 6386.9 6302.0 6316.8 6320.0 6322.5 6333.6 6120.7 6291.2 6373.8 6377.2 6384.8 5534.5 5552.3 5560.4 5565.1 5572.3 25536.9 26298.3 26754.0 26765.7 26767.9 1866.6 2250.8 2304.8 2884.9 2899.5 7303.6 10130.5 10180.5 10275.8 10428.9 1744.0 1754.9 1767.6 1770.8 1783.7 102341.5 106559.0 108353.0 109890.5 113058.3 11589.9 11591.3 11604.0 11625.1 11771.8 12391.9 12730.5 13279.7 14288.7 14334.2 7227.8 8693.2 8958.6 9077.1 9101.6 9256.4 9262.7 9274.2 9303.5 9333.6 4550.3 4623.2 4626.2 4672.2 4675.2 1920.3 1976.1 1976.7 1980.3 1980.9 815.0 984.0 1048.9 1104.2 1113.1 16666.1 20604.6 21215.8 21245.4 21331.3 1000.9 1006.5 1010.3 1048.1 1081.7 9199.6 11041.9 11194.9 11415.6 11443.5 507698.0 683002.0 686996.0 695069.0 698951.0 119993.0 123614.0 124612.0 125909.1 126896.0 1056.8 1097.8 1120.2 1124.0 1126.0 18212.2 18519.8 18682.8 19596.0 19651.7 50746.0 53421.7 53901.2 55986.4 57702.0 75215.6 76693.0 77555.0 77961.6 82530.0 7841.3 8208.2 8360.7 8419.5 8420.9 9482.7 12773.7 12924.1 13680.0 13708.9 9298.0 11135.7 11825.2 11901.5 11956.6 8918.3 11263.7 11853.8 11867.8 11875.3 11877.6 11947.1 12095.8 12382.8 12420.0 8271.4 8359.9 8425.6 8464.9 8630.4 9029.3 11127.7 11595.9 11666.6 11838.0 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#3] mootools.html Cycles:180 Average:6398.28 Median:5665.91 stddev:3420.83 (60.4%) stddev-sans-first:3410.55 19:17:36 INFO - PROCESS | 2318 | Values: 1492.9 1624.1 1644.3 1963.8 2055.3 8441.2 9100.2 9285.9 9301.6 9344.8 9098.9 9423.6 9426.1 9443.9 9454.1 2578.7 2615.5 2616.1 2617.9 2618.1 2498.3 2558.7 2563.7 2566.8 2576.3 2553.9 2562.4 2622.9 2626.9 2627.1 2559.9 2578.5 2587.4 2609.4 2612.6 2540.8 2574.8 2582.4 2615.8 2618.8 9205.7 9365.5 9403.2 9503.1 9521.0 4727.6 5911.6 5915.0 5922.2 5993.4 3665.7 3710.4 3779.1 3838.8 3882.9 1043.8 1044.6 1052.7 1054.5 1104.3 12428.9 12613.2 12630.2 12637.0 12651.1 4882.8 4898.4 5033.7 5035.2 5042.7 9927.0 9940.4 9942.2 9953.9 9953.9 5661.1 5670.7 5676.7 5684.4 5684.8 4669.0 4812.7 5103.5 5238.2 5239.7 3993.9 4014.1 4056.9 4072.4 4087.4 1233.5 1244.3 1246.0 1250.2 1256.5 979.8 1026.9 1063.0 1268.4 1430.8 8566.5 8576.1 8691.7 8695.7 8697.4 659.5 665.9 674.7 675.4 815.2 5214.5 6611.4 6632.3 6638.5 6644.6 10962.6 11196.9 11240.4 11248.9 11349.7 10771.2 11116.4 11168.9 11198.4 11205.3 8633.4 8763.0 8807.2 8826.6 8840.5 8633.6 8646.4 8688.7 8756.0 8826.9 9972.8 10211.2 10253.1 10365.7 10378.2 10355.2 10683.0 10848.0 10861.2 10897.0 7734.7 8095.6 8106.4 8114.0 8132.6 8977.1 9018.4 9065.7 9073.8 9077.2 7942.2 8055.8 8125.8 8188.7 8237.3 7923.5 7998.0 8047.5 8125.8 8157.8 8067.8 8158.7 8193.7 8219.6 8243.9 7361.3 7382.3 7387.1 7523.8 7524.5 6314.2 7544.5 7564.8 7565.2 7610.9 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#4] prototype.html Cycles:180 Average:5825.05 Median:6648.17 stddev:1869.42 (28.1%) stddev-sans-first:1828.74 19:17:36 INFO - PROCESS | 2318 | Values: 338.4 344.2 347.4 349.5 352.5 5722.6 6246.7 6657.4 6698.4 6712.1 6617.5 6643.8 6649.2 6653.4 6655.7 4716.6 4811.2 4906.8 4937.7 4941.6 4008.3 4888.1 4898.2 4925.4 4935.2 3956.8 3981.3 4944.2 4961.5 4963.5 4683.7 4775.3 4925.5 4951.9 4963.6 4259.3 4259.7 4267.0 4273.9 4288.4 6408.9 6416.3 6423.2 6429.4 6455.3 5874.5 5876.6 5885.9 5887.1 5889.9 6714.4 7072.7 7090.8 7092.9 7094.0 4568.8 4582.7 4616.7 4619.9 4625.8 7416.6 7493.5 7495.6 7503.6 7540.0 4709.6 4721.5 4728.1 4741.3 4743.1 4782.4 4795.9 4799.9 4804.1 4818.4 5514.4 5710.2 5714.8 5716.1 5733.7 7112.5 7115.2 7123.8 7147.9 7174.3 1627.4 1663.3 1666.5 1667.4 1667.9 1014.7 1056.8 1066.3 1089.7 1092.9 6570.2 6671.3 6851.1 6854.2 6855.3 7285.6 7360.4 7363.7 7365.3 7387.6 6633.4 6644.2 6647.1 6652.9 6657.5 3751.4 3801.2 3840.2 3842.1 3849.6 6230.4 7684.2 7733.9 7754.3 7764.3 7598.1 7604.1 7606.8 7612.5 7642.1 7694.6 7710.0 7729.6 7732.4 7733.9 5970.9 7157.3 7339.3 7345.3 7393.3 7278.2 7329.9 7334.9 7336.2 7357.3 7777.0 7796.4 7799.3 7823.5 7838.1 4658.7 4694.7 4698.1 4708.4 4721.8 5916.8 7141.9 7355.3 7395.3 7405.5 7127.6 7180.8 7194.7 7196.9 7198.4 7152.9 7157.2 7160.7 7163.7 7166.1 6960.7 6976.9 6980.5 6980.8 6998.5 6889.6 7133.6 7147.6 7162.6 7183.2 6659.5 6840.1 6854.8 6965.8 6979.0 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - PROCESS | 2318 | [#5] yui.html Cycles:180 Average:19398.90 Median:54571.73 stddev:27581.20 (50.5%) stddev-sans-first:27622.96 19:17:36 INFO - PROCESS | 2318 | Values: 731.9 757.9 761.4 773.9 792.5 15225.0 15558.1 15566.1 15570.2 15579.1 14349.4 14461.8 14464.2 14475.8 14491.0 786.8 788.3 971.5 991.5 992.0 969.4 974.7 979.0 980.1 981.5 841.5 1042.4 1049.5 1052.4 1053.3 1020.0 1033.5 1036.0 1036.2 1038.8 756.2 768.8 770.5 771.6 772.8 12615.7 15316.8 15395.5 15415.5 15429.2 12797.9 12878.0 13166.3 13371.8 13591.5 10267.3 11230.0 11352.5 11558.1 11692.2 3461.3 3513.2 3535.7 3558.2 3583.3 86988.0 87224.1 87935.0 88729.6 88791.7 9396.7 9462.2 9522.5 9700.2 9728.7 21413.3 21609.2 21653.8 21716.0 22188.7 7633.9 7644.8 7675.8 7735.3 7765.8 4159.8 4171.8 4183.0 4237.2 4398.3 3632.4 3778.8 3854.2 3860.0 3861.5 662.9 663.5 667.9 678.6 699.7 492.4 550.4 551.4 588.9 616.8 7221.9 8336.7 8401.7 8490.2 8519.8 534.8 570.9 612.1 612.2 624.1 3486.6 3948.7 4010.8 4094.9 4097.6 82356.0 95056.0 95655.5 97157.0 99396.0 74055.0 76632.0 77322.0 81493.8 85226.7 77432.2 79319.0 79993.4 80350.6 80381.6 8675.8 8699.1 8729.7 8787.2 8823.8 51754.5 53483.9 54335.5 54808.0 55232.7 72464.0 76841.7 76967.2 80329.0 80884.0 11743.9 11842.6 11912.1 12195.3 12323.5 12118.9 12203.5 12266.1 12463.9 13006.3 9880.4 11752.2 11859.3 12224.8 12402.9 10074.0 11279.8 11967.6 12031.3 12218.6 11406.1 11439.2 11746.9 11949.5 12092.8 9214.7 9492.7 9524.5 9606.2 9830.1 9596.6 9853.3 10108.4 10157.2 10238.4 19:17:36 INFO - PROCESS | 2318 | -------- Summary: end -------- 19:17:36 INFO - PROCESS | 2318 | 19:17:36 INFO - TEST-INFO | 2318: exit 0 19:17:36 INFO - TEST-OK | dromaeo_css | took 1112586ms 19:17:36 INFO - TEST-START | kraken 19:17:36 INFO - operating with platform_type : mac_ 19:17:36 INFO - Initialising browser for kraken test... 19:17:36 INFO - TEST-INFO | started process 2322 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp_gYa2H/profile http://localhost:50174/getInfo.html) 19:17:38 INFO - PROCESS | 2322 | __metrics Screen width/height:1600/1200 19:17:38 INFO - PROCESS | 2322 | colorDepth:24 19:17:38 INFO - PROCESS | 2322 | Browser inner width/height: 1024/689 19:17:38 INFO - PROCESS | 2322 | __metrics 19:17:38 INFO - PROCESS | 2322 | 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" 19:17:38 INFO - PROCESS | 2322 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:17:39 INFO - TEST-INFO | 2322: exit 0 19:17:39 INFO - Browser initialized. 19:17:39 INFO - Running cycle 1/1 for kraken test... 19:17:39 INFO - TEST-INFO | started process 2324 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp_gYa2H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:17:51 INFO - PROCESS | 2324 | RSS: Main: 210845696 19:17:51 INFO - PROCESS | 2324 | 19:17:53 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50174/tests/kraken/driver.html?testName=audio-beat-detection) 19:17:54 INFO - PROCESS | 2324 | RSS: Main: 265322496 19:17:54 INFO - PROCESS | 2324 | 19:17:57 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50174/tests/kraken/driver.html?testName=audio-dft) 19:17:57 INFO - PROCESS | 2324 | RSS: Main: 275582976 19:17:57 INFO - PROCESS | 2324 | 19:18:00 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50174/tests/kraken/driver.html?testName=audio-fft) 19:18:01 INFO - PROCESS | 2324 | RSS: Main: 265347072 19:18:01 INFO - PROCESS | 2324 | 19:18:03 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50174/tests/kraken/driver.html?testName=audio-oscillator) 19:18:04 INFO - PROCESS | 2324 | RSS: Main: 266334208 19:18:04 INFO - PROCESS | 2324 | 19:18:06 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50174/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:18:06 INFO - PROCESS | 2324 | RSS: Main: 267091968 19:18:06 INFO - PROCESS | 2324 | 19:18:09 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50174/tests/kraken/driver.html?testName=imaging-darkroom) 19:18:09 INFO - PROCESS | 2324 | RSS: Main: 268615680 19:18:09 INFO - PROCESS | 2324 | 19:18:12 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50174/tests/kraken/driver.html?testName=imaging-desaturate) 19:18:13 INFO - PROCESS | 2324 | RSS: Main: 267460608 19:18:13 INFO - PROCESS | 2324 | 19:18:16 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50174/tests/kraken/driver.html?testName=json-parse-financial) 19:18:16 INFO - PROCESS | 2324 | RSS: Main: 267522048 19:18:16 INFO - PROCESS | 2324 | 19:18:18 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50174/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:18:18 INFO - PROCESS | 2324 | RSS: Main: 300539904 19:18:18 INFO - PROCESS | 2324 | 19:18:20 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:18:21 INFO - PROCESS | 2324 | RSS: Main: 301039616 19:18:21 INFO - PROCESS | 2324 | 19:18:23 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:18:23 INFO - PROCESS | 2324 | RSS: Main: 308281344 19:18:23 INFO - PROCESS | 2324 | 19:18:26 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:18:27 INFO - PROCESS | 2324 | RSS: Main: 309084160 19:18:27 INFO - PROCESS | 2324 | 19:18:29 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:18:30 INFO - PROCESS | 2324 | RSS: Main: 310300672 19:18:30 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | Cycle 1(1): loaded http://localhost:50174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50174/tests/kraken/driver.html?testName=ai-astar) 19:18:31 INFO - PROCESS | 2324 | RSS: Main: 370106368 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | __start_tp_report 19:18:31 INFO - PROCESS | 2324 | _x_x_mozilla_page_load 19:18:31 INFO - PROCESS | 2324 | _x_x_mozilla_page_load_details 19:18:31 INFO - PROCESS | 2324 | |i|pagename|runs| 19:18:31 INFO - PROCESS | 2324 | |0;ai-astar;111;108;110;110;110;111;118;110;109;110 19:18:31 INFO - PROCESS | 2324 | |1;audio-beat-detection;155;117;163;173;117;138;116;151;175;149 19:18:31 INFO - PROCESS | 2324 | |2;audio-dft;183;184;184;185;185;183;185;185;184;184 19:18:31 INFO - PROCESS | 2324 | |3;audio-fft;74;75;74;120;55;56;57;58;127;74 19:18:31 INFO - PROCESS | 2324 | |4;audio-oscillator;90;96;132;80;76;87;118;76;81;115 19:18:31 INFO - PROCESS | 2324 | |5;imaging-gaussian-blur;87;83;86;84;86;90;89;82;86;82 19:18:31 INFO - PROCESS | 2324 | |6;imaging-darkroom;91;95;93;93;92;92;93;93;92;126 19:18:31 INFO - PROCESS | 2324 | |7;imaging-desaturate;86;87;87;86;85;86;86;90;87;86 19:18:31 INFO - PROCESS | 2324 | |8;json-parse-financial;54;58;58;58;57;57;58;68;52;51 19:18:31 INFO - PROCESS | 2324 | |9;json-stringify-tinderbox;62;63;70;73;69;66;65;66;67;66 19:18:31 INFO - PROCESS | 2324 | |10;stanford-crypto-aes;82;81;80;80;80;79;80;80;82;84 19:18:31 INFO - PROCESS | 2324 | |11;stanford-crypto-ccm;128;127;127;106;121;127;129;135;119;124 19:18:31 INFO - PROCESS | 2324 | |12;stanford-crypto-pbkdf2;132;121;132;132;134;121;133;135;131;129 19:18:31 INFO - PROCESS | 2324 | |13;stanford-crypto-sha256-iterative;65;65;67;65;66;66;65;65;64;64 19:18:31 INFO - PROCESS | 2324 | __end_tp_report 19:18:31 INFO - PROCESS | 2324 | __start_cc_report 19:18:31 INFO - PROCESS | 2324 | _x_x_mozilla_cycle_collect,1241 19:18:31 INFO - PROCESS | 2324 | __end_cc_report 19:18:31 INFO - PROCESS | 2324 | __startTimestamp1457921911919__endTimestamp 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | ------- Summary: start ------- 19:18:31 INFO - PROCESS | 2324 | Number of tests: 14 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#0] ai-astar Cycles:10 Average:110.70 Median:110.00 stddev:2.71 (2.5%) stddev-sans-first:2.87 19:18:31 INFO - PROCESS | 2324 | Values: 111.0 108.0 110.0 110.0 110.0 111.0 118.0 110.0 109.0 110.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:153.00 stddev:22.65 (14.8%) stddev-sans-first:23.75 19:18:31 INFO - PROCESS | 2324 | Values: 155.0 117.0 163.0 173.0 117.0 138.0 116.0 151.0 175.0 149.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:0.79 (0.4%) stddev-sans-first:0.71 19:18:31 INFO - PROCESS | 2324 | Values: 183.0 184.0 184.0 185.0 185.0 183.0 185.0 185.0 184.0 184.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#3] audio-fft Cycles:10 Average:77.00 Median:66.00 stddev:25.96 (39.3%) stddev-sans-first:27.51 19:18:31 INFO - PROCESS | 2324 | Values: 74.0 75.0 74.0 120.0 55.0 56.0 57.0 58.0 127.0 74.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:80.50 stddev:19.81 (24.6%) stddev-sans-first:20.92 19:18:31 INFO - PROCESS | 2324 | Values: 90.0 96.0 132.0 80.0 76.0 87.0 118.0 76.0 81.0 115.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:2.76 (3.2%) stddev-sans-first:2.87 19:18:31 INFO - PROCESS | 2324 | Values: 87.0 83.0 86.0 84.0 86.0 90.0 89.0 82.0 86.0 82.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:93.00 stddev:10.59 (11.4%) stddev-sans-first:11.08 19:18:31 INFO - PROCESS | 2324 | Values: 91.0 95.0 93.0 93.0 92.0 92.0 93.0 93.0 92.0 126.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#7] imaging-desaturate Cycles:10 Average:86.60 Median:86.50 stddev:1.35 (1.6%) stddev-sans-first:1.41 19:18:31 INFO - PROCESS | 2324 | Values: 86.0 87.0 87.0 86.0 85.0 86.0 86.0 90.0 87.0 86.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:4.65 (8.0%) stddev-sans-first:4.80 19:18:31 INFO - PROCESS | 2324 | Values: 54.0 58.0 58.0 58.0 57.0 57.0 58.0 68.0 52.0 51.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:66.50 stddev:3.27 (4.9%) stddev-sans-first:2.99 19:18:31 INFO - PROCESS | 2324 | Values: 62.0 63.0 70.0 73.0 69.0 66.0 65.0 66.0 67.0 66.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.50 stddev:1.48 (1.8%) stddev-sans-first:1.50 19:18:31 INFO - PROCESS | 2324 | Values: 82.0 81.0 80.0 80.0 80.0 79.0 80.0 80.0 82.0 84.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:127.00 stddev:7.79 (6.1%) stddev-sans-first:8.15 19:18:31 INFO - PROCESS | 2324 | Values: 128.0 127.0 127.0 106.0 121.0 127.0 129.0 135.0 119.0 124.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.00 Median:132.00 stddev:5.01 (3.8%) stddev-sans-first:5.26 19:18:31 INFO - PROCESS | 2324 | Values: 132.0 121.0 132.0 132.0 134.0 121.0 133.0 135.0 131.0 129.0 19:18:31 INFO - PROCESS | 2324 | 19:18:31 INFO - PROCESS | 2324 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 19:18:31 INFO - PROCESS | 2324 | Values: 65.0 65.0 67.0 65.0 66.0 66.0 65.0 65.0 64.0 64.0 19:18:31 INFO - PROCESS | 2324 | -------- Summary: end -------- 19:18:31 INFO - PROCESS | 2324 | 19:18:32 INFO - TEST-INFO | 2324: exit 0 19:18:32 INFO - TEST-OK | kraken | took 56065ms 19:18:32 INFO - SUITE-END | took 1168s 19:18:33 INFO - Completed test suite (00:19:29) 19:18:33 INFO - javascript benchmark 19:18:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2719.2657982344767, 3487.3548393547194, 3511.17487390463, 3529.0647978285742, 3562.25192709531, 5709.942900570993, 5712.714364281785, 5748.137779333102, 5753.338366087901, 5766.682832444214, 5713.0, 5776.797812076578, 5779.595428320019, 5783.942160578393, 5790.334111577159, 5094.490550944913, 5172.586193104561, 5188.37739471263, 5274.55166310864, 5293.311869456985, 5262.710550919698, 5263.394709608387, 5273.235380869772, 5289.550388216987, 5304.3634763828395, 5209.426963034063, 5260.342457192851, 5261.079311120558, 5276.103062479368, 5298.708571028592, 5279.445658205891, 5347.545458636001, 5350.598705097094, 5363.436839131874, 5372.91940620891, 5057.418396884369, 5239.842804715865, 5244.292020577212, 5255.369355677305, 5270.631055826094, 5530.142827861688, 5532.834014979517, 5567.47109024642, 5596.832095037155, 5607.271054762855, 6083.513318934474, 6238.625682459022, 6273.1844860167885, 6276.435120839101, 6296.496280297611, 6188.040853667689, 6196.225471816023, 6234.750609975641, 6248.468880145193, 6259.217597800275, 5845.7369418376275, 5846.649201047908, 5898.616589921728, 5911.645301281937, 5921.0, 5482.616216864861, 5520.895820835849, 5990.28116625999, 6388.840278993062, 6413.518986076063, 6117.248930028882, 6215.098810672427, 6225.315215326311, 6230.626162430178, 6256.843578910563, 6242.157308763259, 6243.843903902439, 6248.843778905563, 6257.155284036597, 6259.499240060784, 6044.395560443921, 6071.848203794852, 6097.75608975636, 6173.444390004921, 6179.258488981351, 5242.9213561796605, 5969.970150149222, 6156.415140561639, 6242.687865606792, 6280.340564240781, 4921.212605983025, 4922.360093187863, 4923.286123512143, 4924.064427758714, 4963.0, 4354.0, 4440.777961101932, 4440.888977775581, 4455.443069616298, 4506.098780243899, 5743.569232307594, 5813.011787996051, 5829.446202610745, 5836.0, 5870.471657550841, 6156.29202641693, 6168.691565421711, 6178.165947597017, 6178.567500274938, 6178.721957511832, 4697.224957881911, 5714.628549144292, 5724.971375143181, 5740.225069615549, 5760.251167348301, 5340.0, 5410.026195284808, 5415.187721841677, 5432.375276843213, 5478.150886612581, 6455.225372955276, 6455.709493072917, 6474.449671777952, 6481.611103333815, 6485.77299794505, 6119.380693981147, 6170.629762214281, 6218.906716399167, 6222.160008398808, 6235.844103897439, 5399.973000134974, 6494.6103233806125, 6529.934700652932, 6542.313057128933, 6550.410463058348, 6157.26112866441, 6180.536459765625, 6190.2571691395515, 6197.566170368031, 6205.069239614094, 6138.815835524942, 6144.2319710036245, 6145.6927153641245, 6173.81478555644, 6226.159468471698, 6076.859660101179, 6149.815505534841, 6157.1072194532435, 6163.229596300463, 6168.1056246844855, 6091.42131497507, 6144.262688477412, 6147.84630384244, 6167.84580385494, 6244.812655620339, 4746.003339298786, 5078.314427552233, 5889.469947704556, 6036.63780173191, 6113.816585502442, 6110.511159107172, 6125.234345706786, 6129.724162412613, 6136.018237082224, 6139.4781443577795, 5960.105984102419, 5999.580029398075, 6012.579119461596, 6016.42843929826, 6018.578699491169], "unit": "score", "name": "dojo.html", "value": 5693.542217918588}, {"lowerIsBetter": false, "replicates": [498.88250319284765, 589.5872888977717, 594.9202198010613, 663.7743167323109, 666.9866602667947, 32798.0, 33373.66626333739, 41134.58865411345, 41502.37746433798, 41548.376774348406, 43815.56184438155, 44188.77905610467, 44367.0, 44392.55607443924, 44434.777826110825, 9739.951300243489, 9891.208703303755, 9902.158316543102, 9902.306838521306, 9905.108540231378, 9683.515824208796, 9817.46003969785, 9829.262805289596, 9836.606535738561, 9837.262205334593, 9920.305578609501, 9971.451570163637, 9990.400575965428, 10002.499875006257, 10003.049710277512, 9888.40669559825, 9969.950150249275, 9980.101790838788, 9989.75025624358, 9991.450470224136, 9662.710118696381, 9773.902260977371, 9795.804083918281, 9817.116459518678, 9829.213662907021, 43011.35482967758, 43308.0, 43316.0, 43415.782921085505, 43429.0, 3243.8702451901895, 3405.165734395064, 3762.3415902217275, 3858.4405261237243, 3913.0413048803225, 29662.258443539085, 29883.701162988527, 29911.70088299133, 30003.94986175495, 30014.699853001628, 2761.5465056920875, 2773.736495032969, 2826.321682796114, 2860.099068793344, 2866.3980564081567, 487252.0, 487790.0, 488744.99999999645, 488824.0, 489486.5525672349, 71630.0, 71717.6414117926, 72038.9194162088, 72076.63961680158, 72174.63912680403, 76121.61939190268, 76889.0, 80082.19917800864, 81117.59441202757, 81605.0, 18823.152958116916, 18844.34044808425, 19030.90484547596, 19051.1426985786, 19067.80932190688, 14348.928255358865, 14422.783658245135, 14441.06133101345, 14442.927785361217, 14472.493462728755, 18888.527786805163, 23087.0, 23175.7682423177, 23461.296161114853, 23494.88252558726, 9505.144536991704, 9726.951365243129, 9736.853947190797, 9742.902570974342, 9764.267679923893, 980.4705459052175, 1028.2545154762738, 1105.6240878101323, 1145.374011344369, 1202.0143482344395, 30411.695883041328, 31976.0, 33537.496937545955, 34212.82893585516, 35635.643643563235, 1020.2399212586671, 1098.0008192544747, 1115.8995690387903, 1137.971550711239, 1152.8213126965334, 15474.381024759108, 15900.602484937968, 16081.758773618405, 16103.436379726887, 16257.722243551652, 654157.0, 671185.0, 674332.0, 675420.0, 675889.0, 64505.70988580202, 65037.67481162564, 65045.674771626786, 65122.37194070186, 65208.673956629915, 1359.5377571625695, 1531.5175719648275, 1569.089927841849, 1587.5396135120686, 1613.0886049382295, 31145.0, 31776.523352149274, 32123.238595653063, 32163.839180804884, 33267.50098748569, 52820.0, 53428.0, 53556.0, 53615.0, 53630.73184634052, 364266.0, 365849.0, 367285.0, 369979.0, 370289.0, 36385.90842274736, 47127.76436117797, 47571.524284756706, 49459.0, 49475.0, 47883.0, 48016.0, 48327.0, 49100.75449622729, 49919.75040124776, 30661.0, 31125.53311700281, 38238.42642360422, 39871.0, 40668.59331406648, 38841.2231755369, 39132.80433597814, 39506.46909432739, 39622.80188599038, 39877.401838971855, 38919.805400972815, 39402.21195576015, 39742.60257397389, 39863.40204896987, 40377.0, 33975.32049359049, 33976.3204735899, 34681.9595412138, 34852.825735871156, 35000.47499287462, 22182.66725999079, 26336.8683156583, 26359.472810544066, 26485.470290594465, 26608.60087098734], "unit": "score", "name": "ext.html", "value": 21269.211017617912}, {"lowerIsBetter": false, "replicates": [382.3633649972795, 387.8080350226637, 388.0550858659165, 396.58358723340484, 396.84919730502406, 16682.83317166828, 16832.495025149234, 19367.22531098756, 21154.89422552885, 21304.57390852182, 20394.490137746525, 20945.895270523662, 20951.476213094644, 21133.788662113373, 21477.818719970397, 4898.853034408973, 5196.610254230929, 5773.711314434283, 5815.418458154193, 5826.766929322822, 4295.570442955695, 5696.800611978581, 5794.942050579482, 6096.664683442409, 6386.87226255477, 6302.0, 6316.810495685137, 6319.968400158015, 6322.525810564203, 6333.55665103443, 6120.69396530174, 6291.213598300213, 6373.820843143987, 6377.23473183221, 6384.808455746358, 5534.4742249486235, 5552.333719953632, 5560.388357280696, 5565.1095824667855, 5572.275604171432, 25536.872315638302, 26298.342541436425, 26754.0, 26765.732342676714, 26767.866160669266, 1866.5893503429224, 2250.7861753133425, 2304.7580004099495, 2884.927876803076, 2899.4780939430893, 7303.634818259119, 10130.493475326279, 10180.490975451272, 10275.845862312071, 10428.947855260674, 1744.0, 1754.9122543872884, 1767.6199617082389, 1770.7786526684165, 1783.7235228539598, 102341.4882925588, 106559.0, 108353.0, 109890.45054774675, 113058.30412543737, 11589.884101159048, 11591.304521728724, 11604.0, 11625.069994400428, 11771.764564708828, 12391.93804030992, 12730.490780368867, 13279.66800829987, 14288.714225715428, 14334.211618360881, 7227.819304517429, 8693.174148455886, 8958.552072396355, 9077.09229077087, 9101.590428430736, 9256.352055356196, 9262.675806346746, 9274.21169200612, 9303.534823258946, 9333.579988900516, 4550.340200670884, 4623.214053610895, 4626.249801618775, 4672.2057250267535, 4675.18184317743, 1920.3470819921295, 1976.0811222781476, 1976.723258743777, 1980.2969945669367, 1980.920763169486, 815.0016230118176, 983.9914088059653, 1048.926575139733, 1104.1939384249542, 1113.0539041814554, 16666.08718975222, 20604.587908241752, 21215.78784212169, 21245.362639120853, 21331.253406130712, 1000.8748906386702, 1006.4917216805467, 1010.2625083688948, 1048.109107258824, 1081.734974931147, 9199.636606866416, 11041.944790275997, 11194.944025279985, 11415.60045398407, 11443.485043173077, 507698.0, 683002.0, 686996.0, 695069.0, 698951.0, 119993.0, 123614.0, 124612.0, 125909.11136332962, 126896.0, 1056.815057364958, 1097.7694684116266, 1120.2046546951756, 1123.9887601123885, 1126.0, 18212.18045187943, 18519.814801851808, 18682.813171868107, 19596.0, 19651.70522442193, 50746.0, 53421.7328913353, 53901.19148212702, 55986.440135598124, 57702.0, 75215.62392188005, 76693.0, 77555.0, 77961.61019195095, 82530.0, 7841.255080767318, 8208.179182081743, 8360.66557337724, 8419.494830310201, 8420.87368689458, 9482.668106616235, 12773.680657983625, 12924.095313328353, 13680.0, 13708.93145534266, 9297.953510232406, 11135.72160695989, 11825.231359961575, 11901.464434100659, 11956.641300760992, 8918.286537077149, 11263.66209013731, 11853.76292474163, 11867.821982670093, 11875.287482751062, 11877.58428455, 11947.103967202669, 12095.75808483843, 12382.81425778632, 12420.0, 8271.421000529905, 8359.874601881098, 8425.62084706202, 8464.873026904725, 8630.439021463584, 9029.322800790098, 11127.666170014912, 11595.884041159818, 11666.591669291873, 11837.993770529602], "unit": "score", "name": "jquery.html", "value": 9714.575733759926}, {"lowerIsBetter": false, "replicates": [1492.850714928508, 1624.1148574027154, 1644.2765183319339, 1963.8036196380356, 2055.2703790154487, 8441.155884411555, 9100.226480749136, 9285.907140928588, 9301.581428835701, 9344.766380840474, 9098.86351704725, 9423.57593908274, 9426.05739426057, 9443.858342124873, 9454.054594540543, 2578.7034491033583, 2615.4769046190745, 2616.0582190411437, 2617.8560179190135, 2618.0836707152534, 2498.263012411343, 2558.654581631484, 2563.679540057493, 2566.8203225774205, 2576.2528866628654, 2553.872306384683, 2562.449073449208, 2622.881970311331, 2626.8686565671737, 2627.106783693544, 2559.897604095834, 2578.5229732499456, 2587.4178309880313, 2609.3737502999334, 2612.58198688209, 2540.847549147057, 2574.768270855613, 2582.3931376126593, 2615.751503607173, 2618.8166828322023, 9205.677801276923, 9365.48489833059, 9403.200727938134, 9503.144716975436, 9521.0, 4727.57451829337, 5911.615744976606, 5914.97042514789, 5922.230110085704, 5993.430624090704, 3665.7433979621437, 3710.4063349864164, 3779.055236190952, 3838.8272527736317, 3882.9029274268087, 1043.8173319669104, 1044.571725592511, 1052.7105046112304, 1054.46222426563, 1104.320842681753, 12428.875711242863, 12613.180143290656, 12630.24218546881, 12637.0, 12651.0511711622, 4882.829100981448, 4898.363212782316, 5033.723145226974, 5035.244713293036, 5042.697438153723, 9927.007299270159, 9940.403575785476, 9942.204623630092, 9953.850692239623, 9953.900460995443, 5661.122526008476, 5670.688112153871, 5676.744546495335, 5684.37471878093, 5684.8294551163535, 4669.0195059037305, 4812.687175333592, 5103.464136265713, 5238.214267859776, 5239.73801309933, 3993.900152496211, 4014.116894283253, 4056.8985775355263, 4072.3687828386646, 4087.3868919661695, 1233.4572787973264, 1244.3031902134833, 1245.9563915262922, 1250.2373552133192, 1256.466001949168, 979.7599588100961, 1026.8511065895552, 1063.0273299930477, 1268.3594784633701, 1430.8354539228121, 8566.52884091381, 8576.142385761374, 8691.652333906699, 8695.695650652196, 8697.391182617283, 659.4856012310405, 665.9467242620578, 674.7031306225246, 675.3516624040976, 815.1644564321617, 5214.530692237717, 6611.438027767597, 6632.2704502504685, 6638.4689224861895, 6644.60132392058, 10962.56149754001, 11196.936291052338, 11240.381779002233, 11248.931351521593, 11349.659510214708, 10771.246012779187, 11116.38859862699, 11168.944155279172, 11198.38408887519, 11205.271657342246, 8633.352498562634, 8763.036066032731, 8807.163319484638, 8826.5586720665, 8840.469571825712, 8633.56832158377, 8646.351523635627, 8688.652453902027, 8756.0, 8826.867596986178, 9972.750681232737, 10211.23415743837, 10253.07722304996, 10365.689029329133, 10378.221633377678, 10355.171586272929, 10683.0, 10848.02367786903, 10861.185411094042, 10897.0, 7734.651940662796, 8095.595220239083, 8106.351491880753, 8114.0, 8132.552709600792, 8977.10228977097, 9018.413803102778, 9065.728028159167, 9073.818523629623, 9077.228435583049, 7942.245486678757, 8055.7583272501915, 8125.837483250184, 8188.713395031145, 8237.341012718849, 7923.524588524707, 7997.960010199912, 8047.476914000571, 8125.8374832504205, 8157.836843262983, 8067.757967260992, 8158.673653054048, 8193.67225310981, 8219.589020549069, 8243.917560824315, 7361.337479626859, 7382.335589796944, 7387.076615423072, 7523.849523009619, 7524.548527088392, 6314.210723659543, 7544.509606875536, 7564.810879728051, 7565.167831538416, 7610.9238907610215], "unit": "score", "name": "mootools.html", "value": 5112.524943841968}, {"lowerIsBetter": false, "replicates": [338.4212995777221, 344.1739824421388, 347.3625896479959, 349.500214692989, 352.5012107867369, 5722.6280291781, 6246.719422518384, 6657.434118099955, 6698.430633396167, 6712.06031155638, 6617.470602351812, 6643.833904152399, 6649.235337936138, 6653.401193892549, 6655.667216639161, 4716.575508204261, 4811.206150985101, 4906.828261010865, 4937.728424936627, 4941.60467162627, 4008.318585840414, 4888.095702295069, 4898.167311557026, 4925.3597032385915, 4935.210366341386, 3956.821943012557, 3981.3231750602467, 4944.2089265717495, 4961.5286547777905, 4963.528464795839, 4683.742394168319, 4775.283707443859, 4925.482824303468, 4951.876203094916, 4963.553280204799, 4259.275923093051, 4259.680523960685, 4267.0, 4273.850415235483, 4288.442502474658, 6408.93591064088, 6416.294207637156, 6423.229212494531, 6429.35706429358, 6455.289918109004, 5874.471297583238, 5876.55925805562, 5885.882282354329, 5887.087501437284, 5889.882202355929, 6714.362135597159, 7072.681729322193, 7090.759117154477, 7092.893606595905, 7093.964530177316, 4568.771561421949, 4582.747948862811, 4616.722996620214, 4619.937414394674, 4625.838095666635, 7416.555006699616, 7493.475456718032, 7495.587742674151, 7503.587302698349, 7539.962300188518, 4709.576138147515, 4721.504242054603, 4728.078024785203, 4741.336212930192, 4743.075100355398, 4782.402199725035, 4795.856124316276, 4799.856004319876, 4804.06320767447, 4818.445878723992, 5514.448555144453, 5710.229119068956, 5714.79998200061, 5716.085426331767, 5733.741981610754, 7112.537685050455, 7115.181754098345, 7123.786286411416, 7147.89278160828, 7174.282571742889, 1627.4385337058695, 1663.3346661335563, 1666.4584010196734, 1667.4247384652276, 1667.90826504541, 1014.660088870222, 1056.8309070548828, 1066.2749330455365, 1089.7330154112294, 1092.8852470490642, 6570.244421891543, 6671.299513551007, 6851.109355783717, 6854.246032936373, 6855.280195579492, 7285.599292038989, 7360.411167106618, 7363.705451781976, 7365.305979624716, 7387.556746595114, 6633.436157926534, 6644.235912869985, 6647.102641143384, 6652.8669426611195, 6657.46740260778, 3751.4185301278344, 3801.163743976321, 3840.1551658635058, 3842.0971071798103, 3849.576546579874, 6230.408111229426, 7684.154742978268, 7733.922660773433, 7754.263344982218, 7764.262395072572, 7598.088229412506, 7604.12552556452, 7606.847863042819, 7612.505187162817, 7642.121156067013, 7694.576798276036, 7710.036245469317, 7729.5748733819055, 7732.381409487115, 7733.883991740128, 5970.850728731816, 7157.329500653726, 7339.339459448675, 7345.3021962913435, 7393.297636724503, 7278.235785242473, 7329.890051649336, 7334.926650733638, 7336.193018768038, 7357.337839594462, 7776.961115194388, 7796.376289896682, 7799.298063174341, 7823.530588164728, 7838.137804841349, 4658.67389282747, 4694.671373003857, 4698.130845793403, 4708.3643708100335, 4721.834735784231, 5916.763329466773, 7141.928580714126, 7355.264473552602, 7395.297446742551, 7405.481616286808, 7127.5723456590695, 7180.820479487846, 7194.676239569128, 7196.928030719626, 7198.424126070006, 7152.856942861218, 7157.24848890859, 7160.677769500281, 7163.677634506355, 7166.068411106523, 6960.651967401711, 6976.860462790614, 6980.546264492792, 6980.755673551402, 6998.475114366544, 6889.586624802328, 7133.5719856808755, 7147.571145731273, 7162.570245785269, 7183.173934997542, 6659.500537459807, 6840.076589660531, 6854.828629284308, 6965.825854353681, 6978.953157026283], "unit": "score", "name": "prototype.html", "value": 5203.775576536095}, {"lowerIsBetter": false, "replicates": [731.8865575835745, 757.8522188173305, 761.3832795435695, 773.9032620922385, 792.4571668407145, 15225.0, 15558.144302063381, 15566.066036037832, 15570.221488925565, 15579.065256084643, 14349.354279057441, 14461.78307325391, 14464.204468754215, 14475.782863257033, 14491.0, 786.8032991752048, 788.2984144111745, 971.4996776660025, 991.5092029445435, 992.0, 969.3505351414569, 974.741693451236, 979.0356498848629, 980.127686359141, 981.4651015196699, 841.5203334099566, 1042.3849928542159, 1049.5329578337644, 1052.389614023868, 1053.2785042246048, 1019.9949000254989, 1033.5142483032962, 1036.0209601926183, 1036.19694736579, 1038.771470276538, 756.2475337039681, 768.8154842837735, 770.5107256891858, 771.5640663025373, 772.7952092695384, 12615.684607884785, 15316.770248446282, 15395.538133855891, 15415.460458883885, 15429.151396673178, 12797.936010319889, 12878.0, 13166.341682915816, 13371.799423008662, 13591.524296649668, 10267.332623379456, 11229.989300962952, 11352.48913798881, 11558.075353971662, 11692.240004399771, 3461.273132642148, 3513.2270900401872, 3535.7171426285836, 3558.217192217708, 3583.3191693578137, 86988.0, 87224.1277587216, 87935.0, 88729.55635221847, 88791.66812497818, 9396.684464175021, 9462.195713364303, 9522.523873806282, 9700.175485083846, 9728.659496917573, 21413.250536231157, 21609.243676471247, 21653.78346216549, 21716.0, 22188.667169992463, 7633.885491717629, 7644.847103058019, 7675.769726908202, 7735.265149810759, 7765.844683106419, 4159.771212583337, 4171.83312667496, 4183.0, 4237.237297286518, 4398.252297109499, 3632.418812989909, 3778.7732736035928, 3854.248421557825, 3859.996400935777, 3861.498005259298, 662.8873091574444, 663.5090033375365, 667.853072324088, 678.6437120511771, 699.6781480518916, 492.4484577273405, 550.3533348315793, 551.4457969740386, 588.8792797476507, 616.7881402151835, 7221.89167162493, 8336.66653333872, 8401.747947561584, 8490.235878770816, 8519.829603408021, 534.767376191358, 570.925779648643, 612.0788213738296, 612.1705089603612, 624.1386886097157, 3486.599041110254, 3948.7038472114705, 4010.7794071326357, 4094.9181016379507, 4097.631213190827, 82356.0, 95056.0, 95655.52172239234, 97157.02842971621, 99396.0, 74055.0, 76632.0, 77322.0, 81493.77759333614, 85226.72159917606, 77432.22567774363, 79319.0, 79993.4001319982, 80350.59824700838, 80381.59809200917, 8675.828698276406, 8699.08659590759, 8729.65081396737, 8787.209151176425, 8823.823523529622, 51754.482455176476, 53483.930321392574, 54335.45664543462, 54808.0, 55232.72383638056, 72464.0, 76841.70003849626, 76967.230327696, 80329.0, 80884.0, 11743.882561174622, 11842.585509507471, 11912.10659200546, 12195.268283902995, 12323.50705971751, 12118.878811212127, 12203.450844711831, 12266.080043996915, 12463.940565052073, 13006.349682516025, 9880.40717556949, 11752.177347585928, 11859.34773587461, 12224.75550489003, 12402.937985310016, 10074.0, 11279.7744045117, 11967.61423541342, 12031.338276394883, 12218.572349967708, 11406.14453915943, 11439.199256051996, 11746.94126529396, 11949.522019119138, 12092.818607720716, 9214.723558293263, 9492.715218543455, 9524.52034073716, 9606.231501480004, 9830.115289623967, 9596.616135354509, 9853.349187475114, 10108.444035578113, 10157.238207134347, 10238.385696858213], "unit": "score", "name": "yui.html", "value": 7200.785758579874}], "name": "dromaeo_css", "value": 7801.078044209988}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 110.0, 110.0, 110.0, 111.0, 118.0, 110.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 110.7}, {"lowerIsBetter": true, "replicates": [155.0, 117.0, 163.0, 173.0, 117.0, 138.0, 116.0, 151.0, 175.0, 149.0], "unit": "score", "name": "audio-beat-detection", "value": 145.4}, {"lowerIsBetter": true, "replicates": [183.0, 184.0, 184.0, 185.0, 185.0, 183.0, 185.0, 185.0, 184.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.2}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 74.0, 120.0, 55.0, 56.0, 57.0, 58.0, 127.0, 74.0], "unit": "score", "name": "audio-fft", "value": 77.0}, {"lowerIsBetter": true, "replicates": [90.0, 96.0, 132.0, 80.0, 76.0, 87.0, 118.0, 76.0, 81.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 95.1}, {"lowerIsBetter": true, "replicates": [87.0, 83.0, 86.0, 84.0, 86.0, 90.0, 89.0, 82.0, 86.0, 82.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.5}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 126.0], "unit": "score", "name": "imaging-darkroom", "value": 96.0}, {"lowerIsBetter": true, "replicates": [86.0, 87.0, 87.0, 86.0, 85.0, 86.0, 86.0, 90.0, 87.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 86.6}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 68.0, 52.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 70.0, 73.0, 69.0, 66.0, 65.0, 66.0, 67.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.7}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 80.0, 80.0, 80.0, 79.0, 80.0, 80.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.8}, {"lowerIsBetter": true, "replicates": [128.0, 127.0, 127.0, 106.0, 121.0, 127.0, 129.0, 135.0, 119.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [132.0, 121.0, 132.0, 132.0, 134.0, 121.0, 133.0, 135.0, 131.0, 129.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 130.0}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 65.0, 64.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.2}], "name": "kraken", "value": 1404.6000000000001}]} 19:18:33 INFO - Return code: 0 19:18:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:18:33 INFO - # TBPL SUCCESS # 19:18:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:18:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:18:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:18:33 INFO - 2016-03-13 19:18:33.221 screenresolution[2328:21693] starting screenresolution argv=screenresolution get 19:18:33 INFO - 2016-03-13 19:18:33.223 screenresolution[2328:21693] Display 0: 1600x1200x32@60 19:18:33 INFO - 2016-03-13 19:18:33.229 screenresolution[2329:21696] starting screenresolution argv=screenresolution list 19:18:33 INFO - Available Modes on Display 0 19:18:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:18:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:18:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:18:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:18:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:18:33 INFO - Intel Iris: 19:18:33 INFO - Chipset Model: Intel Iris 19:18:33 INFO - Type: GPU 19:18:33 INFO - Bus: Built-In 19:18:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:18:33 INFO - Vendor: Intel (0x8086) 19:18:33 INFO - Device ID: 0x0a2e 19:18:33 INFO - Revision ID: 0x0009 19:18:33 INFO - Displays: 19:18:33 INFO - Display: 19:18:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:18:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:18:33 INFO - Display Serial Number: 1600x1200 60 19:18:33 INFO - Main Display: Yes 19:18:33 INFO - Mirror: Off 19:18:33 INFO - Online: Yes 19:18:33 INFO - Rotation: Supported 19:18:33 INFO - Return code: 0 19:18:33 INFO - Running post-action listener: _resource_record_post_action 19:18:33 INFO - Running post-run listener: _resource_record_post_run 19:18:34 INFO - Total resource usage - Wall time: 1202s; CPU: 52.0%; Read bytes: 413696; Write bytes: 662927360; Read time: 7; Write time: 1878 19:18:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350683648; Write bytes: 352110592; Read time: 27056; Write time: 649 19:18:34 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 413696; Write bytes: 299343872; Read time: 7; Write time: 1197 19:18:34 INFO - Running post-run listener: _upload_blobber_files 19:18:34 INFO - Blob upload gear active. 19:18:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:18:34 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 19:18:34 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:18:34 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:18:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:18:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:18:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:36 INFO - (blobuploader) - INFO - Done attempting. 19:18:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:18:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:18:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:18:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:18:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:18:37 INFO - (blobuploader) - INFO - Done attempting. 19:18:37 INFO - (blobuploader) - INFO - Iteration through files over. 19:18:37 INFO - Return code: 0 19:18:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:18:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:18:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b399d605aa1af1e5a5a08de20afa4c5aacc56830838c7d823fddc49c868d8bf964601e19f025a724d2ee1ebe51f3bfe25dfec638bd7748c54584dc502e1bd76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c67e8056dcb91530490818079c5e2c4bb73ac422a74eef6e3838764dc7e9f2a9d03ffdafe27232a3eed2f73cfe7d0802e37dfc48e563bc1a8f76e2cb32163436"} 19:18:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:18:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:18:37 INFO - Contents: 19:18:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b399d605aa1af1e5a5a08de20afa4c5aacc56830838c7d823fddc49c868d8bf964601e19f025a724d2ee1ebe51f3bfe25dfec638bd7748c54584dc502e1bd76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c67e8056dcb91530490818079c5e2c4bb73ac422a74eef6e3838764dc7e9f2a9d03ffdafe27232a3eed2f73cfe7d0802e37dfc48e563bc1a8f76e2cb32163436"} 19:18:37 INFO - Running post-run listener: copy_logs_to_upload_dir 19:18:37 INFO - Copying logs to upload dir... 19:18:37 INFO - mkdir: /builds/slave/test/build/upload/logs 19:18:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.591556 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-03-13 19:18:37.909001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-13 19:18:37.912337) ========= 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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/Mozilla-Aurora/sha512/b399d605aa1af1e5a5a08de20afa4c5aacc56830838c7d823fddc49c868d8bf964601e19f025a724d2ee1ebe51f3bfe25dfec638bd7748c54584dc502e1bd76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c67e8056dcb91530490818079c5e2c4bb73ac422a74eef6e3838764dc7e9f2a9d03ffdafe27232a3eed2f73cfe7d0802e37dfc48e563bc1a8f76e2cb32163436"} build_url:https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011689 build_url: 'https://queue.taskcluster.net/v1/task/d9jNbJVgQXmj9iCsmpEiDw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b399d605aa1af1e5a5a08de20afa4c5aacc56830838c7d823fddc49c868d8bf964601e19f025a724d2ee1ebe51f3bfe25dfec638bd7748c54584dc502e1bd76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c67e8056dcb91530490818079c5e2c4bb73ac422a74eef6e3838764dc7e9f2a9d03ffdafe27232a3eed2f73cfe7d0802e37dfc48e563bc1a8f76e2cb32163436"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-13 19:18:37.940083) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 19:18:37.940383) ========= 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.n0xIVjTjE9/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.isIZyp8SaZ/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005611 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-13 19:18:37.989091) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-13 19:18:37.989400) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-13 19:18:37.989846) ========= ========= Total master_lag: 8.19 =========