builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0140 starttime: 1460046542.43 results: success (0) buildid: 20160407091415 builduid: bcb111c56ea04b73b2a00670f058fa8e revision: 1725b460c3e0de97590cb8764df9ace9115b813e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.427535) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.428288) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.428753) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005927 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.457120) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.457441) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.503835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.504277) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005464 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.564923) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.565268) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.565669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.565998) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-04-07 09:29:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.90M=0.001s 2016-04-07 09:29:02 (8.90 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.686257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.686759) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.020844 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:02.756719) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 09:29:02.757139) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1725b460c3e0de97590cb8764df9ace9115b813e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1725b460c3e0de97590cb8764df9ace9115b813e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-04-07 09:29:02,844 truncating revision to first 12 chars 2016-04-07 09:29:02,844 Setting DEBUG logging. 2016-04-07 09:29:02,844 attempt 1/10 2016-04-07 09:29:02,844 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1725b460c3e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 09:29:04,216 unpacking tar archive at: fx-team-1725b460c3e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.615146 ========= master_lag: 3.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-07 09:29:08.005064) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:08.005601) ========= echo 1725b460c3e0de97590cb8764df9ace9115b813e in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1725b460c3e0de97590cb8764df9ace9115b813e'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 1725b460c3e0de97590cb8764df9ace9115b813e program finished with exit code 0 elapsedTime=0.005302 script_repo_revision: '1725b460c3e0de97590cb8764df9ace9115b813e' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:08.032127) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:08.032455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 09:29:08.054387) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 09:29:08.054777) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:08 INFO - MultiFileLogger online at 20160407 09:29:08 in /builds/slave/test 09:29:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:29:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:08 INFO - {'append_to_log': False, 09:29:08 INFO - 'base_work_dir': '/builds/slave/test', 09:29:08 INFO - 'blob_upload_branch': 'Fx-Team', 09:29:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:08 INFO - 'branch': 'Fx-Team', 09:29:08 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:08 INFO - 'config_files': ('talos/mac_config.py',), 09:29:08 INFO - 'default_actions': ('clobber', 09:29:08 INFO - 'read-buildbot-config', 09:29:08 INFO - 'download-and-extract', 09:29:08 INFO - 'populate-webroot', 09:29:08 INFO - 'create-virtualenv', 09:29:08 INFO - 'install', 09:29:08 INFO - 'run-tests'), 09:29:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:08 INFO - 'download_minidump_stackwalk': True, 09:29:08 INFO - 'download_symbols': 'ondemand', 09:29:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:08 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:08 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:08 INFO - 'installer_path': 'installer.exe', 09:29:08 INFO - 'log_level': 'info', 09:29:08 INFO - 'log_name': 'talos', 09:29:08 INFO - 'log_to_console': True, 09:29:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:08 INFO - 'opt_config_files': (), 09:29:08 INFO - 'pip_index': False, 09:29:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:08 INFO - 'cmd': ('bash', 09:29:08 INFO - '-c', 09:29:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:08 INFO - 'enabled': True, 09:29:08 INFO - 'halt_on_failure': False, 09:29:08 INFO - 'name': 'check_screen_resolution'},), 09:29:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:08 INFO - 'cmd': ('bash', 09:29:08 INFO - '-c', 09:29:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:08 INFO - 'enabled': True, 09:29:08 INFO - 'halt_on_failure': False, 09:29:08 INFO - 'name': 'check_screen_resolution'},), 09:29:08 INFO - 'run_cmd_checks_enabled': True, 09:29:08 INFO - 'sps_profile': False, 09:29:08 INFO - 'sps_profile_interval': 0, 09:29:08 INFO - 'suite': 'dromaeojs', 09:29:08 INFO - 'system_bits': '32', 09:29:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:08 INFO - 'title': 't-yosemite-r7-0140', 09:29:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:08 INFO - 'use_talos_json': True, 09:29:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:08 INFO - 'work_dir': 'build'} 09:29:08 INFO - ##### 09:29:08 INFO - ##### Running clobber step. 09:29:08 INFO - ##### 09:29:08 INFO - Running pre-action listener: _resource_record_pre_action 09:29:08 INFO - Running main action method: clobber 09:29:08 INFO - rmtree: /builds/slave/test/build 09:29:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:10 INFO - Running post-action listener: _resource_record_post_action 09:29:10 INFO - ##### 09:29:10 INFO - ##### Running read-buildbot-config step. 09:29:10 INFO - ##### 09:29:10 INFO - Running pre-action listener: _resource_record_pre_action 09:29:10 INFO - Running main action method: read_buildbot_config 09:29:10 INFO - Using buildbot properties: 09:29:10 INFO - { 09:29:10 INFO - "project": "", 09:29:10 INFO - "product": "firefox", 09:29:10 INFO - "script_repo_revision": "production", 09:29:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:29:10 INFO - "repository": "", 09:29:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:29:10 INFO - "buildid": "20160407091415", 09:29:10 INFO - "stage_platform": "macosx64", 09:29:10 INFO - "basedir": "/builds/slave/test", 09:29:10 INFO - "buildnumber": 586, 09:29:10 INFO - "platform": "yosemite_r7", 09:29:10 INFO - "mozci_request": { 09:29:10 INFO - "priority": -1, 09:29:10 INFO - "type": "trigger_all_talos_jobs", 09:29:10 INFO - "times": 6 09:29:10 INFO - }, 09:29:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:29:10 INFO - "slavebuilddir": "test", 09:29:10 INFO - "branch": "fx-team", 09:29:10 INFO - "revision": "1725b460c3e0de97590cb8764df9ace9115b813e", 09:29:10 INFO - "slavename": "t-yosemite-r7-0140", 09:29:10 INFO - "builduid": "bcb111c56ea04b73b2a00670f058fa8e", 09:29:10 INFO - "repo_path": "integration/fx-team" 09:29:10 INFO - } 09:29:10 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg. 09:29:10 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json. 09:29:10 INFO - Running post-action listener: _resource_record_post_action 09:29:10 INFO - ##### 09:29:10 INFO - ##### Running download-and-extract step. 09:29:10 INFO - ##### 09:29:10 INFO - Running pre-action listener: _resource_record_pre_action 09:29:10 INFO - Running main action method: download_and_extract 09:29:10 INFO - mkdir: /builds/slave/test/build/tests 09:29:10 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')]} 09:29:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json 09:29:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:29:10 INFO - Downloaded 1199 bytes. 09:29:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:29:10 INFO - Using the following test package requirements: 09:29:10 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:29:10 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:29:10 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:29:10 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'jsshell-mac.zip'], 09:29:10 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:29:10 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:29:10 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:29:10 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:29:10 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:29:10 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:29:10 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:29:10 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:29:10 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')]} 09:29:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:10 INFO - Downloaded 17970713 bytes. 09:29:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:29:13 INFO - Return code: 0 09:29:13 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:14 INFO - Downloaded 17970713 bytes. 09:29:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:29:17 INFO - Return code: 0 09:29: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')]} 09:29:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:29:18 INFO - Downloaded 10847617 bytes. 09:29:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:29:18 INFO - Return code: 0 09:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:29:19 INFO - Downloaded 108918030 bytes. 09:29:19 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:19 INFO - mkdir: /builds/slave/test/properties 09:29:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:19 INFO - Contents: 09:29:19 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg 09:29:19 INFO - Running post-action listener: _resource_record_post_action 09:29:19 INFO - Running post-action listener: set_extra_try_arguments 09:29:19 INFO - ##### 09:29:19 INFO - ##### Running populate-webroot step. 09:29:19 INFO - ##### 09:29:19 INFO - Running pre-action listener: _resource_record_pre_action 09:29:19 INFO - Running main action method: populate_webroot 09:29:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:19 INFO - '--tppagecycles', 09:29:19 INFO - '7'], 09:29:19 INFO - 'tests': ['tsvgm']}}, 09:29:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['damp', 'tps']}, 09:29:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'tests': ['damp', 'tps']}, 09:29:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['dromaeo_dom']}, 09:29:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['a11yr', 09:29:19 INFO - 'ts_paint', 09:29:19 INFO - 'tpaint', 09:29:19 INFO - 'sessionrestore', 09:29:19 INFO - 'sessionrestore_no_auto_restore', 09:29:19 INFO - 'tabpaint']}, 09:29:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['tsvgx', 09:29:19 INFO - 'tsvgr_opacity', 09:29:19 INFO - 'tart', 09:29:19 INFO - 'tscrollx', 09:29:19 INFO - 'cart']}, 09:29:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:19 INFO - 'tsvgr_opacity', 09:29:19 INFO - 'tart', 09:29:19 INFO - 'tscrollx', 09:29:19 INFO - 'cart']}, 09:29:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'talos_options': ['--disable-e10s'], 09:29:19 INFO - 'tests': ['tp5o']}, 09:29:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'tests': ['tp5o']}, 09:29:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'talos_options': ['--disable-e10s', 09:29:19 INFO - '--xperf_path', 09:29:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:19 INFO - 'tests': ['tp5n']}, 09:29:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:19 INFO - 'talos_options': ['--xperf_path', 09:29:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:19 INFO - 'tests': ['tp5n']}}, 09:29:19 INFO - 'talos.zip': {'path': '', 09:29:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:19 INFO - Running post-action listener: _resource_record_post_action 09:29:19 INFO - ##### 09:29:19 INFO - ##### Running create-virtualenv step. 09:29:19 INFO - ##### 09:29:19 INFO - Running pre-action listener: _resource_record_pre_action 09:29:19 INFO - Running main action method: create_virtualenv 09:29:19 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:19 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 09:29:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:19 INFO - Using real prefix '/tools/python27' 09:29:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:23 INFO - Installing pip.................done. 09:29:23 INFO - Return code: 0 09:29:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:23 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 09:29:23 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 09:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:23 INFO - 'HOME': '/Users/cltbld', 09:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:23 INFO - 'LOGNAME': 'cltbld', 09:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:23 INFO - 'MOZ_NO_REMOTE': '1', 09:29:23 INFO - 'NO_EM_RESTART': '1', 09:29:23 INFO - 'PAGER': '/bin/cat', 09:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:23 INFO - 'PWD': '/builds/slave/test', 09:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:23 INFO - 'SHELL': '/bin/bash', 09:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:23 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:23 INFO - 'USER': 'cltbld', 09:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:23 INFO - 'XPC_FLAGS': '0x0', 09:29:23 INFO - 'XPC_SERVICE_NAME': '0', 09:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:23 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 09:29:23 INFO - Cleaning up... 09:29:23 INFO - Return code: 0 09:29:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:23 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 09:29:23 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 09:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:23 INFO - 'HOME': '/Users/cltbld', 09:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:23 INFO - 'LOGNAME': 'cltbld', 09:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:23 INFO - 'MOZ_NO_REMOTE': '1', 09:29:23 INFO - 'NO_EM_RESTART': '1', 09:29:23 INFO - 'PAGER': '/bin/cat', 09:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:23 INFO - 'PWD': '/builds/slave/test', 09:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:23 INFO - 'SHELL': '/bin/bash', 09:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:23 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:23 INFO - 'USER': 'cltbld', 09:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:23 INFO - 'XPC_FLAGS': '0x0', 09:29:23 INFO - 'XPC_SERVICE_NAME': '0', 09:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:23 INFO - Downloading/unpacking psutil>=0.7.1 09:29:23 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 09:29:23 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 09:29:23 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 09:29:23 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 09:29:23 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 09:29:23 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 09:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:27 INFO - Installing collected packages: psutil 09:29:27 INFO - Running setup.py install for psutil 09:29:27 INFO - building 'psutil._psutil_osx' extension 09:29:27 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 09:29:27 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 09:29:27 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 09:29:27 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 09:29:27 INFO - building 'psutil._psutil_posix' extension 09:29:27 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 09:29:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:27 INFO - ^ 09:29:27 INFO - 1 warning generated. 09:29:27 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 09:29:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:27 INFO - Successfully installed psutil 09:29:27 INFO - Cleaning up... 09:29:28 INFO - Return code: 0 09:29:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:29:28 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')]} 09:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:28 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')]} 09:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:28 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:28 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 09:29:28 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 09:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:28 INFO - 'HOME': '/Users/cltbld', 09:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:28 INFO - 'LOGNAME': 'cltbld', 09:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:28 INFO - 'MOZ_NO_REMOTE': '1', 09:29:28 INFO - 'NO_EM_RESTART': '1', 09:29:28 INFO - 'PAGER': '/bin/cat', 09:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:28 INFO - 'PWD': '/builds/slave/test', 09:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:28 INFO - 'SHELL': '/bin/bash', 09:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:28 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:28 INFO - 'USER': 'cltbld', 09:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:28 INFO - 'XPC_FLAGS': '0x0', 09:29:28 INFO - 'XPC_SERVICE_NAME': '0', 09:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29: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 09:29:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:29:30 INFO - Installing collected packages: mozsystemmonitor 09:29:30 INFO - Running setup.py install for mozsystemmonitor 09:29:30 INFO - Successfully installed mozsystemmonitor 09:29:30 INFO - Cleaning up... 09:29:30 INFO - Return code: 0 09:29:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:30 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:30 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 09:29:30 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 09:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:30 INFO - 'HOME': '/Users/cltbld', 09:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:30 INFO - 'LOGNAME': 'cltbld', 09:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:30 INFO - 'MOZ_NO_REMOTE': '1', 09:29:30 INFO - 'NO_EM_RESTART': '1', 09:29:30 INFO - 'PAGER': '/bin/cat', 09:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:30 INFO - 'PWD': '/builds/slave/test', 09:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:30 INFO - 'SHELL': '/bin/bash', 09:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:30 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:30 INFO - 'USER': 'cltbld', 09:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:30 INFO - 'XPC_FLAGS': '0x0', 09:29:30 INFO - 'XPC_SERVICE_NAME': '0', 09:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:31 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 09:29:31 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 09:29:31 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 09:29:31 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 09:29:31 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 09:29:31 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 09:29:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:33 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 09:29:33 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 09:29:33 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 09:29:33 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 09:29:33 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 09:29:33 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 09:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 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 09:29:34 INFO - Downloading docopt-0.6.1.tar.gz 09:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:34 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:34 INFO - Running setup.py install for blobuploader 09:29:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:35 INFO - Running setup.py install for requests 09:29:35 INFO - Running setup.py install for docopt 09:29:35 INFO - Successfully installed blobuploader requests docopt 09:29:35 INFO - Cleaning up... 09:29:35 INFO - Return code: 0 09:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:35 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:35 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 09:29:35 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 09:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:35 INFO - 'HOME': '/Users/cltbld', 09:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:35 INFO - 'LOGNAME': 'cltbld', 09:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:35 INFO - 'MOZ_NO_REMOTE': '1', 09:29:35 INFO - 'NO_EM_RESTART': '1', 09:29:35 INFO - 'PAGER': '/bin/cat', 09:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:35 INFO - 'PWD': '/builds/slave/test', 09:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:35 INFO - 'SHELL': '/bin/bash', 09:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:35 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:35 INFO - 'USER': 'cltbld', 09:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:35 INFO - 'XPC_FLAGS': '0x0', 09:29:35 INFO - 'XPC_SERVICE_NAME': '0', 09:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-zRzn9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-FqesD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-yYIQwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-XhHTCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-FFMefz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-UiFO4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-bD3__0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-45RCiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:36 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qdKKna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-H6nlkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-V2rlVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-hJ2gt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-jAaEp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-3_zprD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ilz3Zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-mBllmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6l2tGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:37 INFO - Running setup.py install for manifestparser 09:29:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:38 INFO - Running setup.py install for mozcrash 09:29:38 INFO - Running setup.py install for mozdebug 09:29:38 INFO - Running setup.py install for mozdevice 09:29:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:38 INFO - Running setup.py install for mozfile 09:29:38 INFO - Running setup.py install for mozhttpd 09:29:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:38 INFO - Running setup.py install for mozinfo 09:29:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:38 INFO - Running setup.py install for mozInstall 09:29:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Running setup.py install for mozleak 09:29:39 INFO - Running setup.py install for mozlog 09:29:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Running setup.py install for moznetwork 09:29:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Running setup.py install for mozprocess 09:29:39 INFO - Running setup.py install for mozprofile 09:29:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:39 INFO - Running setup.py install for mozrunner 09:29:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:40 INFO - Running setup.py install for mozscreenshot 09:29:40 INFO - Running setup.py install for moztest 09:29:40 INFO - Running setup.py install for mozversion 09:29:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:40 INFO - Cleaning up... 09:29:40 INFO - Return code: 0 09:29:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:40 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:40 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 09:29:40 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 09:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:40 INFO - 'HOME': '/Users/cltbld', 09:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:40 INFO - 'LOGNAME': 'cltbld', 09:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:40 INFO - 'MOZ_NO_REMOTE': '1', 09:29:40 INFO - 'NO_EM_RESTART': '1', 09:29:40 INFO - 'PAGER': '/bin/cat', 09:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:40 INFO - 'PWD': '/builds/slave/test', 09:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:40 INFO - 'SHELL': '/bin/bash', 09:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:40 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:40 INFO - 'USER': 'cltbld', 09:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:40 INFO - 'XPC_FLAGS': '0x0', 09:29:40 INFO - 'XPC_SERVICE_NAME': '0', 09:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:40 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-u_ujgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:40 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)) 09:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:40 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-y9wfxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-YgcFo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-4N7nIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-0rWYTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-a7FYFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6VWTpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-xcgALS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-hUca2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-olzUt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-peplZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:41 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)) 09:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:41 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-iW1ohs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:42 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)) 09:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-hIrx6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:42 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)) 09:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-57iE4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:42 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)) 09:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-nUwW3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:42 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)) 09:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Var4du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:42 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)) 09:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-TRQKnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:42 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)) 09:29:42 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)) 09:29:42 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)) 09:29:42 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)) 09:29:42 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)) 09:29:42 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)) 09:29:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:42 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 09:29:42 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 09:29:42 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 09:29:42 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 09:29:42 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 09:29:42 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 09:29:44 INFO - Downloading blessings-1.6.tar.gz 09:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:45 INFO - Installing collected packages: blessings 09:29:45 INFO - Running setup.py install for blessings 09:29:45 INFO - Successfully installed blessings 09:29:45 INFO - Cleaning up... 09:29:45 INFO - Return code: 0 09:29:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:45 INFO - Reading from file tmpfile_stdout 09:29:45 INFO - Current package versions: 09:29:45 INFO - blessings == 1.6 09:29:45 INFO - blobuploader == 1.2.4 09:29:45 INFO - docopt == 0.6.1 09:29:45 INFO - manifestparser == 1.1 09:29:45 INFO - mozInstall == 1.12 09:29:45 INFO - mozcrash == 0.17 09:29:45 INFO - mozdebug == 0.1 09:29:45 INFO - mozdevice == 0.48 09:29:45 INFO - mozfile == 1.2 09:29:45 INFO - mozhttpd == 0.7 09:29:45 INFO - mozinfo == 0.9 09:29:45 INFO - mozleak == 0.1 09:29:45 INFO - mozlog == 3.1 09:29:45 INFO - moznetwork == 0.27 09:29:45 INFO - mozprocess == 0.22 09:29:45 INFO - mozprofile == 0.28 09:29:45 INFO - mozrunner == 6.11 09:29:45 INFO - mozscreenshot == 0.1 09:29:45 INFO - mozsystemmonitor == 0.0 09:29:45 INFO - moztest == 0.7 09:29:45 INFO - mozversion == 1.4 09:29:45 INFO - psutil == 3.1.1 09:29:45 INFO - requests == 1.2.3 09:29:45 INFO - wsgiref == 0.1.2 09:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:45 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')]} 09:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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')]} 09:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:45 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 09:29:45 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 09:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:45 INFO - 'HOME': '/Users/cltbld', 09:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:45 INFO - 'LOGNAME': 'cltbld', 09:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:45 INFO - 'MOZ_NO_REMOTE': '1', 09:29:45 INFO - 'NO_EM_RESTART': '1', 09:29:45 INFO - 'PAGER': '/bin/cat', 09:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:45 INFO - 'PWD': '/builds/slave/test', 09:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:45 INFO - 'SHELL': '/bin/bash', 09:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:45 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:45 INFO - 'USER': 'cltbld', 09:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:45 INFO - 'XPC_FLAGS': '0x0', 09:29:45 INFO - 'XPC_SERVICE_NAME': '0', 09:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 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)) 09:29:45 INFO - Cleaning up... 09:29:45 INFO - Return code: 0 09:29:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:45 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')]} 09:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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')]} 09:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:45 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 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:45 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 09:29:45 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 09:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:45 INFO - 'HOME': '/Users/cltbld', 09:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:45 INFO - 'LOGNAME': 'cltbld', 09:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:45 INFO - 'MOZ_NO_REMOTE': '1', 09:29:45 INFO - 'NO_EM_RESTART': '1', 09:29:45 INFO - 'PAGER': '/bin/cat', 09:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:45 INFO - 'PWD': '/builds/slave/test', 09:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:45 INFO - 'SHELL': '/bin/bash', 09:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:45 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:45 INFO - 'USER': 'cltbld', 09:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:45 INFO - 'XPC_FLAGS': '0x0', 09:29:45 INFO - 'XPC_SERVICE_NAME': '0', 09:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:46 INFO - Downloading/unpacking jsonschema 09:29:46 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 09:29:46 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 09:29:46 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 09:29:46 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 09:29:46 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 09:29:46 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 09:29:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:48 INFO - Downloading/unpacking functools32 (from jsonschema) 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 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 09:29:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:48 INFO - warning: no files found matching '*.txt' 09:29:48 INFO - no previously-included directories found matching 'build' 09:29:48 INFO - no previously-included directories found matching 'dist' 09:29:48 INFO - no previously-included directories found matching '.git*' 09:29:49 INFO - Installing collected packages: jsonschema, functools32 09:29:49 INFO - Running setup.py install for functools32 09:29:49 INFO - warning: no files found matching '*.txt' 09:29:49 INFO - no previously-included directories found matching 'build' 09:29:49 INFO - no previously-included directories found matching 'dist' 09:29:49 INFO - no previously-included directories found matching '.git*' 09:29:49 INFO - Successfully installed jsonschema functools32 09:29:49 INFO - Cleaning up... 09:29:49 INFO - Return code: 0 09:29:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10349ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd104c07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, '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.K4QMPaebsq/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.x5AsVDgAEk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 09:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:49 INFO - 'HOME': '/Users/cltbld', 09:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:49 INFO - 'LOGNAME': 'cltbld', 09:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:49 INFO - 'MOZ_NO_REMOTE': '1', 09:29:49 INFO - 'NO_EM_RESTART': '1', 09:29:49 INFO - 'PAGER': '/bin/cat', 09:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:49 INFO - 'PWD': '/builds/slave/test', 09:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:49 INFO - 'SHELL': '/bin/bash', 09:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:29:49 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:49 INFO - 'USER': 'cltbld', 09:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:49 INFO - 'XPC_FLAGS': '0x0', 09:29:49 INFO - 'XPC_SERVICE_NAME': '0', 09:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:49 INFO - Downloading/unpacking flake8 09:29:49 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 09:29:49 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 09:29:49 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 09:29:49 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 09:29:49 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 09:29:49 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 09:29:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:29:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:29:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:52 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 09:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:29:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:29:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:29:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:29:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:29:53 INFO - no previously-included directories found matching 'docs/_build' 09:29:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:29:53 INFO - Running setup.py install for pep8 09:29:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:29:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:29:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:29:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:29:53 INFO - no previously-included directories found matching 'docs/_build' 09:29:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:29:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:29:53 INFO - Cleaning up... 09:29:53 INFO - Return code: 0 09:29:53 INFO - Running post-action listener: _resource_record_post_action 09:29:53 INFO - Running post-action listener: _start_resource_monitoring 09:29:53 INFO - Starting resource monitoring. 09:29:53 INFO - ##### 09:29:53 INFO - ##### Running install step. 09:29:53 INFO - ##### 09:29:53 INFO - Running pre-action listener: _resource_record_pre_action 09:29:53 INFO - Running main action method: install 09:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:53 INFO - Reading from file tmpfile_stdout 09:29:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:54 INFO - Reading from file tmpfile_stdout 09:29:54 INFO - Output received: 09:29:54 INFO - Usage: mozinstall [options] installer 09:29:54 INFO - Options: 09:29:54 INFO - -h, --help show this help message and exit 09:29:54 INFO - -d DEST, --destination=DEST 09:29:54 INFO - Directory to install application into. [default: 09:29:54 INFO - "/builds/slave/test"] 09:29:54 INFO - --app=APP Application being installed. [default: firefox] 09:29:54 INFO - mkdir: /builds/slave/test/build/application 09:29:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:30:27 INFO - Reading from file tmpfile_stdout 09:30:27 INFO - Output received: 09:30:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:27 INFO - Running post-action listener: _resource_record_post_action 09:30:27 INFO - ##### 09:30:27 INFO - ##### Running run-tests step. 09:30:27 INFO - ##### 09:30:27 INFO - Running pre-action listener: _resource_record_pre_action 09:30:27 INFO - Running main action method: run_tests 09:30:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:27 INFO - 2016-04-07 09:30:27.574 screenresolution[2249:65014] starting screenresolution argv=screenresolution get 09:30:27 INFO - 2016-04-07 09:30:27.577 screenresolution[2249:65014] Display 0: 1600x1200x32@60 09:30:27 INFO - 2016-04-07 09:30:27.583 screenresolution[2250:65018] starting screenresolution argv=screenresolution list 09:30:27 INFO - Available Modes on Display 0 09:30:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:27 INFO - Intel Iris: 09:30:27 INFO - Chipset Model: Intel Iris 09:30:27 INFO - Type: GPU 09:30:27 INFO - Bus: Built-In 09:30:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:27 INFO - Vendor: Intel (0x8086) 09:30:27 INFO - Device ID: 0x0a2e 09:30:27 INFO - Revision ID: 0x0009 09:30:27 INFO - Displays: 09:30:27 INFO - Display: 09:30:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:27 INFO - Display Serial Number: 1600x1200 60 09:30:27 INFO - Main Display: Yes 09:30:27 INFO - Mirror: Off 09:30:27 INFO - Online: Yes 09:30:27 INFO - Rotation: Supported 09:30:27 INFO - Return code: 0 09:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:27 INFO - Python 2.7.3 09:30:27 INFO - Return code: 0 09:30:27 INFO - grabbing minidump binary from tooltool 09:30:27 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')]} 09:30:27 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 0x10333ae00>, '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 0x103163db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103335b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:27 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 09:30:27 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 09:30:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:27 INFO - Return code: 0 09:30:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:30:29 INFO - Return code: 0 09:30:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 09:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0140 --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 09:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K4QMPaebsq/Render', 09:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:29 INFO - 'HOME': '/Users/cltbld', 09:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:29 INFO - 'LOGNAME': 'cltbld', 09:30:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:29 INFO - 'MOZ_NO_REMOTE': '1', 09:30:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:29 INFO - 'NO_EM_RESTART': '1', 09:30:29 INFO - 'PAGER': '/bin/cat', 09:30:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:29 INFO - 'PWD': '/builds/slave/test', 09:30:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:29 INFO - 'SHELL': '/bin/bash', 09:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.x5AsVDgAEk/Listeners', 09:30:29 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 09:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:29 INFO - 'USER': 'cltbld', 09:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:29 INFO - 'XPC_FLAGS': '0x0', 09:30:29 INFO - 'XPC_SERVICE_NAME': '0', 09:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 09:30:29 INFO - mozversion application_buildid: 20160406110850 09:30:29 INFO - mozversion application_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:29 INFO - mozversion application_display_name: Nightly 09:30:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:29 INFO - mozversion application_name: Firefox 09:30:29 INFO - mozversion application_remotingname: firefox 09:30:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:30:29 INFO - mozversion application_vendor: Mozilla 09:30:29 INFO - mozversion application_version: 48.0a1 09:30:29 INFO - mozversion platform_buildid: 20160406110850 09:30:29 INFO - mozversion platform_changeset: 1725b460c3e0de97590cb8764df9ace9115b813e 09:30:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:30:29 INFO - mozversion platform_version: 48.0a1 09:30:29 INFO - using testdate: 1460046629 09:30:29 INFO - actual date: 1460046629 09:30:29 INFO - starting webserver on 'localhost:50975' 09:30:29 INFO - SUITE-START | Running 2 tests 09:30:29 INFO - TEST-START | dromaeo_css 09:30:29 INFO - operating with platform_type : mac_ 09:30:29 INFO - Initialising browser for dromaeo_css test... 09:30:29 INFO - TEST-INFO | started process 2268 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp5F3nJe/profile http://localhost:50975/getInfo.html) 09:30:31 INFO - PROCESS | 2268 | __metrics Screen width/height:1600/1200 09:30:31 INFO - PROCESS | 2268 | colorDepth:24 09:30:31 INFO - PROCESS | 2268 | Browser inner width/height: 1024/689 09:30:31 INFO - PROCESS | 2268 | __metrics 09:30:31 INFO - PROCESS | 2268 | 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" 09:30:31 ERROR - PROCESS | 2268 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:30:31 INFO - TEST-INFO | 2268: exit 0 09:30:31 INFO - Browser initialized. 09:30:31 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:31 INFO - TEST-INFO | started process 2270 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp5F3nJe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:43 INFO - PROCESS | 2270 | RSS: Main: 236425216 09:30:43 INFO - PROCESS | 2270 | 09:31:35 INFO - PROCESS | 2270 | 1460046695028 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] 09:33:45 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50975/tests/dromaeo/cssquery-ext.html) 09:33:46 INFO - PROCESS | 2270 | RSS: Main: 323149824 09:33:46 INFO - PROCESS | 2270 | 09:36:48 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-ext.html (next: http://localhost:50975/tests/dromaeo/cssquery-jquery.html) 09:36:49 INFO - PROCESS | 2270 | RSS: Main: 368852992 09:36:49 INFO - PROCESS | 2270 | 09:39:51 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50975/tests/dromaeo/cssquery-mootools.html) 09:39:51 INFO - PROCESS | 2270 | RSS: Main: 368668672 09:39:51 INFO - PROCESS | 2270 | 09:42:54 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50975/tests/dromaeo/cssquery-prototype.html) 09:42:54 INFO - PROCESS | 2270 | RSS: Main: 325943296 09:42:54 INFO - PROCESS | 2270 | 09:45:57 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50975/tests/dromaeo/cssquery-yui.html) 09:45:58 INFO - PROCESS | 2270 | RSS: Main: 326279168 09:45:58 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | Cycle 1(1): loaded http://localhost:50975/tests/dromaeo/cssquery-yui.html (next: http://localhost:50975/tests/dromaeo/cssquery-dojo.html) 09:49:01 INFO - PROCESS | 2270 | RSS: Main: 375623680 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | __start_tp_report 09:49:01 INFO - PROCESS | 2270 | _x_x_mozilla_page_load 09:49:01 INFO - PROCESS | 2270 | _x_x_mozilla_page_load_details 09:49:01 INFO - PROCESS | 2270 | |i|pagename|runs| 09:49:01 INFO - PROCESS | 2270 | |0;dojo.html;3348.9665103348966;3435.072530416786;3448.051785758916;3465.60145583258;3492.7904325740456;5544.16837474379;5596.300462442195;5610.551155907523;5611.130274807406;5655.3779084300695;5625.156226566017;5684.204211410406;5698.230738850254;5698.629589076718;5708.1437784332375;5092.567131793793;5119.539241468267;5130.486951304877;5156.071907056728;5194.506521880434;4405.559444055585;5099.898002039976;5204.959008198357;5210.088234558956;5213.113770658978;5127.641065125443;5152.896942061157;5160.845174644766;5174.922376164361;5191.714455704935;5179.585633149358;5217.165253559431;5220.086484865134;5238.161894096965;5271.4992075753125;5056.570191533724;5088.160453525164;5101.183810590288;5156.948430515685;5167.896642067138;4603.815847366135;5537.723113844332;5588.0779671354585;5617.269754931833;5695;6194.81415557534;6201.472874805647;6203.472704820096;6205.286392064925;6214.68926553675;6090.488975835674;6146;6317;6320.62076275425;6329.968350158266;5763.308402991668;5782.277215348082;5782.3639399666;5786.623869448473;5804.854878628026;6370.331115232926;6371.076193951851;6371.490280777527;6373.490120790419;6380;5954.225950626391;5990.460858522667;6030.758769649253;6102.206713127265;6119.143319935212;6216.285127210338;6234.470070044006;6299.275583307886;6301.117843501914;6310.4636105930595;6140.109684095781;6202.4417802398;6208.751649934043;6219.098230756515;6223.96888015557;5189.6886186828915;6080.665563394055;6105.64170830808;6170.290416602148;6236.906446403307;4862.878428039327;4865.294532292798;4877.170880950176;4908.165611845995;4917.704937703749;4413.955860441419;4416.006398560363;4434.866953991385;4437.312216606431;4469.5530446955045;4598.333241679938;5521.116621340647;5547.195656629766;5590.105583106683;5627.409122042209;5111.5655169310285;6199.628022318675;6248.4376406123665;6259.311475737665;6269.059641053878;5488.1493368527945;5542.75057622408;5548.639338443069;5559.916601250984;5562.082256427644;5245.895082098336;5298.152295632757;5348.652337598043;5359.892802143935;5402.108652072443;6297.779577714849;6322.589031712924;6324.430801227912;6326;6334.049892516159;6117.724702388314;6275.02737075754;6299.96850015747;6305.716242768995;6308.400701933309;6255.280642726145;6309.148264984352;6346.587471814318;6357.872842543031;6357.96821015892;6138.539609529393;6157.630542167484;6175.722092505758;6183.505319574513;6203.844903877439;6092.116643086816;6188.690565471618;6263.906041409291;6268.498520118471;6272;6064.727087281171;6118.162361256354;6118.984682626333;6129.603311142764;6134.069235337226;6104.8779024420155;6106.1451396803595;6108.694565271808;6112.358202388685;6133.202683651097;4712.363830882787;5717.571182161271;5897.64614123154;5948.4943779779205;5960.016597261403;5878.647281163144;5886.440788125121;6012;6048.939510604838;6112.297085835008;5878.265216847894;5904.911426328695;5909.172715819703;5918.644881307136;5922.55580831448 09:49:01 INFO - PROCESS | 2270 | |1;ext.html;489.81387072912287;543.1689515041986;575.551070165271;610.4414460768397;623.1867413026;37066.258674826524;38116;38189.80905095474;38214.61785382145;38282.808585957064;39588.60411395885;39778.80110599443;39930.00174995627;39937.40093898587;40182.598174018254;9397.1072748089;9620.518974051305;9638.228941684649;9639.879226570143;9654.662086826962;9592.568334424968;9762.560684769167;9771.413715177077;9771.804563908718;9780.413175209475;9719.56261968213;9779.804403911918;9784;9798.902010979906;9799.265055120859;9665.565049572786;9762.658306959222;9765.560549775242;9765.951170244103;9774.364666296668;9405.952970235105;9584.808303833954;9587.568559414844;9596.088371604686;9605.231581473465;37753.43369849455;38111.80944095289;38137.61862381369;38193;38205.42691859624;3122.8750849965973;3183.7453003759874;3288.917777055593;3340.43212653847;3363.6972672459597;25486.617700734503;28642.427151456854;29245.415091698258;29990.400191996257;29999.950001749836;2727.304537342965;2781.123945957017;2785.958210626842;2815.296175956011;2818.6758522769733;421987.89006054774;487826;489262;489818;490025;61604.38395616076;62251;62274;62281;62446.37553624406;63136.684316578125;64892.67553662202;65975.68048639;66153.33846661568;66155.33844661588;16930.830691692925;17191.656166876593;17192.65614687724;17208.397706080228;17230.310787568356;10937.398443085707;12888.48446062147;12932.41804118817;12942.676433089247;12996;18871.19199236042;19193.848369097897;19211.807881921282;19239.711404328948;19372.612547748966;6883.795335816212;7642.4650274480255;7754.5734984575265;7879.803004924923;7937.920620793833;859.3125499600295;878.1701292278863;890.9483230085431;970.2141265574766;1100.2958106811486;28358.432831343256;28629.713702862704;29372.97194598179;29421.26446838803;29427;982.916452101565;1082.99458502707;1095.9832214932185;1124.7075760302216;1190.0182349561646;11411.900468085638;14644.487442939446;14840;15044.623884402978;15199.848001519844;531073;535313;536920;537044;537200;57577.424225757204;57596.27211183671;57701;57792;57919.55201120006;1300.479808076758;1329.3951252180234;1412.3644360038024;1449.340550049725;1510.4562357551054;23988.88005559961;25642.46145231292;29201.558908764375;29503.704962950094;29785.5532167022;45910;46330.07339853251;46484.837879053295;46532;46548.06903861836;374273;376190;376433;376790;376872;30771;32139;32148.87478938226;32157.35685286328;32236.03291901247;33580.55705086081;40269.993250168984;43200;43675.56324436715;43906.780466097465;35738.92783216508;35783.28433431369;35827.283454331286;36516;36614.07087432946;34688.9593312201;34766;35327;35416.64583354237;35573.822130890214;35003.82498087493;35233;35583.82208088943;35961.92114236577;36060.639393606776;29141;30950.69049309568;31441.8427907859;31761.20596985094;31820.840895795372;17814.91092544529;18995.620087598447;22827.31518054461;23948.40128996789;24449.266522004367 09:49:01 INFO - PROCESS | 2270 | |2;jquery.html;365.06725316815533;365.5010910107702;366.15235729286707;373.8859647807419;377.9773213607182;19929.103190356433;20104.597908041847;20405.429049916278;21241.93790310486;21250.256241031566;19709;20004.699929501032;20385.388438346836;20404.285849995253;21467.892660536716;5815.767369305223;6050.304215015286;6089.878202435949;6100.81697549072;6101.0848372744;5079.3650793650795;6057.091436284571;6059.515238780887;6071.058985857199;6095.146679464856;6022.578419510636;6034.4568988791;6037.30570984338;6038.245219347582;6060.090986352038;6091.238595175603;6505.967470162666;6613.140291762064;6677.499187560952;6687.197536295627;5775.451332123442;5857.26784151981;5872.823815285548;5875.676837773921;5881.176635271066;13508.729825403536;13886;13919;13933.30333483332;13945;2034.216826521785;2088.9268875589282;2090.780468050848;2098.8845613491253;2100.12844669462;9600.42397456148;9668.419894806335;9741.610335586569;9922.50387480623;10123;1687.4853169783212;1724.465415721118;1746.2840235503509;1761.445144779403;1791.3282519055354;96885.51557242169;100802;108333;109789.45105274422;110360;10926.399048052433;10926.836097458545;10944.671659850217;11046.889531104747;11149.944250278697;13464;13502.392392342368;13668.93165534166;13706.314684265746;13722.862771372158;8261.339092872557;8292.04641466239;8377.078521362646;8477.576121193915;8500.064992850781;8308.916910830936;8414.537200454033;8493.27807136388;8525.786855328543;8635.050144484101;4314.417553630283;4397.648188144941;4413.653835580179;4429.977850110793;4455.576720211575;1798.3346161920174;1837.9172937217857;1840.3006857394105;1840.926362945494;1846.1784505895016;826.801567623766;894.9418287811271;902.9819403611891;931.2643012020563;996.3573495095768;16242.837571624132;20200.393988180378;20347.69478457824;20389.694154587694;20592.58814823695;946.3091942881734;966.2173639352147;968.3995922528078;992.0674565908023;1008.0434295390563;10963.83554246687;11166.300219868013;11201.215914886043;11227.157963152797;11293.71765705864;572695;596756;609080;611227;625414;121101;121608;123179.3841030789;126261.36869315595;127401.3629931863;1067.3755852826184;1074.6561100447939;1093.3494570730402;1094.0919037199105;1095.594629986894;14946.850531494982;17631.471055868344;18331.266749329876;18696.6260674783;19176.8082319175;53158;53314;53334.46665533295;55340.44659553514;55401.168982464485;60361.39638603733;61967.90160491867;72545;77040.61479692566;77085;7689.577073260801;7886.5409899168835;7917.643706033357;8025;8257.79355516117;9161.49611771359;11864.406779661154;12220.938895305466;12385.62843114708;12613.87386126127;9696.981816909123;10847.186461015297;10956.12351011933;11191.832122518006;11211.943940280247;10710.196735244732;10732.785344293226;10802.567897284021;10976.067034301854;11127.165462590176;6535.477161827138;10711.196660250669;11163.776724465628;11297.7740445189;11468.770624587294;7408;7801.375889928906;7833.295003449717;7836.294733473786;8020.238077382639;9747.707568772948;10878.510467028844;11115.166362522681;11133.276337038067;11443.25618834773 09:49:01 INFO - PROCESS | 2270 | |3;mootools.html;1405.3465138710499;1454.6508837878907;1539.9923000385;1737.2269340143632;1927.566297583044;7947.880781788271;8650;8789.560521973899;8792.78018049549;8798.736037918856;8492.66029358825;8751.518666473341;8759.167879051496;8761;8772;2143.732033495813;2192.6162921488753;2199.49411635324;2413.2518919135123;2453.681021467207;2414.058517178302;2461.6430617560445;2467.5311690778694;2469.506098780242;2469.5184439034397;2376.572217000939;2405.675233843426;2411.144043864429;2412.3124909400826;2437.5124975004983;2025.1899240303849;2409.373562873648;2427.4416884116663;2435.890384932682;2437.622168563875;2432.683751112344;2455.0916161020536;2456.2262887190477;2465.4452748131707;2469.987650061756;8741.475511469333;8817.559122043873;8822.514761688104;8828.955855220747;8864.20222180007;5353.277307563468;5429.511343979061;5470.316210473691;5476.890462190774;5520.144377621435;3419.760616756828;3424.143964008998;3558.946615800765;3573.8391772370305;3593.317269718745;1013.3160116921123;1020.285799940045;1038.5067093130779;1039.272509243525;1040.7866387390566;11724.70688232801;11728.472218750177;11768.352740599265;11769.176157668966;11784.823227651506;4270.530241673413;4689.729570696532;4800.567948884617;4872.975635121802;4882.267659851051;9883.752906177404;9932.602695892228;9970.551325190383;9970.700878973643;9991.500424978867;5422.810201643002;5448.073827449343;5449.5640348772;5452.645578037414;5456.781728730806;4449.555044495524;4563.429571303587;4777.402824646919;4973.229149481836;4985.15549246781;3825.2349530094093;3842.269968705937;3885.7668539887695;3892.5718171001167;3904.8438062477185;1264.1277518512381;1265.3989355056274;1276.8403949506312;1279.5777393460137;1296.092735085444;1340.7988801679826;1363.9181649101085;1366.4602482019548;1374.1960952842499;1396.218117854005;7894.21057894206;8236.135205803424;8236.876446853303;8242.423030387936;8288.21261980111;509.4548832748997;526.1345087331318;528.2393353570845;528.7012837746738;529.6716036057669;6197.194364732556;6208.844778880474;6262.248530176408;6265.185525881698;6282.089097080898;10401.167906567456;10750.193735469871;10885.83671244932;10899.29154604948;10902.836457453144;10538.998795114452;10550.577976880839;10681.786364272826;10770.246082774287;10772.569097236179;7046.682899269443;8266.338692904674;8278.793030174415;8371;8437.114103019094;7654.119776225805;7890.092639346319;7902.407319450949;7917.089534703583;7934.20657934202;9281.814363712823;9505.66730164441;9688.564014619218;9728.319017668695;9785.36395134314;9661.372010819274;9812.067853553901;9948.204143668345;9992.600295988079;10026.899731002597;6231.3145553988115;6510.544261901621;6720.932790672031;6923.9653801730665;7509.399248060033;7675.53946763196;7743.29647477554;7745.65144568507;7749.496282741829;8291.08798032204;7236.20401755817;7322.524035937647;7366.337029667356;7470.925290747023;7487.101547814079;7182.4613154014705;7188.101487314086;7295.343419092307;7316.853662926818;7344.779656610311;7503.949447077304;7547.811304717426;7667.769966901002;7699.461037727529;7710.11333696632;6907.481938854707;6908.827279318058;6948.270431604608;6949.270326615835;6955.443564514928;6957.443404527525;7012.368886800008;7050.435965122675;7055.788326350218;7057.294270572902 09:49:01 INFO - PROCESS | 2270 | |4;prototype.html;322.7788964559276;324.8099861580975;330.39537646107624;334.14458984998396;335.73476953206966;5513.669179849212;6128.804883047807;6405.391487808656;6412.134361861154;6451.870962580751;5651.745671444784;6032.245969253843;6347.650879201642;6390.297067322591;6421.8715625687455;4573.245414506602;4652.790624421892;4701.811927522912;4782.258749893772;4806.639502037361;4122.454079720105;4738.928916066262;4775.259834725605;4794.664373493857;4801.639877009221;4404.4934832494355;4748.121597504455;4752.500987396325;4765.618750499952;4772.928406073912;4497.9325310120375;4575.245084561044;4606.5393460654;4621.607163391116;4728.787204575802;4066.410370496291;4072.2059198456313;4077.408775727503;4097.42636030953;4105.712600117993;6149.046897730859;6163.691815409212;6167.167432396659;6184.5980011299125;6190.659513726743;5598.916016259719;5603.887922241533;5613.410591887834;5617.325920889479;5625.128105143668;6821.590704557742;6838.555493892931;6840.042394064785;6840.828979275509;6842.144731908512;4291.141771645684;4450.2434586120435;4491.842785502492;4501.437320334958;4511.796969136397;7021.859562808766;7377.225391333886;7382.335589796944;7384.556926584369;7390.741324053686;3859.421086836941;4486.6186374157905;4546.704464209815;4549.181147393435;4551.431071116111;4583.9083218335445;4595.586397224199;4609.9308510372375;4616.515265897033;4617.953820461819;4488.4165058542185;5391.436483419843;5424.945750542444;5425.728713564385;5437.564994800407;6599.769008084694;6820.488463365267;6878.449724022166;6881.174259088842;6902.516823822385;1565.2095691675631;1585.2153184173908;1594.4419453191522;1595.9680806383808;1598.9600259993592;1003.508280942333;1013.7921726046143;1027.3219675014454;1030.902064303905;1034.2760067952463;6133.417325354084;6145.784897528565;6270.467010304175;6280.623162610258;6321.905171422432;7099.574025558483;7105.609191494414;7106.680199391039;7117.395021423134;7159.212486626467;6297.464715499143;6323.27282362525;6331.556791024584;6350.301466838644;6362.427381535592;3705.9073523162137;3759.548854137466;3763.8494460221286;3776.169242766587;3791.8672846450795;7110.146782386147;7344.375728063174;7366.594837284001;7378.188399276075;7443.478956473104;7093.1488221413765;7308.232635573295;7356.705731770776;7362.926370736224;7371.88942165868;6294.937050629435;7417.332440080419;7419.888701669372;7420.814479637944;7423.665935032936;5891.58758886891;7076.221615622175;7131.393831524171;7136.3220494052985;7184.245654206442;6096.359882212304;6377.501287197598;7055.2944705529035;7060.376113494028;7089.468289878383;6362.300146983921;7010.47421443404;7420.146683131509;7431.777046688608;7438.367738742268;4528.230200865926;4568.908621827479;4574.085182963354;4576.244919588231;4580.221362368338;5879.676617785892;7128.358447739728;7130.750423735144;7148.928510714826;7151.141862976476;5540.891821635609;6533.0853680485725;6870.587764734132;6875.759348422781;6896.724131034703;6869.931300687129;6918.584884906922;6924.965375173092;6928.722851086101;6942.201646810479;6775.796726098225;6787.457003439812;6795.52431329822;6800.217974932946;6802.387785099365;6923.861522769617;6930.3762661360715;6931.202911665021;6939.930600694131;6942.826429339307;6457.935420645734;6515.73937042532;6648.966755166193;6787.966060169668;6803.9659801702655 09:49:01 INFO - PROCESS | 2270 | |5;yui.html;688.7245101959217;756.8486302739453;763.7441457111868;771.4522688890887;818.7216346442209;14110.435582576698;14285.21431321277;14307.78538321926;14308.284585770709;14392.352344144512;13047.934760326209;13101.47594096235;13121.212727236372;13137;13138.540151094714;868;898.0435835834847;900.4372267332917;900.837849187146;902.0032863685632;716.0369303287071;726.1612837173074;877.7235170921172;898.2050884966819;900.8693739407782;899.1413200393608;930.0559931669361;938.8310104181245;941.0448394879203;944.8441007233798;896.4666023715911;932.5011119051309;934.2525979216599;936.1574582875398;938.3994243684082;483.7470950238898;568.2073507457075;697.5988806436319;705.1643802094534;706.0891450029455;14326.068805527711;14389;14403.567892963229;14414.279286035657;14433;12330.445129969083;12405.069619778462;12443.688907777287;12605.432755526022;12709.491620335175;8516.148385161496;9844.261680374;9903;9968.302218844683;10305.021023002802;2984.85075746214;3513.2270900401872;3563.1448452371505;3582.140286331288;3614.7288953328343;71645.6417717908;71719.28280717178;71962.2803771966;72044.27955720428;72056.91914621285;9198.862017069749;9349.579268932914;9359;9630.422174669542;9648.228141748783;19902;20062.09720562549;20081;20110.195592176442;20158.29445969384;7515.887261691079;7528.1719010908755;7685.346745526581;7738.92261077382;7881.290683838482;3282.2778988622463;4088.366303223005;4143.212789569972;4156.397322388237;4263.8081286342185;3362.7982321060813;3483.5993860706344;3525.11872031992;3537.2041290709385;3538.929221415557;626.2797782550012;652.3802387731674;653.1965682210812;653.6111013946694;679.0832376292065;567.2568934695562;590.9704514774244;593.7031484257872;600.3366280260336;609.7164818359396;7731.92268077312;7891.842163156704;7989.360851131895;8173.550454724928;8287.295579875657;489.60586727683847;506.1623013911982;533.9572834173257;579.3887448741534;589.799468180812;3605.675489205984;3737.7383583149463;3797.8290976906105;3869.129445874712;4002.799860006988;83315;83696;85013.29973400498;85441;85567.14432855716;68300;71456.28543714496;71997.2800272001;72244;72283;62205;63039.99999999908;63042;63333.73332533416;64202;7420.777376678709;7540.736074237376;7590.924090759022;7653.732119375795;7841.764747057598;40992.59007410007;41474.37788433236;41613.16773664449;43877;44944;46715.065698686514;55057.449425505234;60262.39737602743;60680.69659651673;61314.38685613265;9856.359336643096;9950.601975921172;10005.449155380928;10654.14766818668;10862.565497380016;12054;12095.334756588472;12153.149279550347;12312.445939932904;12727.109102362745;8529.317654587494;11374.20380573352;11555.768884622428;11647.242929209575;11725.179237453638;9604.27967902396;9795.9020409795;10100.696979090639;10201.234907382124;12151.81772273399;11203.15976301797;11230.21388502797;11250.789106110435;11317.547298107984;11401.543938242377;9369.81260374775;9423.670171544238;9514.524273786421;9596.136347728738;9914.504274786375;7515.774526764206;7567.745540168425;9624.903750962401;9625.374350667184;9777.16894063984 09:49:01 INFO - PROCESS | 2270 | __end_tp_report 09:49:01 INFO - PROCESS | 2270 | __start_cc_report 09:49:01 INFO - PROCESS | 2270 | _x_x_mozilla_cycle_collect,1162 09:49:01 INFO - PROCESS | 2270 | __end_cc_report 09:49:01 INFO - PROCESS | 2270 | __startTimestamp1460047741341__endTimestamp 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | ------- Summary: start ------- 09:49:01 INFO - PROCESS | 2270 | Number of tests: 6 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#0] dojo.html Cycles:165 Average:5682.39 Median:5897.65 stddev:660.83 (11.2%) stddev-sans-first:637.00 09:49:01 INFO - PROCESS | 2270 | Values: 3349.0 3435.1 3448.1 3465.6 3492.8 5544.2 5596.3 5610.6 5611.1 5655.4 5625.2 5684.2 5698.2 5698.6 5708.1 5092.6 5119.5 5130.5 5156.1 5194.5 4405.6 5099.9 5205.0 5210.1 5213.1 5127.6 5152.9 5160.8 5174.9 5191.7 5179.6 5217.2 5220.1 5238.2 5271.5 5056.6 5088.2 5101.2 5156.9 5167.9 4603.8 5537.7 5588.1 5617.3 5695.0 6194.8 6201.5 6203.5 6205.3 6214.7 6090.5 6146.0 6317.0 6320.6 6330.0 5763.3 5782.3 5782.4 5786.6 5804.9 6370.3 6371.1 6371.5 6373.5 6380.0 5954.2 5990.5 6030.8 6102.2 6119.1 6216.3 6234.5 6299.3 6301.1 6310.5 6140.1 6202.4 6208.8 6219.1 6224.0 5189.7 6080.7 6105.6 6170.3 6236.9 4862.9 4865.3 4877.2 4908.2 4917.7 4414.0 4416.0 4434.9 4437.3 4469.6 4598.3 5521.1 5547.2 5590.1 5627.4 5111.6 6199.6 6248.4 6259.3 6269.1 5488.1 5542.8 5548.6 5559.9 5562.1 5245.9 5298.2 5348.7 5359.9 5402.1 6297.8 6322.6 6324.4 6326.0 6334.0 6117.7 6275.0 6300.0 6305.7 6308.4 6255.3 6309.1 6346.6 6357.9 6358.0 6138.5 6157.6 6175.7 6183.5 6203.8 6092.1 6188.7 6263.9 6268.5 6272.0 6064.7 6118.2 6119.0 6129.6 6134.1 6104.9 6106.1 6108.7 6112.4 6133.2 4712.4 5717.6 5897.6 5948.5 5960.0 5878.6 5886.4 6012.0 6048.9 6112.3 5878.3 5904.9 5909.2 5918.6 5922.6 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#1] ext.html Cycles:180 Average:60600.87 Median:376831.00 stddev:124305.11 (33.0%) stddev-sans-first:124571.89 09:49:01 INFO - PROCESS | 2270 | Values: 489.8 543.2 575.6 610.4 623.2 37066.3 38116.0 38189.8 38214.6 38282.8 39588.6 39778.8 39930.0 39937.4 40182.6 9397.1 9620.5 9638.2 9639.9 9654.7 9592.6 9762.6 9771.4 9771.8 9780.4 9719.6 9779.8 9784.0 9798.9 9799.3 9665.6 9762.7 9765.6 9766.0 9774.4 9406.0 9584.8 9587.6 9596.1 9605.2 37753.4 38111.8 38137.6 38193.0 38205.4 3122.9 3183.7 3288.9 3340.4 3363.7 25486.6 28642.4 29245.4 29990.4 30000.0 2727.3 2781.1 2786.0 2815.3 2818.7 421987.9 487826.0 489262.0 489818.0 490025.0 61604.4 62251.0 62274.0 62281.0 62446.4 63136.7 64892.7 65975.7 66153.3 66155.3 16930.8 17191.7 17192.7 17208.4 17230.3 10937.4 12888.5 12932.4 12942.7 12996.0 18871.2 19193.8 19211.8 19239.7 19372.6 6883.8 7642.5 7754.6 7879.8 7937.9 859.3 878.2 890.9 970.2 1100.3 28358.4 28629.7 29373.0 29421.3 29427.0 982.9 1083.0 1096.0 1124.7 1190.0 11411.9 14644.5 14840.0 15044.6 15199.8 531073.0 535313.0 536920.0 537044.0 537200.0 57577.4 57596.3 57701.0 57792.0 57919.6 1300.5 1329.4 1412.4 1449.3 1510.5 23988.9 25642.5 29201.6 29503.7 29785.6 45910.0 46330.1 46484.8 46532.0 46548.1 374273.0 376190.0 376433.0 376790.0 376872.0 30771.0 32139.0 32148.9 32157.4 32236.0 33580.6 40270.0 43200.0 43675.6 43906.8 35738.9 35783.3 35827.3 36516.0 36614.1 34689.0 34766.0 35327.0 35416.6 35573.8 35003.8 35233.0 35583.8 35961.9 36060.6 29141.0 30950.7 31441.8 31761.2 31820.8 17814.9 18995.6 22827.3 23948.4 24449.3 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#2] jquery.html Cycles:180 Average:34014.28 Median:21359.07 stddev:100275.25 (469.5%) stddev-sans-first:100524.72 09:49:01 INFO - PROCESS | 2270 | Values: 365.1 365.5 366.2 373.9 378.0 19929.1 20104.6 20405.4 21241.9 21250.3 19709.0 20004.7 20385.4 20404.3 21467.9 5815.8 6050.3 6089.9 6100.8 6101.1 5079.4 6057.1 6059.5 6071.1 6095.1 6022.6 6034.5 6037.3 6038.2 6060.1 6091.2 6506.0 6613.1 6677.5 6687.2 5775.5 5857.3 5872.8 5875.7 5881.2 13508.7 13886.0 13919.0 13933.3 13945.0 2034.2 2088.9 2090.8 2098.9 2100.1 9600.4 9668.4 9741.6 9922.5 10123.0 1687.5 1724.5 1746.3 1761.4 1791.3 96885.5 100802.0 108333.0 109789.5 110360.0 10926.4 10926.8 10944.7 11046.9 11149.9 13464.0 13502.4 13668.9 13706.3 13722.9 8261.3 8292.0 8377.1 8477.6 8500.1 8308.9 8414.5 8493.3 8525.8 8635.1 4314.4 4397.6 4413.7 4430.0 4455.6 1798.3 1837.9 1840.3 1840.9 1846.2 826.8 894.9 903.0 931.3 996.4 16242.8 20200.4 20347.7 20389.7 20592.6 946.3 966.2 968.4 992.1 1008.0 10963.8 11166.3 11201.2 11227.2 11293.7 572695.0 596756.0 609080.0 611227.0 625414.0 121101.0 121608.0 123179.4 126261.4 127401.4 1067.4 1074.7 1093.3 1094.1 1095.6 14946.9 17631.5 18331.3 18696.6 19176.8 53158.0 53314.0 53334.5 55340.4 55401.2 60361.4 61967.9 72545.0 77040.6 77085.0 7689.6 7886.5 7917.6 8025.0 8257.8 9161.5 11864.4 12220.9 12385.6 12613.9 9697.0 10847.2 10956.1 11191.8 11211.9 10710.2 10732.8 10802.6 10976.1 11127.2 6535.5 10711.2 11163.8 11297.8 11468.8 7408.0 7801.4 7833.3 7836.3 8020.2 9747.7 10878.5 11115.2 11133.3 11443.3 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#3] mootools.html Cycles:180 Average:5984.59 Median:5451.10 stddev:3198.85 (58.7%) stddev-sans-first:3189.30 09:49:01 INFO - PROCESS | 2270 | Values: 1405.3 1454.7 1540.0 1737.2 1927.6 7947.9 8650.0 8789.6 8792.8 8798.7 8492.7 8751.5 8759.2 8761.0 8772.0 2143.7 2192.6 2199.5 2413.3 2453.7 2414.1 2461.6 2467.5 2469.5 2469.5 2376.6 2405.7 2411.1 2412.3 2437.5 2025.2 2409.4 2427.4 2435.9 2437.6 2432.7 2455.1 2456.2 2465.4 2470.0 8741.5 8817.6 8822.5 8829.0 8864.2 5353.3 5429.5 5470.3 5476.9 5520.1 3419.8 3424.1 3558.9 3573.8 3593.3 1013.3 1020.3 1038.5 1039.3 1040.8 11724.7 11728.5 11768.4 11769.2 11784.8 4270.5 4689.7 4800.6 4873.0 4882.3 9883.8 9932.6 9970.6 9970.7 9991.5 5422.8 5448.1 5449.6 5452.6 5456.8 4449.6 4563.4 4777.4 4973.2 4985.2 3825.2 3842.3 3885.8 3892.6 3904.8 1264.1 1265.4 1276.8 1279.6 1296.1 1340.8 1363.9 1366.5 1374.2 1396.2 7894.2 8236.1 8236.9 8242.4 8288.2 509.5 526.1 528.2 528.7 529.7 6197.2 6208.8 6262.2 6265.2 6282.1 10401.2 10750.2 10885.8 10899.3 10902.8 10539.0 10550.6 10681.8 10770.2 10772.6 7046.7 8266.3 8278.8 8371.0 8437.1 7654.1 7890.1 7902.4 7917.1 7934.2 9281.8 9505.7 9688.6 9728.3 9785.4 9661.4 9812.1 9948.2 9992.6 10026.9 6231.3 6510.5 6720.9 6924.0 7509.4 7675.5 7743.3 7745.7 7749.5 8291.1 7236.2 7322.5 7366.3 7470.9 7487.1 7182.5 7188.1 7295.3 7316.9 7344.8 7503.9 7547.8 7667.8 7699.5 7710.1 6907.5 6908.8 6948.3 6949.3 6955.4 6957.4 7012.4 7050.4 7055.8 7057.3 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#4] prototype.html Cycles:180 Average:5576.41 Median:6275.55 stddev:1779.58 (28.4%) stddev-sans-first:1740.33 09:49:01 INFO - PROCESS | 2270 | Values: 322.8 324.8 330.4 334.1 335.7 5513.7 6128.8 6405.4 6412.1 6451.9 5651.7 6032.2 6347.7 6390.3 6421.9 4573.2 4652.8 4701.8 4782.3 4806.6 4122.5 4738.9 4775.3 4794.7 4801.6 4404.5 4748.1 4752.5 4765.6 4772.9 4497.9 4575.2 4606.5 4621.6 4728.8 4066.4 4072.2 4077.4 4097.4 4105.7 6149.0 6163.7 6167.2 6184.6 6190.7 5598.9 5603.9 5613.4 5617.3 5625.1 6821.6 6838.6 6840.0 6840.8 6842.1 4291.1 4450.2 4491.8 4501.4 4511.8 7021.9 7377.2 7382.3 7384.6 7390.7 3859.4 4486.6 4546.7 4549.2 4551.4 4583.9 4595.6 4609.9 4616.5 4618.0 4488.4 5391.4 5424.9 5425.7 5437.6 6599.8 6820.5 6878.4 6881.2 6902.5 1565.2 1585.2 1594.4 1596.0 1599.0 1003.5 1013.8 1027.3 1030.9 1034.3 6133.4 6145.8 6270.5 6280.6 6321.9 7099.6 7105.6 7106.7 7117.4 7159.2 6297.5 6323.3 6331.6 6350.3 6362.4 3705.9 3759.5 3763.8 3776.2 3791.9 7110.1 7344.4 7366.6 7378.2 7443.5 7093.1 7308.2 7356.7 7362.9 7371.9 6294.9 7417.3 7419.9 7420.8 7423.7 5891.6 7076.2 7131.4 7136.3 7184.2 6096.4 6377.5 7055.3 7060.4 7089.5 6362.3 7010.5 7420.1 7431.8 7438.4 4528.2 4568.9 4574.1 4576.2 4580.2 5879.7 7128.4 7130.8 7148.9 7151.1 5540.9 6533.1 6870.6 6875.8 6896.7 6869.9 6918.6 6925.0 6928.7 6942.2 6775.8 6787.5 6795.5 6800.2 6802.4 6923.9 6930.4 6931.2 6939.9 6942.8 6457.9 6515.7 6649.0 6788.0 6804.0 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - PROCESS | 2270 | [#5] yui.html Cycles:180 Average:16725.12 Median:63041.00 stddev:22885.12 (36.3%) stddev-sans-first:22917.64 09:49:01 INFO - PROCESS | 2270 | Values: 688.7 756.8 763.7 771.5 818.7 14110.4 14285.2 14307.8 14308.3 14392.4 13047.9 13101.5 13121.2 13137.0 13138.5 868.0 898.0 900.4 900.8 902.0 716.0 726.2 877.7 898.2 900.9 899.1 930.1 938.8 941.0 944.8 896.5 932.5 934.3 936.2 938.4 483.7 568.2 697.6 705.2 706.1 14326.1 14389.0 14403.6 14414.3 14433.0 12330.4 12405.1 12443.7 12605.4 12709.5 8516.1 9844.3 9903.0 9968.3 10305.0 2984.9 3513.2 3563.1 3582.1 3614.7 71645.6 71719.3 71962.3 72044.3 72056.9 9198.9 9349.6 9359.0 9630.4 9648.2 19902.0 20062.1 20081.0 20110.2 20158.3 7515.9 7528.2 7685.3 7738.9 7881.3 3282.3 4088.4 4143.2 4156.4 4263.8 3362.8 3483.6 3525.1 3537.2 3538.9 626.3 652.4 653.2 653.6 679.1 567.3 591.0 593.7 600.3 609.7 7731.9 7891.8 7989.4 8173.6 8287.3 489.6 506.2 534.0 579.4 589.8 3605.7 3737.7 3797.8 3869.1 4002.8 83315.0 83696.0 85013.3 85441.0 85567.1 68300.0 71456.3 71997.3 72244.0 72283.0 62205.0 63040.0 63042.0 63333.7 64202.0 7420.8 7540.7 7590.9 7653.7 7841.8 40992.6 41474.4 41613.2 43877.0 44944.0 46715.1 55057.4 60262.4 60680.7 61314.4 9856.4 9950.6 10005.4 10654.1 10862.6 12054.0 12095.3 12153.1 12312.4 12727.1 8529.3 11374.2 11555.8 11647.2 11725.2 9604.3 9795.9 10100.7 10201.2 12151.8 11203.2 11230.2 11250.8 11317.5 11401.5 9369.8 9423.7 9514.5 9596.1 9914.5 7515.8 7567.7 9624.9 9625.4 9777.2 09:49:01 INFO - PROCESS | 2270 | -------- Summary: end -------- 09:49:01 INFO - PROCESS | 2270 | 09:49:01 INFO - TEST-INFO | 2270: exit 0 09:49:01 INFO - TEST-OK | dromaeo_css | took 1112447ms 09:49:01 INFO - TEST-START | kraken 09:49:01 INFO - operating with platform_type : mac_ 09:49:01 INFO - Initialising browser for kraken test... 09:49:01 INFO - TEST-INFO | started process 2272 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpZK9DJN/profile http://localhost:50975/getInfo.html) 09:49:03 INFO - PROCESS | 2272 | __metrics Screen width/height:1600/1200 09:49:03 INFO - PROCESS | 2272 | colorDepth:24 09:49:03 INFO - PROCESS | 2272 | Browser inner width/height: 1024/689 09:49:03 INFO - PROCESS | 2272 | __metrics 09:49:03 INFO - PROCESS | 2272 | 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" 09:49:03 ERROR - PROCESS | 2272 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:49:04 INFO - TEST-INFO | 2272: exit 0 09:49:04 INFO - Browser initialized. 09:49:04 INFO - Running cycle 1/1 for kraken test... 09:49:04 INFO - TEST-INFO | started process 2274 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpZK9DJN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:16 INFO - PROCESS | 2274 | RSS: Main: 236380160 09:49:16 INFO - PROCESS | 2274 | 09:49:19 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50975/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:19 INFO - PROCESS | 2274 | RSS: Main: 282394624 09:49:19 INFO - PROCESS | 2274 | 09:49:22 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50975/tests/kraken/driver.html?testName=audio-dft) 09:49:23 INFO - PROCESS | 2274 | RSS: Main: 293236736 09:49:23 INFO - PROCESS | 2274 | 09:49:26 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50975/tests/kraken/driver.html?testName=audio-fft) 09:49:26 INFO - PROCESS | 2274 | RSS: Main: 279445504 09:49:26 INFO - PROCESS | 2274 | 09:49:28 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50975/tests/kraken/driver.html?testName=audio-oscillator) 09:49:29 INFO - PROCESS | 2274 | RSS: Main: 281927680 09:49:29 INFO - PROCESS | 2274 | 09:49:31 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50975/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:32 INFO - PROCESS | 2274 | RSS: Main: 282267648 09:49:32 INFO - PROCESS | 2274 | 09:49:34 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50975/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:35 INFO - PROCESS | 2274 | RSS: Main: 282152960 09:49:35 INFO - PROCESS | 2274 | 09:49:38 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50975/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:39 INFO - PROCESS | 2274 | RSS: Main: 282374144 09:49:39 INFO - PROCESS | 2274 | 09:49:41 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50975/tests/kraken/driver.html?testName=json-parse-financial) 09:49:42 INFO - PROCESS | 2274 | RSS: Main: 282755072 09:49:42 INFO - PROCESS | 2274 | 09:49:44 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50975/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:44 INFO - PROCESS | 2274 | RSS: Main: 318484480 09:49:44 INFO - PROCESS | 2274 | 09:49:46 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:47 INFO - PROCESS | 2274 | RSS: Main: 318996480 09:49:47 INFO - PROCESS | 2274 | 09:49:49 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:50 INFO - PROCESS | 2274 | RSS: Main: 322797568 09:49:50 INFO - PROCESS | 2274 | 09:49:52 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:53 INFO - PROCESS | 2274 | RSS: Main: 324100096 09:49:53 INFO - PROCESS | 2274 | 09:49:55 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:56 INFO - PROCESS | 2274 | RSS: Main: 324341760 09:49:56 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | Cycle 1(1): loaded http://localhost:50975/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50975/tests/kraken/driver.html?testName=ai-astar) 09:49:58 INFO - PROCESS | 2274 | RSS: Main: 387837952 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | __start_tp_report 09:49:58 INFO - PROCESS | 2274 | _x_x_mozilla_page_load 09:49:58 INFO - PROCESS | 2274 | _x_x_mozilla_page_load_details 09:49:58 INFO - PROCESS | 2274 | |i|pagename|runs| 09:49:58 INFO - PROCESS | 2274 | |0;ai-astar;112;109;111;110;111;111;117;111;111;111 09:49:58 INFO - PROCESS | 2274 | |1;audio-beat-detection;181;159;173;160;119;144;161;123;163;120 09:49:58 INFO - PROCESS | 2274 | |2;audio-dft;185;186;186;184;184;184;185;180;182;180 09:49:58 INFO - PROCESS | 2274 | |3;audio-fft;82;73;84;82;56;58;61;80;78;55 09:49:58 INFO - PROCESS | 2274 | |4;audio-oscillator;96;96;131;79;77;78;123;80;78;125 09:49:58 INFO - PROCESS | 2274 | |5;imaging-gaussian-blur;88;85;92;86;87;85;90;85;87;84 09:49:58 INFO - PROCESS | 2274 | |6;imaging-darkroom;93;95;94;94;93;93;110;129;143;151 09:49:58 INFO - PROCESS | 2274 | |7;imaging-desaturate;86;86;88;87;72;131;134;92;82;84 09:49:58 INFO - PROCESS | 2274 | |8;json-parse-financial;55;55;54;53;52;60;82;94;82;90 09:49:58 INFO - PROCESS | 2274 | |9;json-stringify-tinderbox;68;71;69;76;73;70;71;69;72;70 09:49:58 INFO - PROCESS | 2274 | |10;stanford-crypto-aes;86;85;85;84;85;91;115;84;84;86 09:49:58 INFO - PROCESS | 2274 | |11;stanford-crypto-ccm;138;131;116;121;123;123;124;123;121;126 09:49:58 INFO - PROCESS | 2274 | |12;stanford-crypto-pbkdf2;140;126;140;140;136;161;175;141;139;140 09:49:58 INFO - PROCESS | 2274 | |13;stanford-crypto-sha256-iterative;67;68;68;66;69;86;94;67;66;68 09:49:58 INFO - PROCESS | 2274 | __end_tp_report 09:49:58 INFO - PROCESS | 2274 | __start_cc_report 09:49:58 INFO - PROCESS | 2274 | _x_x_mozilla_cycle_collect,1265 09:49:58 INFO - PROCESS | 2274 | __end_cc_report 09:49:58 INFO - PROCESS | 2274 | __startTimestamp1460047798379__endTimestamp 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | ------- Summary: start ------- 09:49:58 INFO - PROCESS | 2274 | Number of tests: 14 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:2.12 (1.9%) stddev-sans-first:2.24 09:49:58 INFO - PROCESS | 2274 | Values: 112.0 109.0 111.0 110.0 111.0 111.0 117.0 111.0 111.0 111.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:160.50 stddev:22.57 (14.1%) stddev-sans-first:21.03 09:49:58 INFO - PROCESS | 2274 | Values: 181.0 159.0 173.0 160.0 119.0 144.0 161.0 123.0 163.0 120.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:2.22 (1.2%) stddev-sans-first:2.30 09:49:58 INFO - PROCESS | 2274 | Values: 185.0 186.0 186.0 184.0 184.0 184.0 185.0 180.0 182.0 180.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#3] audio-fft Cycles:10 Average:70.90 Median:79.00 stddev:11.99 (15.2%) stddev-sans-first:12.03 09:49:58 INFO - PROCESS | 2274 | Values: 82.0 73.0 84.0 82.0 56.0 58.0 61.0 80.0 78.0 55.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:78.50 stddev:21.98 (28.0%) stddev-sans-first:23.31 09:49:58 INFO - PROCESS | 2274 | Values: 96.0 96.0 131.0 79.0 77.0 78.0 123.0 80.0 78.0 125.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.00 stddev:2.51 (2.9%) stddev-sans-first:2.64 09:49:58 INFO - PROCESS | 2274 | Values: 88.0 85.0 92.0 86.0 87.0 85.0 90.0 85.0 87.0 84.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:93.00 stddev:22.93 (24.7%) stddev-sans-first:23.53 09:49:58 INFO - PROCESS | 2274 | Values: 93.0 95.0 94.0 94.0 93.0 93.0 110.0 129.0 143.0 151.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:86.00 stddev:20.85 (24.2%) stddev-sans-first:21.91 09:49:58 INFO - PROCESS | 2274 | Values: 86.0 86.0 88.0 87.0 72.0 131.0 134.0 92.0 82.0 84.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:71.00 stddev:17.09 (24.1%) stddev-sans-first:17.50 09:49:58 INFO - PROCESS | 2274 | Values: 55.0 55.0 54.0 53.0 52.0 60.0 82.0 94.0 82.0 90.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:2.33 (3.3%) stddev-sans-first:2.22 09:49:58 INFO - PROCESS | 2274 | Values: 68.0 71.0 69.0 76.0 73.0 70.0 71.0 69.0 72.0 70.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:85.00 stddev:9.54 (11.2%) stddev-sans-first:10.07 09:49:58 INFO - PROCESS | 2274 | Values: 86.0 85.0 85.0 84.0 85.0 91.0 115.0 84.0 84.0 86.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:123.50 stddev:6.06 (4.9%) stddev-sans-first:4.04 09:49:58 INFO - PROCESS | 2274 | Values: 138.0 131.0 116.0 121.0 123.0 123.0 124.0 123.0 121.0 126.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.80 Median:140.00 stddev:13.89 (9.9%) stddev-sans-first:14.66 09:49:58 INFO - PROCESS | 2274 | Values: 140.0 126.0 140.0 140.0 136.0 161.0 175.0 141.0 139.0 140.0 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - PROCESS | 2274 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:68.00 stddev:9.77 (14.4%) stddev-sans-first:10.20 09:49:58 INFO - PROCESS | 2274 | Values: 67.0 68.0 68.0 66.0 69.0 86.0 94.0 67.0 66.0 68.0 09:49:58 INFO - PROCESS | 2274 | -------- Summary: end -------- 09:49:58 INFO - PROCESS | 2274 | 09:49:58 INFO - TEST-INFO | 2274: exit 0 09:49:58 INFO - TEST-OK | kraken | took 57213ms 09:49:58 INFO - SUITE-END | took 1169s 09:49:59 INFO - Completed test suite (00:19:29) 09:49:59 INFO - javascript benchmark 09:49:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3348.9665103348966, 3435.072530416786, 3448.051785758916, 3465.60145583258, 3492.7904325740456, 5544.16837474379, 5596.300462442195, 5610.551155907523, 5611.130274807406, 5655.3779084300695, 5625.156226566017, 5684.204211410406, 5698.230738850254, 5698.629589076718, 5708.1437784332375, 5092.567131793793, 5119.539241468267, 5130.486951304877, 5156.071907056728, 5194.506521880434, 4405.559444055585, 5099.898002039976, 5204.959008198357, 5210.088234558956, 5213.113770658978, 5127.641065125443, 5152.896942061157, 5160.845174644766, 5174.922376164361, 5191.714455704935, 5179.585633149358, 5217.165253559431, 5220.086484865134, 5238.161894096965, 5271.4992075753125, 5056.570191533724, 5088.160453525164, 5101.183810590288, 5156.948430515685, 5167.896642067138, 4603.815847366135, 5537.723113844332, 5588.0779671354585, 5617.269754931833, 5695.0, 6194.81415557534, 6201.472874805647, 6203.472704820096, 6205.286392064925, 6214.68926553675, 6090.488975835674, 6146.0, 6317.0, 6320.62076275425, 6329.968350158266, 5763.308402991668, 5782.277215348082, 5782.3639399666, 5786.623869448473, 5804.854878628026, 6370.331115232926, 6371.076193951851, 6371.490280777527, 6373.490120790419, 6380.0, 5954.225950626391, 5990.460858522667, 6030.758769649253, 6102.206713127265, 6119.143319935212, 6216.285127210338, 6234.470070044006, 6299.275583307886, 6301.117843501914, 6310.4636105930595, 6140.109684095781, 6202.4417802398, 6208.751649934043, 6219.098230756515, 6223.96888015557, 5189.6886186828915, 6080.665563394055, 6105.64170830808, 6170.290416602148, 6236.906446403307, 4862.878428039327, 4865.294532292798, 4877.170880950176, 4908.165611845995, 4917.704937703749, 4413.955860441419, 4416.006398560363, 4434.866953991385, 4437.312216606431, 4469.5530446955045, 4598.333241679938, 5521.116621340647, 5547.195656629766, 5590.105583106683, 5627.409122042209, 5111.5655169310285, 6199.628022318675, 6248.4376406123665, 6259.311475737665, 6269.059641053878, 5488.1493368527945, 5542.75057622408, 5548.639338443069, 5559.916601250984, 5562.082256427644, 5245.895082098336, 5298.152295632757, 5348.652337598043, 5359.892802143935, 5402.108652072443, 6297.779577714849, 6322.589031712924, 6324.430801227912, 6326.0, 6334.049892516159, 6117.724702388314, 6275.02737075754, 6299.96850015747, 6305.716242768995, 6308.400701933309, 6255.280642726145, 6309.148264984352, 6346.587471814318, 6357.872842543031, 6357.96821015892, 6138.539609529393, 6157.630542167484, 6175.722092505758, 6183.505319574513, 6203.844903877439, 6092.116643086816, 6188.690565471618, 6263.906041409291, 6268.498520118471, 6272.0, 6064.727087281171, 6118.162361256354, 6118.984682626333, 6129.603311142764, 6134.069235337226, 6104.8779024420155, 6106.1451396803595, 6108.694565271808, 6112.358202388685, 6133.202683651097, 4712.363830882787, 5717.571182161271, 5897.64614123154, 5948.4943779779205, 5960.016597261403, 5878.647281163144, 5886.440788125121, 6012.0, 6048.939510604838, 6112.297085835008, 5878.265216847894, 5904.911426328695, 5909.172715819703, 5918.644881307136, 5922.55580831448], "unit": "score", "name": "dojo.html", "value": 5641.5111197985225}, {"lowerIsBetter": false, "replicates": [489.81387072912287, 543.1689515041986, 575.551070165271, 610.4414460768397, 623.1867413026, 37066.258674826524, 38116.0, 38189.80905095474, 38214.61785382145, 38282.808585957064, 39588.60411395885, 39778.80110599443, 39930.00174995627, 39937.40093898587, 40182.598174018254, 9397.1072748089, 9620.518974051305, 9638.228941684649, 9639.879226570143, 9654.662086826962, 9592.568334424968, 9762.560684769167, 9771.413715177077, 9771.804563908718, 9780.413175209475, 9719.56261968213, 9779.804403911918, 9784.0, 9798.902010979906, 9799.265055120859, 9665.565049572786, 9762.658306959222, 9765.560549775242, 9765.951170244103, 9774.364666296668, 9405.952970235105, 9584.808303833954, 9587.568559414844, 9596.088371604686, 9605.231581473465, 37753.43369849455, 38111.80944095289, 38137.61862381369, 38193.0, 38205.42691859624, 3122.8750849965973, 3183.7453003759874, 3288.917777055593, 3340.43212653847, 3363.6972672459597, 25486.617700734503, 28642.427151456854, 29245.415091698258, 29990.400191996257, 29999.950001749836, 2727.304537342965, 2781.123945957017, 2785.958210626842, 2815.296175956011, 2818.6758522769733, 421987.89006054774, 487826.0, 489262.0, 489818.0, 490025.0, 61604.38395616076, 62251.0, 62274.0, 62281.0, 62446.37553624406, 63136.684316578125, 64892.67553662202, 65975.68048639, 66153.33846661568, 66155.33844661588, 16930.830691692925, 17191.656166876593, 17192.65614687724, 17208.397706080228, 17230.310787568356, 10937.398443085707, 12888.48446062147, 12932.41804118817, 12942.676433089247, 12996.0, 18871.19199236042, 19193.848369097897, 19211.807881921282, 19239.711404328948, 19372.612547748966, 6883.795335816212, 7642.4650274480255, 7754.5734984575265, 7879.803004924923, 7937.920620793833, 859.3125499600295, 878.1701292278863, 890.9483230085431, 970.2141265574766, 1100.2958106811486, 28358.432831343256, 28629.713702862704, 29372.97194598179, 29421.26446838803, 29427.0, 982.916452101565, 1082.99458502707, 1095.9832214932185, 1124.7075760302216, 1190.0182349561646, 11411.900468085638, 14644.487442939446, 14840.0, 15044.623884402978, 15199.848001519844, 531073.0, 535313.0, 536920.0, 537044.0, 537200.0, 57577.424225757204, 57596.27211183671, 57701.0, 57792.0, 57919.55201120006, 1300.479808076758, 1329.3951252180234, 1412.3644360038024, 1449.340550049725, 1510.4562357551054, 23988.88005559961, 25642.46145231292, 29201.558908764375, 29503.704962950094, 29785.5532167022, 45910.0, 46330.07339853251, 46484.837879053295, 46532.0, 46548.06903861836, 374273.0, 376190.0, 376433.0, 376790.0, 376872.0, 30771.0, 32139.0, 32148.87478938226, 32157.35685286328, 32236.03291901247, 33580.55705086081, 40269.993250168984, 43200.0, 43675.56324436715, 43906.780466097465, 35738.92783216508, 35783.28433431369, 35827.283454331286, 36516.0, 36614.07087432946, 34688.9593312201, 34766.0, 35327.0, 35416.64583354237, 35573.822130890214, 35003.82498087493, 35233.0, 35583.82208088943, 35961.92114236577, 36060.639393606776, 29141.0, 30950.69049309568, 31441.8427907859, 31761.20596985094, 31820.840895795372, 17814.91092544529, 18995.620087598447, 22827.31518054461, 23948.40128996789, 24449.266522004367], "unit": "score", "name": "ext.html", "value": 19189.18286437451}, {"lowerIsBetter": false, "replicates": [365.06725316815533, 365.5010910107702, 366.15235729286707, 373.8859647807419, 377.9773213607182, 19929.103190356433, 20104.597908041847, 20405.429049916278, 21241.93790310486, 21250.256241031566, 19709.0, 20004.699929501032, 20385.388438346836, 20404.285849995253, 21467.892660536716, 5815.767369305223, 6050.304215015286, 6089.878202435949, 6100.81697549072, 6101.0848372744, 5079.3650793650795, 6057.091436284571, 6059.515238780887, 6071.058985857199, 6095.146679464856, 6022.578419510636, 6034.4568988791, 6037.30570984338, 6038.245219347582, 6060.090986352038, 6091.238595175603, 6505.967470162666, 6613.140291762064, 6677.499187560952, 6687.197536295627, 5775.451332123442, 5857.26784151981, 5872.823815285548, 5875.676837773921, 5881.176635271066, 13508.729825403536, 13886.0, 13919.0, 13933.30333483332, 13945.0, 2034.216826521785, 2088.9268875589282, 2090.780468050848, 2098.8845613491253, 2100.12844669462, 9600.42397456148, 9668.419894806335, 9741.610335586569, 9922.50387480623, 10123.0, 1687.4853169783212, 1724.465415721118, 1746.2840235503509, 1761.445144779403, 1791.3282519055354, 96885.51557242169, 100802.0, 108333.0, 109789.45105274422, 110360.0, 10926.399048052433, 10926.836097458545, 10944.671659850217, 11046.889531104747, 11149.944250278697, 13464.0, 13502.392392342368, 13668.93165534166, 13706.314684265746, 13722.862771372158, 8261.339092872557, 8292.04641466239, 8377.078521362646, 8477.576121193915, 8500.064992850781, 8308.916910830936, 8414.537200454033, 8493.27807136388, 8525.786855328543, 8635.050144484101, 4314.417553630283, 4397.648188144941, 4413.653835580179, 4429.977850110793, 4455.576720211575, 1798.3346161920174, 1837.9172937217857, 1840.3006857394105, 1840.926362945494, 1846.1784505895016, 826.801567623766, 894.9418287811271, 902.9819403611891, 931.2643012020563, 996.3573495095768, 16242.837571624132, 20200.393988180378, 20347.69478457824, 20389.694154587694, 20592.58814823695, 946.3091942881734, 966.2173639352147, 968.3995922528078, 992.0674565908023, 1008.0434295390563, 10963.83554246687, 11166.300219868013, 11201.215914886043, 11227.157963152797, 11293.71765705864, 572695.0, 596756.0, 609080.0, 611227.0, 625414.0, 121101.0, 121608.0, 123179.3841030789, 126261.36869315595, 127401.3629931863, 1067.3755852826184, 1074.6561100447939, 1093.3494570730402, 1094.0919037199105, 1095.594629986894, 14946.850531494982, 17631.471055868344, 18331.266749329876, 18696.6260674783, 19176.8082319175, 53158.0, 53314.0, 53334.46665533295, 55340.44659553514, 55401.168982464485, 60361.39638603733, 61967.90160491867, 72545.0, 77040.61479692566, 77085.0, 7689.577073260801, 7886.5409899168835, 7917.643706033357, 8025.0, 8257.79355516117, 9161.49611771359, 11864.406779661154, 12220.938895305466, 12385.62843114708, 12613.87386126127, 9696.981816909123, 10847.186461015297, 10956.12351011933, 11191.832122518006, 11211.943940280247, 10710.196735244732, 10732.785344293226, 10802.567897284021, 10976.067034301854, 11127.165462590176, 6535.477161827138, 10711.196660250669, 11163.776724465628, 11297.7740445189, 11468.770624587294, 7408.0, 7801.375889928906, 7833.295003449717, 7836.294733473786, 8020.238077382639, 9747.707568772948, 10878.510467028844, 11115.166362522681, 11133.276337038067, 11443.25618834773], "unit": "score", "name": "jquery.html", "value": 9231.420199817847}, {"lowerIsBetter": false, "replicates": [1405.3465138710499, 1454.6508837878907, 1539.9923000385, 1737.2269340143632, 1927.566297583044, 7947.880781788271, 8650.0, 8789.560521973899, 8792.78018049549, 8798.736037918856, 8492.66029358825, 8751.518666473341, 8759.167879051496, 8761.0, 8772.0, 2143.732033495813, 2192.6162921488753, 2199.49411635324, 2413.2518919135123, 2453.681021467207, 2414.058517178302, 2461.6430617560445, 2467.5311690778694, 2469.506098780242, 2469.5184439034397, 2376.572217000939, 2405.675233843426, 2411.144043864429, 2412.3124909400826, 2437.5124975004983, 2025.1899240303849, 2409.373562873648, 2427.4416884116663, 2435.890384932682, 2437.622168563875, 2432.683751112344, 2455.0916161020536, 2456.2262887190477, 2465.4452748131707, 2469.987650061756, 8741.475511469333, 8817.559122043873, 8822.514761688104, 8828.955855220747, 8864.20222180007, 5353.277307563468, 5429.511343979061, 5470.316210473691, 5476.890462190774, 5520.144377621435, 3419.760616756828, 3424.143964008998, 3558.946615800765, 3573.8391772370305, 3593.317269718745, 1013.3160116921123, 1020.285799940045, 1038.5067093130779, 1039.272509243525, 1040.7866387390566, 11724.70688232801, 11728.472218750177, 11768.352740599265, 11769.176157668966, 11784.823227651506, 4270.530241673413, 4689.729570696532, 4800.567948884617, 4872.975635121802, 4882.267659851051, 9883.752906177404, 9932.602695892228, 9970.551325190383, 9970.700878973643, 9991.500424978867, 5422.810201643002, 5448.073827449343, 5449.5640348772, 5452.645578037414, 5456.781728730806, 4449.555044495524, 4563.429571303587, 4777.402824646919, 4973.229149481836, 4985.15549246781, 3825.2349530094093, 3842.269968705937, 3885.7668539887695, 3892.5718171001167, 3904.8438062477185, 1264.1277518512381, 1265.3989355056274, 1276.8403949506312, 1279.5777393460137, 1296.092735085444, 1340.7988801679826, 1363.9181649101085, 1366.4602482019548, 1374.1960952842499, 1396.218117854005, 7894.21057894206, 8236.135205803424, 8236.876446853303, 8242.423030387936, 8288.21261980111, 509.4548832748997, 526.1345087331318, 528.2393353570845, 528.7012837746738, 529.6716036057669, 6197.194364732556, 6208.844778880474, 6262.248530176408, 6265.185525881698, 6282.089097080898, 10401.167906567456, 10750.193735469871, 10885.83671244932, 10899.29154604948, 10902.836457453144, 10538.998795114452, 10550.577976880839, 10681.786364272826, 10770.246082774287, 10772.569097236179, 7046.682899269443, 8266.338692904674, 8278.793030174415, 8371.0, 8437.114103019094, 7654.119776225805, 7890.092639346319, 7902.407319450949, 7917.089534703583, 7934.20657934202, 9281.814363712823, 9505.66730164441, 9688.564014619218, 9728.319017668695, 9785.36395134314, 9661.372010819274, 9812.067853553901, 9948.204143668345, 9992.600295988079, 10026.899731002597, 6231.3145553988115, 6510.544261901621, 6720.932790672031, 6923.9653801730665, 7509.399248060033, 7675.53946763196, 7743.29647477554, 7745.65144568507, 7749.496282741829, 8291.08798032204, 7236.20401755817, 7322.524035937647, 7366.337029667356, 7470.925290747023, 7487.101547814079, 7182.4613154014705, 7188.101487314086, 7295.343419092307, 7316.853662926818, 7344.779656610311, 7503.949447077304, 7547.811304717426, 7667.769966901002, 7699.461037727529, 7710.11333696632, 6907.481938854707, 6908.827279318058, 6948.270431604608, 6949.270326615835, 6955.443564514928, 6957.443404527525, 7012.368886800008, 7050.435965122675, 7055.788326350218, 7057.294270572902], "unit": "score", "name": "mootools.html", "value": 4789.440928205287}, {"lowerIsBetter": false, "replicates": [322.7788964559276, 324.8099861580975, 330.39537646107624, 334.14458984998396, 335.73476953206966, 5513.669179849212, 6128.804883047807, 6405.391487808656, 6412.134361861154, 6451.870962580751, 5651.745671444784, 6032.245969253843, 6347.650879201642, 6390.297067322591, 6421.8715625687455, 4573.245414506602, 4652.790624421892, 4701.811927522912, 4782.258749893772, 4806.639502037361, 4122.454079720105, 4738.928916066262, 4775.259834725605, 4794.664373493857, 4801.639877009221, 4404.4934832494355, 4748.121597504455, 4752.500987396325, 4765.618750499952, 4772.928406073912, 4497.9325310120375, 4575.245084561044, 4606.5393460654, 4621.607163391116, 4728.787204575802, 4066.410370496291, 4072.2059198456313, 4077.408775727503, 4097.42636030953, 4105.712600117993, 6149.046897730859, 6163.691815409212, 6167.167432396659, 6184.5980011299125, 6190.659513726743, 5598.916016259719, 5603.887922241533, 5613.410591887834, 5617.325920889479, 5625.128105143668, 6821.590704557742, 6838.555493892931, 6840.042394064785, 6840.828979275509, 6842.144731908512, 4291.141771645684, 4450.2434586120435, 4491.842785502492, 4501.437320334958, 4511.796969136397, 7021.859562808766, 7377.225391333886, 7382.335589796944, 7384.556926584369, 7390.741324053686, 3859.421086836941, 4486.6186374157905, 4546.704464209815, 4549.181147393435, 4551.431071116111, 4583.9083218335445, 4595.586397224199, 4609.9308510372375, 4616.515265897033, 4617.953820461819, 4488.4165058542185, 5391.436483419843, 5424.945750542444, 5425.728713564385, 5437.564994800407, 6599.769008084694, 6820.488463365267, 6878.449724022166, 6881.174259088842, 6902.516823822385, 1565.2095691675631, 1585.2153184173908, 1594.4419453191522, 1595.9680806383808, 1598.9600259993592, 1003.508280942333, 1013.7921726046143, 1027.3219675014454, 1030.902064303905, 1034.2760067952463, 6133.417325354084, 6145.784897528565, 6270.467010304175, 6280.623162610258, 6321.905171422432, 7099.574025558483, 7105.609191494414, 7106.680199391039, 7117.395021423134, 7159.212486626467, 6297.464715499143, 6323.27282362525, 6331.556791024584, 6350.301466838644, 6362.427381535592, 3705.9073523162137, 3759.548854137466, 3763.8494460221286, 3776.169242766587, 3791.8672846450795, 7110.146782386147, 7344.375728063174, 7366.594837284001, 7378.188399276075, 7443.478956473104, 7093.1488221413765, 7308.232635573295, 7356.705731770776, 7362.926370736224, 7371.88942165868, 6294.937050629435, 7417.332440080419, 7419.888701669372, 7420.814479637944, 7423.665935032936, 5891.58758886891, 7076.221615622175, 7131.393831524171, 7136.3220494052985, 7184.245654206442, 6096.359882212304, 6377.501287197598, 7055.2944705529035, 7060.376113494028, 7089.468289878383, 6362.300146983921, 7010.47421443404, 7420.146683131509, 7431.777046688608, 7438.367738742268, 4528.230200865926, 4568.908621827479, 4574.085182963354, 4576.244919588231, 4580.221362368338, 5879.676617785892, 7128.358447739728, 7130.750423735144, 7148.928510714826, 7151.141862976476, 5540.891821635609, 6533.0853680485725, 6870.587764734132, 6875.759348422781, 6896.724131034703, 6869.931300687129, 6918.584884906922, 6924.965375173092, 6928.722851086101, 6942.201646810479, 6775.796726098225, 6787.457003439812, 6795.52431329822, 6800.217974932946, 6802.387785099365, 6923.861522769617, 6930.3762661360715, 6931.202911665021, 6939.930600694131, 6942.826429339307, 6457.935420645734, 6515.73937042532, 6648.966755166193, 6787.966060169668, 6803.9659801702655], "unit": "score", "name": "prototype.html", "value": 4985.786419689663}, {"lowerIsBetter": false, "replicates": [688.7245101959217, 756.8486302739453, 763.7441457111868, 771.4522688890887, 818.7216346442209, 14110.435582576698, 14285.21431321277, 14307.78538321926, 14308.284585770709, 14392.352344144512, 13047.934760326209, 13101.47594096235, 13121.212727236372, 13137.0, 13138.540151094714, 868.0, 898.0435835834847, 900.4372267332917, 900.837849187146, 902.0032863685632, 716.0369303287071, 726.1612837173074, 877.7235170921172, 898.2050884966819, 900.8693739407782, 899.1413200393608, 930.0559931669361, 938.8310104181245, 941.0448394879203, 944.8441007233798, 896.4666023715911, 932.5011119051309, 934.2525979216599, 936.1574582875398, 938.3994243684082, 483.7470950238898, 568.2073507457075, 697.5988806436319, 705.1643802094534, 706.0891450029455, 14326.068805527711, 14389.0, 14403.567892963229, 14414.279286035657, 14433.0, 12330.445129969083, 12405.069619778462, 12443.688907777287, 12605.432755526022, 12709.491620335175, 8516.148385161496, 9844.261680374, 9903.0, 9968.302218844683, 10305.021023002802, 2984.85075746214, 3513.2270900401872, 3563.1448452371505, 3582.140286331288, 3614.7288953328343, 71645.6417717908, 71719.28280717178, 71962.2803771966, 72044.27955720428, 72056.91914621285, 9198.862017069749, 9349.579268932914, 9359.0, 9630.422174669542, 9648.228141748783, 19902.0, 20062.09720562549, 20081.0, 20110.195592176442, 20158.29445969384, 7515.887261691079, 7528.1719010908755, 7685.346745526581, 7738.92261077382, 7881.290683838482, 3282.2778988622463, 4088.366303223005, 4143.212789569972, 4156.397322388237, 4263.8081286342185, 3362.7982321060813, 3483.5993860706344, 3525.11872031992, 3537.2041290709385, 3538.929221415557, 626.2797782550012, 652.3802387731674, 653.1965682210812, 653.6111013946694, 679.0832376292065, 567.2568934695562, 590.9704514774244, 593.7031484257872, 600.3366280260336, 609.7164818359396, 7731.92268077312, 7891.842163156704, 7989.360851131895, 8173.550454724928, 8287.295579875657, 489.60586727683847, 506.1623013911982, 533.9572834173257, 579.3887448741534, 589.799468180812, 3605.675489205984, 3737.7383583149463, 3797.8290976906105, 3869.129445874712, 4002.799860006988, 83315.0, 83696.0, 85013.29973400498, 85441.0, 85567.14432855716, 68300.0, 71456.28543714496, 71997.2800272001, 72244.0, 72283.0, 62205.0, 63039.99999999908, 63042.0, 63333.73332533416, 64202.0, 7420.777376678709, 7540.736074237376, 7590.924090759022, 7653.732119375795, 7841.764747057598, 40992.59007410007, 41474.37788433236, 41613.16773664449, 43877.0, 44944.0, 46715.065698686514, 55057.449425505234, 60262.39737602743, 60680.69659651673, 61314.38685613265, 9856.359336643096, 9950.601975921172, 10005.449155380928, 10654.14766818668, 10862.565497380016, 12054.0, 12095.334756588472, 12153.149279550347, 12312.445939932904, 12727.109102362745, 8529.317654587494, 11374.20380573352, 11555.768884622428, 11647.242929209575, 11725.179237453638, 9604.27967902396, 9795.9020409795, 10100.696979090639, 10201.234907382124, 12151.81772273399, 11203.15976301797, 11230.21388502797, 11250.789106110435, 11317.547298107984, 11401.543938242377, 9369.81260374775, 9423.670171544238, 9514.524273786421, 9596.136347728738, 9914.504274786375, 7515.774526764206, 7567.745540168425, 9624.903750962401, 9625.374350667184, 9777.16894063984], "unit": "score", "name": "yui.html", "value": 6593.24559964991}], "name": "dromaeo_css", "value": 7347.602324159435}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 111.0, 110.0, 111.0, 111.0, 117.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.4}, {"lowerIsBetter": true, "replicates": [181.0, 159.0, 173.0, 160.0, 119.0, 144.0, 161.0, 123.0, 163.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 150.3}, {"lowerIsBetter": true, "replicates": [185.0, 186.0, 186.0, 184.0, 184.0, 184.0, 185.0, 180.0, 182.0, 180.0], "unit": "score", "name": "audio-dft", "value": 183.6}, {"lowerIsBetter": true, "replicates": [82.0, 73.0, 84.0, 82.0, 56.0, 58.0, 61.0, 80.0, 78.0, 55.0], "unit": "score", "name": "audio-fft", "value": 70.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 131.0, 79.0, 77.0, 78.0, 123.0, 80.0, 78.0, 125.0], "unit": "score", "name": "audio-oscillator", "value": 96.3}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 92.0, 86.0, 87.0, 85.0, 90.0, 85.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.9}, {"lowerIsBetter": true, "replicates": [93.0, 95.0, 94.0, 94.0, 93.0, 93.0, 110.0, 129.0, 143.0, 151.0], "unit": "score", "name": "imaging-darkroom", "value": 109.5}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 88.0, 87.0, 72.0, 131.0, 134.0, 92.0, 82.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 94.2}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 54.0, 53.0, 52.0, 60.0, 82.0, 94.0, 82.0, 90.0], "unit": "score", "name": "json-parse-financial", "value": 67.7}, {"lowerIsBetter": true, "replicates": [68.0, 71.0, 69.0, 76.0, 73.0, 70.0, 71.0, 69.0, 72.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.9}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 85.0, 84.0, 85.0, 91.0, 115.0, 84.0, 84.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.5}, {"lowerIsBetter": true, "replicates": [138.0, 131.0, 116.0, 121.0, 123.0, 123.0, 124.0, 123.0, 121.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.6}, {"lowerIsBetter": true, "replicates": [140.0, 126.0, 140.0, 140.0, 136.0, 161.0, 175.0, 141.0, 139.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.8}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 66.0, 69.0, 86.0, 94.0, 67.0, 66.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.9}], "name": "kraken", "value": 1470.5}]} 09:49:59 INFO - Return code: 0 09:49:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:49:59 ERROR - # TBPL SUCCESS # 09:49:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:59 INFO - 2016-04-07 09:49:59.204 screenresolution[2278:66860] starting screenresolution argv=screenresolution get 09:49:59 INFO - 2016-04-07 09:49:59.206 screenresolution[2278:66860] Display 0: 1600x1200x32@60 09:49:59 INFO - 2016-04-07 09:49:59.212 screenresolution[2279:66864] starting screenresolution argv=screenresolution list 09:49:59 INFO - Available Modes on Display 0 09:49:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:59 INFO - Intel Iris: 09:49:59 INFO - Chipset Model: Intel Iris 09:49:59 INFO - Type: GPU 09:49:59 INFO - Bus: Built-In 09:49:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:59 INFO - Vendor: Intel (0x8086) 09:49:59 INFO - Device ID: 0x0a2e 09:49:59 INFO - Revision ID: 0x0009 09:49:59 INFO - Displays: 09:49:59 INFO - Display: 09:49:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:59 INFO - Display Serial Number: 1600x1200 60 09:49:59 INFO - Main Display: Yes 09:49:59 INFO - Mirror: Off 09:49:59 INFO - Online: Yes 09:49:59 INFO - Rotation: Supported 09:49:59 INFO - Return code: 0 09:49:59 INFO - Running post-action listener: _resource_record_post_action 09:49:59 INFO - Running post-run listener: _resource_record_post_run 09:50:00 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 40960; Write bytes: 690745344; Read time: 2; Write time: 2254 09:50:00 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373297664; Write bytes: 366888960; Read time: 27931; Write time: 641 09:50:00 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 8192; Write bytes: 296740864; Read time: 0; Write time: 1561 09:50:00 INFO - Running post-run listener: _upload_blobber_files 09:50:00 INFO - Blob upload gear active. 09:50:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:00 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 09:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:50:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:01 INFO - (blobuploader) - INFO - Done attempting. 09:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:50:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:02 INFO - (blobuploader) - INFO - Done attempting. 09:50:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:02 INFO - Return code: 0 09:50:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36b35002a4d45a43c690ea9c256935ecd56514285d213d38a861d4fe1268c9dd3bb8962bebd2edc92712ddfb89fc3d5bf98c45a73934a4612014d2f478892fb1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46ae204383ad47019951b52793c2b84ef3e080f98293256c863c3136e13004660c03430794f170246ff36ab023cc2f021dc09d847768b5190d684f70bfefb5b7"} 09:50:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:02 INFO - Contents: 09:50:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36b35002a4d45a43c690ea9c256935ecd56514285d213d38a861d4fe1268c9dd3bb8962bebd2edc92712ddfb89fc3d5bf98c45a73934a4612014d2f478892fb1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46ae204383ad47019951b52793c2b84ef3e080f98293256c863c3136e13004660c03430794f170246ff36ab023cc2f021dc09d847768b5190d684f70bfefb5b7"} 09:50:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:02 INFO - Copying logs to upload dir... 09:50:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.684206 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-04-07 09:50:03.176196) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:50:03.180287) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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/Fx-Team/sha512/36b35002a4d45a43c690ea9c256935ecd56514285d213d38a861d4fe1268c9dd3bb8962bebd2edc92712ddfb89fc3d5bf98c45a73934a4612014d2f478892fb1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46ae204383ad47019951b52793c2b84ef3e080f98293256c863c3136e13004660c03430794f170246ff36ab023cc2f021dc09d847768b5190d684f70bfefb5b7"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011073 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1459966130/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36b35002a4d45a43c690ea9c256935ecd56514285d213d38a861d4fe1268c9dd3bb8962bebd2edc92712ddfb89fc3d5bf98c45a73934a4612014d2f478892fb1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46ae204383ad47019951b52793c2b84ef3e080f98293256c863c3136e13004660c03430794f170246ff36ab023cc2f021dc09d847768b5190d684f70bfefb5b7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 09:50:03.216399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:50:03.216760) ========= 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.K4QMPaebsq/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.x5AsVDgAEk/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.004532 ========= master_lag: 4.57 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-07 09:50:07.793445) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:50:07.793856) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 09:50:07.799675) ========= ========= Total master_lag: 8.84 =========