builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0115 starttime: 1474406359.27 results: success (0) buildid: 20160920141830 builduid: facff264b90c4909a8d98cd6e98d959f revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.272470) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.272803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.273068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.333034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.333321) ========= 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.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-20 14:19:19-- 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% 15.6M=0.001s 2016-09-20 14:19:19 (15.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155617 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.518943) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:19.522230) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.028879 ========= master_lag: 0.55 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:20.103162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:19:20.103474) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6be9a5c9c8cd25d2082376495b788d85ce3cca98 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-20 14:19:20,515 truncating revision to first 12 chars 2016-09-20 14:19:20,515 Setting DEBUG logging. 2016-09-20 14:19:20,515 attempt 1/10 2016-09-20 14:19:20,515 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6be9a5c9c8cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 14:19:21,637 unpacking tar archive at: mozilla-beta-6be9a5c9c8cd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.380555 ========= master_lag: 0.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 14:19:21.839762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.840078) ========= script_repo_revision: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.843473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.843735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 14:19:21.863017) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-20 14:19:21.863374) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:22 INFO - MultiFileLogger online at 20160920 14:19:22 in /builds/slave/test 14:19:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:19:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:19:22 INFO - {'append_to_log': False, 14:19:22 INFO - 'base_work_dir': '/builds/slave/test', 14:19:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:19:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:19:22 INFO - 'branch': 'Mozilla-Beta', 14:19:22 INFO - 'buildbot_json_path': 'buildprops.json', 14:19:22 INFO - 'config_files': ('talos/mac_config.py',), 14:19:22 INFO - 'default_actions': ('clobber', 14:19:22 INFO - 'read-buildbot-config', 14:19:22 INFO - 'download-and-extract', 14:19:22 INFO - 'populate-webroot', 14:19:22 INFO - 'create-virtualenv', 14:19:22 INFO - 'install', 14:19:22 INFO - 'run-tests'), 14:19:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:19:22 INFO - 'download_minidump_stackwalk': True, 14:19:22 INFO - 'download_symbols': 'ondemand', 14:19:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:19:22 INFO - 'tooltool.py': '/tools/tooltool.py', 14:19:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:19:22 INFO - '/tools/misc-python/virtualenv.py')}, 14:19:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:19:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:19:22 INFO - 'installer_path': 'installer.exe', 14:19:22 INFO - 'log_level': 'info', 14:19:22 INFO - 'log_name': 'talos', 14:19:22 INFO - 'log_to_console': True, 14:19:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:19:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:19:22 INFO - 'opt_config_files': (), 14:19:22 INFO - 'pip_index': False, 14:19:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:22 INFO - 'cmd': ('bash', 14:19:22 INFO - '-c', 14:19:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:22 INFO - 'enabled': True, 14:19:22 INFO - 'halt_on_failure': False, 14:19:22 INFO - 'name': 'check_screen_resolution'},), 14:19:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:19:22 INFO - 'cmd': ('bash', 14:19:22 INFO - '-c', 14:19:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:19:22 INFO - 'enabled': True, 14:19:22 INFO - 'halt_on_failure': False, 14:19:22 INFO - 'name': 'check_screen_resolution'},), 14:19:22 INFO - 'run_cmd_checks_enabled': True, 14:19:22 INFO - 'sps_profile': False, 14:19:22 INFO - 'sps_profile_interval': 0, 14:19:22 INFO - 'suite': 'dromaeojs-e10s', 14:19:22 INFO - 'system_bits': '32', 14:19:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:19:22 INFO - 'title': 't-yosemite-r7-0115', 14:19:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:19:22 INFO - 'use_talos_json': True, 14:19:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:19:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:19:22 INFO - 'work_dir': 'build'} 14:19:22 INFO - [mozharness: 2016-09-20 21:19:22.075430Z] Running clobber step. 14:19:22 INFO - Running pre-action listener: _resource_record_pre_action 14:19:22 INFO - Running main action method: clobber 14:19:22 INFO - rmtree: /builds/slave/test/build 14:19:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - [mozharness: 2016-09-20 21:19:24.478048Z] Finished clobber step (success) 14:19:24 INFO - [mozharness: 2016-09-20 21:19:24.478162Z] Running read-buildbot-config step. 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: read_buildbot_config 14:19:24 INFO - Using buildbot properties: 14:19:24 INFO - { 14:19:24 INFO - "project": "", 14:19:24 INFO - "product": "firefox", 14:19:24 INFO - "script_repo_revision": "production", 14:19:24 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 14:19:24 INFO - "repository": "", 14:19:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 14:19:24 INFO - "buildid": "20160920141830", 14:19:24 INFO - "stage_platform": "macosx64", 14:19:24 INFO - "basedir": "/builds/slave/test", 14:19:24 INFO - "buildnumber": 4, 14:19:24 INFO - "platform": "yosemite_r7", 14:19:24 INFO - "mozci_request": { 14:19:24 INFO - "priority": -1, 14:19:24 INFO - "type": "trigger_all_talos_jobs", 14:19:24 INFO - "times": 6 14:19:24 INFO - }, 14:19:24 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:19:24 INFO - "slavebuilddir": "test", 14:19:24 INFO - "branch": "mozilla-beta", 14:19:24 INFO - "revision": "6be9a5c9c8cd25d2082376495b788d85ce3cca98", 14:19:24 INFO - "slavename": "t-yosemite-r7-0115", 14:19:24 INFO - "builduid": "facff264b90c4909a8d98cd6e98d959f", 14:19:24 INFO - "repo_path": "releases/mozilla-beta" 14:19:24 INFO - } 14:19:24 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg. 14:19:24 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json. 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - [mozharness: 2016-09-20 21:19:24.481468Z] Finished read-buildbot-config step (success) 14:19:24 INFO - [mozharness: 2016-09-20 21:19:24.481583Z] Running download-and-extract step. 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: download_and_extract 14:19:24 INFO - mkdir: /builds/slave/test/build/tests 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json 14:19:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json'}, attempt #1 14:19:24 INFO - Downloaded 1165 bytes. 14:19:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.mac.test_packages.json 14:19:24 INFO - Using the following test package requirements: 14:19:24 INFO - {u'common': [u'firefox-50.0.en-US.mac.common.tests.zip'], 14:19:24 INFO - u'cppunittest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.cppunittest.tests.zip'], 14:19:24 INFO - u'gtest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.gtest.tests.zip'], 14:19:24 INFO - u'jittest': [u'firefox-50.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:19:24 INFO - u'mochitest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.mochitest.tests.zip'], 14:19:24 INFO - u'mozbase': [u'firefox-50.0.en-US.mac.common.tests.zip'], 14:19:24 INFO - u'reftest': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.reftest.tests.zip'], 14:19:24 INFO - u'talos': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.talos.tests.zip'], 14:19:24 INFO - u'web-platform': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.web-platform.tests.zip'], 14:19:24 INFO - u'xpcshell': [u'firefox-50.0.en-US.mac.common.tests.zip', 14:19:24 INFO - u'firefox-50.0.en-US.mac.xpcshell.tests.zip']} 14:19:24 INFO - Downloading packages: [u'firefox-50.0.en-US.mac.common.tests.zip'] for test suite category: common 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip 14:19:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip'}, attempt #1 14:19:25 INFO - Downloaded 16113415 bytes. 14:19:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:19:28 INFO - Return code: 0 14:19:28 INFO - Downloading packages: [u'firefox-50.0.en-US.mac.common.tests.zip', u'firefox-50.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:19: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')]} 14:19:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip 14:19:28 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip 14:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip'}, attempt #1 14:19:28 INFO - Downloaded 16113415 bytes. 14:19:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:19:32 INFO - Return code: 0 14:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:32 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip 14:19:32 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip 14:19:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip'}, attempt #1 14:19:32 INFO - Downloaded 14413740 bytes. 14:19:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:19:32 INFO - Return code: 0 14:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:32 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:32 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0.en-US.mac.dmg 14:19:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.mac.dmg'}, attempt #1 14:19:34 INFO - Downloaded 86213837 bytes. 14:19:34 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:34 INFO - mkdir: /builds/slave/test/properties 14:19:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:19:34 INFO - Writing to file /builds/slave/test/properties/build_url 14:19:34 INFO - Contents: 14:19:34 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg 14:19:35 INFO - Running post-action listener: _resource_record_post_action 14:19:35 INFO - Running post-action listener: set_extra_try_arguments 14:19:35 INFO - [mozharness: 2016-09-20 21:19:35.470020Z] Finished download-and-extract step (success) 14:19:35 INFO - [mozharness: 2016-09-20 21:19:35.470161Z] Running populate-webroot step. 14:19:35 INFO - Running pre-action listener: _resource_record_pre_action 14:19:35 INFO - Running main action method: populate_webroot 14:19:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:19:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:19:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:19:35 INFO - '--tppagecycles', 14:19:35 INFO - '7'], 14:19:35 INFO - 'tests': ['tsvgm']}}, 14:19:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:19:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:19:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:19:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:19:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['damp', 'tps']}, 14:19:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'tests': ['damp', 'tps']}, 14:19:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['dromaeo_dom']}, 14:19:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:19:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['basic_compositor_video']}, 14:19:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:19:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'other-e10s': {'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['a11yr', 14:19:35 INFO - 'ts_paint', 14:19:35 INFO - 'tpaint', 14:19:35 INFO - 'sessionrestore', 14:19:35 INFO - 'sessionrestore_no_auto_restore', 14:19:35 INFO - 'tabpaint']}, 14:19:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['tsvgx', 14:19:35 INFO - 'tsvgr_opacity', 14:19:35 INFO - 'tart', 14:19:35 INFO - 'tscrollx', 14:19:35 INFO - 'cart']}, 14:19:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:19:35 INFO - 'tsvgr_opacity', 14:19:35 INFO - 'tart', 14:19:35 INFO - 'tscrollx', 14:19:35 INFO - 'cart']}, 14:19:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'talos_options': ['--disable-e10s'], 14:19:35 INFO - 'tests': ['tp5o']}, 14:19:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'tests': ['tp5o']}, 14:19:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'talos_options': ['--disable-e10s', 14:19:35 INFO - '--xperf_path', 14:19:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:35 INFO - 'tests': ['tp5n']}, 14:19:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:35 INFO - 'talos_options': ['--xperf_path', 14:19:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:35 INFO - 'tests': ['tp5n']}}, 14:19:35 INFO - 'talos.zip': {'path': '', 14:19:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:19:35 INFO - Running post-action listener: _resource_record_post_action 14:19:35 INFO - [mozharness: 2016-09-20 21:19:35.489536Z] Finished populate-webroot step (success) 14:19:35 INFO - [mozharness: 2016-09-20 21:19:35.489675Z] Running create-virtualenv step. 14:19:35 INFO - Running pre-action listener: _resource_record_pre_action 14:19:35 INFO - Running main action method: create_virtualenv 14:19:35 INFO - Creating virtualenv /builds/slave/test/build/venv 14:19:35 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 14:19:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:19:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:19:35 INFO - Using real prefix '/tools/python27' 14:19:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:19:36 INFO - Installing distribute.............................................................................................................................................................................................done. 14:19:39 INFO - Installing pip.................done. 14:19:39 INFO - Return code: 0 14:19:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:19:39 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')]} 14:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 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')]} 14:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:39 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 14:19:39 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 14:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:39 INFO - 'HOME': '/Users/cltbld', 14:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:39 INFO - 'LOGNAME': 'cltbld', 14:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:39 INFO - 'MOZ_NO_REMOTE': '1', 14:19:39 INFO - 'NO_EM_RESTART': '1', 14:19:39 INFO - 'PAGER': '/bin/cat', 14:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:39 INFO - 'PWD': '/builds/slave/test', 14:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:39 INFO - 'SHELL': '/bin/bash', 14:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:39 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:39 INFO - 'USER': 'cltbld', 14:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:39 INFO - 'XPC_FLAGS': '0x0', 14:19:39 INFO - 'XPC_SERVICE_NAME': '0', 14:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:39 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 14:19:39 INFO - Cleaning up... 14:19:39 INFO - Return code: 0 14:19:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:19:39 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')]} 14:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 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')]} 14:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:39 INFO - 'HOME': '/Users/cltbld', 14:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:39 INFO - 'LOGNAME': 'cltbld', 14:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:39 INFO - 'MOZ_NO_REMOTE': '1', 14:19:39 INFO - 'NO_EM_RESTART': '1', 14:19:39 INFO - 'PAGER': '/bin/cat', 14:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:39 INFO - 'PWD': '/builds/slave/test', 14:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:39 INFO - 'SHELL': '/bin/bash', 14:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:39 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:39 INFO - 'USER': 'cltbld', 14:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:39 INFO - 'XPC_FLAGS': '0x0', 14:19:39 INFO - 'XPC_SERVICE_NAME': '0', 14:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:39 INFO - Downloading/unpacking psutil>=3.1.1 14:19:39 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 14:19:39 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 14:19:39 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 14:19:39 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 14:19:39 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 14:19:39 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 14:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:42 INFO - Installing collected packages: psutil 14:19:42 INFO - Running setup.py install for psutil 14:19:42 INFO - building 'psutil._psutil_osx' extension 14:19:42 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 14:19:43 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 14:19:43 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 14:19:43 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 14:19:43 INFO - building 'psutil._psutil_posix' extension 14:19:43 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 14:19:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:19:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:19:43 INFO - ^ 14:19:43 INFO - 1 warning generated. 14:19:43 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 14:19:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:43 INFO - Successfully installed psutil 14:19:43 INFO - Cleaning up... 14:19:44 INFO - Return code: 0 14:19:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:19:44 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')]} 14:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:44 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')]} 14:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:44 INFO - 'HOME': '/Users/cltbld', 14:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:44 INFO - 'LOGNAME': 'cltbld', 14:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:44 INFO - 'MOZ_NO_REMOTE': '1', 14:19:44 INFO - 'NO_EM_RESTART': '1', 14:19:44 INFO - 'PAGER': '/bin/cat', 14:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:44 INFO - 'PWD': '/builds/slave/test', 14:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:44 INFO - 'SHELL': '/bin/bash', 14:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:44 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:44 INFO - 'USER': 'cltbld', 14:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:44 INFO - 'XPC_FLAGS': '0x0', 14:19:44 INFO - 'XPC_SERVICE_NAME': '0', 14:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:19:44 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 14:19:44 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 14:19:44 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 14:19:44 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 14:19:44 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 14:19:44 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 14:19:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:19:47 INFO - Installing collected packages: mozsystemmonitor 14:19:47 INFO - Running setup.py install for mozsystemmonitor 14:19:47 INFO - Successfully installed mozsystemmonitor 14:19:47 INFO - Cleaning up... 14:19:47 INFO - Return code: 0 14:19:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:48 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')]} 14:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:19:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:48 INFO - 'HOME': '/Users/cltbld', 14:19:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:48 INFO - 'LOGNAME': 'cltbld', 14:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:48 INFO - 'MOZ_NO_REMOTE': '1', 14:19:48 INFO - 'NO_EM_RESTART': '1', 14:19:48 INFO - 'PAGER': '/bin/cat', 14:19:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:48 INFO - 'PWD': '/builds/slave/test', 14:19:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:48 INFO - 'SHELL': '/bin/bash', 14:19:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:48 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:48 INFO - 'USER': 'cltbld', 14:19:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:48 INFO - 'XPC_FLAGS': '0x0', 14:19:48 INFO - 'XPC_SERVICE_NAME': '0', 14:19:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:51 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:51 INFO - warning: no files found matching '*.txt' 14:19:51 INFO - no previously-included directories found matching 'build' 14:19:51 INFO - no previously-included directories found matching 'dist' 14:19:51 INFO - no previously-included directories found matching '.git*' 14:19:51 INFO - Installing collected packages: jsonschema, functools32 14:19:51 INFO - Running setup.py install for functools32 14:19:51 INFO - warning: no files found matching '*.txt' 14:19:51 INFO - no previously-included directories found matching 'build' 14:19:51 INFO - no previously-included directories found matching 'dist' 14:19:51 INFO - no previously-included directories found matching '.git*' 14:19:51 INFO - Successfully installed jsonschema functools32 14:19:51 INFO - Cleaning up... 14:19:51 INFO - Return code: 0 14:19:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:51 INFO - 'HOME': '/Users/cltbld', 14:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:51 INFO - 'LOGNAME': 'cltbld', 14:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:51 INFO - 'MOZ_NO_REMOTE': '1', 14:19:51 INFO - 'NO_EM_RESTART': '1', 14:19:51 INFO - 'PAGER': '/bin/cat', 14:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:51 INFO - 'PWD': '/builds/slave/test', 14:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:51 INFO - 'SHELL': '/bin/bash', 14:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:51 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:51 INFO - 'USER': 'cltbld', 14:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:51 INFO - 'XPC_FLAGS': '0x0', 14:19:51 INFO - 'XPC_SERVICE_NAME': '0', 14:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:19:51 INFO - Cleaning up... 14:19:51 INFO - Return code: 0 14:19:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:51 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:51 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 14:19:51 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 14:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:51 INFO - 'HOME': '/Users/cltbld', 14:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:51 INFO - 'LOGNAME': 'cltbld', 14:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:51 INFO - 'MOZ_NO_REMOTE': '1', 14:19:51 INFO - 'NO_EM_RESTART': '1', 14:19:51 INFO - 'PAGER': '/bin/cat', 14:19:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:51 INFO - 'PWD': '/builds/slave/test', 14:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:51 INFO - 'SHELL': '/bin/bash', 14:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:51 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:51 INFO - 'USER': 'cltbld', 14:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:51 INFO - 'XPC_FLAGS': '0x0', 14:19:51 INFO - 'XPC_SERVICE_NAME': '0', 14:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19: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 14:19:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:55 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 14:19:55 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 14:19:55 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 14:19:55 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 14:19:55 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 14:19:55 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 14:19:55 INFO - Downloading docopt-0.6.1.tar.gz 14:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:55 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:55 INFO - Running setup.py install for blobuploader 14:19:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:55 INFO - Running setup.py install for requests 14:19:56 INFO - Running setup.py install for docopt 14:19:56 INFO - Successfully installed blobuploader requests docopt 14:19:56 INFO - Cleaning up... 14:19:56 INFO - Return code: 0 14:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:56 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:19:56 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 14:19:56 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 14:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:56 INFO - 'HOME': '/Users/cltbld', 14:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:56 INFO - 'LOGNAME': 'cltbld', 14:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:56 INFO - 'MOZ_NO_REMOTE': '1', 14:19:56 INFO - 'NO_EM_RESTART': '1', 14:19:56 INFO - 'PAGER': '/bin/cat', 14:19:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:56 INFO - 'PWD': '/builds/slave/test', 14:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:56 INFO - 'SHELL': '/bin/bash', 14:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:19:56 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:56 INFO - 'USER': 'cltbld', 14:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:56 INFO - 'XPC_FLAGS': '0x0', 14:19:56 INFO - 'XPC_SERVICE_NAME': '0', 14:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:56 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-daTA3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-jgiNxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-UFj3kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-DX5S5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-68oeOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-GfK8Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2P5uph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-YFfyft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-iHKmB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HHznnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:57 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-okhz_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-UC90ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-0zctm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-G0ObCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-h0GpwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6DtGRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:58 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Mswrh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:58 INFO - Running setup.py install for manifestparser 14:19:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:58 INFO - Running setup.py install for mozcrash 14:19:59 INFO - Running setup.py install for mozdebug 14:19:59 INFO - Running setup.py install for mozdevice 14:19:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Running setup.py install for mozfile 14:19:59 INFO - Running setup.py install for mozhttpd 14:19:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Running setup.py install for mozinfo 14:19:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Running setup.py install for mozInstall 14:19:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:59 INFO - Running setup.py install for mozleak 14:20:00 INFO - Running setup.py install for mozlog 14:20:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Running setup.py install for moznetwork 14:20:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Running setup.py install for mozprocess 14:20:00 INFO - Running setup.py install for mozprofile 14:20:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Running setup.py install for mozrunner 14:20:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:20:00 INFO - Running setup.py install for mozscreenshot 14:20:00 INFO - Running setup.py install for moztest 14:20:01 INFO - Running setup.py install for mozversion 14:20:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:20:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:20:01 INFO - Cleaning up... 14:20:01 INFO - Return code: 0 14:20:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:01 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:20:01 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 14:20:01 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 14:20:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:20:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:01 INFO - 'HOME': '/Users/cltbld', 14:20:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:01 INFO - 'LOGNAME': 'cltbld', 14:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:01 INFO - 'MOZ_NO_REMOTE': '1', 14:20:01 INFO - 'NO_EM_RESTART': '1', 14:20:01 INFO - 'PAGER': '/bin/cat', 14:20:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:01 INFO - 'PWD': '/builds/slave/test', 14:20:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:01 INFO - 'SHELL': '/bin/bash', 14:20:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:20:01 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:20:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:01 INFO - 'USER': 'cltbld', 14:20:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:01 INFO - 'XPC_FLAGS': '0x0', 14:20:01 INFO - 'XPC_SERVICE_NAME': '0', 14:20:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:20:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xMYB93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:20:01 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)) 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:20:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-CF27wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:20:01 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)) 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:20:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MKz7qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:20:01 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)) 14:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:20:01 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7fNBVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-blyyKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-8KJZbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-21mBt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-H126O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MZULDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-C5aJfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:20:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-VRfRj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-imkCPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:20:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7WbdFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:20:02 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)) 14:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:20:02 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WbqIec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:03 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-hVjtD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:20:03 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)) 14:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:20:03 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7JB6M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:20:03 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)) 14:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:20:03 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tpJJMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:20:03 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)) 14:20:03 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)) 14:20:03 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)) 14:20:03 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)) 14:20:03 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)) 14:20:03 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)) 14:20:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:20:03 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 14:20:03 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 14:20:03 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 14:20:03 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 14:20:03 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 14:20:03 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 14:20:05 INFO - Downloading blessings-1.6.tar.gz 14:20:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:20:05 INFO - Installing collected packages: blessings 14:20:05 INFO - Running setup.py install for blessings 14:20:06 INFO - Successfully installed blessings 14:20:06 INFO - Cleaning up... 14:20:06 INFO - Return code: 0 14:20:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:06 INFO - Reading from file tmpfile_stdout 14:20:06 INFO - Current package versions: 14:20:06 INFO - blessings == 1.6 14:20:06 INFO - blobuploader == 1.2.4 14:20:06 INFO - docopt == 0.6.1 14:20:06 INFO - functools32 == 3.2.3-2 14:20:06 INFO - jsonschema == 2.5.1 14:20:06 INFO - manifestparser == 1.1 14:20:06 INFO - mozInstall == 1.12 14:20:06 INFO - mozcrash == 0.17 14:20:06 INFO - mozdebug == 0.1 14:20:06 INFO - mozdevice == 0.48 14:20:06 INFO - mozfile == 1.2 14:20:06 INFO - mozhttpd == 0.7 14:20:06 INFO - mozinfo == 0.9 14:20:06 INFO - mozleak == 0.1 14:20:06 INFO - mozlog == 3.3 14:20:06 INFO - moznetwork == 0.27 14:20:06 INFO - mozprocess == 0.23 14:20:06 INFO - mozprofile == 0.28 14:20:06 INFO - mozrunner == 6.12 14:20:06 INFO - mozscreenshot == 0.1 14:20:06 INFO - mozsystemmonitor == 0.3 14:20:06 INFO - moztest == 0.7 14:20:06 INFO - mozversion == 1.4 14:20:06 INFO - psutil == 3.1.1 14:20:06 INFO - requests == 1.2.3 14:20:06 INFO - wsgiref == 0.1.2 14:20:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:06 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:20:06 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 14:20:06 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 14:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:06 INFO - 'HOME': '/Users/cltbld', 14:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:06 INFO - 'LOGNAME': 'cltbld', 14:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:06 INFO - 'MOZ_NO_REMOTE': '1', 14:20:06 INFO - 'NO_EM_RESTART': '1', 14:20:06 INFO - 'PAGER': '/bin/cat', 14:20:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:06 INFO - 'PWD': '/builds/slave/test', 14:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:06 INFO - 'SHELL': '/bin/bash', 14:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:20:06 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:06 INFO - 'USER': 'cltbld', 14:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:06 INFO - 'XPC_FLAGS': '0x0', 14:20:06 INFO - 'XPC_SERVICE_NAME': '0', 14:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 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)) 14:20:06 INFO - Cleaning up... 14:20:06 INFO - Return code: 0 14:20:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:20:06 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 0x10d495a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d52a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff569d9cbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, '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.mi4h61cI2g/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.pT1h9m5hiP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 14:20:06 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 14:20:06 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 14:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:06 INFO - 'HOME': '/Users/cltbld', 14:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:06 INFO - 'LOGNAME': 'cltbld', 14:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:06 INFO - 'MOZ_NO_REMOTE': '1', 14:20:06 INFO - 'NO_EM_RESTART': '1', 14:20:06 INFO - 'PAGER': '/bin/cat', 14:20:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:06 INFO - 'PWD': '/builds/slave/test', 14:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:06 INFO - 'SHELL': '/bin/bash', 14:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:20:06 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:06 INFO - 'USER': 'cltbld', 14:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:06 INFO - 'XPC_FLAGS': '0x0', 14:20:06 INFO - 'XPC_SERVICE_NAME': '0', 14:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:20:07 INFO - Cleaning up... 14:20:07 INFO - Return code: 0 14:20:07 INFO - Running post-action listener: _resource_record_post_action 14:20:07 INFO - Running post-action listener: _start_resource_monitoring 14:20:07 INFO - Starting resource monitoring. 14:20:07 INFO - [mozharness: 2016-09-20 21:20:07.098406Z] Finished create-virtualenv step (success) 14:20:07 INFO - [mozharness: 2016-09-20 21:20:07.099352Z] Running install step. 14:20:07 INFO - Running pre-action listener: _resource_record_pre_action 14:20:07 INFO - Running main action method: install 14:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:20:07 INFO - Reading from file tmpfile_stdout 14:20:07 INFO - Detecting whether we're running mozinstall >=1.0... 14:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:20:07 INFO - Reading from file tmpfile_stdout 14:20:07 INFO - Output received: 14:20:07 INFO - Usage: mozinstall [options] installer 14:20:07 INFO - Options: 14:20:07 INFO - -h, --help show this help message and exit 14:20:07 INFO - -d DEST, --destination=DEST 14:20:07 INFO - Directory to install application into. [default: 14:20:07 INFO - "/builds/slave/test"] 14:20:07 INFO - --app=APP Application being installed. [default: firefox] 14:20:07 INFO - mkdir: /builds/slave/test/build/application 14:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:20:29 INFO - Reading from file tmpfile_stdout 14:20:29 INFO - Output received: 14:20:29 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:20:29 INFO - Running post-action listener: _resource_record_post_action 14:20:29 INFO - [mozharness: 2016-09-20 21:20:29.519896Z] Finished install step (success) 14:20:29 INFO - [mozharness: 2016-09-20 21:20:29.520070Z] Running run-tests step. 14:20:29 INFO - Running pre-action listener: _resource_record_pre_action 14:20:29 INFO - Running main action method: run_tests 14:20:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:29 INFO - 2016-09-20 14:20:29.534 screenresolution[1647:7463] starting screenresolution argv=screenresolution get 14:20:29 INFO - 2016-09-20 14:20:29.537 screenresolution[1647:7463] Display 0: 1600x1200x32@60 14:20:29 INFO - 2016-09-20 14:20:29.543 screenresolution[1648:7465] starting screenresolution argv=screenresolution list 14:20:29 INFO - Available Modes on Display 0 14:20:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:29 INFO - Intel Iris: 14:20:29 INFO - Chipset Model: Intel Iris 14:20:29 INFO - Type: GPU 14:20:29 INFO - Bus: Built-In 14:20:29 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:29 INFO - Vendor: Intel (0x8086) 14:20:29 INFO - Device ID: 0x0a2e 14:20:29 INFO - Revision ID: 0x0009 14:20:29 INFO - Displays: 14:20:29 INFO - Display: 14:20:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:29 INFO - Display Serial Number: 1600x1200 60 14:20:29 INFO - Main Display: Yes 14:20:29 INFO - Mirror: Off 14:20:29 INFO - Online: Yes 14:20:29 INFO - Rotation: Supported 14:20:29 INFO - Return code: 0 14:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:20:29 INFO - Python 2.7.3 14:20:29 INFO - Return code: 0 14:20:29 INFO - grabbing minidump binary from tooltool 14:20:29 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')]} 14:20:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f9d30>, '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 0x10d19edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3f1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:20:29 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 14:20:29 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 14:20:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:20:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:20:29 INFO - Return code: 0 14:20:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:20:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:20:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:20:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:20:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mi4h61cI2g/Render', 14:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:29 INFO - 'HOME': '/Users/cltbld', 14:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:29 INFO - 'LOGNAME': 'cltbld', 14:20:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:20:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:29 INFO - 'MOZ_NO_REMOTE': '1', 14:20:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:20:29 INFO - 'NO_EM_RESTART': '1', 14:20:29 INFO - 'PAGER': '/bin/cat', 14:20:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:29 INFO - 'PWD': '/builds/slave/test', 14:20:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:29 INFO - 'SHELL': '/bin/bash', 14:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pT1h9m5hiP/Listeners', 14:20:29 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 14:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:29 INFO - 'USER': 'cltbld', 14:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:29 INFO - 'XPC_FLAGS': '0x0', 14:20:29 INFO - 'XPC_SERVICE_NAME': '0', 14:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:20:30 INFO - mozversion application_buildid: 20160920092516 14:20:30 INFO - mozversion application_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:20:30 INFO - mozversion application_display_name: Firefox 14:20:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:20:30 INFO - mozversion application_name: Firefox 14:20:30 INFO - mozversion application_remotingname: firefox 14:20:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:30 INFO - mozversion application_vendor: Mozilla 14:20:30 INFO - mozversion application_version: 50.0 14:20:30 INFO - mozversion platform_buildid: 20160920092516 14:20:30 INFO - mozversion platform_changeset: 6be9a5c9c8cd25d2082376495b788d85ce3cca98 14:20:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:20:30 INFO - mozversion platform_version: 50.0 14:20:30 INFO - using testdate: 1474406430 14:20:30 INFO - actual date: 1474406430 14:20:30 INFO - starting webserver on 'localhost:49265' 14:20:30 INFO - SUITE-START | Running 2 tests 14:20:30 INFO - TEST-START | dromaeo_css 14:20:30 INFO - operating with platform_type : mac_ 14:20:30 INFO - Initialising browser for dromaeo_css test... 14:20:30 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpFjGKv1/profile http://localhost:49265/getInfo.html) 14:20:32 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 14:20:32 INFO - PROCESS | 1658 | colorDepth:24 14:20:32 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 14:20:32 INFO - PROCESS | 1658 | __metrics 14:20:32 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:20:33 INFO - TEST-INFO | 1658: exit 0 14:20:33 INFO - Browser initialized. 14:20:33 INFO - Running cycle 1/1 for dromaeo_css test... 14:20:33 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpFjGKv1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:45 INFO - PROCESS | 1665 | RSS: Main: 174739456 14:20:45 INFO - PROCESS | 1665 | 14:21:35 INFO - PROCESS | 1665 | 1474406495272 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] 14:23:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 14:23:46 INFO - PROCESS | 1665 | RSS: Main: 186236928 14:23:46 INFO - PROCESS | 1665 | 14:26:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 14:26:49 INFO - PROCESS | 1665 | RSS: Main: 198504448 14:26:49 INFO - PROCESS | 1665 | 14:29:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 14:29:51 INFO - PROCESS | 1665 | RSS: Main: 200593408 14:29:51 INFO - PROCESS | 1665 | 14:32:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 14:32:54 INFO - PROCESS | 1665 | RSS: Main: 204918784 14:32:54 INFO - PROCESS | 1665 | 14:35:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 14:35:57 INFO - PROCESS | 1665 | RSS: Main: 206942208 14:35:57 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 14:39:00 INFO - PROCESS | 1665 | RSS: Main: 206225408 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | __start_tp_report 14:39:00 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 14:39:00 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 14:39:00 INFO - PROCESS | 1665 | |i|pagename|runs| 14:39:00 INFO - PROCESS | 1665 | |0;dojo.html;3615.313090512803;3616.656417640323;3628.727845411595;3634.6728794408514;3674.926501469971;5086.821961231358;5807.680577568232;5935.525157987361;5985.670788106652;6015.518758499321;5799.826005219839;5814.854628634275;5890.469857712805;5899.705014749266;5909.881802363951;5271.762770675319;5317.601179911503;5327.493888080626;5362.678239305635;5416.91874621881;5300.125479295912;5349.732513374316;5369.19462080689;5395.7032363220005;5425.240466334716;5322.2548843161985;5342.305500284958;5380.192971054334;5384.3538775346815;5385.838424847241;5327.893442131174;5333.946660533423;5382.770337797329;5390.757415916293;5406.999705054558;5248.606354523387;5263.631545791796;5267.183586544092;5313.0967735485065;5340;5905.818836232771;5933.792317268918;5970.13433052205;5978.940210597925;6066.241719785027;6225.62646241227;6227.1282020517165;6277.027060805537;6287.2140982377205;6307.117003619451;6223.719932603044;6230.8130756076935;6235.625862448268;6258.186435763367;6348.682565871734;6059.21230240066;6070.757169713162;6084.908726369108;6085.5394705270855;6108.992016317302;6414.262359828586;6470.708818103197;6473.579217350857;6480.805575832733;6501.8699626008165;6196.566240363221;6213.068039794067;6223.190985171898;6230.753849230172;6281.59169653971;5781.82654520365;5966.671833049224;6387.808365749035;6392;6429.807105786834;6229.345918678564;6268.435840774261;6276.497880169667;6313.273973493016;6326.398992095744;6175.2898416681755;6205.875882482325;6226.937730622636;6230.439260466489;6316.589421687576;5098.46466121052;5131.076406246837;5155;5161.870953226199;5173.948260517347;4633.652476064309;4647.767611619405;4658.34783130362;4661.696989695659;4665.253559430543;5975.641461512323;6076.392360763888;6079.209702738704;6099.512039036955;6147.815565533041;6365.077063825812;6395.936040639534;6396.360363963567;6397.808065758035;6402.583832050902;5997.220361352996;6166.845828854225;6173.382661733791;6184.5052395808225;6190.2571691397325;5496.587755918322;5575.191597218381;5612.017896868032;5632.5212356950115;5677.772889084473;6556.836079097965;6604.405603495612;6614.272430032596;6619;6621.801345959629;6358.650274234777;6375;6390.360963903572;6395.616263024233;6398.360163983564;6362.9681851592295;6450.516211284079;6494.2856285807575;6512.218533775977;6592.439642630238;6261.186045814015;6275.623462592259;6284.654344011124;6289.9056514153235;6294.590851594815;6323.557350985387;6380.042993551005;6385.425311721968;6400.39196276353;6405.327440618855;6158.846028849314;6181.134641150153;6188.938110618837;6197.566170368031;6248.9062664059165;6219.222597175353;6247.781327653511;6250.968745156245;6256.624602523863;6258.655773932477;6077.301110372364;6211.3167551570195;6221.751129954932;6237.5009999200865;6250.531210159347;5468.316460442445;5650.89807487536;6115.666295175977;6237.656928868956;6270.623762574261;5282.4717528246865;6200.255969283535;6214.5960512566535;6254.21822272216;6267.310595834363 14:39:00 INFO - PROCESS | 1665 | |1;ext.html;509.5515945967548;539.9821336780169;549.6701978812711;561.0910325273059;567.809783722453;34974.65025349746;43992.56007439925;44003.339949900736;44130.77934610331;44170;43143.78428107855;43359.34960975588;43808.56191438084;43997.56002439975;44258.89352766182;9851.359661622007;10007.499625018756;10034.197264218845;10077.798444031116;10091.848622270672;8730.51982140982;9830.852537211947;9951.004899510064;10061.993800619952;10063.597456101747;10008.699739007841;10035;10035.29752917296;10038.849417258747;10050.547725352377;9858.112769850712;9860.408375497458;9973.451460169725;10032.64885728996;10039.24705647072;9895.257855660855;9949.502524873727;10057.29598928068;10057.798844023078;10070.093691567721;35547.82226088853;36532.72135475272;42606.573934260574;42755;43485.13029739387;3570.7502374169094;3619.963800361989;3628.909277268063;3757.7745335279697;4609.308603709437;33285.833570832234;33637.495437568454;33791.493127603106;33834.83082584596;33849.492257616155;3268.591426071741;3401.795892246473;3416.8633254669785;3428.9485657715154;3450.551428314328;547049;550869;553397;554485.9999999959;555104.9999999959;70212.54256101012;70520;70785.64607176931;70787;70799.64600177068;37953.240935181144;38309.6169038306;38357.80821095876;39414.2117157661;39655.206895862495;19032.238710451427;19151.52121196981;19172.71240931387;19178.712319315222;19276.421707348803;12546.121771476091;14524.49164279245;14605.92697036508;14771.852281477262;14821.92589037048;20640.07119679618;21023.264185753425;21103.472413189487;21121.366359008945;21131.8943405282;8628.654853805903;8735.606897689619;8742.25690816275;8742.38803283764;8826.55867206637;935.555611084743;1025.3898930136556;1057.281048886749;1167.3812879174052;1192.421675487388;33266.66733332684;33287.66712332894;33397.833010835275;33582.99251022473;33663.83168084144;1154.3131836557236;1172.6188988578747;1230.1073989820193;1283.2685369339388;1290.6644272489032;17314.393996210074;17425.47723568295;17601.29594816193;17665.02842343658;17690;750095;755497;760644;760752;763792;65277.673611632585;65633;65944.6811063776;65961.6701916497;65973.34026659768;1460.9269536523132;1485.5320574019365;1489.1735087026805;1496.6408062065027;1508.6530098077503;31524.52713209258;32808.015759527254;33083.33833323368;33098.50352244767;33176.50235246521;57864.710676446346;58278.7086064567;58286.4171358281;58376;58414.4158558426;541231.5876841181;545762;548908;549145;550083.2495837496;29147.562786557795;29329.826806928337;29385.559216612197;29389.412211755218;29448.558271625516;52569.737151313995;52996.735016324674;53046.92670159569;53100.4689953111;53127.468725312254;41793.95515112025;43542.56457435512;44546.3318050223;44838.10323793571;45170.322445164005;42680.78659606682;42689.359659605754;42894;43871.34192987044;44879.775601121786;35948.28103437864;36359.81820090883;41317.1736565273;41726.374104387854;41783;36023.63976360203;37895.81052094722;37947.19567285353;38246.42630360602;38422.615773841906;27776.861115694293;28111.71888281173;28147.15558533247;28213.15360539187;28282 14:39:00 INFO - PROCESS | 1665 | |2;jquery.html;392.6740805131739;400.781574042147;402.12337105110885;407.17343792102037;423.10513264445706;20886.37340879772;21059.157633694653;21081.26215582455;22348.21781237653;23312;21021.789782102176;21885.781142188614;22198.445038874037;22608;22623.434414139614;5208.296879921209;5303.681779093266;5403.891922161554;5425.3489581250105;5440;4907.6073914086965;5365.141577347626;5564.666120032791;6049.243844519435;6066.181065556159;5060.721660308682;5907.113932910055;6076.4834989025985;6095.908561371583;6122.051082082262;5514.448555144493;5850.853728656775;5868.2664666916635;5915.994280972245;5924.318703349127;5017.096922553938;5246.134387826004;5246.7901283948595;5265.86835329116;5740.885182296331;12966.286854223014;13109.541166059142;13123.475060997549;16027.839721602752;26807.46385072307;2105.3157723739846;2334.6498025296173;2381.7499162588024;2417.688863409477;2722.9727702722917;9760.560774765152;9814.705558833246;9960.601575936953;10011.50117750148;10975.725606859813;1657.32049859557;1827.259959716317;1828.698264786322;1846.1230915315252;1943.9514012149668;128818.35590821986;129279;131980;132861;134697.6530234695;11178.385188814693;11213.495392707347;11234.438278086063;11486.0811135109;12110.515579376726;14982.700345993018;15225.695486090217;15417.152056636769;15451.922740386226;15459.072455652697;8886.731808299357;9112.498812565373;9177.219936305355;9350.625974961062;9396.577154028086;9187.632494700138;9236.907630923739;9265.25877929764;9334.019927907608;9539.952300238454;4287.464066991626;4360.65114790816;4369.235383807821;4666;4823.831165909176;2038.0523056778372;2054.6712525995767;2062.1442101527996;2088.4361222469847;2109.7679255281905;871.3116637856023;936.6019441737235;949.852772820214;980.2158273381267;1065.3448129400363;20598.27906023282;21708.348749537538;21750.89124554367;21804.127834886745;21805.345839624835;1023.1047833145997;1054.3726482742757;1063.2663462211049;1066.81330767117;1093.704699731068;10192.541335639915;11752.001079908132;11795.174337796443;11806.937375636235;11842.052635789116;646905;655227;660947;671172;671330;119287;135790;136689;143234;151432;1049.1711547877244;1052.3265110329396;1089.5478376473675;1106.817375133094;1109.8113320735495;17048.829511705222;18023.369182078564;19639.8036019638;19650.60698786045;19821.702674460183;51258.48741512537;52559.73720131375;54313;54560.18159727528;55074;77569;78616.21383786318;84636.57681711552;84662.57668711618;85656.14343856482;7999.320057795153;8021.879671804811;8241.175882411711;8287.337013039063;8338.541380224146;12400;13685.247311397969;13704.383302751201;13744;13788.034837561676;10493.9475302623;10634.202434817265;11519.424028798694;11778.352190629597;11782.939535441852;9035.819283614159;9729.562169702238;11776.70558236051;11840.171188016757;11858.881411185777;11829.467673954521;11841.28952262867;11857.229280096766;11898.464569094238;12207;7794.92205077942;7843.333316668147;8085.83828323442;8209.261166495044;8695.347848911484;11252.156088293246;11498.54005839757;11701.94682478581;11709.355985420883;11762.76474470523 14:39:00 INFO - PROCESS | 1665 | |3;mootools.html;1471.5364660132057;1576.6610178811557;1802.3061121468231;1937.56404808918;1972.211115553779;8308.210719981607;8910.50992195429;8989.640414383433;9473.052694730532;9476.857847132282;8959.955200223989;9178.265738740902;9180.90819091809;9198.98811130775;9233.630654773819;2441.5116976604663;2450.4363996280867;2484.2671411933525;2485.3413845331;2486.7140278867982;2302.7450039728305;2309.364924645719;2444.3644652390417;2462.54442928058;2506.9373265668323;2454.828162028659;2463.0763463701114;2467.4324905271797;2485.2544236729;2489.041718938213;2418;2439.7926176275037;2441.047991283392;2442.157455677788;2444.6577479152934;2434.6834911461574;2440.304513213743;2445.0708730682227;2450.8652024138664;2456.398182445312;9193.954030229874;9335.859962100574;9400.294977876687;9401.341906066578;9401.435913845124;5433.429489903543;5483.506484416382;5505.532029777473;5543.6396634218645;5555.972220138873;3545.8936231913085;3695.5380577427823;3749.531308586427;3757.380032294668;3766.2655782122497;1087.065123993365;1108.7616162525014;1112.9387883666477;1122.685648018556;1127.4250132432437;11826.704332391759;11935.224210426295;11987.940060299643;11992.820107698391;12020.218685785483;4796.8081276748535;4866.294387313819;4869.488703686133;4959.330490383824;4979.3526841510375;10119.342242754197;10173.949130254301;10290.639827605997;10312.793744125076;10338;5506.972465137648;5516.172574113834;5620.437956204347;5634.352049514277;5638.126090455986;4661.347411362344;4947.876303092451;5048.570871475966;5094.847154585294;5147.176451767699;4130.318497447887;4176.248275310473;4244.193603215379;4254.914901701949;4264.33902745075;1176.505867535637;1299.8375203099613;1303.687115092371;1321.7026169111873;1323.2920387592785;961.3078583419926;983.8475036369374;989.3223142147652;1003.6738060130341;1012.193235075387;5980.744531506689;6001.759929602855;6009.2188015557695;6060.484858786965;6202.472789812826;438.04069088695655;441.47684993283065;451.3230154767848;1020.132887046005;1084.4902895639036;3442.1394651337164;3636.8363423646;3936.645701886844;4141.461609990682;4181.707280490336;9223.49270790113;10921.508532116073;10984.01143897053;11264.605738799102;11522.135839812048;8402.621882015324;9138.040505746934;9454.57454414553;10585.364878107339;11089.445527723581;8221.753347399588;8461.23848853606;8599.097094804956;8635.525046122399;8637;5265.868353291045;5344.839654810362;5617.32592088952;6009.338972712911;6086.878262434638;9033.728988130366;10122.291439599157;10468.057874791306;10472.842907356244;10528.105111065644;10131.746706332106;10351.792964140826;10366;10557.472126393803;10559;7332.340089391981;7764.5729484878875;7932.801679958047;7942.682292708227;8117.553534555656;8754.387192896691;8924.330675199515;8946.68686595966;8997.235235005099;9007.189352958265;7886.566238856918;7982.241687039722;8048.5170889746805;8074.0311162660855;8136.837263254583;7905.446618736633;7911.208879112043;7999.080105787909;8105.230003149692;8109.635066422144;7118.430525558047;7269.600171990592;7392.260773922565;7394.2975417335265;7410.073740782403;5365.195220716923;5397.325334333208;5410.594205434687;5444.891102178013;5528.087865502147;5428.511433970803;5466.644668096561;5472.589555783253;5483.75323110453;5555.611107222618 14:39:00 INFO - PROCESS | 1665 | |4;prototype.html;293.9265183704074;297.9378515591915;299.92651800308926;301.76914660284876;305.3039070918307;5489.670619762812;5585.944140558593;5618.325800903887;5622.076520209489;5635.605507614464;4549.384968336242;5444.265024221729;5503.394626591082;5524.050304032616;5545.085060964936;3957.5844536323702;4064.5325787534516;4143.627073563379;4189.748615083105;4196.538380778112;4157.979210103946;4182.268103081964;4188.6649068074485;4190.308599081148;4200.264953633102;3476.3568739783223;3483.3207524532727;4134.131832315203;4164.146349998258;4172.853950111747;3828.176941957465;4060.533038700543;4171.93615628016;4194.16893596821;4251.447311849455;2986.8058576192693;3601.1897323102353;3618.6381361863864;3622.9094272643133;3629.076589407618;4423.800928958204;4425.690201685883;5417.566594672417;5424.593155513352;5428.647137936022;4595.655325850575;4825.686330388514;4828.444728856191;4840.249761287007;4855.417349917997;4778.856634300976;5337.946620533784;5670.55400872779;5778.526475748699;5835.153902684129;3363.915902102467;3688.428293614494;3995.920081598381;4001.9995001249686;4019.2966230909474;5838.4453476919625;6133.29467111279;6139.723712432951;6173.41352571505;6204.751809927598;3801.2777572261184;4049.481444458328;4068.694847886361;4070.247004304213;4107.650849677811;4151.81673223136;4160.687948403882;4163.895902602399;4167.958320416757;4180.770057646859;4604.618614415662;4825.638077144228;4836.371271734652;4862.81173471939;4889.755512224375;5682.54539636828;5872.060470324813;5874.618149820248;5903.911441328383;5927.377625349276;1390.332640332646;1412.180935057664;1414.130309859429;1421.6943357178257;1424.750668632985;788.6254029336134;803.8553060449059;862.4609618988133;866.423828154283;870.6157210036073;4517.232070548015;5194.532492075731;5226.320578324793;5369.275147855068;5516.310461192351;6084.421979911901;6094.481969032593;6099.756009759649;6112.7554897804475;6135.509159267248;5217.373915130132;5504.311961004874;5525.309336332958;5584;5595.71298601324;2759.765419939328;3019.773516986235;3148.77197892822;3164.240582260287;3170.77804553679;5532.419095994943;5977.312609049928;6042.607230530001;6238.1266622672865;6255.311915689271;5804.390538993429;6133.6505968686815;6137.294211165684;6142.570020098639;6155.784547540904;5283.630145889828;5547.223388725582;5644.238027866268;5847.532197424196;6292.46514046302;5653;5716.857078573069;6006.849828754316;6049.667268300285;6075.787347442818;5939.2575928009;6011.87976240464;6051.394860513913;6060.575759696779;6068.393160683896;5992.43071908168;6298.874022519615;6318.957372033563;6335.416145963509;6399.296077431572;3253.6908993645566;3919.549251836075;3924.3328634131694;3936.724429290037;3944.309745794532;5088.796448142033;5338.492843179892;6115.785947491817;6129.08063790435;6146.661933593696;4704.388429504143;5173.948260517497;5740.885182296247;5875.676837773964;5954.732037058429;5864.208331875142;5872.26939473147;5900.616459930091;5948.643081415129;5948.810237952341;5787.450192231731;5811.912821307768;5824.504917082096;5828.854278642898;5839.474447299764;5063.594912407072;5889.764409423575;5893;5926.696126852085;5952.880942381042;4683.508231635629;5394.732237924163;5511.22842802016;5826.563007774519;5840.649561026352 14:39:00 INFO - PROCESS | 1665 | |5;yui.html;731.5683746589511;774.9341305988991;809.348474478045;859.2524503681798;902;14687.559373218794;16070.553650171481;16496.896623407483;17343.566410839736;17502.649947001053;14174.362153703083;15140.485951404855;15883.285252163652;15995.760063599055;16028.519144425685;765.0857225615364;932.8647346134824;935.96724114656;936.3024546712675;937.503123344625;927.3647551427293;942.3968660057569;944.9007854175316;946.2382781860599;960.3661583354988;968.7432830299977;1007.0936157458273;1007.1137399088828;1007.5214273220198;1010.9241806864478;868.3574155125225;999.8300288950859;1001;1002.2082554781714;1003.0821798054808;633.4425705379283;736.4734215036221;740.3669862267737;740.6481921087494;743.2344684974485;13317.400716967759;15621.453249136226;15686.13726245056;15805.841941580553;15941.282642281125;14160.504382346671;14493.855061449356;14668.55994320172;15801.209939503095;15820.76268855968;12714.364281785965;12993.545225917142;13005.154664946747;13236.669083272898;13882.652933676638;4943.950560494385;5006.2740902569285;5007.674501157413;5148.304978827848;5174.09453345663;112057.75884482346;115114;137686.31156844253;137907.31046344806;138052;11791.35147566875;11839.526418943318;12205.511779528897;12225.633231003085;12266.632001039983;23554.29337119889;24376;24549.754502454747;24601.63097553538;24642.876785616314;8302.460340077876;8342.582870856433;8632.568371581396;8858.86711699325;8890.244329232088;4177.916441671149;4503;4673.462551806518;4816.325714399987;4922.237053256751;3648.908777280589;3673.9081522962138;3727.9068023299633;3763.9059023524082;3767.321882061255;651.3388910256094;652.7160685101901;655.0861548140317;665.1320027364239;671.717878491035;575.7466714645543;649.250116115887;656.1543004908783;663.4990582110572;682.1098466501263;8715.346349023848;9166.312526560534;9317.767055823659;9482.496118845736;9751.512424378894;618.1068356225301;632.9556931014877;668.909697190873;670.8255853478131;698.7414656577099;4703.635945575813;4711.693739906895;4757.215059515211;4767.261074533453;4792.485953314941;88454;117194;159364.20317898336;160594.3940560603;165036.3496365045;150258.24870875577;151198;154345;156328;157564.21217894065;123022.38488807499;128495.35752321365;133063;133453.3327333357;135665;8160.551169685724;8642.438241514534;9193.678221262224;9230.630774769203;9323.906760932576;64044.35955640384;70073.64963175151;70256.89229323129;72200;72779.63610181915;104357;104937.42593860945;111312;111460.442697786;112494;14037.157770533393;14088.22514761657;14130.717385652024;14193.787093193818;14605.561833145022;15043.473478428203;15482.303296351916;15735.291911863764;16189.919050404673;17039.06285154279;12571.434285457359;14265;14353.784693229818;14899.851001489846;15334.463293784664;14576.85423145755;14662.780058299348;15358.682518609234;15375.231238438257;15451.459198927983;14382.568522944328;15346.693066138838;15409.922950385177;15488;15551.455699050479;11399.772004560029;12186.025117990363;12560.623181304576;12571.874281257436;12593.559225427067;11607.245529040585;11860;12131.272123672608;12462.688432789253;12527.624171274876 14:39:00 INFO - PROCESS | 1665 | __end_tp_report 14:39:00 INFO - PROCESS | 1665 | __start_cc_report 14:39:00 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,408 14:39:00 INFO - PROCESS | 1665 | __end_cc_report 14:39:00 INFO - PROCESS | 1665 | __startTimestamp1474407540499__endTimestamp 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | ------- Summary: start ------- 14:39:00 INFO - PROCESS | 1665 | Number of tests: 6 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5901.14 Median:6181.13 stddev:619.10 (10.0%) stddev-sans-first:594.46 14:39:00 INFO - PROCESS | 1665 | Values: 3615.3 3616.7 3628.7 3634.7 3674.9 5086.8 5807.7 5935.5 5985.7 6015.5 5799.8 5814.9 5890.5 5899.7 5909.9 5271.8 5317.6 5327.5 5362.7 5416.9 5300.1 5349.7 5369.2 5395.7 5425.2 5322.3 5342.3 5380.2 5384.4 5385.8 5327.9 5333.9 5382.8 5390.8 5407.0 5248.6 5263.6 5267.2 5313.1 5340.0 5905.8 5933.8 5970.1 5978.9 6066.2 6225.6 6227.1 6277.0 6287.2 6307.1 6223.7 6230.8 6235.6 6258.2 6348.7 6059.2 6070.8 6084.9 6085.5 6109.0 6414.3 6470.7 6473.6 6480.8 6501.9 6196.6 6213.1 6223.2 6230.8 6281.6 5781.8 5966.7 6387.8 6392.0 6429.8 6229.3 6268.4 6276.5 6313.3 6326.4 6175.3 6205.9 6226.9 6230.4 6316.6 5098.5 5131.1 5155.0 5161.9 5173.9 4633.7 4647.8 4658.3 4661.7 4665.3 5975.6 6076.4 6079.2 6099.5 6147.8 6365.1 6395.9 6396.4 6397.8 6402.6 5997.2 6166.8 6173.4 6184.5 6190.3 5496.6 5575.2 5612.0 5632.5 5677.8 6556.8 6604.4 6614.3 6619.0 6621.8 6358.7 6375.0 6390.4 6395.6 6398.4 6363.0 6450.5 6494.3 6512.2 6592.4 6261.2 6275.6 6284.7 6289.9 6294.6 6323.6 6380.0 6385.4 6400.4 6405.3 6158.8 6181.1 6188.9 6197.6 6248.9 6219.2 6247.8 6251.0 6256.6 6258.7 6077.3 6211.3 6221.8 6237.5 6250.5 5468.3 5650.9 6115.7 6237.7 6270.6 5282.5 6200.3 6214.6 6254.2 6267.3 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:75934.97 Median:37921.50 stddev:167753.32 (442.4%) stddev-sans-first:168128.32 14:39:00 INFO - PROCESS | 1665 | Values: 509.6 540.0 549.7 561.1 567.8 34974.7 43992.6 44003.3 44130.8 44170.0 43143.8 43359.3 43808.6 43997.6 44258.9 9851.4 10007.5 10034.2 10077.8 10091.8 8730.5 9830.9 9951.0 10062.0 10063.6 10008.7 10035.0 10035.3 10038.8 10050.5 9858.1 9860.4 9973.5 10032.6 10039.2 9895.3 9949.5 10057.3 10057.8 10070.1 35547.8 36532.7 42606.6 42755.0 43485.1 3570.8 3620.0 3628.9 3757.8 4609.3 33285.8 33637.5 33791.5 33834.8 33849.5 3268.6 3401.8 3416.9 3428.9 3450.6 547049.0 550869.0 553397.0 554486.0 555105.0 70212.5 70520.0 70785.6 70787.0 70799.6 37953.2 38309.6 38357.8 39414.2 39655.2 19032.2 19151.5 19172.7 19178.7 19276.4 12546.1 14524.5 14605.9 14771.9 14821.9 20640.1 21023.3 21103.5 21121.4 21131.9 8628.7 8735.6 8742.3 8742.4 8826.6 935.6 1025.4 1057.3 1167.4 1192.4 33266.7 33287.7 33397.8 33583.0 33663.8 1154.3 1172.6 1230.1 1283.3 1290.7 17314.4 17425.5 17601.3 17665.0 17690.0 750095.0 755497.0 760644.0 760752.0 763792.0 65277.7 65633.0 65944.7 65961.7 65973.3 1460.9 1485.5 1489.2 1496.6 1508.7 31524.5 32808.0 33083.3 33098.5 33176.5 57864.7 58278.7 58286.4 58376.0 58414.4 541231.6 545762.0 548908.0 549145.0 550083.2 29147.6 29329.8 29385.6 29389.4 29448.6 52569.7 52996.7 53046.9 53100.5 53127.5 41794.0 43542.6 44546.3 44838.1 45170.3 42680.8 42689.4 42894.0 43871.3 44879.8 35948.3 36359.8 41317.2 41726.4 41783.0 36023.6 37895.8 37947.2 38246.4 38422.6 27776.9 28111.7 28147.2 28213.2 28282.0 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:37519.57 Median:22042.11 stddev:110391.94 (500.8%) stddev-sans-first:110666.42 14:39:00 INFO - PROCESS | 1665 | Values: 392.7 400.8 402.1 407.2 423.1 20886.4 21059.2 21081.3 22348.2 23312.0 21021.8 21885.8 22198.4 22608.0 22623.4 5208.3 5303.7 5403.9 5425.3 5440.0 4907.6 5365.1 5564.7 6049.2 6066.2 5060.7 5907.1 6076.5 6095.9 6122.1 5514.4 5850.9 5868.3 5916.0 5924.3 5017.1 5246.1 5246.8 5265.9 5740.9 12966.3 13109.5 13123.5 16027.8 26807.5 2105.3 2334.6 2381.7 2417.7 2723.0 9760.6 9814.7 9960.6 10011.5 10975.7 1657.3 1827.3 1828.7 1846.1 1944.0 128818.4 129279.0 131980.0 132861.0 134697.7 11178.4 11213.5 11234.4 11486.1 12110.5 14982.7 15225.7 15417.2 15451.9 15459.1 8886.7 9112.5 9177.2 9350.6 9396.6 9187.6 9236.9 9265.3 9334.0 9540.0 4287.5 4360.7 4369.2 4666.0 4823.8 2038.1 2054.7 2062.1 2088.4 2109.8 871.3 936.6 949.9 980.2 1065.3 20598.3 21708.3 21750.9 21804.1 21805.3 1023.1 1054.4 1063.3 1066.8 1093.7 10192.5 11752.0 11795.2 11806.9 11842.1 646905.0 655227.0 660947.0 671172.0 671330.0 119287.0 135790.0 136689.0 143234.0 151432.0 1049.2 1052.3 1089.5 1106.8 1109.8 17048.8 18023.4 19639.8 19650.6 19821.7 51258.5 52559.7 54313.0 54560.2 55074.0 77569.0 78616.2 84636.6 84662.6 85656.1 7999.3 8021.9 8241.2 8287.3 8338.5 12400.0 13685.2 13704.4 13744.0 13788.0 10493.9 10634.2 11519.4 11778.4 11782.9 9035.8 9729.6 11776.7 11840.2 11858.9 11829.5 11841.3 11857.2 11898.5 12207.0 7794.9 7843.3 8085.8 8209.3 8695.3 11252.2 11498.5 11701.9 11709.4 11762.8 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5910.98 Median:4908.68 stddev:3276.92 (66.8%) stddev-sans-first:3269.12 14:39:00 INFO - PROCESS | 1665 | Values: 1471.5 1576.7 1802.3 1937.6 1972.2 8308.2 8910.5 8989.6 9473.1 9476.9 8960.0 9178.3 9180.9 9199.0 9233.6 2441.5 2450.4 2484.3 2485.3 2486.7 2302.7 2309.4 2444.4 2462.5 2506.9 2454.8 2463.1 2467.4 2485.3 2489.0 2418.0 2439.8 2441.0 2442.2 2444.7 2434.7 2440.3 2445.1 2450.9 2456.4 9194.0 9335.9 9400.3 9401.3 9401.4 5433.4 5483.5 5505.5 5543.6 5556.0 3545.9 3695.5 3749.5 3757.4 3766.3 1087.1 1108.8 1112.9 1122.7 1127.4 11826.7 11935.2 11987.9 11992.8 12020.2 4796.8 4866.3 4869.5 4959.3 4979.4 10119.3 10173.9 10290.6 10312.8 10338.0 5507.0 5516.2 5620.4 5634.4 5638.1 4661.3 4947.9 5048.6 5094.8 5147.2 4130.3 4176.2 4244.2 4254.9 4264.3 1176.5 1299.8 1303.7 1321.7 1323.3 961.3 983.8 989.3 1003.7 1012.2 5980.7 6001.8 6009.2 6060.5 6202.5 438.0 441.5 451.3 1020.1 1084.5 3442.1 3636.8 3936.6 4141.5 4181.7 9223.5 10921.5 10984.0 11264.6 11522.1 8402.6 9138.0 9454.6 10585.4 11089.4 8221.8 8461.2 8599.1 8635.5 8637.0 5265.9 5344.8 5617.3 6009.3 6086.9 9033.7 10122.3 10468.1 10472.8 10528.1 10131.7 10351.8 10366.0 10557.5 10559.0 7332.3 7764.6 7932.8 7942.7 8117.6 8754.4 8924.3 8946.7 8997.2 9007.2 7886.6 7982.2 8048.5 8074.0 8136.8 7905.4 7911.2 7999.1 8105.2 8109.6 7118.4 7269.6 7392.3 7394.3 7410.1 5365.2 5397.3 5410.6 5444.9 5528.1 5428.5 5466.6 5472.6 5483.8 5555.6 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4751.54 Median:5436.46 stddev:1491.72 (27.4%) stddev-sans-first:1457.90 14:39:00 INFO - PROCESS | 1665 | Values: 293.9 297.9 299.9 301.8 305.3 5489.7 5585.9 5618.3 5622.1 5635.6 4549.4 5444.3 5503.4 5524.1 5545.1 3957.6 4064.5 4143.6 4189.7 4196.5 4158.0 4182.3 4188.7 4190.3 4200.3 3476.4 3483.3 4134.1 4164.1 4172.9 3828.2 4060.5 4171.9 4194.2 4251.4 2986.8 3601.2 3618.6 3622.9 3629.1 4423.8 4425.7 5417.6 5424.6 5428.6 4595.7 4825.7 4828.4 4840.2 4855.4 4778.9 5337.9 5670.6 5778.5 5835.2 3363.9 3688.4 3995.9 4002.0 4019.3 5838.4 6133.3 6139.7 6173.4 6204.8 3801.3 4049.5 4068.7 4070.2 4107.7 4151.8 4160.7 4163.9 4168.0 4180.8 4604.6 4825.6 4836.4 4862.8 4889.8 5682.5 5872.1 5874.6 5903.9 5927.4 1390.3 1412.2 1414.1 1421.7 1424.8 788.6 803.9 862.5 866.4 870.6 4517.2 5194.5 5226.3 5369.3 5516.3 6084.4 6094.5 6099.8 6112.8 6135.5 5217.4 5504.3 5525.3 5584.0 5595.7 2759.8 3019.8 3148.8 3164.2 3170.8 5532.4 5977.3 6042.6 6238.1 6255.3 5804.4 6133.7 6137.3 6142.6 6155.8 5283.6 5547.2 5644.2 5847.5 6292.5 5653.0 5716.9 6006.8 6049.7 6075.8 5939.3 6011.9 6051.4 6060.6 6068.4 5992.4 6298.9 6319.0 6335.4 6399.3 3253.7 3919.5 3924.3 3936.7 3944.3 5088.8 5338.5 6115.8 6129.1 6146.7 4704.4 5173.9 5740.9 5875.7 5954.7 5864.2 5872.3 5900.6 5948.6 5948.8 5787.5 5811.9 5824.5 5828.9 5839.5 5063.6 5889.8 5893.0 5926.7 5952.9 4683.5 5394.7 5511.2 5826.6 5840.6 14:39:00 INFO - PROCESS | 1665 | 14:39:00 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:27578.09 Median:16343.41 stddev:44344.28 (271.3%) stddev-sans-first:44422.87 14:39:00 INFO - PROCESS | 1665 | Values: 731.6 774.9 809.3 859.3 902.0 14687.6 16070.6 16496.9 17343.6 17502.6 14174.4 15140.5 15883.3 15995.8 16028.5 765.1 932.9 936.0 936.3 937.5 927.4 942.4 944.9 946.2 960.4 968.7 1007.1 1007.1 1007.5 1010.9 868.4 999.8 1001.0 1002.2 1003.1 633.4 736.5 740.4 740.6 743.2 13317.4 15621.5 15686.1 15805.8 15941.3 14160.5 14493.9 14668.6 15801.2 15820.8 12714.4 12993.5 13005.2 13236.7 13882.7 4944.0 5006.3 5007.7 5148.3 5174.1 112057.8 115114.0 137686.3 137907.3 138052.0 11791.4 11839.5 12205.5 12225.6 12266.6 23554.3 24376.0 24549.8 24601.6 24642.9 8302.5 8342.6 8632.6 8858.9 8890.2 4177.9 4503.0 4673.5 4816.3 4922.2 3648.9 3673.9 3727.9 3763.9 3767.3 651.3 652.7 655.1 665.1 671.7 575.7 649.3 656.2 663.5 682.1 8715.3 9166.3 9317.8 9482.5 9751.5 618.1 633.0 668.9 670.8 698.7 4703.6 4711.7 4757.2 4767.3 4792.5 88454.0 117194.0 159364.2 160594.4 165036.3 150258.2 151198.0 154345.0 156328.0 157564.2 123022.4 128495.4 133063.0 133453.3 135665.0 8160.6 8642.4 9193.7 9230.6 9323.9 64044.4 70073.6 70256.9 72200.0 72779.6 104357.0 104937.4 111312.0 111460.4 112494.0 14037.2 14088.2 14130.7 14193.8 14605.6 15043.5 15482.3 15735.3 16189.9 17039.1 12571.4 14265.0 14353.8 14899.9 15334.5 14576.9 14662.8 15358.7 15375.2 15451.5 14382.6 15346.7 15409.9 15488.0 15551.5 11399.8 12186.0 12560.6 12571.9 12593.6 11607.2 11860.0 12131.3 12462.7 12527.6 14:39:00 INFO - PROCESS | 1665 | -------- Summary: end -------- 14:39:00 INFO - PROCESS | 1665 | 14:39:01 INFO - TEST-INFO | 1665: exit 0 14:39:01 INFO - TEST-OK | dromaeo_css | took 1110906ms 14:39:01 INFO - TEST-START | kraken 14:39:01 INFO - operating with platform_type : mac_ 14:39:01 INFO - Initialising browser for kraken test... 14:39:01 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpck8sFJ/profile http://localhost:49265/getInfo.html) 14:39:03 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 14:39:03 INFO - PROCESS | 1675 | colorDepth:24 14:39:03 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 14:39:03 INFO - PROCESS | 1675 | __metrics 14:39:03 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:39:04 INFO - TEST-INFO | 1675: exit 0 14:39:04 INFO - Browser initialized. 14:39:04 INFO - Running cycle 1/1 for kraken test... 14:39:04 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpck8sFJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:39:16 INFO - PROCESS | 1678 | RSS: Main: 174931968 14:39:16 INFO - PROCESS | 1678 | 14:39:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 14:39:19 INFO - PROCESS | 1678 | RSS: Main: 187047936 14:39:19 INFO - PROCESS | 1678 | 14:39:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 14:39:22 INFO - PROCESS | 1678 | RSS: Main: 195837952 14:39:22 INFO - PROCESS | 1678 | 14:39:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 14:39:26 INFO - PROCESS | 1678 | RSS: Main: 199589888 14:39:26 INFO - PROCESS | 1678 | 14:39:28 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 14:39:29 INFO - PROCESS | 1678 | RSS: Main: 199909376 14:39:29 INFO - PROCESS | 1678 | 14:39:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:32 INFO - PROCESS | 1678 | RSS: Main: 201449472 14:39:32 INFO - PROCESS | 1678 | 14:39:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:35 INFO - PROCESS | 1678 | RSS: Main: 202276864 14:39:35 INFO - PROCESS | 1678 | 14:39:38 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:39 INFO - PROCESS | 1678 | RSS: Main: 202416128 14:39:39 INFO - PROCESS | 1678 | 14:39:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 14:39:42 INFO - PROCESS | 1678 | RSS: Main: 202797056 14:39:42 INFO - PROCESS | 1678 | 14:39:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:44 INFO - PROCESS | 1678 | RSS: Main: 201719808 14:39:44 INFO - PROCESS | 1678 | 14:39:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:47 INFO - PROCESS | 1678 | RSS: Main: 201662464 14:39:47 INFO - PROCESS | 1678 | 14:39:49 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:50 INFO - PROCESS | 1678 | RSS: Main: 202137600 14:39:50 INFO - PROCESS | 1678 | 14:39:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:53 INFO - PROCESS | 1678 | RSS: Main: 201908224 14:39:53 INFO - PROCESS | 1678 | 14:39:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:57 INFO - PROCESS | 1678 | RSS: Main: 201986048 14:39:57 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 14:39:59 INFO - PROCESS | 1678 | RSS: Main: 204029952 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | __start_tp_report 14:39:59 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 14:39:59 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 14:39:59 INFO - PROCESS | 1678 | |i|pagename|runs| 14:39:59 INFO - PROCESS | 1678 | |0;ai-astar;103;121;108;116;119;117;115;115;114;117 14:39:59 INFO - PROCESS | 1678 | |1;audio-beat-detection;134;107;135;153;113;141;109;136;169;141 14:39:59 INFO - PROCESS | 1678 | |2;audio-dft;199;204;193;197;272;247;194;200;199;199 14:39:59 INFO - PROCESS | 1678 | |3;audio-fft;65;81;74;149;62;74;83;86;130;64 14:39:59 INFO - PROCESS | 1678 | |4;audio-oscillator;69;69;115;70;71;81;113;101;102;144 14:39:59 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;93;88;78;130;88;122;136;76;119;130 14:39:59 INFO - PROCESS | 1678 | |6;imaging-darkroom;96;96;96;102;97;147;153;97;97;97 14:39:59 INFO - PROCESS | 1678 | |7;imaging-desaturate;91;93;96;91;92;91;90;89;91;89 14:39:59 INFO - PROCESS | 1678 | |8;json-parse-financial;45;46;85;84;53;57;58;57;58;58 14:39:59 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;68;69;75;76;78;74;71;76;102;101 14:39:59 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;83;82;79;82;95;93;81;85;88 14:39:59 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;145;148;141;131;145;135;136;135;197;148 14:39:59 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;161;163;163;161;159;140;208;225;224;192 14:39:59 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;68;65;87;64;58;84;75;83;63;66 14:39:59 INFO - PROCESS | 1678 | __end_tp_report 14:39:59 INFO - PROCESS | 1678 | __start_cc_report 14:39:59 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1060 14:39:59 INFO - PROCESS | 1678 | __end_cc_report 14:39:59 INFO - PROCESS | 1678 | __startTimestamp1474407599400__endTimestamp 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | ------- Summary: start ------- 14:39:59 INFO - PROCESS | 1678 | Number of tests: 14 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:114.50 Median:116.50 stddev:5.30 (4.5%) stddev-sans-first:3.63 14:39:59 INFO - PROCESS | 1678 | Values: 103.0 121.0 108.0 116.0 119.0 117.0 115.0 115.0 114.0 117.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:138.50 stddev:19.67 (14.2%) stddev-sans-first:20.87 14:39:59 INFO - PROCESS | 1678 | Values: 134.0 107.0 135.0 153.0 113.0 141.0 109.0 136.0 169.0 141.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:210.40 Median:199.50 stddev:26.72 (13.4%) stddev-sans-first:28.02 14:39:59 INFO - PROCESS | 1678 | Values: 199.0 204.0 193.0 197.0 272.0 247.0 194.0 200.0 199.0 199.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:86.80 Median:74.00 stddev:29.29 (39.6%) stddev-sans-first:29.99 14:39:59 INFO - PROCESS | 1678 | Values: 65.0 81.0 74.0 149.0 62.0 74.0 83.0 86.0 130.0 64.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:69.00 stddev:25.68 (37.2%) stddev-sans-first:25.67 14:39:59 INFO - PROCESS | 1678 | Values: 69.0 69.0 115.0 70.0 71.0 81.0 113.0 101.0 102.0 144.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:106.00 Median:77.00 stddev:23.52 (30.5%) stddev-sans-first:24.47 14:39:59 INFO - PROCESS | 1678 | Values: 93.0 88.0 78.0 130.0 88.0 122.0 136.0 76.0 119.0 130.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:96.50 stddev:22.35 (23.2%) stddev-sans-first:23.30 14:39:59 INFO - PROCESS | 1678 | Values: 96.0 96.0 96.0 102.0 97.0 147.0 153.0 97.0 97.0 97.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:91.00 stddev:2.06 (2.3%) stddev-sans-first:2.18 14:39:59 INFO - PROCESS | 1678 | Values: 91.0 93.0 96.0 91.0 92.0 91.0 90.0 89.0 91.0 89.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:58.00 stddev:13.75 (23.7%) stddev-sans-first:13.45 14:39:59 INFO - PROCESS | 1678 | Values: 45.0 46.0 85.0 84.0 53.0 57.0 58.0 57.0 58.0 58.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:79.00 Median:74.50 stddev:12.28 (16.5%) stddev-sans-first:12.37 14:39:59 INFO - PROCESS | 1678 | Values: 68.0 69.0 75.0 76.0 78.0 74.0 71.0 76.0 102.0 101.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.00 stddev:5.40 (6.4%) stddev-sans-first:5.55 14:39:59 INFO - PROCESS | 1678 | Values: 81.0 83.0 82.0 79.0 82.0 95.0 93.0 81.0 85.0 88.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:145.00 stddev:18.86 (13.0%) stddev-sans-first:20.00 14:39:59 INFO - PROCESS | 1678 | Values: 145.0 148.0 141.0 131.0 145.0 135.0 136.0 135.0 197.0 148.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:177.50 stddev:30.24 (17.0%) stddev-sans-first:31.31 14:39:59 INFO - PROCESS | 1678 | Values: 161.0 163.0 163.0 161.0 159.0 140.0 208.0 225.0 224.0 192.0 14:39:59 INFO - PROCESS | 1678 | 14:39:59 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.50 stddev:10.20 (14.3%) stddev-sans-first:10.75 14:39:59 INFO - PROCESS | 1678 | Values: 68.0 65.0 87.0 64.0 58.0 84.0 75.0 83.0 63.0 66.0 14:39:59 INFO - PROCESS | 1678 | -------- Summary: end -------- 14:39:59 INFO - PROCESS | 1678 | 14:40:00 INFO - TEST-INFO | 1678: exit 0 14:40:00 INFO - TEST-OK | kraken | took 59158ms 14:40:00 INFO - SUITE-END | took 1170s 14:40:00 INFO - Completed test suite (00:19:30) 14:40:00 INFO - javascript benchmark 14:40:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7716.865436160571, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3615.313090512803, 3616.656417640323, 3628.727845411595, 3634.6728794408514, 3674.926501469971, 5086.821961231358, 5807.680577568232, 5935.525157987361, 5985.670788106652, 6015.518758499321, 5799.826005219839, 5814.854628634275, 5890.469857712805, 5899.705014749266, 5909.881802363951, 5271.762770675319, 5317.601179911503, 5327.493888080626, 5362.678239305635, 5416.91874621881, 5300.125479295912, 5349.732513374316, 5369.19462080689, 5395.7032363220005, 5425.240466334716, 5322.2548843161985, 5342.305500284958, 5380.192971054334, 5384.3538775346815, 5385.838424847241, 5327.893442131174, 5333.946660533423, 5382.770337797329, 5390.757415916293, 5406.999705054558, 5248.606354523387, 5263.631545791796, 5267.183586544092, 5313.0967735485065, 5340.0, 5905.818836232771, 5933.792317268918, 5970.13433052205, 5978.940210597925, 6066.241719785027, 6225.62646241227, 6227.1282020517165, 6277.027060805537, 6287.2140982377205, 6307.117003619451, 6223.719932603044, 6230.8130756076935, 6235.625862448268, 6258.186435763367, 6348.682565871734, 6059.21230240066, 6070.757169713162, 6084.908726369108, 6085.5394705270855, 6108.992016317302, 6414.262359828586, 6470.708818103197, 6473.579217350857, 6480.805575832733, 6501.8699626008165, 6196.566240363221, 6213.068039794067, 6223.190985171898, 6230.753849230172, 6281.59169653971, 5781.82654520365, 5966.671833049224, 6387.808365749035, 6392.0, 6429.807105786834, 6229.345918678564, 6268.435840774261, 6276.497880169667, 6313.273973493016, 6326.398992095744, 6175.2898416681755, 6205.875882482325, 6226.937730622636, 6230.439260466489, 6316.589421687576, 5098.46466121052, 5131.076406246837, 5155.0, 5161.870953226199, 5173.948260517347, 4633.652476064309, 4647.767611619405, 4658.34783130362, 4661.696989695659, 4665.253559430543, 5975.641461512323, 6076.392360763888, 6079.209702738704, 6099.512039036955, 6147.815565533041, 6365.077063825812, 6395.936040639534, 6396.360363963567, 6397.808065758035, 6402.583832050902, 5997.220361352996, 6166.845828854225, 6173.382661733791, 6184.5052395808225, 6190.2571691397325, 5496.587755918322, 5575.191597218381, 5612.017896868032, 5632.5212356950115, 5677.772889084473, 6556.836079097965, 6604.405603495612, 6614.272430032596, 6619.0, 6621.801345959629, 6358.650274234777, 6375.0, 6390.360963903572, 6395.616263024233, 6398.360163983564, 6362.9681851592295, 6450.516211284079, 6494.2856285807575, 6512.218533775977, 6592.439642630238, 6261.186045814015, 6275.623462592259, 6284.654344011124, 6289.9056514153235, 6294.590851594815, 6323.557350985387, 6380.042993551005, 6385.425311721968, 6400.39196276353, 6405.327440618855, 6158.846028849314, 6181.134641150153, 6188.938110618837, 6197.566170368031, 6248.9062664059165, 6219.222597175353, 6247.781327653511, 6250.968745156245, 6256.624602523863, 6258.655773932477, 6077.301110372364, 6211.3167551570195, 6221.751129954932, 6237.5009999200865, 6250.531210159347, 5468.316460442445, 5650.89807487536, 6115.666295175977, 6237.656928868956, 6270.623762574261, 5282.4717528246865, 6200.255969283535, 6214.5960512566535, 6254.21822272216, 6267.310595834363], "value": 5864.756616996283, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.5515945967548, 539.9821336780169, 549.6701978812711, 561.0910325273059, 567.809783722453, 34974.65025349746, 43992.56007439925, 44003.339949900736, 44130.77934610331, 44170.0, 43143.78428107855, 43359.34960975588, 43808.56191438084, 43997.56002439975, 44258.89352766182, 9851.359661622007, 10007.499625018756, 10034.197264218845, 10077.798444031116, 10091.848622270672, 8730.51982140982, 9830.852537211947, 9951.004899510064, 10061.993800619952, 10063.597456101747, 10008.699739007841, 10035.0, 10035.29752917296, 10038.849417258747, 10050.547725352377, 9858.112769850712, 9860.408375497458, 9973.451460169725, 10032.64885728996, 10039.24705647072, 9895.257855660855, 9949.502524873727, 10057.29598928068, 10057.798844023078, 10070.093691567721, 35547.82226088853, 36532.72135475272, 42606.573934260574, 42755.0, 43485.13029739387, 3570.7502374169094, 3619.963800361989, 3628.909277268063, 3757.7745335279697, 4609.308603709437, 33285.833570832234, 33637.495437568454, 33791.493127603106, 33834.83082584596, 33849.492257616155, 3268.591426071741, 3401.795892246473, 3416.8633254669785, 3428.9485657715154, 3450.551428314328, 547049.0, 550869.0, 553397.0, 554485.9999999959, 555104.9999999959, 70212.54256101012, 70520.0, 70785.64607176931, 70787.0, 70799.64600177068, 37953.240935181144, 38309.6169038306, 38357.80821095876, 39414.2117157661, 39655.206895862495, 19032.238710451427, 19151.52121196981, 19172.71240931387, 19178.712319315222, 19276.421707348803, 12546.121771476091, 14524.49164279245, 14605.92697036508, 14771.852281477262, 14821.92589037048, 20640.07119679618, 21023.264185753425, 21103.472413189487, 21121.366359008945, 21131.8943405282, 8628.654853805903, 8735.606897689619, 8742.25690816275, 8742.38803283764, 8826.55867206637, 935.555611084743, 1025.3898930136556, 1057.281048886749, 1167.3812879174052, 1192.421675487388, 33266.66733332684, 33287.66712332894, 33397.833010835275, 33582.99251022473, 33663.83168084144, 1154.3131836557236, 1172.6188988578747, 1230.1073989820193, 1283.2685369339388, 1290.6644272489032, 17314.393996210074, 17425.47723568295, 17601.29594816193, 17665.02842343658, 17690.0, 750095.0, 755497.0, 760644.0, 760752.0, 763792.0, 65277.673611632585, 65633.0, 65944.6811063776, 65961.6701916497, 65973.34026659768, 1460.9269536523132, 1485.5320574019365, 1489.1735087026805, 1496.6408062065027, 1508.6530098077503, 31524.52713209258, 32808.015759527254, 33083.33833323368, 33098.50352244767, 33176.50235246521, 57864.710676446346, 58278.7086064567, 58286.4171358281, 58376.0, 58414.4158558426, 541231.5876841181, 545762.0, 548908.0, 549145.0, 550083.2495837496, 29147.562786557795, 29329.826806928337, 29385.559216612197, 29389.412211755218, 29448.558271625516, 52569.737151313995, 52996.735016324674, 53046.92670159569, 53100.4689953111, 53127.468725312254, 41793.95515112025, 43542.56457435512, 44546.3318050223, 44838.10323793571, 45170.322445164005, 42680.78659606682, 42689.359659605754, 42894.0, 43871.34192987044, 44879.775601121786, 35948.28103437864, 36359.81820090883, 41317.1736565273, 41726.374104387854, 41783.0, 36023.63976360203, 37895.81052094722, 37947.19567285353, 38246.42630360602, 38422.615773841906, 27776.861115694293, 28111.71888281173, 28147.15558533247, 28213.15360539187, 28282.0], "value": 21529.375010987464, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.6740805131739, 400.781574042147, 402.12337105110885, 407.17343792102037, 423.10513264445706, 20886.37340879772, 21059.157633694653, 21081.26215582455, 22348.21781237653, 23312.0, 21021.789782102176, 21885.781142188614, 22198.445038874037, 22608.0, 22623.434414139614, 5208.296879921209, 5303.681779093266, 5403.891922161554, 5425.3489581250105, 5440.0, 4907.6073914086965, 5365.141577347626, 5564.666120032791, 6049.243844519435, 6066.181065556159, 5060.721660308682, 5907.113932910055, 6076.4834989025985, 6095.908561371583, 6122.051082082262, 5514.448555144493, 5850.853728656775, 5868.2664666916635, 5915.994280972245, 5924.318703349127, 5017.096922553938, 5246.134387826004, 5246.7901283948595, 5265.86835329116, 5740.885182296331, 12966.286854223014, 13109.541166059142, 13123.475060997549, 16027.839721602752, 26807.46385072307, 2105.3157723739846, 2334.6498025296173, 2381.7499162588024, 2417.688863409477, 2722.9727702722917, 9760.560774765152, 9814.705558833246, 9960.601575936953, 10011.50117750148, 10975.725606859813, 1657.32049859557, 1827.259959716317, 1828.698264786322, 1846.1230915315252, 1943.9514012149668, 128818.35590821986, 129279.0, 131980.0, 132861.0, 134697.6530234695, 11178.385188814693, 11213.495392707347, 11234.438278086063, 11486.0811135109, 12110.515579376726, 14982.700345993018, 15225.695486090217, 15417.152056636769, 15451.922740386226, 15459.072455652697, 8886.731808299357, 9112.498812565373, 9177.219936305355, 9350.625974961062, 9396.577154028086, 9187.632494700138, 9236.907630923739, 9265.25877929764, 9334.019927907608, 9539.952300238454, 4287.464066991626, 4360.65114790816, 4369.235383807821, 4666.0, 4823.831165909176, 2038.0523056778372, 2054.6712525995767, 2062.1442101527996, 2088.4361222469847, 2109.7679255281905, 871.3116637856023, 936.6019441737235, 949.852772820214, 980.2158273381267, 1065.3448129400363, 20598.27906023282, 21708.348749537538, 21750.89124554367, 21804.127834886745, 21805.345839624835, 1023.1047833145997, 1054.3726482742757, 1063.2663462211049, 1066.81330767117, 1093.704699731068, 10192.541335639915, 11752.001079908132, 11795.174337796443, 11806.937375636235, 11842.052635789116, 646905.0, 655227.0, 660947.0, 671172.0, 671330.0, 119287.0, 135790.0, 136689.0, 143234.0, 151432.0, 1049.1711547877244, 1052.3265110329396, 1089.5478376473675, 1106.817375133094, 1109.8113320735495, 17048.829511705222, 18023.369182078564, 19639.8036019638, 19650.60698786045, 19821.702674460183, 51258.48741512537, 52559.73720131375, 54313.0, 54560.18159727528, 55074.0, 77569.0, 78616.21383786318, 84636.57681711552, 84662.57668711618, 85656.14343856482, 7999.320057795153, 8021.879671804811, 8241.175882411711, 8287.337013039063, 8338.541380224146, 12400.0, 13685.247311397969, 13704.383302751201, 13744.0, 13788.034837561676, 10493.9475302623, 10634.202434817265, 11519.424028798694, 11778.352190629597, 11782.939535441852, 9035.819283614159, 9729.562169702238, 11776.70558236051, 11840.171188016757, 11858.881411185777, 11829.467673954521, 11841.28952262867, 11857.229280096766, 11898.464569094238, 12207.0, 7794.92205077942, 7843.333316668147, 8085.83828323442, 8209.261166495044, 8695.347848911484, 11252.156088293246, 11498.54005839757, 11701.94682478581, 11709.355985420883, 11762.76474470523], "value": 9770.233219493826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.5364660132057, 1576.6610178811557, 1802.3061121468231, 1937.56404808918, 1972.211115553779, 8308.210719981607, 8910.50992195429, 8989.640414383433, 9473.052694730532, 9476.857847132282, 8959.955200223989, 9178.265738740902, 9180.90819091809, 9198.98811130775, 9233.630654773819, 2441.5116976604663, 2450.4363996280867, 2484.2671411933525, 2485.3413845331, 2486.7140278867982, 2302.7450039728305, 2309.364924645719, 2444.3644652390417, 2462.54442928058, 2506.9373265668323, 2454.828162028659, 2463.0763463701114, 2467.4324905271797, 2485.2544236729, 2489.041718938213, 2418.0, 2439.7926176275037, 2441.047991283392, 2442.157455677788, 2444.6577479152934, 2434.6834911461574, 2440.304513213743, 2445.0708730682227, 2450.8652024138664, 2456.398182445312, 9193.954030229874, 9335.859962100574, 9400.294977876687, 9401.341906066578, 9401.435913845124, 5433.429489903543, 5483.506484416382, 5505.532029777473, 5543.6396634218645, 5555.972220138873, 3545.8936231913085, 3695.5380577427823, 3749.531308586427, 3757.380032294668, 3766.2655782122497, 1087.065123993365, 1108.7616162525014, 1112.9387883666477, 1122.685648018556, 1127.4250132432437, 11826.704332391759, 11935.224210426295, 11987.940060299643, 11992.820107698391, 12020.218685785483, 4796.8081276748535, 4866.294387313819, 4869.488703686133, 4959.330490383824, 4979.3526841510375, 10119.342242754197, 10173.949130254301, 10290.639827605997, 10312.793744125076, 10338.0, 5506.972465137648, 5516.172574113834, 5620.437956204347, 5634.352049514277, 5638.126090455986, 4661.347411362344, 4947.876303092451, 5048.570871475966, 5094.847154585294, 5147.176451767699, 4130.318497447887, 4176.248275310473, 4244.193603215379, 4254.914901701949, 4264.33902745075, 1176.505867535637, 1299.8375203099613, 1303.687115092371, 1321.7026169111873, 1323.2920387592785, 961.3078583419926, 983.8475036369374, 989.3223142147652, 1003.6738060130341, 1012.193235075387, 5980.744531506689, 6001.759929602855, 6009.2188015557695, 6060.484858786965, 6202.472789812826, 438.04069088695655, 441.47684993283065, 451.3230154767848, 1020.132887046005, 1084.4902895639036, 3442.1394651337164, 3636.8363423646, 3936.645701886844, 4141.461609990682, 4181.707280490336, 9223.49270790113, 10921.508532116073, 10984.01143897053, 11264.605738799102, 11522.135839812048, 8402.621882015324, 9138.040505746934, 9454.57454414553, 10585.364878107339, 11089.445527723581, 8221.753347399588, 8461.23848853606, 8599.097094804956, 8635.525046122399, 8637.0, 5265.868353291045, 5344.839654810362, 5617.32592088952, 6009.338972712911, 6086.878262434638, 9033.728988130366, 10122.291439599157, 10468.057874791306, 10472.842907356244, 10528.105111065644, 10131.746706332106, 10351.792964140826, 10366.0, 10557.472126393803, 10559.0, 7332.340089391981, 7764.5729484878875, 7932.801679958047, 7942.682292708227, 8117.553534555656, 8754.387192896691, 8924.330675199515, 8946.68686595966, 8997.235235005099, 9007.189352958265, 7886.566238856918, 7982.241687039722, 8048.5170889746805, 8074.0311162660855, 8136.837263254583, 7905.446618736633, 7911.208879112043, 7999.080105787909, 8105.230003149692, 8109.635066422144, 7118.430525558047, 7269.600171990592, 7392.260773922565, 7394.2975417335265, 7410.073740782403, 5365.195220716923, 5397.325334333208, 5410.594205434687, 5444.891102178013, 5528.087865502147, 5428.511433970803, 5466.644668096561, 5472.589555783253, 5483.75323110453, 5555.611107222618], "value": 4732.550929340397, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.9265183704074, 297.9378515591915, 299.92651800308926, 301.76914660284876, 305.3039070918307, 5489.670619762812, 5585.944140558593, 5618.325800903887, 5622.076520209489, 5635.605507614464, 4549.384968336242, 5444.265024221729, 5503.394626591082, 5524.050304032616, 5545.085060964936, 3957.5844536323702, 4064.5325787534516, 4143.627073563379, 4189.748615083105, 4196.538380778112, 4157.979210103946, 4182.268103081964, 4188.6649068074485, 4190.308599081148, 4200.264953633102, 3476.3568739783223, 3483.3207524532727, 4134.131832315203, 4164.146349998258, 4172.853950111747, 3828.176941957465, 4060.533038700543, 4171.93615628016, 4194.16893596821, 4251.447311849455, 2986.8058576192693, 3601.1897323102353, 3618.6381361863864, 3622.9094272643133, 3629.076589407618, 4423.800928958204, 4425.690201685883, 5417.566594672417, 5424.593155513352, 5428.647137936022, 4595.655325850575, 4825.686330388514, 4828.444728856191, 4840.249761287007, 4855.417349917997, 4778.856634300976, 5337.946620533784, 5670.55400872779, 5778.526475748699, 5835.153902684129, 3363.915902102467, 3688.428293614494, 3995.920081598381, 4001.9995001249686, 4019.2966230909474, 5838.4453476919625, 6133.29467111279, 6139.723712432951, 6173.41352571505, 6204.751809927598, 3801.2777572261184, 4049.481444458328, 4068.694847886361, 4070.247004304213, 4107.650849677811, 4151.81673223136, 4160.687948403882, 4163.895902602399, 4167.958320416757, 4180.770057646859, 4604.618614415662, 4825.638077144228, 4836.371271734652, 4862.81173471939, 4889.755512224375, 5682.54539636828, 5872.060470324813, 5874.618149820248, 5903.911441328383, 5927.377625349276, 1390.332640332646, 1412.180935057664, 1414.130309859429, 1421.6943357178257, 1424.750668632985, 788.6254029336134, 803.8553060449059, 862.4609618988133, 866.423828154283, 870.6157210036073, 4517.232070548015, 5194.532492075731, 5226.320578324793, 5369.275147855068, 5516.310461192351, 6084.421979911901, 6094.481969032593, 6099.756009759649, 6112.7554897804475, 6135.509159267248, 5217.373915130132, 5504.311961004874, 5525.309336332958, 5584.0, 5595.71298601324, 2759.765419939328, 3019.773516986235, 3148.77197892822, 3164.240582260287, 3170.77804553679, 5532.419095994943, 5977.312609049928, 6042.607230530001, 6238.1266622672865, 6255.311915689271, 5804.390538993429, 6133.6505968686815, 6137.294211165684, 6142.570020098639, 6155.784547540904, 5283.630145889828, 5547.223388725582, 5644.238027866268, 5847.532197424196, 6292.46514046302, 5653.0, 5716.857078573069, 6006.849828754316, 6049.667268300285, 6075.787347442818, 5939.2575928009, 6011.87976240464, 6051.394860513913, 6060.575759696779, 6068.393160683896, 5992.43071908168, 6298.874022519615, 6318.957372033563, 6335.416145963509, 6399.296077431572, 3253.6908993645566, 3919.549251836075, 3924.3328634131694, 3936.724429290037, 3944.309745794532, 5088.796448142033, 5338.492843179892, 6115.785947491817, 6129.08063790435, 6146.661933593696, 4704.388429504143, 5173.948260517497, 5740.885182296247, 5875.676837773964, 5954.732037058429, 5864.208331875142, 5872.26939473147, 5900.616459930091, 5948.643081415129, 5948.810237952341, 5787.450192231731, 5811.912821307768, 5824.504917082096, 5828.854278642898, 5839.474447299764, 5063.594912407072, 5889.764409423575, 5893.0, 5926.696126852085, 5952.880942381042, 4683.508231635629, 5394.732237924163, 5511.22842802016, 5826.563007774519, 5840.649561026352], "value": 4262.290521516201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.5683746589511, 774.9341305988991, 809.348474478045, 859.2524503681798, 902.0, 14687.559373218794, 16070.553650171481, 16496.896623407483, 17343.566410839736, 17502.649947001053, 14174.362153703083, 15140.485951404855, 15883.285252163652, 15995.760063599055, 16028.519144425685, 765.0857225615364, 932.8647346134824, 935.96724114656, 936.3024546712675, 937.503123344625, 927.3647551427293, 942.3968660057569, 944.9007854175316, 946.2382781860599, 960.3661583354988, 968.7432830299977, 1007.0936157458273, 1007.1137399088828, 1007.5214273220198, 1010.9241806864478, 868.3574155125225, 999.8300288950859, 1001.0, 1002.2082554781714, 1003.0821798054808, 633.4425705379283, 736.4734215036221, 740.3669862267737, 740.6481921087494, 743.2344684974485, 13317.400716967759, 15621.453249136226, 15686.13726245056, 15805.841941580553, 15941.282642281125, 14160.504382346671, 14493.855061449356, 14668.55994320172, 15801.209939503095, 15820.76268855968, 12714.364281785965, 12993.545225917142, 13005.154664946747, 13236.669083272898, 13882.652933676638, 4943.950560494385, 5006.2740902569285, 5007.674501157413, 5148.304978827848, 5174.09453345663, 112057.75884482346, 115114.0, 137686.31156844253, 137907.31046344806, 138052.0, 11791.35147566875, 11839.526418943318, 12205.511779528897, 12225.633231003085, 12266.632001039983, 23554.29337119889, 24376.0, 24549.754502454747, 24601.63097553538, 24642.876785616314, 8302.460340077876, 8342.582870856433, 8632.568371581396, 8858.86711699325, 8890.244329232088, 4177.916441671149, 4503.0, 4673.462551806518, 4816.325714399987, 4922.237053256751, 3648.908777280589, 3673.9081522962138, 3727.9068023299633, 3763.9059023524082, 3767.321882061255, 651.3388910256094, 652.7160685101901, 655.0861548140317, 665.1320027364239, 671.717878491035, 575.7466714645543, 649.250116115887, 656.1543004908783, 663.4990582110572, 682.1098466501263, 8715.346349023848, 9166.312526560534, 9317.767055823659, 9482.496118845736, 9751.512424378894, 618.1068356225301, 632.9556931014877, 668.909697190873, 670.8255853478131, 698.7414656577099, 4703.635945575813, 4711.693739906895, 4757.215059515211, 4767.261074533453, 4792.485953314941, 88454.0, 117194.0, 159364.20317898336, 160594.3940560603, 165036.3496365045, 150258.24870875577, 151198.0, 154345.0, 156328.0, 157564.21217894065, 123022.38488807499, 128495.35752321365, 133063.0, 133453.3327333357, 135665.0, 8160.551169685724, 8642.438241514534, 9193.678221262224, 9230.630774769203, 9323.906760932576, 64044.35955640384, 70073.64963175151, 70256.89229323129, 72200.0, 72779.63610181915, 104357.0, 104937.42593860945, 111312.0, 111460.442697786, 112494.0, 14037.157770533393, 14088.22514761657, 14130.717385652024, 14193.787093193818, 14605.561833145022, 15043.473478428203, 15482.303296351916, 15735.291911863764, 16189.919050404673, 17039.06285154279, 12571.434285457359, 14265.0, 14353.784693229818, 14899.851001489846, 15334.463293784664, 14576.85423145755, 14662.780058299348, 15358.682518609234, 15375.231238438257, 15451.459198927983, 14382.568522944328, 15346.693066138838, 15409.922950385177, 15488.0, 15551.455699050479, 11399.772004560029, 12186.025117990363, 12560.623181304576, 12571.874281257436, 12593.559225427067, 11607.245529040585, 11860.0, 12131.272123672608, 12462.688432789253, 12527.624171274876], "value": 8485.453147345657, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 121.0, 108.0, 116.0, 119.0, 117.0, 115.0, 115.0, 114.0, 117.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 107.0, 135.0, 153.0, 113.0, 141.0, 109.0, 136.0, 169.0, 141.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 204.0, 193.0, 197.0, 272.0, 247.0, 194.0, 200.0, 199.0, 199.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 74.0, 149.0, 62.0, 74.0, 83.0, 86.0, 130.0, 64.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 115.0, 70.0, 71.0, 81.0, 113.0, 101.0, 102.0, 144.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 78.0, 130.0, 88.0, 122.0, 136.0, 76.0, 119.0, 130.0], "value": 106.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 102.0, 97.0, 147.0, 153.0, 97.0, 97.0, 97.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 96.0, 91.0, 92.0, 91.0, 90.0, 89.0, 91.0, 89.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 46.0, 85.0, 84.0, 53.0, 57.0, 58.0, 57.0, 58.0, 58.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 75.0, 76.0, 78.0, 74.0, 71.0, 76.0, 102.0, 101.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 79.0, 82.0, 95.0, 93.0, 81.0, 85.0, 88.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 148.0, 141.0, 131.0, 145.0, 135.0, 136.0, 135.0, 197.0, 148.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 161.0, 159.0, 140.0, 208.0, 225.0, 224.0, 192.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 87.0, 64.0, 58.0, 84.0, 75.0, 83.0, 63.0, 66.0], "value": 71.3, "unit": "score"}]}]} 14:40:00 INFO - Return code: 0 14:40:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:40:00 ERROR - # TBPL SUCCESS # 14:40:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:40:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:40:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:40:00 INFO - 2016-09-20 14:40:00.741 screenresolution[1683:10328] starting screenresolution argv=screenresolution get 14:40:00 INFO - 2016-09-20 14:40:00.743 screenresolution[1683:10328] Display 0: 1600x1200x32@60 14:40:00 INFO - 2016-09-20 14:40:00.749 screenresolution[1684:10331] starting screenresolution argv=screenresolution list 14:40:00 INFO - Available Modes on Display 0 14:40:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:40:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:40:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:40:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:40:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:40:00 INFO - Intel Iris: 14:40:00 INFO - Chipset Model: Intel Iris 14:40:00 INFO - Type: GPU 14:40:00 INFO - Bus: Built-In 14:40:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:40:00 INFO - Vendor: Intel (0x8086) 14:40:00 INFO - Device ID: 0x0a2e 14:40:00 INFO - Revision ID: 0x0009 14:40:00 INFO - Displays: 14:40:00 INFO - Display: 14:40:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:40:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:40:00 INFO - Display Serial Number: 1600x1200 60 14:40:00 INFO - Main Display: Yes 14:40:00 INFO - Mirror: Off 14:40:00 INFO - Online: Yes 14:40:00 INFO - Rotation: Supported 14:40:00 INFO - Return code: 0 14:40:00 INFO - Running post-action listener: _resource_record_post_action 14:40:00 INFO - [mozharness: 2016-09-20 21:40:00.984580Z] Finished run-tests step (success) 14:40:00 INFO - Running post-run listener: _resource_record_post_run 14:40:01 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 35000320; Write bytes: 495671808; Read time: 346; Write time: 1705 14:40:01 INFO - TinderboxPrint: CPU usage
54.1% 14:40:01 INFO - TinderboxPrint: I/O read bytes / time
35,000,320 / 346 14:40:01 INFO - TinderboxPrint: I/O write bytes / time
495,671,808 / 1,705 14:40:01 INFO - TinderboxPrint: CPU idle
2,192.2 (45.9%) 14:40:01 INFO - TinderboxPrint: CPU system
967.4 (20.3%) 14:40:01 INFO - TinderboxPrint: CPU user
1,614.0 (33.8%) 14:40:01 INFO - TinderboxPrint: Swap in / out
233,926,656 / 0 14:40:01 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192373248; Write bytes: 187793408; Read time: 17566; Write time: 358 14:40:01 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 32940032; Write bytes: 296888832; Read time: 323; Write time: 1308 14:40:01 INFO - Running post-run listener: _upload_blobber_files 14:40:01 INFO - Blob upload gear active. 14:40:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:40:01 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 14:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:40:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:40:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:02 INFO - (blobuploader) - INFO - Done attempting. 14:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:03 INFO - (blobuploader) - INFO - Done attempting. 14:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:40:05 INFO - (blobuploader) - INFO - Done attempting. 14:40:05 INFO - (blobuploader) - INFO - Iteration through files over. 14:40:05 INFO - Return code: 0 14:40:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:40:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:40:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c4338111a7bc58e8a85a80d11dc25884e18faeba9779274c378a6b053cb4966b72b9cccad763ef080e54819d454676342884c70d7a5cba7aa635e4fdb28e5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44bfa1bdb3021f37dc389afbd2518523c76fab0003f77c3aebe4f23b021cdf8dbc5fb860be9ca4b2bf8eb79dfb48d906b3085f8ba2b9d939748007f7f6a36845", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f589a69e3e238b0b650e6552f66138b25ce72d1b8f8c5bebe4608a78dc725a32d4f96dbb07535b9a8762cae5ad1bcd7cb5e633bd42c3a8ff78c3169d8dad8519"} 14:40:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:40:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:40:05 INFO - Contents: 14:40:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c4338111a7bc58e8a85a80d11dc25884e18faeba9779274c378a6b053cb4966b72b9cccad763ef080e54819d454676342884c70d7a5cba7aa635e4fdb28e5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44bfa1bdb3021f37dc389afbd2518523c76fab0003f77c3aebe4f23b021cdf8dbc5fb860be9ca4b2bf8eb79dfb48d906b3085f8ba2b9d939748007f7f6a36845", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f589a69e3e238b0b650e6552f66138b25ce72d1b8f8c5bebe4608a78dc725a32d4f96dbb07535b9a8762cae5ad1bcd7cb5e633bd42c3a8ff78c3169d8dad8519"} 14:40:05 INFO - Running post-run listener: copy_logs_to_upload_dir 14:40:05 INFO - Copying logs to upload dir... 14:40:05 INFO - mkdir: /builds/slave/test/build/upload/logs 14:40:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.065095 ========= master_lag: 3.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-20 14:40:09.536293) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:40:09.540548) ========= 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.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c4338111a7bc58e8a85a80d11dc25884e18faeba9779274c378a6b053cb4966b72b9cccad763ef080e54819d454676342884c70d7a5cba7aa635e4fdb28e5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44bfa1bdb3021f37dc389afbd2518523c76fab0003f77c3aebe4f23b021cdf8dbc5fb860be9ca4b2bf8eb79dfb48d906b3085f8ba2b9d939748007f7f6a36845", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f589a69e3e238b0b650e6552f66138b25ce72d1b8f8c5bebe4608a78dc725a32d4f96dbb07535b9a8762cae5ad1bcd7cb5e633bd42c3a8ff78c3169d8dad8519"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011894 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1474388716/firefox-50.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c4338111a7bc58e8a85a80d11dc25884e18faeba9779274c378a6b053cb4966b72b9cccad763ef080e54819d454676342884c70d7a5cba7aa635e4fdb28e5fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/44bfa1bdb3021f37dc389afbd2518523c76fab0003f77c3aebe4f23b021cdf8dbc5fb860be9ca4b2bf8eb79dfb48d906b3085f8ba2b9d939748007f7f6a36845", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f589a69e3e238b0b650e6552f66138b25ce72d1b8f8c5bebe4608a78dc725a32d4f96dbb07535b9a8762cae5ad1bcd7cb5e633bd42c3a8ff78c3169d8dad8519"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 14:40:09.591588) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:40:09.591884) ========= 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.mi4h61cI2g/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.pT1h9m5hiP/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005062 ========= master_lag: 0.46 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 14:40:10.053953) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-20 14:40:10.054247) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2016-09-20 14:40:15.973094) ========= ========= Total master_lag: 5.04 =========