builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0249 starttime: 1497275822.11 results: success (0) revision: b99b018d5f005aca15fe4a57418b96b6826fdcd7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.109997) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.110516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.110832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.136602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.137220) ========= 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.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 --2017-06-12 06:57:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-06-12 06:57:02 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153215 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.313036) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.313355) ========= 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.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.022676 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:02.378714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 06:57:02.379089) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b99b018d5f005aca15fe4a57418b96b6826fdcd7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev b99b018d5f005aca15fe4a57418b96b6826fdcd7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 2017-06-12 06:57:02,464 truncating revision to first 12 chars 2017-06-12 06:57:02,465 Setting DEBUG logging. 2017-06-12 06:57:02,465 attempt 1/10 2017-06-12 06:57:02,465 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b99b018d5f00?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 06:57:03,568 unpacking tar archive at: mozilla-central-b99b018d5f00/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376746 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 06:57:03.812868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:03.813215) ========= script_repo_revision: b99b018d5f005aca15fe4a57418b96b6826fdcd7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:03.813655) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:03.813958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 06:57:03.845566) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-12 06:57:03.845916) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:57:04 INFO - MultiFileLogger online at 20170612 06:57:04 in /builds/slave/test 06:57:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:57:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:57:04 INFO - {'append_to_log': False, 06:57:04 INFO - 'base_work_dir': '/builds/slave/test', 06:57:04 INFO - 'blob_upload_branch': 'Firefox', 06:57:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:57:04 INFO - 'branch': 'Firefox', 06:57:04 INFO - 'buildbot_json_path': 'buildprops.json', 06:57:04 INFO - 'config_files': ('talos/mac_config.py',), 06:57:04 INFO - 'default_actions': ('clobber', 06:57:04 INFO - 'read-buildbot-config', 06:57:04 INFO - 'download-and-extract', 06:57:04 INFO - 'populate-webroot', 06:57:04 INFO - 'create-virtualenv', 06:57:04 INFO - 'install', 06:57:04 INFO - 'setup-mitmproxy', 06:57:04 INFO - 'run-tests'), 06:57:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:57:04 INFO - 'download_minidump_stackwalk': True, 06:57:04 INFO - 'download_symbols': 'ondemand', 06:57:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:57:04 INFO - 'tooltool.py': '/tools/tooltool.py', 06:57:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:57:04 INFO - '/tools/misc-python/virtualenv.py')}, 06:57:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:57:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:57:04 INFO - 'gecko_profile': False, 06:57:04 INFO - 'gecko_profile_interval': 0, 06:57:04 INFO - 'installer_path': 'installer.exe', 06:57:04 INFO - 'log_level': 'info', 06:57:04 INFO - 'log_name': 'talos', 06:57:04 INFO - 'log_to_console': True, 06:57:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:57:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:57:04 INFO - 'opt_config_files': (), 06:57:04 INFO - 'pip_index': False, 06:57:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:57:04 INFO - 'cmd': ('bash', 06:57:04 INFO - '-c', 06:57:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:57:04 INFO - 'enabled': True, 06:57:04 INFO - 'halt_on_failure': False, 06:57:04 INFO - 'name': 'check_screen_resolution'},), 06:57:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:57:04 INFO - 'cmd': ('bash', 06:57:04 INFO - '-c', 06:57:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:57:04 INFO - 'enabled': True, 06:57:04 INFO - 'halt_on_failure': False, 06:57:04 INFO - 'name': 'check_screen_resolution'},), 06:57:04 INFO - 'run_cmd_checks_enabled': True, 06:57:04 INFO - 'suite': 'dromaeojs-e10s', 06:57:04 INFO - 'system_bits': '32', 06:57:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:57:04 INFO - 'title': 't-yosemite-r7-0249', 06:57:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:57:04 INFO - 'use_talos_json': True, 06:57:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:57:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:57:04 INFO - 'work_dir': 'build'} 06:57:04 INFO - [mozharness: 2017-06-12 13:57:04.038778Z] Running clobber step. 06:57:04 INFO - Running pre-action listener: _resource_record_pre_action 06:57:04 INFO - Running main action method: clobber 06:57:04 INFO - rmtree: /builds/slave/test/build 06:57:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:57:07 INFO - Running post-action listener: _resource_record_post_action 06:57:07 INFO - [mozharness: 2017-06-12 13:57:07.460865Z] Finished clobber step (success) 06:57:07 INFO - [mozharness: 2017-06-12 13:57:07.461060Z] Running read-buildbot-config step. 06:57:07 INFO - Running pre-action listener: _resource_record_pre_action 06:57:07 INFO - Running main action method: read_buildbot_config 06:57:07 INFO - Using buildbot properties: 06:57:07 INFO - { 06:57:07 INFO - "project": "", 06:57:07 INFO - "product": "firefox", 06:57:07 INFO - "who": "mtabara@mozilla.com", 06:57:07 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 06:57:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:57:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:57:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:57:07 INFO - "stage_platform": "macosx64", 06:57:07 INFO - "basedir": "/builds/slave/test", 06:57:07 INFO - "buildnumber": 234, 06:57:07 INFO - "platform": "yosemite_r7", 06:57:07 INFO - "parent_task_id": "TNKERECdQVKqs7fZhevVyg", 06:57:07 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 06:57:07 INFO - "slavebuilddir": "test", 06:57:07 INFO - "taskId": "WRPmVUwAQdSji9wrsBmaPg", 06:57:07 INFO - "branch": "mozilla-central", 06:57:07 INFO - "script_repo_revision": "production", 06:57:07 INFO - "revision": "b99b018d5f005aca15fe4a57418b96b6826fdcd7", 06:57:07 INFO - "slavename": "t-yosemite-r7-0249", 06:57:07 INFO - "repo_path": "mozilla-central" 06:57:07 INFO - } 06:57:07 INFO - Finding installer, test and symbols from parent task. 06:57:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRPmVUwAQdSji9wrsBmaPg'}, attempt #1 06:57:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRPmVUwAQdSji9wrsBmaPg'}, attempt #1 06:57:08 INFO - Task dependencies: fegrhsnJTk-h03GgVQQzyg TNKERECdQVKqs7fZhevVyg 06:57:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg'}, attempt #1 06:57:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 06:57:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/test_packages.json 06:57:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/target.crashreporter-symbols.zip 06:57:09 INFO - Running post-action listener: _resource_record_post_action 06:57:09 INFO - [mozharness: 2017-06-12 13:57:09.168389Z] Finished read-buildbot-config step (success) 06:57:09 INFO - [mozharness: 2017-06-12 13:57:09.168540Z] Running download-and-extract step. 06:57:09 INFO - Running pre-action listener: _resource_record_pre_action 06:57:09 INFO - Running main action method: download_and_extract 06:57:09 INFO - mkdir: /builds/slave/test/build/tests 06:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:09 INFO - trying https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 06:57:09 INFO - Downloading https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 06:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 06:57:10 INFO - Downloaded 1328 bytes. 06:57:10 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 06:57:10 INFO - Using the following test package requirements: 06:57:10 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 06:57:10 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 06:57:10 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 06:57:10 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 06:57:10 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'jsshell-mac.zip'], 06:57:10 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 06:57:10 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 06:57:10 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 06:57:10 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 06:57:10 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 06:57:10 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 06:57:10 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 06:57:10 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:57:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 06:57:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:57:10 INFO - Fetch https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 06:57:12 INFO - Content-Length response header: 59540209 06:57:12 INFO - Bytes received: 59540209 06:57:30 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:57:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 06:57:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:57:30 INFO - Fetch https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 06:57:31 INFO - Content-Length response header: 59540209 06:57:31 INFO - Bytes received: 59540209 06:57:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 06:57:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:57:49 INFO - Fetch https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 06:57:50 INFO - Content-Length response header: 14450010 06:57:50 INFO - Bytes received: 14450010 06:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:51 INFO - trying https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 06:57:51 INFO - Downloading https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 06:57:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 06:57:52 INFO - Downloaded 69309927 bytes. 06:57:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 06:57:52 INFO - mkdir: /builds/slave/test/properties 06:57:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:57:52 INFO - Writing to file /builds/slave/test/properties/build_url 06:57:52 INFO - Contents: 06:57:52 INFO - build_url:https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 06:57:52 INFO - Running post-action listener: _resource_record_post_action 06:57:52 INFO - Running post-action listener: set_extra_try_arguments 06:57:52 INFO - [mozharness: 2017-06-12 13:57:52.964638Z] Finished download-and-extract step (success) 06:57:52 INFO - [mozharness: 2017-06-12 13:57:52.964775Z] Running populate-webroot step. 06:57:52 INFO - Running pre-action listener: _resource_record_pre_action 06:57:52 INFO - Running main action method: populate_webroot 06:57:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:57:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:57:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:57:52 INFO - '--tppagecycles', 06:57:52 INFO - '7'], 06:57:52 INFO - 'tests': ['tsvgm']}}, 06:57:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:57:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:57:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:57:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:57:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:57:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['damp', 'tps']}, 06:57:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'tests': ['damp', 'tps']}, 06:57:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['dromaeo_dom']}, 06:57:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:57:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:57:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:57:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:57:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'other-e10s': {'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['a11yr', 06:57:52 INFO - 'ts_paint', 06:57:52 INFO - 'tpaint', 06:57:52 INFO - 'sessionrestore', 06:57:52 INFO - 'sessionrestore_no_auto_restore', 06:57:52 INFO - 'tabpaint']}, 06:57:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:57:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:57:52 INFO - 'bloom_basic_ref']}, 06:57:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:57:52 INFO - 'talos_options': ['--mitmproxy', 06:57:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:57:52 INFO - '--firstNonBlankPaint'], 06:57:52 INFO - 'tests': ['quantum_pageload_google', 06:57:52 INFO - 'quantum_pageload_youtube', 06:57:52 INFO - 'quantum_pageload_amazon', 06:57:52 INFO - 'quantum_pageload_facebook']}, 06:57:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['tsvgx', 06:57:52 INFO - 'tsvgr_opacity', 06:57:52 INFO - 'tart', 06:57:52 INFO - 'tscrollx', 06:57:52 INFO - 'cart', 06:57:52 INFO - 'tsvg_static']}, 06:57:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:57:52 INFO - 'tsvgr_opacity', 06:57:52 INFO - 'tart', 06:57:52 INFO - 'tscrollx', 06:57:52 INFO - 'cart', 06:57:52 INFO - 'tsvg_static']}, 06:57:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'talos_options': ['--disable-e10s'], 06:57:52 INFO - 'tests': ['tp5o']}, 06:57:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:57:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'talos_options': ['--disable-e10s', 06:57:52 INFO - '--xperf_path', 06:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:57:52 INFO - 'tests': ['tp5n']}, 06:57:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:57:52 INFO - 'talos_options': ['--xperf_path', 06:57:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:57:52 INFO - 'tests': ['tp5n']}}, 06:57:52 INFO - 'talos.zip': {'path': '', 06:57:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:57:52 INFO - Running post-action listener: _resource_record_post_action 06:57:52 INFO - [mozharness: 2017-06-12 13:57:52.986846Z] Finished populate-webroot step (success) 06:57:52 INFO - [mozharness: 2017-06-12 13:57:52.986980Z] Running create-virtualenv step. 06:57:52 INFO - Running pre-action listener: _resource_record_pre_action 06:57:52 INFO - Running main action method: create_virtualenv 06:57:52 INFO - Creating virtualenv /builds/slave/test/build/venv 06:57:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:57:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:57:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:57:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:57:53 INFO - Using real prefix '/tools/python27' 06:57:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:57:53 INFO - Installing distribute.............................................................................................................................................................................................done. 06:57:56 INFO - Installing pip.................done. 06:57:56 INFO - Return code: 0 06:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:57:56 INFO - Reading from file tmpfile_stdout 06:57:56 INFO - Output received: 06:57:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:57:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:56 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:57:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:56 INFO - 'HOME': '/Users/cltbld', 06:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:56 INFO - 'LOGNAME': 'cltbld', 06:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:56 INFO - 'MOZ_NO_REMOTE': '1', 06:57:56 INFO - 'NO_EM_RESTART': '1', 06:57:56 INFO - 'PAGER': '/bin/cat', 06:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:56 INFO - 'PWD': '/builds/slave/test', 06:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:56 INFO - 'SHELL': '/bin/bash', 06:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:57:56 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:56 INFO - 'USER': 'cltbld', 06:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:56 INFO - 'XPC_FLAGS': '0x0', 06:57:56 INFO - 'XPC_SERVICE_NAME': '0', 06:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:57:57 INFO - Cleaning up... 06:57:57 INFO - Return code: 0 06:57:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:57 INFO - 'HOME': '/Users/cltbld', 06:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:57 INFO - 'LOGNAME': 'cltbld', 06:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:57 INFO - 'MOZ_NO_REMOTE': '1', 06:57:57 INFO - 'NO_EM_RESTART': '1', 06:57:57 INFO - 'PAGER': '/bin/cat', 06:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:57 INFO - 'PWD': '/builds/slave/test', 06:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:57 INFO - 'SHELL': '/bin/bash', 06:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:57:57 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:57 INFO - 'USER': 'cltbld', 06:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:57 INFO - 'XPC_FLAGS': '0x0', 06:57:57 INFO - 'XPC_SERVICE_NAME': '0', 06:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:57 INFO - Downloading/unpacking psutil>=3.1.1 06:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:57:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:57:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:57:59 INFO - Installing collected packages: psutil 06:57:59 INFO - Running setup.py install for psutil 06:57:59 INFO - building 'psutil._psutil_osx' extension 06:57:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:58:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:58:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:58:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:58:00 INFO - building 'psutil._psutil_posix' extension 06:58:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:58:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:58:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:58:00 INFO - ^ 06:58:00 INFO - 1 warning generated. 06:58:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:58:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:58:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:58:00 INFO - Successfully installed psutil 06:58:00 INFO - Cleaning up... 06:58:00 INFO - Return code: 0 06:58:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:58:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:00 INFO - 'HOME': '/Users/cltbld', 06:58:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:00 INFO - 'LOGNAME': 'cltbld', 06:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:00 INFO - 'MOZ_NO_REMOTE': '1', 06:58:00 INFO - 'NO_EM_RESTART': '1', 06:58:00 INFO - 'PAGER': '/bin/cat', 06:58:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:00 INFO - 'PWD': '/builds/slave/test', 06:58:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:00 INFO - 'SHELL': '/bin/bash', 06:58:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:00 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:00 INFO - 'USER': 'cltbld', 06:58:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:00 INFO - 'XPC_FLAGS': '0x0', 06:58:00 INFO - 'XPC_SERVICE_NAME': '0', 06:58:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:58:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:58:02 INFO - Installing collected packages: mozsystemmonitor 06:58:02 INFO - Running setup.py install for mozsystemmonitor 06:58:02 INFO - Successfully installed mozsystemmonitor 06:58:02 INFO - Cleaning up... 06:58:02 INFO - Return code: 0 06:58:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:02 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:02 INFO - 'HOME': '/Users/cltbld', 06:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:02 INFO - 'LOGNAME': 'cltbld', 06:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:02 INFO - 'MOZ_NO_REMOTE': '1', 06:58:02 INFO - 'NO_EM_RESTART': '1', 06:58:02 INFO - 'PAGER': '/bin/cat', 06:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:02 INFO - 'PWD': '/builds/slave/test', 06:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:02 INFO - 'SHELL': '/bin/bash', 06:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:02 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:02 INFO - 'USER': 'cltbld', 06:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:02 INFO - 'XPC_FLAGS': '0x0', 06:58:02 INFO - 'XPC_SERVICE_NAME': '0', 06:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:02 INFO - Downloading/unpacking jsonschema==2.5.1 06:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:58:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:04 INFO - Downloading functools32-3.2.3-2.tar.gz 06:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:58:04 INFO - warning: no files found matching '*.txt' 06:58:04 INFO - no previously-included directories found matching 'build' 06:58:04 INFO - no previously-included directories found matching 'dist' 06:58:04 INFO - no previously-included directories found matching '.git*' 06:58:04 INFO - Installing collected packages: jsonschema, functools32 06:58:04 INFO - Running setup.py install for functools32 06:58:04 INFO - warning: no files found matching '*.txt' 06:58:04 INFO - no previously-included directories found matching 'build' 06:58:04 INFO - no previously-included directories found matching 'dist' 06:58:04 INFO - no previously-included directories found matching '.git*' 06:58:04 INFO - Successfully installed jsonschema functools32 06:58:04 INFO - Cleaning up... 06:58:04 INFO - Return code: 0 06:58:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:04 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:04 INFO - 'HOME': '/Users/cltbld', 06:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:04 INFO - 'LOGNAME': 'cltbld', 06:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:04 INFO - 'MOZ_NO_REMOTE': '1', 06:58:04 INFO - 'NO_EM_RESTART': '1', 06:58:04 INFO - 'PAGER': '/bin/cat', 06:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:04 INFO - 'PWD': '/builds/slave/test', 06:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:04 INFO - 'SHELL': '/bin/bash', 06:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:04 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:04 INFO - 'USER': 'cltbld', 06:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:04 INFO - 'XPC_FLAGS': '0x0', 06:58:04 INFO - 'XPC_SERVICE_NAME': '0', 06:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:58:05 INFO - Cleaning up... 06:58:05 INFO - Return code: 0 06:58:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:05 INFO - 'HOME': '/Users/cltbld', 06:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:05 INFO - 'LOGNAME': 'cltbld', 06:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:05 INFO - 'MOZ_NO_REMOTE': '1', 06:58:05 INFO - 'NO_EM_RESTART': '1', 06:58:05 INFO - 'PAGER': '/bin/cat', 06:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:05 INFO - 'PWD': '/builds/slave/test', 06:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:05 INFO - 'SHELL': '/bin/bash', 06:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:05 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:05 INFO - 'USER': 'cltbld', 06:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:05 INFO - 'XPC_FLAGS': '0x0', 06:58:05 INFO - 'XPC_SERVICE_NAME': '0', 06:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:05 INFO - Downloading/unpacking blobuploader==1.2.4 06:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:06 INFO - Downloading blobuploader-1.2.4.tar.gz 06:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:58:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:58:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:07 INFO - Downloading docopt-0.6.1.tar.gz 06:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:58:07 INFO - Installing collected packages: blobuploader, requests, docopt 06:58:07 INFO - Running setup.py install for blobuploader 06:58:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:58:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:58:07 INFO - Running setup.py install for requests 06:58:08 INFO - Running setup.py install for docopt 06:58:08 INFO - Successfully installed blobuploader requests docopt 06:58:08 INFO - Cleaning up... 06:58:08 INFO - Return code: 0 06:58:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:08 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:08 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:58:08 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:08 INFO - 'HOME': '/Users/cltbld', 06:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:08 INFO - 'LOGNAME': 'cltbld', 06:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:08 INFO - 'MOZ_NO_REMOTE': '1', 06:58:08 INFO - 'NO_EM_RESTART': '1', 06:58:08 INFO - 'PAGER': '/bin/cat', 06:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:08 INFO - 'PWD': '/builds/slave/test', 06:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:08 INFO - 'SHELL': '/bin/bash', 06:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:08 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:08 INFO - 'USER': 'cltbld', 06:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:08 INFO - 'XPC_FLAGS': '0x0', 06:58:08 INFO - 'XPC_SERVICE_NAME': '0', 06:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:58:08 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-HM2sTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:58:08 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-gmES_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-1p56vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Wh4tGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-pnruw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Db9lpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-AWQX_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cl2iTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-NE7N9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Jrs8y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xVo1SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:58:09 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-GPRfVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:58:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-zRRV7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:58:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-3dei22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:58:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-QOoGpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:58:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-8m6LVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:58:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:58:10 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-k_mQvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:58:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:58:10 INFO - Running setup.py install for manifestparser 06:58:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:58:10 INFO - Running setup.py install for mozcrash 06:58:10 INFO - Running setup.py install for mozdebug 06:58:11 INFO - Running setup.py install for mozdevice 06:58:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Running setup.py install for mozfile 06:58:11 INFO - Running setup.py install for mozhttpd 06:58:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Running setup.py install for mozinfo 06:58:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Running setup.py install for mozInstall 06:58:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:58:11 INFO - Running setup.py install for mozleak 06:58:11 INFO - Running setup.py install for mozlog 06:58:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Running setup.py install for moznetwork 06:58:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Running setup.py install for mozprocess 06:58:12 INFO - Running setup.py install for mozprofile 06:58:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Running setup.py install for mozrunner 06:58:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:58:12 INFO - Running setup.py install for mozscreenshot 06:58:12 INFO - Running setup.py install for moztest 06:58:13 INFO - Running setup.py install for mozversion 06:58:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:58:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:58:13 INFO - Cleaning up... 06:58:13 INFO - Return code: 0 06:58:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:13 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:13 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:58:13 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:58:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:13 INFO - 'HOME': '/Users/cltbld', 06:58:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:13 INFO - 'LOGNAME': 'cltbld', 06:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:13 INFO - 'MOZ_NO_REMOTE': '1', 06:58:13 INFO - 'NO_EM_RESTART': '1', 06:58:13 INFO - 'PAGER': '/bin/cat', 06:58:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:13 INFO - 'PWD': '/builds/slave/test', 06:58:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:13 INFO - 'SHELL': '/bin/bash', 06:58:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:13 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:13 INFO - 'USER': 'cltbld', 06:58:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:13 INFO - 'XPC_FLAGS': '0x0', 06:58:13 INFO - 'XPC_SERVICE_NAME': '0', 06:58:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-bt_lJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-5xR7s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MpmGbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-1JaLqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cAtFX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:58:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-W6C049-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-GIyG5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cnkD4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-r0DunP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-TCAt5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-1v0zx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-0YNITn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Z0_Lus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-q94NW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:58:14 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MnNzw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:58:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-6SoDhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:58:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:58:15 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-rpveXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:58:15 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:58:15 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:58:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:16 INFO - Downloading blessings-1.6.tar.gz 06:58:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:58:16 INFO - Installing collected packages: blessings 06:58:16 INFO - Running setup.py install for blessings 06:58:16 INFO - Successfully installed blessings 06:58:16 INFO - Cleaning up... 06:58:17 INFO - Return code: 0 06:58:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:58:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:58:17 INFO - Reading from file tmpfile_stdout 06:58:17 INFO - Current package versions: 06:58:17 INFO - blessings == 1.6 06:58:17 INFO - blobuploader == 1.2.4 06:58:17 INFO - docopt == 0.6.1 06:58:17 INFO - functools32 == 3.2.3-2 06:58:17 INFO - jsonschema == 2.5.1 06:58:17 INFO - manifestparser == 1.1 06:58:17 INFO - mozInstall == 1.13 06:58:17 INFO - mozcrash == 1.0 06:58:17 INFO - mozdebug == 0.1 06:58:17 INFO - mozdevice == 0.51 06:58:17 INFO - mozfile == 1.2 06:58:17 INFO - mozhttpd == 0.7 06:58:17 INFO - mozinfo == 0.10 06:58:17 INFO - mozleak == 0.1 06:58:17 INFO - mozlog == 3.4 06:58:17 INFO - moznetwork == 0.27 06:58:17 INFO - mozprocess == 0.25 06:58:17 INFO - mozprofile == 0.28 06:58:17 INFO - mozrunner == 6.13 06:58:17 INFO - mozscreenshot == 0.1 06:58:17 INFO - mozsystemmonitor == 0.3 06:58:17 INFO - moztest == 0.8 06:58:17 INFO - mozversion == 1.4 06:58:17 INFO - psutil == 3.1.1 06:58:17 INFO - requests == 1.2.3 06:58:17 INFO - wsgiref == 0.1.2 06:58:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:17 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:58:17 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:17 INFO - 'HOME': '/Users/cltbld', 06:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:17 INFO - 'LOGNAME': 'cltbld', 06:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:17 INFO - 'MOZ_NO_REMOTE': '1', 06:58:17 INFO - 'NO_EM_RESTART': '1', 06:58:17 INFO - 'PAGER': '/bin/cat', 06:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:17 INFO - 'PWD': '/builds/slave/test', 06:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:17 INFO - 'SHELL': '/bin/bash', 06:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:17 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:17 INFO - 'USER': 'cltbld', 06:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:17 INFO - 'XPC_FLAGS': '0x0', 06:58:17 INFO - 'XPC_SERVICE_NAME': '0', 06:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:58:17 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:58:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:58:19 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:58:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:58:19 INFO - Installing collected packages: marionette-driver 06:58:19 INFO - Running setup.py install for marionette-driver 06:58:19 INFO - Successfully installed marionette-driver 06:58:19 INFO - Cleaning up... 06:58:19 INFO - Return code: 0 06:58:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:58:19 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/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 0x10e29ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa581d6d150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, '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.xnKAqp4YAG/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.aFifVNnpnz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:19 INFO - 'HOME': '/Users/cltbld', 06:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:19 INFO - 'LOGNAME': 'cltbld', 06:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:19 INFO - 'MOZ_NO_REMOTE': '1', 06:58:19 INFO - 'NO_EM_RESTART': '1', 06:58:19 INFO - 'PAGER': '/bin/cat', 06:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:19 INFO - 'PWD': '/builds/slave/test', 06:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:19 INFO - 'SHELL': '/bin/bash', 06:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:19 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:19 INFO - 'USER': 'cltbld', 06:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:19 INFO - 'XPC_FLAGS': '0x0', 06:58:19 INFO - 'XPC_SERVICE_NAME': '0', 06:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:58:19 INFO - Cleaning up... 06:58:19 INFO - Return code: 0 06:58:19 INFO - Running post-action listener: _resource_record_post_action 06:58:19 INFO - Running post-action listener: _start_resource_monitoring 06:58:19 INFO - Starting resource monitoring. 06:58:19 INFO - [mozharness: 2017-06-12 13:58:19.648209Z] Finished create-virtualenv step (success) 06:58:19 INFO - [mozharness: 2017-06-12 13:58:19.649205Z] Running install step. 06:58:19 INFO - Running pre-action listener: _resource_record_pre_action 06:58:19 INFO - Running main action method: install 06:58:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:58:19 INFO - Reading from file tmpfile_stdout 06:58:19 INFO - Detecting whether we're running mozinstall >=1.0... 06:58:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:58:20 INFO - Reading from file tmpfile_stdout 06:58:20 INFO - Output received: 06:58:20 INFO - Usage: mozinstall [options] installer 06:58:20 INFO - Options: 06:58:20 INFO - -h, --help show this help message and exit 06:58:20 INFO - -d DEST, --destination=DEST 06:58:20 INFO - Directory to install application into. [default: 06:58:20 INFO - "/builds/slave/test"] 06:58:20 INFO - --app=APP Application being installed. [default: firefox] 06:58:20 INFO - mkdir: /builds/slave/test/build/application 06:58:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:58:42 INFO - Reading from file tmpfile_stdout 06:58:42 INFO - Output received: 06:58:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:58:42 INFO - Running post-action listener: _resource_record_post_action 06:58:42 INFO - [mozharness: 2017-06-12 13:58:42.244412Z] Finished install step (success) 06:58:42 INFO - [mozharness: 2017-06-12 13:58:42.244524Z] Running setup-mitmproxy step. 06:58:42 INFO - Running pre-action listener: _resource_record_pre_action 06:58:42 INFO - Running main action method: setup_mitmproxy 06:58:42 INFO - Skipping: mitmproxy is not required 06:58:42 INFO - Running post-action listener: _resource_record_post_action 06:58:42 INFO - [mozharness: 2017-06-12 13:58:42.245219Z] Finished setup-mitmproxy step (success) 06:58:42 INFO - [mozharness: 2017-06-12 13:58:42.245333Z] Running run-tests step. 06:58:42 INFO - Running pre-action listener: _resource_record_pre_action 06:58:42 INFO - Running main action method: run_tests 06:58:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:58:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:58:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:58:42 INFO - 2017-06-12 06:58:42.259 screenresolution[1925:21594] starting screenresolution argv=screenresolution get 06:58:42 INFO - 2017-06-12 06:58:42.262 screenresolution[1925:21594] Display 0: 1600x1200x32@60 06:58:42 INFO - 2017-06-12 06:58:42.268 screenresolution[1926:21596] starting screenresolution argv=screenresolution list 06:58:42 INFO - Available Modes on Display 0 06:58:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:58:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:58:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:58:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:58:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:58:42 INFO - Intel Iris: 06:58:42 INFO - Chipset Model: Intel Iris 06:58:42 INFO - Type: GPU 06:58:42 INFO - Bus: Built-In 06:58:42 INFO - VRAM (Dynamic, Max): 1536 MB 06:58:42 INFO - Vendor: Intel (0x8086) 06:58:42 INFO - Device ID: 0x0a2e 06:58:42 INFO - Revision ID: 0x0009 06:58:42 INFO - Displays: 06:58:42 INFO - Display: 06:58:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:58:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:58:42 INFO - Display Serial Number: 1600x1200 60 06:58:42 INFO - Main Display: Yes 06:58:42 INFO - Mirror: Off 06:58:42 INFO - Online: Yes 06:58:42 INFO - Rotation: Supported 06:58:42 INFO - Return code: 0 06:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:58:42 INFO - Python 2.7.3 06:58:42 INFO - Return code: 0 06:58:42 INFO - grabbing minidump binary from tooltool 06:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:58:42 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 0x10dff4d30>, '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 0x10dfbecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfe5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:58:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:58:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:58:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:58:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:58:42 INFO - Return code: 0 06:58:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:58:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:58:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:58:42 INFO - ENV: RUST_BACKTRACE is now 1 06:58:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:58:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 06:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0249 --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 06:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnKAqp4YAG/Render', 06:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:42 INFO - 'HOME': '/Users/cltbld', 06:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:42 INFO - 'LOGNAME': 'cltbld', 06:58:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:58:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:42 INFO - 'MOZ_NO_REMOTE': '1', 06:58:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:58:42 INFO - 'NO_EM_RESTART': '1', 06:58:42 INFO - 'PAGER': '/bin/cat', 06:58:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:42 INFO - 'PWD': '/builds/slave/test', 06:58:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:42 INFO - 'RUST_BACKTRACE': '1', 06:58:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:58:42 INFO - 'SHELL': '/bin/bash', 06:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aFifVNnpnz/Listeners', 06:58:42 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 06:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:42 INFO - 'USER': 'cltbld', 06:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:42 INFO - 'XPC_FLAGS': '0x0', 06:58:42 INFO - 'XPC_SERVICE_NAME': '0', 06:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 06:58:42 INFO - mozversion application_buildid: 20170612055115 06:58:42 INFO - mozversion application_changeset: b99b018d5f005aca15fe4a57418b96b6826fdcd7 06:58:42 INFO - mozversion application_display_name: Nightly 06:58:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:58:42 INFO - mozversion application_name: Firefox 06:58:42 INFO - mozversion application_remotingname: firefox 06:58:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:58:42 INFO - mozversion application_vendor: Mozilla 06:58:42 INFO - mozversion application_version: 56.0a1 06:58:42 INFO - mozversion platform_buildid: 20170612055115 06:58:42 INFO - mozversion platform_changeset: b99b018d5f005aca15fe4a57418b96b6826fdcd7 06:58:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:58:42 INFO - mozversion platform_version: 56.0a1 06:58:42 INFO - using testdate: 1497275922 06:58:42 INFO - actual date: 1497275922 06:58:42 INFO - starting webserver on 'localhost:56638' 06:58:42 INFO - SUITE-START | Running 2 tests 06:58:42 INFO - TEST-START | dromaeo_css 06:58:42 INFO - operating with platform_type : mac_ 06:58:42 INFO - Initialising browser for dromaeo_css test... 06:58:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56638/getInfo.html -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpbUJhST/profile 06:58:42 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56638/getInfo.html -foreground) 06:59:16 INFO - TEST-INFO | 1936: killed by SIGTERM 06:59:16 INFO - Browser initialized. 06:59:16 INFO - Running cycle 1/1 for dromaeo_css test... 06:59:16 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpbUJhST/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:59:18 INFO - PID 1942 | 2017-06-12 06:59:18.547 plugin-container[1943:22072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 06:59:18 INFO - PID 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:59:18 INFO - PID 1942 | 2017-06-12 06:59:18.547 plugin-container[1943:22072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa103, name = 'com.apple.CFPasteboardClient' 06:59:18 INFO - PID 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:59:18 INFO - PID 1942 | 2017-06-12 06:59:18.547 plugin-container[1943:22072] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:59:19 INFO - PID 1942 | 2017-06-12 06:59:19.355 plugin-container[1944:22123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 06:59:19 INFO - PID 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:59:19 INFO - PID 1942 | 2017-06-12 06:59:19.356 plugin-container[1944:22123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 06:59:19 INFO - PID 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:59:19 INFO - PID 1942 | 2017-06-12 06:59:19.356 plugin-container[1944:22123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:59:28 INFO - PID 1942 | RSS: Main: 251920384 06:59:28 INFO - PID 1942 | 07:02:31 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56638/tests/dromaeo/cssquery-ext.html) 07:02:33 INFO - PID 1942 | RSS: Main: 267063296 07:02:33 INFO - PID 1942 | 07:05:35 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-ext.html (next: http://localhost:56638/tests/dromaeo/cssquery-jquery.html) 07:05:36 INFO - PID 1942 | RSS: Main: 276021248 07:05:36 INFO - PID 1942 | 07:08:37 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56638/tests/dromaeo/cssquery-mootools.html) 07:08:38 INFO - PID 1942 | RSS: Main: 277803008 07:08:38 INFO - PID 1942 | 07:11:39 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56638/tests/dromaeo/cssquery-prototype.html) 07:11:40 INFO - PID 1942 | RSS: Main: 279396352 07:11:40 INFO - PID 1942 | 07:14:43 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56638/tests/dromaeo/cssquery-yui.html) 07:14:44 INFO - PID 1942 | RSS: Main: 280965120 07:14:44 INFO - PID 1942 | 07:17:45 INFO - PID 1942 | Cycle 1(1): loaded http://localhost:56638/tests/dromaeo/cssquery-yui.html (next: http://localhost:56638/tests/dromaeo/cssquery-dojo.html) 07:17:46 INFO - PID 1942 | RSS: Main: 282054656 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | __start_tp_report 07:17:46 INFO - PID 1942 | _x_x_mozilla_page_load 07:17:46 INFO - PID 1942 | _x_x_mozilla_page_load_details 07:17:46 INFO - PID 1942 | |i|pagename|runs| 07:17:46 INFO - PID 1942 | |0;dojo.html;2870.2393865625613;3412.624611292759;3492.9695739756776;3562.4834399012143;3569.1255642367623;5966.8662954038755;6032.155498230246;6074.787382441616;6084.878302433955;6092.543059270551;6046.727897244624;6058.6061905976085;6085.7565697372165;6095.994160963436;6096.116063170837;5265.210218467222;5274.393444753845;5299.655522391032;5334.359876814788;5353.7590808413715;5313.9734301328435;5353.732313384335;5371.435999220084;5380.488853558905;5384.461553844604;5341.599380046512;5345.679259244438;5375.489328513785;5392.946070539284;5401.837944861661;5449.427810079944;5460.31746031746;5462.180672899057;5476.205950137208;5528.032594295983;5389.78440862365;5407.918881216785;5428.321459817523;5433.320834895638;5452.672839629595;5184.870378240536;6032.698365081772;6224.253089629274;6236.064590311445;6291.521492449271;6421.389967953037;6496.090547323379;6502.382273683992;6644.468442524587;6657.700403481855;6576.506761992822;6654.567453115532;6754.763583274562;6761.01965215041;6772.051912732172;6173;6184.19605451293;6202.906956395657;6212.1303017577575;6213.56505044647;6697.6986035628515;6718;6721.630310332881;6724.159480064992;6734.966325168343;5618.353889302701;6347;6365.395287447777;6411.96794016027;6465.870682586322;6563.1796025496815;6568.474522038226;6600.570962887493;6666.333366663295;6703.597784132968;6508.251551071688;6565.343465653492;6607.2071351438135;6610.7025183866845;6645.269020407752;6613.900791488131;6614.669266536654;6622.966885165543;6638.601683898885;6640.103586015826;5186.974065129651;5199.402068762065;5221.321228240305;5239.1355426354985;5250.527452529214;4013.418054382098;4729.385179926588;4753.500882407367;4759.262314341283;4771.475137734847;6188.9690551546955;6284.340144284784;6314.778982735674;6320.905186422207;6335.619862808247;6741.561798483083;6742.393184613408;6743;6762.087118239073;6778.084958530634;6016.578839481194;6129.267100814801;6225.8132256032395;6292.937070629327;6315.652639104894;4732.432108147044;5740.454656807597;5742.253507044057;5758.740856661377;5825.50483208932;6094.329623741385;6295.276043255084;6793.5244532882225;6843.349881761224;6861.485388595976;6708.463322934251;6751.156105486814;6771.966140169267;6779.593224406552;6799.93200067993;6849.863002740017;6872.553284036521;6884.965575172092;6900.068490753684;6924.826879328057;6404.487640988803;6481.708323125376;6483.675816209265;6600.768973086111;6612.280806990121;6599;6652.168478940132;6730.0914376560495;6734.831629209309;6739.090222820053;6463.903041454288;6483.384078512534;6498.837529061622;6503.804885853432;6517.837054073686;6485.027245913151;6499.38255865692;6504.707288172138;6523.24982627004;6527.379898909596;6269.49844012487;6340.334264902119;6373.681315934277;6375.489960803032;6385.87228255423;6242.937570624417;6318.747250109944;6320.367963203643;6417.229932407954;6484.092227088306;6237.4698150657705;6279.372062793684;6298.559100862895;6328.841778955563;6350.237971443272 07:17:46 INFO - PID 1942 | |1;ext.html;765.1277543600297;811.3549727966274;813.5566116466525;823.1644880446346;856.7601071699922;36577.81711091444;37700.43449348255;37700.811495942515;37762.24475510488;37786.62213377869;37134.442983355206;37479.250414991686;37498.62501374986;37498.62501374992;37571.4364284536;7368.152662443834;7472.962635186816;7491.138519070295;7503.137139229004;7504.061992250969;8050.476719013274;8223.301019413357;8270.37972152088;8311.667533298662;8337.83324333513;8318.334533237357;8343.95828020862;8349.457285276469;8376.706815261467;8411.369147313946;8270.586470676471;8327.8334433311;8337.83324333513;8341.124181960928;8369.288610468118;8024.836398722153;8059.314958228558;8114.797130071736;8144.6742130314715;8150.836983260301;35781.46327805085;35827;35851.4622280666;35865;36027;3445.758796884219;3448.793072415638;3484.4599087141532;3490.930181396358;3491.127218195451;29249.268768280752;29403.558946615816;29498.262543436373;29530.70469295301;29616.555751663742;2948.2629342664336;3025.9243518912203;3033.620797400325;3052.2521982114304;3060.724534791857;233674.83162584077;236859;238677;314983;317994;36792.448113278326;45421.77289113533;45508;45582.77208614002;46115.538844611794;49091.509084909405;49380.259296110584;49401;49577.50422495729;49617.751911240215;14006.509772157926;14081.788773168411;14086.084404513675;14112.22382768937;14132.364043618061;11479.25384849982;11490.655280341603;11498.25261358009;11514.251573647685;11544.88455115438;13171.868281317064;13242.940564754797;13298.135621184592;13335.866641333656;13384.330783460788;6009.969950150309;6049.909251361233;6099.359567245376;6104.725287362079;6115.257151711741;1001.7345403467941;1007.4861820471613;1030.0420608833856;1073.162932912314;1075;22694.659580106312;22934.42663933415;22988.65517017246;23001.424964376023;23798.208919461515;1090.7164137324353;1102.2119184782919;1159.721666799962;1161.2742036227357;1167.2762887010108;11284.605038823602;11570.19008669385;11658;11781.941090294493;12157.148999570121;332239.3388033044;332769;334040;334521;334940;52200.73899630553;52251;52415.475845241825;52670.946581068165;52799;1466.0617204988816;1490.3442485306648;1506.9773953390709;1520.3538496139097;1524.9695006100037;18309.45071647853;21848.235311764012;22457;22743.203987860346;23943.640845386984;36404.27191456103;36488.905332840055;36550;36644.45033324556;36691.44962825613;248215;257879;258142.41857581935;259795;259865;23983.640245395985;28144.29639258953;28592.42815143727;28668.71331286744;32926.84756033623;38706.80646596749;39365;39420.60579394169;39529.209415812096;39553.604463954995;22066.88966555157;26816.865915670296;26840.865795670896;27364.042258520858;27385;28418.00536981196;28852.711472885003;29054.7094529052;29171.85414072916;29567.408651827274;27391;27885.442291154468;27924.86037569799;31514.36971260608;33854.830725846216;27844.16467505978;28197.43605127927;28572.42855142844;29286;29386;19075.809241907402;20990.95045247701;21705.674414884106;23251.53496930018;23427 07:17:46 INFO - PID 1942 | |2;jquery.html;500.3595397890699;508.25794340263235;513.4480433533951;522.6106550619787;532.419662567801;21242.681359779577;21791;22246.998885050172;22472.66291005634;24306.392340191505;24545.631815522738;24823.875880620573;25021.74978250217;25116.74883251167;25147.622785658183;6321.431071203579;6472.2880483147055;6477.449416799578;6491.578047426902;6500.772472963447;5688.146777983295;6084.513238940896;6371.267304260023;6430.1319321891415;6440.742370305182;6304.085907543404;6313.684315784215;6352.5235607329405;6354.87290254197;6367.204099487564;6339.746410143589;6357.141785858919;6357.396047375492;6363.554551181443;6381.904271435886;5826.592138550303;5829.300483941955;5829.446202610745;5833.504152147073;5834.708264586754;27082.5937610934;28593.85703071492;29432.411351772844;29711.851440742874;29857.70142298571;2179.08478439056;2203.4271089516683;2240.10395841663;2241.1595651630637;2242.585121752481;18047.819521804744;18577.442676719584;18579.90710046441;18585.07074646262;18601.44195674132;2143.9035243414082;2199.967000494994;2224.67742177385;2228.8662680239236;2239.9328020159423;152638;158399.2080039604;160621.19689401478;160775.19612401864;184836;12003.51985920571;13145.619709930319;13297.800532992012;13670.589882303548;14781.482648107265;19804.70292945607;19975.20099196016;19999.00004999744;20007;20048.59902801936;9651.324407291422;9689.612415503441;9719.65981190655;9755.268354873413;9767.51162441875;6855.245922948472;8580.442271252348;8905.688300909436;8907.777305567413;8974.596143173572;8829.60266787996;8895;8920.910790892009;8991.505467199237;9021.413608115452;2554.731753165928;2569.614557816305;2572.331193889602;2576.330154159932;2712.823666461674;951.8667386565886;962.8796400449944;963.9759006024906;1004.7340351157594;1010.8368092282375;18247.54381140482;19734;20339;26384.340391490365;29411.823527058677;881.9064360193443;924.7965447601517;997.850322451638;1039.8284283093205;1045.6340280901775;10302.70897027424;10713.464326783629;10902.945485272681;11041.889581104248;11360.091192704564;579210;591392;602269;602636;603666;154293;154466;154903.22548387412;167031;169325;1072.7527637061714;1220.0788404754353;1237.5915947737226;1248.3134276148082;1248.650377894191;16088.517344479684;19140.330088446837;19161.712574311117;19450.61098778045;20030.799692002893;69173.61652766816;70712.93930590943;72528.63735681499;73075;75553.6222318907;96037.51981240329;100563.49718251362;100650;101647.98352016385;103248;11467.598634047768;11680.06559475257;11687.415629218674;11694.064474842162;11715.121365897421;14508.419663213353;17444.040577767835;18131.456056318333;18374.81625183731;18403.447896563124;14619.049761765451;14703.485378011717;14705.485308014167;14838.40646374133;15165.62085947816;12595.433205505591;12715.427805748948;12954.676133096747;13319.467221311426;13563.864361356655;15050.322735477148;15471;15545.766813498032;15649.061056336655;15673.68652626964;11494.425278736197;11715.238509496856;11717.76564468723;11727.648170554898;11863.406829658656;14797.778033329294;14892.106473611619;14911.776323355376;15017;15035.699286014436 07:17:46 INFO - PID 1942 | |3;mootools.html;1243.7686690176727;1332.593558964516;1376.2774543364737;1394.672252020775;1405.578326502049;11447.942760286198;12985;13857.514986975459;13885.514007009757;13901;13397.799033014513;13713.108647937877;13814.171149731023;13841.100328478642;13853.861461385382;2720.2519307190482;2772.1960631416864;2775.111964171466;2781.2629653141935;2782.4296019316093;2685;2712.891484340624;2725.3322935880637;2732.1257197696746;2733.084416720391;2259.9887000565054;2593.299809051555;2773.847438390888;2778.7221277872254;2778.7915906307007;2709.607106969488;2735.384538478846;2768.9169324920285;2769.030839206282;2774.2370848016753;2711.7559419652325;2721.598376835931;2728.31792051987;2736.138116493298;2749.4913441013477;12686.746265074738;12858.807117893239;12923.030772691991;12987.87012129876;12992.415341309665;7309;7523.623818809037;7570.091589009299;7578;7585.279398457193;4441.289393696993;4666.626669866402;4735.976320118411;4740.644451666104;4772.093302272557;1227.1532642476664;1380.0684537936952;1380.4892189889806;1389.8957578181678;1409.6757745718542;15708;19431.51421214467;19885.9005704972;19893.20427182911;19937.60124797496;6748.831279218059;6852.897206541906;6954.165500140016;6993.335633114846;6996.6151861648095;16276.186190690418;16323.673526529641;16384.75422868658;16408.6718265634;16429.83570164307;7313.341799238094;7574.356179724675;7613.9238607613215;7621.275978782118;7645.5794931279315;6594.637294948728;6754.93245067543;6800.965995170091;6824.283450237753;6866.038754574363;4528.618771274727;5148.099082660519;5319.228711836762;5351.785928562814;5378;1650.8266632003706;1679.5471916791887;1681.2350380576809;1685.7892763404575;1704.8806583539033;785.3834739729399;800.7717800426967;805.685782544808;812.9349652027942;843.7215718812777;6863.828404289833;7058.964705176441;7138.428925686037;7189.0654214952765;7238.493305468567;649.1820306413953;670.3430637974812;705.6260182103493;706.0327351528439;710.9502334836616;3932.8623498177426;4058.7970601470397;4239.894002649959;4688.812447502061;5074.746262686925;16470.42353517621;17536.82463175352;17955.73066404005;18017.2793088275;18253.54366140857;17830.46508604744;17903.552411189565;18139.18373673188;18174.727379089323;18227.17977691007;9291.907080929239;9425.96314405401;9473.47895865734;9501.952490237505;9602.08780165883;5831.94168058314;7640.274173953466;7718.266764657348;8147.511149331059;8396.874046889423;15632.531024069296;15923;16078.35686572524;16081.75877361817;16673.333066677198;13006.544770932973;13552.661183470493;15953.601159971093;16853.23041080718;16915.577110572332;10576.259661823598;10596.099331556905;10613.787724245627;10665.573377064831;10791;11734.413279335828;11934.701632459259;11949.163558550817;11979.700507487383;12001.879981200076;9706.85439718419;9717.805643887225;9967.352122112039;10033.1973442123;10170.694879153638;9494.287928405536;9706.85439718419;9716.271279653913;9746.122848943629;9996.300258982092;7557.697692092254;8921.776955576162;8936.687215947411;8983.595738191667;8998.910010899806;5960.748242869046;6455;6828.863422731617;7001.124859392576;7074;6538.542302038812;6703.631300278336;6799.864002720017;7036.507444478837;7096.467764917755 07:17:46 INFO - PID 1942 | |4;prototype.html;378.7954504567534;416.96455801256894;446.3728461511577;468.19236816491554;476.25703901913016;6095.531309819519;6174.6295222286635;6296.118543403927;6474.287828338879;6508.349165083495;5008.574271186953;5650.163696798536;6129.509639228862;6229.159063526423;6236.345183755697;4903.583195428375;4915.115279249742;4920.384951881015;4922.114019476493;4942.3327850740225;3856.7878766667823;4549.590536851682;4922.26166075088;4930.087933732253;4955.529224723646;4034.838606455738;4915.311856340115;4924.753762311888;4945.307656928033;4950.851474455772;4147.6267135957905;4514.345419914111;4718.693284936502;4733.313669517935;4933.506649335073;3989.6036387264517;4103.543242149024;4158.93947043505;4167.5832416758385;4175.665946724285;5223.660462069991;6153.476954458876;6239.532035097386;6274.247090349141;6308.085327627468;5627.6904770237625;5641;5644.5484361251;5645.854575261415;5652.9152062719095;6029.155918171459;6818.4886133539685;6833.897491537631;6873.896891546631;6884.139482564679;4219.97890010548;4780.306855505932;4787.186178349689;4791.808327666889;4801.255805350142;6331.620102793801;7107.218205997337;7295.234000429931;7352.448566357544;7375.778726638209;4888.144574699484;4890.388701412324;4896.387951506062;4904.215325547895;4905.533974272438;3850.132685647459;4753.500882407367;4893.804247830119;4961.685153434343;4984.651074424755;5685.77256909719;5687.345955215121;5688.374278829326;5696.8860622787315;5745.023346031101;5681.914771278434;6617.0405291233465;6905.861882762317;6915.89626155608;6973.232944376216;1505.367745546873;1537.6847746212;1699.3967141664778;1710.4868539438119;1711.5464401933498;957.9616815327448;1094.5457635081511;1145.484531960621;1147.839302497651;1150.2868221702597;5058;6126.448619624255;6332.968335158387;6338.461230795434;6342.39747224013;6124.8775024500155;6468.773592924225;6968.965155174192;6976.302369762983;7040.612766297801;5601.411851755588;6374.394432528902;6379.840503987438;6382.234131904202;6411.807645770541;3497.8251087446033;3632.6912212462234;3675.5038069860225;3676.0482481332674;3682.1531047859144;6391.712372943229;6455.515836312202;6960.825979350557;7196.964015179995;7245.971128084145;6422.426505506088;6466.64433456155;7187.89218161728;7285.907113932846;7343.669534870944;6487.740490380426;6694.129763130762;6697.6986035628515;6848.507261296675;7057.329553692391;6061;7111.573305601681;7118.750843720445;7120.537165084253;7132.750353737594;5933.317668468008;6837.487188460985;6993.915943028838;7019.824504387431;7028.8594228114125;6640.302768209462;7042.732308184371;7266.1280646322775;7320.81697957534;7337.3763230123905;4111.753294802322;4479.8800299925015;4529.954700452953;4884.609231261419;4884.780184891617;5900.819836032725;6151.907721384094;6857.55425897315;7027.6837542309695;7144.714211431485;6802.80950833612;6850.691718872563;6854.725810967506;6861.965690171517;6879.759208427881;5738.9139162913425;6511.73953041873;6865.313468653295;6881.002254673144;6893.862122757417;5317.069512835161;5802.65184088956;6700.162479690039;6724.260331363448;6733.42765864907;5368.7852485901685;6570.671466426755;6810.080639113657;6819.89770153458;6829.326814930374;5081.9581985693585;5332.786688532416;6595.713835802165;6654.833629159116;6656.703437314119 07:17:46 INFO - PID 1942 | |5;yui.html;702.8172675104473;829.1749709039507;830.4975489828649;859.7233108833382;863.9740807775765;14647.194404307758;14794.778078328807;14964;14970.550883473486;14984.400623975029;12530.436130374132;12946.158499697513;13083.476660933551;15531.301091450912;16293.266802993865;965.95170241488;987.2694206287367;989.0554520432969;992.5186284651938;994.9452780097092;982.062130665213;986.9161121304697;988.4464699768117;991.3209451525692;992.1021475564626;1050.6690392526368;1052.7999680060802;1054.0039662518923;1054.7099547624389;1055.5619417941546;948.9952550237239;966.632679581758;1037.5590374090982;1039.636127355427;1059.7774467361862;758.8140905478193;767.7236194969806;767.9616019199074;769.1539306762517;771.0400551313651;16953.406630767986;16959.15204239783;17085.316587336492;17087.145642717813;17104;16545.586360340967;17029.40397086096;17439.779215454924;17470.039147846863;17620.823791762046;13963.441462341494;14648.340824662915;14721.852781472155;14737.852621473756;14829;3563.1626567756557;3575.8927232183078;3594.8382322795537;3599.6760291573623;3600.6579374959338;143781.99999999895;147141.17152970855;147802.26098869543;150057;151234;13296.800547991788;13465.596032119052;13510.459581616626;13519.797203041962;13618.591442256748;25680;25884;26340.73659263421;26430.867845661032;26521.73478265231;5780.075187969905;6331.556791024584;6460.773872914426;6872.862542749217;6985.755498557628;3357.8321083945707;3910.648041676263;3910.765354078764;3912;3914.745541539791;4445.488768791565;4532.7280363178315;4537.909241815211;4835.395575553056;5047.712833227577;1016.9491525423699;1030.6701855406197;1040.3029969920171;1040.843873418978;1044.9582016719398;623.1618473153601;647.4205586000503;657.6481582353418;667.7462564225564;676.3371895542396;9274.350795444388;10030.799384012425;11484.655460336204;11660.242084264495;11812.350320732443;629.3423372575669;633.4552285034866;638.7349250061266;658.9901151482732;674.3795707948699;5256.684598924076;5550.503182994687;5647.463490968352;5656.745446454919;5861.794837180678;135941;141274;147288.26355868153;147328;149413;119415.20877186849;119552;123354;123575;126529.36735316449;79479.24426423703;83104.58447707722;92982;95926;97912;11923.403829808649;11946.223495472766;12011.63965081049;12366.44351004189;12469.438875250817;38847.6115238844;40705.38941915814;41549.792251038554;43122.78438607787;44902.77548612236;57749;58447;71499.64250178715;77193.2280677186;77953;16376.181190940644;16624;16736.246868891172;17098.658026839643;17395.47813565595;18147.54631134232;18436.539086522946;18597.163127659016;18668.253269869598;18710.06449677538;15034.774478383051;15568.844311556739;15637.765433518278;17456.73814892752;17687.115644217996;16462.42381516689;17029.643677013773;17236.224369903135;17855.910720446314;17961.910190448965;15532.611684707972;15718.68562628718;16589.08760018162;16599.751003734713;17230.913845430692;11627.88372116268;12000.099992500773;12182.817257741319;12255.632331030083;12281.201721888048;10926.562937482411;12894.419751111034;13599.048066635241;13760.380782864595;14248.786268206193 07:17:46 INFO - PID 1942 | __end_tp_report 07:17:46 INFO - PID 1942 | __start_cc_report 07:17:46 INFO - PID 1942 | _x_x_mozilla_cycle_collect,463 07:17:46 INFO - PID 1942 | __end_cc_report 07:17:46 INFO - PID 1942 | __startTimestamp1497277066037__endTimestamp 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | ------- Summary: start ------- 07:17:46 INFO - PID 1942 | Number of tests: 6 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#0] dojo.html Cycles:165 Average:6083.06 Median:6320.37 stddev:751.98 (11.9%) stddev-sans-first:710.80 07:17:46 INFO - PID 1942 | Values: 2870.2 3412.6 3493.0 3562.5 3569.1 5966.9 6032.2 6074.8 6084.9 6092.5 6046.7 6058.6 6085.8 6096.0 6096.1 5265.2 5274.4 5299.7 5334.4 5353.8 5314.0 5353.7 5371.4 5380.5 5384.5 5341.6 5345.7 5375.5 5392.9 5401.8 5449.4 5460.3 5462.2 5476.2 5528.0 5389.8 5407.9 5428.3 5433.3 5452.7 5184.9 6032.7 6224.3 6236.1 6291.5 6421.4 6496.1 6502.4 6644.5 6657.7 6576.5 6654.6 6754.8 6761.0 6772.1 6173.0 6184.2 6202.9 6212.1 6213.6 6697.7 6718.0 6721.6 6724.2 6735.0 5618.4 6347.0 6365.4 6412.0 6465.9 6563.2 6568.5 6600.6 6666.3 6703.6 6508.3 6565.3 6607.2 6610.7 6645.3 6613.9 6614.7 6623.0 6638.6 6640.1 5187.0 5199.4 5221.3 5239.1 5250.5 4013.4 4729.4 4753.5 4759.3 4771.5 6189.0 6284.3 6314.8 6320.9 6335.6 6741.6 6742.4 6743.0 6762.1 6778.1 6016.6 6129.3 6225.8 6292.9 6315.7 4732.4 5740.5 5742.3 5758.7 5825.5 6094.3 6295.3 6793.5 6843.3 6861.5 6708.5 6751.2 6772.0 6779.6 6799.9 6849.9 6872.6 6885.0 6900.1 6924.8 6404.5 6481.7 6483.7 6600.8 6612.3 6599.0 6652.2 6730.1 6734.8 6739.1 6463.9 6483.4 6498.8 6503.8 6517.8 6485.0 6499.4 6504.7 6523.2 6527.4 6269.5 6340.3 6373.7 6375.5 6385.9 6242.9 6318.7 6320.4 6417.2 6484.1 6237.5 6279.4 6298.6 6328.8 6350.2 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#1] ext.html Cycles:180 Average:42709.05 Median:173248.68 stddev:76004.54 (43.9%) stddev-sans-first:76152.51 07:17:46 INFO - PID 1942 | Values: 765.1 811.4 813.6 823.2 856.8 36577.8 37700.4 37700.8 37762.2 37786.6 37134.4 37479.3 37498.6 37498.6 37571.4 7368.2 7473.0 7491.1 7503.1 7504.1 8050.5 8223.3 8270.4 8311.7 8337.8 8318.3 8344.0 8349.5 8376.7 8411.4 8270.6 8327.8 8337.8 8341.1 8369.3 8024.8 8059.3 8114.8 8144.7 8150.8 35781.5 35827.0 35851.5 35865.0 36027.0 3445.8 3448.8 3484.5 3490.9 3491.1 29249.3 29403.6 29498.3 29530.7 29616.6 2948.3 3025.9 3033.6 3052.3 3060.7 233674.8 236859.0 238677.0 314983.0 317994.0 36792.4 45421.8 45508.0 45582.8 46115.5 49091.5 49380.3 49401.0 49577.5 49617.8 14006.5 14081.8 14086.1 14112.2 14132.4 11479.3 11490.7 11498.3 11514.3 11544.9 13171.9 13242.9 13298.1 13335.9 13384.3 6010.0 6049.9 6099.4 6104.7 6115.3 1001.7 1007.5 1030.0 1073.2 1075.0 22694.7 22934.4 22988.7 23001.4 23798.2 1090.7 1102.2 1159.7 1161.3 1167.3 11284.6 11570.2 11658.0 11781.9 12157.1 332239.3 332769.0 334040.0 334521.0 334940.0 52200.7 52251.0 52415.5 52670.9 52799.0 1466.1 1490.3 1507.0 1520.4 1525.0 18309.5 21848.2 22457.0 22743.2 23943.6 36404.3 36488.9 36550.0 36644.5 36691.4 248215.0 257879.0 258142.4 259795.0 259865.0 23983.6 28144.3 28592.4 28668.7 32926.8 38706.8 39365.0 39420.6 39529.2 39553.6 22066.9 26816.9 26840.9 27364.0 27385.0 28418.0 28852.7 29054.7 29171.9 29567.4 27391.0 27885.4 27924.9 31514.4 33854.8 27844.2 28197.4 28572.4 29286.0 29386.0 19075.8 20991.0 21705.7 23251.5 23427.0 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#2] jquery.html Cycles:180 Average:40012.44 Median:2201.70 stddev:101806.86 (4624.0%) stddev-sans-first:102049.23 07:17:46 INFO - PID 1942 | Values: 500.4 508.3 513.4 522.6 532.4 21242.7 21791.0 22247.0 22472.7 24306.4 24545.6 24823.9 25021.7 25116.7 25147.6 6321.4 6472.3 6477.4 6491.6 6500.8 5688.1 6084.5 6371.3 6430.1 6440.7 6304.1 6313.7 6352.5 6354.9 6367.2 6339.7 6357.1 6357.4 6363.6 6381.9 5826.6 5829.3 5829.4 5833.5 5834.7 27082.6 28593.9 29432.4 29711.9 29857.7 2179.1 2203.4 2240.1 2241.2 2242.6 18047.8 18577.4 18579.9 18585.1 18601.4 2143.9 2200.0 2224.7 2228.9 2239.9 152638.0 158399.2 160621.2 160775.2 184836.0 12003.5 13145.6 13297.8 13670.6 14781.5 19804.7 19975.2 19999.0 20007.0 20048.6 9651.3 9689.6 9719.7 9755.3 9767.5 6855.2 8580.4 8905.7 8907.8 8974.6 8829.6 8895.0 8920.9 8991.5 9021.4 2554.7 2569.6 2572.3 2576.3 2712.8 951.9 962.9 964.0 1004.7 1010.8 18247.5 19734.0 20339.0 26384.3 29411.8 881.9 924.8 997.9 1039.8 1045.6 10302.7 10713.5 10902.9 11041.9 11360.1 579210.0 591392.0 602269.0 602636.0 603666.0 154293.0 154466.0 154903.2 167031.0 169325.0 1072.8 1220.1 1237.6 1248.3 1248.7 16088.5 19140.3 19161.7 19450.6 20030.8 69173.6 70712.9 72528.6 73075.0 75553.6 96037.5 100563.5 100650.0 101648.0 103248.0 11467.6 11680.1 11687.4 11694.1 11715.1 14508.4 17444.0 18131.5 18374.8 18403.4 14619.0 14703.5 14705.5 14838.4 15165.6 12595.4 12715.4 12954.7 13319.5 13563.9 15050.3 15471.0 15545.8 15649.1 15673.7 11494.4 11715.2 11717.8 11727.6 11863.4 14797.8 14892.1 14911.8 15017.0 15035.7 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#3] mootools.html Cycles:180 Average:8143.09 Median:2774.04 stddev:5385.38 (194.1%) stddev-sans-first:5375.53 07:17:46 INFO - PID 1942 | Values: 1243.8 1332.6 1376.3 1394.7 1405.6 11447.9 12985.0 13857.5 13885.5 13901.0 13397.8 13713.1 13814.2 13841.1 13853.9 2720.3 2772.2 2775.1 2781.3 2782.4 2685.0 2712.9 2725.3 2732.1 2733.1 2260.0 2593.3 2773.8 2778.7 2778.8 2709.6 2735.4 2768.9 2769.0 2774.2 2711.8 2721.6 2728.3 2736.1 2749.5 12686.7 12858.8 12923.0 12987.9 12992.4 7309.0 7523.6 7570.1 7578.0 7585.3 4441.3 4666.6 4736.0 4740.6 4772.1 1227.2 1380.1 1380.5 1389.9 1409.7 15708.0 19431.5 19885.9 19893.2 19937.6 6748.8 6852.9 6954.2 6993.3 6996.6 16276.2 16323.7 16384.8 16408.7 16429.8 7313.3 7574.4 7613.9 7621.3 7645.6 6594.6 6754.9 6801.0 6824.3 6866.0 4528.6 5148.1 5319.2 5351.8 5378.0 1650.8 1679.5 1681.2 1685.8 1704.9 785.4 800.8 805.7 812.9 843.7 6863.8 7059.0 7138.4 7189.1 7238.5 649.2 670.3 705.6 706.0 711.0 3932.9 4058.8 4239.9 4688.8 5074.7 16470.4 17536.8 17955.7 18017.3 18253.5 17830.5 17903.6 18139.2 18174.7 18227.2 9291.9 9426.0 9473.5 9502.0 9602.1 5831.9 7640.3 7718.3 8147.5 8396.9 15632.5 15923.0 16078.4 16081.8 16673.3 13006.5 13552.7 15953.6 16853.2 16915.6 10576.3 10596.1 10613.8 10665.6 10791.0 11734.4 11934.7 11949.2 11979.7 12001.9 9706.9 9717.8 9967.4 10033.2 10170.7 9494.3 9706.9 9716.3 9746.1 9996.3 7557.7 8921.8 8936.7 8983.6 8998.9 5960.7 6455.0 6828.9 7001.1 7074.0 6538.5 6703.6 6799.9 7036.5 7096.5 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#4] prototype.html Cycles:180 Average:5494.52 Median:6110.20 stddev:1681.26 (27.5%) stddev-sans-first:1641.55 07:17:46 INFO - PID 1942 | Values: 378.8 417.0 446.4 468.2 476.3 6095.5 6174.6 6296.1 6474.3 6508.3 5008.6 5650.2 6129.5 6229.2 6236.3 4903.6 4915.1 4920.4 4922.1 4942.3 3856.8 4549.6 4922.3 4930.1 4955.5 4034.8 4915.3 4924.8 4945.3 4950.9 4147.6 4514.3 4718.7 4733.3 4933.5 3989.6 4103.5 4158.9 4167.6 4175.7 5223.7 6153.5 6239.5 6274.2 6308.1 5627.7 5641.0 5644.5 5645.9 5652.9 6029.2 6818.5 6833.9 6873.9 6884.1 4220.0 4780.3 4787.2 4791.8 4801.3 6331.6 7107.2 7295.2 7352.4 7375.8 4888.1 4890.4 4896.4 4904.2 4905.5 3850.1 4753.5 4893.8 4961.7 4984.7 5685.8 5687.3 5688.4 5696.9 5745.0 5681.9 6617.0 6905.9 6915.9 6973.2 1505.4 1537.7 1699.4 1710.5 1711.5 958.0 1094.5 1145.5 1147.8 1150.3 5058.0 6126.4 6333.0 6338.5 6342.4 6124.9 6468.8 6969.0 6976.3 7040.6 5601.4 6374.4 6379.8 6382.2 6411.8 3497.8 3632.7 3675.5 3676.0 3682.2 6391.7 6455.5 6960.8 7197.0 7246.0 6422.4 6466.6 7187.9 7285.9 7343.7 6487.7 6694.1 6697.7 6848.5 7057.3 6061.0 7111.6 7118.8 7120.5 7132.8 5933.3 6837.5 6993.9 7019.8 7028.9 6640.3 7042.7 7266.1 7320.8 7337.4 4111.8 4479.9 4530.0 4884.6 4884.8 5900.8 6151.9 6857.6 7027.7 7144.7 6802.8 6850.7 6854.7 6862.0 6879.8 5738.9 6511.7 6865.3 6881.0 6893.9 5317.1 5802.7 6700.2 6724.3 6733.4 5368.8 6570.7 6810.1 6819.9 6829.3 5082.0 5332.8 6595.7 6654.8 6656.7 07:17:46 INFO - PID 1942 | 07:17:46 INFO - PID 1942 | [#5] yui.html Cycles:180 Average:24944.19 Median:17448.26 stddev:38939.15 (223.2%) stddev-sans-first:39005.85 07:17:46 INFO - PID 1942 | Values: 702.8 829.2 830.5 859.7 864.0 14647.2 14794.8 14964.0 14970.6 14984.4 12530.4 12946.2 13083.5 15531.3 16293.3 966.0 987.3 989.1 992.5 994.9 982.1 986.9 988.4 991.3 992.1 1050.7 1052.8 1054.0 1054.7 1055.6 949.0 966.6 1037.6 1039.6 1059.8 758.8 767.7 768.0 769.2 771.0 16953.4 16959.2 17085.3 17087.1 17104.0 16545.6 17029.4 17439.8 17470.0 17620.8 13963.4 14648.3 14721.9 14737.9 14829.0 3563.2 3575.9 3594.8 3599.7 3600.7 143782.0 147141.2 147802.3 150057.0 151234.0 13296.8 13465.6 13510.5 13519.8 13618.6 25680.0 25884.0 26340.7 26430.9 26521.7 5780.1 6331.6 6460.8 6872.9 6985.8 3357.8 3910.6 3910.8 3912.0 3914.7 4445.5 4532.7 4537.9 4835.4 5047.7 1016.9 1030.7 1040.3 1040.8 1045.0 623.2 647.4 657.6 667.7 676.3 9274.4 10030.8 11484.7 11660.2 11812.4 629.3 633.5 638.7 659.0 674.4 5256.7 5550.5 5647.5 5656.7 5861.8 135941.0 141274.0 147288.3 147328.0 149413.0 119415.2 119552.0 123354.0 123575.0 126529.4 79479.2 83104.6 92982.0 95926.0 97912.0 11923.4 11946.2 12011.6 12366.4 12469.4 38847.6 40705.4 41549.8 43122.8 44902.8 57749.0 58447.0 71499.6 77193.2 77953.0 16376.2 16624.0 16736.2 17098.7 17395.5 18147.5 18436.5 18597.2 18668.3 18710.1 15034.8 15568.8 15637.8 17456.7 17687.1 16462.4 17029.6 17236.2 17855.9 17961.9 15532.6 15718.7 16589.1 16599.8 17230.9 11627.9 12000.1 12182.8 12255.6 12281.2 10926.6 12894.4 13599.0 13760.4 14248.8 07:17:46 INFO - PID 1942 | -------- Summary: end -------- 07:17:46 INFO - PID 1942 | 07:17:46 INFO - TEST-INFO | 1942: exit 0 07:17:46 INFO - TEST-OK | dromaeo_css | took 1143727ms 07:17:46 INFO - TEST-START | kraken 07:17:46 INFO - operating with platform_type : mac_ 07:17:46 INFO - Initialising browser for kraken test... 07:17:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56638/getInfo.html -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpNPkAI6/profile 07:17:46 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56638/getInfo.html -foreground) 07:18:20 INFO - TEST-INFO | 1948: killed by SIGTERM 07:18:20 INFO - Browser initialized. 07:18:20 INFO - Running cycle 1/1 for kraken test... 07:18:20 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpNPkAI6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.120 plugin-container[1953:24451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 07:18:22 INFO - PID 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.121 plugin-container[1953:24451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.CFPasteboardClient' 07:18:22 INFO - PID 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.121 plugin-container[1953:24451] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.924 plugin-container[1954:24497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 07:18:22 INFO - PID 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.925 plugin-container[1954:24497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.CFPasteboardClient' 07:18:22 INFO - PID 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:18:22 INFO - PID 1952 | 2017-06-12 07:18:22.925 plugin-container[1954:24497] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:18:32 INFO - PID 1952 | RSS: Main: 252829696 07:18:32 INFO - PID 1952 | 07:18:35 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56638/tests/kraken/driver.html?testName=audio-beat-detection) 07:18:36 INFO - PID 1952 | RSS: Main: 264716288 07:18:36 INFO - PID 1952 | 07:18:38 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56638/tests/kraken/driver.html?testName=audio-dft) 07:18:39 INFO - PID 1952 | RSS: Main: 275587072 07:18:39 INFO - PID 1952 | 07:18:42 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56638/tests/kraken/driver.html?testName=audio-fft) 07:18:43 INFO - PID 1952 | RSS: Main: 278478848 07:18:43 INFO - PID 1952 | 07:18:45 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56638/tests/kraken/driver.html?testName=audio-oscillator) 07:18:45 INFO - PID 1952 | RSS: Main: 280567808 07:18:45 INFO - PID 1952 | 07:18:47 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56638/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:18:48 INFO - PID 1952 | RSS: Main: 281309184 07:18:48 INFO - PID 1952 | 07:18:51 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56638/tests/kraken/driver.html?testName=imaging-darkroom) 07:18:52 INFO - PID 1952 | RSS: Main: 286150656 07:18:52 INFO - PID 1952 | 07:18:55 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56638/tests/kraken/driver.html?testName=imaging-desaturate) 07:18:55 INFO - PID 1952 | RSS: Main: 287948800 07:18:55 INFO - PID 1952 | 07:18:58 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56638/tests/kraken/driver.html?testName=json-parse-financial) 07:18:59 INFO - PID 1952 | RSS: Main: 286363648 07:18:59 INFO - PID 1952 | 07:19:00 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56638/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:19:01 INFO - PID 1952 | RSS: Main: 286441472 07:19:01 INFO - PID 1952 | 07:19:03 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:19:04 INFO - PID 1952 | RSS: Main: 286113792 07:19:04 INFO - PID 1952 | 07:19:06 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:19:06 INFO - PID 1952 | RSS: Main: 286408704 07:19:06 INFO - PID 1952 | 07:19:09 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:19:10 INFO - PID 1952 | RSS: Main: 285941760 07:19:10 INFO - PID 1952 | 07:19:13 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:19:14 INFO - PID 1952 | RSS: Main: 285896704 07:19:14 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | Cycle 1(1): loaded http://localhost:56638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56638/tests/kraken/driver.html?testName=ai-astar) 07:19:15 INFO - PID 1952 | RSS: Main: 287662080 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | __start_tp_report 07:19:15 INFO - PID 1952 | _x_x_mozilla_page_load 07:19:15 INFO - PID 1952 | _x_x_mozilla_page_load_details 07:19:15 INFO - PID 1952 | |i|pagename|runs| 07:19:15 INFO - PID 1952 | |0;ai-astar;107;114;116;136;126;162;152;118;118;117 07:19:15 INFO - PID 1952 | |1;audio-beat-detection;140;135;184;174;97;156;142;186;144;112 07:19:15 INFO - PID 1952 | |2;audio-dft;229;188;246;255;231;200;195;200;195;195 07:19:15 INFO - PID 1952 | |3;audio-fft;66;81;74;127;58;57;63;62;98;61 07:19:15 INFO - PID 1952 | |4;audio-oscillator;70;67;108;74;70;105;143;79;71;110 07:19:15 INFO - PID 1952 | |5;imaging-gaussian-blur;89;90;90;90;90;89;90;95;91;90 07:19:15 INFO - PID 1952 | |6;imaging-darkroom;98;97;96;143;97;97;97;140;132;97 07:19:15 INFO - PID 1952 | |7;imaging-desaturate;94;120;141;93;90;90;92;91;89;92 07:19:15 INFO - PID 1952 | |8;json-parse-financial;50;51;50;52;54;53;52;52;53;49 07:19:15 INFO - PID 1952 | |9;json-stringify-tinderbox;58;57;60;60;60;62;58;57;58;58 07:19:15 INFO - PID 1952 | |10;stanford-crypto-aes;82;81;78;81;92;101;104;101;77;81 07:19:15 INFO - PID 1952 | |11;stanford-crypto-ccm;136;135;136;137;133;140;133;155;137;162 07:19:15 INFO - PID 1952 | |12;stanford-crypto-pbkdf2;227;202;179;178;181;177;182;180;178;179 07:19:15 INFO - PID 1952 | |13;stanford-crypto-sha256-iterative;66;68;69;67;68;66;66;64;64;67 07:19:15 INFO - PID 1952 | __end_tp_report 07:19:15 INFO - PID 1952 | __start_cc_report 07:19:15 INFO - PID 1952 | _x_x_mozilla_cycle_collect,1178 07:19:15 INFO - PID 1952 | __end_cc_report 07:19:15 INFO - PID 1952 | __startTimestamp1497277155983__endTimestamp 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | ------- Summary: start ------- 07:19:15 INFO - PID 1952 | Number of tests: 14 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#0] ai-astar Cycles:10 Average:126.60 Median:122.00 stddev:17.90 (14.7%) stddev-sans-first:17.52 07:19:15 INFO - PID 1952 | Values: 107.0 114.0 116.0 136.0 126.0 162.0 152.0 118.0 118.0 117.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:165.00 stddev:29.16 (17.7%) stddev-sans-first:30.82 07:19:15 INFO - PID 1952 | Values: 140.0 135.0 184.0 174.0 97.0 156.0 142.0 186.0 144.0 112.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#2] audio-dft Cycles:10 Average:213.40 Median:214.50 stddev:24.42 (11.4%) stddev-sans-first:25.24 07:19:15 INFO - PID 1952 | Values: 229.0 188.0 246.0 255.0 231.0 200.0 195.0 200.0 195.0 195.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#3] audio-fft Cycles:10 Average:74.70 Median:64.50 stddev:22.29 (34.6%) stddev-sans-first:23.42 07:19:15 INFO - PID 1952 | Values: 66.0 81.0 74.0 127.0 58.0 57.0 63.0 62.0 98.0 61.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:70.00 stddev:25.44 (36.3%) stddev-sans-first:25.96 07:19:15 INFO - PID 1952 | Values: 70.0 67.0 108.0 74.0 70.0 105.0 143.0 79.0 71.0 110.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:90.00 stddev:1.71 (1.9%) stddev-sans-first:1.74 07:19:15 INFO - PID 1952 | Values: 89.0 90.0 90.0 90.0 90.0 89.0 90.0 95.0 91.0 90.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:97.00 stddev:20.15 (20.8%) stddev-sans-first:20.95 07:19:15 INFO - PID 1952 | Values: 98.0 97.0 96.0 143.0 97.0 97.0 97.0 140.0 132.0 97.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:91.50 stddev:17.29 (18.9%) stddev-sans-first:18.23 07:19:15 INFO - PID 1952 | Values: 94.0 120.0 141.0 93.0 90.0 90.0 92.0 91.0 89.0 92.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:1.58 (3.0%) stddev-sans-first:1.56 07:19:15 INFO - PID 1952 | Values: 50.0 51.0 50.0 52.0 54.0 53.0 52.0 52.0 53.0 49.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:1.62 (2.7%) stddev-sans-first:1.69 07:19:15 INFO - PID 1952 | Values: 58.0 57.0 60.0 60.0 60.0 62.0 58.0 57.0 58.0 58.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:81.00 stddev:10.61 (13.1%) stddev-sans-first:11.05 07:19:15 INFO - PID 1952 | Values: 82.0 81.0 78.0 81.0 92.0 101.0 104.0 101.0 77.0 81.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:137.00 stddev:9.89 (7.2%) stddev-sans-first:10.36 07:19:15 INFO - PID 1952 | Values: 136.0 135.0 136.0 137.0 133.0 140.0 133.0 155.0 137.0 162.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:180.50 stddev:16.06 (8.9%) stddev-sans-first:7.74 07:19:15 INFO - PID 1952 | Values: 227.0 202.0 179.0 178.0 181.0 177.0 182.0 180.0 178.0 179.0 07:19:15 INFO - PID 1952 | 07:19:15 INFO - PID 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.65 (2.5%) stddev-sans-first:1.74 07:19:15 INFO - PID 1952 | Values: 66.0 68.0 69.0 67.0 68.0 66.0 66.0 64.0 64.0 67.0 07:19:15 INFO - PID 1952 | -------- Summary: end -------- 07:19:15 INFO - PID 1952 | 07:19:16 INFO - TEST-INFO | 1952: exit 0 07:19:16 INFO - TEST-OK | kraken | took 90231ms 07:19:16 INFO - SUITE-END | took 1233s 07:19:17 INFO - Completed test suite (00:20:34) 07:19:17 INFO - javascript benchmark 07:19:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8179.865573740106, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2870.2393865625613, 3412.624611292759, 3492.9695739756776, 3562.4834399012143, 3569.1255642367623, 5966.8662954038755, 6032.155498230246, 6074.787382441616, 6084.878302433955, 6092.543059270551, 6046.727897244624, 6058.6061905976085, 6085.7565697372165, 6095.994160963436, 6096.116063170837, 5265.210218467222, 5274.393444753845, 5299.655522391032, 5334.359876814788, 5353.7590808413715, 5313.9734301328435, 5353.732313384335, 5371.435999220084, 5380.488853558905, 5384.461553844604, 5341.599380046512, 5345.679259244438, 5375.489328513785, 5392.946070539284, 5401.837944861661, 5449.427810079944, 5460.31746031746, 5462.180672899057, 5476.205950137208, 5528.032594295983, 5389.78440862365, 5407.918881216785, 5428.321459817523, 5433.320834895638, 5452.672839629595, 5184.870378240536, 6032.698365081772, 6224.253089629274, 6236.064590311445, 6291.521492449271, 6421.389967953037, 6496.090547323379, 6502.382273683992, 6644.468442524587, 6657.700403481855, 6576.506761992822, 6654.567453115532, 6754.763583274562, 6761.01965215041, 6772.051912732172, 6173.0, 6184.19605451293, 6202.906956395657, 6212.1303017577575, 6213.56505044647, 6697.6986035628515, 6718.0, 6721.630310332881, 6724.159480064992, 6734.966325168343, 5618.353889302701, 6347.0, 6365.395287447777, 6411.96794016027, 6465.870682586322, 6563.1796025496815, 6568.474522038226, 6600.570962887493, 6666.333366663295, 6703.597784132968, 6508.251551071688, 6565.343465653492, 6607.2071351438135, 6610.7025183866845, 6645.269020407752, 6613.900791488131, 6614.669266536654, 6622.966885165543, 6638.601683898885, 6640.103586015826, 5186.974065129651, 5199.402068762065, 5221.321228240305, 5239.1355426354985, 5250.527452529214, 4013.418054382098, 4729.385179926588, 4753.500882407367, 4759.262314341283, 4771.475137734847, 6188.9690551546955, 6284.340144284784, 6314.778982735674, 6320.905186422207, 6335.619862808247, 6741.561798483083, 6742.393184613408, 6743.0, 6762.087118239073, 6778.084958530634, 6016.578839481194, 6129.267100814801, 6225.8132256032395, 6292.937070629327, 6315.652639104894, 4732.432108147044, 5740.454656807597, 5742.253507044057, 5758.740856661377, 5825.50483208932, 6094.329623741385, 6295.276043255084, 6793.5244532882225, 6843.349881761224, 6861.485388595976, 6708.463322934251, 6751.156105486814, 6771.966140169267, 6779.593224406552, 6799.93200067993, 6849.863002740017, 6872.553284036521, 6884.965575172092, 6900.068490753684, 6924.826879328057, 6404.487640988803, 6481.708323125376, 6483.675816209265, 6600.768973086111, 6612.280806990121, 6599.0, 6652.168478940132, 6730.0914376560495, 6734.831629209309, 6739.090222820053, 6463.903041454288, 6483.384078512534, 6498.837529061622, 6503.804885853432, 6517.837054073686, 6485.027245913151, 6499.38255865692, 6504.707288172138, 6523.24982627004, 6527.379898909596, 6269.49844012487, 6340.334264902119, 6373.681315934277, 6375.489960803032, 6385.87228255423, 6242.937570624417, 6318.747250109944, 6320.367963203643, 6417.229932407954, 6484.092227088306, 6237.4698150657705, 6279.372062793684, 6298.559100862895, 6328.841778955563, 6350.237971443272], "value": 6029.697707287264, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.1277543600297, 811.3549727966274, 813.5566116466525, 823.1644880446346, 856.7601071699922, 36577.81711091444, 37700.43449348255, 37700.811495942515, 37762.24475510488, 37786.62213377869, 37134.442983355206, 37479.250414991686, 37498.62501374986, 37498.62501374992, 37571.4364284536, 7368.152662443834, 7472.962635186816, 7491.138519070295, 7503.137139229004, 7504.061992250969, 8050.476719013274, 8223.301019413357, 8270.37972152088, 8311.667533298662, 8337.83324333513, 8318.334533237357, 8343.95828020862, 8349.457285276469, 8376.706815261467, 8411.369147313946, 8270.586470676471, 8327.8334433311, 8337.83324333513, 8341.124181960928, 8369.288610468118, 8024.836398722153, 8059.314958228558, 8114.797130071736, 8144.6742130314715, 8150.836983260301, 35781.46327805085, 35827.0, 35851.4622280666, 35865.0, 36027.0, 3445.758796884219, 3448.793072415638, 3484.4599087141532, 3490.930181396358, 3491.127218195451, 29249.268768280752, 29403.558946615816, 29498.262543436373, 29530.70469295301, 29616.555751663742, 2948.2629342664336, 3025.9243518912203, 3033.620797400325, 3052.2521982114304, 3060.724534791857, 233674.83162584077, 236859.0, 238677.0, 314983.0, 317994.0, 36792.448113278326, 45421.77289113533, 45508.0, 45582.77208614002, 46115.538844611794, 49091.509084909405, 49380.259296110584, 49401.0, 49577.50422495729, 49617.751911240215, 14006.509772157926, 14081.788773168411, 14086.084404513675, 14112.22382768937, 14132.364043618061, 11479.25384849982, 11490.655280341603, 11498.25261358009, 11514.251573647685, 11544.88455115438, 13171.868281317064, 13242.940564754797, 13298.135621184592, 13335.866641333656, 13384.330783460788, 6009.969950150309, 6049.909251361233, 6099.359567245376, 6104.725287362079, 6115.257151711741, 1001.7345403467941, 1007.4861820471613, 1030.0420608833856, 1073.162932912314, 1075.0, 22694.659580106312, 22934.42663933415, 22988.65517017246, 23001.424964376023, 23798.208919461515, 1090.7164137324353, 1102.2119184782919, 1159.721666799962, 1161.2742036227357, 1167.2762887010108, 11284.605038823602, 11570.19008669385, 11658.0, 11781.941090294493, 12157.148999570121, 332239.3388033044, 332769.0, 334040.0, 334521.0, 334940.0, 52200.73899630553, 52251.0, 52415.475845241825, 52670.946581068165, 52799.0, 1466.0617204988816, 1490.3442485306648, 1506.9773953390709, 1520.3538496139097, 1524.9695006100037, 18309.45071647853, 21848.235311764012, 22457.0, 22743.203987860346, 23943.640845386984, 36404.27191456103, 36488.905332840055, 36550.0, 36644.45033324556, 36691.44962825613, 248215.0, 257879.0, 258142.41857581935, 259795.0, 259865.0, 23983.640245395985, 28144.29639258953, 28592.42815143727, 28668.71331286744, 32926.84756033623, 38706.80646596749, 39365.0, 39420.60579394169, 39529.209415812096, 39553.604463954995, 22066.88966555157, 26816.865915670296, 26840.865795670896, 27364.042258520858, 27385.0, 28418.00536981196, 28852.711472885003, 29054.7094529052, 29171.85414072916, 29567.408651827274, 27391.0, 27885.442291154468, 27924.86037569799, 31514.36971260608, 33854.830725846216, 27844.16467505978, 28197.43605127927, 28572.42855142844, 29286.0, 29386.0, 19075.809241907402, 20990.95045247701, 21705.674414884106, 23251.53496930018, 23427.0], "value": 16633.957844829984, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.3595397890699, 508.25794340263235, 513.4480433533951, 522.6106550619787, 532.419662567801, 21242.681359779577, 21791.0, 22246.998885050172, 22472.66291005634, 24306.392340191505, 24545.631815522738, 24823.875880620573, 25021.74978250217, 25116.74883251167, 25147.622785658183, 6321.431071203579, 6472.2880483147055, 6477.449416799578, 6491.578047426902, 6500.772472963447, 5688.146777983295, 6084.513238940896, 6371.267304260023, 6430.1319321891415, 6440.742370305182, 6304.085907543404, 6313.684315784215, 6352.5235607329405, 6354.87290254197, 6367.204099487564, 6339.746410143589, 6357.141785858919, 6357.396047375492, 6363.554551181443, 6381.904271435886, 5826.592138550303, 5829.300483941955, 5829.446202610745, 5833.504152147073, 5834.708264586754, 27082.5937610934, 28593.85703071492, 29432.411351772844, 29711.851440742874, 29857.70142298571, 2179.08478439056, 2203.4271089516683, 2240.10395841663, 2241.1595651630637, 2242.585121752481, 18047.819521804744, 18577.442676719584, 18579.90710046441, 18585.07074646262, 18601.44195674132, 2143.9035243414082, 2199.967000494994, 2224.67742177385, 2228.8662680239236, 2239.9328020159423, 152638.0, 158399.2080039604, 160621.19689401478, 160775.19612401864, 184836.0, 12003.51985920571, 13145.619709930319, 13297.800532992012, 13670.589882303548, 14781.482648107265, 19804.70292945607, 19975.20099196016, 19999.00004999744, 20007.0, 20048.59902801936, 9651.324407291422, 9689.612415503441, 9719.65981190655, 9755.268354873413, 9767.51162441875, 6855.245922948472, 8580.442271252348, 8905.688300909436, 8907.777305567413, 8974.596143173572, 8829.60266787996, 8895.0, 8920.910790892009, 8991.505467199237, 9021.413608115452, 2554.731753165928, 2569.614557816305, 2572.331193889602, 2576.330154159932, 2712.823666461674, 951.8667386565886, 962.8796400449944, 963.9759006024906, 1004.7340351157594, 1010.8368092282375, 18247.54381140482, 19734.0, 20339.0, 26384.340391490365, 29411.823527058677, 881.9064360193443, 924.7965447601517, 997.850322451638, 1039.8284283093205, 1045.6340280901775, 10302.70897027424, 10713.464326783629, 10902.945485272681, 11041.889581104248, 11360.091192704564, 579210.0, 591392.0, 602269.0, 602636.0, 603666.0, 154293.0, 154466.0, 154903.22548387412, 167031.0, 169325.0, 1072.7527637061714, 1220.0788404754353, 1237.5915947737226, 1248.3134276148082, 1248.650377894191, 16088.517344479684, 19140.330088446837, 19161.712574311117, 19450.61098778045, 20030.799692002893, 69173.61652766816, 70712.93930590943, 72528.63735681499, 73075.0, 75553.6222318907, 96037.51981240329, 100563.49718251362, 100650.0, 101647.98352016385, 103248.0, 11467.598634047768, 11680.06559475257, 11687.415629218674, 11694.064474842162, 11715.121365897421, 14508.419663213353, 17444.040577767835, 18131.456056318333, 18374.81625183731, 18403.447896563124, 14619.049761765451, 14703.485378011717, 14705.485308014167, 14838.40646374133, 15165.62085947816, 12595.433205505591, 12715.427805748948, 12954.676133096747, 13319.467221311426, 13563.864361356655, 15050.322735477148, 15471.0, 15545.766813498032, 15649.061056336655, 15673.68652626964, 11494.425278736197, 11715.238509496856, 11717.76564468723, 11727.648170554898, 11863.406829658656, 14797.778033329294, 14892.106473611619, 14911.776323355376, 15017.0, 15035.699286014436], "value": 11634.513718399981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.7686690176727, 1332.593558964516, 1376.2774543364737, 1394.672252020775, 1405.578326502049, 11447.942760286198, 12985.0, 13857.514986975459, 13885.514007009757, 13901.0, 13397.799033014513, 13713.108647937877, 13814.171149731023, 13841.100328478642, 13853.861461385382, 2720.2519307190482, 2772.1960631416864, 2775.111964171466, 2781.2629653141935, 2782.4296019316093, 2685.0, 2712.891484340624, 2725.3322935880637, 2732.1257197696746, 2733.084416720391, 2259.9887000565054, 2593.299809051555, 2773.847438390888, 2778.7221277872254, 2778.7915906307007, 2709.607106969488, 2735.384538478846, 2768.9169324920285, 2769.030839206282, 2774.2370848016753, 2711.7559419652325, 2721.598376835931, 2728.31792051987, 2736.138116493298, 2749.4913441013477, 12686.746265074738, 12858.807117893239, 12923.030772691991, 12987.87012129876, 12992.415341309665, 7309.0, 7523.623818809037, 7570.091589009299, 7578.0, 7585.279398457193, 4441.289393696993, 4666.626669866402, 4735.976320118411, 4740.644451666104, 4772.093302272557, 1227.1532642476664, 1380.0684537936952, 1380.4892189889806, 1389.8957578181678, 1409.6757745718542, 15708.0, 19431.51421214467, 19885.9005704972, 19893.20427182911, 19937.60124797496, 6748.831279218059, 6852.897206541906, 6954.165500140016, 6993.335633114846, 6996.6151861648095, 16276.186190690418, 16323.673526529641, 16384.75422868658, 16408.6718265634, 16429.83570164307, 7313.341799238094, 7574.356179724675, 7613.9238607613215, 7621.275978782118, 7645.5794931279315, 6594.637294948728, 6754.93245067543, 6800.965995170091, 6824.283450237753, 6866.038754574363, 4528.618771274727, 5148.099082660519, 5319.228711836762, 5351.785928562814, 5378.0, 1650.8266632003706, 1679.5471916791887, 1681.2350380576809, 1685.7892763404575, 1704.8806583539033, 785.3834739729399, 800.7717800426967, 805.685782544808, 812.9349652027942, 843.7215718812777, 6863.828404289833, 7058.964705176441, 7138.428925686037, 7189.0654214952765, 7238.493305468567, 649.1820306413953, 670.3430637974812, 705.6260182103493, 706.0327351528439, 710.9502334836616, 3932.8623498177426, 4058.7970601470397, 4239.894002649959, 4688.812447502061, 5074.746262686925, 16470.42353517621, 17536.82463175352, 17955.73066404005, 18017.2793088275, 18253.54366140857, 17830.46508604744, 17903.552411189565, 18139.18373673188, 18174.727379089323, 18227.17977691007, 9291.907080929239, 9425.96314405401, 9473.47895865734, 9501.952490237505, 9602.08780165883, 5831.94168058314, 7640.274173953466, 7718.266764657348, 8147.511149331059, 8396.874046889423, 15632.531024069296, 15923.0, 16078.35686572524, 16081.75877361817, 16673.333066677198, 13006.544770932973, 13552.661183470493, 15953.601159971093, 16853.23041080718, 16915.577110572332, 10576.259661823598, 10596.099331556905, 10613.787724245627, 10665.573377064831, 10791.0, 11734.413279335828, 11934.701632459259, 11949.163558550817, 11979.700507487383, 12001.879981200076, 9706.85439718419, 9717.805643887225, 9967.352122112039, 10033.1973442123, 10170.694879153638, 9494.287928405536, 9706.85439718419, 9716.271279653913, 9746.122848943629, 9996.300258982092, 7557.697692092254, 8921.776955576162, 8936.687215947411, 8983.595738191667, 8998.910010899806, 5960.748242869046, 6455.0, 6828.863422731617, 7001.124859392576, 7074.0, 6538.542302038812, 6703.631300278336, 6799.864002720017, 7036.507444478837, 7096.467764917755], "value": 5995.226071898523, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.7954504567534, 416.96455801256894, 446.3728461511577, 468.19236816491554, 476.25703901913016, 6095.531309819519, 6174.6295222286635, 6296.118543403927, 6474.287828338879, 6508.349165083495, 5008.574271186953, 5650.163696798536, 6129.509639228862, 6229.159063526423, 6236.345183755697, 4903.583195428375, 4915.115279249742, 4920.384951881015, 4922.114019476493, 4942.3327850740225, 3856.7878766667823, 4549.590536851682, 4922.26166075088, 4930.087933732253, 4955.529224723646, 4034.838606455738, 4915.311856340115, 4924.753762311888, 4945.307656928033, 4950.851474455772, 4147.6267135957905, 4514.345419914111, 4718.693284936502, 4733.313669517935, 4933.506649335073, 3989.6036387264517, 4103.543242149024, 4158.93947043505, 4167.5832416758385, 4175.665946724285, 5223.660462069991, 6153.476954458876, 6239.532035097386, 6274.247090349141, 6308.085327627468, 5627.6904770237625, 5641.0, 5644.5484361251, 5645.854575261415, 5652.9152062719095, 6029.155918171459, 6818.4886133539685, 6833.897491537631, 6873.896891546631, 6884.139482564679, 4219.97890010548, 4780.306855505932, 4787.186178349689, 4791.808327666889, 4801.255805350142, 6331.620102793801, 7107.218205997337, 7295.234000429931, 7352.448566357544, 7375.778726638209, 4888.144574699484, 4890.388701412324, 4896.387951506062, 4904.215325547895, 4905.533974272438, 3850.132685647459, 4753.500882407367, 4893.804247830119, 4961.685153434343, 4984.651074424755, 5685.77256909719, 5687.345955215121, 5688.374278829326, 5696.8860622787315, 5745.023346031101, 5681.914771278434, 6617.0405291233465, 6905.861882762317, 6915.89626155608, 6973.232944376216, 1505.367745546873, 1537.6847746212, 1699.3967141664778, 1710.4868539438119, 1711.5464401933498, 957.9616815327448, 1094.5457635081511, 1145.484531960621, 1147.839302497651, 1150.2868221702597, 5058.0, 6126.448619624255, 6332.968335158387, 6338.461230795434, 6342.39747224013, 6124.8775024500155, 6468.773592924225, 6968.965155174192, 6976.302369762983, 7040.612766297801, 5601.411851755588, 6374.394432528902, 6379.840503987438, 6382.234131904202, 6411.807645770541, 3497.8251087446033, 3632.6912212462234, 3675.5038069860225, 3676.0482481332674, 3682.1531047859144, 6391.712372943229, 6455.515836312202, 6960.825979350557, 7196.964015179995, 7245.971128084145, 6422.426505506088, 6466.64433456155, 7187.89218161728, 7285.907113932846, 7343.669534870944, 6487.740490380426, 6694.129763130762, 6697.6986035628515, 6848.507261296675, 7057.329553692391, 6061.0, 7111.573305601681, 7118.750843720445, 7120.537165084253, 7132.750353737594, 5933.317668468008, 6837.487188460985, 6993.915943028838, 7019.824504387431, 7028.8594228114125, 6640.302768209462, 7042.732308184371, 7266.1280646322775, 7320.81697957534, 7337.3763230123905, 4111.753294802322, 4479.8800299925015, 4529.954700452953, 4884.609231261419, 4884.780184891617, 5900.819836032725, 6151.907721384094, 6857.55425897315, 7027.6837542309695, 7144.714211431485, 6802.80950833612, 6850.691718872563, 6854.725810967506, 6861.965690171517, 6879.759208427881, 5738.9139162913425, 6511.73953041873, 6865.313468653295, 6881.002254673144, 6893.862122757417, 5317.069512835161, 5802.65184088956, 6700.162479690039, 6724.260331363448, 6733.42765864907, 5368.7852485901685, 6570.671466426755, 6810.080639113657, 6819.89770153458, 6829.326814930374, 5081.9581985693585, 5332.786688532416, 6595.713835802165, 6654.833629159116, 6656.703437314119], "value": 4983.473934026729, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.8172675104473, 829.1749709039507, 830.4975489828649, 859.7233108833382, 863.9740807775765, 14647.194404307758, 14794.778078328807, 14964.0, 14970.550883473486, 14984.400623975029, 12530.436130374132, 12946.158499697513, 13083.476660933551, 15531.301091450912, 16293.266802993865, 965.95170241488, 987.2694206287367, 989.0554520432969, 992.5186284651938, 994.9452780097092, 982.062130665213, 986.9161121304697, 988.4464699768117, 991.3209451525692, 992.1021475564626, 1050.6690392526368, 1052.7999680060802, 1054.0039662518923, 1054.7099547624389, 1055.5619417941546, 948.9952550237239, 966.632679581758, 1037.5590374090982, 1039.636127355427, 1059.7774467361862, 758.8140905478193, 767.7236194969806, 767.9616019199074, 769.1539306762517, 771.0400551313651, 16953.406630767986, 16959.15204239783, 17085.316587336492, 17087.145642717813, 17104.0, 16545.586360340967, 17029.40397086096, 17439.779215454924, 17470.039147846863, 17620.823791762046, 13963.441462341494, 14648.340824662915, 14721.852781472155, 14737.852621473756, 14829.0, 3563.1626567756557, 3575.8927232183078, 3594.8382322795537, 3599.6760291573623, 3600.6579374959338, 143781.99999999895, 147141.17152970855, 147802.26098869543, 150057.0, 151234.0, 13296.800547991788, 13465.596032119052, 13510.459581616626, 13519.797203041962, 13618.591442256748, 25680.0, 25884.0, 26340.73659263421, 26430.867845661032, 26521.73478265231, 5780.075187969905, 6331.556791024584, 6460.773872914426, 6872.862542749217, 6985.755498557628, 3357.8321083945707, 3910.648041676263, 3910.765354078764, 3912.0, 3914.745541539791, 4445.488768791565, 4532.7280363178315, 4537.909241815211, 4835.395575553056, 5047.712833227577, 1016.9491525423699, 1030.6701855406197, 1040.3029969920171, 1040.843873418978, 1044.9582016719398, 623.1618473153601, 647.4205586000503, 657.6481582353418, 667.7462564225564, 676.3371895542396, 9274.350795444388, 10030.799384012425, 11484.655460336204, 11660.242084264495, 11812.350320732443, 629.3423372575669, 633.4552285034866, 638.7349250061266, 658.9901151482732, 674.3795707948699, 5256.684598924076, 5550.503182994687, 5647.463490968352, 5656.745446454919, 5861.794837180678, 135941.0, 141274.0, 147288.26355868153, 147328.0, 149413.0, 119415.20877186849, 119552.0, 123354.0, 123575.0, 126529.36735316449, 79479.24426423703, 83104.58447707722, 92982.0, 95926.0, 97912.0, 11923.403829808649, 11946.223495472766, 12011.63965081049, 12366.44351004189, 12469.438875250817, 38847.6115238844, 40705.38941915814, 41549.792251038554, 43122.78438607787, 44902.77548612236, 57749.0, 58447.0, 71499.64250178715, 77193.2280677186, 77953.0, 16376.181190940644, 16624.0, 16736.246868891172, 17098.658026839643, 17395.47813565595, 18147.54631134232, 18436.539086522946, 18597.163127659016, 18668.253269869598, 18710.06449677538, 15034.774478383051, 15568.844311556739, 15637.765433518278, 17456.73814892752, 17687.115644217996, 16462.42381516689, 17029.643677013773, 17236.224369903135, 17855.910720446314, 17961.910190448965, 15532.611684707972, 15718.68562628718, 16589.08760018162, 16599.751003734713, 17230.913845430692, 11627.88372116268, 12000.099992500773, 12182.817257741319, 12255.632331030083, 12281.201721888048, 10926.562937482411, 12894.419751111034, 13599.048066635241, 13760.380782864595, 14248.786268206193], "value": 8591.576044912428, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 116.0, 136.0, 126.0, 162.0, 152.0, 118.0, 118.0, 117.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 184.0, 174.0, 97.0, 156.0, 142.0, 186.0, 144.0, 112.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 188.0, 246.0, 255.0, 231.0, 200.0, 195.0, 200.0, 195.0, 195.0], "value": 213.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 74.0, 127.0, 58.0, 57.0, 63.0, 62.0, 98.0, 61.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 108.0, 74.0, 70.0, 105.0, 143.0, 79.0, 71.0, 110.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 90.0, 90.0, 89.0, 90.0, 95.0, 91.0, 90.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 96.0, 143.0, 97.0, 97.0, 97.0, 140.0, 132.0, 97.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 120.0, 141.0, 93.0, 90.0, 90.0, 92.0, 91.0, 89.0, 92.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 52.0, 54.0, 53.0, 52.0, 52.0, 53.0, 49.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 60.0, 60.0, 62.0, 58.0, 57.0, 58.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 78.0, 81.0, 92.0, 101.0, 104.0, 101.0, 77.0, 81.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 136.0, 137.0, 133.0, 140.0, 133.0, 155.0, 137.0, 162.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 202.0, 179.0, 178.0, 181.0, 177.0, 182.0, 180.0, 178.0, 179.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 67.0, 68.0, 66.0, 66.0, 64.0, 64.0, 67.0], "value": 66.5, "unit": "score"}]}]} 07:19:17 INFO - Return code: 0 07:19:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:19:17 INFO - # TBPL SUCCESS # 07:19:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:17 INFO - 2017-06-12 07:19:17.388 screenresolution[1960:24875] starting screenresolution argv=screenresolution get 07:19:17 INFO - 2017-06-12 07:19:17.390 screenresolution[1960:24875] Display 0: 1600x1200x32@60 07:19:17 INFO - 2017-06-12 07:19:17.396 screenresolution[1961:24878] starting screenresolution argv=screenresolution list 07:19:17 INFO - Available Modes on Display 0 07:19:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:17 INFO - Intel Iris: 07:19:17 INFO - Chipset Model: Intel Iris 07:19:17 INFO - Type: GPU 07:19:17 INFO - Bus: Built-In 07:19:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:17 INFO - Vendor: Intel (0x8086) 07:19:17 INFO - Device ID: 0x0a2e 07:19:17 INFO - Revision ID: 0x0009 07:19:17 INFO - Displays: 07:19:17 INFO - Display: 07:19:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:17 INFO - Display Serial Number: 1600x1200 60 07:19:17 INFO - Main Display: Yes 07:19:17 INFO - Mirror: Off 07:19:17 INFO - Online: Yes 07:19:17 INFO - Rotation: Supported 07:19:17 INFO - Return code: 0 07:19:17 INFO - Running post-action listener: _resource_record_post_action 07:19:17 INFO - [mozharness: 2017-06-12 14:19:17.631534Z] Finished run-tests step (success) 07:19:17 INFO - Running post-run listener: _resource_record_post_run 07:19:17 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 98304; Write bytes: 575966208; Read time: 3; Write time: 1743 07:19:17 INFO - TinderboxPrint: CPU usage
53.5% 07:19:17 INFO - TinderboxPrint: I/O read bytes / time
98,304 / 3 07:19:17 INFO - TinderboxPrint: I/O write bytes / time
575,966,208 / 1,743 07:19:17 INFO - TinderboxPrint: CPU idle
2,340.2 (46.5%) 07:19:17 INFO - TinderboxPrint: CPU system
1,029.4 (20.5%) 07:19:17 INFO - TinderboxPrint: CPU user
1,660.1 (33.0%) 07:19:17 INFO - TinderboxPrint: Swap in / out
251,838,464 / 0 07:19:17 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 239309312; Write bytes: 236804096; Read time: 17766; Write time: 477 07:19:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:19:17 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 98304; Write bytes: 319792128; Read time: 3; Write time: 1217 07:19:18 INFO - Running post-run listener: _upload_blobber_files 07:19:18 INFO - Blob upload gear active. 07:19:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:19:18 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 07:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:19:18 INFO - (blobuploader) - INFO - Open directory for files ... 07:19:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:19:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:19:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:19:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:19:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:19:19 INFO - (blobuploader) - INFO - Done attempting. 07:19:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:19:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:19:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:19:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:19:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:19:20 INFO - (blobuploader) - INFO - Done attempting. 07:19:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:19:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:19:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:19:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:19:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:19:21 INFO - (blobuploader) - INFO - Done attempting. 07:19:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:19:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:19:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:19:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:19:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:19:23 INFO - (blobuploader) - INFO - Done attempting. 07:19:23 INFO - (blobuploader) - INFO - Iteration through files over. 07:19:23 INFO - Return code: 0 07:19:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:19:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:19:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dd076b44d2ffb563244e13e4c2cf319ca7f90d0b3f5a632068307f533218935edbdc05a70ed93e7cc0fdd84ffc676304f3059e64a7d927e310d4c8e7807d8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9393b4131fc7543d14fea71846186e242da56d78e7a7337feaf6b394f800da28afdc4ec040ab5a05afd250afdb915da8a681ef0cb73a97f2027d90ed33c0a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9bd468bc16bf26f68ef182d109c0e8e89686f68dd5cb260de3e51c358e867d66368ec1856f90bb23d2c9d4860ab2117256595bbfdd3903066474001c998f43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb5c65d47f12e9d928b7195f320510bd7cb966ad8e1ed0181babf4ddd7f1ada98972e64d7e8ed2c12ed1a88fbeb5793411d2fc6a6e4523ff1fbca66c4058d845"} 07:19:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:19:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:19:23 INFO - Contents: 07:19:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dd076b44d2ffb563244e13e4c2cf319ca7f90d0b3f5a632068307f533218935edbdc05a70ed93e7cc0fdd84ffc676304f3059e64a7d927e310d4c8e7807d8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9393b4131fc7543d14fea71846186e242da56d78e7a7337feaf6b394f800da28afdc4ec040ab5a05afd250afdb915da8a681ef0cb73a97f2027d90ed33c0a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9bd468bc16bf26f68ef182d109c0e8e89686f68dd5cb260de3e51c358e867d66368ec1856f90bb23d2c9d4860ab2117256595bbfdd3903066474001c998f43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb5c65d47f12e9d928b7195f320510bd7cb966ad8e1ed0181babf4ddd7f1ada98972e64d7e8ed2c12ed1a88fbeb5793411d2fc6a6e4523ff1fbca66c4058d845"} 07:19:23 INFO - Running post-run listener: copy_logs_to_upload_dir 07:19:23 INFO - Copying logs to upload dir... 07:19:23 INFO - mkdir: /builds/slave/test/build/upload/logs 07:19:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.463057 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-12 07:19:23.439333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 07:19:23.442765) ========= 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.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/Firefox/sha512/86dd076b44d2ffb563244e13e4c2cf319ca7f90d0b3f5a632068307f533218935edbdc05a70ed93e7cc0fdd84ffc676304f3059e64a7d927e310d4c8e7807d8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9393b4131fc7543d14fea71846186e242da56d78e7a7337feaf6b394f800da28afdc4ec040ab5a05afd250afdb915da8a681ef0cb73a97f2027d90ed33c0a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9bd468bc16bf26f68ef182d109c0e8e89686f68dd5cb260de3e51c358e867d66368ec1856f90bb23d2c9d4860ab2117256595bbfdd3903066474001c998f43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb5c65d47f12e9d928b7195f320510bd7cb966ad8e1ed0181babf4ddd7f1ada98972e64d7e8ed2c12ed1a88fbeb5793411d2fc6a6e4523ff1fbca66c4058d845"} build_url:https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010969 build_url: 'https://queue.taskcluster.net/v1/task/TNKERECdQVKqs7fZhevVyg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dd076b44d2ffb563244e13e4c2cf319ca7f90d0b3f5a632068307f533218935edbdc05a70ed93e7cc0fdd84ffc676304f3059e64a7d927e310d4c8e7807d8c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9393b4131fc7543d14fea71846186e242da56d78e7a7337feaf6b394f800da28afdc4ec040ab5a05afd250afdb915da8a681ef0cb73a97f2027d90ed33c0a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af9bd468bc16bf26f68ef182d109c0e8e89686f68dd5cb260de3e51c358e867d66368ec1856f90bb23d2c9d4860ab2117256595bbfdd3903066474001c998f43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb5c65d47f12e9d928b7195f320510bd7cb966ad8e1ed0181babf4ddd7f1ada98972e64d7e8ed2c12ed1a88fbeb5793411d2fc6a6e4523ff1fbca66c4058d845"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 07:19:23.478206) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 07:19:23.478519) ========= 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.xnKAqp4YAG/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.aFifVNnpnz/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.005051 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 07:19:23.529306) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 07:19:23.530022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 07:19:23.530725) ========= ========= Total master_lag: 0.32 =========